Closed Bug 1732489 Opened 4 years ago Closed 3 years ago

Intermittent damp | toolbox/screenshot.js: Error: Module `damp-test/tests/toolbox/screenshot.js` is not found at file:///C:/Users/task_1632500138/AppData/Local/Temp/tmpfzc6idf_/profile/extensions/bug1150215@mozilla.org">bug1150215@mozilla.org/content/tests/toolbox/screenshot.js

Categories

(DevTools :: General, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=352675546&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/IRnICrpmQfiGButuriwIyw/runs/0/artifacts/public/logs/live_backing.log


[task 2021-09-24T20:08:22.226Z] 20:08:22     INFO -  PID 996 | Open toolbox on 'inspector'
[task 2021-09-24T20:08:22.226Z] 20:08:22     INFO -  PID 996 | Open toolbox - Call showToolboxForTab
[task 2021-09-24T20:08:22.226Z] 20:08:22     INFO -  PID 996 | Open toolbox - Wait for "toolbox-created"
[task 2021-09-24T20:08:22.230Z] 20:08:22     INFO -  PID 996 | Open toolbox - Wait for showToolbox to resolve
[task 2021-09-24T20:08:23.435Z] 20:08:23     INFO -  PID 996 | Garbage collect
[task 2021-09-24T20:08:26.965Z] 20:08:26     INFO -  PID 996 | inspector/mutations.js took 4882ms.
[task 2021-09-24T20:08:26.965Z] 20:08:26     INFO -  PID 996 | Loading test 'inspector/layout.js'
[task 2021-09-24T20:08:26.965Z] 20:08:26     INFO -  PID 996 | Executing test 'inspector/layout.js'
[task 2021-09-24T20:08:26.972Z] 20:08:26     INFO -  PID 996 | Wait for a pageshow event for browsing context 198
[task 2021-09-24T20:08:27.013Z] 20:08:27     INFO -  PID 996 | Received pageshow event for 198
[task 2021-09-24T20:08:27.118Z] 20:08:27     INFO -  PID 996 | Open toolbox on 'inspector'
[task 2021-09-24T20:08:27.118Z] 20:08:27     INFO -  PID 996 | Open toolbox - Call showToolboxForTab
[task 2021-09-24T20:08:27.119Z] 20:08:27     INFO -  PID 996 | Open toolbox - Wait for "toolbox-created"
[task 2021-09-24T20:08:27.122Z] 20:08:27     INFO -  PID 996 | Open toolbox - Wait for showToolbox to resolve
[task 2021-09-24T20:08:27.402Z] 20:08:27     INFO -  PID 996 | Garbage collect
[task 2021-09-24T20:08:31.607Z] 20:08:31     INFO -  PID 996 | inspector/layout.js took 4642ms.
[task 2021-09-24T20:08:31.608Z] 20:08:31     INFO -  PID 996 | Loading test 'toolbox/panels-in-background.js'
[task 2021-09-24T20:08:31.608Z] 20:08:31     INFO -  PID 996 | Executing test 'toolbox/panels-in-background.js'
[task 2021-09-24T20:08:31.615Z] 20:08:31     INFO -  PID 996 | Wait for a pageshow event for browsing context 204
[task 2021-09-24T20:08:31.620Z] 20:08:31     INFO -  PID 996 | [Parent 7092, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2021-09-24T20:08:31.901Z] 20:08:31     INFO -  PID 996 | Received pageshow event for 204
[task 2021-09-24T20:08:32.142Z] 20:08:32     INFO -  PID 996 | Open toolbox on 'webconsole'
[task 2021-09-24T20:08:32.148Z] 20:08:32     INFO -  PID 996 | Open toolbox - Call showToolboxForTab
[task 2021-09-24T20:08:32.154Z] 20:08:32     INFO -  PID 996 | Open toolbox - Wait for "toolbox-created"
[task 2021-09-24T20:08:32.596Z] 20:08:32     INFO -  PID 996 | Open toolbox - Wait for showToolbox to resolve
[task 2021-09-24T20:08:33.892Z] 20:08:33     INFO -  PID 996 | Reload page on 'panelsInBackground'
[task 2021-09-24T20:08:33.893Z] 20:08:33     INFO -  PID 996 | Wait for a pageshow event for browsing context 204
[task 2021-09-24T20:08:34.503Z] 20:08:34     INFO -  PID 996 | Received pageshow event for 204
[task 2021-09-24T20:08:34.504Z] 20:08:34     INFO -  PID 996 | Wait for pending paints on 'panelsInBackground.reload'
[task 2021-09-24T20:08:34.504Z] 20:08:34     INFO -  PID 996 | 'panelsInBackground.reload.settle.DAMP' took 0.0703146546438802ms.
[task 2021-09-24T20:08:37.332Z] 20:08:37     INFO -  PID 996 | Garbage collect
[task 2021-09-24T20:08:41.259Z] 20:08:41     INFO -  PID 996 | toolbox/panels-in-background.js took 9651ms.
[task 2021-09-24T20:08:41.259Z] 20:08:41     INFO -  PID 996 | Loading test 'toolbox/screenshot.js'
[task 2021-09-24T20:08:41.260Z] 20:08:41     INFO -  PID 996 | TEST-UNEXPECTED-FAIL | damp | toolbox/screenshot.js: Error: Module `damp-test/tests/toolbox/screenshot.js` is not found at file:///C:/Users/task_1632500138/AppData/Local/Temp/tmpfzc6idf_/profile/extensions/bug1150215@mozilla.org/content/tests/toolbox/screenshot.js
[task 2021-09-24T20:08:41.264Z] 20:08:41     INFO -  Launcher process psutil.Process(pid=996, name='firefox.exe', started='20:05:59') detected. Terminating parent process psutil.Process(pid=7092, name='firefox.exe', started='20:05:59') instead.
[task 2021-09-24T20:08:41.264Z] 20:08:41     INFO -  Terminating psutil.Process(pid=7092, name='firefox.exe', started='20:05:59')
[task 2021-09-24T20:08:41.264Z] 20:08:41     INFO -  PID 996 | [DampLoad helper] Unregister DampLoad actors
[task 2021-09-24T20:08:41.265Z] 20:08:41     INFO -  PID 996 | load@resource://devtools/shared/base-loader.js:169:15
[task 2021-09-24T20:08:41.265Z] 20:08:41     INFO -  PID 996 | _require@resource://devtools/shared/base-loader.js:346:23
[task 2021-09-24T20:08:41.265Z] 20:08:41     INFO -  PID 996 | requireHook@resource://devtools/shared/Loader.jsm:112:14
[task 2021-09-24T20:08:41.266Z] 20:08:41     INFO -  PID 996 | require@resource://devtools/shared/base-loader.js:300:14
[task 2021-09-24T20:08:41.266Z] 20:08:41     INFO -  PID 996 | _runNextTest@file:///C:/Users/task_1632500138/AppData/Local/Temp/tmpfzc6idf_/profile/extensions/bug1150215@mozilla.org/content/damp.js:255:29
[task 2021-09-24T20:08:41.267Z] 20:08:41     INFO -  PID 996 | testTeardown@file:///C:/Users/task_1632500138/AppData/Local/Temp/tmpfzc6idf_/profile/extensions/bug1150215@mozilla.org/content/damp.js:223:10
[task 2021-09-24T20:08:41.267Z] 20:08:41     INFO -  PID 996 |
[task 2021-09-24T20:08:41.279Z] 20:08:41     INFO -  PID 996 | Exiting due to channel error.
[task 2021-09-24T20:08:41.605Z] 20:08:41     INFO -  mozcrash checking C:\Users\task_1632500138\AppData\Local\Temp\tmpfzc6idf_\profile\minidumps for minidumps...
[task 2021-09-24T20:08:41.606Z] 20:08:41     INFO -  TEST-UNEXPECTED-ERROR | damp | unexpected error
[task 2021-09-24T20:08:41.606Z] 20:08:41    ERROR -  Traceback (most recent call last):
[task 2021-09-24T20:08:41.606Z] 20:08:41     INFO -    File "C:\Users\task_1632500138\build\tests\talos\talos\run_tests.py", line 336, in run_tests
[task 2021-09-24T20:08:41.607Z] 20:08:41     INFO -      talos_results.add(mytest.runTest(browser_config, test))
[task 2021-09-24T20:08:41.607Z] 20:08:41     INFO -    File "C:\Users\task_1632500138\build\tests\talos\talos\ttest.py", line 65, in runTest
[task 2021-09-24T20:08:41.607Z] 20:08:41     INFO -      return self._runTest(browser_config, test_config, setup)
[task 2021-09-24T20:08:41.608Z] 20:08:41     INFO -    File "C:\Users\task_1632500138\build\tests\talos\talos\ttest.py", line 215, in _runTest
[task 2021-09-24T20:08:41.608Z] 20:08:41     INFO -      debugger_args=browser_config["debugger_args"],
[task 2021-09-24T20:08:41.608Z] 20:08:41     INFO -    File "C:\Users\task_1632500138\build\tests\talos\talos\talos_process.py", line 191, in run_browser
[task 2021-09-24T20:08:41.609Z] 20:08:41     INFO -      raise TalosError("unexpected error")
[task 2021-09-24T20:08:41.609Z] 20:08:41     INFO -  talos.utils.TalosError: unexpected error
[task 2021-09-24T20:08:41.609Z] 20:08:41     INFO -  TEST-INFO took 162271ms
[task 2021-09-24T20:08:41.609Z] 20:08:41     INFO -  SUITE-END | took 162s
[task 2021-09-24T20:08:41.645Z] 20:08:41     INFO -  Exception ignored in: <bound method ProcessHandlerMixin.Process.__del__ of <mozprocess.processhandler.ProcessHandlerMixin.Process object at 0x000001B46729B240>>
[task 2021-09-24T20:08:41.645Z] 20:08:41    ERROR -  Traceback (most recent call last):
[task 2021-09-24T20:08:41.645Z] 20:08:41     INFO -    File "C:\Users\task_1632500138\build\venv\lib\site-packages\mozprocess\processhandler.py", line 192, in __del__
[task 2021-09-24T20:08:41.646Z] 20:08:41     INFO -      self._internal_poll(_deadstate=_maxint)
[task 2021-09-24T20:08:41.646Z] 20:08:41     INFO -    File "c:\mozilla-build\python3\lib\subprocess.py", line 1035, in _internal_poll
[task 2021-09-24T20:08:41.646Z] 20:08:41     INFO -      if _WaitForSingleObject(self._handle, 0) == _WAIT_OBJECT_0:
[task 2021-09-24T20:08:41.647Z] 20:08:41    ERROR -  TypeError: WaitForSingleObject() argument 1 must be int, not AutoHANDLE
[task 2021-09-24T20:08:41.673Z] 20:08:41    ERROR - Return code: 2
[task 2021-09-24T20:08:41.673Z] 20:08:41  WARNING - setting return code to 2
[task 2021-09-24T20:08:41.673Z] 20:08:41    ERROR - # TBPL FAILURE #
[task 2021-09-24T20:08:41.673Z] 20:08:41     INFO - Running post-action listener: _package_coverage_data
[task 2021-09-24T20:08:41.673Z] 20:08:41     INFO - Running post-action listener: _resource_record_post_action
[task 2021-09-24T20:08:41.673Z] 20:08:41     INFO - Running post-action listener: process_java_coverage_data
[task 2021-09-24T20:08:41.674Z] 20:08:41     INFO - [mozharness: 2021-09-24 20:08:41.673161Z] Finished run-tests step (success)
[task 2021-09-24T20:08:41.674Z] 20:08:41     INFO - Running post-run listener: _resource_record_post_run
[task 2021-09-24T20:08:41.766Z] 20:08:41     INFO - Total resource usage - Wall time: 164s; CPU: 21%; Read bytes: 426581504; Write bytes: 406667264; Read time: 9; Write time: 5
[task 2021-09-24T20:08:41.766Z] 20:08:41     INFO - TinderboxPrint: CPU usage<br/>20.7%
[task 2021-09-24T20:08:41.766Z] 20:08:41     INFO - TinderboxPrint: I/O read bytes / time<br/>426,581,504 / 9
[task 2021-09-24T20:08:41.766Z] 20:08:41     INFO - TinderboxPrint: I/O write bytes / time<br/>406,667,264 / 5
[task 2021-09-24T20:08:41.767Z] 20:08:41     INFO - TinderboxPrint: CPU idle<br/>1,040.8 (79.2%)
[task 2021-09-24T20:08:41.767Z] 20:08:41     INFO - TinderboxPrint: CPU system<br/>52.5 (4.0%)
[task 2021-09-24T20:08:41.767Z] 20:08:41     INFO - TinderboxPrint: CPU user<br/>219.1 (16.7%)
[task 2021-09-24T20:08:41.767Z] 20:08:41     INFO - install - Wall time: 2s; CPU: 13%; Read bytes: 98304; Write bytes: 5758976; Read time: 0; Write time: 0
[task 2021-09-24T20:08:41.768Z] 20:08:41     INFO - run-tests - Wall time: 163s; CPU: 21%; Read bytes: 426068992; Write bytes: 373170176; Read time: 9; Write time: 5
[task 2021-09-24T20:08:41.816Z] 20:08:41  WARNING - returning nonzero exit status 2
[taskcluster 2021-09-24T20:08:41.858Z]    Exit Code: 2
[taskcluster 2021-09-24T20:08:41.858Z]    User Time: 0s
[taskcluster 2021-09-24T20:08:41.858Z]  Kernel Time: 0s
[taskcluster 2021-09-24T20:08:41.858Z]    Wall Time: 4m25.001021s
[taskcluster 2021-09-24T20:08:41.858Z]       Result: FAILED
[taskcluster 2021-09-24T20:08:41.858Z] === Task Finished ===
[taskcluster 2021-09-24T20:08:41.858Z] Task Duration: 4m25.001021s
[taskcluster 2021-09-24T20:08:41.941Z] Uploading artifact public/logs/localconfig.json from file logs\localconfig.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2022-09-24T18:49:28.205Z
[taskcluster 2021-09-24T20:08:42.125Z] Uploading artifact public/test_info/damp_errorsummary.log from file build\blobber_upload_dir\damp_errorsummary.log with content encoding "gzip", mime type "text/plain" and expiry 2022-09-24T18:49:28.205Z
[taskcluster 2021-09-24T20:08:42.232Z] Uploading artifact public/test_info/damp_raw.log from file build\blobber_upload_dir\damp_raw.log with content encoding "gzip", mime type "text/plain" and expiry 2022-09-24T18:49:28.205Z
[taskcluster 2021-09-24T20:08:42.323Z] 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-09-24T18:49:28.205Z
[taskcluster 2021-09-24T20:08:42.429Z] Uploading redirect artifact public/logs/live.log to URL https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/IRnICrpmQfiGButuriwIyw/runs/0/artifacts/public/logs/live_backing.log with mime type "text/plain; charset=utf-8" and expiry 2022-09-24T18:49:28.205Z
[taskcluster:error] exit status 2
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Component: Talos → General
Product: Testing → DevTools
Status: REOPENED → RESOLVED
Closed: 4 years ago3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.