Closed Bug 1486974 Opened 6 years ago Closed 5 years ago

Intermittent devtools/client/debugger/test/mochitest/browser_dbg_worker-window.js | Test timed out -

Categories

(DevTools :: Debugger, defect, P5)

Product:
DevTools
Component:
Debugger
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED FIXED

People

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

Details

(Keywords: intermittent-failure, Whiteboard: [retriggered][stockwell disabled])

Attachments

(1 file)

Skipped test on all platforms
769 bytes, patch
jmaher
: review+
Details | Diff | Splinter Review 
Filed by: dluca [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=196270606&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/VARk_i9CSFGY_Q6MRGlghQ/runs/0/artifacts/public/logs/live_backing.log

TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg_worker-window.js | Correct set of tools supported by worker - 
[task 2018-08-28T19:00:09.414Z] 19:00:09     INFO - Terminating worker with url 'code_WorkerTargetActor.attachThread-worker.js' in tab.
[task 2018-08-28T19:00:09.415Z] 19:00:09     INFO - Waiting for worker to close.
[task 2018-08-28T19:00:09.419Z] 19:00:09     INFO - Buffered messages logged at 18:58:44
[task 2018-08-28T19:00:09.421Z] 19:00:09     INFO - Console message: [JavaScript Error: "remote browser crashed while on http://example.com/browser/devtools/client/debugger/test/mochitest/doc_WorkerTargetActor.attachThread-tab.html
[task 2018-08-28T19:00:09.423Z] 19:00:09     INFO - " {file: "chrome://mochikit/content/mochitest-e10s-utils.js" line: 8}]
[task 2018-08-28T19:00:09.424Z] 19:00:09     INFO - e10s_init/<@chrome://mochikit/content/mochitest-e10s-utils.js:8:5
[task 2018-08-28T19:00:09.425Z] 19:00:09     INFO - EventListener.handleEvent*e10s_init@chrome://mochikit/content/mochitest-e10s-utils.js:6:3
[task 2018-08-28T19:00:09.426Z] 19:00:09     INFO - testInit@chrome://mochikit/content/browser-test.js:110:5
[task 2018-08-28T19:00:09.427Z] 19:00:09     INFO - setTimeout handler*@chrome://mochikit/content/browser-test.js:30:3
[task 2018-08-28T19:00:09.428Z] 19:00:09     INFO - loadChromeScripts@jar:file:///tmp/tmpqlizUH.mozrunner/extensions/mochikit@mozilla.org.xpi!/api.js:15:3
[task 2018-08-28T19:00:09.430Z] 19:00:09     INFO - loadMochitest@jar:file:///tmp/tmpqlizUH.mozrunner/extensions/mochikit@mozilla.org.xpi!/api.js:107:3
[task 2018-08-28T19:00:09.431Z] 19:00:09     INFO - @tests/mochitest/runtests.py:1771:1
[task 2018-08-28T19:00:09.433Z] 19:00:09     INFO - @tests/mochitest/runtests.py:1753:2
[task 2018-08-28T19:00:09.434Z] 19:00:09     INFO - evaluate.sandbox/promise<@chrome://marionette/content/evaluate.js:128:13
[task 2018-08-28T19:00:09.435Z] 19:00:09     INFO - evaluate.sandbox@chrome://marionette/content/evaluate.js:97:17
[task 2018-08-28T19:00:09.436Z] 19:00:09     INFO - GeckoDriver.prototype.execute_@chrome://marionette/content/driver.js:1012:19
[task 2018-08-28T19:00:09.437Z] 19:00:09     INFO - async*GeckoDriver.prototype.executeScript@chrome://marionette/content/driver.js:882:24
[task 2018-08-28T19:00:09.439Z] 19:00:09     INFO - Async*despatch@chrome://marionette/content/server.js:298:20
[task 2018-08-28T19:00:09.440Z] 19:00:09     INFO - async*execute@chrome://marionette/content/server.js:271:11
[task 2018-08-28T19:00:09.441Z] 19:00:09     INFO - async*onPacket/<@chrome://marionette/content/server.js:246:15
[task 2018-08-28T19:00:09.443Z] 19:00:09     INFO - async*onPacket@chrome://marionette/content/server.js:245:8
[task 2018-08-28T19:00:09.443Z] 19:00:09     INFO - _onJSONObjectReady/<@chrome://marionette/content/transport.js:490:9
[task 2018-08-28T19:00:09.445Z] 19:00:09     INFO - 
[task 2018-08-28T19:00:09.446Z] 19:00:09     INFO - Buffered messages logged at 18:59:09
[task 2018-08-28T19:00:09.447Z] 19:00:09     INFO - Console message: [JavaScript Error: "Polling for changes failed: Server error 404 Not Found: "JSON.parse: unexpected character at line 1 column 1 of the JSON data"." {file: "resource://services-settings/remote-settings.js" line: 721}]
[task 2018-08-28T19:00:09.448Z] 19:00:09     INFO - remoteSettingsFunction/remoteSettings.pollChanges@resource://services-settings/remote-settings.js:721:13
[task 2018-08-28T19:00:09.450Z] 19:00:09     INFO - async*notify@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/RemoteSettingsComponents.js:24:5
[task 2018-08-28T19:00:09.452Z] 19:00:09     INFO - TM_notify/<@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsUpdateTimerManager.js:195:48
[task 2018-08-28T19:00:09.453Z] 19:00:09     INFO - TM_notify@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsUpdateTimerManager.js:242:7
[task 2018-08-28T19:00:09.454Z] 19:00:09     INFO - 
[task 2018-08-28T19:00:09.456Z] 19:00:09     INFO - Buffered messages finished
[task 2018-08-28T19:00:09.459Z] 19:00:09     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg_worker-window.js | Test timed out - 
[task 2018-08-28T19:00:09.461Z] 19:00:09     INFO - Removing tab.
[task 2018-08-28T19:00:09.463Z] 19:00:09     INFO - Tab removed and finished closing.
[task 2018-08-28T19:00:09.465Z] 19:00:09     INFO - finish() was called, cleaning up...
[task 2018-08-28T19:00:09.467Z] 19:00:09     INFO - Forcing GC/CC after debugger test.
[task 2018-08-28T19:00:09.468Z] 19:00:09     INFO - GECKO(2104) | MEMORY STAT | vsize 2294MB | residentFast 336MB | heapAllocated 109MB
[task 2018-08-28T19:00:09.470Z] 19:00:09     INFO - TEST-OK | devtools/client/debugger/test/mochitest/browser_dbg_worker-window.js | took 90340ms
[task 2018-08-28T19:00:09.472Z] 19:00:09     INFO - checking window state
[task 2018-08-28T19:00:09.473Z] 19:00:09     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-08-28T19:00:09.477Z] 19:00:09     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg_worker-window.js | Found a devtools:toolbox after previous test timed out - 
[task 2018-08-28T19:00:09.478Z] 19:00:09     INFO - GECKO(2104) | must wait for focus
[task 2018-08-28T19:00:11.548Z] 19:00:11     INFO - GECKO(2104) | Completed ShutdownLeaks collections in process 2835
[task 2018-08-28T19:00:11.548Z] 19:00:11     INFO - GECKO(2104) | Completed ShutdownLeaks collections in process 2179
[task 2018-08-28T19:00:11.556Z] 19:00:11     INFO - GECKO(2104) | Completed ShutdownLeaks collections in process 2194
[task 2018-08-28T19:00:11.571Z] 19:00:11     INFO - GECKO(2104) | Completed ShutdownLeaks collections in process 2261
[task 2018-08-28T19:00:11.572Z] 19:00:11     INFO - GECKO(2104) | Completed ShutdownLeaks collections in process 2325
[task 2018-08-28T19:00:12.098Z] 19:00:12     INFO - GECKO(2104) | Completed ShutdownLeaks collections in process 2104
[task 2018-08-28T19:00:12.099Z] 19:00:12     INFO - TEST-START | Shutdown
[task 2018-08-28T19:00:12.100Z] 19:00:12     INFO - Browser Chrome Test Summary
[task 2018-08-28T19:00:12.101Z] 19:00:12     INFO - Passed:  6299
[task 2018-08-28T19:00:12.102Z] 19:00:12     INFO - Failed:  2
[task 2018-08-28T19:00:12.104Z] 19:00:12     INFO - Todo:    0
[task 2018-08-28T19:00:12.105Z] 19:00:12     INFO - Mode:    e10s
[task 2018-08-28T19:00:12.107Z] 19:00:12     INFO - *** End BrowserChrome Test Results ***
[task 2018-08-28T19:00:12.246Z] 19:00:12     INFO - GECKO(2104) | JavaScript error: resource://gre/modules/AddonManager.jsm, line 2355: NS_ERROR_ILLEGAL_VALUE: aListener must be an AddonListener object
[task 2018-08-28T19:00:12.555Z] 19:00:12     INFO - GECKO(2104) | 1535482812551	Marionette	DEBUG	Received observer notification xpcom-will-shutdown
[task 2018-08-28T19:00:12.555Z] 19:00:12     INFO - GECKO(2104) | 1535482812551	Marionette	INFO	Stopped listening on port 2828
[task 2018-08-28T19:00:12.555Z] 19:00:12     INFO - GECKO(2104) | 1535482812551	Marionette	DEBUG	Remote service is inactive
[task 2018-08-28T19:00:12.676Z] 19:00:12     INFO - TEST-INFO | Main app process: exit 0
[task 2018-08-28T19:00:12.677Z] 19:00:12     INFO - runtests.py | Application ran for: 0:07:34.394454
[task 2018-08-28T19:00:12.678Z] 19:00:12     INFO - zombiecheck | Reading PID log: /tmp/tmpAapZIGpidlog
[task 2018-08-28T19:00:12.679Z] 19:00:12     INFO - ==> process 2104 launched child process 2125
[task 2018-08-28T19:00:12.680Z] 19:00:12     INFO - ==> process 2104 launched child process 2179
[task 2018-08-28T19:00:12.681Z] 19:00:12     INFO - ==> process 2104 launched child process 2194
[task 2018-08-28T19:00:12.683Z] 19:00:12     INFO - ==> process 2104 launched child process 2261
[task 2018-08-28T19:00:12.684Z] 19:00:12     INFO - ==> process 2104 launched child process 2290
[task 2018-08-28T19:00:12.686Z] 19:00:12     INFO - ==> process 2104 launched child process 2325
[task 2018-08-28T19:00:12.686Z] 19:00:12     INFO - ==> process 2104 launched child process 2829
[task 2018-08-28T19:00:12.691Z] 19:00:12     INFO - ==> process 2104 launched child process 2835
[task 2018-08-28T19:00:12.691Z] 19:00:12     INFO - zombiecheck | Checking for orphan process with PID: 2179
[task 2018-08-28T19:00:12.691Z] 19:00:12     INFO - zombiecheck | Checking for orphan process with PID: 2829
[task 2018-08-28T19:00:12.691Z] 19:00:12     INFO - zombiecheck | Checking for orphan process with PID: 2194
[task 2018-08-28T19:00:12.691Z] 19:00:12     INFO - zombiecheck | Checking for orphan process with PID: 2125
[task 2018-08-28T19:00:12.691Z] 19:00:12     INFO - zombiecheck | Checking for orphan process with PID: 2290
[task 2018-08-28T19:00:12.691Z] 19:00:12     INFO - zombiecheck | Checking for orphan process with PID: 2835
[task 2018-08-28T19:00:12.691Z] 19:00:12     INFO - zombiecheck | Checking for orphan process with PID: 2325
[task 2018-08-28T19:00:12.691Z] 19:00:12     INFO - zombiecheck | Checking for orphan process with PID: 2261
[task 2018-08-28T19:00:12.691Z] 19:00:12     INFO - mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/esPf1xI6SuuODVQsM3BFRA/artifacts/public/build/target.crashreporter-symbols.zip
[task 2018-08-28T19:00:19.743Z] 19:00:19     INFO - mozcrash Copy/paste: /usr/local/bin/linux64-minidump_stackwalk /tmp/tmpqlizUH.mozrunner/minidumps/55f51586-3f40-14cc-4a44-d36b7327d26c.dmp /tmp/tmpyRgeOa
[task 2018-08-28T19:00:28.998Z] 19:00:28     INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/55f51586-3f40-14cc-4a44-d36b7327d26c.dmp
[task 2018-08-28T19:00:28.999Z] 19:00:28     INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/55f51586-3f40-14cc-4a44-d36b7327d26c.extra
[task 2018-08-28T19:00:29.071Z] 19:00:29     INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ mozilla::dom::exceptions::JSStackFrame::GetFilename(JSContext*, nsTSubstring<char16_t>&)]
[task 2018-08-28T19:00:29.072Z] 19:00:29     INFO - Crash dump filename: /tmp/tmpqlizUH.mozrunner/minidumps/55f51586-3f40-14cc-4a44-d36b7327d26c.dmp
[task 2018-08-28T19:00:29.073Z] 19:00:29     INFO - Operating system: Linux
[task 2018-08-28T19:00:29.073Z] 19:00:29     INFO -                   0.0.0 Linux 4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018 x86_64
[task 2018-08-28T19:00:29.074Z] 19:00:29     INFO - CPU: amd64
[task 2018-08-28T19:00:29.078Z] 19:00:29     INFO -      family 6 model 62 stepping 4
[task 2018-08-28T19:00:29.079Z] 19:00:29     INFO -      2 CPUs
[task 2018-08-28T19:00:29.079Z] 19:00:29     INFO - 
[task 2018-08-28T19:00:29.080Z] 19:00:29     INFO - GPU: UNKNOWN
[task 2018-08-28T19:00:29.081Z] 19:00:29     INFO - 
[task 2018-08-28T19:00:29.081Z] 19:00:29     INFO - Crash reason:  SIGSEGV
[task 2018-08-28T19:00:29.082Z] 19:00:29     INFO - Crash address: 0x0
[task 2018-08-28T19:00:29.083Z] 19:00:29     INFO - Process uptime: not available
(In reply to Tiberius Oros[:tiberius_oros] from comment #2)
> Retriggered tests on the following pushes:
> https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&filter-
> searchStr=opt-mochitest-devtools-chrome-
> e10s&tochange=ee4af6d31e69542f53aee91cb126e3d2a85691de&fromchange=4c623a756c0
> 6017d341d956ce5a8633baf3f7fc8
> 
> The failures started on this push:
> https://treeherder.mozilla.org/#/jobs?repo=mozilla-
> inbound&revision=7ccc1c8a7abeefd54bc4cf9a5d0eef23681c924a&filter-
> searchStr=opt-mochitest-devtools-chrome-e10s

Kris, the fail seems to have started from the above push.
Can you please take a look?
Flags: needinfo?(kmaglione+bmo)
In the last 7 days, there have been 54 failures.

The failures are on linux32, linux64, osx-10-10, windows10-64, windows7-32, windows10-64-msvc, windows7-32-msvc and windows7-32-nightly.
Affected build types: opt, pgo and asan.

An example of a recent log file:
https://treeherder.mozilla.org/logviewer.html#?job_id=198314532&repo=autoland&lineNumber=9571

And the relevant part of the log:
[task 2018-09-09T18:58:47.607Z] 18:58:47     INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg_worker-window.js | Correct set of tools supported by worker - 
[task 2018-09-09T18:58:47.608Z] 18:58:47     INFO - Terminating worker with url 'code_WorkerTargetActor.attachThread-worker.js' in tab.
[task 2018-09-09T18:58:47.609Z] 18:58:47     INFO - Waiting for worker to close.
[task 2018-09-09T18:58:47.612Z] 18:58:47     INFO - Buffered messages logged at 18:58:08
[task 2018-09-09T18:58:47.613Z] 18:58:47     INFO - Console message: [JavaScript Error: "remote browser crashed while on http://example.com/browser/devtools/client/debugger/test/mochitest/doc_WorkerTargetActor.attachThread-tab.html
[task 2018-09-09T18:58:47.614Z] 18:58:47     INFO - " {file: "chrome://mochikit/content/mochitest-e10s-utils.js" line: 8}]
[task 2018-09-09T18:58:47.615Z] 18:58:47     INFO - e10s_init/<@chrome://mochikit/content/mochitest-e10s-utils.js:8:5
[task 2018-09-09T18:58:47.616Z] 18:58:47     INFO - EventListener.handleEvent*e10s_init@chrome://mochikit/content/mochitest-e10s-utils.js:6:3
[task 2018-09-09T18:58:47.617Z] 18:58:47     INFO - testInit@chrome://mochikit/content/browser-test.js:110:5
[task 2018-09-09T18:58:47.618Z] 18:58:47     INFO - setTimeout handler*@chrome://mochikit/content/browser-test.js:30:3
[task 2018-09-09T18:58:47.618Z] 18:58:47     INFO - loadChromeScripts@jar:file:///tmp/tmpbi8Nn5.mozrunner/extensions/mochikit@mozilla.org.xpi!/api.js:15:3
[task 2018-09-09T18:58:47.620Z] 18:58:47     INFO - loadMochitest@jar:file:///tmp/tmpbi8Nn5.mozrunner/extensions/mochikit@mozilla.org.xpi!/api.js:107:3
[task 2018-09-09T18:58:47.621Z] 18:58:47     INFO - @tests/mochitest/runtests.py:1771:1
[task 2018-09-09T18:58:47.622Z] 18:58:47     INFO - @tests/mochitest/runtests.py:1753:2
[task 2018-09-09T18:58:47.623Z] 18:58:47     INFO - evaluate.sandbox/promise<@chrome://marionette/content/evaluate.js:128:13
[task 2018-09-09T18:58:47.624Z] 18:58:47     INFO - evaluate.sandbox@chrome://marionette/content/evaluate.js:97:17
[task 2018-09-09T18:58:47.625Z] 18:58:47     INFO - GeckoDriver.prototype.execute_@chrome://marionette/content/driver.js:1016:19
[task 2018-09-09T18:58:47.626Z] 18:58:47     INFO - async*GeckoDriver.prototype.executeScript@chrome://marionette/content/driver.js:886:24
[task 2018-09-09T18:58:47.627Z] 18:58:47     INFO - Async*despatch@chrome://marionette/content/server.js:298:20
[task 2018-09-09T18:58:47.628Z] 18:58:47     INFO - async*execute@chrome://marionette/content/server.js:271:11
[task 2018-09-09T18:58:47.629Z] 18:58:47     INFO - async*onPacket/<@chrome://marionette/content/server.js:246:15
[task 2018-09-09T18:58:47.633Z] 18:58:47     INFO - async*onPacket@chrome://marionette/content/server.js:245:8
[task 2018-09-09T18:58:47.634Z] 18:58:47     INFO - _onJSONObjectReady/<@chrome://marionette/content/transport.js:490:9
[task 2018-09-09T18:58:47.635Z] 18:58:47     INFO - 
[task 2018-09-09T18:58:47.636Z] 18:58:47     INFO - Buffered messages finished
[task 2018-09-09T18:58:47.637Z] 18:58:47     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg_worker-window.js | Test timed out - 
[task 2018-09-09T18:58:47.638Z] 18:58:47     INFO - Removing tab.
[task 2018-09-09T18:58:47.638Z] 18:58:47     INFO - Tab removed and finished closing.
[task 2018-09-09T18:58:47.641Z] 18:58:47     INFO - finish() was called, cleaning up...
[task 2018-09-09T18:58:47.641Z] 18:58:47     INFO - Forcing GC/CC after debugger test.
[task 2018-09-09T18:58:47.642Z] 18:58:47     INFO - GECKO(1987) | MEMORY STAT | vsize 750MB | residentFast 313MB | heapAllocated 92MB
[task 2018-09-09T18:58:47.643Z] 18:58:47     INFO - TEST-OK | devtools/client/debugger/test/mochitest/browser_dbg_worker-window.js | took 45452ms
[task 2018-09-09T18:58:47.765Z] 18:58:47     INFO - checking window state
[task 2018-09-09T18:58:47.767Z] 18:58:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-09-09T18:58:47.768Z] 18:58:47     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg_worker-window.js | Found a devtools:toolbox after previous test timed out - 
[task 2018-09-09T18:58:47.769Z] 18:58:47     INFO - GECKO(1987) | must wait for focus
[task 2018-09-09T18:58:49.629Z] 18:58:49     INFO - GECKO(1987) | Completed ShutdownLeaks collections in process 2062
[task 2018-09-09T18:58:49.665Z] 18:58:49     INFO - GECKO(1987) | Completed ShutdownLeaks collections in process 2632
[task 2018-09-09T18:58:49.706Z] 18:58:49     INFO - GECKO(1987) | Completed ShutdownLeaks collections in process 2083
[task 2018-09-09T18:58:49.715Z] 18:58:49     INFO - GECKO(1987) | Completed ShutdownLeaks collections in process 2194
[task 2018-09-09T18:58:49.737Z] 18:58:49     INFO - GECKO(1987) | Completed ShutdownLeaks collections in process 2160
[task 2018-09-09T18:58:50.266Z] 18:58:50     INFO - GECKO(1987) | Completed ShutdownLeaks collections in process 1987

:jlast As you are the triage owner of this component, could you please take a look at this?
:kmag, could you please see comment 4?

Thank you!
Flags: needinfo?(jlaster)
Whiteboard: [retriggered][stockwell disable-recommended] → [retriggered]
Whiteboard: [retriggered] → [retriggered][stockwell needswork]

        Attachment #9007764 -
        Flags: review?(jmaher)

    
    

    
  
Comment on attachment 9007764 [details] [diff] [review]
Skipped test on all platforms

Review of attachment 9007764 [details] [diff] [review]:
-----------------------------------------------------------------

thanks Dorel

        Attachment #9007764 -
        Flags: review?(jmaher) → review+
Keywords: checkin-needed, 
          
            leave-open
Whiteboard: [retriggered][stockwell needswork] → [retriggered][stockwell disabled]

    
    

    
  
Pushed by dluca@mozilla.com:
https://hg.mozilla.org/integration/mozilla-inbound/rev/d7856d8aca95
Disabled browser_dbg_worker-window.js on all platforms for frequent failures. r=jmaher
Keywords: checkin-needed

    
    

    
  
this is an old debugger test so it is okay skipping.
Flags: needinfo?(jlaster)
Flags: needinfo?(kmaglione+bmo)

    
    

    
      
  

    
    

    
      
  

    
    

    
      
  

    
    

    
      
  

    
    

    
      
  

    
    

    
      
  

    
    

    
      
  

    
    

    
      
  
Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → FIXED
Assignee: nobody → dluca

          You need to log in
          before you can comment on or make changes to this bug.
        






  

    
  







  

    

      
Attachment

      

      
      
      
      
    

    

      

        

          

            
General

            

              Creator: 



            

            
Created: 

            
Updated: 

            
Size: