1606513 - Intermittent browser/extensions/formautofill/test/mochitest/test_multi_locale_CA_address_form.html | application terminated with exit code -6

Status: RESOLVED INCOMPLETE

(Toolkit :: Form Autofill, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2019-12-31T22:59:22.117Z] 22:59:22 INFO - TEST-START | browser/extensions/formautofill/test/mochitest/test_multi_locale_CA_address_form.html
[task 2019-12-31T22:59:25.669Z] 22:59:25 INFO - TEST-PASS | browser/extensions/formautofill/test/mochitest/test_multi_locale_CA_address_form.html | Checking address-level1 field
[task 2019-12-31T22:59:25.669Z] 22:59:25 INFO - add_task | Leaving test autofill_with_level1_code
[task 2019-12-31T22:59:25.670Z] 22:59:25 INFO - add_task | Entering test autofill_with_level1_full_name
[task 2019-12-31T22:59:25.671Z] 22:59:25 INFO - expecting the target input being focused and indentified
[task 2019-12-31T22:59:25.671Z] 22:59:25 INFO - expecting a popup
[task 2019-12-31T22:59:25.673Z] 22:59:25 INFO - Buffered messages finished
[task 2019-12-31T22:59:25.674Z] 22:59:25 ERROR - TEST-UNEXPECTED-FAIL | browser/extensions/formautofill/test/mochitest/test_multi_locale_CA_address_form.html | application terminated with exit code -6
[task 2019-12-31T22:59:25.674Z] 22:59:25 INFO - runtests.py | Application ran for: 0:00:38.328305
[task 2019-12-31T22:59:25.675Z] 22:59:25 INFO - zombiecheck | Reading PID log: /tmp/tmpoQiZU5pidlog
[task 2019-12-31T22:59:25.676Z] 22:59:25 INFO - ==> process 2649 launched child process 2664
[task 2019-12-31T22:59:25.677Z] 22:59:25 INFO - ==> process 2649 launched child process 2702
[task 2019-12-31T22:59:25.677Z] 22:59:25 INFO - ==> process 2649 launched child process 2715
[task 2019-12-31T22:59:25.679Z] 22:59:25 INFO - ==> process 2649 launched child process 2789
[task 2019-12-31T22:59:25.680Z] 22:59:25 INFO - zombiecheck | Checking for orphan process with PID: 2664
[task 2019-12-31T22:59:25.681Z] 22:59:25 INFO - zombiecheck | Checking for orphan process with PID: 2715
[task 2019-12-31T22:59:25.681Z] 22:59:25 INFO - zombiecheck | Checking for orphan process with PID: 2789
[task 2019-12-31T22:59:25.681Z] 22:59:25 INFO - zombiecheck | Checking for orphan process with PID: 2702
[task 2019-12-31T22:59:25.682Z] 22:59:25 INFO - Stopping web server
[task 2019-12-31T22:59:25.682Z] 22:59:25 INFO - Stopping web socket server
[task 2019-12-31T22:59:25.686Z] 22:59:25 INFO - Stopping ssltunnel
[task 2019-12-31T22:59:25.706Z] 22:59:25 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2019-12-31T22:59:25.707Z] 22:59:25 INFO - runtests.py | Running tests: end.
[task 2019-12-31T22:59:25.722Z] 22:59:25 INFO - Buffered messages finished
[task 2019-12-31T22:59:25.722Z] 22:59:25 INFO - Running manifest: caps/tests/mochitest/mochitest.ini
[task 2019-12-31T22:59:25.743Z] 22:59:25 INFO - Setting pipeline to PAUSED ...
[task 2019-12-31T22:59:25.743Z] 22:59:25 INFO - Pipeline is PREROLLING ...
[task 2019-12-31T22:59:25.746Z] 22:59:25 INFO - Pipeline is PREROLLED ...
[task 2019-12-31T22:59:25.746Z] 22:59:25 INFO - Setting pipeline to PLAYING ...
[task 2019-12-31T22:59:25.747Z] 22:59:25 INFO - New clock: GstSystemClock
[task 2019-12-31T22:59:25.783Z] 22:59:25 INFO - Got EOS from element "pipeline0".
[task 2019-12-31T22:59:25.783Z] 22:59:25 INFO - Execution ended after 0:00:00.033405545
[task 2019-12-31T22:59:25.783Z] 22:59:25 INFO - Setting pipeline to PAUSED ...
[task 2019-12-31T22:59:25.783Z] 22:59:25 INFO - Setting pipeline to READY ...
[task 2019-12-31T22:59:25.783Z] 22:59:25 INFO - (gst-launch-1.0:2834): GStreamer-CRITICAL *: 22:59:25.778: gst_object_unref: assertion '((GObject ) object)->ref_count > 0' failed
[task 2019-12-31T22:59:25.783Z] 22:59:25 INFO - Setting pipeline to NULL ...
[task 2019-12-31T22:59:25.783Z] 22:59:25 INFO - Freeing pipeline ...
[task 2019-12-31T22:59:25.818Z] 22:59:25 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2019-12-31T22:59:25.819Z] 22:59:25 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2019-12-31T22:59:25.819Z] 22:59:25 INFO - UBSan enabled.
[task 2019-12-31T22:59:26.168Z] 22:59:26 INFO - pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2019-12-31T22:59:26.209Z] 22:59:26 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2019-12-31T22:59:26.212Z] 22:59:26 INFO - LSan enabled.
[task 2019-12-31T22:59:26.213Z] 22:59:26 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2019-12-31T22:59:26.213Z] 22:59:26 INFO - UBSan enabled.
[task 2019-12-31T22:59:26.213Z] 22:59:26 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2019-12-31T22:59:26.217Z] 22:59:26 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2019-12-31T22:59:26.217Z] 22:59:26 INFO - UBSan enabled.
[task 2019-12-31T22:59:26.224Z] 22:59:26 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/tmpidsWcJ.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 2019-12-31T22:59:26.224Z] 22:59:26 INFO - runtests.py | Server pid: 2860
[task 2019-12-31T22:59:26.228Z] 22:59:26 INFO - runtests.py | Websocket server pid: 2863
[task 2019-12-31T22:59:26.229Z] 22:59:26 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2019-12-31T22:59:26.237Z] 22:59:26 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2019-12-31T22:59:26.238Z] 22:59:26 INFO - UBSan enabled.
[task 2019-12-31T22:59:26.253Z] 22:59:26 INFO - runtests.py | SSL tunnel pid: 2868
[task 2019-12-31T22:59:26.652Z] 22:59:26 INFO - runtests.py | Running with scheme: http
[task 2019-12-31T22:59:26.652Z] 22:59:26 INFO - runtests.py | Running with e10s: True
[task 2019-12-31T22:59:26.652Z] 22:59:26 INFO - runtests.py | Running with fission: False
[task 2019-12-31T22:59:26.652Z] 22:59:26 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2019-12-31T22:59:26.652Z] 22:59:26 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2019-12-31T22:59:26.652Z] 22:59:26 INFO - runtests.py | Running tests: start.
[task 2019-12-31T22:59:26.652Z] 22:59:26 INFO -
[task 2019-12-31T22:59:26.667Z] 22:59:26 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpidsWcJ.mozrunner
[task 2019-12-31T22:59:26.667Z] 22:59:26 INFO - runtests.py | Application pid: 2882
[task 2019-12-31T22:59:26.669Z] 22:59:26 INFO - TEST-INFO | started process GECKO(2882)
[task 2019-12-31T22:59:27.699Z] 22:59:27 INFO - GECKO(2882) | 1577833167696 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: networkStatus
[task 2019-12-31T22:59:28.842Z] 22:59:28 INFO - GECKO(2882) | 1577833168831 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: mozillaAddons
[task 2019-12-31T22:59:28.843Z] 22:59:28 INFO - GECKO(2882) | 1577833168831 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: telemetry
[task 2019-12-31T22:59:28.843Z] 22:59:28 INFO - GECKO(2882) | 1577833168832 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2019-12-31T22:59:28.843Z] 22:59:28 INFO - GECKO(2882) | 1577833168832 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: about:reader
[task 2019-12-31T22:59:29.140Z] 22:59:29 INFO - GECKO(2882) | 1577833169135 Marionette TRACE Received observer notification profile-after-change
[task 2019-12-31T22:59:29.241Z] 22:59:29 INFO - GECKO(2882) | 1577833169239 Marionette TRACE Received observer notification command-line-startup
[task 2019-12-31T22:59:29.242Z] 22:59:29 INFO - GECKO(2882) | 1577833169240 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false)
[task 2019-12-31T22:59:29.445Z] 22:59:29 INFO - GECKO(2882) | 1577833169439 Marionette TRACE Received observer notification toplevel-window-ready
[task 2019-12-31T22:59:35.762Z] 22:59:35 INFO - GECKO(2882) | 1577833175758 Marionette TRACE Received observer notification marionette-startup-requested
[task 2019-12-31T22:59:35.763Z] 22:59:35 INFO - GECKO(2882) | 1577833175759 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
[task 2019-12-31T22:59:35.859Z] 22:59:35 INFO - GECKO(2882) | 1577833175853 Marionette TRACE All scripts recorded.
[task 2019-12-31T22:59:36.080Z] 22:59:36 INFO - GECKO(2882) | 1577833176078 Marionette INFO Listening on port 2828
[task 2019-12-31T22:59:36.082Z] 22:59:36 INFO - GECKO(2882) | 1577833176079 Marionette DEBUG Marionette is listening
[task 2019-12-31T22:59:36.117Z] 22:59:36 INFO - GECKO(2882) | 1577833176115 Marionette DEBUG Accepted connection 0 from 127.0.0.1:60138
[task 2019-12-31T22:59:36.133Z] 22:59:36 INFO - GECKO(2882) | 1577833176127 Marionette DEBUG Closed connection 0
[task 2019-12-31T22:59:36.135Z] 22:59:36 INFO - GECKO(2882) | 1577833176128 Marionette DEBUG Accepted connection 1 from 127.0.0.1:60140
[task 2019-12-31T22:59:36.143Z] 22:59:36 INFO - GECKO(2882) | 1577833176131 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2019-12-31T22:59:36.247Z] 22:59:36 INFO - GECKO(2882) | 1577833176237 Marionette TRACE [17] Frame script loaded
[task 2019-12-31T22:59:36.247Z] 22:59:36 INFO - GECKO(2882) | 1577833176243 Marionette TRACE [17] Frame script registered
[task 2019-12-31T22:59:36.288Z] 22:59:36 INFO - GECKO(2882) | 1577833176275 Marionette TRACE [23] Frame script loaded
[task 2019-12-31T22:59:36.288Z] 22:59:36 INFO - GECKO(2882) | 1577833176279 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"05ec1125-3fbd-4e48-8b53-9b19eaae6448","capabilities":{"browserName":"firefox","browserVersion":"73.0a ... p/tmpidsWcJ.mozrunner","moz:shutdownTimeout":180000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2019-12-31T22:59:36.324Z] 22:59:36 INFO - GECKO(2882) | 1577833176313 Marionette TRACE [23] Frame script registered
[task 2019-12-31T22:59:36.339Z] 22:59:36 INFO - GECKO(2882) | 1577833176332 Marionette DEBUG 1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpLFEAVc.zip","temporary":false}]
[task 2019-12-31T22:59:36.474Z] 22:59:36 INFO - GECKO(2882) | 1577833176464 Marionette DEBUG 1 <- [1,2,null,{"value":"[e-mail]"}]
[task 2019-12-31T22:59:36.490Z] 22:59:36 INFO - GECKO(2882) | 1577833176486 Marionette DEBUG 1 -> [0,3,"Addon:Install",{"path":"/tmp/tmpiiYAEY.zip","temporary":false}]
[task 2019-12-31T22:59:36.558Z] 22:59:36 INFO - GECKO(2882) | 1577833176552 Marionette DEBUG 1 <- [1,3,null,{"value":"[e-mail]"}]
[task 2019-12-31T22:59:36.559Z] 22:59:36 INFO - GECKO(2882) | 1577833176554 Marionette DEBUG 1 -> [0,4,"Marionette:GetContext",{}]
[task 2019-12-31T22:59:36.560Z] 22:59:36 INFO - GECKO(2882) | 1577833176555 Marionette DEBUG 1 <- [1,4,null,{"value":"content"}]
[task 2019-12-31T22:59:36.567Z] 22:59:36 INFO - GECKO(2882) | 1577833176562 Marionette DEBUG 1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-12-31T22:59:36.568Z] 22:59:36 INFO - GECKO(2882) | 1577833176563 Marionette DEBUG 1 <- [1,5,null,{"value":null}]
[task 2019-12-31T22:59:36.569Z] 22:59:36 INFO - GECKO(2882) | 1577833176565 Marionette DEBUG 1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/ This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... %2Ftmp&cleanupCrashes=true","flavor":"mochitest"}],"filename":"tests/mochitest/runtests.py","sandbox":"default","line":1772}]
[task 2019-12-31T22:59:36.585Z] 22:59:36 INFO - GECKO(2882) | 1577833176576 Marionette DEBUG 1 <- [1,6,null,{"value":null}]
[task 2019-12-31T22:59:36.636Z] 22:59:36 INFO - GECKO(2882) | 1577833176626 Marionette DEBUG 1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2019-12-31T22:59:36.637Z] 22:59:36 INFO - GECKO(2882) | 1577833176627 Marionette DEBUG 1 <- [1,7,null,{"value":null}]
[task 2019-12-31T22:59:36.712Z] 22:59:36 INFO - GECKO(2882) | 1577833176703 Marionette DEBUG 1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2019-12-31T22:59:36.789Z] 22:59:36 INFO - GECKO(2882) | 1577833176779 Marionette DEBUG 1 <- [1,8,null,{"value":null}]
[task 2019-12-31T22:59:36.792Z] 22:59:36 INFO - runtests.py | Waiting for browser...
[task 2019-12-31T22:59:36.808Z] 22:59:36 INFO - GECKO(2882) | 1577833176796 Marionette DEBUG Closed connection 1

Comment 1

[Intermittent Failures Robot]

1 failures in 1862 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64-asan: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1606513&startday=2019-12-30&endday=2020-01-05&tree=all

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 3

[Intermittent Failures Robot]

1 failures in 4289 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1

Platform breakdown:

• linux1804-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1606513&startday=2020-07-27&endday=2020-08-02&tree=all

Comment 4

[Razvan Maries]

New occurrence: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=318205092&repo=autoland&lineNumber=2622

Comment 5

[Intermittent Failures Robot]

1 failures in 4130 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1606513&startday=2020-10-05&endday=2020-10-11&tree=all

Comment 6

[Intermittent Failures Robot]

1 failures in 4275 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1

Platform breakdown:

• linux1804-64-shippable-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1606513&startday=2020-10-26&endday=2020-11-01&tree=all

Comment 7

[BugBot [:suhaib / :marco/ :calixte]]

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