1430633 - Intermittent browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | Test timed out.

Reporter

Description

•

6 years ago

+++ This bug was initially created as a clone of Bug #1406581 +++

Filed by: nbeleuzu [at] mozilla.com

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

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

Matthew N. [:MattN]

Updated

•

6 years ago

Component: Form Manager → Form Autofill

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* linux64-qr: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1430633&startday=2018-03-05&endday=2018-03-11&tree=all

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* try: 1

Platform breakdown:
* linux32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1430633&startday=2018-03-26&endday=2018-04-01&tree=trunk

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* linux64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1430633&startday=2018-05-14&endday=2018-05-20&tree=trunk

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* linux64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1430633&startday=2018-05-21&endday=2018-05-27&tree=trunk

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* linux64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1430633&startday=2018-06-18&endday=2018-06-24&tree=trunk

Comment hidden (Intermittent Failures Robot)

5 failures in 2584 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* autoland: 2
* mozilla-inbound: 1
* mozilla-central: 2

Platform breakdown:
* osx-10-10: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1430633&startday=2018-10-22&endday=2018-10-28&tree=all

Comment hidden (Intermittent Failures Robot)

7 failures in 2978 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-inbound: 5
* autoland: 1
* try: 1

Platform breakdown:
* macosx64-nightly: 1
* osx-10-10: 6

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

Comment hidden (Intermittent Failures Robot)

7 failures in 3007 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* try: 1
* mozilla-inbound: 1
* autoland: 5

Platform breakdown:
* osx-10-10: 6
* macosx64-nightly: 1

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

Comment hidden (Intermittent Failures Robot)

6 failures in 2917 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* autoland: 4
* mozilla-inbound: 2

Platform breakdown:
* osx-10-10: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1430633&startday=2018-11-12&endday=2018-11-18&tree=all

Comment hidden (Intermittent Failures Robot)

5 failures in 2387 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-inbound: 2
* autoland: 3

Platform breakdown:
* osx-10-10: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1430633&startday=2018-11-19&endday=2018-11-25&tree=all

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* autoland: 2
* try: 1
* mozilla-central: 1

Platform breakdown:
* osx-10-10: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1430633&startday=2018-11-26&endday=2018-12-02&tree=all

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* mozilla-central: 1
* mozilla-inbound: 1
* autoland: 4

Platform breakdown:
* osx-10-10: 5
* macosx64-nightly: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1430633&startday=2018-12-03&endday=2018-12-09&tree=all

Comment hidden (Intermittent Failures Robot)

4 failures in 2485 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* try: 3
* autoland: 1

Platform breakdown:
* linux64: 1
* linux64-qr: 1
* osx-10-10: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1430633&startday=2018-12-10&endday=2018-12-16&tree=all

Comment hidden (Intermittent Failures Robot)

4 failures in 2648 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-central: 1
* autoland: 3

Platform breakdown:
* osx-10-10: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1430633&startday=2018-12-17&endday=2018-12-23&tree=all

Comment hidden (Intermittent Failures Robot)

5 failures in 1668 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-inbound: 2
* autoland: 2
* mozilla-central: 1

Platform breakdown:
* osx-10-10: 2
* linux64-qr: 1
* linux64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1430633&startday=2018-12-31&endday=2019-01-06&tree=all

Comment hidden (Intermittent Failures Robot)

Andreea Pavel [:apavel]

Comment 20

•

5 years ago

There are 25 total failures in the last 7 days and 69 total failures in the last 30 days.

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=227436946&repo=mozilla-central&lineNumber=3964

