853275 - Intermittent dom/media/tests/crashtests/812785.html | load failed: timed out waiting for reftest-wait to be removed

Reporter

Description

•

11 years ago

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

Rev4 MacOSX Lion 10.7 mozilla-inbound debug test crashtest on 2013-03-20 16:49:54 PDT for push e70bea1fd166
slave: talos-r4-lion-027

16:55:20     INFO -  147308544[11b589e50]: [CCAPP Task|def] ccapi.c:1423: SIPCC-CC_API: 7/9, cc_int_onhook: UI -> GSM: ONHOOK
16:55:20     INFO -  147308544[11b589e50]: [CCAPP Task|def] ccapi.c:1423: SIPCC-CC_API: 6/8, cc_int_onhook: UI -> GSM: ONHOOK
16:55:20     INFO -  207319040[14333f660]: [GSM Task|def] dcsm.c:532: SIPCC-DCSM: dcsm_process_event: DCSM 11  :(DCSM_READY:ONHOOK )
16:55:20     INFO -  207319040[14333f660]: [GSM Task|def] dcsm.c:532: SIPCC-DCSM: dcsm_process_event: DCSM 11  :(DCSM_READY:ONHOOK )
16:55:57     INFO -  WARNING: 1 sort operation has occurred for the SQL statement '0x105172000'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file ../../../storage/src/mozStoragePrivateHelpers.cpp, line 110
16:55:57     INFO -  WARNING: 1 sort operation has occurred for the SQL statement '0x11a86e010'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file ../../../storage/src/mozStoragePrivateHelpers.cpp, line 110
16:55:57     INFO -  WARNING: 1 sort operation has occurred for the SQL statement '0x10c4966e0'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file ../../../storage/src/mozStoragePrivateHelpers.cpp, line 110
16:55:57     INFO -  WARNING: 1 sort operation has occurred for the SQL statement '0x11a85c1c0'.  See https://developer.mozilla.org/En/Storage/Warnings details.: file ../../../storage/src/mozStoragePrivateHelpers.cpp, line 110
17:00:09     INFO -  REFTEST TEST-UNEXPECTED-FAIL | file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html | load failed: timed out waiting for reftest-wait to be removed
17:00:09     INFO -  REFTEST INFO | Saved log: START file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] OnDocumentLoad triggering WaitForTestEnd
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] WaitForTestEnd: Adding listeners
17:00:09     INFO -  REFTEST INFO | Saved log: Initializing canvas snapshot
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FIRE_INVALIDATE_EVENT
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: dispatching MozReftestInvalidate
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] Rect: 8 0 612 41
17:00:09     INFO -  REFTEST INFO | Saved log: Updating canvas for invalidation
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] Rect: 8 0 308 36
17:00:09     INFO -  REFTEST INFO | Saved log: Updating canvas for invalidation
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] Rect: 8 0 308 36
17:00:09     INFO -  REFTEST INFO | Saved log: Updating canvas for invalidation
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] Rect: 8 0 308 36
17:00:09     INFO -  REFTEST INFO | Saved log: Updating canvas for invalidation
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] Rect: 8 0 308 36
17:00:09     INFO -  REFTEST INFO | Saved log: Updating canvas for invalidation
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 2 rects
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] Rect: 8 0 307 36
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] Rect: 307 0 612 41
17:00:09     INFO -  REFTEST INFO | Saved log: Updating canvas for invalidation
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] OnDocumentLoad triggering WaitForTestEnd
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] WaitForTestEnd: Adding listeners
17:00:09     INFO -  REFTEST INFO | Saved log: Initializing canvas snapshot
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FIRE_INVALIDATE_EVENT
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: dispatching MozReftestInvalidate
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] Rect: 8 0 612 41
17:00:09     INFO -  REFTEST INFO | Saved log: Updating canvas for invalidation
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] Rect: 8 0 308 36
17:00:09     INFO -  REFTEST INFO | Saved log: Updating canvas for invalidation
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] Rect: 8 0 308 36
17:00:09     INFO -  REFTEST INFO | Saved log: Updating canvas for invalidation
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 2 rects
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] Rect: 8 0 307 36
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] Rect: 307 0 612 41
17:00:09     INFO -  REFTEST INFO | Saved log: Updating canvas for invalidation
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] OnDocumentLoad triggering WaitForTestEnd
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] WaitForTestEnd: Adding listeners
17:00:09     INFO -  REFTEST INFO | Saved log: Initializing canvas snapshot
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FIRE_INVALIDATE_EVENT
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: dispatching MozReftestInvalidate
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] Rect: 8 0 612 41
17:00:09     INFO -  REFTEST INFO | Saved log: Updating canvas for invalidation
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 2 rects
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] Rect: 8 0 307 36
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] Rect: 307 0 612 41
17:00:09     INFO -  REFTEST INFO | Saved log: Updating canvas for invalidation
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
17:00:09     INFO -  REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
17:00:09     INFO -  REFTEST INFO | Loading a blank page
17:00:09     INFO -  ++DOMWINDOW == 30 (0x117eda718) [serial = 1248] [outer = 0x10c46f358]
17:00:09     INFO -  147308544[11b589e50]: [CCAPP Task|def] ccapi.c:1423: SIPCC-CC_API: 6/10, cc_int_onhook: UI -> GSM: ONHOOK
17:00:09     INFO -  207319040[14333f660]: [GSM Task|def] dcsm.c:532: SIPCC-DCSM: dcsm_process_event: DCSM 11  :(DCSM_READY:ONHOOK )
17:00:09     INFO -  207319040[14333f660]: [GSM Task|fsm_sm] sm.c:46: SIPCC-FSM: sm_process_event: DEF 10  : 0x1032042c0: sm entry: (CALL_SENT:ONHOOK)
17:00:09     INFO -  207319040[14333f660]: [GSM Task|fsm_sm] fsmdef.c:6083: SIPCC-FSM: fsmdef_ev_onhook: Entered.
17:00:09     INFO -  207319040[14333f660]: [GSM Task|fsm_sm] fsmdef.c:1771: SIPCC-FSM: 6/10, fsmdef_release: Entered. cause= NORMAL
17:00:09     INFO -  207319040[14333f660]: [GSM Task|fsm_sm] fsmdef.c:8569: SIPCC-FSM: fsmdef_notify_hook_event: Entered.
17:00:09     INFO -  207319040[14333f660]: [GSM Task|def] ccapi.c:1423: SIPCC-CC_API: 6/10, cc_int_onhook: GSM -> SIP: ONHOOK
17:00:09     INFO -  207319040[14333f660]: [GSM Task|def] fsm.c:346: SIPCC-FSM: 6/10, fsm_change_state: DEF: CALL_SENT -> IDLE
17:00:09     INFO -  207319040[14333f660]: [GSM Task|def] dcsm.c:397: SIPCC-DCSM: dcsm_update_gsm_state: 10 : DCSM_READY --> DCSM_READY
17:00:09     INFO -  207319040[14333f660]: [GSM Task|fsm_sm] fsmdef.c:1084: SIPCC-FSM: 0/0, fsmdef_init_dcb: call_not_counted_in_mnc_bt = FALSE
17:00:09     INFO -  207319040[14333f660]: [GSM Task|def] fsm.c:346: SIPCC-FSM: 0/10, fsm_change_state: DEF: IDLE -> IDLE
17:00:09     INFO -  207319040[14333f660]: [GSM Task|def] dcsm.c:397: SIPCC-DCSM: dcsm_update_gsm_state: 10 : DCSM_READY --> DCSM_READY
17:00:09     INFO -  207319040[14333f660]: [GSM Task|def] sm.c:65: SIPCC-GSM: 6/10, sm_process_event: DEF   :(CALL_SENT:ONHOOK )
17:00:09     INFO -  207319040[14333f660]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 10  : fsm_get_fcb_by_call_id_and_type    : fcb= 0x141c23dc0
17:00:09     INFO -  207319040[14333f660]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 10  : fsm_get_fcb_by_call_id_and_type    : fcb= 0x0
17:00:09     INFO -  207319040[14333f660]: [GSM Task|def] fsm.c:346: SIPCC-FSM: 0/10, fsm_change_state: CNF: IDLE -> IDLE
17:00:09     INFO -  207319040[14333f660]: [GSM Task|def] dcsm.c:337: SIPCC-DCSM: dcsm_update_gsm_state: 10: Not handling for CNF
17:00:09     INFO -  207319040[14333f660]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 10  : fsm_get_fcb_by_call_id_and_type    : fcb= 0x141c23df0
17:00:09     INFO -  207319040[14333f660]: [GSM Task|def] fsm.c:346: SIPCC-FSM: 0/10, fsm_change_state: B2BCNF: IDLE -> IDLE
17:00:09     INFO -  207319040[14333f660]: [GSM Task|def] dcsm.c:337: SIPCC-DCSM: dcsm_update_gsm_state: 10: Not handling for B2BCNF
17:00:09     INFO -  207319040[14333f660]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 10  : fsm_get_fcb_by_call_id_and_type    : fcb= 0x141c23e20
17:00:09     INFO -  207319040[14333f660]: [GSM Task|fsm_sm] fsmxfr.c:501: SIPCC-FSM: fsmxfr_cleanup: Entered.
17:00:09     INFO -  207319040[14333f660]: [GSM Task|fsm_sm] fsmxfr.c:451: SIPCC-FSM: fsmxfr_remove_fcb: Entered.
17:00:09     INFO -  207319040[14333f660]: [GSM Task|def] fsm.c:346: SIPCC-FSM: 0/10, fsm_change_state: XFR: IDLE -> IDLE
17:00:09     INFO -  207319040[14333f660]: [GSM Task|def] dcsm.c:337: SIPCC-DCSM: dcsm_update_gsm_state: 10: Not handling for XFR
17:00:09     INFO -  207319040[14333f660]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 10  : fsm_get_fcb_by_call_id_and_type    : fcb= 0x0
17:00:09     INFO -  207319040[14333f660]: [GSM Task|def] ui.c:795: SIPCC-UI_API: ui_mnc_reached: line 6: Max number of calls reached =0
17:00:09     INFO -  147308544[11b589e50]: [CCAPP Task|def] ccprovider.c:1502: SIPCC-SIP_CC_PROV: ccappUpdateSessionData: Call_STATE:. state=1, attr=0, cause=5, instance=0
17:00:09     INFO -  147308544[11b589e50]: [CCAPP Task|CC_SIPCCService] CC_SIPCCService.cpp:724: onCallEvent(CCAPI_CALL_EV_STATE, 6000A, [ONHOOK|]
17:00:09     INFO -  147308544[11b589e50]: [CCAPP Task|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x17ea6ff0: count=2
17:00:09     INFO -  147308544[11b589e50]: [CCAPP Task|def] ccapi.c:1423: SIPCC-CC_API: 7/11, cc_int_onhook: UI -> GSM: ONHOOK
17:00:09     INFO -  147308544[11b589e50]: [CCAPP Task|CC_SIPCCService] CC_SIPCCService.cpp:691: onLineEvent(CCAPI_LINE_EV_CAPSET_CHANGED, 6, [453053496|OOS]
17:00:09     INFO -  207319040[14333f660]: [GSM Task|def] dcsm.c:532: SIPCC-DCSM: dcsm_process_event: DCSM 11  :(DCSM_READY:ONHOOK )
17:00:09     INFO -  207319040[14333f660]: [GSM Task|fsm_sm] sm.c:46: SIPCC-FSM: sm_process_event: DEF 11  : 0x103201220: sm entry: (IDLE:ONHOOK)
17:00:09     INFO -  207319040[14333f660]: [GSM Task|fsm_sm] fsmdef.c:2206: SIPCC-FSM: default - ignoring.
17:00:09     INFO -  207319040[14333f660]: [GSM Task|def] sm.c:65: SIPCC-GSM: 7/11, sm_process_event: DEF   :(IDLE:ONHOOK )

Jason Smith [:jsmith]

Comment 1

•

11 years ago

FWIW, this means we timed out on our crash test does a PC handshake, reloads the page, repeats again a few more times, and finishes only after 5 executions take place.

Comment hidden (Legacy TBPL/Treeherder Robot)

RyanVM
https://tbpl.mozilla.org/php/getParsedLog.php?id=20911199&tree=Mozilla-Inbound
Rev4 MacOSX Lion 10.7 mozilla-inbound debug test crashtest on 2013-03-20 19:12:45
slave: talos-r4-lion-028

19:22:15     INFO -  REFTEST TEST-UNEXPECTED-FAIL | file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html | load failed: timed out waiting for reftest-wait to be removed

Comment hidden (Legacy TBPL/Treeherder Robot)

philor
https://tbpl.mozilla.org/php/getParsedLog.php?id=20911199&tree=Mozilla-Inbound
Rev4 MacOSX Lion 10.7 mozilla-inbound debug test crashtest on 2013-03-20 19:12:45
slave: talos-r4-lion-028

19:22:15     INFO -  REFTEST TEST-UNEXPECTED-FAIL | file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html | load failed: timed out waiting for reftest-wait to be removed

Jason Smith [:jsmith]

Comment 4

•

11 years ago

Hmm...this definitely has to be a recent regression.

Keywords: regression

Whiteboard: [WebRTC] → [WebRTC][blocking-webrtc+]

Comment hidden (Legacy TBPL/Treeherder Robot)

philor
https://tbpl.mozilla.org/php/getParsedLog.php?id=20963810&tree=Services-Central
Rev4 MacOSX Lion 10.7 services-central debug test crashtest on 2013-03-21 22:10:25
slave: talos-r4-lion-024

22:19:45     INFO -  REFTEST TEST-UNEXPECTED-FAIL | file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html | load failed: timed out waiting for reftest-wait to be removed

Comment hidden (Legacy TBPL/Treeherder Robot)

edmorley
https://tbpl.mozilla.org/php/getParsedLog.php?id=20995877&tree=Mozilla-Inbound
Rev4 MacOSX Lion 10.7 mozilla-inbound debug test crashtest on 2013-03-22 16:21:04
slave: talos-r4-lion-033

16:31:28     INFO -  REFTEST TEST-UNEXPECTED-FAIL | file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html | load failed: timed out waiting for reftest-wait to be removed

Comment hidden (Legacy TBPL/Treeherder Robot)

jesup
https://tbpl.mozilla.org/php/getParsedLog.php?id=21000145&tree=Try
Rev4 MacOSX Snow Leopard 10.6 try debug test crashtest on 2013-03-22 18:35:06
slave: talos-r4-snow-045

18:43:58     INFO -  REFTEST TEST-UNEXPECTED-FAIL | file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html | load failed: timed out waiting for reftest-wait to be removed

Comment hidden (Legacy TBPL/Treeherder Robot)

jesup
https://tbpl.mozilla.org/php/getParsedLog.php?id=21002289&tree=Try
Rev4 MacOSX Snow Leopard 10.6 try debug test crashtest on 2013-03-22 19:53:21
slave: talos-r4-snow-016

20:02:20     INFO -  REFTEST TEST-UNEXPECTED-FAIL | file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html | load failed: timed out waiting for reftest-wait to be removed

Comment hidden (Legacy TBPL/Treeherder Robot)

jesup
https://tbpl.mozilla.org/php/getParsedLog.php?id=21003331&tree=Try
Rev4 MacOSX Lion 10.7 try debug test crashtest on 2013-03-22 20:27:02
slave: talos-r4-lion-066

20:35:58     INFO -  REFTEST TEST-UNEXPECTED-FAIL | file:///Users/cltbld/talos-slave/test/build/tests/reftest/tests/dom/media/tests/crashtests/812785.html | load failed: timed out waiting for reftest-wait to be removed

Randell Jesup [:jesup] (needinfo me)

Comment 10

•

11 years ago

Ok, it appears that in the 'bad' case here, we never got a success callback from SetLocalDescription() (or error callback).  I'll attach some differences from the bad log and a good log from the same changeset.

Assigning to jib, but if anyone else (adam, ethan, etc) wants to look at it, it might be useful. This might be related to some other mochitest failed-to-exit bugs.  Ethan, you might want to at least eyeball the differences leading up to the failure; there's a lot of sipcc debugs in there that may mean more to you than others.

Assignee: nobody → jib

Priority: -- → P2

Randell Jesup [:jesup] (needinfo me)

Comment 11

•

11 years ago

Attached file log snippets showing difference between good and bad runs — Details

Randell Jesup [:jesup] (needinfo me)

Comment 12

•

11 years ago

This bug hasn't been seen since the 22nd, and only was seen from the 20th to 22nd.  Since then, on the 25th we landed a major orange fix (DISPATCH_SYNC removal) that might well be related (it could cause lots of odd/impossible JS results due to re-entrancy and was a timing bug).

Closing as works-for-me unless/until it shows up again.

Status: NEW → RESOLVED

Closed: 11 years ago

Resolution: --- → WORKSFORME

Comment hidden (Legacy TBPL/Treeherder Robot)

log: https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=8028272
repository: mozilla-inbound
start_time: 2015-03-24T20:17:44
who: dbaron[at]dbaron[dot]org
machine: tst-linux64-spot-126
buildname: b2g_emulator_vm mozilla-inbound opt test crashtest-1
revision: cd1ef82e74a4

REFTEST TEST-UNEXPECTED-FAIL | http://10.0.2.2:8888/tests/dom/media/tests/crashtests/812785.html | load failed: timed out waiting for reftest-wait to be removed
Return code: 1

Bugzilla

Quick Search

Intermittent dom/media/tests/crashtests/812785.html | load failed: timed out waiting for reftest-wait to be removed

Categories

(Core :: WebRTC: Audio/Video, defect, P2)

Tracking

()

People

(Reporter: RyanVM, Assigned: jib)

References

Details

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

Crash Data

Security

(public)

User Story

Attachments

(1 file)

Description

Comment 1

Comment 2

Comment 3

Comment 4

Comment 5

Comment 6

Comment 7

Comment 8

Comment 9

Comment 10

Comment 11

Comment 12

Comment 13

Attachment

General

Description

File Name

Content Type