1656961 - Intermittent TV security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | chooseCertificate should have been called if visiting 'requireclientcert.example.com' for the first time - false == true - JS frame

Status: RESOLVED INCOMPLETE

(Core :: Security: PSM, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: nerli [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=311899544&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/IGRRSgmQTXK3jnPFf1umzA/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/IGRRSgmQTXK3jnPFf1umzA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2020-08-03T16:34:27.103Z] 16:34:27 INFO - TEST-START | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js
[task 2020-08-03T16:34:27.181Z] 16:34:27 INFO - GECKO(4832) | [Parent 5204: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 000002AF73629800 == 17 [pid = 5204] [id = {0572d55d-e0e0-4a05-8648-6a8d075a8846}]
[task 2020-08-03T16:34:27.181Z] 16:34:27 INFO - GECKO(4832) | [Parent 5204: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 36 (000002AF7367A9A0) [pid = 5204] [serial = 36] [outer = 0000000000000000]
[task 2020-08-03T16:34:27.181Z] 16:34:27 INFO - GECKO(4832) | [Parent 5204: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 37 (000002AF73629400) [pid = 5204] [serial = 37] [outer = 000002AF7367A9A0]
[task 2020-08-03T16:34:27.246Z] 16:34:27 INFO - GECKO(4832) | [Parent 5204, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp, line 4240
[task 2020-08-03T16:34:27.317Z] 16:34:27 INFO - GECKO(4832) | [Parent 5204, Main Thread] WARNING: NS_ENSURE_TRUE(root) failed: file /builds/worker/checkouts/gecko/layout/base/nsDocumentViewer.cpp, line 2824
[task 2020-08-03T16:34:27.622Z] 16:34:27 INFO - GECKO(4832) | [Parent 5204: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 000002AF66963400 == 18 [pid = 5204] [id = {887c09c8-21a4-4ebb-8651-e8b60b0decc6}]
[task 2020-08-03T16:34:27.622Z] 16:34:27 INFO - GECKO(4832) | [Parent 5204: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 38 (000002AF75E5BC10) [pid = 5204] [serial = 38] [outer = 0000000000000000]
[task 2020-08-03T16:34:27.622Z] 16:34:27 INFO - GECKO(4832) | [Parent 5204: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 39 (000002AF6EFE6000) [pid = 5204] [serial = 39] [outer = 000002AF75E5BC10]
[task 2020-08-03T16:34:27.672Z] 16:34:27 INFO - GECKO(4832) | [Parent 5204, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp, line 4240
[task 2020-08-03T16:34:27.865Z] 16:34:27 INFO - GECKO(4832) | [Parent 5204: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 000002AF6E988400 == 19 [pid = 5204] [id = {53f6329d-5029-4d48-8122-5e6beb8f1a90}]
[task 2020-08-03T16:34:27.865Z] 16:34:27 INFO - GECKO(4832) | [Parent 5204: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 40 (000002AF75E5BA40) [pid = 5204] [serial = 40] [outer = 0000000000000000]
[task 2020-08-03T16:34:27.924Z] 16:34:27 INFO - GECKO(4832) | [Child 8220: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 00000225A7A32000 == 2 [pid = 8220] [id = {ac709d17-b003-4865-a178-33f3af7920b3}]
[task 2020-08-03T16:34:27.924Z] 16:34:27 INFO - GECKO(4832) | [Child 8220: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (00000225A80CB430) [pid = 8220] [serial = 6] [outer = 0000000000000000]
[task 2020-08-03T16:34:27.924Z] 16:34:27 INFO - GECKO(4832) | [Child 8220: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 7 (00000225A7A33400) [pid = 8220] [serial = 7] [outer = 00000225A80CB430]
[task 2020-08-03T16:34:27.963Z] 16:34:27 INFO - GECKO(4832) | [Parent 5204: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 41 (000002AF71385800) [pid = 5204] [serial = 41] [outer = 000002AF75E5BA40]
[task 2020-08-03T16:34:28.107Z] 16:34:28 INFO - GECKO(4832) | [Parent 5204, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp, line 1601
[task 2020-08-03T16:34:28.126Z] 16:34:28 INFO - GECKO(4832) | [Child 8220: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 8 (00000225A804D400) [pid = 8220] [serial = 8] [outer = 00000225A80CB430]
[task 2020-08-03T16:34:28.369Z] 16:34:28 INFO - GECKO(4832) | [Child 8220, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 351
[task 2020-08-03T16:34:28.370Z] 16:34:28 INFO - GECKO(4832) | [Child 8220, Main Thread] WARNING: NS_ENSURE_TRUE(info) failed: file /builds/worker/checkouts/gecko/extensions/permissions/PermissionDelegateHandler.cpp, line 351
[task 2020-08-03T16:34:28.370Z] 16:34:28 INFO - GECKO(4832) | [Child 8220: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 9 (00000225A851A400) [pid = 8220] [serial = 9] [outer = 00000225A80CB430]
[task 2020-08-03T16:34:28.434Z] 16:34:28 INFO - TEST-INFO | started process screenshot
[task 2020-08-03T16:34:28.520Z] 16:34:28 INFO - TEST-INFO | screenshot: exit 0
[task 2020-08-03T16:34:28.521Z] 16:34:28 INFO - Buffered messages logged at 16:34:27
[task 2020-08-03T16:34:28.521Z] 16:34:28 INFO - Entering test bound testRememberedDecisionsUI
[task 2020-08-03T16:34:28.522Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Should be able to find the test client cert -
[task 2020-08-03T16:34:28.522Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Should be able to find pgo-ca-all-usages.pem -
[task 2020-08-03T16:34:28.523Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Should be able to find client-cert-via-intermediate.pem -
[task 2020-08-03T16:34:28.523Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Expected rememberedList to only have one item - 3 == 3 -
[task 2020-08-03T16:34:28.523Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Expected the rememberedList to have three labels - 9 == 9 -
[task 2020-08-03T16:34:28.524Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Expected host to be asciiHost - "example.com" == "example.com" -
[task 2020-08-03T16:34:28.524Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Expected name to be the commonName of the cert - "Mochitest client" == "Mochitest client" -
[task 2020-08-03T16:34:28.525Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Expected serialNumber to be the serialNumber of the cert - "03" == "03" -
[task 2020-08-03T16:34:28.525Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Expected host to be asciiHost - "example.org" == "example.org" -
[task 2020-08-03T16:34:28.525Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Expected name to be the commonName of the cert - "Temporary Certificate Authority" == "Temporary Certificate Authority" -
[task 2020-08-03T16:34:28.526Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Expected serialNumber to be the serialNumber of the cert - "0D:08:36:1B:2E:46:F7:E8:3F:1F:D1:82:52:46:15:33:5A:A7:7F:C2" == "0D:08:36:1B:2E:46:F7:E8:3F:1F:D1:82:52:46:15:33:5A:A7:7F:C2" -
[task 2020-08-03T16:34:28.527Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Expected host to be asciiHost - "example.test" == "example.test" -
[task 2020-08-03T16:34:28.527Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Expected name to be the commonName of the cert - "client cert via intermediate" == "client cert via intermediate" -
[task 2020-08-03T16:34:28.527Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Expected serialNumber to be the serialNumber of the cert - "67:FC:15:B1:90:55:52:39:71:DF:66:EA:03:4B:17:80:71:67:21:4F" == "67:FC:15:B1:90:55:52:39:71:DF:66:EA:03:4B:17:80:71:67:21:4F" -
[task 2020-08-03T16:34:28.527Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Expected to get the same key that was passed in getDecisions() - "exampleKey2" == "exampleKey2" -
[task 2020-08-03T16:34:28.527Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Expected forgetRememberedDecision() to get called - true == true -
[task 2020-08-03T16:34:28.527Z] 16:34:28 INFO - Leaving test bound testRememberedDecisionsUI
[task 2020-08-03T16:34:28.527Z] 16:34:28 INFO - Entering test bound testDeletingRememberedDecisions
[task 2020-08-03T16:34:28.527Z] 16:34:28 INFO - Buffered messages logged at 16:34:28
[task 2020-08-03T16:34:28.528Z] 16:34:28 INFO - TEST-PASS | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | Expected and actual URLs should match (got 'https://requireclientcert.example.com/', expected 'https://requireclientcert.example.com/') - true == true -
[task 2020-08-03T16:34:28.528Z] 16:34:28 INFO - Buffered messages finished
[task 2020-08-03T16:34:28.528Z] 16:34:28 INFO - TEST-UNEXPECTED-FAIL | security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js | chooseCertificate should have been called if visiting 'requireclientcert.example.com' for the first time - false == true - JS frame :: chrome://mochitests/content/browser/security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js :: testDeletingRememberedDecisions :: line 224
[task 2020-08-03T16:34:28.528Z] 16:34:28 INFO - Stack trace:
[task 2020-08-03T16:34:28.528Z] 16:34:28 INFO - chrome://mochitests/content/browser/security/manager/ssl/tests/mochitest/browser/browser_clientAuthRememberService.js:testDeletingRememberedDecisions:224
[task 2020-08-03T16:34:28.528Z] 16:34:28 INFO - GECKO(4832) | [Parent 5204: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 000002AF7138B400 == 20 [pid = 5204] [id = {ef6bfa44-c19b-412e-885e-fb3382f9ffb4}]
[task 2020-08-03T16:34:28.528Z] 16:34:28 INFO - GECKO(4832) | [Parent 5204: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 42 (000002AF75E5AD90) [pid = 5204] [serial = 42] [outer = 0000000000000000]
[task 2020-08-03T16:34:28.528Z] 16:34:28 INFO - GECKO(4832) | [Parent 5204: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 43 (000002AF7414C000) [pid = 5204] [serial = 43] [outer = 000002AF75E5AD90]
[task 2020-08-03T16:34:28.568Z] 16:34:28 INFO - GECKO(4832) | [Parent 5204, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp, line 4240
[task 2020-08-03T16:34:28.753Z] 16:34:28 INFO - GECKO(4832) | [Parent 5204: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 000002AF6A670400 == 21 [pid = 5204] [id = {d404c683-e201-476c-9178-2a40154d3ca5}]
[task 2020-08-03T16:34:28.753Z] 16:34:28 INFO - GECKO(4832) | [Parent 5204: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 44 (000002AF7256CF60) [pid = 5204] [serial = 44] [outer = 0000000000000000]
[task 2020-08-03T16:34:28.812Z] 16:34:28 INFO - GECKO(4832) | [Child 8220: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 00000225A8511000 == 3 [pid = 8220] [id = {4e279966-3afc-41e7-bf57-47ba1e37fe5c}]
[task 2020-08-03T16:34:28.812Z] 16:34:28 INFO - GECKO(4832) | [Child 8220: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 10 (00000225A80CCBC0) [pid = 8220] [serial = 10] [outer = 0000000000000000]
[task 2020-08-03T16:34:28.812Z] 16:34:28 INFO - GECKO(4832) | [Child 8220: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (00000225A851B800) [pid = 8220] [serial = 11] [outer = 00000225A80CCBC0]
[task 2020-08-03T16:34:28.872Z] 16:34:28 INFO - GECKO(4832) | [Parent 5204: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 45 (000002AF6A66AC00) [pid = 5204] [serial = 45] [outer = 000002AF7256CF60]
[task 2020-08-03T16:34:29.010Z] 16:34:29 INFO - GECKO(4832) | [Parent 5204, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp, line 1601
[task 2020-08-03T16:34:29.029Z] 16:34:29 INFO - GECKO(4832) | [Child 8220: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (00000225AA0A4800) [pid = 8220] [serial = 12] [outer = 00000225A80CCBC0]
[task 2020-08-03T16:34:29.372Z] 16:34:29 INFO - GECKO(4832) | [Child 8220: Main Thread]: I/DocShellAndDO

Comment 1

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

Set release status flags based on info from the regressing bug 634697

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 8

Platform breakdown:

• windows10-64: 2
• macosx1014-64: 2
• linux1804-64: 2
• windows7-32: 2

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

Comment 3

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

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

Comment 4

[Sandor Molnar[:smolnar]]

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=345386383&repo=autoland&lineNumber=2434

Comment 5

[Intermittent Failures Robot]

12 failures in 2224 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 12

Platform and build breakdown:

• linux1804-64-qr: 2
  • opt: 1
  • debug: 1
• linux1804-64: 2
  • opt: 1
  • debug: 1
• windows10-64-qr: 2
  • opt: 1
  • debug: 1
• windows10-32-qr: 2
  • debug: 1
  • opt: 1
• macosx1015-64-qr: 4
  • opt: 2
  • debug: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1656961&startday=2021-07-12&endday=2021-07-18&tree=all

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 9

Platform and build breakdown:

• linux1804-64-qr: 2
  • debug: 1
  • opt: 1
• macosx1015-64-qr: 2
  • debug: 1
  • opt: 1
• windows10-32-qr: 2
  • debug: 1
  • opt: 1
• windows10-64-qr: 3
  • debug: 2
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1656961&startday=2021-07-26&endday=2021-08-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.