Closed Bug 1658475 Opened 5 years ago Closed 5 years ago

Intermittent browser/components/urlbar/tests/browser/browser_autocomplete_enter_race.js | application terminated with exit code 2147483651

Categories

(Firefox :: Address Bar, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=312660124&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ex2PXuE5Qq-6N0pIMnpiLw/runs/0/artifacts/public/logs/live_backing.log


[task 2020-08-10T23:14:42.007Z] 23:14:42 INFO - TEST-START | browser/components/urlbar/tests/browser/browser_autocomplete_enter_race.js
[task 2020-08-10T23:14:42.748Z] 23:14:42 INFO - GECKO(1936) | FATAL ERROR: Non-local network connections are disabled and a connection attempt to www.google.com (216.58.208.36) was made.
[task 2020-08-10T23:14:42.748Z] 23:14:42 INFO - GECKO(1936) | You should only access hostnames available via the test networking proxy (if running mochitests) or from a test-specific httpd.js server (if running xpcshell tests). Browser services should be disabled or redirected to a local server.
[task 2020-08-10T23:14:44.897Z] 23:14:44 INFO - GECKO(1936) | Exiting due to channel error.
[task 2020-08-10T23:14:44.897Z] 23:14:44 INFO - GECKO(1936) | Exiting due to channel error.
[task 2020-08-10T23:14:45.180Z] 23:14:45 INFO - TEST-INFO | Main app process: exit 80000003
[task 2020-08-10T23:14:45.180Z] 23:14:45 INFO - Buffered messages logged at 23:14:42
[task 2020-08-10T23:14:45.180Z] 23:14:45 INFO - Entering test bound setup
[task 2020-08-10T23:14:45.181Z] 23:14:45 INFO - TEST-PASS | browser/components/urlbar/tests/browser/browser_autocomplete_enter_race.js | Setup complete -
[task 2020-08-10T23:14:45.181Z] 23:14:45 INFO - Leaving test bound setup
[task 2020-08-10T23:14:45.181Z] 23:14:45 INFO - Entering test bound
[task 2020-08-10T23:14:45.181Z] 23:14:45 INFO - Awaiting for the urlbar panel to open
[task 2020-08-10T23:14:45.182Z] 23:14:45 INFO - wait for the page to load
[task 2020-08-10T23:14:45.182Z] 23:14:45 INFO - Buffered messages finished
[task 2020-08-10T23:14:45.182Z] 23:14:45 ERROR - TEST-UNEXPECTED-FAIL | browser/components/urlbar/tests/browser/browser_autocomplete_enter_race.js | application terminated with exit code 2147483651
[task 2020-08-10T23:14:45.183Z] 23:14:45 INFO - runtests.py | Application ran for: 0:02:30.276000
[task 2020-08-10T23:14:45.183Z] 23:14:45 INFO - zombiecheck | Reading PID log: c:\users\task_1597098872\appdata\local\temp\tmpf8wz78pidlog
[task 2020-08-10T23:14:45.184Z] 23:14:45 INFO - ==> process 7420 launched child process 4792 ("Z:\task_1597098872\build\application\firefox\firefox.exe" -contentproc --channel="7420.0.1455278943\1661130301" -parentBuildID 20200810213634 -prefsHandle 2232 -prefMapHandle 2208 -prefsLen 1 -prefMapSize 241840 -appdir "Z:\task_1597098872\build\application\firefox\browser" - 7420 gpu)
[task 2020-08-10T23:14:45.184Z] 23:14:45 INFO - ==> process 7420 launched child process 9940 ("Z:\task_1597098872\build\application\firefox\firefox.exe" -contentproc --channel="7420.6.94125272\1229913548" -childID 1 -isForBrowser -prefsHandle 2188 -prefMapHandle 2184 -prefsLen 1744 -prefMapSize 241840 -parentBuildID 20200810213634 -appdir "Z:\task_1597098872\build\application\firefox\browser" - 7420 tab)
[task 2020-08-10T23:14:45.185Z] 23:14:45 INFO - ==> process 7420 launched child process 10648 ("Z:\task_1597098872\build\application\firefox\firefox.exe" -contentproc --channel="7420.13.1452517635\535971207" -childID 2 -isForBrowser -prefsHandle 4832 -prefMapHandle 4828 -prefsLen 2158 -prefMapSize 241840 -noScache -parentBuildID 20200810213634 -appdir "Z:\task_1597098872\build\application\firefox\browser" - 7420 tab)
[task 2020-08-10T23:14:45.185Z] 23:14:45 INFO - ==> process 7420 launched child process 5828 ("Z:\task_1597098872\build\application\firefox\firefox.exe" -contentproc --channel="7420.20.212711947\144708627" -childID 3 -isForBrowser -prefsHandle 4988 -prefMapHandle 4992 -prefsLen 2198 -prefMapSize 241840 -parentBuildID 20200810213634 -appdir "Z:\task_1597098872\build\application\firefox\browser" - 7420 tab)
[task 2020-08-10T23:14:45.185Z] 23:14:45 INFO - ==> process 7420 launched child process 3656 ("Z:\task_1597098872\build\application\firefox\firefox.exe" -contentproc --channel="7420.27.45395717\654019652" -childID 4 -isForBrowser -prefsHandle 3272 -prefMapHandle 3324 -prefsLen 10666 -prefMapSize 241840 -noScache -parentBuildID 20200810213634 -appdir "Z:\task_1597098872\build\application\firefox\browser" - 7420 tab)
[task 2020-08-10T23:14:45.185Z] 23:14:45 INFO - ==> process 7420 launched child process 7464 ("Z:\task_1597098872\build\application\firefox\firefox.exe" -contentproc --channel="7420.34.868520590\1547301132" -childID 5 -isForBrowser -prefsHandle 4304 -prefMapHandle 4320 -prefsLen 12036 -prefMapSize 241840 -noScache -parentBuildID 20200810213634 -appdir "Z:\task_1597098872\build\application\firefox\browser" - 7420 tab)
[task 2020-08-10T23:14:45.185Z] 23:14:45 INFO - zombiecheck | Checking for orphan process with PID: 5828
[task 2020-08-10T23:14:45.185Z] 23:14:45 INFO - zombiecheck | Checking for orphan process with PID: 3656
[task 2020-08-10T23:14:45.185Z] 23:14:45 INFO - zombiecheck | Checking for orphan process with PID: 10648
[task 2020-08-10T23:14:45.186Z] 23:14:45 INFO - zombiecheck | Checking for orphan process with PID: 9940
[task 2020-08-10T23:14:45.186Z] 23:14:45 INFO - zombiecheck | Checking for orphan process with PID: 4792
[task 2020-08-10T23:14:45.186Z] 23:14:45 INFO - zombiecheck | Checking for orphan process with PID: 7464
[task 2020-08-10T23:14:45.186Z] 23:14:45 INFO - Stopping web server
[task 2020-08-10T23:14:45.204Z] 23:14:45 INFO - Stopping web socket server
[task 2020-08-10T23:14:45.233Z] 23:14:45 INFO - Stopping ssltunnel
[task 2020-08-10T23:14:45.258Z] 23:14:45 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2020-08-10T23:14:45.258Z] 23:14:45 INFO - runtests.py | Running tests: end.
[task 2020-08-10T23:14:45.299Z] 23:14:45 INFO - Buffered messages finished
[task 2020-08-10T23:14:45.299Z] 23:14:45 INFO - Running manifest: browser\modules\test\browser\formValidation\browser.ini
[task 2020-08-10T23:14:45.339Z] 23:14:45 INFO - INFO | runtests.py | ASan using symbolizer at Z:\task_1597098872\build\application\firefox\llvm-symbolizer.exe
[task 2020-08-10T23:14:45.400Z] 23:14:45 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2020-08-10T23:14:46.503Z] 23:14:46 INFO - Z:\task_1597098872\build\tests\bin\pk12util.exe: PKCS12 IMPORT SUCCESSFUL
[task 2020-08-10T23:14:46.786Z] 23:14:46 INFO - Increasing default timeout to 90 seconds
[task 2020-08-10T23:14:46.786Z] 23:14:46 INFO - INFO | runtests.py | ASan using symbolizer at Z:\task_1597098872\build\application\firefox\llvm-symbolizer.exe
[task 2020-08-10T23:14:46.846Z] 23:14:46 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2020-08-10T23:14:46.846Z] 23:14:46 INFO - INFO | runtests.py | ASan using symbolizer at Z:\task_1597098872\build\application\firefox\llvm-symbolizer.exe
[task 2020-08-10T23:14:46.905Z] 23:14:46 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2020-08-10T23:14:46.905Z] 23:14:46 INFO - MochitestServer : launching [u'Z:\task_1597098872\build\tests\bin\xpcshell.exe', '-g', 'Z:\task_1597098872\build\application\firefox', '-f', 'Z:\task_1597098872\build\tests\bin\components\httpd.js', '-e', "const _PROFILE_PATH = 'c:\\users\\task_1597098872\\appdata\\local\\temp\\tmper2t4u.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', 'Z:\task_1597098872\build\tests\mochitest\server.js']
[task 2020-08-10T23:14:46.905Z] 23:14:46 INFO - runtests.py | Server pid: 3824
[task 2020-08-10T23:14:46.907Z] 23:14:46 INFO - runtests.py | Websocket server pid: 3536
[task 2020-08-10T23:14:46.909Z] 23:14:46 INFO - INFO | runtests.py | ASan using symbolizer at Z:\task_1597098872\build\application\firefox\llvm-symbolizer.exe
[task 2020-08-10T23:14:46.979Z] 23:14:46 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2020-08-10T23:14:46.981Z] 23:14:46 INFO - runtests.py | SSL tunnel pid: 8592
[task 2020-08-10T23:14:47.388Z] 23:14:47 INFO - runtests.py | Running with scheme: http
[task 2020-08-10T23:14:47.388Z] 23:14:47 INFO - runtests.py | Running with e10s: True
[task 2020-08-10T23:14:47.388Z] 23:14:47 INFO - runtests.py | Running with fission: False
[task 2020-08-10T23:14:47.389Z] 23:14:47 INFO - runtests.py | Running with cross-origin iframes: False
[task 2020-08-10T23:14:47.389Z] 23:14:47 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2020-08-10T23:14:47.389Z] 23:14:47 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2020-08-10T23:14:47.389Z] 23:14:47 INFO - runtests.py | Running tests: start.
[task 2020-08-10T23:14:47.389Z] 23:14:47 INFO -
[task 2020-08-10T23:14:47.508Z] 23:14:47 WARNING - Found 'firefox' running before starting test browser!
[task 2020-08-10T23:14:47.508Z] 23:14:47 WARNING - {'username': None, 'cmdline': None, 'ppid': 7420, 'pid': 4824, 'name': 'firefox.exe'}
[task 2020-08-10T23:14:47.508Z] 23:14:47 WARNING -
[task 2020-08-10T23:14:47.508Z] 23:14:47 INFO - Application command: Z:\task_1597098872\build\application\firefox\firefox.exe -marionette --wait-for-browser -foreground -profile c:\users\task_1597098872\appdata\local\temp\tmper2t4u.mozrunner
[task 2020-08-10T23:14:47.509Z] 23:14:47 INFO - runtests.py | Application pid: 1788
[task 2020-08-10T23:14:47.509Z] 23:14:47 INFO - TEST-INFO | started process GECKO(1788)
[task 2020-08-10T23:14:50.130Z] 23:14:50 INFO - GECKO(1788) | 1597101290122 Marionette TRACE Marionette enabled
[task 2020-08-10T23:14:50.412Z] 23:14:50 INFO - GECKO(1788) | 1597101290402 Marionette TRACE Received observer notification toplevel-window-ready
[task 2020-08-10T23:14:53.982Z] 23:14:53 INFO - GECKO(1788) | console.error: SearchCache: "_readCacheFile: Error reading cache file:" (new Error("", "(unknown module)"))
[task 2020-08-10T23:14:56.770Z] 23:14:56 INFO - GECKO(1788) | 1597101296765 Marionette TRACE Received observer notification marionette-startup-requested
[task 2020-08-10T23:14:56.770Z] 23:14:56 INFO - GECKO(1788) | 1597101296766 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
[task 2020-08-10T23:14:56.849Z] 23:14:56 INFO - GECKO(1788) | 1597101296838 Marionette TRACE All scripts recorded.
[task 2020-08-10T23:14:57.088Z] 23:14:57 INFO - GECKO(1788) | 1597101297083 Marionette INFO Listening on port 2828
[task 2020-08-10T23:14:57.088Z] 23:14:57 INFO - GECKO(1788) | 1597101297084 Marionette DEBUG Marionette is listening
[task 2020-08-10T23:14:57.158Z] 23:14:57 INFO - GECKO(1788) | 1597101297152 Marionette DEBUG Accepted connection 0 from 127.0.0.1:50980
[task 2020-08-10T23:14:57.163Z] 23:14:57 INFO - GECKO(1788) | 1597101297159 Marionette DEBUG Closed connection 0
[task 2020-08-10T23:14:57.163Z] 23:14:57 INFO - GECKO(1788) | 1597101297162 Marionette DEBUG Accepted connection 1 from 127.0.0.1:50981
[task 2020-08-10T23:14:57.173Z] 23:14:57 INFO - GECKO(1788) | 1597101297167 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2020-08-10T23:14:57.274Z] 23:14:57 INFO - GECKO(1788) | 1597101297262 Marionette TRACE [20] Frame script loaded
[task 2020-08-10T23:14:57.274Z] 23:14:57 INFO - GECKO(1788) | 1597101297266 Marionette TRACE [20] Frame script registered
[task 2020-08-10T23:14:57.292Z] 23:14:57 INFO - GECKO(1788) | 1597101297282 Marionette TRACE [39] Frame script loaded
[task 2020-08-10T23:14:57.292Z] 23:14:57 INFO - GECKO(1788) | 1597101297286 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"6fa09c52-05dd-48bb-a30d-90c6d297b25f","capabilities":{"browserName":"firefox","browserVersion":"81.0a ... \tmper2t4u.mozrunner","moz:shutdownTimeout":180000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2020-08-10T23:14:57.317Z] 23:14:57 INFO - GECKO(1788) | 1597101297312 Marionette TRACE [39] Frame script registered
[task 2020-08-10T23:14:57.342Z] 23:14:57 INFO - GECKO(1788) | 1597101297340 Marionette DEBUG 1 -> [0,2,"Addon:Install",{"path":"c:\users\task_1597098872\appdata\local\temp\tmpeccjr9.zip","temporary":false}]
[task 2020-08-10T23:14:57.584Z] 23:14:57 INFO - GECKO(1788) | 1597101297576 Marionette DEBUG 1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2020-08-10T23:14:57.605Z] 23:14:57 INFO - GECKO(1788) | 1597101297602 Marionette DEBUG 1 -> [0,3,"Addon:Install",{"path":"c:\users\task_1597098872\appdata\local\temp\tmpofmsav.zip","temporary":false}]
[task 2020-08-10T23:14:57.664Z] 23:14:57 INFO - GECKO(1788) | 1597101297663 Marionette DEBUG 1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2020-08-10T23:14:57.674Z] 23:14:57 INFO - GECKO(1788) | 1597101297669 Marionette DEBUG 1 -> [0,4,"Marionette:GetContext",{}]
[task 2020-08-10T23:14:57.674Z] 23:14:57 INFO - GECKO(1788) | 1597101297670 Marionette DEBUG 1 <- [1,4,null,{"value":"content"}]
[task 2020-08-10T23:14:57.693Z] 23:14:57 INFO - GECKO(1788) | 1597101297681 Marionette DEBUG 1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-08-10T23:14:57.693Z] 23:14:57 INFO - GECKO(1788) | 1597101297682 Marionette DEBUG 1 <- [1,5,null,{"value":null}]
[task 2020-08-10T23:14:57.693Z] 23:14:57 INFO - GECKO(1788) | 1597101297687 Marionette DEBUG 1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... ":"browser-chrome"}],"filename":"Z:\task_1597098872\build\tests\mochitest\runtests.py","sandbox":"default","line":1834}]
[task 2020-08-10T23:14:57.743Z] 23:14:57 INFO - GECKO(1788) | 1597101297736 Marionette DEBUG 1 <- [1,6,null,{"value":null}]
[task 2020-08-10T23:14:57.788Z] 23:14:57 INFO - GECKO(1788) | 1597101297783 Marionette TRACE Received observer notification toplevel-window-ready
[task 2020-08-10T23:14:57.849Z] 23:14:57 INFO - GECKO(1788) | 1597101297841 Marionette DEBUG 1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2020-08-10T23:14:57.849Z] 23:14:57 INFO - GECKO(1788) | 1597101297842 Marionette DEBUG 1 <- [1,7,null,{"value":null}]
[task 2020-08-10T23:14:57.854Z] 23:14:57 INFO - GECKO(1788) | 1597101297849 Marionette DEBUG 1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2020-08-10T23:14:57.854Z] 23:14:57 INFO - GECKO(1788) | 1597101297852 Marionette DEBUG 1 <- [1,8,null,{"value":null}]
[task 2020-08-10T23:14:57.913Z] 23:14:57 INFO - runtests.py | Waiting for browser...
[task 2020-08-10T23:14:57.918Z] 23:14:57 INFO - GECKO(1788) | 1597101297915 Marionette DEBUG Closed connection 1
[task 2020-08-10T23:14:58.443Z] 23:14:58 INFO - TEST-START | browser/modules/test/browser/formValidation/browser_validation_invisible.js
[task 2020-08-10T23:14:59.954Z] 23:14:59 INFO - GECKO(1788) | MEMORY STAT heapAllocated not supported in this build configuration.
[task 2020-08-10T23:14:59.954Z] 23:14:59 INFO - GECKO(1788) | MEMORY STAT | vsize 19406251MB | vsizeMaxContiguous 65600148MB | residentFast 1045MB
[task 2020-08-10T23:14:59.954Z] 23:14:59 INFO - TEST-OK | browser/modules/test/browser/formValidation/browser_validation_invisible.js | took 1517ms
[task 2020-08-10T23:14:59.985Z] 23:14:59 INFO - checking window state
[task 2020-08-10T23:15:01.774Z] 23:15:01 INFO - GECKO(1788) | Completed ShutdownLeaks collections in process 8412
[task 2020-08-10T23:15:01.779Z] 23:15:01 INFO - GECKO(1788) | Completed ShutdownLeaks collections in process 92
[task 2020-08-10T23:15:01.800Z] 23:15:01 INFO - GECKO(1788) | Completed ShutdownLeaks collections in process 8132
[task 2020-08-10T23:15:01.819Z] 23:15:01 INFO - GECKO(1788) | Completed ShutdownLeaks collections in process 7492
[task 2020-08-10T23:15:01.819Z] 23:15:01 INFO - GECKO(1788) | Completed ShutdownLeaks collections in process 8960
[task 2020-08-10T23:15:02.590Z] 23:15:02 INFO - GECKO(1788) | Completed ShutdownLeaks collections in process 3112
[task 2020-08-10T23:15:02.590Z] 23:15:02 INFO - TEST-START | Shutdown
[task 2020-08-10T23:15:02.592Z] 23:15:02 INFO - Browser Chrome Test Summary
[task 2020-08-10T23:15:02.593Z] 23:15:02 INFO - Passed: 3
[task 2020-08-10T23:15:02.593Z] 23:15:02 INFO - Failed: 0
[task 2020-08-10T23:15:02.593Z] 23:15:02 INFO - Todo: 0
[task 2020-08-10T23:15:02.593Z] 23:15:02 INFO - Mode: e10s
[task 2020-08-10T23:15:02.593Z] 23:15:02 INFO - *** End BrowserChrome Test Results ***

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