Intermittent browser_480148.js or Shutdown | Exited with code -6 during test run ("firefox-bin(418,0x7fff75efd960) malloc: *** error for object 0x12149eb48: incorrect checksum for freed object - object was probably modified after being freed.")

RESOLVED INCOMPLETE

Status

()

Core
Widget: Cocoa
--
critical
RESOLVED INCOMPLETE
5 years ago
4 years ago

People

(Reporter: emorley, Unassigned)

Tracking

(4 keywords)

Trunk
x86_64
Mac OS X
crash, csectype-uaf, intermittent-failure, sec-high
Points:
---

Firefox Tracking Flags

(Not tracked)

Details

(Whiteboard: [no traction])

(Reporter)

Description

5 years ago
Rev4 MacOSX Lion 10.7 mozilla-inbound debug test mochitest-browser-chrome on 2012-12-20 14:29:33 PST for push 0e08a2cc3b07

slave: talos-r4-lion-025

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

{
TEST-INFO | chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_480148.js | Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.com/?t=8" line: 0}]
WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file ../../../../content/base/src/nsContentUtils.cpp, line 2954
WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111: file ../../../../toolkit/components/mediasniffer/nsMediaSniffer.cpp, line 81
++DOCSHELL 0x16ec6f1e0 == 211 [id = 1483]
++DOMWINDOW == 513 (0x180653228) [serial = 3906] [outer = 0x0]
++DOCSHELL 0x1806a6d70 == 212 [id = 1484]
++DOMWINDOW == 514 (0x18064df88) [serial = 3907] [outer = 0x0]
firefox-bin(418,0x7fff75efd960) malloc: *** error for object 0x12149eb48: incorrect checksum for freed object - object was probably modified after being freed.
*** set a breakpoint in malloc_error_break to debug
TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_480148.js | Exited with code -6 during test run
}
Comment hidden (Treeherder Robot)
https://tbpl.mozilla.org/php/getParsedLog.php?id=19758201&tree=Mozilla-Inbound
Component: Session Restore → Widget: Cocoa
Product: Firefox → Core
Summary: Intermittent sessionstore/test/browser_480148.js | Exited with code -6 during test run ("firefox-bin(418,0x7fff75efd960) malloc: *** error for object 0x12149eb48: incorrect checksum for freed object - object was probably modified after being freed.") → Intermittent browser_480148.js or Shutdown | Exited with code -6 during test run ("firefox-bin(418,0x7fff75efd960) malloc: *** error for object 0x12149eb48: incorrect checksum for freed object - object was probably modified after being freed.")
"object was probably modified after being freed" sounds like possibly sec-critical.
Group: core-security
A bug that only triggers on TBPL every four months probably isn't going to go anywhere.  It does sound scary.  The next time it happens, it would be nice to get a stack pasted into the bug.  The existing logs here are gone.
Since we're grasping for info here let's open it up to get as much information we can. We can always make it a security bug later when we get some actionable information.
Group: core-security
This could just be random memory errors.
(Reporter)

Comment 7

