Closed Bug 1592856 Opened 5 years ago Closed 5 years ago

Intermittent netwerk/test/unit/test_anonymous-coalescing.js | xpcshell return code: 0

Categories

(Core :: DOM: Networking, defect, P5)

Product:
Core
Component:
DOM: Networking
Type:
defect

Tracking

()

Status:
RESOLVED INVALID

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: rmaries [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=273808543&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/G0ep9srSRTuBvu92izQmWQ/runs/0/artifacts/public/logs/live_backing.log

[task 2019-10-31T00:15:13.401Z] 00:15:13 INFO - TEST-START | netwerk/test/unit/test_anonymous-coalescing.js
[task 2019-10-31T00:15:13.835Z] 00:15:13 WARNING - TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_anonymous-coalescing.js | xpcshell return code: 0
[task 2019-10-31T00:15:13.836Z] 00:15:13 INFO - TEST-INFO took 435ms
[task 2019-10-31T00:15:13.836Z] 00:15:13 INFO - >>>>>>>
[task 2019-10-31T00:15:13.837Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: 'result.isErr()', file z:/build/build/src/startupcache/StartupCache.cpp, line 173
[task 2019-10-31T00:15:13.837Z] 00:15:13 INFO - PID 10084 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-10-31T00:15:13.837Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: Failed to get directory to cache.: file z:/build/build/src/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp, line 83
[task 2019-10-31T00:15:13.838Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: Failed to get directory to cache.: file z:/build/build/src/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp, line 83
[task 2019-10-31T00:15:13.838Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: Failed to get directory to cache.: file z:/build/build/src/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp, line 83
[task 2019-10-31T00:15:13.838Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: Failed to get directory to cache.: file z:/build/build/src/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp, line 83
[task 2019-10-31T00:15:13.839Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: Failed to get directory to cache.: file z:/build/build/src/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp, line 83
[task 2019-10-31T00:15:13.839Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: 'result.isErr()', file z:/build/build/src/startupcache/StartupCache.cpp, line 173
[task 2019-10-31T00:15:13.840Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: 'result.isErr()', file z:/build/build/src/startupcache/StartupCache.cpp, line 173
[task 2019-10-31T00:15:13.840Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: 'result.isErr()', file z:/build/build/src/startupcache/StartupCache.cpp, line 173
[task 2019-10-31T00:15:13.841Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: 'result.isErr()', file z:/build/build/src/startupcache/StartupCache.cpp, line 173
[task 2019-10-31T00:15:13.841Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: 'result.isErr()', file z:/build/build/src/startupcache/StartupCache.cpp, line 173
[task 2019-10-31T00:15:13.841Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: 'result.isErr()', file z:/build/build/src/startupcache/StartupCache.cpp, line 173
[task 2019-10-31T00:15:13.842Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: 'result.isErr()', file z:/build/build/src/startupcache/StartupCache.cpp, line 173
[task 2019-10-31T00:15:13.842Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: 'result.isErr()', file z:/build/build/src/startupcache/StartupCache.cpp, line 173
[task 2019-10-31T00:15:13.843Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: 'result.isErr()', file z:/build/build/src/startupcache/StartupCache.cpp, line 173
[task 2019-10-31T00:15:13.843Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: 'result.isErr()', file z:/build/build/src/startupcache/StartupCache.cpp, line 173
[task 2019-10-31T00:15:13.844Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: 'result.isErr()', file z:/build/build/src/startupcache/StartupCache.cpp, line 173
[task 2019-10-31T00:15:13.844Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file z:/build/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 2632
[task 2019-10-31T00:15:13.845Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: 'result.isErr()', file z:/build/build/src/startupcache/StartupCache.cpp, line 173
[task 2019-10-31T00:15:13.845Z] 00:15:13 INFO - (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2019-10-31T00:15:13.846Z] 00:15:13 INFO - TEST-PASS | netwerk/test/unit/test_anonymous-coalescing.js | run_test - [run_test : 19] "50046" != null
[task 2019-10-31T00:15:13.846Z] 00:15:13 INFO - TEST-PASS | netwerk/test/unit/test_anonymous-coalescing.js | run_test - [run_test : 20] "50046" != ""
[task 2019-10-31T00:15:13.846Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file z:/build/build/src/xpcom/io/nsLocalFileWin.cpp, line 3201
[task 2019-10-31T00:15:13.847Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file z:/build/build/src/xpcom/io/nsLocalFileWin.cpp, line 3201
[task 2019-10-31T00:15:13.847Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file z:/build/build/src/xpcom/io/nsLocalFileWin.cpp, line 3201
[task 2019-10-31T00:15:13.847Z] 00:15:13 INFO - PID 10084 | doTest1()
[task 2019-10-31T00:15:13.847Z] 00:15:13 INFO - (xpcshell/head.js) | test pending (2)
[task 2019-10-31T00:15:13.847Z] 00:15:13 INFO - PID 10084 | execute doTest https://foo.example.com:50046/origin-1
[task 2019-10-31T00:15:13.847Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, kKnownEsrVersion) failed with result 0x80004002: file z:/build/build/src/toolkit/components/resistfingerprinting/nsRFPService.cpp, line 661
[task 2019-10-31T00:15:13.847Z] 00:15:13 INFO - (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2019-10-31T00:15:13.847Z] 00:15:13 INFO - running event loop
[task 2019-10-31T00:15:13.847Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file z:/build/build/src/dom/media/CubebUtils.cpp, line 381
[task 2019-10-31T00:15:13.847Z] 00:15:13 INFO - TEST-PASS | netwerk/test/unit/test_anonymous-coalescing.js | testOnStartRequest - [testOnStartRequest : 73] true == true
[task 2019-10-31T00:15:13.848Z] 00:15:13 INFO - TEST-PASS | netwerk/test/unit/test_anonymous-coalescing.js | testOnStartRequest - [testOnStartRequest : 78] 200 == 200
[task 2019-10-31T00:15:13.848Z] 00:15:13 INFO - TEST-PASS | netwerk/test/unit/test_anonymous-coalescing.js | testOnStopRequest - [testOnStopRequest : 87] true == true
[task 2019-10-31T00:15:13.848Z] 00:15:13 INFO - TEST-PASS | netwerk/test/unit/test_anonymous-coalescing.js | testOnStopRequest - [testOnStopRequest : 91] 0 != 56236
[task 2019-10-31T00:15:13.848Z] 00:15:13 INFO - PID 10084 | doTest2()
[task 2019-10-31T00:15:13.848Z] 00:15:13 INFO - (xpcshell/head.js) | test pending (2)
[task 2019-10-31T00:15:13.848Z] 00:15:13 INFO - PID 10084 | execute doTest https://foo.example.com:50046/origin-2
[task 2019-10-31T00:15:13.848Z] 00:15:13 INFO - (xpcshell/head.js) | test finished (2)
[task 2019-10-31T00:15:13.848Z] 00:15:13 INFO - TEST-PASS | netwerk/test/unit/test_anonymous-coalescing.js | testOnStartRequest - [testOnStartRequest : 73] true == true
[task 2019-10-31T00:15:13.848Z] 00:15:13 INFO - TEST-PASS | netwerk/test/unit/test_anonymous-coalescing.js | testOnStartRequest - [testOnStartRequest : 78] 200 == 200
[task 2019-10-31T00:15:13.848Z] 00:15:13 INFO - TEST-PASS | netwerk/test/unit/test_anonymous-coalescing.js | testOnStopRequest - [testOnStopRequest : 87] true == true
[task 2019-10-31T00:15:13.848Z] 00:15:13 WARNING - TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_anonymous-coalescing.js | testOnStopRequest - [testOnStopRequest : 89] 56236 == 56237
[task 2019-10-31T00:15:13.849Z] 00:15:13 INFO - Z:/task_1572479856/build/tests/xpcshell/tests/netwerk/test/unit/test_anonymous-coalescing.js:testOnStopRequest:89
[task 2019-10-31T00:15:13.849Z] 00:15:13 INFO - Z:\task_1572479856\build\tests\xpcshell\head.js:_do_main:246
[task 2019-10-31T00:15:13.849Z] 00:15:13 INFO - Z:\task_1572479856\build\tests\xpcshell\head.js:_execute_test:573
[task 2019-10-31T00:15:13.849Z] 00:15:13 INFO - -e:null:1
[task 2019-10-31T00:15:13.849Z] 00:15:13 INFO - exiting test
[task 2019-10-31T00:15:13.849Z] 00:15:13 INFO - PID 10084 | JavaScript error: Z:\task_1572479856\build\tests\xpcshell\head.js, line 791: NS_ERROR_ABORT:
[task 2019-10-31T00:15:13.849Z] 00:15:13 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "NS_ERROR_ABORT: " {file: "Z:\task_1572479856\build\tests\xpcshell\head.js" line: 791}]"
[task 2019-10-31T00:15:13.849Z] 00:15:13 INFO - PID 10084 | [10084, Socket Thread] WARNING: cannot post event if not initialized: file z:/build/build/src/netwerk/protocol/http/nsHttpConnectionMgr.cpp, line 279
[task 2019-10-31T00:15:13.849Z] 00:15:13 INFO - PID 10084 | [10084, Socket Thread] WARNING: cannot post event if not initialized: file z:/build/build/src/netwerk/protocol/http/nsHttpConnectionMgr.cpp, line 279
[task 2019-10-31T00:15:13.849Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: OOPDeinit() without successful OOPInit(): file z:/build/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 3114
[task 2019-10-31T00:15:13.850Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file z:/build/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-10-31T00:15:13.850Z] 00:15:13 INFO - PID 10084 | [10084, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file z:/build/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-10-31T00:15:13.850Z] 00:15:13 INFO - PID 10084 | nsStringStats
[task 2019-10-31T00:15:13.850Z] 00:15:13 INFO - PID 10084 | => mAllocCount: 10276
[task 2019-10-31T00:15:13.850Z] 00:15:13 INFO - PID 10084 | => mReallocCount: 0
[task 2019-10-31T00:15:13.850Z] 00:15:13 INFO - PID 10084 | => mFreeCount: 10276
[task 2019-10-31T00:15:13.850Z] 00:15:13 INFO - PID 10084 | => mShareCount: 6976
[task 2019-10-31T00:15:13.850Z] 00:15:13 INFO - PID 10084 | => mAdoptCount: 246
[task 2019-10-31T00:15:13.850Z] 00:15:13 INFO - PID 10084 | => mAdoptFreeCount: 246
[task 2019-10-31T00:15:13.850Z] 00:15:13 INFO - PID 10084 | => Process ID: 10084, Thread ID: 15280
[task 2019-10-31T00:15:13.850Z] 00:15:13 INFO - <<<<<<<
[task 2019-10-31T00:15:13.859Z] 00:15:13 INFO - c:\users\task_1572479856\appdata\local\temp\xpc-profile-2rahc0 could not be cleaned up.
[task 2019-10-31T00:15:13.859Z] 00:15:13 INFO - INFO | Result summary:
[task 2019-10-31T00:15:13.859Z] 00:15:13 INFO - INFO | Passed: 1735
[task 2019-10-31T00:15:13.859Z] 00:15:13 WARNING - INFO | Failed: 1
[task 2019-10-31T00:15:13.859Z] 00:15:13 WARNING - One or more unittests failed.
[task 2019-10-31T00:15:13.859Z] 00:15:13 INFO - INFO | Todo: 0
[task 2019-10-31T00:15:13.860Z] 00:15:13 INFO - INFO | Retried: 13
[task 2019-10-31T00:15:13.860Z] 00:15:13 INFO - SUITE-END | took 341s
[task 2019-10-31T00:15:13.860Z] 00:15:13 INFO - Node moz-http2 server shutting down ...
[task 2019-10-31T00:15:13.860Z] 00:15:13 INFO - Process stderr
[task 2019-10-31T00:15:13.860Z] 00:15:13 INFO - (node:10548) ExperimentalWarning: The http2 module is an experimental API.
[task 2019-10-31T00:15:13.860Z] 00:15:13 INFO - (node:10548) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 window_update listeners added. Use emitter.setMaxListeners() to increase limit
[task 2019-10-31T00:15:13.860Z] 00:15:13 INFO - (node:10548) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 window_update listeners added. Use emitter.setMaxListeners() to increase limit
[task 2019-10-31T00:15:13.860Z] 00:15:13 INFO - (node:10548) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 window_update listeners added. Use emitter.setMaxListeners() to increase limit
[task 2019-10-31T00:15:13.860Z] 00:15:13 INFO - (node:10548) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 window_update listeners added. Use emitter.setMaxListeners() to increase limit
[task 2019-10-31T00:15:13.951Z] 00:15:13 ERROR - Return code: 1
[task 2019-10-31T00:15:13.951Z] 00:15:13 INFO - TinderboxPrint: xpcshell-xpcshell<br/>1735/<em class="testfail">1</em>/0
[task 2019-10-31T00:15:13.951Z] 00:15:13 WARNING - # TBPL FAILURE #
[task 2019-10-31T00:15:13.951Z] 00:15:13 WARNING - setting return code to 2
[task 2019-10-31T00:15:13.951Z] 00:15:13 WARNING - The xpcshell suite: xpcshell ran with return status: FAILURE
[task 2019-10-31T00:15:13.951Z] 00:15:13 INFO - Running post-action listener: _package_coverage_data
[task 2019-10-31T00:15:13.951Z] 00:15:13 INFO - Running post-action listener: _resource_record_post_action
[task 2019-10-31T00:15:13.952Z] 00:15:13 INFO - Running post-action listener: process_java_coverage_data
[task 2019-10-31T00:15:13.952Z] 00:15:13 INFO - [mozharness: 2019-10-31 00:15:13.951000Z] Finished run-tests step (success)
[task 2019-10-31T00:15:13.952Z] 00:15:13 INFO - Running post-run listener: _resource_record_post_run
[task 2019-10-31T00:15:14.087Z] 00:15:14 INFO - Validating Perfherder data against Z:\task_1572479856\mozharness\external_tools\performance-artifact-schema.json
[task 2019-10-31T00:15:14.090Z] 00:15:14 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"subtests": [{"name": "cpu_percent", "value": 81.33323063380283}, {"name": "io_write_bytes", "value": 4817759744}, {"name": "io.read_bytes", "value": 51585536}, {"name": "io_write_time", "value": 173}, {"name": "io_read_time", "value": 5}], "extraOptions": ["e10s", "taskcluster-c5.2xlarge"], "name": "xpcshell.xpcshell.1.overall"}, {"subtests": [{"name": "time", "value": 0.0}], "name": "xpcshell.xpcshell.1.start-pulseaudio"}, {"subtests": [{"name": "time", "value": 2.6480000019073486}, {"name": "cpu_percent", "value": 15.581250000000002}], "name": "xpcshell.xpcshell.1.install"}, {"subtests": [{"name": "time", "value": 0.03299999237060547}], "name": "xpcshell.xpcshell.1.stage-files"}, {"subtests": [{"name": "time", "value": 349.2590000629425}, {"name": "cpu_percent", "value": 82.0310053380783}], "name": "xpcshell.xpcshell.1.run-tests"}]}
[task 2019-10-31T00:15:14.091Z] 00:15:14 INFO - Total resource usage - Wall time: 350s; CPU: 82.0%; Read bytes: 51585536; Write bytes: 4817759744; Read time: 5; Write time: 173
[task 2019-10-31T00:15:14.091Z] 00:15:14 INFO - TinderboxPrint: CPU usage<br/>82.0%
[task 2019-10-31T00:15:14.091Z] 00:15:14 INFO - TinderboxPrint: I/O read bytes / time<br/>51,585,536 / 5
[task 2019-10-31T00:15:14.091Z] 00:15:14 INFO - TinderboxPrint: I/O write bytes / time<br/>4,817,759,744 / 173
[task 2019-10-31T00:15:14.091Z] 00:15:14 INFO - TinderboxPrint: CPU idle<br/>400.1 (14.2%)
[task 2019-10-31T00:15:14.091Z] 00:15:14 INFO - TinderboxPrint: CPU system<br/>674.2 (24.0%)
[task 2019-10-31T00:15:14.091Z] 00:15:14 INFO - TinderboxPrint: CPU user<br/>1,728.1 (61.4%)
[task 2019-10-31T00:15:14.091Z] 00:15:14 INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2019-10-31T00:15:14.092Z] 00:15:14 INFO - install - Wall time: 3s; CPU: 16.0%; Read bytes: 61440; Write bytes: 11341824; Read time: 0; Write time: 0
[task 2019-10-31T00:15:14.092Z] 00:15:14 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2019-10-31T00:15:14.095Z] 00:15:14 INFO - run-tests - Wall time: 349s; CPU: 82.0%; Read bytes: 51360256; Write bytes: 4797156864; Read time: 5; Write time: 173
[task 2019-10-31T00:15:14.164Z] 00:15:14 WARNING - returning nonzero exit status 2
[fetches 2019-10-31T00:15:14.187Z] removing Z:/task_1572479856/fetches
[fetches 2019-10-31T00:15:14.797Z] finished
[taskcluster 2019-10-31T00:15:14.819Z] Exit Code: 2
[taskcluster 2019-10-31T00:15:14.819Z] User Time: 0s
[taskcluster 2019-10-31T00:15:14.819Z] Kernel Time: 0s
[taskcluster 2019-10-31T00:15:14.819Z] Wall Time: 7m29.4596152s
[taskcluster 2019-10-31T00:15:14.819Z] Result: FAILED
[taskcluster 2019-10-31T00:15:14.819Z] === Task Finished ===
[taskcluster 2019-10-31T00:15:14.819Z] Task Duration: 7m29.4606103s
[taskcluster 2019-10-31T00:15:15.396Z] Uploading artifact public/logs/localconfig.json from file logs\localconfig.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2020-10-29T22:47:34.311Z
[taskcluster 2019-10-31T00:15:15.890Z] Uploading artifact public/test_info/resource-usage.json from file build\blobber_upload_dir\resource-usage.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2020-10-29T22:47:34.311Z
[taskcluster 2019-10-31T00:15:16.317Z] Uploading artifact public/test_info/system-info.log from file build\blobber_upload_dir\system-info.log with content encoding "gzip", mime type "text/plain" and expiry 2020-10-29T22:47:34.311Z
[taskcluster 2019-10-31T00:15:16.673Z] Uploading artifact public/test_info/xpcshell_errorsummary.log from file build\blobber_upload_dir\xpcshell_errorsummary.log with content encoding "gzip", mime type "text/plain" and expiry 2020-10-29T22:47:34.311Z
[taskcluster 2019-10-31T00:15:17.086Z] Uploading artifact public/test_info/xpcshell_raw.log from file build\blobber_upload_dir\xpcshell_raw.log with content encoding "gzip", mime type "text/plain" and expiry 2020-10-29T22:47:34.311Z
[taskcluster 2019-10-31T00:15:17.706Z] Uploading redirect artifact public/logs/live.log to URL https://queue.taskcluster.net/v1/task/G0ep9srSRTuBvu92izQmWQ/runs/0/artifacts/public/logs/live_backing.log with mime type "text/plain; charset=utf-8" and expiry 2020-10-29T22:47:34.311Z
[taskcluster:error] exit status 2

Fixed by commit: https://hg.mozilla.org/integration/autoland/rev/1efec06dae2fc6c25b4f0ca1365c01e4b470023f

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INVALID
You need to log in before you can comment on or make changes to this bug.