Closed Bug 1407489 Opened 8 years ago Closed 3 years ago

Intermittent netwerk/test/unit/test_http2.js | testOnStopRequest - [testOnStopRequest : 82] false == true

Categories

(Core :: Networking, defect, P3)

defect

Tracking

()

RESOLVED DUPLICATE of bug 1760062

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, Whiteboard: [necko-triaged])

Nick, do you have time to take a look? It seems the failure is here: > do_check_true(this.isHttp2Connection == this.shouldBeHttp2); Not sure why that would fail
Flags: needinfo?(hurley)
Yep, I've seen this previously (when I was trying the tests on travis-ci, in an attempt to use them as integration tests for node-http2). I thought it was just an artifact of doing things on travis, but perhaps not.
Assignee: nobody → hurley
Flags: needinfo?(hurley)
Priority: P5 → P3
Whiteboard: [necko-triaged]
Status: NEW → RESOLVED
Closed: 8 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 8 years ago8 years ago
Resolution: --- → INCOMPLETE
https://treeherder.mozilla.org/logviewer.html#?job_id=188925981&repo=mozilla-central&lineNumber=1848 11:36:48 INFO - TEST-START | netwerk/test/unit/test_http2.js 11:36:56 WARNING - TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_http2.js | xpcshell return code: 0 11:36:56 INFO - TEST-INFO took 7161ms 11:36:56 INFO - >>>>>>> 11:36:56 INFO - PID 11508 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties 11:36:56 INFO - PID 11508 | [11508, 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 2707 11:36:56 INFO - (xpcshell/head.js) | test MAIN run_test pending (1) 11:36:56 INFO - TEST-PASS | netwerk/test/unit/test_http2.js | run_test - [run_test : 1246] "49859" != null 11:36:56 INFO - PID 11508 | using port 49859 11:36:56 INFO - PID 11508 | [11508, LoadRoots] WARNING: This method is lossy. Use GetCanonicalPath !: file z:/build/build/src/xpcom/io/nsLocalFileWin.cpp, line 3459 11:36:56 INFO - PID 11508 | [11508, LoadRoots] WARNING: This method is lossy. Use GetCanonicalPath !: file z:/build/build/src/xpcom/io/nsLocalFileWin.cpp, line 3459 11:36:56 INFO - PID 11508 | [11508, LoadRoots] WARNING: This method is lossy. Use GetCanonicalPath !: file z:/build/build/src/xpcom/io/nsLocalFileWin.cpp, line 3459 11:36:56 INFO - PID 11508 | [11508, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004002: file z:/build/build/src/toolkit/components/resistfingerprinting/nsRFPService.cpp, line 682 11:36:56 INFO - PID 11508 | [11508, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/extensions/cookie/nsPermissionManager.cpp, line 2940 11:36:56 INFO - PID 11508 | [11508, 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 351 11:36:56 INFO - PID 11508 | starting test number 0 11:36:56 INFO - (xpcshell/head.js) | test pending (2) 11:36:56 INFO - (xpcshell/head.js) | test MAIN run_test finished (2) 11:36:56 INFO - running event loop 11:36:56 INFO - Channel should have a success code! (2152398861) 11:36:56 INFO - Z:/task_1531998635/build/tests/xpcshell/tests/netwerk/test/unit/test_http2.js:testOnStartRequest:54 11:36:56 INFO - Z:\task_1531998635\build\tests\xpcshell\head.js:_do_main:219 11:36:56 INFO - Z:\task_1531998635\build\tests\xpcshell\head.js:_execute_test:533 11:36:56 INFO - -e:null:1 11:36:56 INFO - exiting test 11:36:56 INFO - PID 11508 | JavaScript error: Z:\\task_1531998635\\build\\tests\\xpcshell\\head.js, line 746: NS_ERROR_ABORT: 11:36:56 INFO - TEST-PASS | netwerk/test/unit/test_http2.js | testOnStopRequest - [testOnStopRequest : 74] true == true 11:36:56 WARNING - TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_http2.js | testOnStopRequest - [testOnStopRequest : 79] false == true 11:36:56 INFO - Z:/task_1531998635/build/tests/xpcshell/tests/netwerk/test/unit/test_http2.js:testOnStopRequest:79 11:36:56 INFO - Z:\task_1531998635\build\tests\xpcshell\head.js:_do_main:219 11:36:56 INFO - Z:\task_1531998635\build\tests\xpcshell\head.js:_execute_test:533 11:36:56 INFO - -e:null:1 11:36:56 INFO - exiting test 11:36:56 INFO - PID 11508 | JavaScript error: Z:\\task_1531998635\\build\\tests\\xpcshell\\head.js, line 746: NS_ERROR_ABORT: 11:36:56 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "NS_ERROR_ABORT: " {file: "Z:\\task_1531998635\\build\\tests\\xpcshell\\head.js" line: 746}]" 11:36:56 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "NS_ERROR_ABORT: " {file: "Z:\\task_1531998635\\build\\tests\\xpcshell\\head.js" line: 746}]" 11:36:56 INFO - PID 11508 | [11508, Socket Thread] WARNING: cannot post event if not initialized: file z:/build/build/src/netwerk/protocol/http/nsHttpConnectionMgr.cpp, line 301 11:36:56 INFO - PID 11508 | JavaScript strict warning: resource://testing-common/httpd.js, line 804: ReferenceError: reference to undefined property "_stopCallback" 11:36:56 INFO - PID 11508 | !!! error running onStopped callback: TypeError: callback is not a function 11:36:56 INFO - PID 11508 | !!! error running onStopped callback: TypeError: callback is not a function 11:36:56 INFO - "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "_stopCallback"" {file: "resource://testing-common/httpd.js" line: 804}]" 11:36:56 INFO - PID 11508 | [11508, Main Thread] WARNING: OOPDeinit() without successful OOPInit(): file z:/build/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 3368 11:36:56 INFO - PID 11508 | nsStringStats 11:36:56 INFO - PID 11508 | => mAllocCount: 6052 11:36:56 INFO - PID 11508 | => mReallocCount: 277 11:36:56 INFO - PID 11508 | => mFreeCount: 6052 11:36:56 INFO - PID 11508 | => mShareCount: 9405 11:36:56 INFO - PID 11508 | => mAdoptCount: 390 11:36:56 INFO - PID 11508 | => mAdoptFreeCount: 390 11:36:56 INFO - PID 11508 | => Process ID: 11508, Thread ID: 8508 11:36:56 INFO - <<<<<<< 11:36:56 INFO - TEST-START | netwerk/test/unit/test_altsvc.js 11:41:56 WARNING - TEST-UNEXPECTED-TIMEOUT | netwerk/test/unit/test_altsvc.js | Test timed out
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 8 years ago7 years ago
Resolution: --- → INCOMPLETE
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=204430416&repo=mozilla-central&lineNumber=1853 06:22:56 INFO - TEST-START | netwerk/test/unit/test_http2.js 06:23:02 WARNING - TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_http2.js | xpcshell return code: 0 06:23:02 INFO - TEST-INFO took 6067ms 06:23:02 INFO - >>>>>>> 06:23:02 INFO - PID 9860 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties 06:23:02 INFO - PID 9860 | [9860, 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 2733 06:23:02 INFO - (xpcshell/head.js) | test MAIN run_test pending (1) 06:23:02 INFO - TEST-PASS | netwerk/test/unit/test_http2.js | run_test - [run_test : 1261] "55289" != null 06:23:02 INFO - PID 9860 | using port 55289 06:23:02 INFO - PID 9860 | [9860, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file z:/build/build/src/xpcom/io/nsLocalFileWin.cpp, line 3460 06:23:02 INFO - PID 9860 | [9860, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file z:/build/build/src/xpcom/io/nsLocalFileWin.cpp, line 3460 06:23:02 INFO - PID 9860 | [9860, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file z:/build/build/src/xpcom/io/nsLocalFileWin.cpp, line 3460 06:23:02 INFO - PID 9860 | [9860, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004002: file z:/build/build/src/toolkit/components/resistfingerprinting/nsRFPService.cpp, line 683 06:23:02 INFO - PID 9860 | [9860, 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 06:23:02 INFO - PID 9860 | [9860, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/extensions/cookie/nsPermissionManager.cpp, line 2932 06:23:02 INFO - PID 9860 | starting test number 0 06:23:02 INFO - (xpcshell/head.js) | test pending (2) 06:23:02 INFO - (xpcshell/head.js) | test MAIN run_test finished (2) 06:23:02 INFO - running event loop 06:23:02 INFO - Channel should have a success code! (2152398861) 06:23:02 INFO - Z:/task_1539151552/build/tests/xpcshell/tests/netwerk/test/unit/test_http2.js:testOnStartRequest:54 06:23:02 INFO - Z:\task_1539151552\build\tests\xpcshell\head.js:_do_main:219 06:23:02 INFO - Z:\task_1539151552\build\tests\xpcshell\head.js:_execute_test:533 06:23:02 INFO - -e:null:1 06:23:02 INFO - exiting test 06:23:02 INFO - PID 9860 | JavaScript error: Z:\\task_1539151552\\build\\tests\\xpcshell\\head.js, line 746: NS_ERROR_ABORT: 06:23:02 INFO - TEST-PASS | netwerk/test/unit/test_http2.js | testOnStopRequest - [testOnStopRequest : 74] true == true 06:23:02 WARNING - TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_http2.js | testOnStopRequest - [testOnStopRequest : 79] false == true 06:23:02 INFO - Z:/task_1539151552/build/tests/xpcshell/tests/netwerk/test/unit/test_http2.js:testOnStopRequest:79 06:23:02 INFO - Z:\task_1539151552\build\tests\xpcshell\head.js:_do_main:219 06:23:02 INFO - Z:\task_1539151552\build\tests\xpcshell\head.js:_execute_test:533 06:23:02 INFO - -e:null:1 06:23:02 INFO - exiting test 06:23:02 INFO - PID 9860 | JavaScript error: Z:\\task_1539151552\\build\\tests\\xpcshell\\head.js, line 746: NS_ERROR_ABORT: 06:23:02 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "NS_ERROR_ABORT: " {file: "Z:\\task_1539151552\\build\\tests\\xpcshell\\head.js" line: 746}]" 06:23:02 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "NS_ERROR_ABORT: " {file: "Z:\\task_1539151552\\build\\tests\\xpcshell\\head.js" line: 746}]" 06:23:02 INFO - PID 9860 | [9860, Socket Thread] WARNING: cannot post event if not initialized: file z:/build/build/src/netwerk/protocol/http/nsHttpConnectionMgr.cpp, line 301 06:23:02 INFO - PID 9860 | JavaScript strict warning: resource://testing-common/httpd.js, line 800: ReferenceError: reference to undefined property "_stopCallback" 06:23:02 INFO - PID 9860 | !!! error running onStopped callback: TypeError: callback is not a function 06:23:02 INFO - PID 9860 | !!! error running onStopped callback: TypeError: callback is not a function 06:23:02 INFO - "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "ReferenceError: reference to undefined property "_stopCallback"" {file: "resource://testing-common/httpd.js" line: 800}]" 06:23:02 INFO - PID 9860 | [9860, Main Thread] WARNING: OOPDeinit() without successful OOPInit(): file z:/build/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 3411 06:23:02 INFO - PID 9860 | nsStringStats 06:23:02 INFO - PID 9860 | => mAllocCount: 6340 06:23:02 INFO - PID 9860 | => mReallocCount: 0 06:23:02 INFO - PID 9860 | => mFreeCount: 6340 06:23:02 INFO - PID 9860 | => mShareCount: 9628 06:23:02 INFO - PID 9860 | => mAdoptCount: 403 06:23:02 INFO - PID 9860 | => mAdoptFreeCount: 403 06:23:02 INFO - PID 9860 | => Process ID: 9860, Thread ID: 10316 06:23:02 INFO - <<<<<<<
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

Huh, this is super rare, but seems to only happen in windows10-64-ccov.
Probably an infra issue, or conflicts between the h2 server and the ccov setup?

Assignee: u408661 → nobody
Status: REOPENED → RESOLVED
Closed: 7 years ago7 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
[task 2020-04-30T09:23:06.591Z] 09:23:06     INFO -  Node moz-http2 server shutting down ...
[task 2020-04-30T09:23:06.591Z] 09:23:06     INFO -  Node server moz-http2 already dead 1
[task 2020-04-30T09:23:06.591Z] 09:23:06     INFO -  Process stdout
[task 2020-04-30T09:23:06.591Z] 09:23:06     INFO -  forked process without handler sent: [object Object]
[task 2020-04-30T09:23:06.591Z] 09:23:06     INFO -  Process stderr
[task 2020-04-30T09:23:06.591Z] 09:23:06     INFO -  events.js:189
[task 2020-04-30T09:23:06.591Z] 09:23:06     INFO -      throw err; // Unhandled 'error' event
[task 2020-04-30T09:23:06.591Z] 09:23:06     INFO -      ^
[task 2020-04-30T09:23:06.592Z] 09:23:06     INFO -  Error [ERR_UNHANDLED_ERROR]: Unhandled error. ('STREAM_CLOSED')
[task 2020-04-30T09:23:06.592Z] 09:23:06     INFO -      at Stream.emit (events.js:187:17)
[task 2020-04-30T09:23:06.592Z] 09:23:06     INFO -      at Stream.transition [as _transition] (Z:\task_1588236988\build\tests\xpcshell\node-http2\lib\protocol\stream.js:659:14)
[task 2020-04-30T09:23:06.592Z] 09:23:06     INFO -      at Stream._writeUpstream (Z:\task_1588236988\build\tests\xpcshell\node-http2\lib\protocol\stream.js:252:8)
[task 2020-04-30T09:23:06.592Z] 09:23:06     INFO -      at Connection._receive (Z:\task_1588236988\build\tests\xpcshell\node-http2\lib\protocol\connection.js:393:19)
[task 2020-04-30T09:23:06.592Z] 09:23:06     INFO -      at Connection._write (Z:\task_1588236988\build\tests\xpcshell\node-http2\lib\protocol\flow.js:100:10)
[task 2020-04-30T09:23:06.592Z] 09:23:06     INFO -      at doWrite (_stream_writable.js:415:12)
[task 2020-04-30T09:23:06.592Z] 09:23:06     INFO -      at writeOrBuffer (_stream_writable.js:399:5)
[task 2020-04-30T09:23:06.592Z] 09:23:06     INFO -      at Connection.Writable.write (_stream_writable.js:299:11)
[task 2020-04-30T09:23:06.592Z] 09:23:06     INFO -      at Decompressor.ondata (_stream_readable.js:710:20)
[task 2020-04-30T09:23:06.592Z] 09:23:06     INFO -      at Decompressor.emit (events.js:198:13)
[task 2020-04-30T09:23:06.673Z] 09:23:06    ERROR - Return code: 1
Status: REOPENED → RESOLVED
Closed: 7 years ago3 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.