Closed
Bug 1750669
Opened 3 years ago
Closed 3 years ago
Intermittent browser/components/urlbar/tests/browser/browser_urlbar_event_telemetry_engagement.js | application terminated with exit code 1
Categories
(Firefox :: Address Bar, defect, P5)
Firefox
Address Bar
Tracking
()
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=364423510&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/AXljVsyvSHmDKJ-b2Y5nmw/runs/0/artifacts/public/logs/live_backing.log
[task 2022-01-18T01:06:41.308Z] 01:06:41 INFO - TEST-PASS | browser/components/urlbar/tests/browser/browser_urlbar_event_telemetry_engagement.js | Clipboard has the given value: 'www.example.com' -
[task 2022-01-18T01:06:41.309Z] 01:06:41 INFO - Buffered messages finished
[task 2022-01-18T01:06:41.309Z] 01:06:41 ERROR - TEST-UNEXPECTED-FAIL | browser/components/urlbar/tests/browser/browser_urlbar_event_telemetry_engagement.js | application terminated with exit code 1
[task 2022-01-18T01:06:41.310Z] 01:06:41 INFO - runtests.py | Application ran for: 0:12:17.599019
[task 2022-01-18T01:06:41.310Z] 01:06:41 INFO - zombiecheck | Reading PID log: /tmp/tmp8ejr3itlpidlog
[task 2022-01-18T01:06:41.310Z] 01:06:41 INFO - ==> process 2749 launched child process 2766
[task 2022-01-18T01:06:41.310Z] 01:06:41 INFO - ==> process 2749 launched child process 2820
[task 2022-01-18T01:06:41.311Z] 01:06:41 INFO - ==> process 2749 launched child process 2843
[task 2022-01-18T01:06:41.311Z] 01:06:41 INFO - ==> process 2749 launched child process 2881
[task 2022-01-18T01:06:41.312Z] 01:06:41 INFO - ==> process 2749 launched child process 2954
[task 2022-01-18T01:06:41.312Z] 01:06:41 INFO - ==> process 2749 launched child process 2978
[task 2022-01-18T01:06:41.313Z] 01:06:41 INFO - ==> process 2749 launched child process 3011
[task 2022-01-18T01:06:41.313Z] 01:06:41 INFO - zombiecheck | Checking for orphan process with PID: 2881
[task 2022-01-18T01:06:41.313Z] 01:06:41 INFO - zombiecheck | Checking for orphan process with PID: 2978
[task 2022-01-18T01:06:41.314Z] 01:06:41 INFO - zombiecheck | Checking for orphan process with PID: 3011
[task 2022-01-18T01:06:41.315Z] 01:06:41 INFO - zombiecheck | Checking for orphan process with PID: 2820
[task 2022-01-18T01:06:41.315Z] 01:06:41 INFO - zombiecheck | Checking for orphan process with PID: 2954
[task 2022-01-18T01:06:41.316Z] 01:06:41 INFO - zombiecheck | Checking for orphan process with PID: 2766
[task 2022-01-18T01:06:41.316Z] 01:06:41 INFO - zombiecheck | Checking for orphan process with PID: 2843
[task 2022-01-18T01:06:41.317Z] 01:06:41 INFO - Stopping web server
[task 2022-01-18T01:06:41.318Z] 01:06:41 INFO - Server shut down.
[task 2022-01-18T01:06:41.318Z] 01:06:41 INFO - Web server killed.
[task 2022-01-18T01:06:41.319Z] 01:06:41 INFO - Stopping web socket server
[task 2022-01-18T01:06:41.319Z] 01:06:41 INFO - Stopping ssltunnel
[task 2022-01-18T01:06:41.320Z] 01:06:41 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2022-01-18T01:06:41.321Z] 01:06:41 INFO - runtests.py | Running tests: end.
[task 2022-01-18T01:06:41.322Z] 01:06:41 INFO - Buffered messages finished
[task 2022-01-18T01:06:41.323Z] 01:06:41 INFO - Running manifest: browser/extensions/webcompat/tests/browser/browser.ini
[task 2022-01-18T01:06:41.384Z] 01:06:41 INFO - Setting pipeline to PAUSED ...
[task 2022-01-18T01:06:41.385Z] 01:06:41 INFO - Pipeline is PREROLLING ...
[task 2022-01-18T01:06:41.390Z] 01:06:41 INFO - Pipeline is PREROLLED ...
[task 2022-01-18T01:06:41.391Z] 01:06:41 INFO - Setting pipeline to PLAYING ...
[task 2022-01-18T01:06:41.391Z] 01:06:41 INFO - New clock: GstSystemClock
[task 2022-01-18T01:06:41.425Z] 01:06:41 INFO - Got EOS from element "pipeline0".
[task 2022-01-18T01:06:41.425Z] 01:06:41 INFO - Execution ended after 0:00:00.033408805
[task 2022-01-18T01:06:41.425Z] 01:06:41 INFO - Setting pipeline to PAUSED ...
[task 2022-01-18T01:06:41.425Z] 01:06:41 INFO - Setting pipeline to READY ...
[task 2022-01-18T01:06:41.426Z] 01:06:41 INFO - (gst-launch-1.0:3799): GStreamer-CRITICAL **: 01:06:41.424: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2022-01-18T01:06:41.427Z] 01:06:41 INFO - Setting pipeline to NULL ...
[task 2022-01-18T01:06:41.427Z] 01:06:41 INFO - Freeing pipeline ...
[task 2022-01-18T01:06:41.479Z] 01:06:41 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2022-01-18T01:06:41.484Z] 01:06:41 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2022-01-18T01:06:41.485Z] 01:06:41 INFO - UBSan enabled.
[task 2022-01-18T01:06:41.775Z] 01:06:41 INFO - PID 3811 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2022-01-18T01:06:41.944Z] 01:06:41 INFO - Increasing default timeout to 90 seconds
[task 2022-01-18T01:06:41.945Z] 01:06:41 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2022-01-18T01:06:41.951Z] 01:06:41 INFO - LSan enabled.
[task 2022-01-18T01:06:41.951Z] 01:06:41 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2022-01-18T01:06:41.952Z] 01:06:41 INFO - UBSan enabled.
[task 2022-01-18T01:06:41.952Z] 01:06:41 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2022-01-18T01:06:41.959Z] 01:06:41 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2022-01-18T01:06:41.959Z] 01:06:41 INFO - UBSan enabled.
[task 2022-01-18T01:06:41.964Z] 01:06:41 INFO - MochitestServer : launching ['/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/tmpks_xu55d.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 2022-01-18T01:06:41.965Z] 01:06:41 INFO - runtests.py | Server pid: 3824
[task 2022-01-18T01:06:41.973Z] 01:06:41 INFO - runtests.py | Websocket server pid: 3827
[task 2022-01-18T01:06:41.973Z] 01:06:41 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2022-01-18T01:06:41.981Z] 01:06:41 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2022-01-18T01:06:41.981Z] 01:06:41 INFO - UBSan enabled.
[task 2022-01-18T01:06:41.987Z] 01:06:41 INFO - runtests.py | SSL tunnel pid: 3833
[task 2022-01-18T01:06:42.440Z] 01:06:42 INFO - runtests.py | Running with scheme: http
[task 2022-01-18T01:06:42.440Z] 01:06:42 INFO - runtests.py | Running with e10s: True
[task 2022-01-18T01:06:42.441Z] 01:06:42 INFO - runtests.py | Running with fission: False
[task 2022-01-18T01:06:42.442Z] 01:06:42 INFO - runtests.py | Running with cross-origin iframes: False
[task 2022-01-18T01:06:42.442Z] 01:06:42 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2022-01-18T01:06:42.443Z] 01:06:42 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2022-01-18T01:06:42.444Z] 01:06:42 INFO - runtests.py | Running tests: start.
[task 2022-01-18T01:06:42.444Z] 01:06:42 INFO -
[task 2022-01-18T01:06:42.455Z] 01:06:42 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpks_xu55d.mozrunner
[task 2022-01-18T01:06:42.463Z] 01:06:42 INFO - runtests.py | Application pid: 3849
[task 2022-01-18T01:06:42.463Z] 01:06:42 INFO - TEST-INFO | started process GECKO(3849)
[task 2022-01-18T01:06:43.870Z] 01:06:43 INFO - GECKO(3849) | 1642468003869 Marionette INFO Marionette enabled
[task 2022-01-18T01:06:44.075Z] 01:06:44 INFO - GECKO(3849) | 1642468004074 Marionette TRACE Received observer notification toplevel-window-ready
[task 2022-01-18T01:06:47.088Z] 01:06:47 INFO - GECKO(3849) | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpks_xu55d.mozrunner/search.json.mozlz4", (void 0)))
[task 2022-01-18T01:06:49.800Z] 01:06:49 INFO - GECKO(3849) | 1642468009795 Marionette TRACE Received observer notification marionette-startup-requested
[task 2022-01-18T01:06:49.801Z] 01:06:49 INFO - GECKO(3849) | 1642468009795 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
[task 2022-01-18T01:06:49.843Z] 01:06:49 INFO - GECKO(3849) | 1642468009842 Marionette TRACE All scripts recorded.
[task 2022-01-18T01:06:49.850Z] 01:06:49 INFO - GECKO(3849) | 1642468009849 Marionette INFO Listening on port 2828
[task 2022-01-18T01:06:49.851Z] 01:06:49 INFO - GECKO(3849) | 1642468009850 Marionette DEBUG Marionette is listening
[task 2022-01-18T01:06:49.936Z] 01:06:49 INFO - GECKO(3849) | 1642468009934 Marionette DEBUG Accepted connection 0 from 127.0.0.1:40454
[task 2022-01-18T01:06:49.965Z] 01:06:49 INFO - GECKO(3849) | 1642468009964 Marionette DEBUG Accepted connection 1 from 127.0.0.1:40456
[task 2022-01-18T01:06:49.967Z] 01:06:49 INFO - GECKO(3849) | 1642468009965 Marionette DEBUG Closed connection 0
[task 2022-01-18T01:06:49.983Z] 01:06:49 INFO - GECKO(3849) | 1642468009982 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-01-18T01:06:50.009Z] 01:06:50 INFO - GECKO(3849) | 1642468010008 RemoteAgent TRACE [19] Document already finished loading: about:blank
[task 2022-01-18T01:06:50.020Z] 01:06:50 INFO - GECKO(3849) | 1642468010019 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"3950985c-f558-45ad-ad39-90c41e0b89ea","capabilities":{"browserName":"firefox","browserVersion":"98.0a ... .mozrunner","moz:shutdownTimeout":300000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2022-01-18T01:06:50.046Z] 01:06:50 INFO - GECKO(3849) | 1642468010044 Marionette DEBUG 1 -> [0,2,"Addon:Install",{"path":"/tmp/tmp7wg19z8v.zip","temporary":false}]
[task 2022-01-18T01:06:50.234Z] 01:06:50 INFO - GECKO(3849) | 1642468010233 Marionette DEBUG 1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2022-01-18T01:06:50.305Z] 01:06:50 INFO - GECKO(3849) | 1642468010302 Marionette DEBUG 1 -> [0,3,"Addon:Install",{"path":"/tmp/tmpvwfsivcj.zip","temporary":false}]
[task 2022-01-18T01:06:50.341Z] 01:06:50 INFO - GECKO(3849) | 1642468010340 Marionette DEBUG 1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2022-01-18T01:06:50.352Z] 01:06:50 INFO - GECKO(3849) | 1642468010351 Marionette DEBUG 1 -> [0,4,"Marionette:GetContext",{}]
[task 2022-01-18T01:06:50.354Z] 01:06:50 INFO - GECKO(3849) | 1642468010353 Marionette DEBUG 1 <- [1,4,null,{"value":"content"}]
[task 2022-01-18T01:06:50.356Z] 01:06:50 INFO - GECKO(3849) | 1642468010355 Marionette DEBUG 1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-01-18T01:06:50.357Z] 01:06:50 INFO - GECKO(3849) | 1642468010356 Marionette DEBUG 1 <- [1,5,null,{"value":null}]
[task 2022-01-18T01:06:50.360Z] 01:06:50 INFO - GECKO(3849) | 1642468010359 Marionette DEBUG 1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... hrome","testUrl":"about:blank"}],"newSandbox":true,"sandbox":"default","line":1945,"filename":"tests/mochitest/runtests.py"}]
[task 2022-01-18T01:06:50.373Z] 01:06:50 INFO - GECKO(3849) | 1642468010372 Marionette TRACE [7] MarionetteCommands actor created for window id 2
[task 2022-01-18T01:06:50.428Z] 01:06:50 INFO - GECKO(3849) | 1642468010427 Marionette TRACE Received observer notification domwindowopened
[task 2022-01-18T01:06:50.442Z] 01:06:50 INFO - GECKO(3849) | 1642468010437 Marionette DEBUG 1 <- [1,6,null,{"value":null}]
[task 2022-01-18T01:06:50.443Z] 01:06:50 INFO - GECKO(3849) | 1642468010440 Marionette DEBUG 1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2022-01-18T01:06:50.444Z] 01:06:50 INFO - GECKO(3849) | 1642468010441 Marionette DEBUG 1 <- [1,7,null,{"value":null}]
[task 2022-01-18T01:06:50.459Z] 01:06:50 INFO - GECKO(3849) | 1642468010458 Marionette DEBUG 1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2022-01-18T01:06:50.464Z] 01:06:50 INFO - GECKO(3849) | 1642468010463 Marionette DEBUG 1 <- [1,8,null,{"value":null}]
[task 2022-01-18T01:06:50.533Z] 01:06:50 INFO - runtests.py | Waiting for browser...
[task 2022-01-18T01:06:50.574Z] 01:06:50 INFO - GECKO(3849) | 1642468010573 Marionette DEBUG Closed connection 1
[task 2022-01-18T01:06:51.280Z] 01:06:51 INFO - TEST-START | browser/extensions/webcompat/tests/browser/browser_shims.js
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•