Go to:
Gentoo Home
Documentation
Forums
Lists
Bugs
Planet
Store
Wiki
Get Gentoo!
Gentoo's Bugzilla – Attachment 671197 Details for
Bug 754243
net-libs/libnice-0.1.15 now fails test-send-recv test
Home
|
New
–
[Ex]
|
Browse
|
Search
|
Privacy Policy
|
[?]
|
Reports
|
Requests
|
Help
|
New Account
|
Log In
[x]
|
Forgot Password
Login:
[x]
test-suite.log
test-suite.log (text/x-log), 66.71 KB, created by
Paolo Pedroni
on 2020-11-13 11:41:26 UTC
(
hide
)
Description:
test-suite.log
Filename:
MIME Type:
Creator:
Paolo Pedroni
Created:
2020-11-13 11:41:26 UTC
Size:
66.71 KB
patch
obsolete
>========================================== > libnice 0.1.15: tests/test-suite.log >========================================== > ># TOTAL: 32 ># PASS: 31 ># SKIP: 0 ># XFAIL: 0 ># FAIL: 1 ># XPASS: 0 ># ERROR: 0 > >.. contents:: :depth: 2 > >FAIL: test-send-recv >==================== > >(test-send-recv:5167): libnice-tests-DEBUG: 11:59:49.847: Running test (0, 0, 10000, 50, 4, 0, 2, 0, 0)? >(test-send-recv:5167): libnice-DEBUG: 11:59:49.848: Agent 0x559856332000 : Property set, role is already "controlling". >(test-send-recv:5167): libnice-DEBUG: 11:59:49.848: Agent 0x559856332150 : Property set, changing role to "controlled". >(test-send-recv:5167): libnice-DEBUG: 11:59:49.848: Created NiceStream (1 created, 0 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.848: Created NiceComponent (1 created, 0 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.849: Created NiceComponent (2 created, 0 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.849: Agent 0x559856332000 : allocating stream id 1 (0x559856335fc0) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.849: Created NiceStream (2 created, 0 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.849: Created NiceComponent (3 created, 0 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.849: Created NiceComponent (4 created, 0 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.849: Agent 0x559856332150 : allocating stream id 1 (0x55985633e4b0) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.849: Agent 0x559856332000 : In ICE-FULL mode, starting candidate gathering. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.849: Agent 0x559856332000 : UPnP property Disabled >(test-send-recv:5167): libnice-DEBUG: 11:59:49.849: Agent 0x559856332000: Trying to create host candidate on port 0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.849: Agent 0x559856332000: Could not set IPV6 socket ToS: Protocol not available >(test-send-recv:5167): libnice-DEBUG: 11:59:49.849: Component 0x5598563365e0: Attach source (stream 1). >(test-send-recv:5167): libnice-DEBUG: 11:59:49.849: Attaching source 0x55985634c830 (socket 0x55985632d5e0, FD 10) to context 0x559856339fe0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.849: Agent 0x559856332000: Trying to create host candidate on port 0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.849: Agent 0x559856332000: Could not set IPV6 socket ToS: Protocol not available >(test-send-recv:5167): libnice-DEBUG: 11:59:49.849: Component 0x55985633a530: Attach source (stream 1). >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Attaching source 0x55985634c920 (socket 0x559856349f20, FD 11) to context 0x55985633def0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332000: Candidate gathering FINISHED, no scheduled items. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332000: gathered UDP local candidate : [127.0.0.1]:47969 for s1/c1. U/P 'mJ3W143ZmE8QY6sR'/'(null)' >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332000: gathered UDP local candidate : [127.0.0.1]:42466 for s1/c2. U/P 'mJ3W143ZmE8QY6sR'/'(null)' >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332150: set_remote_candidates 1 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332150 : Adding UDP remote candidate with addr [127.0.0.1]:47969 for s1/c1. U/P 'mJ3W143ZmE8QY6sR'/'(null)' prio: 1000 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332150 : In ICE-FULL mode, starting candidate gathering. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332150 : UPnP property Disabled >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332150: Trying to create host candidate on port 0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332150 : creating a new pair >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332150 : pair 0x55985634e000 state FROZEN (priv_add_new_check_pair) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332150 : new pair 0x55985634e000 : [127.0.0.1]:55394 --> [127.0.0.1]:47969 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332150 : added a new pair 0x55985634e000 with foundation '1:1' to stream 1 component 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332150 : stream 1 component 1 STATE-CHANGE disconnected -> connecting. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332150: Could not set IPV6 socket ToS: Protocol not available >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Component 0x55985633e910: Attach source (stream 1). >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Attaching source 0x55985634e870 (socket 0x55985634b020, FD 12) to context 0x559856342240 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332150: Trying to create host candidate on port 0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332150: Could not set IPV6 socket ToS: Protocol not available >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Component 0x5598563424a0: Attach source (stream 1). >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Attaching source 0x55985634e960 (socket 0x55985634b090, FD 13) to context 0x559856345dd0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332150: Candidate gathering FINISHED, no scheduled items. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332150: gathered UDP local candidate : [127.0.0.1]:55394 for s1/c1. U/P 'vgpe6kH1s1DLvad7'/'(null)' >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332150: gathered UDP local candidate : [127.0.0.1]:52054 for s1/c2. U/P 'vgpe6kH1s1DLvad7'/'(null)' >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332000: set_remote_candidates 1 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332000 : Adding UDP remote candidate with addr [127.0.0.1]:55394 for s1/c1. U/P 'vgpe6kH1s1DLvad7'/'(null)' prio: 1000 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332000 : creating a new pair >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332000 : pair 0x55985634e0b0 state FROZEN (priv_add_new_check_pair) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332000 : new pair 0x55985634e0b0 : [127.0.0.1]:47969 --> [127.0.0.1]:55394 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332000 : added a new pair 0x55985634e0b0 with foundation '1:1' to stream 1 component 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.850: Agent 0x559856332000 : stream 1 component 1 STATE-CHANGE disconnected -> connecting. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.870: Agent 0x559856332150 : Pair 0x55985634e000 with s/c-id 1/1 (1:1) unfrozen. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.870: Agent 0x559856332150 : pair 0x55985634e000 state WAITING (priv_conn_check_unfreeze_next) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.870: Agent 0x559856332150 : pair 0x55985634e000 state IN_PROGRESS (priv_conn_check_initiate) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.870: Agent 0x559856332150 : STUN-CC REQ [127.0.0.1]:55394 --> [127.0.0.1]:47969, socket=12, pair=0x55985634e000 (c-id:1), tie=13855691594298473352, username='mJ3W143ZmE8QY6sRvgpe6kH1s1DLvad7' (32), password='' (0), prio=900, controlled. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.870: Agent 0x559856332150: conncheck created 56 - 0x7fde74003380 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.870: Agent 0x559856332150 : timer set to 100ms, waiting+in_progress=1, nb_active=1 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.871: Agent 0x559856332150 : stream 1: timer tick #1: 0 frozen, 1 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.871: Agent 0x559856332000 : Pair 0x55985634e0b0 with s/c-id 1/1 (1:1) unfrozen. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.871: Agent 0x559856332000 : pair 0x55985634e0b0 state WAITING (priv_conn_check_unfreeze_next) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.871: Agent 0x559856332000 : pair 0x55985634e0b0 state IN_PROGRESS (priv_conn_check_initiate) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.871: Agent 0x559856332000 : STUN-CC REQ [127.0.0.1]:47969 --> [127.0.0.1]:55394, socket=10, pair=0x55985634e0b0 (c-id:1), tie=16125578319856622139, username='vgpe6kH1s1DLvad7mJ3W143ZmE8QY6sR' (32), password='' (0), prio=900, controlling. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.871: Agent 0x559856332000: conncheck created 56 - 0x7fde7c0037d0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.871: Agent 0x559856332000 : timer set to 100ms, waiting+in_progress=1, nb_active=1 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.871: Agent 0x559856332000 : stream 1: timer tick #1: 0 frozen, 1 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332150: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:47969 (56 octets) : >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: First username: 0x76677065366b48317331444c766164376d4a33573134335a6d45385159367352 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: Second uname: 0x76677065366b48317331444c766164376d4a33573134335a6d45385159367352 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: STUN Reply (buffer size = 1300)... >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332000: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:55394 (56 octets) : >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: All done (response size: 68) >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332150 : 1:1 Adding valid source candidate: 127.0.0.1:47969 trans: 0 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: First username: 0x6d4a33573134335a6d4538515936735276677065366b48317331444c76616437 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: Second uname: 0x6d4a33573134335a6d4538515936735276677065366b48317331444c76616437 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: STUN Reply (buffer size = 1300)... >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332150 : STUN-CC RESP to '127.0.0.1:47969', socket=12, len=68, cand=0x559856330d20 (c-id:1), use-cand=1. >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: All done (response size: 68) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332000 : 1:1 Adding valid source candidate: 127.0.0.1:55394 trans: 0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332000 : STUN-CC RESP to '127.0.0.1:55394', socket=10, len=68, cand=0x559856330b70 (c-id:1), use-cand=1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332150 : Found a matching pair 0x55985634e000 (1:1) (IN_PROGRESS) ... >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332000 : Found a matching pair 0x55985634e0b0 (1:1) (IN_PROGRESS) ... >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332150 : pair 0x55985634e000 added for a triggered check. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332000 : pair 0x55985634e0b0 added for a triggered check. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332150 : pair 0x55985634e000 state IN_PROGRESS (priv_add_pair_to_triggered_check_queue) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332000 : pair 0x55985634e0b0 state IN_PROGRESS (priv_add_pair_to_triggered_check_queue) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332150 : marking pair 0x55985634e000 (1:1) as nominated >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332000 : marking pair 0x55985634e0b0 (1:1) as nominated >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332150 : conn.check list status: 0 nominated, 0 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332000 : conn.check list status: 0 nominated, 0 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332150: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:47969 (68 octets) : >(test-send-recv:5167): libnice-DEBUG: 11:59:49.927: Agent 0x559856332000: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:55394 (68 octets) : >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: First username: 0x76677065366b48317331444c766164376d4a33573134335a6d45385159367352 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.927: First username: 0x6d4a33573134335a6d4538515936735276677065366b48317331444c76616437 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: Second uname: 0x76677065366b48317331444c766164376d4a33573134335a6d45385159367352 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: Second uname: 0x6d4a33573134335a6d4538515936735276677065366b48317331444c76616437 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: Received 68-bytes STUN message >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: Received 68-bytes STUN message >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: No XOR-MAPPED-ADDRESS: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: No XOR-MAPPED-ADDRESS: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: Mapped address found! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: Mapped address found! >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000 : stun_bind_process/conncheck for 0x55985634e0b0: controlling,res=success,stun#=0. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150 : stun_bind_process/conncheck for 0x55985634e000: controlled,res=success,stun#=0. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000 : pair 0x55985634e0b0 MATCHED. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150 : pair 0x55985634e000 MATCHED. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000 : pair 0x55985634e0b0 state SUCCEEDED (priv_process_response_check_for_reflexive) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150 : pair 0x55985634e000 state SUCCEEDED (priv_process_response_check_for_reflexive) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000 : changing SELECTED PAIR for component 1: 1:1 (prio:4294967298000). >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150 : changing SELECTED PAIR for component 1: 1:1 (prio:4294967298000). >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: setting SELECTED PAIR for component 1: 1:1 (prio:4294967298000). >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: setting SELECTED PAIR for component 1: 1:1 (prio:4294967298000). >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000 : Keepalive STUN-CC REQ to '127.0.0.1:55394', (c-id:1), username='vgpe6kH1s1DLvad7mJ3W143ZmE8QY6sR' (32), password='' (0), priority=0. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150 : Keepalive STUN-CC REQ to '127.0.0.1:47969', (c-id:1), username='mJ3W143ZmE8QY6sRvgpe6kH1s1DLvad7' (32), password='' (0), priority=0. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000: conncheck created 56 - 0x5598563366a0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150: conncheck created 56 - 0x55985633e9d0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000: Local selected pair: 1:1 1 UDP 127.0.0.1:47969 HOST >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150: Local selected pair: 1:1 1 UDP 127.0.0.1:55394 HOST >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000: Remote selected pair: 1:1 1 UDP 127.0.0.1:55394 HOST >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150: Remote selected pair: 1:1 1 UDP 127.0.0.1:47969 HOST >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000 : stream 1 component 1 STATE-CHANGE connecting -> connected. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150 : stream 1 component 1 STATE-CHANGE connecting -> connected. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000 : stream 1 component 1 STATE-CHANGE connected -> ready. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150 : stream 1 component 1 STATE-CHANGE connected -> ready. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:55394 (56 octets) : >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:47969 (56 octets) : >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: First username: 0x6d4a33573134335a6d4538515936735276677065366b48317331444c76616437 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: First username: 0x76677065366b48317331444c766164376d4a33573134335a6d45385159367352 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: Second uname: 0x6d4a33573134335a6d4538515936735276677065366b48317331444c76616437 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: Second uname: 0x76677065366b48317331444c766164376d4a33573134335a6d45385159367352 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: STUN Reply (buffer size = 1300)... >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: STUN Reply (buffer size = 1300)... >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: All done (response size: 68) >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: All done (response size: 68) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000 : STUN-CC RESP to '127.0.0.1:55394', socket=10, len=68, cand=0x559856330b70 (c-id:1), use-cand=1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150 : STUN-CC RESP to '127.0.0.1:47969', socket=12, len=68, cand=0x559856330d20 (c-id:1), use-cand=1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000 : Found a matching pair 0x55985634e0b0 (1:1) (SUCCEEDED) ... >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150 : Found a matching pair 0x55985634e000 (1:1) (SUCCEEDED) ... >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000 : nothing to do for pair 0x55985634e0b0. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150 : nothing to do for pair 0x55985634e000. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000 : marking pair 0x55985634e0b0 (1:1) as nominated >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150 : marking pair 0x55985634e000 (1:1) as nominated >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:55394 (68 octets) : >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:47969 (68 octets) : >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: First username: 0x76677065366b48317331444c766164376d4a33573134335a6d45385159367352 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: First username: 0x6d4a33573134335a6d4538515936735276677065366b48317331444c76616437 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: Second uname: 0x76677065366b48317331444c766164376d4a33573134335a6d45385159367352 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.928: Second uname: 0x6d4a33573134335a6d4538515936735276677065366b48317331444c76616437 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332000 : Keepalive for selected pair received. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: Agent 0x559856332150 : Keepalive for selected pair received. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.928: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.932: Agent 0x559856332000 : waiting 1000 msecs before checking for failed components. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.932: Agent 0x559856332150 : waiting 1000 msecs before checking for failed components. >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.932: STUN error: Incomplete message: 200 of 5012 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.932: STUN error: Incomplete message: 200 of 5012 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.932: STUN error: Incomplete message: 200 of 56156 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.932: STUN error: Incomplete message: 200 of 56156 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.932: STUN error: Incomplete message: 200 of 900 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.932: STUN error: Incomplete message: 200 of 900 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.932: STUN error: Incomplete message: 200 of 62324 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.932: STUN error: Incomplete message: 200 of 62324 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.933: STUN error: Incomplete message: 200 of 58212 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.933: STUN error: Incomplete message: 200 of 58212 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.933: STUN error: Incomplete message: 200 of 2956 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.933: STUN error: Incomplete message: 200 of 2956 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.933: STUN error: Incomplete message: 200 of 64380 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.933: STUN error: Incomplete message: 200 of 64380 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.933: STUN error: Incomplete message: 200 of 60268 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.933: STUN error: Incomplete message: 200 of 60268 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.933: STUN error: Incomplete message: 200 of 5012 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.933: STUN error: Incomplete message: 200 of 5012 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.933: STUN error: Incomplete message: 200 of 56156 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.933: STUN error: Incomplete message: 200 of 56156 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.933: STUN error: Incomplete message: 200 of 900 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.933: STUN error: Incomplete message: 200 of 900 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.933: STUN error: Incomplete message: 200 of 62324 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.933: STUN error: Incomplete message: 200 of 62324 bytes! >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Agent 0x559856332150, freeing conncheck_list of stream 0x55985633e4b0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Agent 0x559856332000, freeing conncheck_list of stream 0x559856335fc0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Free socket sources for component 0x55985633e910. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Free socket sources for component 0x5598563365e0. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Detaching source 0x55985634e870 (socket 0x55985634b020, FD 12) from context 0x559856342240 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Detaching source 0x55985634c830 (socket 0x55985632d5e0, FD 10) from context 0x559856339fe0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Free socket sources for component 0x55985633a530. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Free socket sources for component 0x5598563424a0. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Detaching source 0x55985634c920 (socket 0x559856349f20, FD 11) from context 0x55985633def0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Detaching source 0x55985634e960 (socket 0x55985634b090, FD 13) from context 0x559856345dd0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Destroyed NiceComponent (4 created, 1 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Destroyed NiceComponent (4 created, 2 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Destroyed NiceComponent (4 created, 3 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Destroyed NiceComponent (4 created, 4 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Destroyed NiceStream (2 created, 1 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Destroyed NiceStream (2 created, 2 destroyed) >(test-send-recv:5167): libnice-tests-DEBUG: 11:59:49.933: Running test (0, 1, 10000, 50, 4, 0, 2, 0, 0)? >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Agent 0x5598563327e0 : Property set, role is already "controlling". >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Agent 0x559856332930 : Property set, changing role to "controlled". >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Created NiceStream (3 created, 2 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Created NiceComponent (5 created, 4 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Created NiceComponent (6 created, 4 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Agent 0x5598563327e0 : allocating stream id 1 (0x559856335fc0) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Created NiceStream (4 created, 2 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Created NiceComponent (7 created, 4 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Created NiceComponent (8 created, 4 destroyed) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Agent 0x559856332930 : allocating stream id 1 (0x5598563424a0) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Agent 0x5598563327e0 : In ICE-FULL mode, starting candidate gathering. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Agent 0x5598563327e0 : UPnP property Disabled >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Agent 0x5598563327e0: Trying to create host candidate on port 0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Agent 0x5598563327e0: Could not set IPV6 socket ToS: Protocol not available >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Component 0x55985633a530: Attach source (stream 1). >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Attaching source 0x559856341f60 (socket 0x55985634b100, FD 10) to context 0x55985634ff30 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.933: Agent 0x5598563327e0: Trying to create host candidate on port 0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x5598563327e0: Could not set IPV6 socket ToS: Protocol not available >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Component 0x5598563365e0: Attach source (stream 1). >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Attaching source 0x559856354720 (socket 0x55985634b170, FD 11) to context 0x55985634c920 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x5598563327e0: Candidate gathering FINISHED, no scheduled items. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x5598563327e0: gathered UDP local candidate : [127.0.0.1]:54440 for s1/c1. U/P 'O45AQpGQqXl4hO0X'/'(null)' >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x5598563327e0: gathered UDP local candidate : [127.0.0.1]:43689 for s1/c2. U/P 'O45AQpGQqXl4hO0X'/'(null)' >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x559856332930: set_remote_candidates 1 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x559856332930 : Adding UDP remote candidate with addr [127.0.0.1]:54440 for s1/c1. U/P 'O45AQpGQqXl4hO0X'/'(null)' prio: 1000 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x559856332930 : In ICE-FULL mode, starting candidate gathering. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x559856332930 : UPnP property Disabled >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x559856332930: Trying to create host candidate on port 0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x559856332930 : creating a new pair >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x559856332930 : pair 0x55985634e160 state FROZEN (priv_add_new_check_pair) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x559856332930 : new pair 0x55985634e160 : [127.0.0.1]:36627 --> [127.0.0.1]:54440 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x559856332930 : added a new pair 0x55985634e160 with foundation '1:1' to stream 1 component 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x559856332930 : stream 1 component 1 STATE-CHANGE disconnected -> connecting. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x559856332930: Could not set IPV6 socket ToS: Protocol not available >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Component 0x55985633e490: Attach source (stream 1). >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Attaching source 0x5598563549c0 (socket 0x55985634b1e0, FD 12) to context 0x55985633a390 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x559856332930: Trying to create host candidate on port 0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x559856332930: Could not set IPV6 socket ToS: Protocol not available >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Component 0x559856350df0: Attach source (stream 1). >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Attaching source 0x559856354ab0 (socket 0x55985634b250, FD 13) to context 0x55985634dca0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x559856332930: Candidate gathering FINISHED, no scheduled items. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x559856332930: gathered UDP local candidate : [127.0.0.1]:36627 for s1/c1. U/P 'Upcz2fzaYd1VDlDV'/'(null)' >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x559856332930: gathered UDP local candidate : [127.0.0.1]:40257 for s1/c2. U/P 'Upcz2fzaYd1VDlDV'/'(null)' >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x5598563327e0: set_remote_candidates 1 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x5598563327e0 : Adding UDP remote candidate with addr [127.0.0.1]:36627 for s1/c1. U/P 'Upcz2fzaYd1VDlDV'/'(null)' prio: 1000 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x5598563327e0 : creating a new pair >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x5598563327e0 : pair 0x55985634e210 state FROZEN (priv_add_new_check_pair) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x5598563327e0 : new pair 0x55985634e210 : [127.0.0.1]:54440 --> [127.0.0.1]:36627 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x5598563327e0 : added a new pair 0x55985634e210 with foundation '1:1' to stream 1 component 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.934: Agent 0x5598563327e0 : stream 1 component 1 STATE-CHANGE disconnected -> connecting. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.954: Agent 0x5598563327e0 : Pair 0x55985634e210 with s/c-id 1/1 (1:1) unfrozen. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.954: Agent 0x5598563327e0 : pair 0x55985634e210 state WAITING (priv_conn_check_unfreeze_next) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.954: Agent 0x559856332930 : Pair 0x55985634e160 with s/c-id 1/1 (1:1) unfrozen. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.954: Agent 0x5598563327e0 : pair 0x55985634e210 state IN_PROGRESS (priv_conn_check_initiate) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.954: Agent 0x559856332930 : pair 0x55985634e160 state WAITING (priv_conn_check_unfreeze_next) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.954: Agent 0x5598563327e0 : STUN-CC REQ [127.0.0.1]:54440 --> [127.0.0.1]:36627, socket=10, pair=0x55985634e210 (c-id:1), tie=13053229328638347996, username='Upcz2fzaYd1VDlDVO45AQpGQqXl4hO0X' (32), password='' (0), prio=900, controlling. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.954: Agent 0x559856332930 : pair 0x55985634e160 state IN_PROGRESS (priv_conn_check_initiate) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.954: Agent 0x559856332930 : STUN-CC REQ [127.0.0.1]:36627 --> [127.0.0.1]:54440, socket=12, pair=0x55985634e160 (c-id:1), tie=14779922883148954844, username='O45AQpGQqXl4hO0XUpcz2fzaYd1VDlDV' (32), password='' (0), prio=900, controlled. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.954: Agent 0x5598563327e0: conncheck created 56 - 0x7fde74002bf0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.954: Agent 0x559856332930: conncheck created 56 - 0x7fde7c003e10 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.954: Agent 0x5598563327e0 : timer set to 100ms, waiting+in_progress=1, nb_active=1 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.954: Agent 0x559856332930 : timer set to 100ms, waiting+in_progress=1, nb_active=1 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.954: Agent 0x559856332930 : stream 1: timer tick #1: 0 frozen, 1 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.954: Agent 0x5598563327e0 : stream 1: timer tick #1: 0 frozen, 1 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.998: Agent 0x559856332930: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:54440 (56 octets) : >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.998: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.998: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.998: First username: 0x5570637a32667a6159643156446c44564f3435415170475171586c34684f3058 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.998: Second uname: 0x5570637a32667a6159643156446c44564f3435415170475171586c34684f3058 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.998: STUN Reply (buffer size = 1300)... >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.998: All done (response size: 68) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.998: Agent 0x559856332930 : 1:1 Adding valid source candidate: 127.0.0.1:54440 trans: 0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.998: Agent 0x559856332930 : STUN-CC RESP to '127.0.0.1:54440', socket=12, len=68, cand=0x559856350270 (c-id:1), use-cand=1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x559856332930 : Found a matching pair 0x55985634e160 (1:1) (IN_PROGRESS) ... >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x559856332930 : pair 0x55985634e160 added for a triggered check. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x559856332930 : pair 0x55985634e160 state IN_PROGRESS (priv_add_pair_to_triggered_check_queue) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x559856332930 : marking pair 0x55985634e160 (1:1) as nominated >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:36627 (68 octets) : >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x559856332930 : conn.check list status: 0 nominated, 0 valid, c-id 1. >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: First username: 0x5570637a32667a6159643156446c44564f3435415170475171586c34684f3058 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: Second uname: 0x5570637a32667a6159643156446c44564f3435415170475171586c34684f3058 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: Received 68-bytes STUN message >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: No XOR-MAPPED-ADDRESS: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: Mapped address found! >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0 : stun_bind_process/conncheck for 0x55985634e210: controlling,res=success,stun#=0. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0 : pair 0x55985634e210 MATCHED. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0 : pair 0x55985634e210 state SUCCEEDED (priv_process_response_check_for_reflexive) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0 : 1:1 Adding valid source candidate: 127.0.0.1:36627 trans: 0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0 : changing SELECTED PAIR for component 1: 1:1 (prio:4294967298000). >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: setting SELECTED PAIR for component 1: 1:1 (prio:4294967298000). >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0 : Keepalive STUN-CC REQ to '127.0.0.1:36627', (c-id:1), username='Upcz2fzaYd1VDlDVO45AQpGQqXl4hO0X' (32), password='' (0), priority=0. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0: conncheck created 56 - 0x55985633a5f0 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x559856332930: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:54440 (56 octets) : >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0: Local selected pair: 1:1 1 UDP 127.0.0.1:54440 HOST >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0: Remote selected pair: 1:1 1 UDP 127.0.0.1:36627 HOST >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0 : stream 1 component 1 STATE-CHANGE connecting -> connected. >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: First username: 0x5570637a32667a6159643156446c44564f3435415170475171586c34684f3058 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0: Finding highest priority for component 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: Second uname: 0x5570637a32667a6159643156446c44564f3435415170475171586c34684f3058 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: STUN Reply (buffer size = 1300)... >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0 : stream 1 component 1 STATE-CHANGE connected -> ready. >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: All done (response size: 68) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x559856332930 : STUN-CC RESP to '127.0.0.1:54440', socket=12, len=68, cand=0x559856350270 (c-id:1), use-cand=1. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x559856332930 : Found a matching pair 0x55985634e160 (1:1) (IN_PROGRESS) ... >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x559856332930 : pair 0x55985634e160 added for a triggered check. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x559856332930 : pair 0x55985634e160 state IN_PROGRESS (priv_add_pair_to_triggered_check_queue) >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:36627 (68 octets) : >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x559856332930 : marking pair 0x55985634e160 (1:1) as nominated >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x559856332930 : conn.check list status: 0 nominated, 0 valid, c-id 1. >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: First username: 0x5570637a32667a6159643156446c44564f3435415170475171586c34684f3058 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: Second uname: 0x5570637a32667a6159643156446c44564f3435415170475171586c34684f3058 >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: Agent 0x5598563327e0 : Keepalive for selected pair received. >(test-send-recv:5167): libnice-DEBUG: 11:59:49.999: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: STUN error: Incomplete message: 200 of 5012 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: STUN error: Incomplete message: 200 of 56156 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: STUN error: Incomplete message: 200 of 900 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: STUN error: Incomplete message: 200 of 62324 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: STUN error: Incomplete message: 200 of 58212 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: STUN error: Incomplete message: 200 of 2956 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: STUN error: Incomplete message: 200 of 64380 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: STUN error: Incomplete message: 200 of 60268 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: STUN error: Incomplete message: 200 of 5012 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: STUN error: Incomplete message: 200 of 56156 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: STUN error: Incomplete message: 200 of 900 bytes! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:49.999: STUN error: Incomplete message: 200 of 62324 bytes! >(test-send-recv:5167): libnice-DEBUG: 11:59:50.015: Agent 0x559856332930 : STUN-CC REQ [127.0.0.1]:36627 --> [127.0.0.1]:54440, socket=12, pair=0x55985634e160 (c-id:1), tie=14779922883148954844, username='O45AQpGQqXl4hO0XUpcz2fzaYd1VDlDV' (32), password='' (0), prio=900, controlled. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.015: Agent 0x559856332930: conncheck created 56 - 0x7fde7c0037a0 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.015: Agent 0x559856332930 : timer set to 100ms, waiting+in_progress=1, nb_active=1 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.015: Agent 0x5598563327e0: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:36627 (56 octets) : >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.015: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.015: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.015: First username: 0x4f3435415170475171586c34684f30585570637a32667a6159643156446c4456 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.015: Second uname: 0x4f3435415170475171586c34684f30585570637a32667a6159643156446c4456 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.015: STUN Reply (buffer size = 1300)... >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.015: All done (response size: 68) >(test-send-recv:5167): libnice-DEBUG: 11:59:50.015: Agent 0x5598563327e0 : STUN-CC RESP to '127.0.0.1:36627', socket=10, len=68, cand=0x559856350150 (c-id:1), use-cand=1. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.015: Agent 0x5598563327e0 : Found a matching pair 0x55985634e210 (1:1) (SUCCEEDED) ... >(test-send-recv:5167): libnice-DEBUG: 11:59:50.015: Agent 0x5598563327e0 : nothing to do for pair 0x55985634e210. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.015: Agent 0x5598563327e0: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.015: Agent 0x5598563327e0: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.015: Agent 0x5598563327e0 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.015: Agent 0x5598563327e0 : marking pair 0x55985634e210 (1:1) as nominated >(test-send-recv:5167): libnice-DEBUG: 11:59:50.015: Agent 0x5598563327e0: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.015: Agent 0x5598563327e0: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.015: Agent 0x5598563327e0 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.015: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.015: Agent 0x5598563327e0 : waiting 1000 msecs before checking for failed components. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.115: Agent 0x559856332930 :STUN transaction retransmitted on pair 0x55985634e160 (timer=2/7 1/200ms). >(test-send-recv:5167): libnice-DEBUG: 11:59:50.115: Agent 0x5598563327e0: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:36627 (56 octets) : >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.115: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.115: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.115: First username: 0x4f3435415170475171586c34684f30585570637a32667a6159643156446c4456 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.115: Second uname: 0x4f3435415170475171586c34684f30585570637a32667a6159643156446c4456 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.115: STUN Reply (buffer size = 1300)... >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.115: All done (response size: 68) >(test-send-recv:5167): libnice-DEBUG: 11:59:50.115: Agent 0x5598563327e0 : STUN-CC RESP to '127.0.0.1:36627', socket=10, len=68, cand=0x559856350150 (c-id:1), use-cand=1. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.115: Agent 0x5598563327e0 : Found a matching pair 0x55985634e210 (1:1) (SUCCEEDED) ... >(test-send-recv:5167): libnice-DEBUG: 11:59:50.115: Agent 0x5598563327e0 : nothing to do for pair 0x55985634e210. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.115: Agent 0x5598563327e0: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.115: Agent 0x5598563327e0: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.115: Agent 0x5598563327e0 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.115: Agent 0x5598563327e0 : marking pair 0x55985634e210 (1:1) as nominated >(test-send-recv:5167): libnice-DEBUG: 11:59:50.115: Agent 0x5598563327e0: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.115: Agent 0x5598563327e0: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.115: Agent 0x5598563327e0 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.115: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.316: Agent 0x559856332930 :STUN transaction retransmitted on pair 0x55985634e160 (timer=3/7 2/400ms). >(test-send-recv:5167): libnice-DEBUG: 11:59:50.316: Agent 0x5598563327e0: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:36627 (56 octets) : >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.316: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.316: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.316: First username: 0x4f3435415170475171586c34684f30585570637a32667a6159643156446c4456 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.316: Second uname: 0x4f3435415170475171586c34684f30585570637a32667a6159643156446c4456 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.316: STUN Reply (buffer size = 1300)... >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.316: All done (response size: 68) >(test-send-recv:5167): libnice-DEBUG: 11:59:50.316: Agent 0x5598563327e0 : STUN-CC RESP to '127.0.0.1:36627', socket=10, len=68, cand=0x559856350150 (c-id:1), use-cand=1. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.316: Agent 0x5598563327e0 : Found a matching pair 0x55985634e210 (1:1) (SUCCEEDED) ... >(test-send-recv:5167): libnice-DEBUG: 11:59:50.316: Agent 0x5598563327e0 : nothing to do for pair 0x55985634e210. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.316: Agent 0x5598563327e0: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.316: Agent 0x5598563327e0: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.316: Agent 0x5598563327e0 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.316: Agent 0x5598563327e0 : marking pair 0x55985634e210 (1:1) as nominated >(test-send-recv:5167): libnice-DEBUG: 11:59:50.316: Agent 0x5598563327e0: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.316: Agent 0x5598563327e0: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.316: Agent 0x5598563327e0 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.316: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.718: Agent 0x559856332930 :STUN transaction retransmitted on pair 0x55985634e160 (timer=4/7 3/800ms). >(test-send-recv:5167): libnice-DEBUG: 11:59:50.718: Agent 0x5598563327e0: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:36627 (56 octets) : >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.718: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.718: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.718: First username: 0x4f3435415170475171586c34684f30585570637a32667a6159643156446c4456 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.718: Second uname: 0x4f3435415170475171586c34684f30585570637a32667a6159643156446c4456 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.718: STUN Reply (buffer size = 1300)... >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:50.718: All done (response size: 68) >(test-send-recv:5167): libnice-DEBUG: 11:59:50.718: Agent 0x5598563327e0 : STUN-CC RESP to '127.0.0.1:36627', socket=10, len=68, cand=0x559856350150 (c-id:1), use-cand=1. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.718: Agent 0x5598563327e0 : Found a matching pair 0x55985634e210 (1:1) (SUCCEEDED) ... >(test-send-recv:5167): libnice-DEBUG: 11:59:50.718: Agent 0x5598563327e0 : nothing to do for pair 0x55985634e210. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.718: Agent 0x5598563327e0: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.718: Agent 0x5598563327e0: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.718: Agent 0x5598563327e0 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.718: Agent 0x5598563327e0 : marking pair 0x55985634e210 (1:1) as nominated >(test-send-recv:5167): libnice-DEBUG: 11:59:50.718: Agent 0x5598563327e0: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.718: Agent 0x5598563327e0: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.718: Agent 0x5598563327e0 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.718: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.959: Agent 0x5598563327e0 : stream 1: timer tick #51: 0 frozen, 0 in-progress, 0 waiting, 1 succeeded, 0 discovered, 1 nominated, 0 waiting-for-nom, 1 valid. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.979: Agent 0x559856332930 : stream 1: timer tick #51: 0 frozen, 1 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.999: Agent 0x5598563327e0 : checking for failed components now. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.999: Agent 0x5598563327e0: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.999: Agent 0x5598563327e0: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:50.999: Agent 0x5598563327e0 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.999: Agent 0x5598563327e0 : conn.check list status: 0 nominated, 0 valid, c-id 2. >(test-send-recv:5167): libnice-DEBUG: 11:59:50.999: Agent 0x5598563327e0 : priv_conn_check_tick_agent_locked: stopping conncheck timer >(test-send-recv:5167): libnice-DEBUG: 11:59:50.999: Agent 0x5598563327e0 : changing conncheck state to COMPLETED. >(test-send-recv:5167): libnice-DEBUG: 11:59:51.522: Agent 0x559856332930 :STUN transaction retransmitted on pair 0x55985634e160 (timer=5/7 6/1600ms). >(test-send-recv:5167): libnice-DEBUG: 11:59:51.522: Agent 0x5598563327e0: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:36627 (56 octets) : >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:51.522: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:51.522: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:51.522: First username: 0x4f3435415170475171586c34684f30585570637a32667a6159643156446c4456 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:51.522: Second uname: 0x4f3435415170475171586c34684f30585570637a32667a6159643156446c4456 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:51.522: STUN Reply (buffer size = 1300)... >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:51.522: All done (response size: 68) >(test-send-recv:5167): libnice-DEBUG: 11:59:51.522: Agent 0x5598563327e0 : STUN-CC RESP to '127.0.0.1:36627', socket=10, len=68, cand=0x559856350150 (c-id:1), use-cand=1. >(test-send-recv:5167): libnice-DEBUG: 11:59:51.522: Agent 0x5598563327e0 : Found a matching pair 0x55985634e210 (1:1) (SUCCEEDED) ... >(test-send-recv:5167): libnice-DEBUG: 11:59:51.522: Agent 0x5598563327e0 : nothing to do for pair 0x55985634e210. >(test-send-recv:5167): libnice-DEBUG: 11:59:51.522: Agent 0x5598563327e0: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:51.522: Agent 0x5598563327e0: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:51.522: Agent 0x5598563327e0 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:51.522: Agent 0x5598563327e0 : marking pair 0x55985634e210 (1:1) as nominated >(test-send-recv:5167): libnice-DEBUG: 11:59:51.522: Agent 0x5598563327e0: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:51.522: Agent 0x5598563327e0: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:51.522: Agent 0x5598563327e0 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:51.522: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-DEBUG: 11:59:51.984: Agent 0x559856332930 : stream 1: timer tick #101: 0 frozen, 1 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid. >(test-send-recv:5167): libnice-DEBUG: 11:59:52.988: Agent 0x559856332930 : stream 1: timer tick #151: 0 frozen, 1 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid. >(test-send-recv:5167): libnice-DEBUG: 11:59:53.129: Agent 0x559856332930 :STUN transaction retransmitted on pair 0x55985634e160 (timer=6/7 15/3200ms). >(test-send-recv:5167): libnice-DEBUG: 11:59:53.129: Agent 0x5598563327e0: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:36627 (56 octets) : >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:53.129: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:53.129: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:53.129: First username: 0x4f3435415170475171586c34684f30585570637a32667a6159643156446c4456 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:53.129: Second uname: 0x4f3435415170475171586c34684f30585570637a32667a6159643156446c4456 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:53.129: STUN Reply (buffer size = 1300)... >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:53.129: All done (response size: 68) >(test-send-recv:5167): libnice-DEBUG: 11:59:53.129: Agent 0x5598563327e0 : STUN-CC RESP to '127.0.0.1:36627', socket=10, len=68, cand=0x559856350150 (c-id:1), use-cand=1. >(test-send-recv:5167): libnice-DEBUG: 11:59:53.129: Agent 0x5598563327e0 : Found a matching pair 0x55985634e210 (1:1) (SUCCEEDED) ... >(test-send-recv:5167): libnice-DEBUG: 11:59:53.129: Agent 0x5598563327e0 : nothing to do for pair 0x55985634e210. >(test-send-recv:5167): libnice-DEBUG: 11:59:53.129: Agent 0x5598563327e0: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:53.129: Agent 0x5598563327e0: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:53.129: Agent 0x5598563327e0 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:53.129: Agent 0x5598563327e0 : marking pair 0x55985634e210 (1:1) as nominated >(test-send-recv:5167): libnice-DEBUG: 11:59:53.129: Agent 0x5598563327e0: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:53.129: Agent 0x5598563327e0: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:53.129: Agent 0x5598563327e0 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:53.129: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-DEBUG: 11:59:53.993: Agent 0x559856332930 : stream 1: timer tick #201: 0 frozen, 1 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid. >(test-send-recv:5167): libnice-DEBUG: 11:59:54.998: Agent 0x559856332930 : stream 1: timer tick #251: 0 frozen, 1 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid. >(test-send-recv:5167): libnice-DEBUG: 11:59:56.002: Agent 0x559856332930 : stream 1: timer tick #301: 0 frozen, 1 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid. >(test-send-recv:5167): libnice-DEBUG: 11:59:56.324: Agent 0x559856332930 :STUN transaction retransmitted on pair 0x55985634e160 (timer=7/7 9/1600ms). >(test-send-recv:5167): libnice-DEBUG: 11:59:56.324: Agent 0x5598563327e0: inbound STUN packet for 1/1 (stream/component) from [127.0.0.1]:36627 (56 octets) : >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:56.324: STUN unknown: 0 mandatory attribute(s)! >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:56.324: Comparing usernames of size 32 and 32: 1 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:56.324: First username: 0x4f3435415170475171586c34684f30585570637a32667a6159643156446c4456 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:56.324: Second uname: 0x4f3435415170475171586c34684f30585570637a32667a6159643156446c4456 >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:56.324: STUN Reply (buffer size = 1300)... >(test-send-recv:5167): libnice-stun-DEBUG: 11:59:56.324: All done (response size: 68) >(test-send-recv:5167): libnice-DEBUG: 11:59:56.324: Agent 0x5598563327e0 : STUN-CC RESP to '127.0.0.1:36627', socket=10, len=68, cand=0x559856350150 (c-id:1), use-cand=1. >(test-send-recv:5167): libnice-DEBUG: 11:59:56.324: Agent 0x5598563327e0 : Found a matching pair 0x55985634e210 (1:1) (SUCCEEDED) ... >(test-send-recv:5167): libnice-DEBUG: 11:59:56.324: Agent 0x5598563327e0 : nothing to do for pair 0x55985634e210. >(test-send-recv:5167): libnice-DEBUG: 11:59:56.324: Agent 0x5598563327e0: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:56.324: Agent 0x5598563327e0: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:56.324: Agent 0x5598563327e0 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:56.324: Agent 0x5598563327e0 : marking pair 0x55985634e210 (1:1) as nominated >(test-send-recv:5167): libnice-DEBUG: 11:59:56.324: Agent 0x5598563327e0: Finding highest priority for component 1 >(test-send-recv:5167): libnice-DEBUG: 11:59:56.324: Agent 0x5598563327e0: Pruning pending checks. Highest nominated priority is 4294967298000 >(test-send-recv:5167): libnice-DEBUG: 11:59:56.324: Agent 0x5598563327e0 : conn.check list status: 1 nominated, 1 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:56.324: agent_recv_message_unlocked: Valid STUN packet received. >(test-send-recv:5167): libnice-DEBUG: 11:59:57.007: Agent 0x559856332930 : stream 1: timer tick #351: 0 frozen, 1 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid. >(test-send-recv:5167): libnice-DEBUG: 11:59:57.931: Agent 0x559856332930 : Retransmissions failed, giving up on pair 0x55985634e160 >(test-send-recv:5167): libnice-DEBUG: 11:59:57.931: Agent 0x559856332930 : Failed pair is [127.0.0.1]:36627 --> [127.0.0.1]:54440 >(test-send-recv:5167): libnice-DEBUG: 11:59:57.931: Agent 0x559856332930 : pair 0x55985634e160 state FAILED (candidate_check_pair_fail) >(test-send-recv:5167): libnice-DEBUG: 11:59:57.931: Agent 0x559856332930 : conn.check list status: 0 nominated, 0 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:57.931: Agent 0x559856332930 : waiting 1000 msecs before checking for failed components. >(test-send-recv:5167): libnice-DEBUG: 11:59:58.011: Agent 0x559856332930 : stream 1: timer tick #401: 0 frozen, 0 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid. >(test-send-recv:5167): libnice-DEBUG: 11:59:58.916: Agent 0x559856332930 : checking for failed components now. >(test-send-recv:5167): libnice-DEBUG: 11:59:58.916: Agent 0x559856332930 : conn.check list status: 0 nominated, 0 valid, c-id 1. >(test-send-recv:5167): libnice-DEBUG: 11:59:58.916: Agent 0x559856332930 : conn.check list status: 0 nominated, 0 valid, c-id 2. >(test-send-recv:5167): libnice-DEBUG: 11:59:58.916: Agent 0x559856332930 : priv_conn_check_tick_agent_locked: stopping conncheck timer >(test-send-recv:5167): libnice-DEBUG: 11:59:58.916: Agent 0x559856332930 : changing conncheck state to COMPLETED. >(test-send-recv:5167): libnice-tests-DEBUG: 12:00:05.091: test-thread:timer_cb: (nil) >(test-send-recv:5167): libnice-tests-DEBUG: 12:00:05.091: ERROR: test has got stuck, aborting... >FAIL test-send-recv (exit status: 255) >
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=671197">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 754243
:
671194
| 671197
