Closed Bug 1689606 Opened 5 years ago Closed 4 years ago

Intermittent TEST-UNEXPECTED-FAIL | damp | netmonitor/complicated.js: Error: Module `*/complicated.js` is not found at file:///C:/Users/task_1611918337/AppData/Local/Temp/tmpcia7vt/profile/extensions/bug1150215@*/content/tests/netmonitor/complicated.js

Categories

(Testing :: Talos, defect, P5)

Product:
Testing
Component:
Talos
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2021-01-29T11:27:22.979Z] 11:27:22     INFO -  PID 5084 | Loading test 'webconsole/complicated.js'
[task 2021-01-29T11:27:22.979Z] 11:27:22     INFO -  PID 5084 | Executing test 'webconsole/complicated.js'
[task 2021-01-29T11:27:22.989Z] 11:27:22     INFO -  PID 5084 | Wait for a pageshow event for browsing context 112
[task 2021-01-29T11:27:23.853Z] 11:27:23     INFO -  PID 5084 | Received pageshow event for 112
[task 2021-01-29T11:27:23.951Z] 11:27:23     INFO -  PID 5084 | Open toolbox on 'webconsole'
[task 2021-01-29T11:27:23.951Z] 11:27:23     INFO -  PID 5084 | Open toolbox - Wait for tab target
[task 2021-01-29T11:27:23.956Z] 11:27:23     INFO -  PID 5084 | Open toolbox - Call showToolbox
[task 2021-01-29T11:27:23.956Z] 11:27:23     INFO -  PID 5084 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-29T11:27:23.961Z] 11:27:23     INFO -  PID 5084 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-29T11:27:24.252Z] 11:27:24     INFO -  PID 5084 | Wait for pending paints on 'complicated.webconsole.open'
[task 2021-01-29T11:27:24.278Z] 11:27:24     INFO -  PID 5084 | 'complicated.webconsole.open.settle.DAMP' took 28.647048442991945ms.
[task 2021-01-29T11:27:24.278Z] 11:27:24     INFO -  PID 5084 | Garbage collect
[task 2021-01-29T11:27:25.088Z] 11:27:25     INFO -  PID 5084 | Reload page on 'complicated.webconsole'
[task 2021-01-29T11:27:26.793Z] 11:27:26     INFO -  PID 5084 | Wait for pending paints on 'complicated.webconsole.reload'
[task 2021-01-29T11:27:26.815Z] 11:27:26     INFO -  PID 5084 | 'complicated.webconsole.reload.settle.DAMP' took 25.535798826564132ms.
[task 2021-01-29T11:27:26.815Z] 11:27:26     INFO -  PID 5084 | Close toolbox on 'complicated.webconsole'
[task 2021-01-29T11:27:26.840Z] 11:27:26     INFO -  PID 5084 | console.warn: "IGNORED REDUX ACTION:" "AUTOCOMPLETE_CLEAR"
[task 2021-01-29T11:27:26.842Z] 11:27:26     INFO -  PID 5084 | Garbage collect
[task 2021-01-29T11:27:27.691Z] 11:27:27     INFO -  PID 5084 | webconsole/complicated.js took 4713ms.
[task 2021-01-29T11:27:27.691Z] 11:27:27     INFO -  PID 5084 | Loading test 'inspector/complicated.js'
[task 2021-01-29T11:27:27.691Z] 11:27:27     INFO -  PID 5084 | Executing test 'inspector/complicated.js'
[task 2021-01-29T11:27:27.701Z] 11:27:27     INFO -  PID 5084 | Wait for a pageshow event for browsing context 117
[task 2021-01-29T11:27:28.811Z] 11:27:28     INFO -  PID 5084 | Received pageshow event for 117
[task 2021-01-29T11:27:28.912Z] 11:27:28     INFO -  PID 5084 | Open toolbox on 'inspector'
[task 2021-01-29T11:27:28.912Z] 11:27:28     INFO -  PID 5084 | Open toolbox - Wait for tab target
[task 2021-01-29T11:27:28.962Z] 11:27:28     INFO -  PID 5084 | Open toolbox - Call showToolbox
[task 2021-01-29T11:27:28.962Z] 11:27:28     INFO -  PID 5084 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-29T11:27:28.967Z] 11:27:28     INFO -  PID 5084 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-29T11:27:29.399Z] 11:27:29     INFO -  PID 5084 | Wait for pending paints on 'complicated.inspector.open'
[task 2021-01-29T11:27:29.449Z] 11:27:29     INFO -  PID 5084 | 'complicated.inspector.open.settle.DAMP' took 51.877484690365236ms.
[task 2021-01-29T11:27:29.449Z] 11:27:29     INFO -  PID 5084 | Garbage collect
[task 2021-01-29T11:27:29.871Z] 11:27:29     INFO -  PID 5084 | Reload page on 'complicated.inspector'
[task 2021-01-29T11:27:29.910Z] 11:27:29     INFO -  PID 5084 | console.error: "Error while calling actor 'pagestyle's method 'getUsedFontFaces'" "can't access property \"rawNode\", node is null"
[task 2021-01-29T11:27:29.910Z] 11:27:29     INFO -  PID 5084 | console.error: "getUsedFontFaces@resource://devtools/server/actors/page-style.js:368:24\nhandler@resource://devtools/shared/protocol/Actor.js:172:37\nonPacket@resource://devtools/server/devtools-server-connection.js:379:58\nreceiveMessage@resource://devtools/shared/transport/child-transport.js:68:16\n"
[task 2021-01-29T11:27:29.910Z] 11:27:29     INFO -  PID 5084 | console.error: "Could not get the list of font families" (new Error("Protocol error (TypeError): can't access property \"rawNode\", node is null from: server0.conn12.child3/pagestyle37 (resource://devtools/server/actors/page-style.js:368:24)", "resource://devtools/shared/protocol/Front.js", 361))
[task 2021-01-29T11:27:30.635Z] 11:27:30     INFO -  PID 5084 | Wait for pending paints on 'complicated.inspector.reload'
[task 2021-01-29T11:27:30.635Z] 11:27:30     INFO -  PID 5084 | 'complicated.inspector.reload.settle.DAMP' took 0.036522623035125434ms.
[task 2021-01-29T11:27:30.635Z] 11:27:30     INFO -  PID 5084 | Close toolbox on 'complicated.inspector'
[task 2021-01-29T11:27:30.720Z] 11:27:30     INFO -  PID 5084 | Garbage collect
[task 2021-01-29T11:27:31.043Z] 11:27:31     INFO -  PID 5084 | inspector/complicated.js took 3353ms.
[task 2021-01-29T11:27:31.043Z] 11:27:31     INFO -  PID 5084 | Loading test 'debugger/complicated.js'
[task 2021-01-29T11:27:31.043Z] 11:27:31     INFO -  PID 5084 | Executing test 'debugger/complicated.js'
[task 2021-01-29T11:27:31.062Z] 11:27:31     INFO -  PID 5084 | Wait for a pageshow event for browsing context 123
[task 2021-01-29T11:27:31.866Z] 11:27:31     INFO -  PID 5084 | Received pageshow event for 123
[task 2021-01-29T11:27:31.965Z] 11:27:31     INFO -  PID 5084 | Open toolbox on 'jsdebugger'
[task 2021-01-29T11:27:31.965Z] 11:27:31     INFO -  PID 5084 | Open toolbox - Wait for tab target
[task 2021-01-29T11:27:31.965Z] 11:27:31     INFO -  PID 5084 | Open toolbox - Call showToolbox
[task 2021-01-29T11:27:31.965Z] 11:27:31     INFO -  PID 5084 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-29T11:27:31.970Z] 11:27:31     INFO -  PID 5084 | Open toolbox - Wait for custom onLoad callback
[task 2021-01-29T11:27:32.321Z] 11:27:32     INFO -  PID 5084 | Waiting for state change: has source http://www.bild.de-talos/fis/tp5n/bild.de/www.bild.de/index.html
[task 2021-01-29T11:27:32.321Z] 11:27:32     INFO -  PID 5084 | Finished waiting for state change: has source http://www.bild.de-talos/fis/tp5n/bild.de/www.bild.de/index.html
[task 2021-01-29T11:27:32.321Z] 11:27:32     INFO -  PID 5084 | Selecting source: ga.js
[task 2021-01-29T11:27:32.361Z] 11:27:32     INFO -  PID 5084 | Waiting for state change: selected source
[task 2021-01-29T11:27:32.866Z] 11:27:32     INFO -  PID 5084 | Finished waiting for state change: selected source
[task 2021-01-29T11:27:32.866Z] 11:27:32     INFO -  PID 5084 | Waiting until: text is visible
[task 2021-01-29T11:27:32.916Z] 11:27:32     INFO -  PID 5084 | Finished Waiting until: text is visible
[task 2021-01-29T11:27:32.916Z] 11:27:32     INFO -  PID 5084 | Waiting until: has file metadata
[task 2021-01-29T11:27:32.976Z] 11:27:32     INFO -  PID 5084 | Finished Waiting until: has file metadata
[task 2021-01-29T11:27:32.976Z] 11:27:32     INFO -  PID 5084 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-29T11:27:32.976Z] 11:27:32     INFO -  PID 5084 | Wait for pending paints on 'complicated.jsdebugger.open'
[task 2021-01-29T11:27:32.976Z] 11:27:32     INFO -  PID 5084 | 'complicated.jsdebugger.open.settle.DAMP' took 0.2768210026224551ms.
[task 2021-01-29T11:27:32.977Z] 11:27:32     INFO -  PID 5084 | Garbage collect
[task 2021-01-29T11:27:33.296Z] 11:27:33     INFO -  PID 5084 | Reload page on 'complicated.jsdebugger'
[task 2021-01-29T11:27:33.335Z] 11:27:33     INFO -  PID 5084 | Waiting for state change: count sources
[task 2021-01-29T11:27:33.555Z] 11:27:33     INFO -  PID 5084 | Finished waiting for state change: count sources
[task 2021-01-29T11:27:33.565Z] 11:27:33     INFO -  PID 5084 | Waiting until: text is visible
[task 2021-01-29T11:27:34.211Z] 11:27:34     INFO -  PID 5084 | Finished Waiting until: text is visible
[task 2021-01-29T11:27:34.211Z] 11:27:34     INFO -  PID 5084 | Waiting until: has file metadata
[task 2021-01-29T11:27:34.663Z] 11:27:34     INFO -  PID 5084 | Finished Waiting until: has file metadata
[task 2021-01-29T11:27:34.665Z] 11:27:34     INFO -  PID 5084 | Wait for pending paints on 'complicated.jsdebugger.reload'
[task 2021-01-29T11:27:34.665Z] 11:27:34     INFO -  PID 5084 | 'complicated.jsdebugger.reload.settle.DAMP' took 0.06417059000523295ms.
[task 2021-01-29T11:27:34.667Z] 11:27:34     INFO -  PID 5084 | Close toolbox on 'complicated.jsdebugger'
[task 2021-01-29T11:27:34.726Z] 11:27:34     INFO -  PID 5084 | Garbage collect
[task 2021-01-29T11:27:35.208Z] 11:27:35     INFO -  PID 5084 | debugger/complicated.js took 4157ms.
[task 2021-01-29T11:27:35.208Z] 11:27:35     INFO -  PID 5084 | Loading test 'styleeditor/complicated.js'
[task 2021-01-29T11:27:35.208Z] 11:27:35     INFO -  PID 5084 | Executing test 'styleeditor/complicated.js'
[task 2021-01-29T11:27:35.213Z] 11:27:35     INFO -  PID 5084 | Wait for a pageshow event for browsing context 128
[task 2021-01-29T11:27:36.271Z] 11:27:36     INFO -  PID 5084 | Received pageshow event for 128
[task 2021-01-29T11:27:36.371Z] 11:27:36     INFO -  PID 5084 | Open toolbox on 'styleeditor'
[task 2021-01-29T11:27:36.371Z] 11:27:36     INFO -  PID 5084 | Open toolbox - Wait for tab target
[task 2021-01-29T11:27:36.441Z] 11:27:36     INFO -  PID 5084 | Open toolbox - Call showToolbox
[task 2021-01-29T11:27:36.441Z] 11:27:36     INFO -  PID 5084 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-29T11:27:36.441Z] 11:27:36     INFO -  PID 5084 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-29T11:27:37.144Z] 11:27:37     INFO -  PID 5084 | Wait for pending paints on 'complicated.styleeditor.open'
[task 2021-01-29T11:27:37.144Z] 11:27:37     INFO -  PID 5084 | 'complicated.styleeditor.open.settle.DAMP' took 0.2283517271935125ms.
[task 2021-01-29T11:27:37.144Z] 11:27:37     INFO -  PID 5084 | Garbage collect
[task 2021-01-29T11:27:37.625Z] 11:27:37     INFO -  PID 5084 | Reload page on 'complicated.styleeditor'
[task 2021-01-29T11:27:37.625Z] 11:27:37     INFO -  PID 5084 | Wait for a pageshow event for browsing context 128
[task 2021-01-29T11:27:39.446Z] 11:27:39     INFO -  PID 5084 | Received pageshow event for 128
[task 2021-01-29T11:27:39.446Z] 11:27:39     INFO -  PID 5084 | Wait for pending paints on 'complicated.styleeditor.reload'
[task 2021-01-29T11:27:39.446Z] 11:27:39     INFO -  PID 5084 | 'complicated.styleeditor.reload.settle.DAMP' took 0.045738612025161274ms.
[task 2021-01-29T11:27:39.446Z] 11:27:39     INFO -  PID 5084 | Close toolbox on 'complicated.styleeditor'
[task 2021-01-29T11:27:39.472Z] 11:27:39     INFO -  PID 5084 | console.error: (new TypeError("this.browserRequire is not a function", "resource://devtools/client/framework/toolbox.js", 1258))
[task 2021-01-29T11:27:39.472Z] 11:27:39     INFO -  PID 5084 | console.error: (new TypeError("can't access property \"has\", this._seenSheets is null", "resource://devtools/client/styleeditor/StyleEditorUI.jsm", 317))
[task 2021-01-29T11:27:39.472Z] 11:27:39     INFO -  PID 5084 | console.error: (new TypeError("can't access property \"has\", this._seenSheets is null", "resource://devtools/client/styleeditor/StyleEditorUI.jsm", 317))
[task 2021-01-29T11:27:39.491Z] 11:27:39     INFO -  PID 5084 | console.error: (new TypeError("can't access property \"has\", this._seenSheets is null", "resource://devtools/client/styleeditor/StyleEditorUI.jsm", 317))
[task 2021-01-29T11:27:39.501Z] 11:27:39     INFO -  PID 5084 | Garbage collect
[task 2021-01-29T11:27:39.913Z] 11:27:39     INFO -  PID 5084 | styleeditor/complicated.js took 4710ms.
[task 2021-01-29T11:27:39.913Z] 11:27:39     INFO -  PID 5084 | Loading test 'netmonitor/complicated.js'
[task 2021-01-29T11:27:39.913Z] 11:27:39     INFO -  PID 5084 | TEST-UNEXPECTED-FAIL | damp | netmonitor/complicated.js: Error: Module `damp-test/tests/netmonitor/complicated.js` is not found at file:///C:/Users/task_1611918337/AppData/Local/Temp/tmpcia7vt/profile/extensions/bug1150215@mozilla.org/content/tests/netmonitor/complicated.js
[task 2021-01-29T11:27:39.913Z] 11:27:39     INFO -  PID 5084 | [DampLoad helper] Unregister DampLoad actors
[task 2021-01-29T11:27:39.913Z] 11:27:39     INFO -  PID 5084 | load@resource://devtools/shared/base-loader.js:169:15
[task 2021-01-29T11:27:39.913Z] 11:27:39     INFO -  PID 5084 | _require@resource://devtools/shared/base-loader.js:346:23
[task 2021-01-29T11:27:39.914Z] 11:27:39     INFO -  PID 5084 | requireHook@resource://devtools/shared/Loader.jsm:116:14
[task 2021-01-29T11:27:39.914Z] 11:27:39     INFO -  PID 5084 | require@resource://devtools/shared/base-loader.js:300:14
[task 2021-01-29T11:27:39.914Z] 11:27:39     INFO -  PID 5084 | _runNextTest@file:///C:/Users/task_1611918337/AppData/Local/Temp/tmpcia7vt/profile/extensions/bug1150215@mozilla.org/content/damp.js:255:29
[task 2021-01-29T11:27:39.914Z] 11:27:39     INFO -  PID 5084 | testTeardown@file:///C:/Users/task_1611918337/AppData/Local/Temp/tmpcia7vt/profile/extensions/bug1150215@mozilla.org/content/damp.js:223:10
[task 2021-01-29T11:27:39.914Z] 11:27:39     INFO -  PID 5084 |
[task 2021-01-29T11:27:39.964Z] 11:27:39     INFO -  Launcher process psutil.Process(pid=5084L, name='firefox.exe', started='11:27:00') detected. Terminating parent process psutil.Process(pid=6580, name='firefox.exe', started='11:27:00') instead.
[task 2021-01-29T11:27:39.964Z] 11:27:39     INFO -  Terminating psutil.Process(pid=6580, name='firefox.exe', started='11:27:00')
[task 2021-01-29T11:27:39.994Z] 11:27:39     INFO -  PID 5084 | Exiting due to channel error.
[task 2021-01-29T11:27:39.994Z] 11:27:39     INFO -  PID 5084 | Exiting due to channel error.
[task 2021-01-29T11:27:40.194Z] 11:27:40     INFO -  mozcrash checking c:\users\task_1611918337\appdata\local\temp\tmpcia7vt\profile\minidumps for minidumps...
[task 2021-01-29T11:27:40.194Z] 11:27:40     INFO -  TEST-UNEXPECTED-ERROR | damp | unexpected error
[task 2021-01-29T11:27:40.194Z] 11:27:40    ERROR -  Traceback (most recent call last):
[task 2021-01-29T11:27:40.194Z] 11:27:40     INFO -    File "C:\Users\task_1611918337\build\tests\talos\talos\run_tests.py", line 337, in run_tests
[task 2021-01-29T11:27:40.194Z] 11:27:40     INFO -      talos_results.add(mytest.runTest(browser_config, test))
[task 2021-01-29T11:27:40.194Z] 11:27:40     INFO -    File "C:\Users\task_1611918337\build\tests\talos\talos\ttest.py", line 65, in runTest
[task 2021-01-29T11:27:40.194Z] 11:27:40     INFO -      return self._runTest(browser_config, test_config, setup)
[task 2021-01-29T11:27:40.195Z] 11:27:40     INFO -    File "C:\Users\task_1611918337\build\tests\talos\talos\ttest.py", line 216, in _runTest
[task 2021-01-29T11:27:40.195Z] 11:27:40     INFO -      debugger_args=browser_config["debugger_args"],
[task 2021-01-29T11:27:40.195Z] 11:27:40     INFO -    File "C:\Users\task_1611918337\build\tests\talos\talos\talos_process.py", line 191, in run_browser
[task 2021-01-29T11:27:40.195Z] 11:27:40     INFO -      raise TalosError("unexpected error")
[task 2021-01-29T11:27:40.195Z] 11:27:40     INFO -  TalosError: unexpected error
[task 2021-01-29T11:27:40.195Z] 11:27:40     INFO -  TEST-INFO took 40268ms
[task 2021-01-29T11:27:40.195Z] 11:27:40     INFO -  SUITE-END | took 40s
[task 2021-01-29T11:27:40.452Z] 11:27:40    ERROR - Return code: 2```

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

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