[task 2019-02-09T22:50:47.568Z] 22:50:47 [task 2019-02-09T22:50:47.590Z] 22:50:47 [task 2019-02-09T22:50:48.661Z] 22:50:48 [task 2019-02-09T22:50:49.674Z] 22:50:49 [task 2019-02-09T22:50:49.712Z] 22:50:49 [task 2019-02-09T22:50:50.075Z] 22:50:50 [task 2019-02-09T22:50:50.077Z] 22:50:50 [task 2019-02-09T22:50:52.203Z] 22:50:52 [task 2019-02-09T22:50:52.204Z] 22:50:52 [task 2019-02-09T22:50:52.651Z] 22:50:52 [task 2019-02-09T22:50:52.655Z] 22:50:52 [task 2019-02-09T22:50:55.798Z] 22:50:55 [task 2019-02-09T22:50:55.799Z] 22:50:55 [task 2019-02-09T22:50:55.799Z] 22:50:55 [task 2019-02-09T22:50:55.800Z] 22:50:55 [task 2019-02-09T22:50:55.800Z] 22:50:55 [task 2019-02-09T22:50:56.423Z] 22:50:56 [task 2019-02-09T22:50:56.424Z] 22:50:56 [task 2019-02-09T22:50:56.562Z] 22:50:56 [task 2019-02-09T22:50:56.563Z] 22:50:56 [task 2019-02-09T22:50:57.855Z] 22:50:57 [task 2019-02-09T22:50:57.856Z] 22:50:57 [task 2019-02-09T22:51:00.929Z] 22:51:00 [task 2019-02-09T22:51:02.790Z] 22:51:02 [task 2019-02-09T22:51:02.791Z] 22:51:02 [task 2019-02-09T22:51:02.855Z] 22:51:02 [task 2019-02-09T22:51:02.857Z] 22:51:02 [task 2019-02-09T22:55:47.587Z] 22:55:47 [task 2019-02-09T22:55:48.100Z] 22:55:48 [task 2019-02-09T22:55:48.102Z] 22:55:48 [task 2019-02-09T22:55:48.103Z] 22:55:48 [task 2019-02-09T22:55:48.105Z] 22:55:48 [task 2019-02-09T22:55:48.106Z] 22:55:48 [task 2019-02-09T22:55:48.107Z] 22:55:48 [task 2019-02-09T22:55:48.109Z] 22:55:48 [task 2019-02-09T22:55:48.111Z] 22:55:48 [task 2019-02-09T22:55:48.112Z] 22:55:48 [task 2019-02-09T22:55:48.113Z] 22:55:48 [task 2019-02-09T22:55:48.114Z] 22:55:48 [task 2019-02-09T22:55:48.115Z] 22:55:48 [task 2019-02-09T22:55:48.116Z] 22:55:48 [task 2019-02-09T22:55:48.118Z] 22:55:48 [task 2019-02-09T22:55:48.118Z] 22:55:48 [task 2019-02-09T22:55:48.121Z] 22:55:48 [task 2019-02-09T22:55:48.122Z] 22:55:48 [task 2019-02-09T22:55:48.124Z] 22:55:48 [task 2019-02-09T22:55:48.126Z] 22:55:48 [task 2019-02-09T22:55:48.127Z] 22:55:48 [task 2019-02-09T22:55:48.128Z] 22:55:48 [task 2019-02-09T22:55:48.129Z] 22:55:48 [task 2019-02-09T22:55:48.130Z] 22:55:48 [task 2019-02-09T22:55:48.134Z] 22:55:48 [task 2019-02-09T22:55:48.134Z] 22:55:48 [task 2019-02-09T22:55:48.135Z] 22:55:48 [task 2019-02-09T22:55:48.135Z] 22:55:48 [task 2019-02-09T22:55:48.135Z] 22:55:48 [task 2019-02-09T22:55:48.137Z] 22:55:48 [task 2019-02-09T22:55:48.138Z] 22:55:48 [task 2019-02-09T22:55:48.138Z] 22:55:48 [task 2019-02-09T22:55:48.139Z] 22:55:48 [task 2019-02-09T22:55:48.140Z] 22:55:48 [task 2019-02-09T22:55:48.140Z] 22:55:48 [task 2019-02-09T22:55:48.148Z] 22:55:48 [task 2019-02-09T22:55:48.148Z] 22:55:48 [task 2019-02-09T22:55:48.148Z] 22:55:48 [task 2019-02-09T22:55:48.148Z] 22:55:48 [task 2019-02-09T22:55:48.148Z] 22:55:48 [task 2019-02-09T22:55:48.148Z] 22:55:48 [task 2019-02-09T22:55:48.148Z] 22:55:48 [task 2019-02-09T22:55:48.148Z] 22:55:48 [task 2019-02-09T22:55:48.148Z] 22:55:48 [task 2019-02-09T22:55:48.148Z] 22:55:48 [task 2019-02-09T22:55:48.148Z] 22:55:48 [task 2019-02-09T22:55:48.148Z] 22:55:48 [task 2019-02-09T22:55:48.148Z] 22:55:48 [task 2019-02-09T22:55:48.620Z] 22:55:48 [task 2019-02-09T22:55:48.621Z] 22:55:48 [task 2019-02-09T22:55:48.629Z] 22:55:48 [task 2019-02-09T22:55:48.646Z] 22:55:48 [task 2019-02-09T22:55:48.647Z] 22:55:48 [task 2019-02-09T22:55:48.663Z] 22:55:48 INFO - TEST-START | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html
INFO - GECKO(2559) | ++DOMWINDOW == 7 (0x7f890f5db400) [pid = 2659] [serial = 7] [outer = 0x7f890fb19400]
INFO - GECKO(2559) | ++DOMWINDOW == 8 (0x7f890f5dd800) [pid = 2659] [serial = 8] [outer = 0x7f890fb19400]
INFO - GECKO(2559) | [Parent 2559, Main Thread] WARNING: 'aRv.Failed()', file /builds/worker/workspace/build/src/dom/ipc/StructuredCloneData.cpp, line 120
INFO - GECKO(2559) | TEST-PASS | https://example.com/tests/browser/extensions/formautofill/test/mochitest/creditCard/parent_utils.js | Got autocomplete popup - {"listEvents":{"handleEvent":"event => {\n if (!this.parentNode) {\n return;\n }\n\n switch (event.type) {\n case "mouseup":\n // Don't call onPopupClick for the scrollbar buttons, thumb,\n // slider, etc. If we hit the richlistbox and not a\n // richlistitem, we ignore the event.\n if (event.target.closest("richlistbox,richlistitem")\n .localName == "richlistitem") {\n this.onPopupClick(event);\n }\n break;\n case "mousemove":\n if (Date.now() - this.mLastMoveTime <= 30) {\n return;\n }\n\n let item = event.target.closest("richlistbox,richlistitem");\n\n // If we hit the richlistbox and not a richlistitem, we ignore\n // the event.\n if (item.localName == "richlistbox") {\n return;\n }\n\n let index = this.richlistbox.getIndexOfItem(item);\n\n this.mousedOverIndex = index;\n\n if (item.selectedByMouseOver) {\n this.richlistbox.selectedIndex = index;\n }\n\n this.mLastMoveTime = Date.now();\n break;\n }\n }"},"richlistbox":{"selectedItems":{},"_currentIndex":null,"_lastKeyTime":0,"_incrementalString":"","_suppressOnSelect":false,"_userSelecting":false,"_selectTimeout":null,"_currentItem":null,"_selectionStart":null,"isRunningDelayedConnectedCallback":false}} == true
INFO - GECKO(2559) | [Parent 2559, Main Thread] WARNING: 'aRv.Failed()', file /builds/worker/workspace/build/src/dom/ipc/StructuredCloneData.cpp, line 120
INFO - GECKO(2559) | [Parent 2559, Main Thread] WARNING: 'aError.Failed()', file /builds/worker/workspace/build/src/dom/base/nsFrameMessageManager.cpp, line 814
INFO - GECKO(2559) | [Child 2659, Main Thread] WARNING: '!mSelection', file /builds/worker/workspace/build/src/editor/libeditor/EditorBase.cpp, line 4836
INFO - GECKO(2559) | [Child 2659, Main Thread] WARNING: '!editActionData.CanHandle()', file /builds/worker/workspace/build/src/editor/libeditor/EditorBase.cpp, line 1263
INFO - GECKO(2559) | ** Message: Remote error from secret service: org.freedesktop.DBus.Error.Failed: Couldn't create item: The secret was transferred or encrypted in an invalid way.
INFO - GECKO(2559) | console.warn: formAutofillParent: User canceled encryption login
INFO - GECKO(2559) | --DOMWINDOW == 14 (0x7fca4a81b400) [pid = 2639] [serial = 10] [outer = (nil)] [url = about:blank]
INFO - GECKO(2559) | --DOMWINDOW == 13 (0x7fca4a81a800) [pid = 2639] [serial = 9] [outer = (nil)] [url = about:blank]
INFO - GECKO(2559) | --DOMWINDOW == 12 (0x7fca4a819000) [pid = 2639] [serial = 7] [outer = (nil)] [url = about:blank]
INFO - GECKO(2559) | --DOMWINDOW == 11 (0x7fca4a819c00) [pid = 2639] [serial = 8] [outer = (nil)] [url = about:blank]
INFO - GECKO(2559) | --DOMWINDOW == 10 (0x7fca4a134c00) [pid = 2639] [serial = 6] [outer = (nil)] [url = about:blank]
INFO - GECKO(2559) | --DOMWINDOW == 7 (0x7f890fb19c00) [pid = 2659] [serial = 6] [outer = (nil)] [url = about:blank]
INFO - GECKO(2559) | --DOMWINDOW == 6 (0x7f890f03b400) [pid = 2659] [serial = 2] [outer = (nil)] [url = about:blank]
INFO - GECKO(2559) | --DOMWINDOW == 9 (0x7fc656f83c00) [pid = 2559] [serial = 2] [outer = (nil)] [url = about:blank]
INFO - GECKO(2559) | --DOMWINDOW == 8 (0x7fc651ad9c00) [pid = 2559] [serial = 8] [outer = (nil)] [url = about:blank]
INFO - GECKO(2559) | --DOCSHELL 0x7fca4a22f000 == 4 [pid = 2639] [id = {60180a1b-1d76-44ff-8c40-5aaf35bd590a}]
INFO - GECKO(2559) | --DOMWINDOW == 9 (0x7fca4a133400) [pid = 2639] [serial = 5] [outer = (nil)] [url = moz-extension://40d844bf-8446-46f1-b490-4c10aab1ded2/_generated_background_page.html]
INFO - GECKO(2559) | --DOMWINDOW == 8 (0x7fca4a822800) [pid = 2639] [serial = 15] [outer = (nil)] [url = moz-extension://40d844bf-8446-46f1-b490-4c10aab1ded2/_generated_background_page.html]
INFO - GECKO(2559) | --DOMWINDOW == 5 (0x7f890f5db400) [pid = 2659] [serial = 7] [outer = (nil)] [url = about:blank]
INFO - GECKO(2559) | --DOMWINDOW == 4 (0x7f891040fc00) [pid = 2659] [serial = 3] [outer = (nil)] [url = about:blank]
INFO - GECKO(2559) | [Parent 2559, StreamTrans #47] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 372
INFO - GECKO(2559) | [Parent 2559, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 1010
INFO - TEST-INFO | started process screentopng
INFO - TEST-INFO | screentopng: exit 0
INFO - Buffered messages logged at 22:50:49
INFO - AddTask.js | Entering test setup
INFO - Buffered messages logged at 22:50:50
INFO - expecting the storage setup
INFO - AddTask.js | Leaving test setup
INFO - AddTask.js | Entering test history_only_menu_checking
INFO - TEST-PASS | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | A valid string reason is expected
INFO - TEST-PASS | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | Reason cannot be empty
INFO - TEST-FAIL | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | The author of the test has indicated that flaky timeouts are expected. Reason: Intentionally wait for UI ready
INFO - Buffered messages logged at 22:50:52
INFO - expecting the target input being focused and indentified
INFO - TEST-PASS | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | A valid string reason is expected
INFO - TEST-PASS | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | Reason cannot be empty
INFO - TEST-FAIL | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | The author of the test has indicated that flaky timeouts are expected. Reason: Guarantee asynchronous identifyAutofillFields is invoked
INFO - expecting a popup
INFO - popup shown for test
INFO - TEST-PASS | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | Checking length of expected menu
INFO - TEST-PASS | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | Checking menu entry #0
INFO - AddTask.js | Leaving test history_only_menu_checking
INFO - AddTask.js | Entering test all_saved_fields_less_than_threshold
INFO - expecting the chrome task finished: FormAutofillTest:AddCreditCard
INFO - Buffered messages finished
INFO - TEST-UNEXPECTED-FAIL | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | Test timed out.
INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:275:7
INFO - reportError@https://example.com/tests/SimpleTest/TestRunner.js:121:7
INFO - TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:142:7
INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
INFO - TestRunner.runTests/<@https://example.com/tests/SimpleTest/TestRunner.js:381:9
INFO - promise callbackTestRunner.runTests@https://example.com/tests/SimpleTest/TestRunner.js:368:50
INFO - RunSet.runtests@https://example.com/tests/SimpleTest/setup.js:201:3
INFO - RunSet.runall@https://example.com/tests/SimpleTest/setup.js:180:5
INFO - hookupTests@https://example.com/tests/SimpleTest/setup.js:273:5
INFO - parseTestManifest@https://example.com/manifestLibrary.js:36:5
INFO - getTestManifest/req.onload@https://example.com/manifestLibrary.js:49:11
INFO - EventHandlerNonNullgetTestManifest@https://example.com/manifestLibrary.js:45:3
INFO - hookup@https://example.com/tests/SimpleTest/setup.js:253:5
INFO - EventHandlerNonNull*@https://example.com/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true:11:1
INFO - GECKO(2559) | [Parent 2559, Main Thread] WARNING: 'aRv.Failed()', file /builds/worker/workspace/build/src/dom/ipc/StructuredCloneData.cpp, line 120
INFO - GECKO(2559) | [Parent 2559, Main Thread] WARNING: 'aError.Failed()', file /builds/worker/workspace/build/src/dom/base/nsFrameMessageManager.cpp, line 814
INFO - expecting the storage cleanup
INFO - GECKO(2559) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
INFO - GECKO(2559) | MEMORY STAT | vsize 1471MB | residentFast 124MB | heapAllocated 11MB
INFO - TEST-OK | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | took 301097ms

Matthew can you please take a look?

Flags: needinfo?(MattN+bmo)

Whiteboard: [form autofill][stockwell fixed:timing] → [form autofill][stockwell needswork:owner]

Comment hidden (Intermittent Failures Robot)

Matthew N. [:MattN]

Updated

•

5 years ago

Priority: P5 → P2

Summary: Intermittent browser/extensions/formautofill/test/mochitest/test_basic_creditcard_autocomplete_form.html | Test timed out. → Intermittent browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | Test timed out.

Comment hidden (Intermittent Failures Robot)

Andreea Pavel [:apavel]

Comment 24

•

5 years ago

There are 24 total failures in the last 7 days on linux32 and linux64, plus linux64-qr, all build types.

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=231364871&repo=mozilla-central&lineNumber=2602

[task 2019-03-01T22:53:48.178Z] 22:53:48 INFO - TEST-START | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html
[task 2019-03-01T22:53:50.019Z] 22:53:50 INFO - GECKO(2589) | TEST-PASS | https://example.com/tests/browser/extensions/formautofill/test/mochitest/creditCard/parent_utils.js | Got autocomplete popup - {"listEvents":{"handleEvent":"event => {\n if (!this.parentNode) {\n return;\n }\n\n switch (event.type) {\n case "mouseup":\n // Don't call onPopupClick for the scrollbar buttons, thumb,\n // slider, etc. If we hit the richlistbox and not a\n // richlistitem, we ignore the event.\n if (event.target.closest("richlistbox,richlistitem")\n .localName == "richlistitem") {\n this.onPopupClick(event);\n }\n break;\n case "mousemove":\n if (Date.now() - this.mLastMoveTime <= 30) {\n return;\n }\n\n let item = event.target.closest("richlistbox,richlistitem");\n\n // If we hit the richlistbox and not a richlistitem, we ignore\n // the event.\n if (item.localName == "richlistbox") {\n return;\n }\n\n let index = this.richlistbox.getIndexOfItem(item);\n\n this.mousedOverIndex = index;\n\n if (item.selectedByMouseOver) {\n this.richlistbox.selectedIndex = index;\n }\n\n this.mLastMoveTime = Date.now();\n break;\n }\n }"},"richlistbox":{"selectedItems":{},"_currentIndex":null,"_lastKeyTime":0,"_incrementalString":"","_suppressOnSelect":false,"_userSelecting":false,"_selectTimeout":null,"_currentItem":null,"_selectionStart":null,"isRunningDelayedConnectedCallback":false}} == true
[task 2019-03-01T22:53:52.637Z] 22:53:52 INFO - GECKO(2589) | ** Message: Remote error from secret service: org.freedesktop.DBus.Error.Failed: Couldn't create item: The secret was transferred or encrypted in an invalid way.
[task 2019-03-01T22:53:52.638Z] 22:53:52 INFO - GECKO(2589) | console.warn: formAutofillParent: User canceled encryption login
[task 2019-03-01T22:59:18.183Z] 22:59:18 INFO - TEST-INFO | started process screentopng
[task 2019-03-01T22:59:18.507Z] 22:59:18 INFO - TEST-INFO | screentopng: exit 0
[task 2019-03-01T22:59:18.507Z] 22:59:18 INFO - Buffered messages logged at 22:53:50
[task 2019-03-01T22:59:18.507Z] 22:59:18 INFO - AddTask.js | Entering test setup
[task 2019-03-01T22:59:18.507Z] 22:59:18 INFO - expecting the storage setup
[task 2019-03-01T22:59:18.507Z] 22:59:18 INFO - AddTask.js | Leaving test setup
[task 2019-03-01T22:59:18.507Z] 22:59:18 INFO - AddTask.js | Entering test history_only_menu_checking
[task 2019-03-01T22:59:18.508Z] 22:59:18 INFO - TEST-PASS | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | A valid string reason is expected
[task 2019-03-01T22:59:18.509Z] 22:59:18 INFO - TEST-PASS | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | Reason cannot be empty
[task 2019-03-01T22:59:18.510Z] 22:59:18 INFO - TEST-FAIL | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | The author of the test has indicated that flaky timeouts are expected. Reason: Intentionally wait for UI ready
[task 2019-03-01T22:59:18.511Z] 22:59:18 INFO - Buffered messages logged at 22:53:52
[task 2019-03-01T22:59:18.512Z] 22:59:18 INFO - expecting the target input being focused and indentified
[task 2019-03-01T22:59:18.514Z] 22:59:18 INFO - TEST-PASS | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | A valid string reason is expected
[task 2019-03-01T22:59:18.515Z] 22:59:18 INFO - TEST-PASS | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | Reason cannot be empty
[task 2019-03-01T22:59:18.516Z] 22:59:18 INFO - TEST-FAIL | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | The author of the test has indicated that flaky timeouts are expected. Reason: Guarantee asynchronous identifyAutofillFields is invoked
[task 2019-03-01T22:59:18.517Z] 22:59:18 INFO - expecting a popup
[task 2019-03-01T22:59:18.518Z] 22:59:18 INFO - popup shown for test
[task 2019-03-01T22:59:18.519Z] 22:59:18 INFO - TEST-PASS | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | Checking length of expected menu
[task 2019-03-01T22:59:18.520Z] 22:59:18 INFO - TEST-PASS | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | Checking menu entry #0
[task 2019-03-01T22:59:18.521Z] 22:59:18 INFO - AddTask.js | Leaving test history_only_menu_checking
[task 2019-03-01T22:59:18.522Z] 22:59:18 INFO - AddTask.js | Entering test all_saved_fields_less_than_threshold
[task 2019-03-01T22:59:18.523Z] 22:59:18 INFO - expecting the chrome task finished: FormAutofillTest:AddCreditCard
[task 2019-03-01T22:59:18.523Z] 22:59:18 INFO - Buffered messages finished
[task 2019-03-01T22:59:18.524Z] 22:59:18 INFO - TEST-UNEXPECTED-FAIL | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | Test timed out.
[task 2019-03-01T22:59:18.524Z] 22:59:18 INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:275:18
[task 2019-03-01T22:59:18.525Z] 22:59:18 INFO - reportError@https://example.com/tests/SimpleTest/TestRunner.js:121:22
[task 2019-03-01T22:59:18.526Z] 22:59:18 INFO - TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:142:7
[task 2019-03-01T22:59:18.526Z] 22:59:18 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-01T22:59:18.527Z] 22:59:18 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-01T22:59:18.528Z] 22:59:18 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-01T22:59:18.528Z] 22:59:18 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-01T22:59:18.528Z] 22:59:18 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-01T22:59:18.528Z] 22:59:18 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-01T22:59:18.528Z] 22:59:18 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-01T22:59:18.529Z] 22:59:18 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-01T22:59:18.529Z] 22:59:18 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-01T22:59:18.529Z] 22:59:18 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-01T22:59:18.529Z] 22:59:18 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-01T22:59:18.529Z] 22:59:18 INFO - TestRunner.runTests/<@https://example.com/tests/SimpleTest/TestRunner.js:381:20
[task 2019-03-01T22:59:18.529Z] 22:59:18 INFO - promise callbackTestRunner.runTests@https://example.com/tests/SimpleTest/TestRunner.js:368:50
[task 2019-03-01T22:59:18.529Z] 22:59:18 INFO - RunSet.runtests@https://example.com/tests/SimpleTest/setup.js:201:14
[task 2019-03-01T22:59:18.529Z] 22:59:18 INFO - RunSet.runall@https://example.com/tests/SimpleTest/setup.js:180:12
[task 2019-03-01T22:59:18.529Z] 22:59:18 INFO - hookupTests@https://example.com/tests/SimpleTest/setup.js:273:12
[task 2019-03-01T22:59:18.529Z] 22:59:18 INFO - parseTestManifest@https://example.com/manifestLibrary.js:36:5
[task 2019-03-01T22:59:18.529Z] 22:59:18 INFO - getTestManifest/req.onload@https://example.com/manifestLibrary.js:49:11
[task 2019-03-01T22:59:18.529Z] 22:59:18 INFO - EventHandlerNonNullgetTestManifest@https://example.com/manifestLibrary.js:45:3
[task 2019-03-01T22:59:18.529Z] 22:59:18 INFO - hookup@https://example.com/tests/SimpleTest/setup.js:253:5
[task 2019-03-01T22:59:18.529Z] 22:59:18 INFO - EventHandlerNonNull@https://example.com/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true:11:1
[task 2019-03-01T22:59:19.213Z] 22:59:19 INFO - expecting the storage cleanup
[task 2019-03-01T22:59:19.234Z] 22:59:19 INFO - GECKO(2589) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2019-03-01T22:59:19.234Z] 22:59:19 INFO - GECKO(2589) | MEMORY STAT heapAllocated not supported in this build configuration.
[task 2019-03-01T22:59:19.234Z] 22:59:19 INFO - GECKO(2589) | MEMORY STAT | vsize 20973375MB | residentFast 435MB
[task 2019-03-01T22:59:19.255Z] 22:59:19 INFO - TEST-OK | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | took 331083ms

