Closed Bug 1004980 Opened 11 years ago Closed 11 years ago

WebRTC Connection with Asterisk 12 failed due to STUN Error

Categories

(Core :: WebRTC: Networking, defect)

Product:
Core
Component:
WebRTC: Networking
Version:
28 Branch
Platform:
x86_64
Linux
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

()

Status:
RESOLVED INVALID

People

(Reporter: marko.seidenglanz, Unassigned)

Details

(Keywords: regression)

Attachments

(4 files, 5 obsolete files)

firefox_debug_stun_success.txt
23.45 KB, text/plain
Details
webrtc-ff-asterisk_success.pcap
2.50 MB, application/vnd.tcpdump.pcap
Details
webrtc-ff-asterisk_failed.pcap
87.43 KB, application/vnd.tcpdump.pcap
Details
firefox_debug_stun_failed.txt
58.38 KB, text/plain
Details
Attached file firefox_debug_stun_fail.txt (obsolete) —
User Agent: Mozilla/5.0 (X11; Linux x86_64; rv:29.0) Gecko/20100101 Firefox/29.0 (Beta/Release) Build ID: 20140421221237 Steps to reproduce: Make a WebRTC Call from Asterisk 12 to Firefox (28 or higher) Actual results: The RTP Connection can't be established, though to STUN Error. Expected results: Connection should be established as it was the case in Firefox 27 or lower.
Comment on attachment 8416395 [details] firefox_debug_stun_fail.txt (ice/WARNING) Peer offered candidates for disabled local component (ice/WARNING) ICE(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing)): peer (PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default) specified bogus candidate (ice/WARNING) Peer offered candidates for disabled local component (ice/WARNING) ICE(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing)): peer (PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default) specified bogus candidate (ice/DEBUG) ICE(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing)): peer (PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default) pairing candidates (ice/DEBUG) Pairing candidates====== (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND(a02I): Pairing local candidate host(IP4:192.168.35.78:44035) (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND(9mq6): Pairing with peer candidate candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host (ice/INFO) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND-PAIR(pIGH): setting pair to state FROZEN: pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host) (ice/INFO) ICE(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing))/CAND-PAIR(pIGH): Pairing candidate IP4:192.168.35.78:44035 (7e7f00ff):IP4:80.86.168.180:11474 (7effffff) priority=9115005270299246591 (7e7f00fffdffffff) (registry/DEBUG) Fetched node 'ice.pref.type.peer_rflx' ('UCHAR') (registry/DEBUG) Couldn't fetch node 'stun.client.retransmission_timeout' ('UINT4') (registry/DEBUG) Couldn't fetch node 'stun.client.retransmission_backoff_factor' ('double') (registry/DEBUG) Fetched node 'stun.client.maximum_transmits' ('UINT4') (registry/DEBUG) Couldn't fetch node 'stun.client.final_retransmit_backoff' ('UINT4') (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND(YVgh): Pairing with peer candidate candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host (ice/INFO) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND-PAIR(aBM4): setting pair to state FROZEN: aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host) (ice/INFO) ICE(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing))/CAND-PAIR(aBM4): Pairing candidate IP4:192.168.35.78:44035 (7e7f00ff):IP4:192.168.37.2:11474 (7effffff) priority=9115005270299246591 (7e7f00fffdffffff) (registry/DEBUG) Fetched node 'ice.pref.type.peer_rflx' ('UCHAR') (registry/DEBUG) Couldn't fetch node 'stun.client.retransmission_timeout' ('UINT4') (registry/DEBUG) Couldn't fetch node 'stun.client.retransmission_backoff_factor' ('double') (registry/DEBUG) Fetched node 'stun.client.maximum_transmits' ('UINT4') (registry/DEBUG) Couldn't fetch node 'stun.client.final_retransmit_backoff' ('UINT4') (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND(v7Pi): Pairing local candidate srflx(IP4:192.168.35.78:44035|IP4:23.21.150.121:3478) (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default): stream 1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio state UNPAIRED->FROZEN (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default): 0 active streams (ice/INFO) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND-PAIR(pIGH): setting pair to state WAITING: pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host) (ice/INFO) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND-PAIR(aBM4): setting pair to state WAITING: aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host) (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default): stream 1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio state FROZEN->ACTIVE (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default): 1 active streams (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default): check timer expired for media stream 1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio (ice/INFO) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND-PAIR(pIGH): setting pair to state IN_PROGRESS: pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host) (stun/DEBUG) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Sending check request (my_addr=IP4:192.168.35.78:44035,peer_addr=IP4:80.86.168.180:11474) (stun/DEBUG) Encoding STUN message (stun/DEBUG) Encoded MsgType: BINDING-REQUEST (stun/DEBUG) Encoded Cookie: 2112a442 (stun/DEBUG) Encoded ID[12]=9db1f7557a6b0d6ad94811ba (stun/DEBUG) Encoded USERNAME: 68047a17715c72ec7ec9e21117b5815d:b34f9a79 (stun/DEBUG) Encoded PRIORITY: 1853817087 (stun/DEBUG) Encoded ICE-CONTROLLED: 5271495881620621142 (stun/DEBUG) Computing MESSAGE-INTEGRITY (stun/DEBUG) Computed MESSAGE-INTEGRITY [20]=63896fa529b99f640b8346fed3335271555ba923 (stun/DEBUG) MESSAGE-INTEGRITY[20]=63896fa529b99f640b8346fed3335271555ba923 (stun/DEBUG) Computed FINGERPRINT d41c5e9e (stun/DEBUG) Encoded FINGERPRINT: d41c5e9e (stun/DEBUG) Encoded Length: 100 (stun/DEBUG) Starting to sanity check encoding (stun/DEBUG) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Sending to IP4:80.86.168.180:11474 [120]=000100642112a4429db1f7557a6b0d6ad94811ba0006002936383034376131373731356337326563376563396532313131376235383135643a6233346639613739000000002400046e7f00ff8029000849281d9322ee87560008001463896fa529b99f640b8346fed3335271555ba92380280004d41c5e9e (stun/DEBUG) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Next timer will fire in 100 ms (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/STREAM(1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio)/COMP(1): looking for pre-answer requests (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/STREAM(1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio)/COMP(2): looking for pre-answer requests (ice/NOTICE) ICE(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing)): peer (PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default) no streams with pre-answer requests (generic/DEBUG) Timer callback fired (set in nr_ice_media_stream_check_timer_cb:382) (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default): check timer expired for media stream 1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio (ice/INFO) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND-PAIR(aBM4): setting pair to state IN_PROGRESS: aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host) (stun/DEBUG) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Sending check request (my_addr=IP4:192.168.35.78:44035,peer_addr=IP4:192.168.37.2:11474) (stun/DEBUG) Encoding STUN message (stun/DEBUG) Encoded MsgType: BINDING-REQUEST (stun/DEBUG) Encoded Cookie: 2112a442 (stun/DEBUG) Encoded ID[12]=da64f262f4293d0ba27860fb (stun/DEBUG) Encoded USERNAME: 68047a17715c72ec7ec9e21117b5815d:b34f9a79 (stun/DEBUG) Encoded PRIORITY: 1853817087 (stun/DEBUG) Encoded ICE-CONTROLLED: 5271495881620621142 (stun/DEBUG) Computing MESSAGE-INTEGRITY (stun/DEBUG) Computed MESSAGE-INTEGRITY [20]=a3bd6a6651ac6d0bad5ba8eba16d5ac799e7e259 (stun/DEBUG) MESSAGE-INTEGRITY[20]=a3bd6a6651ac6d0bad5ba8eba16d5ac799e7e259 (stun/DEBUG) Computed FINGERPRINT 9fd565fb (stun/DEBUG) Encoded FINGERPRINT: 9fd565fb (stun/DEBUG) Encoded Length: 100 (stun/DEBUG) Starting to sanity check encoding (stun/DEBUG) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Sending to IP4:192.168.37.2:11474 [120]=000100642112a442da64f262f4293d0ba27860fb0006002936383034376131373731356337326563376563396532313131376235383135643a6233346639613739000000002400046e7f00ff8029000849281d9322ee875600080014a3bd6a6651ac6d0bad5ba8eba16d5ac799e7e259802800049fd565fb (stun/DEBUG) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Next timer will fire in 100 ms (generic/DEBUG) Timer callback fired (set in nr_ice_media_stream_check_timer_cb:382) (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default): check timer expired for media stream 1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio (ice/WARNING) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default): no pairs for 1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Timer expired (after 100 ms) (stun/DEBUG) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Sending check request (my_addr=IP4:192.168.35.78:44035,peer_addr=IP4:80.86.168.180:11474) (stun/DEBUG) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Sending to IP4:80.86.168.180:11474 [120]=000100642112a4429db1f7557a6b0d6ad94811ba0006002936383034376131373731356337326563376563396532313131376235383135643a6233346639613739000000002400046e7f00ff8029000849281d9322ee87560008001463896fa529b99f640b8346fed3335271555ba92380280004d41c5e9e (stun/DEBUG) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Next timer will fire in 300 ms (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Timer expired (after 100 ms) (stun/DEBUG) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Sending check request (my_addr=IP4:192.168.35.78:44035,peer_addr=IP4:192.168.37.2:11474) (stun/DEBUG) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Sending to IP4:192.168.37.2:11474 [120]=000100642112a442da64f262f4293d0ba27860fb0006002936383034376131373731356337326563376563396532313131376235383135643a6233346639613739000000002400046e7f00ff8029000849281d9322ee875600080014a3bd6a6651ac6d0bad5ba8eba16d5ac799e7e259802800049fd565fb (stun/DEBUG) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Next timer will fire in 300 ms (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Timer expired (after 299 ms) (stun/DEBUG) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Sending check request (my_addr=IP4:192.168.35.78:44035,peer_addr=IP4:80.86.168.180:11474) (stun/DEBUG) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Sending to IP4:80.86.168.180:11474 [120]=000100642112a4429db1f7557a6b0d6ad94811ba0006002936383034376131373731356337326563376563396532313131376235383135643a6233346639613739000000002400046e7f00ff8029000849281d9322ee87560008001463896fa529b99f640b8346fed3335271555ba92380280004d41c5e9e (stun/DEBUG) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Next timer will fire in 700 ms (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Timer expired (after 301 ms) (stun/DEBUG) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Sending check request (my_addr=IP4:192.168.35.78:44035,peer_addr=IP4:192.168.37.2:11474) (stun/DEBUG) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Sending to IP4:192.168.37.2:11474 [120]=000100642112a442da64f262f4293d0ba27860fb0006002936383034376131373731356337326563376563396532313131376235383135643a6233346639613739000000002400046e7f00ff8029000849281d9322ee875600080014a3bd6a6651ac6d0bad5ba8eba16d5ac799e7e259802800049fd565fb (stun/DEBUG) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Next timer will fire in 700 ms (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Timer expired (after 700 ms) (stun/DEBUG) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Sending check request (my_addr=IP4:192.168.35.78:44035,peer_addr=IP4:80.86.168.180:11474) (stun/DEBUG) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Sending to IP4:80.86.168.180:11474 [120]=000100642112a4429db1f7557a6b0d6ad94811ba0006002936383034376131373731356337326563376563396532313131376235383135643a6233346639613739000000002400046e7f00ff8029000849281d9322ee87560008001463896fa529b99f640b8346fed3335271555ba92380280004d41c5e9e (stun/DEBUG) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Next timer will fire in 2300 ms (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Timer expired (after 700 ms) (stun/DEBUG) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Sending check request (my_addr=IP4:192.168.35.78:44035,peer_addr=IP4:192.168.37.2:11474) (stun/DEBUG) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Sending to IP4:192.168.37.2:11474 [120]=000100642112a442da64f262f4293d0ba27860fb0006002936383034376131373731356337326563376563396532313131376235383135643a6233346639613739000000002400046e7f00ff8029000849281d9322ee875600080014a3bd6a6651ac6d0bad5ba8eba16d5ac799e7e259802800049fd565fb (stun/DEBUG) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Next timer will fire in 2300 ms (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Timer expired (after 2300 ms) (stun/INFO) STUN-CLIENT(pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host)): Timed out (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/STREAM(1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio)/CAND-PAIR(pIGH): STUN cb on pair addr = pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host) (ice/INFO) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND-PAIR(pIGH): setting pair to state FAILED: pIGH|IP4:192.168.35.78:44035|IP4:80.86.168.180:11474(host(IP4:192.168.35.78:44035)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 11474 typ host) (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Timer expired (after 2300 ms) (stun/INFO) STUN-CLIENT(aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host)): Timed out (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/STREAM(1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio)/CAND-PAIR(aBM4): STUN cb on pair addr = aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host) (ice/INFO) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND-PAIR(aBM4): setting pair to state FAILED: aBM4|IP4:192.168.35.78:44035|IP4:192.168.37.2:11474(host(IP4:192.168.35.78:44035)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 11474 typ host) (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default): stream 1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio state ACTIVE->FAILED (ice/DEBUG) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default): 0 active streams (ice/INFO) ICE-PEER(PC:1399023361002670 (id=476 url=http://appbox.cloudstack5.appspot.com/#dialing):default): all checks completed success=0 fail=1
Attached file firefox_debug_stun_fail.txt (obsolete) —
This is a working example... Seems to be a timing problem.
FF sometimes somehow ignores the binding requests send from Asterisk, which FF uses to get the right candidate.
Here is the relevant part from the working example: (ice/DEBUG) ICE(PC:1399024391448443 (id=20 url=http://appbox.cloudstack5.appspot.com/#dialing)): Socket ready to read (ice/DEBUG) ICE(PC:1399024391448443 (id=20 url=http://appbox.cloudstack5.appspot.com/#dialing)): Read 140 bytes from IP4:80.86.168.180:18658 (stun/DEBUG) ICE(PC:1399024391448443 (id=20 url=http://appbox.cloudstack5.appspot.com/#dialing)): Message is STUN (request)[140]=000100782112a4422f5a0000b7e65a624abc844e002400046effffff00250000802a0008583972ef29781f5d8022000c706a6e6174682d322e312e300006002962313330303839363a34313936386263663335306630386165313161333636616632396131333934380000000008001422cbe23bb2b15d8085998a2900cae1c73af0033280280004915eab74 (stun/DEBUG) STUN-SERVER(server(IP4:192.168.35.78:0)): Received(my_addr=IP4:192.168.35.78:40099,peer_addr=IP4:80.86.168.180:18658) (stun/DEBUG) STUN-SERVER(server(IP4:192.168.35.78:0)): Received [140]=000100782112a4422f5a0000b7e65a624abc844e002400046effffff00250000802a0008583972ef29781f5d8022000c706a6e6174682d322e312e300006002962313330303839363a34313936386263663335306630386165313161333636616632396131333934380000000008001422cbe23bb2b15d8085998a2900cae1c73af0033280280004915eab74 (stun/DEBUG) Parsing STUN message of 140 bytes (stun/DEBUG) Parsed MsgType: BINDING-REQUEST (stun/DEBUG) Parsed Length: 120 (stun/DEBUG) Parsed Cookie: 2112a442 (stun/DEBUG) Parsed ID[12]=2f5a0000b7e65a624abc844e (stun/DEBUG) size = 120 (stun/DEBUG) Parsed PRIORITY: 1862270975 (stun/DEBUG) Before pedantic attr_info checks (stun/DEBUG) After pedantic attr_info checks (stun/DEBUG) size = 112 (stun/DEBUG) Parsed USE-CANDIDATE: on (stun/DEBUG) Before pedantic attr_info checks (stun/DEBUG) After pedantic attr_info checks (stun/DEBUG) size = 108 (stun/DEBUG) Parsed ICE-CONTROLLING: 6357238720528654173 (stun/DEBUG) Before pedantic attr_info checks (stun/DEBUG) After pedantic attr_info checks (stun/DEBUG) size = 96 (stun/DEBUG) Parsed SERVER: pjnath-2.1.0 (stun/DEBUG) Before pedantic attr_info checks (stun/DEBUG) After pedantic attr_info checks (stun/DEBUG) size = 80 (stun/DEBUG) Parsed USERNAME: b1300896:41968bcf350f08ae11a366af29a13948 (stun/DEBUG) Before pedantic attr_info checks (stun/DEBUG) After pedantic attr_info checks (stun/DEBUG) size = 32 (stun/DEBUG) Computing MESSAGE-INTEGRITY (stun/DEBUG) Computed MESSAGE-INTEGRITY [20]=22cbe23bb2b15d8085998a2900cae1c73af00332 (stun/DEBUG) MESSAGE-INTEGRITY[20]=22cbe23bb2b15d8085998a2900cae1c73af00332 (stun/DEBUG) Before pedantic attr_info checks (stun/DEBUG) After pedantic attr_info checks (stun/DEBUG) size = 8 (stun/DEBUG) Computed FINGERPRINT 915eab74 (stun/DEBUG) Parsed FINGERPRINT: 915eab74 (stun/DEBUG) Before pedantic attr_info checks (stun/DEBUG) After pedantic attr_info checks (stun/DEBUG) Starting to sanity check encoding (stun/DEBUG) Mapped Address = IP4:80.86.168.180:18658 (registry/DEBUG) Couldn't fetch node 'stun.server.name' ('string') (stun/DEBUG) Entering STUN server callback (ice/DEBUG) ICE-PEER(PC:1399024391448443 (id=20 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/STREAM(1399024391448443 (id=20 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio)/COMP(1): received request from IP4:80.86.168.180:18658 (ice/DEBUG) ICE-PEER(PC:1399024391448443 (id=20 url=http://appbox.cloudstack5.appspot.com/#dialing):default): This STUN request appears to map to local addr IP4:192.168.35.78:40099 (ice/DEBUG) ICE-PEER(PC:1399024391448443 (id=20 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND_PAIR(fLfn): Found a matching pair for received check: fLfn|IP4:84.183.253.86:40099
Component: Untriaged → WebRTC: Networking
Keywords: regression
Product: Firefox → Core
Version: 29 Branch → 28 Branch
It is also strange, that it logs: (ice/WARNING) ICE(PC:1399036760008650 (id=39 url=http://appbox.cloudstack5.appspot.com/#dialing)): No STUN servers specified Though I passed two STUN URLs to the PeerConnection on init.
After some more analysis I realized that the problem might be a timing issue while waiting for STUN-Check responses. In the working example, which is in 1 of 10 tries the case, log output looks like this: (stun/DEBUG) STUN-CLIENT(OGZ4|IP4:192.168.35.78:51459/UDP|IP4:192.168.37.2:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 14964 typ host)): Next timer will fire in 100 ms (generic/DEBUG) Timer callback fired (set in nr_ice_media_stream_check_timer_cb:382) (ice/DEBUG) ICE-PEER(PC:1399039463576963 (id=254 url=http://appbox.cloudstack5.appspot.com/#dialing):default): check timer expired for media stream 1399039463576963 (id=254 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio (ice/WARNING) ICE-PEER(PC:1399039463576963 (id=254 url=http://appbox.cloudstack5.appspot.com/#dialing):default): no pairs for 1399039463576963 (id=254 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(brjR|IP4:192.168.35.78:51459/UDP|IP4:80.86.168.180:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 14964 typ host)): Timer expired (after 104 ms) (stun/DEBUG) STUN-CLIENT(brjR|IP4:192.168.35.78:51459/UDP|IP4:80.86.168.180:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 14964 typ host)): Sending check request (my_addr=IP4:192.168.35.78:51459/UDP,peer_addr=IP4:80.86.168.180:14964/UDP) (stun/DEBUG) STUN-CLIENT(brjR|IP4:192.168.35.78:51459/UDP|IP4:80.86.168.180:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 14964 typ host)): Sending to IP4:80.86.168.180:14964/UDP [120]=000100642112a442c0c7abe31e25d3af2a5b267f0006002935623935613833663562633465623038343532633836383233326365376233303a3364666339666430000000002400046e7f00ff802900089d30ecec94942cd100080014d4abc48d69c6dc906dcdd61f9ae731d6b00eeb6d80280004adbdff09 (stun/DEBUG) STUN-CLIENT(brjR|IP4:192.168.35.78:51459/UDP|IP4:80.86.168.180:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 14964 typ host)): Next timer will fire in 300 ms (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(OGZ4|IP4:192.168.35.78:51459/UDP|IP4:192.168.37.2:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 14964 typ host)): Timer expired (after 100 ms) (stun/DEBUG) STUN-CLIENT(OGZ4|IP4:192.168.35.78:51459/UDP|IP4:192.168.37.2:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 14964 typ host)): Sending check request (my_addr=IP4:192.168.35.78:51459/UDP,peer_addr=IP4:192.168.37.2:14964/UDP) (stun/DEBUG) STUN-CLIENT(OGZ4|IP4:192.168.35.78:51459/UDP|IP4:192.168.37.2:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 14964 typ host)): Sending to IP4:192.168.37.2:14964/UDP [120]=000100642112a4428c7e9ee95e602961154a41d70006002935623935613833663562633465623038343532633836383233326365376233303a3364666339666430000000002400046e7f00ff802900089d30ecec94942cd100080014aa0d84020e95908cb7b0bc8dc8e5eef25c08d3d280280004e220a5c6 (stun/DEBUG) STUN-CLIENT(OGZ4|IP4:192.168.35.78:51459/UDP|IP4:192.168.37.2:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 14964 typ host)): Next timer will fire in 300 ms (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(brjR|IP4:192.168.35.78:51459/UDP|IP4:80.86.168.180:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 14964 typ host)): Timer expired (after 300 ms) (stun/DEBUG) STUN-CLIENT(brjR|IP4:192.168.35.78:51459/UDP|IP4:80.86.168.180:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 14964 typ host)): Sending check request (my_addr=IP4:192.168.35.78:51459/UDP,peer_addr=IP4:80.86.168.180:14964/UDP) (stun/DEBUG) STUN-CLIENT(brjR|IP4:192.168.35.78:51459/UDP|IP4:80.86.168.180:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 14964 typ host)): Sending to IP4:80.86.168.180:14964/UDP [120]=000100642112a442c0c7abe31e25d3af2a5b267f0006002935623935613833663562633465623038343532633836383233326365376233303a3364666339666430000000002400046e7f00ff802900089d30ecec94942cd100080014d4abc48d69c6dc906dcdd61f9ae731d6b00eeb6d80280004adbdff09 (stun/DEBUG) STUN-CLIENT(brjR|IP4:192.168.35.78:51459/UDP|IP4:80.86.168.180:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 14964 typ host)): Next timer will fire in 700 ms (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(OGZ4|IP4:192.168.35.78:51459/UDP|IP4:192.168.37.2:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 14964 typ host)): Timer expired (after 300 ms) (stun/DEBUG) STUN-CLIENT(OGZ4|IP4:192.168.35.78:51459/UDP|IP4:192.168.37.2:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 14964 typ host)): Sending check request (my_addr=IP4:192.168.35.78:51459/UDP,peer_addr=IP4:192.168.37.2:14964/UDP) (stun/DEBUG) STUN-CLIENT(OGZ4|IP4:192.168.35.78:51459/UDP|IP4:192.168.37.2:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 14964 typ host)): Sending to IP4:192.168.37.2:14964/UDP [120]=000100642112a4428c7e9ee95e602961154a41d70006002935623935613833663562633465623038343532633836383233326365376233303a3364666339666430000000002400046e7f00ff802900089d30ecec94942cd100080014aa0d84020e95908cb7b0bc8dc8e5eef25c08d3d280280004e220a5c6 (stun/DEBUG) STUN-CLIENT(OGZ4|IP4:192.168.35.78:51459/UDP|IP4:192.168.37.2:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 14964 typ host)): Next timer will fire in 700 ms (ice/DEBUG) ICE(PC:1399039463576963 (id=254 url=http://appbox.cloudstack5.appspot.com/#dialing)): Socket ready to read (ice/DEBUG) ICE(PC:1399039463576963 (id=254 url=http://appbox.cloudstack5.appspot.com/#dialing)): Read 80 bytes from IP4:80.86.168.180:14964/UDP (stun/DEBUG) ICE(PC:1399039463576963 (id=254 url=http://appbox.cloudstack5.appspot.com/#dialing)): Message is STUN (other)[80]=0101003c2112a442c0c7abe31e25d3af2a5b267f002000080001e81175a559148022000c706a6e6174682d322e312e300008001495cac30d5f5c155cf44f65557843e349e30b16818028000449dee5bf (stun/DEBUG) STUN-CLIENT(brjR|IP4:192.168.35.78:51459/UDP|IP4:80.86.168.180:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 14964 typ host)): Received check response (my_addr=IP4:192.168.35.78:51459/UDP,peer_addr=IP4:80.86.168.180:14964/UDP) (stun/DEBUG) STUN-CLIENT(brjR|IP4:192.168.35.78:51459/UDP|IP4:80.86.168.180:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 14964 typ host)): Received [80]=0101003c2112a442c0c7abe31e25d3af2a5b267f002000080001e81175a559148022000c706a6e6174682d322e312e300008001495cac30d5f5c155cf44f65557843e349e30b16818028000449dee5bf (stun/DEBUG) Parsing STUN message of 80 bytes (stun/DEBUG) Parsed MsgType: BINDING-RESPONSE (stun/DEBUG) Parsed Length: 60 (stun/DEBUG) Parsed Cookie: 2112a442 (stun/DEBUG) Parsed ID[12]=c0c7abe31e25d3af2a5b267f (stun/DEBUG) size = 60 (stun/DEBUG) Masked XOR-MAPPED-ADDRESS = IP4:117.165.89.20:59409/UDP (stun/DEBUG) Unmasked XOR-MAPPED-ADDRESS = IP4:84.183.253.86:51459/UDP (stun/DEBUG) Parsed XOR-MAPPED-ADDRESS: IP4:84.183.253.86:51459/UDP (unmasked) IP4:117.165.89.20:59409/UDP (masked) (stun/DEBUG) Before pedantic attr_info checks (stun/DEBUG) After pedantic attr_info checks (stun/DEBUG) size = 48 (stun/DEBUG) Parsed SERVER: pjnath-2.1.0 (stun/DEBUG) Before pedantic attr_info checks (stun/DEBUG) After pedantic attr_info checks (stun/DEBUG) size = 32 (stun/DEBUG) Computing MESSAGE-INTEGRITY (stun/DEBUG) Computed MESSAGE-INTEGRITY [20]=95cac30d5f5c155cf44f65557843e349e30b1681 (stun/DEBUG) MESSAGE-INTEGRITY[20]=95cac30d5f5c155cf44f65557843e349e30b1681 (stun/DEBUG) Before pedantic attr_info checks (stun/DEBUG) After pedantic attr_info checks (stun/DEBUG) size = 8 (stun/DEBUG) Computed FINGERPRINT 49dee5bf (stun/DEBUG) Parsed FINGERPRINT: 49dee5bf (stun/DEBUG) Before pedantic attr_info checks (stun/DEBUG) After pedantic attr_info checks (stun/DEBUG) Starting to sanity check encoding (stun/DEBUG) STUN-CLIENT(brjR|IP4:192.168.35.78:51459/UDP|IP4:80.86.168.180:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 14964 typ host)): successfully received response; processing (stun/DEBUG) STUN-CLIENT(brjR|IP4:192.168.35.78:51459/UDP|IP4:80.86.168.180:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 14964 typ host)): Successfully parsed mode=11 (stun/DEBUG) STUN-CLIENT(brjR|IP4:192.168.35.78:51459/UDP|IP4:80.86.168.180:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 14964 typ host)): Received mapped address: IP4:84.183.253.86:51459/UDP (ice/DEBUG) ICE-PEER(PC:1399039463576963 (id=254 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/STREAM(1399039463576963 (id=254 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio)/CAND-PAIR(brjR): STUN cb on pair addr = brjR|IP4:192.168.35.78:51459/UDP|IP4:80.86.168.180:14964/UDP(host(IP4:192.168.35.78:51459/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 14964 typ host) (ice/INFO) ICE-PEER(PC:1399039463576963 (id=254 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND-PAIR(osyw): setting pair to state FROZEN: osyw|IP4:84.183.253.86:51459/UDP|IP4:80.86.168.180:14964/UDP(srflx(IP4:192.168.35.78:51459/UDP|stun.services.mozilla.com:3478)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 14964 typ host) (ice/INFO) ICE(PC:1399039463576963 (id=254 url=http://appbox.cloudstack5.appspot.com/#dialing))/CAND-PAIR(osyw): Pairing candidate IP4:84.183.253.86:51459/UDP (647fffff):IP4:80.86.168.180:14964/UDP (7effffff) priority=7241788200778203135 (647ffffffdffffff) (registry/DEBUG) Fetched node 'ice.pref.type.peer_rflx' ('UCHAR') (registry/DEBUG) Couldn't fetch node 'stun.client.retransmission_timeout' ('UINT4') (registry/DEBUG) Couldn't fetch node 'stun.client.retransmission_backoff_factor' ('double') (registry/DEBUG) Fetched node 'stun.client.maximum_transmits' ('UINT4') (registry/DEBUG) Couldn't fetch node 'stun.client.final_retransmit_backoff' ('UINT4') (ice/INFO) ICE-PEER(PC:1399039463576963 (id=254 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND-PAIR(osyw): setting pair to state SUCCEEDED: osyw|IP4:84.183.253.86:51459/UDP In the failing example it looks like this: (stun/DEBUG) STUN-CLIENT(npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host)): Next timer will fire in 100 ms (generic/DEBUG) Timer callback fired (set in nr_ice_media_stream_check_timer_cb:382) (ice/DEBUG) ICE-PEER(PC:1399039909838125 (id=278 url=http://appbox.cloudstack5.appspot.com/#dialing):default): check timer expired for media stream 1399039909838125 (id=278 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio (ice/WARNING) ICE-PEER(PC:1399039909838125 (id=278 url=http://appbox.cloudstack5.appspot.com/#dialing):default): no pairs for 1399039909838125 (id=278 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(cOqV|IP4:192.168.35.78:45418/UDP|IP4:80.86.168.180:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 12480 typ host)): Timer expired (after 100 ms) (stun/DEBUG) STUN-CLIENT(cOqV|IP4:192.168.35.78:45418/UDP|IP4:80.86.168.180:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 12480 typ host)): Sending check request (my_addr=IP4:192.168.35.78:45418/UDP,peer_addr=IP4:80.86.168.180:12480/UDP) (stun/DEBUG) STUN-CLIENT(cOqV|IP4:192.168.35.78:45418/UDP|IP4:80.86.168.180:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 12480 typ host)): Sending to IP4:80.86.168.180:12480/UDP [120]=000100642112a4421de9078f0b4a765cf96c68770006002937303061326264613732303930623734336161633632666336656432343965333a3739323263346530000000002400046e7f00ff802900087cd3b4227de3cd6600080014ec3a6f2238f16929f65941eba4e2aa82a79445ad80280004ade866c3 (stun/DEBUG) STUN-CLIENT(cOqV|IP4:192.168.35.78:45418/UDP|IP4:80.86.168.180:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 12480 typ host)): Next timer will fire in 300 ms (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host)): Timer expired (after 100 ms) (stun/DEBUG) STUN-CLIENT(npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host)): Sending check request (my_addr=IP4:192.168.35.78:45418/UDP,peer_addr=IP4:192.168.37.2:12480/UDP) (stun/DEBUG) STUN-CLIENT(npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host)): Sending to IP4:192.168.37.2:12480/UDP [120]=000100642112a442570d2443198de18d9a34abde0006002937303061326264613732303930623734336161633632666336656432343965333a3739323263346530000000002400046e7f00ff802900087cd3b4227de3cd6600080014badc770a1c0e229ce90ea53ef56fb1c332758a1180280004db18ae47 (stun/DEBUG) STUN-CLIENT(npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host)): Next timer will fire in 300 ms (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(cOqV|IP4:192.168.35.78:45418/UDP|IP4:80.86.168.180:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 12480 typ host)): Timer expired (after 300 ms) (stun/DEBUG) STUN-CLIENT(cOqV|IP4:192.168.35.78:45418/UDP|IP4:80.86.168.180:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 12480 typ host)): Sending check request (my_addr=IP4:192.168.35.78:45418/UDP,peer_addr=IP4:80.86.168.180:12480/UDP) (stun/DEBUG) STUN-CLIENT(cOqV|IP4:192.168.35.78:45418/UDP|IP4:80.86.168.180:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 12480 typ host)): Sending to IP4:80.86.168.180:12480/UDP [120]=000100642112a4421de9078f0b4a765cf96c68770006002937303061326264613732303930623734336161633632666336656432343965333a3739323263346530000000002400046e7f00ff802900087cd3b4227de3cd6600080014ec3a6f2238f16929f65941eba4e2aa82a79445ad80280004ade866c3 (stun/DEBUG) STUN-CLIENT(cOqV|IP4:192.168.35.78:45418/UDP|IP4:80.86.168.180:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 12480 typ host)): Next timer will fire in 700 ms (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host)): Timer expired (after 300 ms) (stun/DEBUG) STUN-CLIENT(npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host)): Sending check request (my_addr=IP4:192.168.35.78:45418/UDP,peer_addr=IP4:192.168.37.2:12480/UDP) (stun/DEBUG) STUN-CLIENT(npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host)): Sending to IP4:192.168.37.2:12480/UDP [120]=000100642112a442570d2443198de18d9a34abde0006002937303061326264613732303930623734336161633632666336656432343965333a3739323263346530000000002400046e7f00ff802900087cd3b4227de3cd6600080014badc770a1c0e229ce90ea53ef56fb1c332758a1180280004db18ae47 (stun/DEBUG) STUN-CLIENT(npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host)): Next timer will fire in 700 ms (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(cOqV|IP4:192.168.35.78:45418/UDP|IP4:80.86.168.180:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 12480 typ host)): Timer expired (after 700 ms) (stun/DEBUG) STUN-CLIENT(cOqV|IP4:192.168.35.78:45418/UDP|IP4:80.86.168.180:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 12480 typ host)): Sending check request (my_addr=IP4:192.168.35.78:45418/UDP,peer_addr=IP4:80.86.168.180:12480/UDP) (stun/DEBUG) STUN-CLIENT(cOqV|IP4:192.168.35.78:45418/UDP|IP4:80.86.168.180:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 12480 typ host)): Sending to IP4:80.86.168.180:12480/UDP [120]=000100642112a4421de9078f0b4a765cf96c68770006002937303061326264613732303930623734336161633632666336656432343965333a3739323263346530000000002400046e7f00ff802900087cd3b4227de3cd6600080014ec3a6f2238f16929f65941eba4e2aa82a79445ad80280004ade866c3 (stun/DEBUG) STUN-CLIENT(cOqV|IP4:192.168.35.78:45418/UDP|IP4:80.86.168.180:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 12480 typ host)): Next timer will fire in 2300 ms (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host)): Timer expired (after 700 ms) (stun/DEBUG) STUN-CLIENT(npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host)): Sending check request (my_addr=IP4:192.168.35.78:45418/UDP,peer_addr=IP4:192.168.37.2:12480/UDP) (stun/DEBUG) STUN-CLIENT(npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host)): Sending to IP4:192.168.37.2:12480/UDP [120]=000100642112a442570d2443198de18d9a34abde0006002937303061326264613732303930623734336161633632666336656432343965333a3739323263346530000000002400046e7f00ff802900087cd3b4227de3cd6600080014badc770a1c0e229ce90ea53ef56fb1c332758a1180280004db18ae47 (stun/DEBUG) STUN-CLIENT(npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host)): Next timer will fire in 2300 ms (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(cOqV|IP4:192.168.35.78:45418/UDP|IP4:80.86.168.180:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 12480 typ host)): Timer expired (after 2300 ms) (stun/INFO) STUN-CLIENT(cOqV|IP4:192.168.35.78:45418/UDP|IP4:80.86.168.180:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 12480 typ host)): Timed out (ice/DEBUG) ICE-PEER(PC:1399039909838125 (id=278 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/STREAM(1399039909838125 (id=278 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio)/CAND-PAIR(cOqV): STUN cb on pair addr = cOqV|IP4:192.168.35.78:45418/UDP|IP4:80.86.168.180:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 12480 typ host) (ice/INFO) ICE-PEER(PC:1399039909838125 (id=278 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND-PAIR(cOqV): setting pair to state FAILED: cOqV|IP4:192.168.35.78:45418/UDP|IP4:80.86.168.180:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:H5056a8b4 1 UDP 2130706431 80.86.168.180 12480 typ host) (generic/DEBUG) Timer callback fired (set in nr_stun_client_send_request:395) (stun/DEBUG) STUN-CLIENT(npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host)): Timer expired (after 2300 ms) (stun/INFO) STUN-CLIENT(npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host)): Timed out (ice/DEBUG) ICE-PEER(PC:1399039909838125 (id=278 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/STREAM(1399039909838125 (id=278 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio)/CAND-PAIR(npHk): STUN cb on pair addr = npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host) (ice/INFO) ICE-PEER(PC:1399039909838125 (id=278 url=http://appbox.cloudstack5.appspot.com/#dialing):default)/CAND-PAIR(npHk): setting pair to state FAILED: npHk|IP4:192.168.35.78:45418/UDP|IP4:192.168.37.2:12480/UDP(host(IP4:192.168.35.78:45418/UDP)|candidate:Hc0a82502 1 UDP 2130706431 192.168.37.2 12480 typ host) (ice/DEBUG) ICE-PEER(PC:1399039909838125 (id=278 url=http://appbox.cloudstack5.appspot.com/#dialing):default): stream 1399039909838125 (id=278 url=http://appbox.cloudstack5.appspot.com/#dialing): stream1/audio state ACTIVE->FAILED (ice/DEBUG) ICE-PEER(PC:1399039909838125 (id=278 url=http://appbox.cloudstack5.appspot.com/#dialing):default): 0 active streams (ice/INFO) ICE-PEER(PC:1399039909838125 (id=278 url=http://appbox.cloudstack5.appspot.com/#dialing):default): all checks completed success=0 fail=1
Is there a way to increase the timer for the check responses by configuration?
(In reply to Marko Seidenglanz from comment #9) > Is there a way to increase the timer for the check responses by > configuration? Nope, but the timers are already extremely generous; 100 + 300 + 700 + 2300 = 3.4 seconds to respond to a STUN request is pretty long. Why is it talking this long? Maybe a pcap would shed some light on this.
Attached file webrtc-ff-asterisk_failed.pcap (obsolete) —
PCAP of a failed Asterisk-To-FF Session
PCAP of a successful Asterisk-To-FF-Session
Attached file webrtc-ff-asterisk_failed.pcap (obsolete) —
Attachment #8416559 - Attachment is obsolete: true
Attachment #8416636 - Attachment is obsolete: true
Attachment #8416399 - Attachment is obsolete: true
Ok. I've uploaded FF Debug log: firefox_debug_stun_failed.txt and corresponding pcap: webrtc-ff-asterisk_failed.pcap
Ok, in the success case, we're getting prompt responses to our stun requests. In the failure case, we try sending one STUN request (keep in mind, in the success case, we send dozens), and never see a response. Can we get a look at about:webrtc in the failure case?
about:webrtc --> See file: firefox_debug_stun_failed.txt
Ok. The first STUN request we send is responded to *10 seconds later*. Many more are never responded to at all. There's nothing we can do about this.
How can you see that, as there are no timestamps in the log?
By looking at the pcap, of course.
I see. Thanks for your help.
When does FF send the first binding request? After the remote description was set or after local description was created?
No binding request can be sent until the first remote candidate shows up. This is usually in the remote SDP set by setRemoteDescription, but depending on the implementation, could be trickled in later.
Thank you. That helped a lot.
Is there a chance to delay the binding request process? Maybe a workaround could be to remove all candidates from the offer and pass them later via addCandidate()... But a better solution would be to do the offer answer process faster... I will have a look at that. Thank you very much.
So, one of the basic rules of SDP offer/answer is that you never output an SDP that you are not ready to receive traffic according to. If your SDP has an IP/port in it, that IP/port had better be open and ready to receive traffic before you let the SDP out into the world. This rule applies to the offerer just as much as the answerer.
Ok. Thank you. I will consider this.
Status: UNCONFIRMED → RESOLVED
Closed: 11 years ago
Resolution: --- → INVALID
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: