1471023 - Intermittent dom/u2f/tests/browser/browser_abort_visibility.js | Test timed out -

Status: RESOLVED INCOMPLETE

(Core :: DOM: Device Interfaces, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: nerli [at] mozilla.com

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

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

[task 2018-06-25T20:23:26.069Z] 20:23:26     INFO - TEST-START | dom/u2f/tests/browser/browser_abort_visibility.js
[task 2018-06-25T20:24:56.439Z] 20:24:56     INFO - TEST-INFO | started process screentopng
[task 2018-06-25T20:24:57.298Z] 20:24:57     INFO - TEST-INFO | screentopng: exit 0
[task 2018-06-25T20:24:57.300Z] 20:24:57     INFO - Buffered messages logged at 20:23:26
[task 2018-06-25T20:24:57.301Z] 20:24:57     INFO - Entering test bound test_abort
[task 2018-06-25T20:24:57.302Z] 20:24:57     INFO - Buffered messages logged at 20:23:28
[task 2018-06-25T20:24:57.304Z] 20:24:57     INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/FileUtils.jsm" line: 170}]
[task 2018-06-25T20:24:57.305Z] 20:24:57     INFO - Buffered messages finished
[task 2018-06-25T20:24:57.307Z] 20:24:57     INFO - TEST-UNEXPECTED-FAIL | dom/u2f/tests/browser/browser_abort_visibility.js | Test timed out - 
[task 2018-06-25T20:24:57.308Z] 20:24:57     INFO - GECKO(3548) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2018-06-25T20:24:57.311Z] 20:24:57     INFO - GECKO(3548) | MEMORY STAT heapAllocated not supported in this build configuration.
[task 2018-06-25T20:24:57.312Z] 20:24:57     INFO - GECKO(3548) | MEMORY STAT | vsize 20973920MB | residentFast 974MB
[task 2018-06-25T20:24:57.313Z] 20:24:57     INFO - TEST-OK | dom/u2f/tests/browser/browser_abort_visibility.js | took 90399ms
[task 2018-06-25T20:24:57.314Z] 20:24:57     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-06-25T20:24:57.315Z] 20:24:57     INFO - TEST-UNEXPECTED-FAIL | dom/u2f/tests/browser/browser_abort_visibility.js | Found a tab after previous test timed out: https://example.com/browser/dom/u2f/tests/browser/tab_u2f_result.html - 
[task 2018-06-25T20:24:57.317Z] 20:24:57     INFO - checking window state
[task 2018-06-25T20:24:57.318Z] 20:24:57     INFO - TEST-START | dom/u2f/tests/browser/browser_appid_localhost.js
[task 2018-06-25T20:24:58.893Z] 20:24:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-06-25T20:24:58.894Z] 20:24:58     INFO - Buffered messages logged at 20:24:57
[task 2018-06-25T20:24:58.896Z] 20:24:58     INFO - Entering test bound 
[task 2018-06-25T20:24:58.898Z] 20:24:58     INFO - Buffered messages logged at 20:24:58
[task 2018-06-25T20:24:58.899Z] 20:24:58     INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/FileUtils.jsm" line: 170}]
[task 2018-06-25T20:24:58.901Z] 20:24:58     INFO - Buffered messages finished
[task 2018-06-25T20:24:58.902Z] 20:24:58     INFO - TEST-UNEXPECTED-FAIL | dom/u2f/tests/browser/browser_abort_visibility.js | Uncaught exception received from previously timed out test - at chrome://mochitests/content/browser/dom/u2f/tests/browser/browser_abort_visibility.js:30 - TypeError: ContentTask is null
[task 2018-06-25T20:24:58.904Z] 20:24:58     INFO - Stack trace:
[task 2018-06-25T20:24:58.905Z] 20:24:58     INFO - startMakeCredentialRequest@chrome://mochitests/content/browser/dom/u2f/tests/browser/browser_abort_visibility.js:30:3
[task 2018-06-25T20:24:58.906Z] 20:24:58     INFO - test_abort@chrome://mochitests/content/browser/dom/u2f/tests/browser/browser_abort_visibility.js:78:9
[task 2018-06-25T20:24:58.911Z] 20:24:58     INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1098:34
[task 2018-06-25T20:24:58.912Z] 20:24:58     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1089:16
[task 2018-06-25T20:24:58.913Z] 20:24:58     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:991:9
[task 2018-06-25T20:24:58.915Z] 20:24:58     INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:795:59
[task 2018-06-25T20:24:58.956Z] 20:24:58     INFO - TEST-PASS | dom/u2f/tests/browser/browser_appid_localhost.js | Origin is https://localhost. U2F is available. - 
[task 2018-06-25T20:24:58.956Z] 20:24:58     INFO - Console message: [JavaScript Warning: "Sending message that cannot be cloned. Are you trying to send an XPCOM object?" {file: "resource://testing-common/content-task.js" line: 62}]
[task 2018-06-25T20:24:59.236Z] 20:24:59     INFO - TEST-PASS | dom/u2f/tests/browser/browser_appid_localhost.js | Null AppID should work. - 
[task 2018-06-25T20:24:59.436Z] 20:24:59     INFO - TEST-PASS | dom/u2f/tests/browser/browser_appid_localhost.js | Empty AppID should work. - 
[task 2018-06-25T20:24:59.454Z] 20:24:59     INFO - TEST-PASS | dom/u2f/tests/browser/browser_appid_localhost.js | Incorrect scheme. - 
[task 2018-06-25T20:24:59.462Z] 20:24:59     INFO - TEST-PASS | dom/u2f/tests/browser/browser_appid_localhost.js | Incorrect TLD. - 
[task 2018-06-25T20:24:59.472Z] 20:24:59     INFO - TEST-PASS | dom/u2f/tests/browser/browser_appid_localhost.js | Incorrect TLD. - 
[task 2018-06-25T20:24:59.660Z] 20:24:59     INFO - TEST-PASS | dom/u2f/tests/browser/browser_appid_localhost.js | https://localhost/appId should work. - 
[task 2018-06-25T20:24:59.857Z] 20:24:59     INFO - TEST-PASS | dom/u2f/tests/browser/browser_appid_localhost.js | https://localhost:443/appId should work. - 
[task 2018-06-25T20:24:59.918Z] 20:24:59     INFO - Leaving test bound 
[task 2018-06-25T20:25:00.000Z] 20:25:00     INFO - GECKO(3548) | MEMORY STAT | vsize 20973914MB | residentFast 1084MB
[task 2018-06-25T20:25:00.002Z] 20:25:00     INFO - TEST-OK | dom/u2f/tests/browser/browser_appid_localhost.js | took 2940ms

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-central: 1
* mozilla-beta: 1

Platform breakdown:
* linux64: 2

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

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1471023&startday=2018-07-09&endday=2018-07-15&tree=trunk

Comment 3

[Firefox Bug Husbandry Bot]

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

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:
* try: 1

Platform breakdown:
* windows10-64-devedition: 1

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

Comment 5

[Bogdan Tara[:bogdan_tara | bogdant]]

This is still happening.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=227748518&repo=mozilla-release&lineNumber=36061

21:57:00 INFO - TEST-PASS | dom/webauthn/tests/browser/browser_abort_visibility.js | webauthn request pending -
21:57:00 INFO - Buffered messages logged at 21:57:00
21:57:00 INFO - aborted: AbortError: The operation was aborted.
21:57:00 INFO - visbility state: hidden
21:57:00 INFO - docshell active: false
21:57:00 INFO - visbility state: hidden
21:57:00 INFO - docshell active: false
21:57:00 INFO - TEST-PASS | dom/webauthn/tests/browser/browser_abort_visibility.js | webauthn request aborted -
21:57:00 INFO - Leaving test bound test_switch_tab
21:57:00 INFO - Entering test bound test_new_window_make
21:57:00 INFO - Attempting to create credential for origin: https://example.com
21:57:00 INFO - visbility state: visible
21:57:00 INFO - docshell active: true
21:57:00 INFO - Buffered messages finished
21:57:00 INFO - TEST-UNEXPECTED-FAIL | dom/webauthn/tests/browser/browser_abort_visibility.js | webauthn request pending - Got aborted, expected pending
21:57:00 INFO - Stack trace:
21:57:00 INFO - chrome://mochikit/content/browser-test.js:test_is:1303
21:57:00 INFO - chrome://mochitests/content/browser/dom/webauthn/tests/browser/browser_abort_visibility.js:assertStatus:15
21:57:00 INFO - GECKO(5972) | ++DOCSHELL 0000028596C87000 == 10 [pid = 5972] [id = {bc2b019c-f647-4062-a89a-5b8cc63835a8}]
21:57:00 INFO - GECKO(5972) | ++DOMWINDOW == 24 (00000285991E1400) [pid = 5972] [serial = 24] [outer = 0000000000000000]
21:57:00 INFO - GECKO(5972) | ++DOMWINDOW == 25 (000002859977A800) [pid = 5972] [serial = 25] [outer = 00000285991E1400]
21:57:00 INFO - GECKO(5972) | [Parent 5972, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x805D0021: file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 1010
21:57:00 INFO - GECKO(5972) | ++DOCSHELL 0000028595479800 == 11 [pid = 5972] [id = {2bb4f66d-f16c-449c-b1b8-497b57f386b6}]
21:57:00 INFO - GECKO(5972) | ++DOMWINDOW == 26 (00000285954BF000) [pid = 5972] [serial = 26] [outer = 0000000000000000]
21:57:00 INFO - GECKO(5972) | ++DOCSHELL 00000206879AD000 == 2 [pid = 7044] [id = {5a217f1b-bbad-45e8-926a-6c9c120ffb44}]
21:57:20 INFO - GECKO(5972) | --DOMWINDOW == 7 (000002955ABDA400) [pid = 11052] [serial = 9] [outer = 0000000000000000] [url = about:blank]
21:57:20 INFO - GECKO(5972) | --DOMWINDOW == 6 (00000295525BA800) [pid = 11052] [serial = 1] [outer = 0000000000000000] [url = about:blank]
21:57:21 INFO - GECKO(5972) | --DOMWINDOW == 2 (000002068797F400) [pid = 7044] [serial = 3] [outer = 0000000000000000] [url = https://example.com/browser/dom/webauthn/tests/browser/tab_webauthn_result.html]
21:57:24 INFO - GECKO(5972) | --DOMWINDOW == 5 (0000029559DBBC00) [pid = 11052] [serial = 4] [outer = 0000000000000000] [url = about:blank]
21:57:24 INFO - GECKO(5972) | --DOMWINDOW == 4 (0000029557E79400) [pid = 11052] [serial = 3] [outer = 0000000000000000] [url = about:blank]
21:58:28 INFO - Not taking screenshot here: see the one that was previously logged
21:58:28 INFO - TEST-UNEXPECTED-FAIL | dom/webauthn/tests/browser/browser_abort_visibility.js | Test timed out -
21:58:28 INFO - GECKO(5972) | MEMORY STAT | vsize 5913MB | vsizeMaxContiguous 131526640MB | residentFast 245MB | heapAllocated 60MB
21:58:28 INFO - TEST-OK | dom/webauthn/tests/browser/browser_abort_visibility.js | took 90098ms
21:58:28 INFO - Not taking screenshot here: see the one that was previously logged
21:58:28 INFO - TEST-UNEXPECTED-FAIL | dom/webauthn/tests/browser/browser_abort_visibility.js | Found a tab after previous test timed out: https://example.com/browser/dom/webauthn/tests/browser/tab_webauthn_result.html -
21:58:28 INFO - GECKO(5972) | ++DOCSHELL 00000164E1A4A000 == 1 [pid = 212] [id = {a7e4be13-df4e-496f-9d14-f4e13f626cc9}]
21:58:28 INFO - GECKO(5972) | ++DOMWINDOW == 1 (00000164E1AC2C00) [pid = 212] [serial = 4] [outer = 0000000000000000]
21:58:28 INFO - GECKO(5972) | ++DOMWINDOW == 2 (00000164E7189000) [pid = 212] [serial = 5] [outer = 00000164E1AC2C00]
21:58:28 INFO - checking window state
21:58:28 INFO - Not taking screenshot here: see the one that was previously logged
21:58:28 INFO - TEST-UNEXPECTED-FAIL | dom/webauthn/tests/browser/browser_abort_visibility.js | Found a browser window after previous test timed out -
21:58:28 INFO - GECKO(5972) | ++DOMWINDOW == 3 (00000164E90A9000) [pid = 212] [serial = 6] [outer = 00000164E1AC2C00]
21:58:28 INFO - GECKO(5972) | must wait for focus
21:58:28 INFO - GECKO(5972) | [Parent 5972, Main Thread] WARNING: '!mSelection', file z:/build/build/src/editor/libeditor/EditorBase.cpp, line 4826
21:58:28 INFO - GECKO(5972) | [Parent 5972, Main Thread] WARNING: '!editActionData.CanHandle()', file z:/build/build/src/editor/libeditor/EditorBase.cpp, line 1263
21:58:28 INFO - TEST-START | dom/webauthn/tests/browser/browser_fido_appid_extension.js
21:58:28 INFO - GECKO(5972) | ++DOCSHELL 00000241C933F000 == 2 [pid = 5448] [id = {dd053174-aaeb-4817-a729-e748b88d6235}]
21:58:28 INFO - GECKO(5972) | ++DOMWINDOW == 3 (00000241CEA86000) [pid = 5448] [serial = 6] [outer = 0000000000000000]
21:58:28 INFO - GECKO(5972) | ++DOMWINDOW == 4 (00000241D08A8800) [pid = 5448] [serial = 7] [outer = 00000241CEA86000]
21:58:29 INFO - GECKO(5972) | ++DOMWINDOW == 5 (00000241D0B6E800) [pid = 5448] [serial = 8] [outer = 00000241CEA86000]
21:58:29 INFO - GECKO(5972) | [Parent 5972, IPDL Background] WARNING: '!keyListHead', file z:/build/build/src/dom/webauthn/U2FSoftTokenManager.cpp, line 90
21:58:29 INFO - GECKO(5972) | [Parent 5972, IPDL Background] WARNING: '!unwrappedKey', file z:/build/build/src/dom/webauthn/U2FSoftTokenManager.cpp, line 515
21:58:29 INFO - GECKO(5972) | [Parent 5972, IPDL Background] WARNING: '!unwrappedKey', file z:/build/build/src/dom/webauthn/U2FSoftTokenManager.cpp, line 515
21:58:29 INFO - GECKO(5972) | [Parent 5972, IPDL Background] WARNING: '!unwrappedKey', file z:/build/build/src/dom/webauthn/U2FSoftTokenManager.cpp, line 515
21:58:29 INFO - GECKO(5972) | [Parent 5972, IPDL Background] WARNING: '!unwrappedKey', file z:/build/build/src/dom/webauthn/U2FSoftTokenManager.cpp, line 515
21:58:29 INFO - GECKO(5972) | MEMORY STAT | vsize 5902MB | vsizeMaxContiguous 131526630MB | residentFast 242MB | heapAllocated 64MB
21:58:29 INFO - TEST-OK | dom/webauthn/tests/browser/browser_fido_appid_extension.js | took 688ms

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1
• mozilla-release: 1

Platform breakdown:

• windows10-64: 2

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

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• osx-10-10: 1

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

Comment 8

[Firefox Bug Husbandry Bot]

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

Comment 9

[Razvan Maries]

New occurrences: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=240594209&repo=autoland&lineNumber=21452

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• osx-10-10: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1471023&startday=2019-04-15&endday=2019-04-21&tree=all

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-inbound: 1

Platform breakdown:

• linux64: 1

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

Comment 12

[Firefox Bug Husbandry Bot]

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