Whiteboard: [form autofill][stockwell unknown] → [form autofill][stockwell needswork:owner]

Comment hidden (Intermittent Failures Robot)

Razvan Maries

Comment 32

•

5 years ago

There are 21 total failures in the last 7 days on: linux64, linux32 debug & opt, linux64-shippable-qr opt and on osx-10-10 debug.

Failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=241685045&repo=mozilla-central&lineNumber=4136

[task 2019-04-21T11:28:36.693Z] 11:28:36 INFO - TEST-START | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html
[task 2019-04-21T11:33:37.023Z] 11:33:37 INFO - TEST-UNEXPECTED-FAIL | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | Test timed out.
[task 2019-04-21T11:33:37.024Z] 11:33:37 INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:275:18
[task 2019-04-21T11:33:37.024Z] 11:33:37 INFO - reportError@https://example.com/tests/SimpleTest/TestRunner.js:121:22
[task 2019-04-21T11:33:37.024Z] 11:33:37 INFO - TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:142:7
[task 2019-04-21T11:33:37.025Z] 11:33:37 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-04-21T11:33:37.025Z] 11:33:37 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-04-21T11:33:37.026Z] 11:33:37 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-04-21T11:33:37.026Z] 11:33:37 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-04-21T11:33:37.026Z] 11:33:37 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-04-21T11:33:37.027Z] 11:33:37 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-04-21T11:33:37.027Z] 11:33:37 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-04-21T11:33:37.028Z] 11:33:37 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-04-21T11:33:37.028Z] 11:33:37 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-04-21T11:33:37.028Z] 11:33:37 INFO - setTimeout handlerTestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-04-21T11:33:37.029Z] 11:33:37 INFO - TestRunner.runTests/<@https://example.com/tests/SimpleTest/TestRunner.js:381:20
[task 2019-04-21T11:33:37.029Z] 11:33:37 INFO - promise callbackTestRunner.runTests@https://example.com/tests/SimpleTest/TestRunner.js:368:50
[task 2019-04-21T11:33:37.030Z] 11:33:37 INFO - RunSet.runtests@https://example.com/tests/SimpleTest/setup.js:201:14
[task 2019-04-21T11:33:37.030Z] 11:33:37 INFO - RunSet.runall@https://example.com/tests/SimpleTest/setup.js:180:12
[task 2019-04-21T11:33:37.030Z] 11:33:37 INFO - hookupTests@https://example.com/tests/SimpleTest/setup.js:273:12
[task 2019-04-21T11:33:37.031Z] 11:33:37 INFO - parseTestManifest@https://example.com/manifestLibrary.js:36:5
[task 2019-04-21T11:33:37.031Z] 11:33:37 INFO - getTestManifest/req.onload@https://example.com/manifestLibrary.js:49:11
[task 2019-04-21T11:33:37.031Z] 11:33:37 INFO - EventHandlerNonNullgetTestManifest@https://example.com/manifestLibrary.js:45:3
[task 2019-04-21T11:33:37.032Z] 11:33:37 INFO - hookup@https://example.com/tests/SimpleTest/setup.js:253:5
[task 2019-04-21T11:33:37.032Z] 11:33:37 INFO - EventHandlerNonNull*@https://example.com/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true:11:1
[task 2019-04-21T11:33:37.736Z] 11:33:37 INFO - GECKO(2452) | [Parent 2452, Main Thread] WARNING: 'aRv.Failed()', file /builds/worker/workspace/build/src/dom/ipc/StructuredCloneData.cpp, line 120
[task 2019-04-21T11:33:37.737Z] 11:33:37 INFO - GECKO(2452) | [Parent 2452, Main Thread] WARNING: 'aError.Failed()', file /builds/worker/workspace/build/src/dom/base/nsFrameMessageManager.cpp, line 815
[task 2019-04-21T11:33:37.738Z] 11:33:37 INFO - expecting the storage cleanup
[task 2019-04-21T11:33:37.758Z] 11:33:37 INFO - GECKO(2452) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2019-04-21T11:33:37.762Z] 11:33:37 INFO - GECKO(2452) | MEMORY STAT | vsize 2507MB | residentFast 138MB | heapAllocated 12MB
[task 2019-04-21T11:33:37.783Z] 11:33:37 INFO - TEST-OK | browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html | took 301090ms
[task 2019-04-21T11:33:37.880Z] 11:33:37 INFO - GECKO(2452) | ++DOMWINDOW == 5 (0x7fb9a0f5c800) [pid = 2523] [serial = 9] [outer = 0x7fb9a07c2c40]

