1025368 - Intermittent test_peerConnection_bug1013809.html | pc_local: ICE failed to switch to 'connected' state: failed (followed by the same with pc_remote)

Status: RESOLVED INCOMPLETE

(Core :: WebRTC, defect, P1)

Description

[Wes Kocher (:KWierso) (Not reading bugmail; email directly if needed)]

https://tbpl.mozilla.org/php/getParsedLog.php?id=41705234&tree=Fx-Team
Ubuntu VM 12.04 x64 fx-team opt test mochitest-e10s-3 on 2014-06-13 14:07:40 PDT for push a981121ef93b

slave: tst-linux64-spot-720

Comment 1

[Wes Kocher (:KWierso) (Not reading bugmail; email directly if needed)]

14:11:39     INFO -  1012 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | PeerConnectionWrapper (pcRemote): 'onsignalingstatechange' event fired
14:11:39     INFO -  1013 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | PeerConnectionWrapper (pcRemote): 'onsignalingstatechange' event 'have-remote-offer' received
14:11:39     INFO -  1014 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | PeerConnectionWrapper (pcRemote): Successfully set remote description
14:11:39     INFO -  1015 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | signalingState after remote setRemoteDescription is 'have-remote-offer'
14:11:39     INFO -  1016 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | Run step: PC_REMOTE_CREATE_ANSWER
14:11:39     INFO -  1017 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | PeerConnectionWrapper (pcRemote): Got answer: {"type":"answer","sdp":"v=0\r\no=Mozilla-SIPUA-33.0a1 897 0 IN IP4 0.0.0.0\r\ns=SIP Call\r\nt=0 0\r\na=ice-ufrag:d8883cbe\r\na=ice-pwd:9a0878ef0743b9236461ab3cdbdbae75\r\na=fingerprint:sha-256 B7:12:5F:BA:94:5E:FD:A7:62:18:3F:48:E7:F8:2D:6D:4A:F5:57:A1:DA:8B:01:2A:D2:2B:C0:4C:42:6F:79:3C\r\nm=audio 51945 RTP/SAVPF 109 101\r\nc=IN IP4 10.132.157.178\r\na=rtpmap:109 opus/48000/2\r\na=ptime:20\r\na=rtpmap:101 telephone-event/8000\r\na=fmtp:101 0-15\r\na=sendrecv\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=setup:active\r\na=candidate:0 1 UDP 2122252543 10.132.157.178 51945 typ host\r\na=candidate:0 2 UDP 2122252542 10.132.157.178 49221 typ host\r\na=rtcp-mux\r\n"}
14:11:39     INFO -  1018 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | Remote createAnswer does not change signaling state
14:11:39     INFO -  1019 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | Run step: PC_LOCAL_GET_ANSWER
14:11:39     INFO -  1020 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | Run step: PC_LOCAL_SET_REMOTE_DESCRIPTION
14:11:39     INFO -  1021 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | iceConnectionState should not be undefined
14:11:39     INFO -  1022 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | PeerConnectionWrapper (pcLocal): oniceconnectionstatechange fired, new state is: checking
14:11:39     INFO -  1023 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | Legal ICE state transition from new to checking
14:11:39     INFO -  1024 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | PeerConnectionWrapper (pcLocal): 'onaddstream' event fired for {}
14:11:39     INFO -  1025 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | Got media stream: audio (remote)
14:11:39     INFO -  1026 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | PeerConnectionWrapper (pcLocal): 'onsignalingstatechange' event fired
14:11:39     INFO -  1027 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | PeerConnectionWrapper (pcLocal): 'onsignalingstatechange' event 'stable' received
14:11:39     INFO -  1028 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | PeerConnectionWrapper (pcLocal): Successfully set remote description
14:11:39     INFO -  1029 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | signalingState after local setRemoteDescription is 'stable'
14:11:39     INFO -  1030 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | Run step: PC_REMOTE_SET_LOCAL_DESCRIPTION
14:11:39     INFO -  1031 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | canplaythrough fired for media element pcLocal_remote_audio
14:11:39     INFO -  1032 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | timeupdate fired for media element pcLocal_remote_audio
14:11:39     INFO -  1033 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | time passed for media element pcLocal_remote_audio
14:11:39     INFO -  1034 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | PeerConnectionWrapper (pcRemote): 'onsignalingstatechange' event fired
14:11:39     INFO -  1035 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | PeerConnectionWrapper (pcRemote): 'onsignalingstatechange' event 'stable' received
14:11:39     INFO -  1036 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | PeerConnectionWrapper (pcRemote): Successfully set the local description
14:11:39     INFO -  1037 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | signalingState after remote setLocalDescription is 'stable'
14:11:39     INFO -  1038 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | PeerConnectionWrapper (pcRemote): 'onaddstream' event fired for {}
14:11:39     INFO -  1039 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | Got media stream: audio (remote)
14:11:39     INFO -  1040 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | Run step: PC_LOCAL_WAIT_FOR_ICE_CONNECTED
14:11:39     INFO -  1041 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | iceConnectionState: checking
14:11:39     INFO -  1042 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | timeupdate fired for media element pcRemote_remote_audio
14:11:39     INFO -  1043 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | time passed for media element pcRemote_remote_audio
14:11:39     INFO -  1044 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | canplaythrough fired for media element pcRemote_remote_audio
14:11:39     INFO -  1045 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | iceConnectionState should not be undefined
14:11:39     INFO -  1046 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | PeerConnectionWrapper (pcRemote): oniceconnectionstatechange fired, new state is: checking
14:11:39     INFO -  1047 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | Legal ICE state transition from new to checking
14:11:39     INFO -  1048 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | iceConnectionState should not be undefined
14:11:39     INFO -  1049 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | PeerConnectionWrapper (pcLocal): oniceconnectionstatechange fired, new state is: failed
14:11:39     INFO -  1050 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | Legal ICE state transition from checking to failed
14:11:39     INFO -  1051 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | iceConnectionState: failed
14:11:39     INFO -  1052 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | pc_local: ICE failed to switch to 'connected' state: failed
14:11:39     INFO -  (stun/INFO) STUN-CLIENT(srflx(IP4:10.132.157.178:56988/UDP|stun.services.mozilla.com:3478)): Timed out
14:11:39     INFO -  (stun/INFO) STUN-CLIENT(srflx(IP4:10.132.157.178:45691/UDP|stun.services.mozilla.com:3478)): Timed out
14:11:39     INFO -  902862592[7f014a02c1e0]: [MediaStreamGrph|WebrtcAudioSessionConduit] AudioConduit.cpp:691: A/V sync: GetAVStats failed
14:11:39     INFO -  902862592[7f014a02c1e0]: [MediaStreamGrph|WebrtcAudioSessionConduit] AudioConduit.cpp:691: A/V sync: GetAVStats failed
14:11:40     INFO -  902862592[7f014a02c1e0]: [MediaStreamGrph|WebrtcAudioSessionConduit] AudioConduit.cpp:691: A/V sync: GetAVStats failed
14:11:40     INFO -  902862592[7f014a02c1e0]: [MediaStreamGrph|WebrtcAudioSessionConduit] AudioConduit.cpp:691: A/V sync: GetAVStats failed
14:11:40     INFO -  (stun/INFO) STUN-CLIENT(00pq|IP4:10.132.157.178:51945/UDP|IP4:10.132.157.178:56988/UDP(host(IP4:10.132.157.178:51945/UDP)|candidate:0 1 UDP 2122252543 10.132.157.178 56988 typ host)): Timed out
14:11:40     INFO -  (ice/INFO) ICE-PEER(PC:1402693893197606 (id=431 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html):default)/CAND-PAIR(00pq): setting pair to state FAILED: 00pq|IP4:10.132.157.178:51945/UDP|IP4:10.132.157.178:56988/UDP(host(IP4:10.132.157.178:51945/UDP)|candidate:0 1 UDP 2122252543 10.132.157.178 56988 typ host)
14:11:40     INFO -  (ice/INFO) ICE-PEER(PC:1402693893197606 (id=431 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html):default)/STREAM(1402693893197606 (id=431 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html): stream1/audio)/COMP(1): All pairs are failed, and grace period has elapsed. Marking component as failed.
14:11:40     INFO -  1356850944[7f0157e1d6e0]: NrIceCtx(PC:1402693893197606 (id=431 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html)): state 1->3
14:11:40     INFO -  1356850944[7f0157e1d6e0]: /builds/slave/fx-team-l64-000000000000000000/build/media/mtransport/transportlayerice.cpp:134: Flow[8f9c03b131ea322e:1,rtp(none)]; Layer[ice]: state 1->5
14:11:40     INFO -  1356850944[7f0157e1d6e0]: Flow[8f9c03b131ea322e:1,rtp(none)]; Layer[dtls]: Lower lower experienced an error
14:11:40     INFO -  1356850944[7f0157e1d6e0]: /builds/slave/fx-team-l64-000000000000000000/build/media/mtransport/transportlayerdtls.cpp:625: Flow[8f9c03b131ea322e:1,rtp(none)]; Layer[dtls]: state 1->5
14:11:40     INFO -  (ice/INFO) ICE-PEER(PC:1402693893197606 (id=431 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html):default): all checks completed success=0 fail=1
14:11:40     INFO -  ERROR: SDP offer: v=0
14:11:40     INFO -  o=Mozilla-SIPUA-33.0a1 23790 0 IN IP4 0.0.0.0
14:11:40     INFO -  s=SIP Call
14:11:40     INFO -  t=0 0
14:11:40     INFO -  a=ice-ufrag:1b44888e
14:11:40     INFO -  a=ice-pwd:8d6f14363dca8f566215b8bf78149a34
14:11:40     INFO -  a=fingerprint:sha-256 79:1C:25:F0:96:E6:2B:5E:ED:F9:62:4A:8F:47:54:AD:6D:48:81:B1:59:81:29:95:19:34:54:DC:BC:30:6D:40
14:11:40     INFO -  m=audio 56988 RTP/SAVPF 109 0 8 101
14:11:40     INFO -  c=IN IP4 10.132.157.178
14:11:40     INFO -  a=rtpmap:109 opus/48000/2
14:11:40     INFO -  a=ptime:20
14:11:40     INFO -  a=rtpmap:0 PCMU/8000
14:11:40     INFO -  a=rtpmap:8 PCMA/8000
14:11:40     INFO -  a=rtpmap:101 telephone-event/8000
14:11:40     INFO -  a=fmtp:101 0-15
14:11:40     INFO -  a=sendrecv
14:11:40     INFO -  a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
14:11:40     INFO -  a=setup:actpass
14:11:40     INFO -  a=candidate:0 1 UDP 2122252543 10.132.157.178 56988 typ host
14:11:40     INFO -  a=candidate:0 2 UDP 2122252542 10.132.157.178 49322 typ host
14:11:40     INFO -  a=rtcp-mux
14:11:40     INFO -  ERROR: SDP answer: v=0
14:11:40     INFO -  o=Mozilla-SIPUA-33.0a1 897 0 IN IP4 0.0.0.0
14:11:40     INFO -  s=SIP Call
14:11:40     INFO -  t=0 0
14:11:40     INFO -  a=ice-ufrag:d8883cbe
14:11:40     INFO -  a=ice-pwd:9a0878ef0743b9236461ab3cdbdbae75
14:11:40     INFO -  a=fingerprint:sha-256 B7:12:5F:BA:94:5E:FD:A7:62:18:3F:48:E7:F8:2D:6D:4A:F5:57:A1:DA:8B:01:2A:D2:2B:C0:4C:42:6F:79:3C
14:11:40     INFO -  m=audio 51945 RTP/SAVPF 109 101
14:11:40     INFO -  c=IN IP4 10.132.157.178
14:11:40     INFO -  a=rtpmap:109 opus/48000/2
14:11:40     INFO -  a=ptime:20
14:11:40     INFO -  a=rtpmap:101 telephone-event/8000
14:11:40     INFO -  a=fmtp:101 0-15
14:11:40     INFO -  a=sendrecv
14:11:40     INFO -  a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
14:11:40     INFO -  a=setup:active
14:11:40     INFO -  a=candidate:0 1 UDP 2122252543 10.132.157.178 51945 typ host
14:11:40     INFO -  a=candidate:0 2 UDP 2122252542 10.132.157.178 49221 typ host
14:11:40     INFO -  a=rtcp-mux
14:11:40     INFO -  pcLocal ICE connection state log: new,checking,failed
14:11:40     INFO -  pcRemote ICE connection state log: new,checking,failed
14:11:40     INFO -  Delay between pcLocal.setRemote <-> pcRemote.setLocal: 0.085
14:11:40     INFO -  Delay between pcLocal.setRemote <-> pcLocal.signalingStateStable: 0
14:11:40     INFO -  Delay between pcRemote.setLocal <-> pcRemote.signalingStateStable: -0.004
14:11:40     INFO -  1053 INFO TEST-INFO | dumping last 6 message(s)
14:11:40     INFO -  1054 INFO TEST-INFO | if you need more context, please use SimpleTest.requestCompleteLog() in your test
14:11:40     INFO -  1055 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | Run step: PC_REMOTE_WAIT_FOR_ICE_CONNECTED
14:11:40     INFO -  1056 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | iceConnectionState: checking
14:11:40     INFO -  1057 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | iceConnectionState should not be undefined
14:11:40     INFO -  1058 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | PeerConnectionWrapper (pcRemote): oniceconnectionstatechange fired, new state is: failed
14:11:40     INFO -  1059 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | Legal ICE state transition from checking to failed
14:11:40     INFO -  1060 INFO TEST-INFO | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | iceConnectionState: failed
14:11:40     INFO -  1061 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | pc_remote: ICE failed to switch to 'connected' state: failed
14:11:40     INFO -  1698470336[7f0157e1d140]: [main|PeerConnectionImpl] PeerConnectionImpl.cpp:1691: CloseInt: Closing PeerConnectionImpl 6444a5fb2e46b5cc; ending call
14:11:40     INFO -  1294100224[7f014466d1a0]: [CCAPP Task|def] ccapi.c:1445: SIPCC-CC_API: 1/24, cc_int_onhook: UI -> GSM: ONHOOK
14:11:40     INFO -  1294100224[7f014466d1a0]: [CCAPP Task|def] ccapi.c:1447: (1/24) On-hook called from /builds/slave/fx-team-l64-000000000000000000/build/media/webrtc/signaling/src/sipcc/core/ccapp/ccprovider.c:657
14:11:40     INFO -  1281996544[7f014466cf60]: [GSM Task|def] dcsm.c:532: SIPCC-DCSM: dcsm_process_event: DCSM 11  :(DCSM_READY:ONHOOK )
14:11:40     INFO -  1281996544[7f014466cf60]: [GSM Task|fsm_sm] sm.c:46: SIPCC-FSM: sm_process_event: DEF 24  : 0x7f01617a4ea6x: sm entry: (STABLE:ONHOOK)
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|fsm_sm] fsmdef.c:6713: SIPCC-FSM: fsmdef_ev_onhook: Entered.
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|fsm_sm] fsmdef.c:1987: SIPCC-FSM: 1/24, fsmdef_release: Entered. cause= NORMAL
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|fsm_sm] fsmdef.c:9199: SIPCC-FSM: fsmdef_notify_hook_event: Entered.
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|def] ccapi.c:1445: SIPCC-CC_API: 1/24, cc_int_onhook: GSM -> SIP: ONHOOK
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|def] ccapi.c:1447: (1/24) On-hook called from /builds/slave/fx-team-l64-000000000000000000/build/media/webrtc/signaling/src/sipcc/core/gsm/fsmdef.c:9208
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|tnp] ui.c:1023: SIPCC-UI_API: ui_get_idle_prompt_string: called
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|tnp] ui.c:654: SIPCC-UI_API: 1/24, ui_set_call_status: the stat string =
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|tnp] ui.c:616: SIPCC-UI_API: 1/24, ui_set_call_status_display: the stat string =, timeout= 0, priority=0
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|tnp] ui.c:104: SIPCC-UI_API: 1/24, ui_call_state: event=1
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|def] fsm.c:346: SIPCC-FSM: 1/24, fsm_change_state: DEF: STABLE -> CLOSED
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|def] dcsm.c:397: SIPCC-DCSM: dcsm_update_gsm_state: 24 : DCSM_READY --> DCSM_READY
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|fsm_sm] fsmdef.c:1293: SIPCC-FSM: 0/0, fsmdef_init_dcb: call_not_counted_in_mnc_bt = FALSE
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|def] fsm.c:346: SIPCC-FSM: 0/24, fsm_change_state: DEF: CLOSED -> IDLE
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|def] dcsm.c:397: SIPCC-DCSM: dcsm_update_gsm_state: 24 : DCSM_READY --> DCSM_READY
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|def] sm.c:65: SIPCC-GSM: 1/24, sm_process_event: DEF   :(STABLE:ONHOOK )
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 24  : fsm_get_fcb_by_call_id_and_type    : fcb= 0x7f014a2dd000
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 24  : fsm_get_fcb_by_call_id_and_type    : fcb= (nil)
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|def] fsm.c:346: SIPCC-FSM: 0/24, fsm_change_state: CNF: IDLE -> IDLE
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|def] dcsm.c:337: SIPCC-DCSM: dcsm_update_gsm_state: 24: Not handling for CNF
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 24  : fsm_get_fcb_by_call_id_and_type    : fcb= 0x7f014a2dd030
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|def] fsm.c:346: SIPCC-FSM: 0/24, fsm_change_state: B2BCNF: IDLE -> IDLE
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|def] dcsm.c:337: SIPCC-DCSM: dcsm_update_gsm_state: 24: Not handling for B2BCNF
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 24  : fsm_get_fcb_by_call_id_and_type    : fcb= 0x7f014a2dd060
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|fsm_sm] fsmxfr.c:501: SIPCC-FSM: fsmxfr_cleanup: Entered.
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|fsm_sm] fsmxfr.c:451: SIPCC-FSM: fsmxfr_remove_fcb: Entered.
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|def] fsm.c:346: SIPCC-FSM: 0/24, fsm_change_state: XFR: IDLE -> IDLE
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|def] dcsm.c:337: SIPCC-DCSM: dcsm_update_gsm_state: 24: Not handling for XFR
14:11:41     INFO -  1281996544[7f014466cf60]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 24  : fsm_get_fcb_by_call_id_and_type    : fcb= (nil)
14:11:41     INFO -  1294100224[7f014466d1a0]: [CCAPP Task|def] ccprovider.c:1613: SIPCC-SIP_CC_PROV: ccappUpdateSessionData: Call_STATE:. state=1, attr=0, cause=5, instance=0
14:11:41     INFO -  1294100224[7f014466d1a0]: [CCAPP Task|CC_SIPCCService] CC_SIPCCService.cpp:724: onCallEvent(CCAPI_CALL_EV_STATE, 10018, [ONHOOK|]
14:11:41     INFO -  1294100224[7f014466d1a0]: [CCAPP Task|def] ccapi_call.c:57: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x7f012e9edc00: count=2
14:11:41     INFO -  1294100224[7f014466d1a0]: [CCAPP Task|def] ccapi_call.c:57: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x7f0142cb3800: count=1
14:11:41     INFO -  1698470336[7f0157e1d140]: [main|PeerConnectionImpl] PeerConnectionImpl.cpp:1691: CloseInt: Closing PeerConnectionImpl 8f9c03b131ea322e; ending call
14:11:41     INFO -  1294100224[7f014466d1a0]: [CCAPP Task|def] ccapi.c:1445: SIPCC-CC_API: 1/25, cc_int_onhook: UI -> GSM: ONHOOK

Comment 2

[Legacy TBPL/Treeherder Robot]

KWierso
https://tbpl.mozilla.org/php/getParsedLog.php?id=42309779&tree=Mozilla-Inbound
b2g_emulator_vm mozilla-inbound opt test mochitest-7 on 2014-06-23 16:19:20
revision: 8d2002e67d13
slave: tst-linux64-spot-498

246 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | pc_local: ICE failed to switch to 'connected' state: failed
254 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | pc_remote: ICE is already in bad state: failed
Return code: 1

Comment 3

[Legacy TBPL/Treeherder Robot]

edmorley
https://tbpl.mozilla.org/php/getParsedLog.php?id=42636523&tree=Fx-Team
b2g_emulator_vm fx-team opt test mochitest-7 on 2014-06-27 08:15:47
revision: c124393b6eac
slave: tst-linux64-spot-1061

201 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | pc_local: ICE failed to switch to 'connected' state: failed
210 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | pc_remote: ICE failed to switch to 'connected' state: failed
Return code: 1

Comment 4

[Legacy TBPL/Treeherder Robot]

RyanVM
https://tbpl.mozilla.org/php/getParsedLog.php?id=42649771&tree=Mozilla-Inbound
b2g_emulator_vm mozilla-inbound opt test mochitest-7 on 2014-06-27 11:37:10
revision: 95ce6d0c7993
slave: tst-linux64-spot-608

172 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | This IP:Port UDP:10.0.2.100:52804 appears in SDP offer and answer!
210 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | pc_local: ICE failed to switch to 'connected' state: failed
218 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | pc_remote: ICE is already in bad state: failed
Return code: 1

Comment 5

[Maire Reavy [:mreavy]]

Byron -- I think this is another instance of the ICE connectivity/timing issues that you're already working on.

Comment 6

[Legacy TBPL/Treeherder Robot]

edmorley
https://tbpl.mozilla.org/php/getParsedLog.php?id=43043787&tree=Mozilla-Inbound
Ubuntu VM 12.04 x64 mozilla-inbound opt test mochitest-e10s-3 on 2014-07-03 08:54:54
revision: 4221d7bca996
slave: tst-linux64-spot-090

1073 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | pc_local: ICE failed to switch to 'connected' state: failed
1082 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | pc_remote: ICE failed to switch to 'connected' state: failed

Comment 7

[Nils Ohlmeier [:drno]]

BTW this bug specifically is about the reverse order of setLocalDescription and setRemoteDescription. So in my opinion this has low priority compared to other problems.

Comment 8

[Legacy TBPL/Treeherder Robot]

KWierso
https://tbpl.mozilla.org/php/getParsedLog.php?id=43772062&tree=Fx-Team
Ubuntu VM 12.04 fx-team opt test mochitest-e10s-3 on 2014-07-14 14:07:34
revision: 2bb155ee786b
slave: tst-linux32-spot-607

1072 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | pc_local: ICE failed to switch to 'connected' state: failed
1081 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | pc_remote: ICE failed to switch to 'connected' state: failed

Comment 9

[Legacy TBPL/Treeherder Robot]

RyanVM
https://tbpl.mozilla.org/php/getParsedLog.php?id=44111997&tree=Mozilla-Inbound
Ubuntu VM 12.04 mozilla-inbound opt test mochitest-e10s-3 on 2014-07-18 07:35:32
revision: 18138d562325
slave: tst-linux32-spot-416

9545 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | pc_local: ICE failed to switch to 'connected' state: failed
10449 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | pc_remote: ICE failed to switch to 'connected' state: failed

Comment 10

[Legacy TBPL/Treeherder Robot]

KWierso
https://tbpl.mozilla.org/php/getParsedLog.php?id=44924748&tree=Mozilla-Inbound
Ubuntu VM 12.04 x64 mozilla-inbound opt test mochitest-e10s-3 on 2014-07-30 15:23:04
revision: bb9d2f1e6619
slave: tst-linux64-spot-016

9857 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | This IP:Port UDP:10.134.157.203:59127 appears in SDP offer and answer!
12100 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | pc_local: ICE failed to switch to 'connected' state: failed
13781 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug1013809.html | pc_remote: ICE failed to switch to 'connected' state: failed

Comment 11

[Randell Jesup [:jesup] (needinfo me)]

Not seen in a year