Closed Bug 1567030 Opened 5 years ago Closed 5 years ago

Intermittent accessible/tests/mochitest/editabletext/test_1.html | Test timed out.

Categories

(Core :: Disability Access APIs, defect, P5)

Product:
Core
Component:
Disability Access APIs
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, regression)

Filed by: aciure [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=256957679&repo=mozilla-esr68
Full log: https://queue.taskcluster.net/v1/task/RXR7axBHS1m1Cv2epXVjTg/runs/0/artifacts/public/logs/live_backing.log

15:31:51 INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Test with ID = 'setTextContents 'olleh' for 'div' ' succeed. Event 'text inserted' was handled.
15:31:51 INFO - Invoke the 'setTextContents '' for 'div' ' test { scenario #0: expected 'text removed' event; }
15:31:51 INFO - Buffered messages finished
15:31:51 INFO - TEST-UNEXPECTED-FAIL | accessible/tests/mochitest/editabletext/test_1.html | Test timed out.
15:31:51 INFO - SimpleTest.ok@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:275:18
15:31:51 INFO - reportError@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:121:22
15:31:51 INFO - TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:142:7
15:31:52 INFO - GECKO(1634) | MEMORY STAT | vsize 7623MB | residentFast 374MB | heapAllocated 106MB
15:31:52 INFO - TEST-OK | accessible/tests/mochitest/editabletext/test_1.html | took 314945ms
15:31:52 INFO - GECKO(1634) | ++DOMWINDOW == 28 (0x11f573c00) [pid = 1634] [serial = 109] [outer = 0x12db65020]
15:31:52 INFO - Not taking screenshot here: see the one that was previously logged
15:31:52 INFO - TEST-UNEXPECTED-FAIL | accessible/tests/mochitest/editabletext/test_1.html | Can't get accessible for [ 'div@id="div" node', address: [object HTMLDivElement] ]
15:31:52 INFO - SimpleTest.ok@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:275:18
15:31:52 INFO - getAccessible@chrome://mochitests/content/a11y/accessible/tests/mochitest/common.js:285:9
15:31:52 INFO - stextChangeChecker_match@chrome://mochitests/content/a11y/accessible/tests/mochitest/events.js:1712:31
15:31:52 INFO - eventQueue_compareEvents@chrome://mochitests/content/a11y/accessible/tests/mochitest/events.js:921:21
15:31:52 INFO - eventQueue_handleEvent@chrome://mochitests/content/a11y/accessible/tests/mochitest/events.js:555:24
15:31:52 INFO - observe@chrome://mochitests/content/a11y/accessible/tests/mochitest/events.js:2032:29
15:31:52 INFO - Not taking screenshot here: see the one that was previously logged

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE

This is still happening.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=291312521&repo=mozilla-esr68&lineNumber=1415

19:19:03     INFO - TEST-START | accessible/tests/mochitest/editabletext/test_1.html
19:19:03     INFO - GECKO(1658) | ++DOMWINDOW == 73 (0x11e7e6c00) [pid = 1658] [serial = 105] [outer = 0x12c042b60]
19:19:03     INFO - GECKO(1658) | [1658, StreamTrans #1] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 371
19:19:03     INFO - GECKO(1658) | [1658, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 994
19:19:03     INFO - GECKO(1658) | ++DOCSHELL 0x128c6c000 == 13 [pid = 1658] [id = {c321ee69-4cbe-fa48-9fdd-efd0c6e201d0}]
19:19:03     INFO - GECKO(1658) | ++DOMWINDOW == 74 (0x12764aa60) [pid = 1658] [serial = 106] [outer = 0x0]
19:19:03     INFO - GECKO(1658) | ++DOMWINDOW == 75 (0x11e7e9800) [pid = 1658] [serial = 107] [outer = 0x12764aa60]
19:19:03     INFO - GECKO(1658) | [1658, Main Thread] WARNING: HTMLEditRules::BeforeEdit() failed to handle something: 'NS_SUCCEEDED(rv)', file /builds/worker/workspace/build/src/editor/libeditor/HTMLEditor.cpp, line 3633
19:19:03     INFO - GECKO(1658) | [1658, Main Thread] WARNING: '!aSelection.RangeCount()', file /builds/worker/workspace/build/src/editor/libeditor/EditorBase.cpp, line 3619
19:19:03     INFO - GECKO(1658) | [1658, Main Thread] WARNING: '!selectionStartPoint.IsSet()', file /builds/worker/workspace/build/src/editor/libeditor/HTMLEditRules.cpp, line 9973
19:19:03     INFO - GECKO(1658) | [1658, Main Thread] WARNING: Failed to normalize Selection: 'NS_SUCCEEDED(rv)', file /builds/worker/workspace/build/src/editor/libeditor/HTMLEditRules.cpp, line 462
19:19:03     INFO - GECKO(1658) | ++DOMWINDOW == 76 (0x11e7e8400) [pid = 1658] [serial = 108] [outer = 0x12764aa60]
19:19:03     INFO - GECKO(1658) | [1658, Main Thread] WARNING: HTMLEditRules::BeforeEdit() failed to handle something: 'NS_SUCCEEDED(rv)', file /builds/worker/workspace/build/src/editor/libeditor/HTMLEditor.cpp, line 3633
19:19:03     INFO - GECKO(1658) | --DOMWINDOW == 75 (0x131eaae20) [pid = 1658] [serial = 83] [outer = 0x0] [url = data:text/html,<html><body></body></html>]
19:19:03     INFO - GECKO(1658) | --DOMWINDOW == 74 (0x12b0802e0) [pid = 1658] [serial = 72] [outer = 0x0] [url = data:text/html,<html><body>hey</body></html>]
19:19:03     INFO - GECKO(1658) | setTextContents 'hello'
19:19:03     INFO - GECKO(1658) | [1658, Main Thread] WARNING: '!editingHost', file /builds/worker/workspace/build/src/editor/libeditor/HTMLEditor.cpp, line 554
19:19:03     INFO - GECKO(1658) | setTextContents 'olleh'
19:19:03     INFO - GECKO(1658) | setTextContents ''
19:19:03     INFO - GECKO(1658) | insertText 'hello' at 0 pos
19:19:03     INFO - GECKO(1658) | insertText 'ma ' at 0 pos
19:19:03     INFO - GECKO(1658) | insertText 'ma' at 2 pos
19:19:03     INFO - GECKO(1658) | insertText ' hello' at 10 pos
19:19:04     INFO - GECKO(1658) | setTextContents 'hello'
19:19:04     INFO - GECKO(1658) | setTextContents 'olleh'
19:19:04     INFO - GECKO(1658) | setTextContents ''
19:19:07     INFO - GECKO(1658) | --DOMWINDOW == 73 (0x11ce56800) [pid = 1658] [serial = 89] [outer = 0x0] [url = chrome://mochitests/content/a11y/accessible/tests/mochitest/attributes/test_obj_css.xul]
19:19:07     INFO - GECKO(1658) | --DOMWINDOW == 72 (0x11ce55400) [pid = 1658] [serial = 87] [outer = 0x0] [url = chrome://mochitests/content/a11y/accessible/tests/mochitest/attributes/test_obj_css.html]
19:19:07     INFO - GECKO(1658) | --DOMWINDOW == 71 (0x11ce54800) [pid = 1658] [serial = 88] [outer = 0x0] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
19:19:07     INFO - GECKO(1658) | --DOMWINDOW == 70 (0x11e8df400) [pid = 1658] [serial = 67] [outer = 0x0] [url = chrome://mochitests/content/a11y/accessible/tests/mochitest/actions/test_tree.xul]
19:19:07     INFO - GECKO(1658) | --DOMWINDOW == 69 (0x11e8e0400) [pid = 1658] [serial = 69] [outer = 0x0] [url = chrome://mochitests/content/a11y/accessible/tests/mochitest/actions/test_treegrid.xul]
19:19:07     INFO - GECKO(1658) | --DOMWINDOW == 68 (0x12f1b3800) [pid = 1658] [serial = 74] [outer = 0x0] [url = data:text/html,<html><body>hey</body></html>]
19:19:07     INFO - GECKO(1658) | --DOMWINDOW == 67 (0x11ce53400) [pid = 1658] [serial = 86] [outer = 0x0] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
19:19:07     INFO - GECKO(1658) | --DOMWINDOW == 66 (0x11e25c800) [pid = 1658] [serial = 85] [outer = 0x0] [url = data:text/html,<html><body></body></html>]
19:19:07     INFO - GECKO(1658) | --DOMWINDOW == 65 (0x11e257000) [pid = 1658] [serial = 81] [outer = 0x0] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
19:19:07     INFO - GECKO(1658) | --DOMWINDOW == 64 (0x134b5dc00) [pid = 1658] [serial = 63] [outer = 0x0] [url = chrome://mochitests/content/a11y/accessible/tests/mochitest/actions/test_media.html]
...
19:19:11     INFO - GECKO(1658) | --DOMWINDOW == 36 (0x11e7e9400) [pid = 1658] [serial = 95] [outer = 0x0] [url = chrome://mochitests/content/a11y/accessible/tests/mochitest/attributes/test_obj_group_tree.xul]
19:19:11     INFO - GECKO(1658) | --DOMWINDOW == 35 (0x12fa50800) [pid = 1658] [serial = 90] [outer = 0x0] [url = chrome://mochikit/content/tests/SimpleTest/iframe-between-tests.html]
19:19:59     INFO - GECKO(1658) | --DOMWINDOW == 34 (0x130d51980) [pid = 1658] [serial = 58] [outer = 0x0] [url = about:mozilla]
19:19:59     INFO - GECKO(1658) | --DOMWINDOW == 33 (0x12bd984c0) [pid = 1658] [serial = 54] [outer = 0x0] [url = about:mozilla]
19:20:03     INFO - GECKO(1658) | --DOMWINDOW == 32 (0x117941c00) [pid = 1658] [serial = 57] [outer = 0x0] [url = about:mozilla]
19:20:03     INFO - GECKO(1658) | --DOMWINDOW == 31 (0x1179a4000) [pid = 1658] [serial = 55] [outer = 0x0] [url = about:blank]
19:20:03     INFO - GECKO(1658) | --DOMWINDOW == 30 (0x129322400) [pid = 1658] [serial = 61] [outer = 0x0] [url = about:mozilla]
19:20:03     INFO - GECKO(1658) | --DOMWINDOW == 29 (0x129acac00) [pid = 1658] [serial = 56] [outer = 0x0] [url = about:blank]
19:20:03     INFO - GECKO(1658) | --DOMWINDOW == 28 (0x129405c00) [pid = 1658] [serial = 59] [outer = 0x0] [url = about:blank]
19:20:03     INFO - GECKO(1658) | --DOMWINDOW == 27 (0x130dbd000) [pid = 1658] [serial = 60] [outer = 0x0] [url = about:blank]
19:24:16     INFO - TEST-INFO | started process screencapture
19:24:16     INFO - TEST-INFO | screencapture: exit 0
19:24:16     INFO - Buffered messages logged at 19:19:03
19:24:16     INFO - must wait for load
19:24:16     INFO - Invoke the 'setTextContents 'hello' for  'input' ' test { scenario #0: expected 'text inserted' event;  }
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong start offset for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong length for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Text was inserted for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong inserted text for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong value hello 
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Test with ID = 'setTextContents 'hello' for  'input' ' succeed.  Event 'text inserted' was handled. 
19:24:16     INFO - Invoke the 'setTextContents 'olleh' for  'input' ' test { scenario #0: expected 'text removed' event; expected 'text inserted' event;  }
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong start offset for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong length for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Text was removed for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong removed text for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong start offset for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong length for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Text was inserted for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong inserted text for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong value olleh 
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Test with ID = 'setTextContents 'olleh' for  'input' ' succeed.  Event 'text removed' was handled. 
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Test with ID = 'setTextContents 'olleh' for  'input' ' succeed.  Event 'text inserted' was handled. 
19:24:16     INFO - Invoke the 'setTextContents '' for  'input' ' test { scenario #0: expected 'text removed' event;  }
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong start offset for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong length for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Text was removed for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong removed text for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong value  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Test with ID = 'setTextContents '' for  'input' ' succeed.  Event 'text removed' was handled. 
19:24:16     INFO - Invoke the 'insertText 'hello' at 0 for  'input' ' test { scenario #0: expected 'text inserted' event;  }
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong start offset for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong length for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Text was inserted for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong inserted text for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong value hello 
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Test with ID = 'insertText 'hello' at 0 for  'input' ' succeed.  Event 'text inserted' was handled. 
19:24:16     INFO - Invoke the 'insertText 'ma ' at 0 for  'input' ' test { scenario #0: expected 'text inserted' event;  }
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong start offset for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong length for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Text was inserted for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong inserted text for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong value ma hello 
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Test with ID = 'insertText 'ma ' at 0 for  'input' ' succeed.  Event 'text inserted' was handled. 
19:24:16     INFO - Invoke the 'insertText 'ma' at 2 for  'input' ' test { scenario #0: expected 'text inserted' event;  }
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong start offset for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong length for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Text was inserted for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong inserted text for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong value mama hello 
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Test with ID = 'insertText 'ma' at 2 for  'input' ' succeed.  Event 'text inserted' was handled. 
19:24:16     INFO - Invoke the 'insertText ' hello' at 10 for  'input' ' test { scenario #0: expected 'text inserted' event;  }
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong start offset for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong length for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Text was inserted for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong inserted text for  'input'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong value mama hello hello 
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Test with ID = 'insertText ' hello' at 10 for  'input' ' succeed.  Event 'text inserted' was handled. 
19:24:16     INFO - Buffered messages logged at 19:19:04
19:24:16     INFO - Invoke the 'setTextContents 'hello' for  'div' ' test { scenario #0: expected 'text inserted' event;  }
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong start offset for  'div'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong length for  'div'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Text was inserted for  'div'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong inserted text for  'div'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong value hello 
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Test with ID = 'setTextContents 'hello' for  'div' ' succeed.  Event 'text inserted' was handled. 
19:24:16     INFO - Invoke the 'setTextContents 'olleh' for  'div' ' test { scenario #0: expected 'text removed' event; expected 'text inserted' event;  }
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong start offset for  'div'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong length for  'div'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Text was removed for  'div'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong removed text for  'div'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong start offset for  'div'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong length for  'div'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Text was inserted for  'div'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong inserted text for  'div'  
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Wrong value olleh 
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Test with ID = 'setTextContents 'olleh' for  'div' ' succeed.  Event 'text removed' was handled. 
19:24:16     INFO - TEST-PASS | accessible/tests/mochitest/editabletext/test_1.html | Test with ID = 'setTextContents 'olleh' for  'div' ' succeed.  Event 'text inserted' was handled. 
19:24:16     INFO - Invoke the 'setTextContents '' for  'div' ' test { scenario #0: expected 'text removed' event;  }
19:24:16     INFO - Buffered messages finished
19:24:16     INFO - TEST-UNEXPECTED-FAIL | accessible/tests/mochitest/editabletext/test_1.html | Test timed out. 
19:24:16     INFO - SimpleTest.ok@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:275:18
19:24:16     INFO - reportError@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:121:22
19:24:16     INFO - TestRunner._checkForHangs@chrome://mochikit/content/tests/SimpleTest/TestRunner.js:142:7
19:24:17     INFO - GECKO(1658) | MEMORY STAT | vsize 7636MB | residentFast 370MB | heapAllocated 101MB
19:24:17     INFO - TEST-OK | accessible/tests/mochitest/editabletext/test_1.html | took 314313ms
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
See Also: → 1619387

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

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