Closed Bug 874850 Opened 11 years ago Closed 11 years ago

Intermittent dom/media/tests/mochitest/test_dataChannel_noOffer.html | Exited with code -20 during test run

Categories

(Core :: WebRTC, defect)

Product:
Core
Component:
WebRTC
Platform:
x86_64
macOS
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

()

Status:
RESOLVED WORKSFORME

People

(Reporter: RyanVM, Unassigned)

References

Details

(Keywords: intermittent-failure, Whiteboard: [WebRTC][blocking-webrtc-]) 

https://tbpl.mozilla.org/php/getParsedLog.php?id=23219185&tree=Mozilla-Inbound

Rev5 MacOSX Mountain Lion 10.8 mozilla-inbound opt test mochitest-3 on 2013-05-21 13:09:43 PDT for push 8521a38f2803
slave: talos-mtnlion-r5-068

13:20:54     INFO -  617230336[123a9df50]: [CCAPP Task|def] ccapi.c:1161: SIPCC-CC_API: 1/1, cc_int_feature2: UI -> GSM: SETPEERCONNECTION
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|def] dcsm.c:532: SIPCC-DCSM: dcsm_process_event: DCSM 22  :(DCSM_READY:SETPEERCONNECTION )
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsm.c:232: SIPCC-GSM_DBG_PTR: FSM 0   : fsm_get_fcb_by_call_id             : fcb= 0x124817000
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsm.c:266: SIPCC-GSM_DBG_PTR: FSM 1   : fsm_get_new_fcb                    : fcb= 0x124817000
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsm.c:232: SIPCC-GSM_DBG_PTR: FSM 0   : fsm_get_fcb_by_call_id             : fcb= 0x124817030
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsm.c:266: SIPCC-GSM_DBG_PTR: FSM 1   : fsm_get_new_fcb                    : fcb= 0x124817030
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsm.c:232: SIPCC-GSM_DBG_PTR: FSM 0   : fsm_get_fcb_by_call_id             : fcb= 0x124817060
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsm.c:266: SIPCC-GSM_DBG_PTR: FSM 1   : fsm_get_new_fcb                    : fcb= 0x124817060
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsm.c:232: SIPCC-GSM_DBG_PTR: FSM 0   : fsm_get_fcb_by_call_id             : fcb= 0x124817090
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsm.c:266: SIPCC-GSM_DBG_PTR: FSM 1   : fsm_get_new_fcb                    : fcb= 0x124817090
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] sm.c:46: SIPCC-FSM: sm_process_event: DEF 1   : 0x102f7eae0x: sm entry: (IDLE:SETPEERCONNECTION)
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsmdef.c:3486: SIPCC-FSM: fsmdef_ev_setpeerconnection: Entered.
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsmdef.c:702: SIPCC-FSM: DEF 0   /0: fsmdef_get_dcb_by_call_id          : dcb= 0x1248c9000
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsmdef.c:1233: SIPCC-FSM: DEF 1   /0: fsmdef_get_new_dcb                 : dcb= 0x1248c9000
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsmdef.c:1084: SIPCC-FSM: 1/1, fsmdef_init_dcb: call_not_counted_in_mnc_bt = FALSE
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 1   : fsm_get_fcb_by_call_id_and_type    : fcb= 0x124817000
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 1   : fsm_get_fcb_by_call_id_and_type    : fcb= 0x124817030
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 1   : fsm_get_fcb_by_call_id_and_type    : fcb= 0x124817060
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 1   : fsm_get_fcb_by_call_id_and_type    : fcb= 0x124817090
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|fsm_sm] fsmdef.c:3521: SIPCC-FSM: fsmdef_ev_setpeerconnection: Setting peerconnection handle for (1/1) to 0ed1084e3bb0cf04
13:20:54     INFO -  617451520[123a9e1b0]: [GSM Task|def] sm.c:65: SIPCC-GSM: 1/1, sm_process_event: DEF   :(IDLE:SETPEERCONNECTION )
13:20:54     INFO -  617377792[123a9e410]: [SIP MsgQueueWait task|cpr] cpr_darwin_stdio.c:124: cprAdjustRelativeThreadPriority: could not set the nice..err=13
13:20:54     INFO -  617304064[123a9e080]: [SIPStack task|sdp_config] sdp_config.c:101: SDP: Initialized config pointer: 0x104284f00 (magic=0xABCDABCD)
13:20:54     INFO -  617304064[123a9e080]: [SIPStack task|tnp] ui.c:879: SIPCC-UI_API: ui_set_sip_registration_state: ALL LINES 255: REGISTERED
13:20:54     INFO -  617304064[123a9e080]: [SIPStack task|def] ccsip_task.c:471: SIP : SIPTaskProcessListEvent : sip.taskInited is set to true
13:20:54     INFO -  617230336[123a9df50]: [CCAPP Task|CC_SIPCCService] CC_SIPCCService.cpp:628: onDeviceEvent( CCAPI_DEVICE_EV_STATE, 0, [] )
13:20:54     INFO -  617230336[123a9df50]: [CCAPP Task|def] cc_device_manager.c:232: registration_processEvent:  Event EV_CC_INSERVICE, current State MGMT_STATE_REGISTERING
13:20:54     INFO -  617230336[123a9df50]: [CCAPP Task|def] cc_device_manager.c:193: setState: new registration state=  MGMT_STATE_REGISTERED
13:20:54     INFO -  617230336[123a9df50]: [CCAPP Task|def] cc_device_manager.c:193: setState: new registration state=  MGMT_STATE_REGISTERED
13:20:54     INFO -  NOTE: child process received `Goodbye', closing down
13:20:54  WARNING -  TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_dataChannel_noOffer.html | Exited with code -20 during test run
13:20:54     INFO -  INFO | automation.py | Application ran for: 0:09:40.403124
13:20:54     INFO -  INFO | zombiecheck | Reading PID log: /var/folders/rx/_s2192dn6j30_5kykk2bghl400000w/T/tmppitTgHpidlog
13:20:54     INFO -  WARNING | leakcheck | refcount logging is off, so leaks can't be detected!
13:20:54     INFO -  INFO | runtests.py | Running tests: end.
13:20:54    ERROR - Return code: 236
Ethan - from the log, it seems like things shut down pretty early in sipcc startup - does it give any hints about what would be next/where it may have failed?
Whiteboard: [WebRTC][blocking-webrtc-]
(In reply to Randell Jesup [:jesup] from comment #1)
> Ethan - from the log, it seems like things shut down pretty early in sipcc
> startup - does it give any hints about what would be next/where it may have
> failed?

Hmm...not seeing the output from the ok test in there, so we're failing at createOffer.
jesup - it does look like it aborted very early on.  Here are the lines that should've followed those based on a local run of this mochitest.  That assumes also that lines are not lost when this kind of crash happens.

384655360[10e99e9f0]: [CCAPP Task|def] ccapi.c:1231: SIPCC-CC_API: 1/1, send_message_helper: UI -> GSM: CREATEOFFER
575619072[10e99ec50]: [GSM Task|def] dcsm.c:532: SIPCC-DCSM: dcsm_process_event: DCSM 18  :(DCSM_READY:CREATEOFFER )
575619072[10e99ec50]: [GSM Task|fsm_sm] sm.c:46: SIPCC-FSM: sm_process_event: DEF 1   : 0x10516e010x: sm entry: (IDLE:CREATEOFFER)
575619072[10e99ec50]: [GSM Task|fsm_sm] fsmdef.c:2872: SIPCC-FSM: fsmdef_ev_createoffer: Entered.
575619072[10e99ec50]: [GSM Task|tnp] ui.c:1613: SIPCC-UI_API: 1/1, ui_create_offer: state=22 call_instance=0
Depends on: 884471
Fixed (or worked around) by bug 884471.
Status: NEW → RESOLVED
Closed: 11 years ago
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.