Closed Bug 1623735 Opened 6 years ago Closed 5 years ago

Intermittent toolkit/content/tests/chrome/test_edit_contextmenu.html | application terminated with exit code 1

Categories

(Toolkit :: UI Widgets, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2020-03-19T17:18:42.949Z] 17:18:42 INFO - TEST-START | toolkit/content/tests/chrome/test_edit_contextmenu.html
[task 2020-03-19T17:18:43.536Z] 17:18:43 INFO - GECKO(4148) | Gdk-Message: 17:18:43.530: firefox: Fatal IO error 11 (Resource temporarily unavailable) on X server :0.
[task 2020-03-19T17:18:43.559Z] 17:18:43 INFO - GECKO(4148) | Exiting due to channel error.
[task 2020-03-19T17:18:43.605Z] 17:18:43 INFO - TEST-INFO | Main app process: exit 1
[task 2020-03-19T17:18:43.605Z] 17:18:43 INFO - Buffered messages logged at 17:18:43
[task 2020-03-19T17:18:43.605Z] 17:18:43 INFO - must wait for focus
[task 2020-03-19T17:18:43.605Z] 17:18:43 INFO - TEST-PASS | toolkit/content/tests/chrome/test_edit_contextmenu.html | element exists
[task 2020-03-19T17:18:43.605Z] 17:18:43 INFO - Synthesizing a key so 'Undo' will be enabled
[task 2020-03-19T17:18:43.605Z] 17:18:43 INFO - TEST-PASS | toolkit/content/tests/chrome/test_edit_contextmenu.html | initial value
[task 2020-03-19T17:18:43.605Z] 17:18:43 INFO - Calling prevent default on the first contextmenu event
[task 2020-03-19T17:18:43.605Z] 17:18:43 INFO - TEST-PASS | toolkit/content/tests/chrome/test_edit_contextmenu.html | contextmenu with preventDefault() doesn't run
[task 2020-03-19T17:18:43.605Z] 17:18:43 INFO - TEST-PASS | toolkit/content/tests/chrome/test_edit_contextmenu.html | context menu exists after right click
[task 2020-03-19T17:18:43.605Z] 17:18:43 INFO - TEST-PASS | toolkit/content/tests/chrome/test_edit_contextmenu.html | the outer context menu state is is not closed, it's: closed
[task 2020-03-19T17:18:43.605Z] 17:18:43 INFO - TEST-PASS | toolkit/content/tests/chrome/test_edit_contextmenu.html | undo enabled
[task 2020-03-19T17:18:43.605Z] 17:18:43 INFO - TEST-PASS | toolkit/content/tests/chrome/test_edit_contextmenu.html | cut disabled
[task 2020-03-19T17:18:43.605Z] 17:18:43 INFO - TEST-PASS | toolkit/content/tests/chrome/test_edit_contextmenu.html | copy disabled
[task 2020-03-19T17:18:43.605Z] 17:18:43 INFO - TEST-PASS | toolkit/content/tests/chrome/test_edit_contextmenu.html | paste enabled
[task 2020-03-19T17:18:43.605Z] 17:18:43 INFO - TEST-PASS | toolkit/content/tests/chrome/test_edit_contextmenu.html | delete disabled
[task 2020-03-19T17:18:43.611Z] 17:18:43 INFO - TEST-PASS | toolkit/content/tests/chrome/test_edit_contextmenu.html | select all enabled
[task 2020-03-19T17:18:43.611Z] 17:18:43 INFO - TEST-PASS | toolkit/content/tests/chrome/test_edit_contextmenu.html | undo worked
[task 2020-03-19T17:18:43.611Z] 17:18:43 INFO - TEST-PASS | toolkit/content/tests/chrome/test_edit_contextmenu.html | element exists
[task 2020-03-19T17:18:43.611Z] 17:18:43 INFO - Synthesizing a key so 'Undo' will be enabled
[task 2020-03-19T17:18:43.611Z] 17:18:43 INFO - Buffered messages finished
[task 2020-03-19T17:18:43.611Z] 17:18:43 ERROR - TEST-UNEXPECTED-FAIL | toolkit/content/tests/chrome/test_edit_contextmenu.html | application terminated with exit code 1
[task 2020-03-19T17:18:43.611Z] 17:18:43 INFO - runtests.py | Application ran for: 0:03:53.505031
[task 2020-03-19T17:18:43.612Z] 17:18:43 INFO - zombiecheck | Reading PID log: /tmp/tmpScW2fDpidlog
[task 2020-03-19T17:18:43.612Z] 17:18:43 INFO - ==> process 4148 launched child process 4170
[task 2020-03-19T17:18:43.612Z] 17:18:43 INFO - ==> process 4148 launched child process 4341
[task 2020-03-19T17:18:43.612Z] 17:18:43 INFO - ==> process 4148 launched child process 4387
[task 2020-03-19T17:18:43.612Z] 17:18:43 INFO - ==> process 4148 launched child process 4450
[task 2020-03-19T17:18:43.613Z] 17:18:43 INFO - zombiecheck | Checking for orphan process with PID: 4170
[task 2020-03-19T17:18:43.615Z] 17:18:43 INFO - zombiecheck | Checking for orphan process with PID: 4387
[task 2020-03-19T17:18:43.615Z] 17:18:43 INFO - zombiecheck | Checking for orphan process with PID: 4341
[task 2020-03-19T17:18:43.616Z] 17:18:43 INFO - zombiecheck | Checking for orphan process with PID: 4450
[task 2020-03-19T17:18:43.616Z] 17:18:43 INFO - Stopping web server
[task 2020-03-19T17:18:43.616Z] 17:18:43 INFO - Stopping web socket server
[task 2020-03-19T17:18:43.624Z] 17:18:43 INFO - Stopping ssltunnel
[task 2020-03-19T17:18:43.644Z] 17:18:43 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2020-03-19T17:18:43.644Z] 17:18:43 INFO - runtests.py | Running tests: end.
[task 2020-03-19T17:18:43.665Z] 17:18:43 INFO - Buffered messages finished
[task 2020-03-19T17:18:43.665Z] 17:18:43 INFO - Running manifest: tools/profiler/tests/chrome/chrome.ini
[task 2020-03-19T17:18:43.685Z] 17:18:43 INFO - Setting pipeline to PAUSED ...
[task 2020-03-19T17:18:43.686Z] 17:18:43 INFO - Pipeline is PREROLLING ...
[task 2020-03-19T17:18:43.686Z] 17:18:43 INFO - Pipeline is PREROLLED ...
[task 2020-03-19T17:18:43.686Z] 17:18:43 INFO - Setting pipeline to PLAYING ...
[task 2020-03-19T17:18:43.686Z] 17:18:43 INFO - New clock: GstSystemClock
[task 2020-03-19T17:18:43.722Z] 17:18:43 INFO - Got EOS from element "pipeline0".
[task 2020-03-19T17:18:43.722Z] 17:18:43 INFO - Execution ended after 0:00:00.033423778
[task 2020-03-19T17:18:43.723Z] 17:18:43 INFO - Setting pipeline to PAUSED ...
[task 2020-03-19T17:18:43.723Z] 17:18:43 INFO - Setting pipeline to READY ...
[task 2020-03-19T17:18:43.723Z] 17:18:43 INFO - (gst-launch-1.0:4541): GStreamer-CRITICAL *: 17:18:43.717: gst_object_unref: assertion '((GObject ) object)->ref_count > 0' failed
[task 2020-03-19T17:18:43.723Z] 17:18:43 INFO - Setting pipeline to NULL ...
[task 2020-03-19T17:18:43.723Z] 17:18:43 INFO - Freeing pipeline ...
[task 2020-03-19T17:18:44.119Z] 17:18:44 INFO - pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2020-03-19T17:18:44.147Z] 17:18:44 INFO - MochitestServer : launching [u'/builds/worker/workspace/build/tests/bin/xpcshell', '-g', '/builds/worker/workspace/build/application/firefox', '-f', '/builds/worker/workspace/build/tests/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmpzq3Dl9.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/worker/workspace/build/tests/mochitest/server.js']
[task 2020-03-19T17:18:44.148Z] 17:18:44 INFO - runtests.py | Server pid: 4560
[task 2020-03-19T17:18:44.164Z] 17:18:44 INFO - runtests.py | Websocket server pid: 4563
[task 2020-03-19T17:18:44.179Z] 17:18:44 INFO - runtests.py | SSL tunnel pid: 4566
[task 2020-03-19T17:18:44.228Z] 17:18:44 INFO - runtests.py | Running with scheme: http
[task 2020-03-19T17:18:44.228Z] 17:18:44 INFO - runtests.py | Running with e10s: False
[task 2020-03-19T17:18:44.229Z] 17:18:44 INFO - runtests.py | Running with fission: False
[task 2020-03-19T17:18:44.229Z] 17:18:44 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2020-03-19T17:18:44.230Z] 17:18:44 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2020-03-19T17:18:44.230Z] 17:18:44 INFO - runtests.py | Running tests: start.
[task 2020-03-19T17:18:44.231Z] 17:18:44 INFO -
[task 2020-03-19T17:18:44.248Z] 17:18:44 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpzq3Dl9.mozrunner
[task 2020-03-19T17:18:44.256Z] 17:18:44 INFO - runtests.py | Application pid: 4584
[task 2020-03-19T17:18:44.256Z] 17:18:44 INFO - TEST-INFO | started process GECKO(4584)
[task 2020-03-19T17:18:44.720Z] 17:18:44 INFO - GECKO(4584) | 1584638324711 addons.webextension.doh-rollout@mozilla.org WARN Loading extension 'doh-rollout@mozilla.org': Reading manifest: Invalid extension permission: networkStatus
[task 2020-03-19T17:18:45.102Z] 17:18:45 INFO - GECKO(4584) | 1584638325098 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
[task 2020-03-19T17:18:45.103Z] 17:18:45 INFO - GECKO(4584) | 1584638325098 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: telemetry
[task 2020-03-19T17:18:45.103Z] 17:18:45 INFO - GECKO(4584) | 1584638325099 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2020-03-19T17:18:45.104Z] 17:18:45 INFO - GECKO(4584) | 1584638325099 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader

