Closed Bug 764030 Opened 12 years ago Closed 11 years ago

Intermittent assertion during test_TelemetryPing.js | test failed (with xpcshell return code: -1073741819) | test-ping == saved-session | false == true ("Assertion failure: !connections[i]->ConnectionReady(), at mozStorageService.cpp:852")

Categories

(Core :: Networking: Cookies, defect)

Product:
Core
Component:
Networking: Cookies
Version:
15 Branch
Type:
defect
Priority:
Not set
Severity:
critical

Tracking

()

Status:
RESOLVED WORKSFORME

People

(Reporter: emorley, Unassigned)

References

Details

(Keywords: assertion, intermittent-failure) 

Rev3 WINNT 6.1 mozilla-aurora debug test xpcshell on 2012-06-12 08:32:35 PDT for push 94e4994199c0

slave: talos-r3-w7-040

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

{
TEST-INFO | c:\talos-slave\test\build\xpcshell\tests\toolkit\components\telemetry\tests\unit\test_nsITelemetry.js | running test ...
TEST-PASS | c:\talos-slave\test\build\xpcshell\tests\toolkit\components\telemetry\tests\unit\test_nsITelemetry.js | test passed (time: 247.000ms)

== BloatView: ALL (cumulative) LEAK STATISTICS, default process 904

     |<----------------Class--------------->|<-----Bytes------>|<----------------Objects---------------->|<--------------References-------------->|
                                              Per-Inst   Leaked    Total      Rem      Mean       StdDev     Total      Rem      Mean       StdDev
   0 TOTAL                                          21       24     9890        3 (  412.92 +/-   451.23)    29306        3 ( 1400.02 +/-  1745.20)
 123 nsStringBuffer                                  8       24     2074        3 (  807.38 +/-   422.38)     9584        3 ( 3205.52 +/-  1819.28)

nsTraceRefcntImpl::DumpStatistics: 161 entries
TEST-INFO | c:\talos-slave\test\build\xpcshell\tests\toolkit\components\telemetry\tests\unit\test_TelemetryPing.js | running test ...
TEST-UNEXPECTED-FAIL | c:\talos-slave\test\build\xpcshell\tests\toolkit\components\telemetry\tests\unit\test_TelemetryPing.js | test failed (with xpcshell return code: -1073741819), see following log:
>>>>>>>
### XPCOM_MEM_LEAK_LOG defined -- logging leaks to c:\users\cltbld\appdata\local\temp\tmpicmxdq\runxpcshelltests_leaks.log

TEST-INFO | (xpcshell/head.js) | test 1 pending
WARNING: This method is lossy. Use GetCanonicalPath !: file e:/builds/moz2_slave/m-aurora-w32-dbg/build/xpcom/io/nsLocalFileWin.cpp, line 3274

TEST-INFO | (xpcshell/head.js) | test 2 pending

TEST-INFO | (xpcshell/head.js) | test 2 finished

TEST-INFO | (xpcshell/head.js) | running event loop

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 122] XPCShell == XPCShell

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 122] xpcshell@tests.mozilla.org == xpcshell@tests.mozilla.org

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 122] 1 == 1

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 122] XPCShell == XPCShell

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 122] 2007010101 == 2007010101

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 122] 2007010101 == 2007010101

TEST-UNEXPECTED-FAIL | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | test-ping == saved-session - See following stack:
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: do_throw :: line 440
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: _do_check_eq :: line 534
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: do_check_eq :: line 555
JS frame :: c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js :: checkPayloadInfo :: line 125
JS frame :: c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js :: checkPersistedHistograms :: line 148
JS frame :: c:/talos-slave/test/build/firefox/components/httpd.js :: <TOP_LEVEL> :: line 2253
JS frame :: c:/talos-slave/test/build/firefox/components/httpd.js :: <TOP_LEVEL> :: line 1141
JS frame :: c:/talos-slave/test/build/firefox/components/httpd.js :: <TOP_LEVEL> :: line 1589
JS frame :: c:/talos-slave/test/build/firefox/components/httpd.js :: <TOP_LEVEL> :: line 1437
JS frame :: c:/talos-slave/test/build/firefox/components/httpd.js :: <TOP_LEVEL> :: line 1306

TEST-INFO | (xpcshell/head.js) | exiting test

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 122] XPCShell == XPCShell

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 122] xpcshell@tests.mozilla.org == xpcshell@tests.mozilla.org

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 122] 1 == 1

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 122] XPCShell == XPCShell

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 122] 2007010101 == 2007010101

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 122] 2007010101 == 2007010101

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 125] saved-session == saved-session

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 126] true == true

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 127] true == true

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 137] true == true

TEST-PASS | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | [checkPayloadInfo : 138] true == true

TEST-UNEXPECTED-FAIL | c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js | false == true - See following stack:
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: do_throw :: line 440
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: _do_check_eq :: line 534
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: do_check_eq :: line 555
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: do_check_true :: line 569
JS frame :: c:/talos-slave/test/build/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_TelemetryPing.js :: <TOP_LEVEL> :: line 306
JS frame :: c:\talos-slave\test\build\xpcshell\head.js :: _execute_test :: line 341
JS frame :: -e :: <TOP_LEVEL> :: line 1

TEST-INFO | (xpcshell/head.js) | exiting test
uncaught exception: 2147500036
WARNING: nsExceptionService ignoring thread destruction after shutdown: file e:/builds/moz2_slave/m-aurora-w32-dbg/build/xpcom/base/nsExceptionService.cpp, line 166
JS Component Loader: ERROR c:/talos-slave/test/build/firefox/components/httpd.js:445
                     TypeError: dumpn is not a function
Assertion failure: !connections[i]->ConnectionReady(), at e:/builds/moz2_slave/m-aurora-w32-dbg/build/storage/src/mozStorageService.cpp:852
<<<<<<<
Downloading symbols from: http://ftp.mozilla.org/pub/mozilla.org/firefox/tinderbox-builds/mozilla-aurora-win32-debug/1339508424/firefox-15.0a2.en-US.win32.crashreporter-symbols.zip
PROCESS-CRASH | c:\talos-slave\test\build\xpcshell\tests\toolkit\components\telemetry\tests\unit\test_TelemetryPing.js | application crashed (minidump found)
Crash dump filename: c:\talos-slave\test\build\xpcshell\tests\toolkit\components\telemetry\tests\unit\03f940cb-3306-4306-9488-9bc7f0074b76.dmp
Operating system: Windows NT
                  6.1.7600 
CPU: x86
     GenuineIntel family 6 model 23 stepping 10
     2 CPUs

Crash reason:  EXCEPTION_ACCESS_VIOLATION_WRITE
Crash address: 0x0

Thread 0 (crashed)
 0  xul.dll!mozilla::storage::Service::Observe(nsISupports *,char const *,wchar_t const *) [mozStorageService.cpp:94e4994199c0 : 852 + 0x19]
    eip = 0x6a8f020d   esp = 0x002ef7a8   ebp = 0x002ef7d0   ebx = 0x00000000
    esi = 0x00000003   edi = 0x00000000   eax = 0x00000000   ecx = 0x14826c02
    edx = 0x71c0e4d8   efl = 0x00010206
    Found by: given as instruction pointer in context
 1  xul.dll!nsObserverList::NotifyObservers(nsISupports *,char const *,wchar_t const *) [nsObserverList.cpp:94e4994199c0 : 99 + 0x1b]
    eip = 0x6aca006c   esp = 0x002ef7d8   ebp = 0x002ef7f0
    Found by: call frame info
 2  xul.dll!nsObserverService::NotifyObservers(nsISupports *,char const *,wchar_t const *) [nsObserverService.cpp:94e4994199c0 : 149 + 0xd]
    eip = 0x6aca0542   esp = 0x002ef7f8   ebp = 0x002ef80c
    Found by: call frame info
 3  xul.dll!mozilla::ShutdownXPCOM(nsIServiceManager *) [nsXPComInit.cpp:94e4994199c0 : 585 + 0x14]
    eip = 0x6ac8f785   esp = 0x002ef814   ebp = 0x002ef844
    Found by: call frame info
 4  xpcshell.exe!main [xpcshell.cpp:94e4994199c0 : 1971 + 0x6]
    eip = 0x01377724   esp = 0x002ef84c   ebp = 0x002ef910
    Found by: call frame info
 5  xpcshell.exe!__tmainCRTStartup [crtexe.c : 555 + 0x18]
    eip = 0x0137cbef   esp = 0x002ef918   ebp = 0x002ef960
    Found by: call frame info
 6  xpcshell.exe!mainCRTStartup [crtexe.c : 370 + 0x4]
    eip = 0x0137ca1f   esp = 0x002ef968   ebp = 0x002ef968
    Found by: call frame info
 7  kernel32.dll + 0x51173
    eip = 0x75c81174   esp = 0x002ef970   ebp = 0x002ef974
    Found by: call frame info
 8  ntdll.dll + 0x5b3f4
    eip = 0x7712b3f5   esp = 0x002ef97c   ebp = 0x002ef9b4
    Found by: previous frame's frame pointer
 9  ntdll.dll + 0x5b3c7
    eip = 0x7712b3c8   esp = 0x002ef9bc   ebp = 0x002ef9cc
    Found by: previous frame's frame pointer
}
We're declaring this different than bug 753701?  Looks like roughly the same failure (certainly the later reports have the same assert).
I just looked at bug 753701 comment 0, didn't see an assert, exit code was different, so filed separately in case. Happy for you to dupe if you like? :-)
Version: Trunk → 15 Branch
Blocks: 774259
I think the assertion failure is different than what got fixed in bug 753701; all we care about here is the !connections[i]->ConnectionReady() failure.

At least when I'm seeing the assertion (pretty consistently on my Linux x86-64 machine), the problematic database comes from nsPermissionManager.  nsPermissionManager waits for profile-before-change to close the database and even then doesn't close it consistently all the time.  xpcshell testing doesn't fire profile-before-change (?), so the close code never gets called.  Can the call be moved earlier, or should we make xpcshell testing fire profile-before-change...?

CC'ing nsPermissionManager folks; maybe they'll have ideas.
Component: Telemetry → Networking: Cookies
OS: Windows 7 → All
Product: Toolkit → Core
Hardware: x86 → All
Whiteboard: [orange]
(OrangeWFM bugs not touched for 2 months)
Status: NEW → RESOLVED
Closed: 11 years ago
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.