5 years ago
(In reply to Andrew McCreight [:mccr8] from comment #4)
> A bug that only triggers on TBPL every four months probably isn't going to
> go anywhere.  It does sound scary.  The next time it happens, it would be
> nice to get a stack pasted into the bug.  The existing logs here are gone.

There wasn't a stack iirc. (I would normally paste it otherwise) :-)
Should bug 867196 be opened then too?
That one is still quite new.
https://tbpl.mozilla.org/php/getParsedLog.php?id=23060073&tree=Mozilla-Beta

(sorry, but you asked for it)

18:35:45     INFO -  16091 INFO SimpleTest FINISHED
18:35:45     INFO -  ++DOMWINDOW == 59 (0x15086bdf8) [serial = 10635] [outer = 0x12c263238]
18:35:45     INFO -  JavaScript strict warning: resource://services-common/log4moz.js, line 396: reference to undefined property message.level
18:35:45     INFO -  WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file ../../../../content/base/src/nsContentUtils.cpp, line 3076
18:35:45     INFO -  WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file ../../../../content/xbl/src/nsXBLProtoImplMethod.cpp, line 309
18:35:45     INFO -  WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file ../../../../content/base/src/nsContentUtils.cpp, line 3076
18:35:45     INFO -  WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file ../../../../content/xbl/src/nsXBLProtoImplMethod.cpp, line 309
18:35:45     INFO -  WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file ../../../../content/base/src/nsContentUtils.cpp, line 3076
18:35:45     INFO -  WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file ../../../../content/xbl/src/nsXBLProtoImplMethod.cpp, line 309
18:35:45     INFO -  WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file ../../../../content/base/src/nsContentUtils.cpp, line 3076
18:35:45     INFO -  WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file ../../../../content/xbl/src/nsXBLProtoImplMethod.cpp, line 309
18:35:45     INFO -  WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file ../../../../content/base/src/nsContentUtils.cpp, line 3076
18:35:45     INFO -  WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file ../../../../content/xbl/src/nsXBLProtoImplMethod.cpp, line 309
18:35:45     INFO -  --DOCSHELL 0x122d061b0 == 9 [id = 3]
18:35:45     INFO -  WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file ../../../../content/base/src/nsContentUtils.cpp, line 3076
18:35:45     INFO -  WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file ../../../../content/xbl/src/nsXBLProtoImplMethod.cpp, line 309
18:35:45     INFO -  WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file ../../../../content/base/src/nsContentUtils.cpp, line 3076
18:35:45     INFO -  WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file ../../../../content/xbl/src/nsXBLProtoImplMethod.cpp, line 309
18:35:45     INFO -  WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file ../../../../content/base/src/nsContentUtils.cpp, line 3076
18:35:45     INFO -  WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file ../../../../content/xbl/src/nsXBLProtoImplMethod.cpp, line 309
18:35:45     INFO -  WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file ../../../../content/base/src/nsContentUtils.cpp, line 3076
18:35:45     INFO -  WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file ../../../../content/xbl/src/nsXBLProtoImplMethod.cpp, line 309
18:35:45     INFO -  WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x8000FFFF: file ../../../../content/base/src/nsContentUtils.cpp, line 3076
18:35:45     INFO -  WARNING: NS_ENSURE_TRUE(pusher.Push(aBoundElement)) failed: file ../../../../content/xbl/src/nsXBLProtoImplMethod.cpp, line 309
18:35:45     INFO -  --DOCSHELL 0x10829b760 == 8 [id = 1]
18:35:46     INFO -  --DOMWINDOW == 58 (0x14f5e5bb8) [serial = 10628] [outer = 0x150813ec8] [url = about:blank]
18:35:46     INFO -  --DOMWINDOW == 57 (0x150813ec8) [serial = 10624] [outer = 0x0] [url = about:blank]
18:35:46     INFO -  --DOMWINDOW == 56 (0x151b1d648) [serial = 10627] [outer = 0x15088c198] [url = about:blank]
18:35:46     INFO -  --DOMWINDOW == 55 (0x15088c198) [serial = 10623] [outer = 0x0] [url = about:blank]
18:35:46     INFO -  --DOMWINDOW == 54 (0x12973e938) [serial = 10] [outer = 0x122d095c8] [url = about:blank]
18:35:46     INFO -  --DOMWINDOW == 53 (0x122d095c8) [serial = 6] [outer = 0x0] [url = about:blank]
18:35:46     INFO -  --DOMWINDOW == 52 (0x15086bdf8) [serial = 10635] [outer = 0x12c263238] [url = about:blank]
18:35:46     INFO -  --DOMWINDOW == 51 (0x15355c5e8) [serial = 10634] [outer = 0x12c263238] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
18:35:46     INFO -  --DOMWINDOW == 50 (0x150d8d148) [serial = 10448] [outer = 0x12c263238] [url = http://mochi.test:8888/tests/dom/tests/mochitest/pointerlock/test_pointerlock-api.html]
18:35:46     INFO -  --DOMWINDOW == 49 (0x12c263238) [serial = 13] [outer = 0x0] [url = about:blank]
18:35:46     INFO -  --DOMWINDOW == 48 (0x12973a528) [serial = 9] [outer = 0x122d06e58] [url = about:blank]
18:35:46     INFO -  --DOMWINDOW == 47 (0x122d06e58) [serial = 5] [outer = 0x0] [url = about:blank]
18:35:46     INFO -  ###!!! ASSERTION: Widget destroyed while running modal!: '!mModal', file ../../../widget/cocoa/nsCocoaWindow.mm, line 171
18:35:46     INFO -  nsCocoaWindow::~nsCocoaWindow() [obj-firefox/dist/include/mozilla/mozalloc.h:225]
18:35:46     INFO -  nsBaseWidget::Release() [widget/xpwidgets/nsBaseWidget.cpp:67]
18:35:46     INFO -  nsCocoaWindow::Release() [widget/cocoa/nsCocoaWindow.mm:83]
18:35:46     INFO -  nsBaseWidget::~nsBaseWidget() [widget/xpwidgets/nsBaseWidget.cpp:178]
18:35:46     INFO -  nsCocoaWindow::~nsCocoaWindow() [widget/cocoa/nsCocoaWindow.mm:178]
18:35:46     INFO -  nsCocoaWindow::~nsCocoaWindow() [obj-firefox/dist/include/mozilla/mozalloc.h:225]
18:35:46     INFO -  nsBaseWidget::Release() [widget/xpwidgets/nsBaseWidget.cpp:67]
18:35:46     INFO -  nsCocoaWindow::Release() [widget/cocoa/nsCocoaWindow.mm:83]
18:35:46     INFO -  nsBaseWidget::~nsBaseWidget() [widget/xpwidgets/nsBaseWidget.cpp:178]
18:35:46     INFO -  nsCocoaWindow::~nsCocoaWindow() [widget/cocoa/nsCocoaWindow.mm:178]
18:35:46     INFO -  nsCocoaWindow::~nsCocoaWindow() [obj-firefox/dist/include/mozilla/mozalloc.h:225]
18:35:46     INFO -  nsBaseWidget::Release() [widget/xpwidgets/nsBaseWidget.cpp:67]
18:35:46     INFO -  nsCocoaWindow::Release() [widget/cocoa/nsCocoaWindow.mm:83]
18:35:46     INFO -  nsDeviceContext::Release() [obj-firefox/dist/include/mozilla/mozalloc.h:225]
18:35:46     INFO -  nsPresContext::~nsPresContext() [obj-firefox/dist/include/nsCOMPtr.h:492]
18:35:46     INFO -  nsRootPresContext::~nsRootPresContext() [obj-firefox/dist/include/mozilla/mozalloc.h:225]
18:35:46     INFO -  nsPresContext::Release() [layout/base/nsPresContext.cpp:323]
18:35:46     INFO -  nsDOMEvent::~nsDOMEvent() [dom/base/nsWrapperCache.h:70]
18:35:46     INFO -  nsDOMEvent::~nsDOMEvent() [obj-firefox/dist/include/mozilla/mozalloc.h:225]
18:35:46     INFO -  nsDOMEvent::Release() [content/events/src/nsDOMEvent.cpp:123]
18:35:46     INFO -  XPCJSRuntime::GCCallback(JSRuntime*, JSGCStatus) [obj-firefox/dist/include/nsTArray.h:353]
18:35:46     INFO -  Collect [js/src/gc/Statistics.h:205]
18:35:46     INFO -  nsXREDirProvider::DoShutdown() [obj-firefox/dist/include/nsCOMPtr.h:783]
18:35:46     INFO -  ScopedXPCOMStartup::~ScopedXPCOMStartup() [toolkit/xre/nsAppRunner.cpp:1118]
18:35:46     INFO -  XREMain::XRE_main(int, char**, nsXREAppData const*) [obj-firefox/dist/include/mozilla/mozalloc.h:225]
18:35:46     INFO -  XRE_main [toolkit/xre/nsAppRunner.cpp:4140]
18:35:46     INFO -  main [browser/app/nsBrowserApp.cpp:273]
18:35:46     INFO -  --DOCSHELL 0x128b4ac80 == 7 [id = 5]
18:35:46     INFO -  --DOCSHELL 0x15076a0f0 == 6 [id = 3318]
18:35:46     INFO -  --DOCSHELL 0x122d08810 == 5 [id = 4]
18:35:46     INFO -  --DOCSHELL 0x10aec0120 == 4 [id = 2]
18:35:46     INFO -  --DOCSHELL 0x12c2625f0 == 3 [id = 6]
18:35:46     INFO -  --DOCSHELL 0x14f5d2850 == 2 [id = 3320]
18:35:46     INFO -  --DOCSHELL 0x1508df8e0 == 1 [id = 3322]
18:35:46     INFO -  --DOCSHELL 0x151b54450 == 0 [id = 3321]
18:35:46     INFO -  1892252864[100205a80]: [main|CallControlManager] CallControlManagerImpl.cpp:56: destroy()
18:35:46     INFO -  1892252864[100205a80]: [main|CallControlManager] CallControlManagerImpl.cpp:229: disconnect()
18:35:46     INFO -  1892252864[100205a80]: [main|CC_SIPCCService] CC_SIPCCService.cpp:563: endAllActiveCalls(): 4 calls to be ended.
18:35:46     INFO -  1892252864[100205a80]: [main|CC_SIPCCCallInfo] CC_SIPCCCallInfo.cpp:555: State 34 not handled in generateCapabilities()
18:35:46     INFO -  1892252864[100205a80]: [main|CC_SIPCCCallInfo] CC_SIPCCCallInfo.cpp:555: State 34 not handled in generateCapabilities()
18:35:46     INFO -  1892252864[100205a80]: [main|CC_SIPCCCallInfo] CC_SIPCCCallInfo.cpp:555: State 22 not handled in generateCapabilities()
18:35:46     INFO -  1892252864[100205a80]: [main|CC_SIPCCCallInfo] CC_SIPCCCallInfo.cpp:555: State 22 not handled in generateCapabilities()
18:35:46     INFO -  1892252864[100205a80]: [main|CC_SIPCCCallInfo] CC_SIPCCCallInfo.cpp:555: State 30 not handled in generateCapabilities()
18:35:46     INFO -  1892252864[100205a80]: [main|CC_SIPCCCallInfo] CC_SIPCCCallInfo.cpp:555: State 30 not handled in generateCapabilities()
18:35:46     INFO -  1892252864[100205a80]: [main|CC_SIPCCCallInfo] CC_SIPCCCallInfo.cpp:555: State 31 not handled in generateCapabilities()
18:35:46     INFO -  1892252864[100205a80]: [main|CC_SIPCCCallInfo] CC_SIPCCCallInfo.cpp:555: State 31 not handled in generateCapabilities()
18:35:46     INFO -  1892252864[100205a80]: [main|ccapp] ccapi_service.c:104: CCAPI_Service_stop - calling registration stop
18:35:46     INFO -  1892252864[100205a80]: [main|def] cc_device_manager.c:232: registration_processEvent:  Event EV_CC_STOP, current State MGMT_STATE_REGISTERED
18:35:46     INFO -  1892252864[100205a80]: [main|def] cc_device_manager.c:200: CC_device_manager:  processInsToOoS
18:35:46     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: cprSendMessage: Msg not sent to undefined queue: invalid input
18:35:46     INFO -  1892252864[100205a80]: [main|def] cc_device_manager.c:193: setState: new registration state=  MGMT_STATE_STOP_AWAIT_SHUTDOWN_ACK
18:35:46     INFO -  1892252864[100205a80]: [main|ccapp] ccapi_service.c:65: CCAPI_Service_destroy - calling CC_Service_destroy
18:35:46     INFO -  1892252864[100205a80]: [main|def] cc_device_manager.c:232: registration_processEvent:  Event EV_CC_DESTROY, current State MGMT_STATE_STOP_AWAIT_SHUTDOWN_ACK
18:35:46     INFO -  1892252864[100205a80]: [main|def] cc_device_manager.c:193: setState: new registration state=  MGMT_STATE_IDLE
18:35:46     INFO -  1892252864[100205a80]: [main|def] init.c:567: SIPCC-SIP_CC_INIT: ccUnload: ccUnload called..
18:35:46     INFO -  1892252864[100205a80]: [main|def] init.c:479: SIPCC-SIP_CC_INIT: send_task_unload_msg: send Unload message to SIP task ..
18:35:47     INFO -  1892252864[100205a80]: [main|def] init.c:479: SIPCC-SIP_CC_INIT: send_task_unload_msg: send Unload message to GSM task ..
18:35:47     INFO -  1892252864[100205a80]: [main|def] init.c:479: SIPCC-SIP_CC_INIT: send_task_unload_msg: send Unload message to CCApp task ..
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: send_task_unload_msg:  send UNLOAD msg to CCapp thread good
18:35:47     INFO -  1229664256[12ca24b30]: [GSM Task|def] gsm.c:601: SIPCC-SIP_CC_INIT: destroy_gsm_thread: Unloading GSM and destroying GSM thread
18:35:47     INFO -  1229664256[12ca24b30]: [GSM Task|def] fsmcac.c:297: SIPCC-CAC: fsm_cac_clear_list: Clear all pending CAC dat.
18:35:47     INFO -  1229664256[12ca24b30]: [GSM Task|cpr] cpr_darwin_stdio.c:124: cprGetMutex - NULL pointer passed in.
18:35:47     INFO -  1229664256[12ca24b30]: [GSM Task|cpr] cpr_darwin_stdio.c:124: cprReleaseMutex - NULL pointer passed in.
18:35:47     INFO -  1229664256[12ca24b30]: [GSM Task|cpr] cpr_darwin_stdio.c:152: cprDestroyThread: Destroying Thread 4
18:35:47     INFO -  759394304[12ca7f450]: [CCAPP Task|tnp] ccprovider.c:2107: SIPCC-SIP_CC_INIT: destroy_ccapp_thread: Unloading ccapp and destroying ccapp thread
18:35:47     INFO -  759394304[12ca7f450]: [CCAPP Task|cpr] cpr_darwin_stdio.c:152: cprDestroyThread: Destroying Thread 1
18:35:47     INFO -  779522048[12ca4d2a0]: [SIPStack task|def] ccsip_core.c:7424: SIPCC-SIP_CTRL: sip_shutdown_phase1: In sip_shutdown_phase1 (1)
18:35:47     INFO -  779522048[12ca4d2a0]: [SIPStack task|cpr] cpr_darwin_stdio.c:124: cprCancelTimer - NULL pointer passed in.
18:35:47     INFO -  779522048[12ca4d2a0]: [SIPStack task|def] ccsip_core.c:7424: SIPCC-SIP_CTRL: sip_shutdown_phase1: In sip_shutdown_phase1 (1)
18:35:47     INFO -  779522048[12ca4d2a0]: [SIPStack task|cpr] cpr_darwin_stdio.c:124: cprCancelTimer - NULL pointer passed in.
18:35:47     INFO -  779522048[12ca4d2a0]: [SIPStack task|def] ccsip_task.c:3030: SIPCC-SIP_CC_INIT: destroy_sip_thread: Unloading SIP and destroying sip thread
18:35:47     INFO -  779522048[12ca4d2a0]: [SIPStack task|cpr] cpr_darwin_stdio.c:152: cprDestroyThread: Destroying Thread 2
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4d22c6d0: count=2
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a426620: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a41e440: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a41bfe0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a419fa0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a419da0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a4187c0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a4181b0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a417fb0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a1e1d90: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a1e1b90: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a1e1990: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a1e0f90: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a1dfb80: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a1dea50: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a1dda50: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a108170: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x4a107c70: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x49ab4ca0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x49aac800: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x49aa9cf0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x49aa5370: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x49aa4d60: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x49aa4b60: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x49a757a0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x498398a0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x498396a0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x49832a50: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x498065b0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x49802ae0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2e5263b0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2cadf2b0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2cadf040: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2cadedf0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2cadd050: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2cad4800: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2cad44c0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2cac49f0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2cac1570: count=2
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2caa09e0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2ca962c0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2ca8b5f0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2ca7ead0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2ca7e8d0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2ca7a0a0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2ca6ff60: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2ca6fd60: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2ca5d4b0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2ca544a0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2ca4d5f0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2ca46de0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2c2db800: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2c2a5500: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2c279980: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2aa82850: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2aa57ef0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2aa23a00: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2a2d04a0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2a2cf410: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2a2b3b50: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2a292e80: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2a20c370: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2a20c050: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2a20be10: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2a204fb0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x28b9f6f0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x22afa760: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x22af69a0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x22a12c60: count=2
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2270a530: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x22704300: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2245a280: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x223f4350: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x223cb7e0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x223bb810: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x223b1c50: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x223a0dd0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x2237c1f0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x22372570: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x22372370: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x22372170: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x22371f70: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x22364130: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x223626f0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x22354150: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x22269f20: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x22216240: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0xbbbb180: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0xbbb30a0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0xbbb2b70: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0xbbb13c0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0xbbab060: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0xbb82af0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0xbb36600: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0xbb33b90: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0xae3abe0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x85d1250: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x85c2a80: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x8596260: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x85901c0: count=2
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x858db80: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x858c600: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x858bd60: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x85786b0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x8572340: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x8559a60: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x854cf60: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x8523690: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x851d0a0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x851b420: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x851a860: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x85192f0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x85188a0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x8514870: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x850d790: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x850b590: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x8250460: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x824f970: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x8248050: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x82431c0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x823df80: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|cpr] cpr_darwin_stdio.c:124: Strlib Error: strlib_is_tring passed invalid string
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x28b390: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x1f2ad0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x1ebf70: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x1ebd50: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x1cf1b0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|def] ccapi_call.c:56: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0x1b04b0: count=1
18:35:47     INFO -  1892252864[100205a80]: [main|CallControlManager] CallControlManagerImpl.cpp:50: ~CallControlManagerImpl()
18:35:47     INFO -  1892252864[100205a80]: [main|CallControlManager] CallControlManagerImpl.cpp:56: destroy()
18:35:47     INFO -  1892252864[100205a80]: [main|CallControlManager] CallControlManagerImpl.cpp:229: disconnect()
18:35:47     INFO -  WARNING: NS_ENSURE_TRUE(mDB) failed: file ../../../netwerk/cache/nsDiskCacheDeviceSQL.cpp, line 1389
18:35:47     INFO -  WARNING: nsExceptionService ignoring thread destruction after shutdown: file ../../../xpcom/base/nsExceptionService.cpp, line 167
18:35:47     INFO -  WARNING: nsAppShell::Exit() called redundantly: file ../../../widget/cocoa/nsAppShell.mm, line 757
18:35:48     INFO -  --DOMWINDOW == 46 (0x151bb76d8) [serial = 10581] [outer = 0x0] [url = chrome://browser/content/browser.xul]
18:35:48     INFO -  --DOMWINDOW == 45 (0x151bd7578) [serial = 10615] [outer = 0x0] [url = http://mochi.test:8888/tests/dom/tests/mochitest/pointerlock/file_withoutDOM.html]
18:35:48     INFO -  --DOMWINDOW == 44 (0x1506a6ee8) [serial = 10585] [outer = 0x0] [url = http://mochi.test:8888/tests/dom/tests/mochitest/pointerlock/file_retargetMouseEvents.html]
18:35:48     INFO -  --DOMWINDOW == 43 (0x1506a4698) [serial = 10595] [outer = 0x0] [url = http://mochi.test:8888/tests/dom/tests/mochitest/pointerlock/file_suppressSomeMouseEvents.html]
18:35:48     INFO -  --DOMWINDOW == 42 (0x14ce42768) [serial = 1873] [outer = 0x0] [url = chrome://browser/content/browser.xul]
18:35:48     INFO -  --DOMWINDOW == 41 (0x151b44c98) [serial = 10591] [outer = 0x0] [url = chrome://browser/content/browser.xul]
18:35:48     INFO -  --DOMWINDOW == 40 (0x14cfb6b38) [serial = 10605] [outer = 0x0] [url = http://mochi.test:8888/tests/dom/tests/mochitest/pointerlock/file_targetOutOfFocus.html]
18:35:48     INFO -  --DOMWINDOW == 39 (0x149a91c48) [serial = 1837] [outer = 0x0] [url = chrome://browser/content/browser.xul]
18:35:48     INFO -  --DOMWINDOW == 38 (0x150647418) [serial = 10555] [outer = 0x0] [url = http://mochi.test:8888/tests/dom/tests/mochitest/pointerlock/file_pointerlockerror.html]
18:35:48     INFO -  --DOMWINDOW == 37 (0x14c541808) [serial = 1884] [outer = 0x0] [url = chrome://browser/content/browser.xul]
18:35:48     INFO -  --DOMWINDOW == 36 (0x151b7f418) [serial = 10601] [outer = 0x0] [url = chrome://browser/content/browser.xul]
18:35:48     INFO -  --DOMWINDOW == 35 (0x1531d5e58) [serial = 10611] [outer = 0x0] [url = chrome://browser/content/browser.xul]
18:35:48     INFO -  --DOMWINDOW == 34 (0x14e254298) [serial = 10625] [outer = 0x0] [url = http://mochi.test:8888/tests/dom/tests/mochitest/pointerlock/file_allowPointerLockSandboxFlag.html]
18:35:48     INFO -  --DOMWINDOW == 33 (0x14a4cadb8) [serial = 1778] [outer = 0x0] [url = chrome://browser/content/browser.xul]
18:35:48     INFO -  --DOMWINDOW == 32 (0x100214378) [serial = 10631] [outer = 0x0] [url = http://mochi.test:8888/tests/dom/tests/mochitest/pointerlock/iframe_differentDOM.html]
18:35:48     INFO -  --DOMWINDOW == 31 (0x1512b2948) [serial = 1701] [outer = 0x0] [url = chrome://browser/content/browser.xul]
18:35:48     INFO -  --DOMWINDOW == 30 (0x10829c818) [serial = 1] [outer = 0x0] [url = chrome://browser/content/hiddenWindow.xul]
18:35:48     INFO -  --DOMWINDOW == 29 (0x152ceb328) [serial = 1742] [outer = 0x0] [url = chrome://browser/content/browser.xul]
18:35:48     INFO -  --DOMWINDOW == 28 (0x15069d7c8) [serial = 10621] [outer = 0x0] [url = chrome://browser/content/browser.xul]
18:35:48     INFO -  --DOMWINDOW == 27 (0x14f1b97f8) [serial = 1711] [outer = 0x0] [url = chrome://browser/content/browser.xul]
18:35:48     INFO -  --DOMWINDOW == 26 (0x14ce3ea18) [serial = 1965] [outer = 0x0] [url = chrome://browser/content/browser.xul]
18:35:48     INFO -  --DOMWINDOW == 25 (0x128b4ba28) [serial = 7] [outer = 0x0] [url = http://mochi.test:8888/tests/?autorun=1&closeWhenDone=1&consoleLevel=INFO&totalChunks=5&thisChunk=3&chunkByDir=4&hideResultsTable=1]
18:35:48     INFO -  --DOMWINDOW == 24 (0x10aebf1f8) [serial = 3] [outer = 0x0] [url = chrome://browser/content/browser.xul]
18:35:48     INFO -  --DOMWINDOW == 23 (0x150497a78) [serial = 10630] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  --DOMWINDOW == 22 (0x15052f878) [serial = 10633] [outer = 0x0] [url = http://mochi.test:8888/tests/dom/tests/mochitest/pointerlock/iframe_differentDOM.html]
18:35:48     INFO -  --DOMWINDOW == 21 (0x15081ba38) [serial = 10632] [outer = 0x0] [url = http://mochi.test:8888/tests/dom/tests/mochitest/pointerlock/iframe_differentDOM.html]
18:35:48     INFO -  --DOMWINDOW == 20 (0x14cfba758) [serial = 10622] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  WARNING: not an nsIRDFRemoteDataSource: 'remote != nullptr', file ../../../../rdf/datasource/src/nsLocalStore.cpp, line 279
18:35:48     INFO -  --DOMWINDOW == 19 (0x150da5a88) [serial = 10620] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  --DOMWINDOW == 18 (0x150d27cb8) [serial = 10612] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  WARNING: not an nsIRDFRemoteDataSource: 'remote != nullptr', file ../../../../rdf/datasource/src/nsLocalStore.cpp, line 279
18:35:48     INFO -  --DOMWINDOW == 17 (0x150509468) [serial = 10610] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  --DOMWINDOW == 16 (0x1505bf9f8) [serial = 10602] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  WARNING: not an nsIRDFRemoteDataSource: 'remote != nullptr', file ../../../../rdf/datasource/src/nsLocalStore.cpp, line 279
18:35:48     INFO -  --DOMWINDOW == 15 (0x14f4b4578) [serial = 10600] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  --DOMWINDOW == 14 (0x151b55f58) [serial = 10592] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  WARNING: not an nsIRDFRemoteDataSource: 'remote != nullptr', file ../../../../rdf/datasource/src/nsLocalStore.cpp, line 279
18:35:48     INFO -  --DOMWINDOW == 13 (0x15065f4d8) [serial = 10590] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  --DOMWINDOW == 12 (0x15350ece8) [serial = 10582] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  WARNING: not an nsIRDFRemoteDataSource: 'remote != nullptr', file ../../../../rdf/datasource/src/nsLocalStore.cpp, line 279
18:35:48     INFO -  --DOMWINDOW == 11 (0x14cfcdc08) [serial = 10560] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  --DOMWINDOW == 10 (0x152c40828) [serial = 1966] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  WARNING: not an nsIRDFRemoteDataSource: 'remote != nullptr', file ../../../../rdf/datasource/src/nsLocalStore.cpp, line 279
18:35:48     INFO -  --DOMWINDOW == 9 (0x12e045408) [serial = 1885] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  WARNING: not an nsIRDFRemoteDataSource: 'remote != nullptr', file ../../../../rdf/datasource/src/nsLocalStore.cpp, line 279
18:35:48     INFO -  --DOMWINDOW == 8 (0x14f1cc708) [serial = 1874] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  WARNING: not an nsIRDFRemoteDataSource: 'remote != nullptr', file ../../../../rdf/datasource/src/nsLocalStore.cpp, line 279
18:35:48     INFO -  --DOMWINDOW == 7 (0x12ca2d338) [serial = 1838] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  WARNING: not an nsIRDFRemoteDataSource: 'remote != nullptr', file ../../../../rdf/datasource/src/nsLocalStore.cpp, line 279
18:35:48     INFO -  --DOMWINDOW == 6 (0x1515a2208) [serial = 1779] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  WARNING: not an nsIRDFRemoteDataSource: 'remote != nullptr', file ../../../../rdf/datasource/src/nsLocalStore.cpp, line 279
18:35:48     INFO -  --DOMWINDOW == 5 (0x152c89758) [serial = 1743] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  WARNING: not an nsIRDFRemoteDataSource: 'remote != nullptr', file ../../../../rdf/datasource/src/nsLocalStore.cpp, line 279
18:35:48     INFO -  --DOMWINDOW == 4 (0x14f0bbfe8) [serial = 1712] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  WARNING: not an nsIRDFRemoteDataSource: 'remote != nullptr', file ../../../../rdf/datasource/src/nsLocalStore.cpp, line 279
18:35:48     INFO -  --DOMWINDOW == 3 (0x1512b5178) [serial = 1702] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  WARNING: not an nsIRDFRemoteDataSource: 'remote != nullptr', file ../../../../rdf/datasource/src/nsLocalStore.cpp, line 279
18:35:48     INFO -  --DOMWINDOW == 2 (0x12aa66228) [serial = 12] [outer = 0x0] [url = http://mochi.test:8888/tests/?autorun=1&closeWhenDone=1&consoleLevel=INFO&totalChunks=5&thisChunk=3&chunkByDir=4&hideResultsTable=1]
18:35:48     INFO -  --DOMWINDOW == 1 (0x10aec5cb8) [serial = 4] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  WARNING: not an nsIRDFRemoteDataSource: 'remote != nullptr', file ../../../../rdf/datasource/src/nsLocalStore.cpp, line 279
18:35:48     INFO -  --DOMWINDOW == 0 (0x1082a5be8) [serial = 2] [outer = 0x0] [url = about:blank]
18:35:48     INFO -  WARNING: not an nsIRDFRemoteDataSource: 'remote != nullptr', file ../../../../rdf/datasource/src/nsLocalStore.cpp, line 279
18:35:48     INFO -  WARNING: NS_ENSURE_TRUE(mMainThread) failed: file ../../../xpcom/threads/nsThreadManager.cpp, line 250
18:35:48     INFO -  WARNING: NS_ENSURE_TRUE(compMgr) failed: file nsComponentManagerUtils.cpp, line 58
18:35:48     INFO -  WARNING: NS_ENSURE_TRUE(mTextInputHandler) failed: file ../../../widget/cocoa/nsChildView.mm, line 4225
18:35:49     INFO -  firefox-bin(385,0x7fff70c97cc0) malloc: *** error for object 0x12ca6c108: incorrect checksum for freed object - object was probably modified after being freed.
18:35:49     INFO -  *** set a breakpoint in malloc_error_break to debug
18:35:51  WARNING -  TEST-UNEXPECTED-FAIL | Shutdown | Exited with code -6 during test run
18:35:51     INFO -  INFO | automation.py | Application ran for: 0:20:44.589715
18:35:51     INFO -  INFO | zombiecheck | Reading PID log: /var/folders/Hs/HsDn6a9SG8idoIya6p9mtE+++TI/-Tmp-/tmp_PQCbfpidlog
18:35:52     INFO -  == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, default process 385
18:35:52     INFO -       |<----------------Class--------------->|<-----Bytes------>|<----------------Objects---------------->|<--------------References-------------->|
18:35:52     INFO -                                                Per-Inst   Leaked    Total      Rem      Mean       StdDev     Total      Rem      Mean       StdDev
18:35:52     INFO -     0 TOTAL                                          20        0 290395857        0 (23759.00 +/- 45852.53) 275431236        0 ( 8685.65 +/- 16755.52)
18:35:52     INFO -  nsTraceRefcntImpl::DumpStatistics: 1313 entries
18:35:52     INFO -  TEST-PASS | leakcheck | no leaks detected!
18:35:52     INFO -  ==> process 387 will purposefully crash
18:35:52     INFO -  TEST-INFO | leakcheck | plugin process: deliberate crash and thus no leak log
18:35:52     INFO -  ==> process 390 will purposefully crash
18:35:52     INFO -  TEST-INFO | leakcheck | plugin process: deliberate crash and thus no leak log
18:35:52     INFO -  == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, plugin process 393
18:35:52     INFO -       |<----------------Class--------------->|<-----Bytes------>|<----------------Objects---------------->|<--------------References-------------->|
18:35:52     INFO -                                                Per-Inst   Leaked    Total      Rem      Mean       StdDev     Total      Rem      Mean       StdDev
18:35:52     INFO -     0 TOTAL                                          20        0     6196        0 (   60.75 +/-    39.04)     4415        0 (  121.77 +/-    71.09)
18:35:52     INFO -  nsTraceRefcntImpl::DumpStatistics: 23 entries
18:35:52     INFO -  TEST-PASS | leakcheck | plugin process: no leaks detected!
18:35:52     INFO -  == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, plugin process 399
18:35:52     INFO -       |<----------------Class--------------->|<-----Bytes------>|<----------------Objects---------------->|<--------------References-------------->|
18:35:52     INFO -                                                Per-Inst   Leaked    Total      Rem      Mean       StdDev     Total      Rem      Mean       StdDev
18:35:52     INFO -     0 TOTAL                                          68        0      285        0 (   27.25 +/-    22.14)      278        0 (   55.65 +/-    45.84)
18:35:52     INFO -  nsTraceRefcntImpl::DumpStatistics: 20 entries
18:35:52     INFO -  TEST-PASS | leakcheck | plugin process: no leaks detected!
18:35:52     INFO -  INFO | runtests.py | Running tests: end.
18:35:53    ERROR - Return code: 250

Updated

5 years ago
Keywords: csec-uaf, sec-critical
Bug 869151 could have caused this because it stopped us from holding onto widgets for way too long.
Timothy I was suggesting assigning this to you during triage but I wonder if traction is even possible given comment 4?
Flags: needinfo?(tnikkel)
This bug predates bug 869151, so bug 869151 likely has nothing to do this with, comment 11 was probably mistaken. I don't have much idea what to do here given it's only happened twice.
Flags: needinfo?(tnikkel)
Whiteboard: [no traction]
Changing to sec-high. Waiting for it to become more of a problem.
Keywords: sec-critical → sec-high
Comment hidden (Treeherder Robot)
Bug 933226 just landed on inbound which may improve the situation for this test.
This hasn't happened in 3 months, closing.
Status: NEW → RESOLVED
Last Resolved: 4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.