824046 - 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.")

Reporter

Description

•

12 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 (Legacy TBPL/Treeherder Robot)

tanvi
https://tbpl.mozilla.org/php/getParsedLog.php?id=18276058&tree=Try
Rev4 MacOSX Snow Leopard 10.6 try opt test mochitest-browser-chrome on 2012-12-26 12:39:28
slave: talos-r4-snow-045

TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_480148.js | Exited with code 1 during test run
PROCESS-CRASH | chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_480148.js | application crashed [@ ReleaseSliceNow(unsigned int, void*)]
Thread 0 (crashed)

Ryan VanderMeulen [:RyanVM]

Comment 2

•

11 years ago

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.")

Mats Palmgren (inactive)

Comment 3

•

11 years ago

"object was probably modified after being freed" sounds like possibly sec-critical.

Group: core-security

Andrew McCreight [:mccr8]

Comment 4

•

11 years ago

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.

Daniel Veditz [:dveditz]

Comment 5

•

11 years ago

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

Andrew McCreight [:mccr8]

Comment 6

•

11 years ago

This could just be random memory errors.

Ed Morley [:emorley]

Reporter

Comment 7

•

11 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) :-)

Ryan VanderMeulen [:RyanVM]

Comment 8

•

11 years ago

Should bug 867196 be opened then too?

Andrew McCreight [:mccr8]

Comment 9

•

11 years ago

That one is still quite new.

Phil Ringnalda (:philor)

Comment 10

•

11 years ago

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

Mats Palmgren (inactive)

Updated

•

11 years ago

Keywords: csec-uaf, sec-critical

Timothy Nikkel (:tnikkel)

Comment 11

•

11 years ago

Bug 869151 could have caused this because it stopped us from holding onto widgets for way too long.

David Bolter [:davidb] (NeedInfo me for attention)

Comment 12

•

11 years ago

Timothy I was suggesting assigning this to you during triage but I wonder if traction is even possible given comment 4?

Flags: needinfo?(tnikkel)

Timothy Nikkel (:tnikkel)

Comment 13

•

11 years ago

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)

David Bolter [:davidb] (NeedInfo me for attention)

Updated

•

11 years ago

Whiteboard: [no traction]

Matt Wobensmith [:mwobensmith][:matt:]

Comment 14

•

11 years ago

Changing to sec-high. Waiting for it to become more of a problem.

Matt Wobensmith [:mwobensmith][:matt:]

Updated

•

11 years ago

Keywords: sec-critical → sec-high

Ryan VanderMeulen [:RyanVM]

Comment 15

•

11 years ago

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

Comment hidden (Legacy TBPL/Treeherder Robot)

bugs%pettay.fi
https://tbpl.mozilla.org/php/getParsedLog.php?id=25033485&tree=Try
Windows 7 32-bit try debug test mochitest-browser-chrome on 2013-07-08 13:43:59
revision: d5f17a628d68
slave: t-w732-ix-058

13:58:39     INFO -  Assertion failure: cx->iterValue.isMagic(JS_NO_ITER_VALUE), at e:/builds/moz2_slave/try-w32-d-00000000000000000000/build/js/src/vm/Interpreter.cpp:452
13:58:39  WARNING -  TEST-UNEXPECTED-FAIL | chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_480148.js | Exited with code -2147483645 during test run
13:58:46  WARNING -  PROCESS-CRASH | chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_480148.js | application crashed [@ js::Invoke(JSContext *,JS::CallArgs,js::MaybeConstruct)]
13:58:48    ERROR - Return code: 1

Ryan VanderMeulen [:RyanVM]

Comment 17

•

11 years ago

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

Ryan VanderMeulen [:RyanVM]

Comment 18

•

11 years ago

Bug 933226 just landed on inbound which may improve the situation for this test.

Andrew McCreight [:mccr8]

Comment 19

•

11 years ago

This hasn't happened in 3 months, closing.

Status: NEW → RESOLVED

Closed: 11 years ago

Resolution: --- → INCOMPLETE

Ryan VanderMeulen [:RyanVM]

Comment 20

•

11 years ago

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

Ryan VanderMeulen [:RyanVM]

Comment 21

•

10 years ago

https://tbpl.mozilla.org/php/getParsedLog.php?id=38192912&tree=Mozilla-B2g28-v1.3