[libnice] Getting stuck waiting for "reliable-transport-writable"

Scott Twiname scott at sylo.io
Thu Jan 25 22:11:33 UTC 2018


Hi

I have written Go bindings for libnice and I am running into problems when
running my tests. I can establish a reliable transport and send some data
before it stops being able to send. I am waiting for
"reliable-transport-writable" and if I try to send anyway I get -1 as the
result, the component state is still connected.

Am I running into issues because I am running 2 Nice Agents within the same
process and there is global state causing issues? I also seem to be having
the same issues when running in seperate processes on 1 machine and across
multiple machines.

I have also attached logs.

Any help would be appreciated

Version: 0.1.14
OS: MacOS
Go Version: 1.9.2


Regards,
Scott Twiname
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/nice/attachments/20180126/6ff7e49a/attachment-0001.html>
-------------- next part --------------
Running Suite: GoLibnice Suite
=============================
ComponentStateChanged connecting
ComponentStateChanged connecting
(process:59740): libnice-DEBUG: Agent 0x6805000 : Property set, role is already "controlling".
(process:59740): libnice-DEBUG: Agent 0x6805120 : Property set, changing role to "controlled".
(process:59740): libnice-DEBUG: Created NiceStream (1 created, 0 destroyed)
(process:59740): libnice-DEBUG: Created NiceComponent (1 created, 0 destroyed)
(process:59740): libnice-DEBUG: Agent 0x6805000 : allocating stream id 1 (0x680ac00)
(process:59740): libnice-DEBUG: Agent 0x6805000 : reliable stream
(process:59740): libnice-DEBUG: Agent 0x6805000: Create Pseudo Tcp Socket for component 1
(process:59740): libnice-DEBUG: Created NiceStream (2 created, 0 destroyed)
(process:59740): libnice-DEBUG: Created NiceComponent (2 created, 0 destroyed)
(process:59740): libnice-DEBUG: Agent 0x6805120 : allocating stream id 1 (0x5833600)
(process:59740): libnice-DEBUG: Agent 0x6805120 : reliable stream
(process:59740): libnice-DEBUG: Agent 0x6805120: Create Pseudo Tcp Socket for component 1
(process:59740): libnice-DEBUG: Agent 0x6805000 : In ICE-FULL mode, starting candidate gathering.
(process:59740): libnice-DEBUG: Agent 0x6805000 : libnice compiled without UPnP support
(process:59740): libnice-DEBUG: Failed to convert address to string for interface ?lo0?.
(process:59740): libnice-DEBUG: Interface:  lo0
(process:59740): libnice-DEBUG: IP Address: 127.0.0.1
(process:59740): libnice-DEBUG: Ignoring loopback interface
(process:59740): libnice-DEBUG: Interface:  lo0
(process:59740): libnice-DEBUG: IP Address: ::1
(process:59740): libnice-DEBUG: Ignoring loopback interface
(process:59740): libnice-DEBUG: Interface:  lo0
(process:59740): libnice-DEBUG: IP Address: fe80::1%lo0
(process:59740): libnice-DEBUG: Ignoring loopback interface
(process:59740): libnice-DEBUG: Failed to convert address to string for interface ?en0?.
(process:59740): libnice-DEBUG: Interface:  en0
(process:59740): libnice-DEBUG: IP Address: fe80::847:e28:82af:7c87%en0
(process:59740): libnice-DEBUG: Interface:  en0
(process:59740): libnice-DEBUG: IP Address: 192.168.1.22
(process:59740): libnice-DEBUG: Failed to convert address to string for interface ?en1?.
(process:59740): libnice-DEBUG: Failed to convert address to string for interface ?awdl0?.
(process:59740): libnice-DEBUG: Failed to convert address to string for interface ?en2?.
(process:59740): libnice-DEBUG: Failed to convert address to string for interface ?en3?.
(process:59740): libnice-DEBUG: Failed to convert address to string for interface ?bridge0?.
(process:59740): libnice-DEBUG: Failed to convert address to string for interface ?utun0?.
(process:59740): libnice-DEBUG: Interface:  utun0
(process:59740): libnice-DEBUG: IP Address: fe80::7b4b:2dc5:626c:6425%utun0
(process:59740): libnice-DEBUG: Agent 0x6805000: Trying to create host candidate on port 0
(process:59740): libnice-DEBUG: Agent 0x6805000: Could not set socket ToS: Invalid argument
(process:59740): libnice-DEBUG: Component 0x680b200 (agent 0x6805000): Attach source (stream 1).
(process:59740): libnice-DEBUG: Attaching source 0x5101600 (socket 0x6800f20, FD 12) to context 0x521aaa0
(process:59740): libnice-DEBUG: Agent 0x6805000: Trying to create host candidate on port 0
(process:59740): libnice-DEBUG: Agent 0x6805000: Could not set IPV6 socket ToS: Invalid argument
(process:59740): libnice-DEBUG: Component 0x680b200 (agent 0x6805000): Attach source (stream 1).
(process:59740): libnice-DEBUG: Attaching source 0x5101680 (socket 0x70030e0, FD 13) to context 0x521aaa0
(process:59740): libnice-DEBUG: Agent 0x6805000: Trying to create host candidate on port 0
(process:59740): libnice-DEBUG: Agent 0x6805000: Could not set socket ToS: Invalid argument
(process:59740): libnice-DEBUG: Component 0x680b200 (agent 0x6805000): Attach source (stream 1).
(process:59740): libnice-DEBUG: Attaching source 0x5101f10 (socket 0x7003150, FD 14) to context 0x521aaa0
(process:59740): libnice-DEBUG: Agent 0x6805000: Candidate gathering FINISHED, no scheduled items.
(process:59740): libnice-DEBUG: Agent 0x6805000: gathered UDP local candidate : [fe80::7b4b:2dc5:626c:6425]:63277 for s1/c1. U/P '(null)'/'(null)'
(process:59740): libnice-DEBUG: Agent 0x6805000: gathered UDP local candidate : [192.168.1.22]:52785 for s1/c1. U/P '(null)'/'(null)'
(process:59740): libnice-DEBUG: Agent 0x6805000: gathered UDP local candidate : [fe80::847:e28:82af:7c87]:52787 for s1/c1. U/P '(null)'/'(null)'
(process:59740): libnice-DEBUG: Agent 0x6805120 : In ICE-FULL mode, starting candidate gathering.
(process:59740): libnice-DEBUG: Agent 0x6805120 : libnice compiled without UPnP support
(process:59740): libnice-DEBUG: Failed to convert address to string for interface ?lo0?.
(process:59740): libnice-DEBUG: Interface:  lo0
(process:59740): libnice-DEBUG: IP Address: 127.0.0.1
(process:59740): libnice-DEBUG: Ignoring loopback interface
(process:59740): libnice-DEBUG: Interface:  lo0
(process:59740): libnice-DEBUG: IP Address: ::1
(process:59740): libnice-DEBUG: Ignoring loopback interface
(process:59740): libnice-DEBUG: Interface:  lo0
(process:59740): libnice-DEBUG: IP Address: fe80::1%lo0
(process:59740): libnice-DEBUG: Ignoring loopback interface
(process:59740): libnice-DEBUG: Failed to convert address to string for interface ?en0?.
(process:59740): libnice-DEBUG: Interface:  en0
(process:59740): libnice-DEBUG: IP Address: fe80::847:e28:82af:7c87%en0
(process:59740): libnice-DEBUG: Interface:  en0
(process:59740): libnice-DEBUG: IP Address: 192.168.1.22
(process:59740): libnice-DEBUG: Failed to convert address to string for interface ?en1?.
(process:59740): libnice-DEBUG: Failed to convert address to string for interface ?awdl0?.
(process:59740): libnice-DEBUG: Failed to convert address to string for interface ?en2?.
(process:59740): libnice-DEBUG: Failed to convert address to string for interface ?en3?.
(process:59740): libnice-DEBUG: Failed to convert address to string for interface ?bridge0?.
(process:59740): libnice-DEBUG: Failed to convert address to string for interface ?utun0?.
(process:59740): libnice-DEBUG: Interface:  utun0
(process:59740): libnice-DEBUG: IP Address: fe80::7b4b:2dc5:626c:6425%utun0
(process:59740): libnice-DEBUG: Agent 0x6805120: Trying to create host candidate on port 0
(process:59740): libnice-DEBUG: Agent 0x6805120: Could not set socket ToS: Invalid argument
(process:59740): libnice-DEBUG: Component 0x5833c00 (agent 0x6805120): Attach source (stream 1).
(process:59740): libnice-DEBUG: Attaching source 0x53077e0 (socket 0x70031c0, FD 15) to context 0x521aaa0
(process:59740): libnice-DEBUG: Agent 0x6805120: Trying to create host candidate on port 0
(process:59740): libnice-DEBUG: Agent 0x6805120: Could not set IPV6 socket ToS: Invalid argument
(process:59740): libnice-DEBUG: Component 0x5833c00 (agent 0x6805120): Attach source (stream 1).
(process:59740): libnice-DEBUG: Attaching source 0x5307860 (socket 0x7003230, FD 16) to context 0x521aaa0
(process:59740): libnice-DEBUG: Agent 0x6805120: Trying to create host candidate on port 0
(process:59740): libnice-DEBUG: Agent 0x6805120: Could not set socket ToS: Invalid argument
(process:59740): libnice-DEBUG: Component 0x5833c00 (agent 0x6805120): Attach source (stream 1).
(process:59740): libnice-DEBUG: Attaching source 0x5305f80 (socket 0x70032a0, FD 17) to context 0x521aaa0
(process:59740): libnice-DEBUG: Agent 0x6805120: Candidate gathering FINISHED, no scheduled items.
(process:59740): libnice-DEBUG: Agent 0x6805120: gathered UDP local candidate : [fe80::7b4b:2dc5:626c:6425]:52788 for s1/c1. U/P '(null)'/'(null)'
(process:59740): libnice-DEBUG: Agent 0x6805120: gathered UDP local candidate : [192.168.1.22]:62956 for s1/c1. U/P '(null)'/'(null)'
(process:59740): libnice-DEBUG: Agent 0x6805120: gathered UDP local candidate : [fe80::847:e28:82af:7c87]:62957 for s1/c1. U/P '(null)'/'(null)'
(process:59740): libnice-DEBUG: Agent 0x6805120 : Adding UDP remote candidate with addr [fe80::7b4b:2dc5:626c:6425]:63277 for s1/c1. U/P '(null)'/'(null)' prio: 1006633471
(process:59740): libnice-DEBUG: Agent 0x6805120 : Adding check pair between 1 and 1 for s1/c1
(process:59740): libnice-DEBUG: Agent 0x6805120 : creating new pair 0x5861000 state 5
(process:59740): libnice-DEBUG: Agent 0x6805120 : new pair 0x5861000 : [fe80::7b4b:2dc5:626c:6425]:52788 --> [fe80::7b4b:2dc5:626c:6425]:63277
(process:59740): libnice-DEBUG: Agent 0x6805120 : added a new conncheck 0x5861000 with foundation of '1:1' to list 1.
(process:59740): libnice-DEBUG: Agent 0x6805120 : stream 1 component 1 STATE-CHANGE disconnected -> connecting.
(process:59740): libnice-DEBUG: Agent 0x6805120 : Adding check pair between 3 and 1 for s1/c1
(process:59740): libnice-DEBUG: Agent 0x6805120 : creating new pair 0x58610b0 state 5
(process:59740): libnice-DEBUG: Agent 0x6805120 : new pair 0x58610b0 : [fe80::847:e28:82af:7c87]:62957 --> [fe80::7b4b:2dc5:626c:6425]:63277
(process:59740): libnice-DEBUG: Agent 0x6805120 : added a new conncheck 0x58610b0 with foundation of '3:1' to list 1.
(process:59740): libnice-DEBUG: Agent 0x6805120 : Adding UDP remote candidate with addr [192.168.1.22]:52785 for s1/c1. U/P '(null)'/'(null)' prio: 1006633470
(process:59740): libnice-DEBUG: Agent 0x6805120 : Adding check pair between 2 and 2 for s1/c1
(process:59740): libnice-DEBUG: Agent 0x6805120 : creating new pair 0x5861160 state 5
(process:59740): libnice-DEBUG: Agent 0x6805120 : new pair 0x5861160 : [192.168.1.22]:62956 --> [192.168.1.22]:52785
(process:59740): libnice-DEBUG: Agent 0x6805120 : added a new conncheck 0x5861160 with foundation of '2:2' to list 1.
(process:59740): libnice-DEBUG: Agent 0x6805120 : Adding UDP remote candidate with addr [fe80::847:e28:82af:7c87]:52787 for s1/c1. U/P '(null)'/'(null)' prio: 1006633469
(process:59740): libnice-DEBUG: Agent 0x6805120 : Adding check pair between 1 and 3 for s1/c1
(process:59740): libnice-DEBUG: Agent 0x6805120 : creating new pair 0x5861210 state 5
(process:59740): libnice-DEBUG: Agent 0x6805120 : new pair 0x5861210 : [fe80::7b4b:2dc5:626c:6425]:52788 --> [fe80::847:e28:82af:7c87]:52787
(process:59740): libnice-DEBUG: Agent 0x6805120 : added a new conncheck 0x5861210 with foundation of '1:3' to list 1.
(process:59740): libnice-DEBUG: Agent 0x6805120 : Adding check pair between 3 and 3 for s1/c1
(process:59740): libnice-DEBUG: Agent 0x6805120 : creating new pair 0x58612c0 state 5
(process:59740): libnice-DEBUG: Agent 0x6805120 : new pair 0x58612c0 : [fe80::847:e28:82af:7c87]:62957 --> [fe80::847:e28:82af:7c87]:52787
(process:59740): libnice-DEBUG: Agent 0x6805120 : added a new conncheck 0x58612c0 with foundation of '3:3' to list 1.
(process:59740): libnice-DEBUG: Agent 0x6805000 : Adding UDP remote candidate with addr [fe80::7b4b:2dc5:626c:6425]:52788 for s1/c1. U/P '(null)'/'(null)' prio: 1006633471
(process:59740): libnice-DEBUG: Agent 0x6805000 : Adding check pair between 1 and 1 for s1/c1
(process:59740): libnice-DEBUG: Agent 0x6805000 : creating new pair 0x5861370 state 5
(process:59740): libnice-DEBUG: Agent 0x6805000 : new pair 0x5861370 : [fe80::7b4b:2dc5:626c:6425]:63277 --> [fe80::7b4b:2dc5:626c:6425]:52788
(process:59740): libnice-DEBUG: Agent 0x6805000 : added a new conncheck 0x5861370 with foundation of '1:1' to list 1.
(process:59740): libnice-DEBUG: Agent 0x6805000 : stream 1 component 1 STATE-CHANGE disconnected -> connecting.
(process:59740): libnice-DEBUG: Agent 0x6805000 : Adding check pair between 3 and 1 for s1/c1
(process:59740): libnice-DEBUG: Agent 0x6805000 : creating new pair 0x5861420 state 5
(process:59740): libnice-DEBUG: Agent 0x6805000 : new pair 0x5861420 : [fe80::847:e28:82af:7c87]:52787 --> [fe80::7b4b:2dc5:626c:6425]:52788
(process:59740): libnice-DEBUG: Agent 0x6805000 : added a new conncheck 0x5861420 with foundation of '3:1' to list 1.
(process:59740): libnice-DEBUG: Agent 0x6805000 : Adding UDP remote candidate with addr [192.168.1.22]:62956 for s1/c1. U/P '(null)'/'(null)' prio: 1006633470
(process:59740): libnice-DEBUG: Agent 0x6805000 : Adding check pair between 2 and 2 for s1/c1
(process:59740): libnice-DEBUG: Agent 0x6805000 : creating new pair 0x58614d0 state 5
(process:59740): libnice-DEBUG: Agent 0x6805000 : new pair 0x58614d0 : [192.168.1.22]:52785 --> [192.168.1.22]:62956
(process:59740): libnice-DEBUG: Agent 0x6805000 : added a new conncheck 0x58614d0 with foundation of '2:2' to list 1.
(process:59740): libnice-DEBUG: Agent 0x6805000 : Adding UDP remote candidate with addr [fe80::847:e28:82af:7c87]:62957 for s1/c1. U/P '(null)'/'(null)' prio: 1006633469
(process:59740): libnice-DEBUG: Agent 0x6805000 : Adding check pair between 1 and 3 for s1/c1
(process:59740): libnice-DEBUG: Agent 0x6805000 : creating new pair 0x5861580 state 5
(process:59740): libnice-DEBUG: Agent 0x6805000 : new pair 0x5861580 : [fe80::7b4b:2dc5:626c:6425]:63277 --> [fe80::847:e28:82af:7c87]:62957
(process:59740): libnice-DEBUG: Agent 0x6805000 : added a new conncheck 0x5861580 with foundation of '1:3' to list 1.
(process:59740): libnice-DEBUG: Agent 0x6805000 : Adding check pair between 3 and 3 for s1/c1
(process:59740): libnice-DEBUG: Agent 0x6805000 : creating new pair 0x5861630 state 5
(process:59740): libnice-DEBUG: Agent 0x6805000 : new pair 0x5861630 : [fe80::847:e28:82af:7c87]:52787 --> [fe80::847:e28:82af:7c87]:62957
(process:59740): libnice-DEBUG: Agent 0x6805000 : added a new conncheck 0x5861630 with foundation of '3:3' to list 1.
(process:59740): libnice-DEBUG: Agent 0x6805120 : Pair 0x5861000 with s/c-id 1/1 (1:1) unfrozen.
(process:59740): libnice-DEBUG: Agent 0x6805120 : pair 0x5861000 state WAITING
(process:59740): libnice-DEBUG: Agent 0x6805120 : Pair 0x5861160 with s/c-id 1/1 (2:2) unfrozen.
(process:59740): libnice-DEBUG: Agent 0x6805120 : pair 0x5861160 state WAITING
(process:59740): libnice-DEBUG: Agent 0x6805120 : Pair 0x58610b0 with s/c-id 1/1 (3:1) unfrozen.
(process:59740): libnice-DEBUG: Agent 0x6805120 : pair 0x58610b0 state WAITING
(process:59740): libnice-DEBUG: Agent 0x6805120 : Pair 0x5861210 with s/c-id 1/1 (1:3) unfrozen.
(process:59740): libnice-DEBUG: Agent 0x6805120 : pair 0x5861210 state WAITING
(process:59740): libnice-DEBUG: Agent 0x6805120 : Pair 0x58612c0 with s/c-id 1/1 (3:3) unfrozen.
(process:59740): libnice-DEBUG: Agent 0x6805120 : pair 0x58612c0 state WAITING
(process:59740): libnice-DEBUG: Agent 0x6805120 : pair 0x5861000 state IN_PROGRESS
(process:59740): libnice-DEBUG: Agent 0x6805120 : STUN-CC REQ [fe80::7b4b:2dc5:626c:6425]:52788 --> [fe80::7b4b:2dc5:626c:6425]:63277, socket=15, pair=0x5861000 (c-id:1), tie=11461964759089149272, username='YShf:V3Eh' (9), password='v3aGQUmYYbNk1L5dPX3Imp' (22), prio=922747391, controlled.
(process:59740): libnice-DEBUG: Agent 0x6805120 : conn_check_send: set cand_use=0 (aggressive nomination).
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 message integrity:
(process:59740): libnice-stun-DEBUG:   key     : 0x7633614751556d5959624e6b314c3564505833496d70
(process:59740): libnice-stun-DEBUG:   sent    : 0x129417c3ee5a6e2a5a31e46da975470fccd5f885
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 fingerprint: 0xe6aab576
(process:59740): libnice-DEBUG: Agent 0x6805120: conncheck created 88 - 0x7005a30
(process:59740): libnice-DEBUG: Agent 0x6805120 : timer set to 500ms, waiting+in_progress=5, nb_active=1
(process:59740): libnice-DEBUG: Agent 0x6805120 : stream 1: timer tick #1: 0 frozen, 1 in-progress, 4 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid.
(process:59740): libnice-DEBUG: Agent 0x6805000 : Pair 0x5861370 with s/c-id 1/1 (1:1) unfrozen.
(process:59740): libnice-DEBUG: Agent 0x6805000 : pair 0x5861370 state WAITING
(process:59740): libnice-DEBUG: Agent 0x6805000 : Pair 0x58614d0 with s/c-id 1/1 (2:2) unfrozen.
(process:59740): libnice-DEBUG: Agent 0x6805000 : pair 0x58614d0 state WAITING
(process:59740): libnice-DEBUG: Agent 0x6805000 : Pair 0x5861580 with s/c-id 1/1 (1:3) unfrozen.
(process:59740): libnice-DEBUG: Agent 0x6805000 : pair 0x5861580 state WAITING
(process:59740): libnice-DEBUG: Agent 0x6805000 : Pair 0x5861420 with s/c-id 1/1 (3:1) unfrozen.
(process:59740): libnice-DEBUG: Agent 0x6805000 : pair 0x5861420 state WAITING
(process:59740): libnice-DEBUG: Agent 0x6805000 : Pair 0x5861630 with s/c-id 1/1 (3:3) unfrozen.
(process:59740): libnice-DEBUG: Agent 0x6805000 : pair 0x5861630 state WAITING
(process:59740): libnice-DEBUGComponentStateChanged connected
: Agent 0x6805000 : pair 0x5861370 state IN_PROGRESS
(process:59740): libnice-DEBUG: Agent 0x6805000 : STUN-CC REQ [fe80::7b4b:2dc5:626c:6425]:63277 --> [fe80::7b4b:2dc5:626c:6425]:52788, socket=12, pair=0x5861370 (c-id:1), tie=10301687668629697011, username='V3Eh:YShf' (9), password='zkcxSvhF0NAmqWmpDbMmfl' (22), prio=922747391, controlling.
(process:59740): libnice-DEBUG: Agent 0x6805000 : conn_check_send: set cand_use=1 (aggressive nomination).
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 message integrity:
(process:59740): libnice-stun-DEBUG:   key     : 0x7a6b637853766846304e416d71576d7044624d6d666c
(process:59740): libnice-stun-DEBUG:   sent    : 0xe72906e903910447a7ce42642f4fe6239e0f1583
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 fingerprint: 0xfcfff0e7
(process:59740): libnice-DEBUG: Agent 0x6805000: conncheck created 92 - 0x585e830
(process:59740): libnice-DEBUG: Agent 0x6805000 : timer set to 500ms, waiting+in_progress=5, nb_active=1
(process:59740): libnice-DEBUG: Agent 0x6805000 : stream 1: timer tick #1: 0 frozen, 1 in-progress, 4 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid.
(process:59740): libnice-DEBUG: Agent 0x6805120 : pair 0x5861160 state IN_PROGRESS
(process:59740): libnice-DEBUG: Agent 0x6805120 : STUN-CC REQ [192.168.1.22]:62956 --> [192.168.1.22]:52785, socket=16, pair=0x5861160 (c-id:1), tie=11461964759089149272, username='YShf:V3Eh' (9), password='v3aGQUmYYbNk1L5dPX3Imp' (22), prio=922747389, controlled.
(process:59740): libnice-DEBUG: Agent 0x6805120 : conn_check_send: set cand_use=0 (aggressive nomination).
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 message integrity:
(process:59740): libnice-stun-DEBUG:   key     : 0x7633614751556d5959624e6b314c3564505833496d70
(process:59740): libnice-stun-DEBUG:   sent    : 0x3a4c3619f72bae3fa185affaebee2c607d88760a
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 fingerprint: 0xab827d90
(process:59740): libnice-DEBUG: Agent 0x6805120: conncheck created 88 - 0x6000e30
(process:59740): libnice-DEBUG: Agent 0x6805120 : timer set to 500ms, waiting+in_progress=5, nb_active=1
(process:59740): libnice-DEBUG: Agent 0x6805000 : pair 0x58614d0 state IN_PROGRESS
(process:59740): libnice-DEBUG: Agent 0x6805000 : STUN-CC REQ [192.168.1.22]:52785 --> [192.168.1.22]:62956, socket=13, pair=0x58614d0 (c-id:1), tie=10301687668629697011, username='V3Eh:YShf' (9), password='zkcxSvhF0NAmqWmpDbMmfl' (22), prio=922747389, controlling.
(process:59740): libnice-DEBUG: Agent 0x6805000 : conn_check_send: set cand_use=1 (aggressive nomination).
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 message integrity:
(process:59740): libnice-stun-DEBUG:   key     : 0x7a6b637853766846304e416d71576d7044624d6d666c
(process:59740): libnice-stun-DEBUG:   sent    : 0xe6728101211167b59e178d58dd74c762c74205be
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 fingerprint: 0x07598ff5
(process:59740): libnice-DEBUG: Agent 0x6805000: conncheck created 92 - 0x6001430
(process:59740): libnice-DEBUG: Agent 0x6805000 : timer set to 500ms, waiting+in_progress=5, nb_active=1
(process:59740): libnice-DEBUG: Agent 0x6805000: inbound STUN packet for 1/1 (stream/component) from [192.168.1.22]:62956 (88 octets) :
(process:59740): libnice-stun-DEBUG: STUN demux: OK!
(process:59740): libnice-stun-DEBUG: Comparing username/ufrag of len 9 and 4, equal=0
(process:59740): libnice-stun-DEBUG:   username: 0x595368663a56334568
(process:59740): libnice-stun-DEBUG:   ufrag:    0x59536866
(process:59740): libnice-stun-DEBUG: Found valid username, returning password: 'v3aGQUmYYbNk1L5dPX3Imp'
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 fingerprint:
(process:59740): libnice-stun-DEBUG:   key     : 0x7633614751556d5959624e6b314c3564505833496d70
(process:59740): libnice-stun-DEBUG:   expected: 0x3a4c3619f72bae3fa185affaebee2c607d88760a
(process:59740): libnice-stun-DEBUG:   received: 0x3a4c3619f72bae3fa185affaebee2c607d88760a
(process:59740): libnice-stun-DEBUG: STUN auth: OK!
(process:59740): libnice-stun-DEBUG: STUN unknown: 0 mandatory attribute(s)!
(process:59740): libnice-stun-DEBUG: STUN Reply (buffer size = 1300)...
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 message integrity:
(process:59740): libnice-stun-DEBUG:   key     : 0x7633614751556d5959624e6b314c3564505833496d70
(process:59740): libnice-stun-DEBUG:   sent    : 0x01526ce50f1acdc83865ddcdafdb77b3bc39446b
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 fingerprint: 0xc82556c2
(process:59740): libnice-stun-DEBUG:  All done (response size: 80)
(process:59740): libnice-DEBUG: Agent 0x6805000 :  1:1 Adding valid source candidate: 192.168.1.22:62956 trans: 0
(process:59740): libnice-DEBUG: Agent 0x6805000 : STUN-CC RESP to '192.168.1.22:62956', socket=13, len=80, cand=0x6003d30 (c-id:1), use-cand=0.
(process:59740): libnice-DEBUG: Agent 0x6805000 : Found a matching pair 0x58614d0 (2:2) (in progress) ...
(process:59740): libnice-DEBUG: Agent 0x6805000 : pair 0x58614d0 added for a triggered check.
(process:59740): libnice-DEBUG: Agent 0x6805000 : pair 0x58614d0 state IN_PROGRESS
(process:59740): libnice-DEBUG: agent_recv_message_unlocked: Valid STUN packet received.
(process:59740): libnice-DEBUG: Agent 0x6805120: inbound STUN packet for 1/1 (stream/component) from [192.168.1.22]:52785 (92 octets) :
(process:59740): libnice-stun-DEBUG: STUN demux: OK!
(process:59740): libnice-stun-DEBUG: Comparing username/ufrag of len 9 and 4, equal=0
(process:59740): libnice-stun-DEBUG:   username: 0x563345683a59536866
(process:59740): libnice-stun-DEBUG:   ufrag:    0x56334568
(process:59740): libnice-stun-DEBUG: Found valid username, returning password: 'zkcxSvhF0NAmqWmpDbMmfl'
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 fingerprint:
(process:59740): libnice-stun-DEBUG:   key     : 0x7a6b637853766846304e416d71576d7044624d6d666c
(process:59740): libnice-stun-DEBUG:   expected: 0xe6728101211167b59e178d58dd74c762c74205be
(process:59740): libnice-stun-DEBUG:   received: 0xe6728101211167b59e178d58dd74c762c74205be
(process:59740): libnice-stun-DEBUG: STUN auth: OK!
(process:59740): libnice-stun-DEBUG: STUN unknown: 0 mandatory attribute(s)!
(process:59740): libnice-stun-DEBUG: STUN Reply (buffer size = 1300)...
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 message integrity:
(process:59740): libnice-stun-DEBUG:   key     : 0x7a6b637853766846304e416d71576d7044624d6d666c
(process:59740): libnice-stun-DEBUG:   sent    : 0x5ac7b95e29f2a600559cb402af05274c07b4eac0
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 fingerprint: 0x20b401f6
(process:59740): libnice-stun-DEBUG:  All done (response size: 80)
(process:59740): libnice-DEBUG: Agent 0x6805120 :  1:1 Adding valid source candidate: 192.168.1.22:52785 trans: 0
(process:59740): libnice-DEBUG: Agent 0x6805120 : STUN-CC RESP to '192.168.1.22:52785', socket=16, len=80, cand=0x5860100 (c-id:1), use-cand=1.
(process:59740): libnice-DEBUG: Agent 0x6805120 : Found a matching pair 0x5861160 (2:2) (in progress) ...
(process:59740): libnice-DEBUG: Agent 0x6805120 : pair 0x5861160 added for a triggered check.
(process:59740): libnice-DEBUG: Agent 0x6805120 : pair 0x5861160 state IN_PROGRESS
(process:59740): libnice-DEBUG: Agent 0x6805120 : pair 0x5861160 (2:2) is in-progress, will be nominated on response receipt.
(process:59740): libnice-DEBUG: agent_recv_message_unlocked: Valid STUN packet received.
(process:59740): libnice-DEBUG: Agent 0x6805120: inbound STUN packet for 1/1 (stream/component) from [192.168.1.22]:52785 (80 octets) :
(process:59740): libnice-stun-DEBUG: STUN demux: OK!
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 fingerprint:
(process:59740): libnice-stun-DEBUG:   key     : 0x7633614751556d5959624e6b314c3564505833496d70
(process:59740): libnice-stun-DEBUG:   expected: 0x01526ce50f1acdc83865ddcdafdb77b3bc39446b
(process:59740): libnice-stun-DEBUG:   received: 0x01526ce50f1acdc83865ddcdafdb77b3bc39446b
(process:59740): libnice-stun-DEBUG: STUN auth: OK!
(process:59740): libnice-stun-DEBUG: STUN unknown: 0 mandatory attribute(s)!
(process:59740): libnice-stun-DEBUG: Received 80-bytes STUN message
(process:59740): libnice-stun-DEBUG: Mapped address found!
(process:59740): libnice-DEBUG: Agent 0x6805120 : stun_bind_process/conncheck for 0x5861160: controlled,res=success,stun#=0.
(process:59740): libnice-DEBUG: Agent 0x6805120 : conncheck 0x5861160 MATCHED.
(process:59740): libnice-DEBUG: Agent 0x6805120 : conncheck 0x5861160 SUCCEEDED.
(process:59740): libnice-DEBUG: Agent 0x6805120 : Updating nominated flag (UDP): ok_pair=0x5861160 (0/1) p=0x5861160 (0/1) (ucnc/mnora)
(process:59740): libnice-DEBUG: Agent 0x6805120 : marking pair 0x5861160 (2:2) as nominated (aggressive nomination, controlled, mark_on_response=1).
(process:59740): libnice-DEBUG: Agent 0x6805120 : changing SELECTED PAIR for component 1: 2:2 (prio:4323457834722264060).
(process:59740): libnice-DEBUG: setting SELECTED PAIR for component 1: 2:2 (prio:4323457834722264060).
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 fingerprint: 0x3314794b
(process:59740): libnice-DEBUG: Agent 0x6805120 : stun_bind_keepalive for pair 0x5833c60 res 28.
(process:59740): libnice-pseudotcp-DEBUG: PseudoTcpSocket 0x521b4e0 LISTEN: State LISTEN ? SYN-SENT.
(process:59740): libnice-pseudotcp-DEBUG: PseudoTcpSocket 0x521b4e0 SYN-SENT: Attempting send with flags 0.
(process:59740): libnice-pseudotcp-verbose-DEBUG: PseudoTcpSocket 0x521b4e0 SYN-SENT: [cwnd: 180  nWindow: 7  nInFlight: 0 nAvailable: 7 nQueued: 7 nEmpty: 92153  nWaiting: 7 ssthresh: 61440]
(process:59740): libnice-pseudotcp-verbose-DEBUG: PseudoTcpSocket 0x521b4e0 SYN-SENT: Sending <CONV=0><FLG=2><SEQ=0:7><ACK=0><WND=61440><TS=1810><TSR=0><LEN=7>
(process:59740): libnice-DEBUG: Agent 0x6805120: Local selected pair: 1:1 2 UDP 192.168.1.22:62956 HOST
(process:59740): libnice-DEBUG: Agent 0x6805120: Remote selected pair: 1:1 2 UDP 192.168.1.22:52785 HOST
(process:59740): libnice-DEBUG: Agent 0x6805120 : stream 1 component 1 STATE-CHANGE connecting -> connected.
(process:59740): libnice-DEBUG: Agent 0x6805120: Finding highest priority for component 1
(process:59740): libnice-DEBUG: Agent 0x6805120: Pruning pending checks. Highest nominated priority is 4323457834722264060
(process:59740): libnice-DEBUG: Agent 0x6805120 : pair 0x5861000 kept IN_PROGRESS because priority 4323457839017231358 is higher than currently nominated pair 4323457834722264060
(process:59740): libnice-DEBUG: Agent 0x6805120 : pair 0x58610b0 removed.
(process:59740): libnice-DEBUG: Agent 0x6805120 : pair 0x5861210 removed.
(process:59740): libnice-DEBUG: Agent 0x6805120 : pair 0x58612c0 removed.
(process:59740): libnice-DEBUG: Agent 0x6805120 : conn.check list status: 1 nominated, 1 valid, c-id 1.
(process:59740): libnice-DEBUG: agent_recv_message_unlocked: Valid STUN packet received.
(process:59740): libnice-DEBUG: Agent 0x6805000: inbound STUN packet for 1/1 (stream/component) from [192.168.1.22]:62956 (80 octets) :
(process:59740): libnice-stun-DEBUG: STUN demux: OK!
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 fingerprint:
(process:59740): libnice-stun-DEBUG:   key     : 0x7a6b637853766846304e416d71576d7044624d6d666c
(process:59740): libnice-stun-DEBUG:   expected: 0x5ac7b95e29f2a600559cb402af05274c07b4eac0
(process:59740): libnice-stun-DEBUG:   received: 0x5ac7b95e29f2a600559cb402af05274c07b4eac0
(process:59740): libnice-stun-DEBUG: STUN auth: OK!
(process:59740): libnice-stun-DEBUG: STUN unknown: 0 mandatory attribute(s)!
(process:59740): libnice-stun-DEBUG: Received 80-bytes STUN message
(process:59740): libnice-stun-DEBUG: Mapped address found!
(process:59740): libnice-DEBUG: Agent 0x6805000 : stun_bind_process/conncheck for 0x58614d0: controlling,res=success,stun#=0.
(process:59740): libnice-DEBUG: Agent 0x6805000 : conncheck 0x58614d0 MATCHED.
(process:59740): libnice-DEBUG: Agent 0x6805000 : conncheck 0x58614d0 SUCCEEDED.
(process:59740): libnice-DEBUG: Agent 0x6805000 : Updating nominated flag (UDP): ok_pair=0x58614d0 (0/0) p=0x58614d0 (0/0) (ucnc/mnora)
(process:59740): libnice-DEBUG: Agent 0x6805000 : marking pair 0x58614d0 (2:2) as nominated (aggressive nomination, controlling).
(process:59740): libnice-DEBUG: Agent 0x6805000 : changing SELECTED PAIR for component 1: 2:2 (prio:4323457834722264060).
(process:59740): libnice-DEBUG: setting SELECTED PAIR for component 1: 2:2 (prio:4323457834722264060).
(process:59740): libnice-stun-DEBUG:  Message HMAC-SHA1 fingerprint: 0x845a12ff
(process:59740): libnice-DEBUG: Agent 0x6805000 : stun_bind_keepalive for pair 0x680b260 res 28.
(process:59740): libnice-pseudotcp-DEBUG: PseudoTcpSocket 0x521b0a0 LISTEN: State LISTEN ? SYN-SENT.
(process:59740): libnice-pseudotcp-DEBUG: PseudoTcpSocket 0x521b0a0 SYN-SENT: Attempting send with flags 0.
(process:59740): libnice-pseudotcp-verbose-DEBUG: PseudoTcpSocket 0x521b0a0 SYN-SENT: [cwnd: 180  nWindow: 7  nInFlight: 0 nAvailable: 7 nQueued: 7 nEmpty: 92153  nWaiting: 7 ssthresh: 61440]
(process:59740): libnice-pseudotcp-verbose-DEBUG: PseudoTcpSocket 0x521b0a0 SYN-SENT: Sending <CONV=0><FLG=2><SEQ=0:7><ACK=0><WND=61440><TS=1810><TSR=0><LEN=7>
(process:59740): libnice-DEBUG: Agent 0x6805000: Local selected pair: 1:1 2 UDP 192.168.1.22:52785 HOST
(process:59740): libnice-DEBUG: Agent 0x6805000: Remote selected pair: 1:1 2 UDP 192.168.1.22:62956 HOST
(process:59740): libnice-DEBUG: Agent 0x6805000 : stream 1 component 1 STATE-CHANGE connecting -> connected.
(process:59740): libnice-DEBUG: Agent 0x6805000: Finding highest priority for component 1
(process:59740): libnice-DEBUG: Agent 0x6805000: Pruning pending checks. Highest nominated priority is 4323457834722264060
(process:59740): libnice-DEBUG: Agent 0x6805000 : pair 0x5861370 kept IN_PROGRESS because priority 4323457839017231358 is higher than currently nominated pair 4323457834722264060
(process:59740): libnice-DEBUG: Agent 0x6805000 : pair 0x5861580 removed.
(process:59740): libnice-DEBUG: Agent 0x6805000 : pair 0x5861420 removed.
(process:59740): libnice-DEBUG: Agent 0x6805000 : pair 0x5861630 removed.
(process:59740): libnice-DEBUG: Agent 0x6805000 : conn.check list status: 1 nominated, 1 valid, c-id 1.
(process:59740): libnice-DEBUG: agent_recv_message_unlocked: Valid STUN packet received.
(process:59740): libnice-DEBUG: Agent 0x6805000: inbound STUN packet for 1/1 (stream/component) from [192.168.1.22]:62956 (28 octets) :
(process:59740): libnice-stun-DEBUG: STUN demux: OK!
(process:59740): libnice-DEBUG: Agent 0x6805000 : Integrity check failed - bad request.
(process:59740): libnice-DEBUG: agent_recv_message_unlocked: Valid STUN packet received.
(process:59740): libnice-pseudotcp-verbose-DEBUG: PseudoTcpSocket 0x521b0a0 SYN-SENT: Received <CONV=0><FLG=2><SEQ=0:7><ACK=0><WND=61440><TS=1810><TSR=0><LEN=7>
(process:59740): libnice-pseudotcp-DEBUG: PseudoTcpSocket 0x521b0a0 SYN-SENT: Setting scale factor to 0
(process:59740): libnice-pseudotcp-DEBUG: PseudoTcpSocket 0x521b0a0 SYN-SENT: FIN-ACK support enabled.
(process:59740): libnice-pseudotcp-DEBUG: PseudoTcpSocket 0x521b0a0 SYN-SENT: State SYN-SENT ? ESTABLISHED.
(process:59740): libnice-pseudotcp-DEBUG: PseudoTcpSocket 0x521b0a0 ESTABLISHED: Adjusting mss to 1284 bytes
(process:59740): libnice-DEBUG: Agent 0x6805000: s1:1 pseudo Tcp socket Opened
(process:59740): libnice-pseudotcp-DEBUG: PseudoTcpSocket 0x521b0a0 ESTABLISHED: Attempting send with flags 1.
(process:59740): libnice-pseudotcp-verbose-DEBUG: PseudoTcpSocket 0x521b0a0 ESTABLISHED: [cwnd: 1284  nWindow: 1284  nInFlight: 7 nAvailable: 0 nQueued: 7 nEmpty: 92153  nWaiting: 0 ssthresh: 61440]
(process:59740): libnice-DEBUG: Agent 0x6805120: inbound STUN packet for 1/1 (stream/component) from [192.168.1.22]:52785 (28 octets) :
(process:59740): libnice-stun-DEBUG: STUN demux: OK!
(process:59740): libnice-DEBUG: Agent 0x6805120 : Integrity check failed - bad request.
(process:59740): libnice-DEBUG: agent_recv_message_unlocked: Valid STUN packet received.
(process:59740): libnice-pseudotcp-verbose-DEBUG: PseudoTcpSocket 0x521b4e0 SYN-SENT: Received <CONV=0><FLG=2><SEQ=0:7><ACK=0><WND=61440><TS=1810><TSR=0><LEN=7>
(process:59740): libnice-pseudotcp-DEBUG: PseudoTcpSocket 0x521b4e0 SYN-SENT: Setting scale factor to 0
(process:59740): libnice-pseudotcp-DEBUG: PseudoTcpSocket 0x521b4e0 SYNComponentStateChanged connected
Failed code: -1
Failed code: -1


More information about the nice mailing list