Flags: needinfo?(MattN+bmo)

Whiteboard: [form autofill][stockwell unknown] → [form autofill][stockwell needswork:owner]

Razvan Maries

Comment 33

•

5 years ago

Matthew, could you please assign this to someone?

Flags: needinfo?(MattN+bmo)

Comment hidden (Intermittent Failures Robot)

Joel Maher ( :jmaher ) (UTC -8)

Comment 36

•

5 years ago

the failure rate has gone down here, :dluca, could you confirm this has reduced and not been disabled or fixed via test file changes?

Flags: needinfo?(dluca)

Dorel Luca [:dluca]

Comment 37

•

5 years ago

The test is not disabled but, 2 weeks ago there have been some changes:
https://hg.mozilla.org/integration/autoland/rev/c83490ca7185506ddf5e9828f8159930c03d15a0

Which changed this:
https://hg.mozilla.org/integration/autoland/diff/c83490ca7185506ddf5e9828f8159930c03d15a0/browser/extensions/formautofill/test/mochitest/creditCard/test_basic_creditcard_autocomplete_form.html

But the failure rate went down in the last 7 days not at the moment of the changes being implemented.

It looks to me that the failure rate just went down.

Flags: needinfo?(dluca)

Comment hidden (Intermittent Failures Robot)

Matthew N. [:MattN]

Comment 39

•

5 years ago

Too low freq. to care…

Flags: needinfo?(MattN+bmo)

Comment hidden (Intermittent Failures Robot)

Jim Mathies [:jimm]

Updated

•

4 years ago

Whiteboard: [form autofill][stockwell unknown] → [form autofill][stockwell unknown][ccautofill]

Jim Mathies [:jimm]

Updated

•

4 years ago

Priority: P2 → P5

Whiteboard: [form autofill][stockwell unknown][ccautofill] → [cc-autofill-reserve]

BMO Automation

Updated

•

2 years ago

Severity: normal → S3