Closed Bug 1703478 Opened 4 years ago Closed 4 years ago

Intermittent [TV] TEST-UNEXPECTED-TIMEOUT | netwerk/test/unit_ipc/test_dns_by_type_resolve_wrap.js | Test timed out

Categories

(Core :: Networking, defect, P3)

Product:
Core
Component:
Networking
Type:
defect

Tracking

()

Status:
RESOLVED WORKSFORME
Tracking Flags:
Tracking Status
firefox-esr78 --- unaffected
firefox87 --- unaffected
firefox88 --- unaffected
firefox89 --- affected

People

(Reporter: intermittent-bug-filer, Assigned: valentin)

References

(Regression)

Details

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

Filed by: dluca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=335674236&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/N2rsUqp-QMifScF3pCxf6A/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://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/N2rsUqp-QMifScF3pCxf6A/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

INFO -  TEST-START | netwerk/test/unit/test_dns_by_type_resolve.js
[task 2021-04-07T08:20:59.564Z] 08:20:59     INFO -  TEST-PASS | netwerk/test/unit/test_dns_by_type_resolve.js | took 475ms
[task 2021-04-07T08:20:59.574Z] 08:20:59     INFO -  INFO | Result summary:
[task 2021-04-07T08:20:59.574Z] 08:20:59     INFO -  INFO | Passed: 20
[task 2021-04-07T08:20:59.574Z] 08:20:59     INFO -  INFO | Failed: 0
[task 2021-04-07T08:20:59.574Z] 08:20:59     INFO -  INFO | Todo: 0
[task 2021-04-07T08:20:59.574Z] 08:20:59     INFO -  INFO | Retried: 0
[task 2021-04-07T08:20:59.574Z] 08:20:59     INFO -  SUITE-END | took 5s
[task 2021-04-07T08:20:59.574Z] 08:20:59     INFO -  :::
[task 2021-04-07T08:20:59.574Z] 08:20:59     INFO -  ::: Test verification summary for: Z:\task_1617783105\build\tests\xpcshell\tests\netwerk\test\unit\test_dns_by_type_resolve.js
[task 2021-04-07T08:20:59.574Z] 08:20:59     INFO -  :::
[task 2021-04-07T08:20:59.574Z] 08:20:59     INFO -  ::: 1. Run each test 10 times, sequentially. : Pass
[task 2021-04-07T08:20:59.575Z] 08:20:59     INFO -  ::: 2. Run each test 10 times, sequentially, in chaos mode. : Pass
[task 2021-04-07T08:20:59.575Z] 08:20:59     INFO -  :::
[task 2021-04-07T08:20:59.575Z] 08:20:59     INFO -  ::: Test verification PASSED
[task 2021-04-07T08:20:59.575Z] 08:20:59     INFO -  :::
[task 2021-04-07T08:20:59.575Z] 08:20:59     INFO -  Node moz-http2 server shutting down ...
[task 2021-04-07T08:20:59.609Z] 08:20:59     INFO - Return code: 0
[task 2021-04-07T08:20:59.609Z] 08:20:59     INFO - TinderboxPrint: xpcshell-xpcshell<br/>20/0/0
[task 2021-04-07T08:20:59.609Z] 08:20:59     INFO - # TBPL SUCCESS #
[task 2021-04-07T08:20:59.609Z] 08:20:59     INFO - TinderboxPrint: Per-test run of ...\unit\test_dns_by_type_resolve.js<br/>: SUCCESS
[task 2021-04-07T08:20:59.609Z] 08:20:59     INFO - Running command: ['Z:\\task_1617783105\\build\\venv\\Scripts\\python', '-u', 'Z:\\task_1617783105\\build\\tests\\xpcshell\\runxpcshelltests.py', '--enable-webrender', '--setpref=fission.autostart=true', '--setpref=dom.serviceWorkers.parent_intercept=true', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--setpref=layers.d3d11.enable-blacklist=false', '--self-test', '--symbols-path=Z:\\task_1617783105\\build\\symbols', '--test-plugin-path=Z:\\task_1617783105\\build\\application\\firefox\\plugins', '--log-raw=Z:\\task_1617783105\\build\\blobber_upload_dir\\xpcshell-test4_raw.log', '--log-errorsummary=Z:\\task_1617783105\\build\\blobber_upload_dir\\xpcshell-test4_errorsummary.log', '--utility-path=tests/bin', '--xpcshell=Z:\\task_1617783105\\build\\application\\firefox/xpcshell.exe', '--manifest=tests/xpcshell/tests/xpcshell.ini', '--verify-max-time=900', '--verify', u'netwerk/test/unit_ipc/test_dns_by_type_resolve_wrap.js'] in Z:\task_1617783105\build
[task 2021-04-07T08:20:59.610Z] 08:20:59     INFO - Copy/paste: Z:\task_1617783105\build\venv\Scripts\python -u Z:\task_1617783105\build\tests\xpcshell\runxpcshelltests.py --enable-webrender --setpref=fission.autostart=true --setpref=dom.serviceWorkers.parent_intercept=true --setpref=media.peerconnection.mtransport_process=false --setpref=network.process.enabled=false --setpref=layers.d3d11.enable-blacklist=false --self-test --symbols-path=Z:\task_1617783105\build\symbols --test-plugin-path=Z:\task_1617783105\build\application\firefox\plugins --log-raw=Z:\task_1617783105\build\blobber_upload_dir\xpcshell-test4_raw.log --log-errorsummary=Z:\task_1617783105\build\blobber_upload_dir\xpcshell-test4_errorsummary.log --utility-path=tests/bin --xpcshell=Z:\task_1617783105\build\application\firefox/xpcshell.exe --manifest=tests/xpcshell/tests/xpcshell.ini --verify-max-time=900 --verify netwerk/test/unit_ipc/test_dns_by_type_resolve_wrap.js
[task 2021-04-07T08:20:59.610Z] 08:20:59     INFO - Using env: (same as previous command)
[task 2021-04-07T08:20:59.610Z] 08:20:59     INFO - Calling ['Z:\\task_1617783105\\build\\venv\\Scripts\\python', '-u', 'Z:\\task_1617783105\\build\\tests\\xpcshell\\runxpcshelltests.py', '--enable-webrender', '--setpref=fission.autostart=true', '--setpref=dom.serviceWorkers.parent_intercept=true', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--setpref=layers.d3d11.enable-blacklist=false', '--self-test', '--symbols-path=Z:\\task_1617783105\\build\\symbols', '--test-plugin-path=Z:\\task_1617783105\\build\\application\\firefox\\plugins', '--log-raw=Z:\\task_1617783105\\build\\blobber_upload_dir\\xpcshell-test4_raw.log', '--log-errorsummary=Z:\\task_1617783105\\build\\blobber_upload_dir\\xpcshell-test4_errorsummary.log', '--utility-path=tests/bin', '--xpcshell=Z:\\task_1617783105\\build\\application\\firefox/xpcshell.exe', '--manifest=tests/xpcshell/tests/xpcshell.ini', '--verify-max-time=900', '--verify', u'netwerk/test/unit_ipc/test_dns_by_type_resolve_wrap.js'] with output_timeout 1000
[task 2021-04-07T08:21:00.524Z] 08:21:00     INFO -  testAddTaskRunNextTest (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:01.174Z] 08:21:01     INFO -  testAddTaskSkip (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:01.804Z] 08:21:01     INFO -  testAddTaskSkipAll (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:02.443Z] 08:21:02     INFO -  testAddTaskStackTrace (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:03.072Z] 08:21:03     INFO -  testAddTaskTestFailureInside (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:03.704Z] 08:21:03     INFO -  testAddTaskTestMultiple (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:04.344Z] 08:21:04     INFO -  testAddTaskTestRejected (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:04.982Z] 08:21:04     INFO -  testAddTaskTestSingle (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:05.612Z] 08:21:05     INFO -  testAddTestFailing (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:06.260Z] 08:21:06     INFO -  testAddTestSimple (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:06.893Z] 08:21:06     INFO -  testAddTestUncaughtRejection (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:07.582Z] 08:21:07     INFO -  testAddTestUncaughtRejectionJSM (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:07.582Z] 08:21:07     INFO -  testAssertStack (__main__.XPCShellTestsTests) ... skipped "We don't have a stack fixer on hand for windows."
[task 2021-04-07T08:21:08.222Z] 08:21:08     INFO -  testAsyncCleanup (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:09.034Z] 08:21:09     INFO -  testChild (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:09.888Z] 08:21:09     INFO -  testChildFail (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:10.706Z] 08:21:10     INFO -  testChildHang (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:11.557Z] 08:21:11     INFO -  testChildMozinfo (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:12.386Z] 08:21:12     INFO -  testChildPass (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:18.796Z] 08:21:18     INFO -  testCrashLogging (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:19.438Z] 08:21:19     INFO -  testDoPrintWhenVerboseExplicit (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:20.097Z] 08:21:20     INFO -  testDoPrintWhenVerboseInManifest (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:20.757Z] 08:21:20     INFO -  testDoPrintWhenVerboseNotExplicit (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:21.403Z] 08:21:21     INFO -  testDoReportForeignObject (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:22.061Z] 08:21:22     INFO -  testDoReportNonSyntaxError (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:22.730Z] 08:21:22     INFO -  testDoReportRefError (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:23.370Z] 08:21:23     INFO -  testDoReportSyntaxError (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:24.026Z] 08:21:24     INFO -  testDoThrowForeignObject (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:24.680Z] 08:21:24     INFO -  testDoThrowString (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:25.355Z] 08:21:25     INFO -  testFail (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:25.355Z] 08:21:25     INFO -  testHangingTimeout (__main__.XPCShellTestsTests) ... skipped 'Timeout code path occasionally times out (bug 1098121)'
[task 2021-04-07T08:21:26.030Z] 08:21:26     INFO -  testHeadlessWhenHeadlessExplicit (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:26.684Z] 08:21:26     INFO -  testHeadlessWhenHeadlessTrueInManifest (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:27.332Z] 08:21:27     INFO -  testKnownFail (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:28.001Z] 08:21:28     INFO -  testLogCorrectFileName (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:28.428Z] 08:21:28     INFO -  testMissingHeadFile (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:29.080Z] 08:21:29     INFO -  testMozinfo (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:29.740Z] 08:21:29     INFO -  testNoRunTestAddTask (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:30.432Z] 08:21:30     INFO -  testNoRunTestAddTaskFail (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:31.101Z] 08:21:31     INFO -  testNoRunTestAddTaskMultiple (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:31.793Z] 08:21:31     INFO -  testNoRunTestAddTest (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:32.452Z] 08:21:32     INFO -  testNoRunTestAddTestAddTask (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:33.139Z] 08:21:33     INFO -  testNoRunTestAddTestFail (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:33.805Z] 08:21:33     INFO -  testNoRunTestEmptyTest (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:34.497Z] 08:21:34     INFO -  testNotHeadlessByDefault (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:35.170Z] 08:21:35     INFO -  testNotHeadlessWhenHeadlessFalseInManifest (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:35.869Z] 08:21:35     INFO -  testNotSkipForAddTask (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:36.556Z] 08:21:36     INFO -  testNotSkipForAddTest (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:37.247Z] 08:21:37     INFO -  testPass (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:38.359Z] 08:21:38     INFO -  testPassFail (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:39.008Z] 08:21:39     INFO -  testPrefsInManifestNonVerbose (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:39.661Z] 08:21:39     INFO -  testPrefsInManifestVerbose (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:44.365Z] 08:21:44     INFO -  testRandomExecution (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:45.055Z] 08:21:45     INFO -  testReturnNonzero (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:45.277Z] 08:21:45     INFO -  testSkip (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:45.983Z] 08:21:45     INFO -  testSkipForAddTask (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:46.679Z] 08:21:46     INFO -  testSkipForAddTest (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:47.389Z] 08:21:47     INFO -  testSyntaxError (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:48.089Z] 08:21:48     INFO -  testUncaughtRejection (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:48.767Z] 08:21:48     INFO -  testUncaughtRejectionJSM (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:49.452Z] 08:21:49     INFO -  testUnexpectedPass (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:50.142Z] 08:21:50     INFO -  testUnicodeInAssertMethods (__main__.XPCShellTestsTests) ... ok
[task 2021-04-07T08:21:50.142Z] 08:21:50     INFO -  ----------------------------------------------------------------------
[task 2021-04-07T08:21:50.142Z] 08:21:50     INFO -  Ran 62 tests in 50.269s
[task 2021-04-07T08:21:50.142Z] 08:21:50     INFO -  OK (skipped=2)
[task 2021-04-07T08:21:50.142Z] 08:21:50     INFO -  Found node at Z:/task_1617783105/fetches\node/node.exe
[task 2021-04-07T08:21:50.142Z] 08:21:50     INFO -  Found moz-http2 at Z:\task_1617783105\build\tests\xpcshell\moz-http2\moz-http2.js
[task 2021-04-07T08:21:50.362Z] 08:21:50     INFO -  Http3 server not found at Z:\task_1617783105\build\tests\xpcshell\http3server\http3server.exe. Tests requiring http/3 will fail.
[task 2021-04-07T08:21:50.777Z] 08:21:50     INFO -  The following path(s) didn't resolve any tests:
[task 2021-04-07T08:21:50.777Z] 08:21:50     INFO -    netwerk/test/unit_ipc/test_dns_by_type_resolve_wrap.js
[task 2021-04-07T08:21:50.777Z] 08:21:50     INFO -  :::
[task 2021-04-07T08:21:50.777Z] 08:21:50     INFO -  ::: Running test verification step "1. Run each test 10 times, sequentially."...
[task 2021-04-07T08:21:50.777Z] 08:21:50     INFO -  :::
[task 2021-04-07T08:21:50.777Z] 08:21:50     INFO -  Using at most 32 threads.
[task 2021-04-07T08:21:50.778Z] 08:21:50     INFO -  SUITE-START | Running 1 tests
[task 2021-04-07T08:21:50.788Z] 08:21:50     INFO -  TEST-START | netwerk/test/unit_ipc/test_dns_by_type_resolve_wrap.js
[task 2021-04-07T08:26:50.787Z] 08:26:50  WARNING -  TEST-UNEXPECTED-TIMEOUT | netwerk/test/unit_ipc/test_dns_by_type_resolve_wrap.js | Test timed out
[task 2021-04-07T08:26:50.787Z] 08:26:50     INFO -  TEST-INFO took 300000ms
[task 2021-04-07T08:26:50.788Z] 08:26:50     INFO -  >>>>>>>
[task 2021-04-07T08:26:50.788Z] 08:26:50     INFO -  PID 9192 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2021-04-07T08:26:50.789Z] 08:26:50     INFO -  PID 9192 | [Parent 9192, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-04-07T08:26:50.789Z] 08:26:50     INFO -  PID 9192 | [Parent 9192, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-04-07T08:26:50.789Z] 08:26:50     INFO -  PID 9192 | [Parent 9192, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-04-07T08:26:50.790Z] 08:26:50     INFO -  PID 9192 | [Parent 9192, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-04-07T08:26:50.790Z] 08:26:50     INFO -  PID 9192 | [Parent 9192, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:2896
[task 2021-04-07T08:26:50.790Z] 08:26:50     INFO -  TEST-PASS | netwerk/test/unit_ipc/test_dns_by_type_resolve_wrap.js | setup - [setup : 12] "51605" != null
[task 2021-04-07T08:26:50.791Z] 08:26:50     INFO -  TEST-PASS | netwerk/test/unit_ipc/test_dns_by_type_resolve_wrap.js | setup - [setup : 13] "51605" != ""```
Assignee: nobody → valentin.gosu
Priority: P5 → P3
Whiteboard: [necko-triaged]

Set release status flags based on info from the regressing bug 1700076

status-firefox87: --- → unaffected
status-firefox88: --- → unaffected
status-firefox89: --- → affected
status-firefox-esr78: --- → unaffected

Hasn't happened in a while. I assume the issue was fixed.

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → WORKSFORME
Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.