1581674 - Intermittent [TV] netwerk/test/unit/test_http2-proxy.js | xpcshell return code: 0

Status: RESOLVED WORKSFORME

(Testing :: XPCShell Harness, defect, P2)

Description

[Treeherder Bug Filer]

Filed by: cbrindusan [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=266941729&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/PaoH1_0fQQu0XzmKbJuWBg/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://queue.taskcluster.net/v1/task/PaoH1_0fQQu0XzmKbJuWBg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2019-09-16T23:00:59.563Z] 23:00:59 INFO - TEST-START | netwerk/test/unit/test_http2-proxy.js
[task 2019-09-16T23:01:03.059Z] 23:01:03 WARNING - TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_http2-proxy.js | xpcshell return code: 0
[task 2019-09-16T23:01:03.060Z] 23:01:03 INFO - TEST-INFO took 3495ms
[task 2019-09-16T23:01:03.060Z] 23:01:03 INFO - >>>>>>>
[task 2019-09-16T23:01:03.060Z] 23:01:03 INFO - PID 4492 | Unable to load \untrusted-startup-test-dll.dll; LoadLibraryW failed: 126Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-09-16T23:01:03.061Z] 23:01:03 INFO - PID 4492 | [4492, 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-09-16T23:01:03.061Z] 23:01:03 INFO - PID 4492 | [4492, 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-09-16T23:01:03.062Z] 23:01:03 INFO - PID 4492 | [4492, 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-09-16T23:01:03.062Z] 23:01:03 INFO - PID 4492 | [4492, 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-09-16T23:01:03.063Z] 23:01:03 INFO - PID 4492 | [4492, 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-09-16T23:01:03.063Z] 23:01:03 INFO - PID 4492 | [4492, 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-09-16T23:01:03.064Z] 23:01:03 INFO - (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2019-09-16T23:01:03.064Z] 23:01:03 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2019-09-16T23:01:03.065Z] 23:01:03 INFO - (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2019-09-16T23:01:03.065Z] 23:01:03 INFO - running event loop
[task 2019-09-16T23:01:03.066Z] 23:01:03 INFO - PID 4492 | [4492, 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 384
[task 2019-09-16T23:01:03.066Z] 23:01:03 INFO - netwerk/test/unit/test_http2-proxy.js | Starting setup
[task 2019-09-16T23:01:03.067Z] 23:01:03 INFO - (xpcshell/head.js) | test setup pending (2)
[task 2019-09-16T23:01:03.067Z] 23:01:03 INFO - TEST-PASS | netwerk/test/unit/test_http2-proxy.js | setup - [setup : 129] "" != null

Comment 1

[Valentin Gosu [:valentin] (he/him)]

Attachment: Relevant log

x

Comment 2

[Valentin Gosu [:valentin] (he/him)]

Comment on attachment 9093248 [details]
Relevant log

```
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'HOMEPATH': '\\Users\\task_1568672141',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'LOCALAPPDATA': 'C:\\Users\\task_1568672141\\AppData\\Local',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'LOGONSERVER': '\\\\I-06366243025B3',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'MINIDUMP_SAVE_PATH': 'Z:\\task_1568672141\\build\\blobber_upload_dir',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'MINIDUMP_STACKWALK': 'Z:\\task_1568672141\\build\\win32-minidump_stackwalk.exe',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'MOZILLABUILD': 'C:\\mozilla-build',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'MOZ_AUTOMATION': '1',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'MOZ_SCM_LEVEL': '3',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'MOZ_UPLOAD_DIR': 'Z:\\task_1568672141\\build\\blobber_upload_dir',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'NUMBER_OF_PROCESSORS': '8',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'ONEDRIVE': 'C:\\Users\\task_1568672141\\OneDrive',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'OS': 'Windows_NT',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'PATH': 'Z:\\task_1568672141\\build\\venv\\Scripts;C:\\Windows\\system32;C:\\Windows;C:\\Windows\\System32\\Wbem;C:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\Windows\\System32\\OpenSSH\\;C:\\Windows\\system32\\config\\systemprofile\\AppData\\Local\\Microsoft\\WindowsApps;C:\\Program Files (x86)\\Windows Kits\\10\\Windows Performance Toolkit\\;;C:\\Program Files\\Mercurial;C:\\mozilla-build\\bin;C:\\mozilla-build\\kdiff3;C:\\mozilla-build\\moztools-x64\\bin;C:\\mozilla-build\\mozmake;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\msys\\local\\bin;C:\\mozilla-build\\nsis-3.01;C:\\mozilla-build\\python;C:\\mozilla-build\\python\\Scripts;C:\\mozilla-build\\python3;C:\\Users\\task_1568672141\\AppData\\Local\\Microsoft\\WindowsApps;Z:\\task_1568672141\\build\\venv\\Lib\\site-packages\\pywin32_system32',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'PIP_DOWNLOAD_CACHE': 'y:\\pip-cache',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'PROCESSOR_ARCHITECTURE': 'AMD64',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 85 Stepping 4, GenuineIntel',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'PROCESSOR_LEVEL': '6',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'PROCESSOR_REVISION': '5504',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'PROGRAMFILES': 'C:\\Program Files',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'PROGRAMW6432': 'C:\\Program Files',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'PROMPT': '$P$G',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'PSMODULEPATH': '%ProgramFiles%\\WindowsPowerShell\\Modules;C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules',
[task 2019-09-16T23:00:58.974Z] 23:00:58     INFO -  'PUBLIC': 'C:\\Users\\Public',
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO -  'RUST_BACKTRACE': 'full',
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO -  'SCCACHE_DISABLE': '1',
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO -  'STYLO_THREADS': '4',
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO -  'SYSTEMDRIVE': 'C:',
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO -  'SYSTEMROOT': 'C:\\Windows',
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO -  'TASKCLUSTER_INSTANCE_TYPE': 'c5.2xlarge',
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO -  'TASKCLUSTER_ROOT_URL': 'https://taskcluster.net',
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO -  'TASK_ID': 'PaoH1_0fQQu0XzmKbJuWBg',
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO -  'TEMP': 'C:\\Users\\task_1568672141\\AppData\\Local\\Temp',
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO -  'TMP': 'C:\\Users\\task_1568672141\\AppData\\Local\\Temp',
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO -  'TOOLTOOL_CACHE': 'y:\\tooltool-cache',
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO -  'USERDOMAIN': 'I-06366243025B3',
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO -  'USERDOMAIN_ROAMINGPROFILE': 'I-06366243025B3',
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO -  'USERNAME': 'task_1568672141',
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO -  'USERPROFILE': 'C:\\Users\\task_1568672141',
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO -  'WINDIR': 'C:\\Windows'}
[task 2019-09-16T23:00:58.975Z] 23:00:58     INFO - Calling ['Z:\\task_1568672141\\build\\venv\\Scripts\\python', '-u', 'Z:\\task_1568672141\\build\\tests\\xpcshell\\runxpcshelltests.py', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--symbols-path=Z:\\task_1568672141\\build\\symbols', '--test-plugin-path=Z:\\task_1568672141\\build\\application\\firefox\\plugins', '--log-raw=Z:\\task_1568672141\\build\\blobber_upload_dir\\xpcshell_raw.log', '--log-errorsummary=Z:\\task_1568672141\\build\\blobber_upload_dir\\xpcshell_errorsummary.log', '--utility-path=tests/bin', '--xpcshell=Z:\\task_1568672141\\build\\application\\firefox/xpcshell.exe', '--manifest=tests/xpcshell/tests/xpcshell.ini', '--verify-max-time=900', '--verify', u'netwerk/test/unit/test_http2-proxy.js'] with output_timeout 1000
[task 2019-09-16T23:00:59.240Z] 23:00:59     INFO -  MOZ_NODE_PATH environment variable not set. Tests requiring http/2 will fail.
[task 2019-09-16T23:00:59.544Z] 23:00:59     INFO -  :::
[task 2019-09-16T23:00:59.544Z] 23:00:59     INFO -  ::: Running test verification step "1. Run each test 10 times, sequentially."...
[task 2019-09-16T23:00:59.544Z] 23:00:59     INFO -  :::
[task 2019-09-16T23:00:59.544Z] 23:00:59     INFO -  Using at most 32 threads.
[task 2019-09-16T23:00:59.544Z] 23:00:59     INFO -  SUITE-START | Running 1 tests
[task 2019-09-16T23:00:59.563Z] 23:00:59     INFO -  TEST-START | netwerk/test/unit/test_http2-proxy.js
[task 2019-09-16T23:01:03.059Z] 23:01:03  WARNING -  TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_http2-proxy.js | xpcshell return code: 0
```

The warning originates here [runxpcshelltests.py#1116](https://searchfox.org/mozilla-central/rev/7ed8e2d3d1d7a1464ba42763a33fd2e60efcaedc/testing/xpcshell/runxpcshelltests.py#1116)

Comment 3

[Valentin Gosu [:valentin] (he/him)]

For some reason MOZ_NODE_PATH isn't set.
Based on when the failures started, bug 1581160 seems to be involved, but I don't see why it would affect any of the env vars.

https://treeherder.mozilla.org/#/jobs?repo=autoland&selectedJob=266950220&revision=2751ade4931f6ccadd26f014f0d1f6fbc2a8e4c4
https://treeherder.mozilla.org/#/jobs?repo=mozilla-central&revision=04da00a61c6df437250e3af0d20f8ec72bbe9f48&selectedJob=267011267

Comment 4

[Intermittent Failures Robot]

15 failures in 845 pushes (0.018 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 9
• mozilla-inbound: 6

Platform breakdown:

• linux64: 3
• windows10-64: 3
• windows7-32: 3
• linux64-shippable: 2
• windows7-32-shippable: 2
• windows10-64-shippable: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1581674&startday=2019-09-17&endday=2019-09-17&tree=all

Comment 5

[Valentin Gosu [:valentin] (he/him)]

I'm now fairly confident that the bug that introduces this orange is somewhere in this range:
https://hg.mozilla.org/integration/mozilla-inbound/pushloghtml?changeset=04da00a61c6df437250e3af0d20f8ec72bbe9f48

Comment 6

[Geoff Brown [:gbrown]]

Easy to reproduce on try: https://treeherder.mozilla.org/#/jobs?repo=try&tier=1%2C2%2C3&revision=9ba6131401bf9879a6e2899bd16be7335b937989

Comment 7

[Intermittent Failures Robot]

21 failures in 4350 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 6
• mozilla-central: 9
• mozilla-inbound: 6

Platform breakdown:

• linux64: 4
• windows10-64: 4
• windows7-32: 4
• linux64-shippable: 3
• windows10-64-shippable: 3
• windows7-32-shippable: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1581674&startday=2019-09-16&endday=2019-09-22&tree=all

Comment 8

[Intermittent Failures Robot]

15 failures in 870 pushes (0.017 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 4
• mozilla-central: 7
• mozilla-inbound: 4

Platform breakdown:

• windows10-64: 4
• windows7-32: 4
• windows7-32-shippable: 3
• windows10-64-shippable: 3
• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1581674&startday=2019-09-26&endday=2019-09-26&tree=all

Comment 9

[Geoff Brown [:gbrown]]

Bug 1584645 should resolve many of these failures, but the test still does not pass consistently in test-verify:

https://treeherder.mozilla.org/#/jobs?repo=try&tier=1%2C2%2C3&revision=3cb68fd0517ed24e290035def9cb8552d347b690

https://bugzilla.mozilla.org/show_bug.cgi?id=1584645#c2 might account for any ongoing failures.

Comment 10

[Andreea Pavel [:apavel]]

This seems fixed with bug 1582515. Feel free to reopen if otherwise.

Comment 11

[Intermittent Failures Robot]

17 failures in 4530 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 6
• mozilla-central: 7
• mozilla-inbound: 4

Platform breakdown:

• windows10-64: 4
• windows7-32: 4
• windows7-32-shippable: 3
• windows10-64-shippable: 3
• linux64: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1581674&startday=2019-09-23&endday=2019-09-29&tree=all

Comment 12

[Intermittent Failures Robot]

2 failures in 4850 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform breakdown:

• windows7-32: 1
• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1581674&startday=2019-11-04&endday=2019-11-10&tree=all