[task 2020-03-19T17:18:45.205Z] 17:18:45 INFO - GECKO(4584) | 1584638325199 Marionette TRACE Marionette enabled
[task 2020-03-19T17:18:45.286Z] 17:18:45 INFO - GECKO(4584) | 1584638325278 Marionette TRACE Received observer notification toplevel-window-ready
[task 2020-03-19T17:18:49.452Z] 17:18:49 INFO - GECKO(4584) | 1584638329450 Marionette TRACE Received observer notification marionette-startup-requested
[task 2020-03-19T17:18:49.453Z] 17:18:49 INFO - GECKO(4584) | 1584638329450 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
[task 2020-03-19T17:18:49.469Z] 17:18:49 INFO - GECKO(4584) | 1584638329464 Marionette TRACE All scripts recorded.
[task 2020-03-19T17:18:49.512Z] 17:18:49 INFO - GECKO(4584) | 1584638329504 Marionette INFO Listening on port 2828
[task 2020-03-19T17:18:49.514Z] 17:18:49 INFO - GECKO(4584) | 1584638329504 Marionette DEBUG Marionette is listening
[task 2020-03-19T17:18:49.576Z] 17:18:49 INFO - GECKO(4584) | 1584638329573 Marionette DEBUG Accepted connection 0 from 127.0.0.1:36688
[task 2020-03-19T17:18:49.591Z] 17:18:49 INFO - GECKO(4584) | 1584638329583 Marionette DEBUG Closed connection 0
[task 2020-03-19T17:18:49.593Z] 17:18:49 INFO - GECKO(4584) | 1584638329583 Marionette DEBUG Accepted connection 1 from 127.0.0.1:36690
[task 2020-03-19T17:18:49.594Z] 17:18:49 INFO - GECKO(4584) | 1584638329584 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2020-03-19T17:18:49.603Z] 17:18:49 INFO - GECKO(4584) | 1584638329594 Marionette TRACE [16] Frame script loaded
[task 2020-03-19T17:18:49.604Z] 17:18:49 INFO - GECKO(4584) | 1584638329595 Marionette TRACE [16] Frame script registered
[task 2020-03-19T17:18:49.606Z] 17:18:49 INFO - GECKO(4584) | 1584638329600 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"42e1b8a3-dc03-4c65-abbd-2d79fc3dab3e","capabilities":{"browserName":"firefox","browserVersion":"76.0a ... mp/tmpzq3Dl9.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2020-03-19T17:18:49.622Z] 17:18:49 INFO - GECKO(4584) | 1584638329615 Marionette DEBUG 1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpkqRBY8.zip","temporary":false}]
[task 2020-03-19T17:18:49.658Z] 17:18:49 INFO - GECKO(4584) | 1584638329654 Marionette DEBUG 1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2020-03-19T17:18:49.706Z] 17:18:49 INFO - GECKO(4584) | 1584638329701 Marionette TRACE [28] Frame script loaded
[task 2020-03-19T17:18:49.706Z] 17:18:49 INFO - GECKO(4584) | 1584638329701 Marionette TRACE [28] Frame script registered
[task 2020-03-19T17:18:49.713Z] 17:18:49 INFO - GECKO(4584) | 1584638329708 Marionette DEBUG 1 -> [0,3,"Addon:Install",{"path":"/tmp/tmp7MJ4sg.zip","temporary":false}]
[task 2020-03-19T17:18:49.834Z] 17:18:49 INFO - GECKO(4584) | 1584638329826 Marionette DEBUG 1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2020-03-19T17:18:49.837Z] 17:18:49 INFO - GECKO(4584) | 1584638329831 Marionette DEBUG 1 -> [0,4,"Marionette:GetContext",{}]
[task 2020-03-19T17:18:49.837Z] 17:18:49 INFO - GECKO(4584) | 1584638329832 Marionette DEBUG 1 <- [1,4,null,{"value":"content"}]
[task 2020-03-19T17:18:49.845Z] 17:18:49 INFO - GECKO(4584) | 1584638329840 Marionette DEBUG 1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-03-19T17:18:49.846Z] 17:18:49 INFO - GECKO(4584) | 1584638329840 Marionette DEBUG 1 <- [1,5,null,{"value":null}]
[task 2020-03-19T17:18:49.846Z] 17:18:49 INFO - GECKO(4584) | 1584638329841 Marionette DEBUG 1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/
This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... mochi.test:8888/redirect.html","flavor":"chrome"}],"filename":"tests/mochitest/runtests.py","sandbox":"default","line":1793}]
[task 2020-03-19T17:18:49.867Z] 17:18:49 INFO - GECKO(4584) | 1584638329860 Marionette DEBUG 1 <- [1,6,null,{"value":null}]
[task 2020-03-19T17:18:49.908Z] 17:18:49 INFO - GECKO(4584) | 1584638329906 Marionette DEBUG 1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2020-03-19T17:18:49.908Z] 17:18:49 INFO - GECKO(4584) | 1584638329906 Marionette DEBUG 1 <- [1,7,null,{"value":null}]
[task 2020-03-19T17:18:50.031Z] 17:18:50 INFO - GECKO(4584) | 1584638330026 Marionette DEBUG 1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2020-03-19T17:18:50.032Z] 17:18:50 INFO - GECKO(4584) | 1584638330026 Marionette DEBUG 1 <- [1,8,null,{"value":null}]
[task 2020-03-19T17:18:50.033Z] 17:18:50 INFO - runtests.py | Waiting for browser...
[task 2020-03-19T17:18:50.049Z] 17:18:50 INFO - GECKO(4584) | 1584638330044 Marionette DEBUG Closed connection 1

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