1725924 - Intermittent TVg not running any tests (for view-source:?): ERROR - No checks run.

Status: RESOLVED INCOMPLETE

(Testing :: General, defect, P3)

Description

[Iulian Moraru]

Push were the failure appeared: https://treeherder.mozilla.org/jobs?repo=autoland&resultStatus=testfailed%2Cbusted%2Cexception%2Crunnable&revision=d75852f907a6cae2baad0bac15b4382fcda5bd48&selectedTaskRun=VkA-pA8dTSW8eILQD5hCiw.0

[task 2021-08-16T05:29:29.390Z] 05:29:29 INFO - REFTEST INFO | Reading manifest file://Z:/task_1629091193/build/tests/reftest/tests/parser/htmlparser/tests/reftest\reftest.list
[task 2021-08-16T05:29:29.393Z] 05:29:29 INFO - REFTEST INFO | Dumping JSON representation of sandbox
[task 2021-08-16T05:29:29.398Z] 05:29:29 INFO - REFTEST INFO | {"isDebugBuild":false,"isCoverageBuild":false,"xulRuntime":{"widgetToolkit":"windows","OS":"WINNT","XPCOMABI":"x86_64-msvc"},"smallScreen":false,"d2d":true,"dwrite":true,"embeddedInFirefoxReality":false,"gpuProcess":true,"azureCairo":false,"azureSkia":false,"skiaContent":true,"azureSkiaGL":false,"contentSameGfxBackendAsCanvas":false,"remoteCanvas":true,"layersGPUAccelerated":true,"d3d11":false,"d3d9":false,"layersOpenGL":false,"swgl":false,"webrender":true,"layersOMTC":true,"advancedLayers":false,"layerChecksEnabled":false,"usesOverlayScrollbars":false,"Android":false,"cocoaWidget":false,"gtkWidget":false,"qtWidget":false,"winWidget":true,"is64Bit":true,"useDrawSnapshot":false,"unsupportedWithDrawSnapshot":false,"retainedDisplayList":true,"geckoview":false,"transparentScrollbars":false,"MinGW":false,"AddressSanitizer":false,"ThreadSanitizer":false,"webrtc":true,"jxl":true,"retainedDisplayLists":true,"compareRetainedDisplayLists":false,"release_or_beta":false,"http":{"userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:93.0) Gecko/20100101 Firefox/93.0","appName":"Mozilla","appVersion":"5.0","platform":"Windows","oscpu":"Windows NT 10.0; Win64; x64","misc":"rv:93.0"},"appleSilicon":false,"windowsDefaultTheme":true,"nativeThemePref":false,"gpuProcessForceEnabled":false,"prefs":{},"browserIsRemote":true,"browserIsFission":false,"asyncPan":true,"usesRepeatResampling":true,"verify":true,"fission":false,"serviceWorkerE10s":true}
[task 2021-08-16T05:29:29.437Z] 05:29:29 INFO - REFTEST INFO | Slowest test took 0ms (undefined)
[task 2021-08-16T05:29:29.438Z] 05:29:29 INFO - REFTEST INFO | Total canvas count = 0
[task 2021-08-16T05:29:29.492Z] 05:29:29 INFO - 1629091769488 Marionette TRACE Received observer notification quit-application
[task 2021-08-16T05:29:29.492Z] 05:29:29 INFO - 1629091769488 Marionette INFO Stopped listening on port 2828
[task 2021-08-16T05:29:29.493Z] 05:29:29 INFO - 1629091769488 Marionette DEBUG Marionette stopped listening
[task 2021-08-16T05:29:29.598Z] 05:29:29 INFO - JavaScript error: resource:///modules/Interactions.jsm, line 230: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIUserIdleService.removeIdleObserver]
[task 2021-08-16T05:29:29.704Z] 05:29:29 INFO - ###!!! [Parent][MessageChannel] Error: (msgtype=0x390077,name=PContent::Msg_DestroyBrowsingContextGroup) Closed channel: cannot send/recv
[task 2021-08-16T05:29:30.412Z] 05:29:30 INFO - REFTEST INFO | Process mode: e10s
[task 2021-08-16T05:29:30.413Z] 05:29:30 INFO - REFTEST SUITE-START | Running 0 tests
[task 2021-08-16T05:29:30.414Z] 05:29:30 INFO - REFTEST INFO | Result summary:
[task 2021-08-16T05:29:30.414Z] 05:29:30 INFO - REFTEST INFO | Successful: 0 (0 pass, 0 load only)
[task 2021-08-16T05:29:30.414Z] 05:29:30 INFO - REFTEST INFO | Unexpected: 0 (0 unexpected fail, 0 unexpected pass, 0 unexpected asserts, 0 failed load, 0 exception)
[task 2021-08-16T05:29:30.414Z] 05:29:30 INFO - REFTEST INFO | Known problems: 0 (0 known fail, 0 known asserts, 0 random, 0 skipped, 0 slow)
[task 2021-08-16T05:29:30.415Z] 05:29:30 INFO - REFTEST SUITE-END | Shutdown
[task 2021-08-16T05:29:30.415Z] 05:29:30 INFO - REFTEST INFO | :::
[task 2021-08-16T05:29:30.416Z] 05:29:30 INFO - REFTEST INFO | ::: Test verification summary for:
[task 2021-08-16T05:29:30.417Z] 05:29:30 INFO - REFTEST INFO | :::
[task 2021-08-16T05:29:30.417Z] 05:29:30 INFO - REFTEST INFO | ::: Z:/task_1629091193/build/tests/reftest/tests/parser/htmlparser/tests/reftest/view-source:bug1319410-1.html
[task 2021-08-16T05:29:30.418Z] 05:29:30 INFO - REFTEST INFO | :::
[task 2021-08-16T05:29:30.419Z] 05:29:30 INFO - REFTEST INFO | ::: 1. Run each test 10 times in one browser. : Pass
[task 2021-08-16T05:29:30.419Z] 05:29:30 INFO - REFTEST INFO | ::: 2. Run each test 5 times in a new browser each time. : Pass
[task 2021-08-16T05:29:30.420Z] 05:29:30 INFO - REFTEST INFO | ::: 3. Run each test 10 times in one browser, in chaos mode. : Pass
[task 2021-08-16T05:29:30.421Z] 05:29:30 INFO - REFTEST INFO | ::: 4. Run each test 5 times in a new browser each time, in chaos mode. : Pass
[task 2021-08-16T05:29:30.421Z] 05:29:30 INFO - REFTEST INFO | :::
[task 2021-08-16T05:29:30.422Z] 05:29:30 INFO - REFTEST INFO | ::: Test verification PASSED
[task 2021-08-16T05:29:30.422Z] 05:29:30 INFO - REFTEST INFO | :::
[task 2021-08-16T05:29:30.481Z] 05:29:30 INFO - Return code: 0
[task 2021-08-16T05:29:30.482Z] 05:29:30 ERROR - No checks run.
[task 2021-08-16T05:29:30.482Z] 05:29:30 INFO - TinderboxPrint: reftest-reftest<br/><em class="testfail">T-FAIL</em>
[task 2021-08-16T05:29:30.483Z] 05:29:30 ERROR - # TBPL FAILURE #
[task 2021-08-16T05:29:30.483Z] 05:29:30 WARNING - setting return code to 2
[task 2021-08-16T05:29:30.483Z] 05:29:30 ERROR - TinderboxPrint: Per-test run of ...\view-source:bug1319410-1.html<br/>: FAILURE
[task 2021-08-16T05:29:30.483Z] 05:29:30 INFO - Per-test suites: {}
[task 2021-08-16T05:29:30.483Z] 05:29:30 INFO - Running post-action listener: _package_coverage_data
[task 2021-08-16T05:29:30.484Z] 05:29:30 INFO - Running post-action listener: _resource_record_post_action
[task 2021-08-16T05:29:30.484Z] 05:29:30 INFO - Running post-action listener: process_java_coverage_data
[task 2021-08-16T05:29:30.484Z] 05:29:30 INFO - [mozharness: 2021-08-16 05:29:30.484804Z] Finished run-tests step (success)
[task 2021-08-16T05:29:30.484Z] 05:29:30 INFO - Running post-run listener: _resource_record_post_run
[task 2021-08-16T05:29:30.624Z] 05:29:30 INFO - Validating Perfherder data against Z:\task_1629091193\mozharness\external_tools\performance-artifact-schema.json
[task 2021-08-16T05:29:30.628Z] 05:29:30 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": ".overall", "extraOptions": ["e10s", "taskcluster-g3s.xlarge"], "subtests": [{"name": "cpu_percent", "value": 66.58894736842105}, {"name": "io_write_bytes", "value": 519380992}, {"name": "io.read_bytes", "value": 73980928}, {"name": "io_write_time", "value": 33}, {"name": "io_read_time", "value": 9}]}, {"name": ".start-pulseaudio", "subtests": [{"name": "time", "value": 0.0}]}, {"name": ".install", "subtests": [{"name": "time", "value": 3.452955722808838}, {"name": "cpu_percent", "value": 37.583333333333336}]}, {"name": ".stage-files", "subtests": [{"name": "time", "value": 0.0010294914245605469}]}, {"name": ".run-tests", "subtests": [{"name": "time", "value": 92.38355898857117}, {"name": "cpu_percent", "value": 67.73571428571427}]}]}
[task 2021-08-16T05:29:30.628Z] 05:29:30 INFO - Total resource usage - Wall time: 95s; CPU: 68%; Read bytes: 73980928; Write bytes: 519380992; Read time: 9; Write time: 33
[task 2021-08-16T05:29:30.628Z] 05:29:30 INFO - TinderboxPrint: CPU usage<br/>67.7%
[task 2021-08-16T05:29:30.628Z] 05:29:30 INFO - TinderboxPrint: I/O read bytes / time<br/>73,980,928 / 9
[task 2021-08-16T05:29:30.629Z] 05:29:30 INFO - TinderboxPrint: I/O write bytes / time<br/>519,380,992 / 33
[task 2021-08-16T05:29:30.629Z] 05:29:30 INFO - TinderboxPrint: CPU idle<br/>128.0 (33.3%)
[task 2021-08-16T05:29:30.629Z] 05:29:30 INFO - TinderboxPrint: CPU system<br/>105.3 (27.4%)
[task 2021-08-16T05:29:30.629Z] 05:29:30 INFO - TinderboxPrint: CPU user<br/>147.3 (38.4%)
[task 2021-08-16T05:29:30.630Z] 05:29:30 INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2021-08-16T05:29:30.630Z] 05:29:30 INFO - install - Wall time: 3s; CPU: 38%; Read bytes: 45568; Write bytes: 35740672; Read time: 0; Write time: 2
[task 2021-08-16T05:29:30.630Z] 05:29:30 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2021-08-16T05:29:30.631Z] 05:29:30 INFO - run-tests - Wall time: 92s; CPU: 68%; Read bytes: 73648640; Write bytes: 483640320; Read time: 9; Write time: 31
[task 2021-08-16T05:29:30.673Z] 05:29:30 WARNING - returning nonzero exit status 2
[taskcluster 2021-08-16T05:29:30.766Z] Exit Code: 2
[taskcluster 2021-08-16T05:29:30.766Z] User Time: 0s
[taskcluster 2021-08-16T05:29:30.766Z] Kernel Time: 62.5ms
[taskcluster 2021-08-16T05:29:30.766Z] Wall Time: 5m44.3181941s
[taskcluster 2021-08-16T05:29:30.766Z] Result: FAILED
[taskcluster 2021-08-16T05:29:30.767Z] === Task Finished ===
[taskcluster 2021-08-16T05:29:30.768Z] Task Duration: 5m44.3211807s
[taskcluster 2021-08-16T05:29:30.924Z] Uploading artifact public/logs/localconfig.json from file logs\localconfig.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2022-08-16T05:06:33.372Z
[taskcluster 2021-08-16T05:29:31.055Z] Uploading artifact public/test_info/reftest-test1_errorsummary.log from file build\blobber_upload_dir\reftest-test1_errorsummary.log with content encoding "gzip", mime type "text/plain" and expiry 2022-08-16T05:06:33.372Z
[taskcluster 2021-08-16T05:29:31.118Z] Uploading artifact public/test_info/reftest-test1_raw.log from file build\blobber_upload_dir\reftest-test1_raw.log with content encoding "gzip", mime type "text/plain" and expiry 2022-08-16T05:06:33.372Z
[taskcluster 2021-08-16T05:29:31.207Z] 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 2022-08-16T05:06:33.372Z
[taskcluster 2021-08-16T05:29:31.322Z] 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 2022-08-16T05:06:33.372Z
[taskcluster 2021-08-16T05:29:31.437Z] Uploading redirect artifact public/logs/live.log to URL https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/VkA-pA8dTSW8eILQD5hCiw/runs/0/artifacts/public/logs/live_backing.log with mime type "text/plain; charset=utf-8" and expiry 2022-08-16T05:06:33.372Z
[taskcluster:error] exit status 2

Comment 1

[Marian-Vasile Laza]

It looks that they've started to appear from this push and are running/failing on your pushes - another push.

Henri could you please take a look?

Comment 2

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

Same issue - a test which uses the view-source protocol.

Comment 3

[Henri Sivonen (:hsivonen)]

The same reftest list has pre-existing view-source: tests. Have those shown the same problem?

Comment 4

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

The latest changes to these tests potentially predate the implementation of the test-verify suite which might have always been affected (no other CI reftest failure reported to Bugzilla for the last year which mentions view-source).

Comment 5

[Intermittent Failures Robot]

20 failures in 466 pushes (0.043 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 20

Platform and build breakdown:

• linux1804-64-qr: 4
  • debug: 2
  • opt: 2
• windows10-32-qr: 4
  • opt: 2
  • debug: 2
• linux1804-64-asan-qr: 2
  • opt: 2
• windows10-64-qr: 4
  • opt: 2
  • debug: 2
• macosx1015-64-qr: 4
  • opt: 2
  • debug: 2
• windows10-64-asan-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1725924&startday=2021-08-16&endday=2021-08-16&tree=all

Comment 6

[Henri Sivonen (:hsivonen)]

Thanks. I think it's extremely unlikely that the new tests caused a new problem. There is nothing special about them. The likely explanation is that there is a pre-existing problem with view-source in reftests.

Comment 7

[Intermittent Failures Robot]

30 failures in 2899 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

This is the #40 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 30

Platform and build breakdown:

• linux1804-64-qr: 6
  • debug: 3
  • opt: 3
• windows10-32-qr: 6
  • opt: 3
  • debug: 3
• linux1804-64-asan-qr: 3
  • opt: 3
• windows10-64-qr: 6
  • opt: 3
  • debug: 3
• macosx1015-64-qr: 6
  • opt: 3
  • debug: 3
• windows10-64-asan-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1725924&startday=2021-08-16&endday=2021-08-22&tree=all

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 10
• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-qr: 2
  • debug: 1
  • opt: 1
• windows10-32-2004-qr: 2
  • opt: 1
  • debug: 1
• windows10-64-2004-qr: 2
  • debug: 1
  • opt: 1
• linux1804-64-asan-qr: 1
  • opt: 1
• macosx1015-64-qr: 2
  • debug: 1
  • opt: 1
• windows10-64-2004-ccov-qr: 1
  • opt: 1
• windows10-64-2004-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1725924&startday=2021-08-30&endday=2021-09-05&tree=all

Comment 9

[Intermittent Failures Robot]

4 failures in 3319 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 4

Platform and build breakdown:

• linux1804-64-qr: 1
  • opt: 1
• macosx1015-64-qr: 2
  • debug: 1
  • opt: 1
• windows10-64-2004-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1725924&startday=2021-12-06&endday=2021-12-12&tree=all

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• macosx1015-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1725924&startday=2022-10-31&endday=2022-11-06&tree=all

Comment 11

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Comment 12

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=453662143&repo=try

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform and build breakdown:

• windows11-32-2009-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1725924&startday=2024-04-01&endday=2024-04-07&tree=all

Comment 14

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.