1776118 - Intermittent security/manager/ssl/tests/unit/test_ocsp_required.js | single tracking bug

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=382230427&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/EAsXo-3SRpOds8FGidvH-A/runs/0/artifacts/public/logs/live_backing.log

---

[task 2022-06-23T02:49:22.296Z] 02:49:22     INFO -  TEST-START | security/manager/ssl/tests/unit/test_ocsp_required.js
[task 2022-06-23T02:49:22.825Z] 02:49:22  WARNING -  TEST-UNEXPECTED-FAIL | security/manager/ssl/tests/unit/test_ocsp_required.js | xpcshell return code: 0
[task 2022-06-23T02:49:22.826Z] 02:49:22     INFO -  TEST-INFO took 520ms
[task 2022-06-23T02:49:22.827Z] 02:49:22     INFO -  >>>>>>>
[task 2022-06-23T02:49:22.827Z] 02:49:22     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2022-06-23T02:49:22.827Z] 02:49:22     INFO -  TEST-PASS | security/manager/ssl/tests/unit/test_ocsp_required.js | run_test - [run_test : 26] Binary util GenerateOCSPResponse should exist - true == true
[task 2022-06-23T02:49:22.827Z] 02:49:22     INFO -  "argArray = sql:ocsp_certs,bad-signature,default-ee,unused,0,/builds/worker/workspace/build/tests/xpcshell/tests/security/manager/ssl/tests/unit/0.ocsp"
[task 2022-06-23T02:49:22.827Z] 02:49:22     INFO -  TEST-PASS | security/manager/ssl/tests/unit/test_ocsp_required.js | run_test - [run_test : 26] Process exit value should be 0 - 0 == 0
[task 2022-06-23T02:49:22.828Z] 02:49:22     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2022-06-23T02:49:22.828Z] 02:49:22     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2022-06-23T02:49:22.828Z] 02:49:22     INFO -  running event loop
[task 2022-06-23T02:49:22.829Z] 02:49:22     INFO -  security/manager/ssl/tests/unit/test_ocsp_required.js | Starting
[task 2022-06-23T02:49:22.829Z] 02:49:22     INFO -  (xpcshell/head.js) | test pending (2)
[task 2022-06-23T02:49:22.830Z] 02:49:22     INFO -  TEST-PASS | security/manager/ssl/tests/unit/test_ocsp_required.js |  - Binary util OCSPStaplingServer should exist - true == true
[task 2022-06-23T02:49:22.830Z] 02:49:22     INFO -  TEST-PASS | security/manager/ssl/tests/unit/test_ocsp_required.js |  - certificate folder (ocsp_certs) should exist - true == true
[task 2022-06-23T02:49:22.831Z] 02:49:22     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2022-06-23T02:49:22.831Z] 02:49:22     INFO -  PID 3428 | sending 'GET / HTTP/1.0
[task 2022-06-23T02:49:22.831Z] 02:49:22     INFO -  PID 3428 | '
[task 2022-06-23T02:49:22.832Z] 02:49:22     INFO -  (xpcshell/head.js) | test pending (2)
[task 2022-06-23T02:49:22.833Z] 02:49:22     INFO -  (xpcshell/head.js) | test finished (2)
[task 2022-06-23T02:49:22.833Z] 02:49:22     INFO -  PID 3428 | HTTP/1.0 200 OK
[task 2022-06-23T02:49:22.834Z] 02:49:22     INFO -  PID 3428 | content-type: text/plain
[task 2022-06-23T02:49:22.834Z] 02:49:22     INFO -  PID 3428 | connection: close
[task 2022-06-23T02:49:22.834Z] 02:49:22     INFO -  PID 3428 | server: httpd.js
[task 2022-06-23T02:49:22.834Z] 02:49:22     INFO -  PID 3428 | date: Thu, 23 Jun 2022 02:49:22 GMT
[task 2022-06-23T02:49:22.834Z] 02:49:22     INFO -  PID 3428 | content-length: 3
[task 2022-06-23T02:49:22.835Z] 02:49:22     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2022-06-23T02:49:22.836Z] 02:49:22     INFO -  (xpcshell/head.js) | test finished (2)
[task 2022-06-23T02:49:22.837Z] 02:49:22     INFO -  security/manager/ssl/tests/unit/test_ocsp_required.js | Starting
[task 2022-06-23T02:49:22.837Z] 02:49:22     INFO -  (xpcshell/head.js) | test pending (2)
[task 2022-06-23T02:49:22.838Z] 02:49:22     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2022-06-23T02:49:22.839Z] 02:49:22     INFO -  PID 3428 | found pre-defined host 'ocsp-stapling-none.example.com'
[task 2022-06-23T02:49:22.841Z] 02:49:22     INFO -  PID 3428 | found pre-defined host 'ocsp-stapling-none.example.com'
[task 2022-06-23T02:49:22.842Z] 02:49:22     INFO -  PID 3428 | PR_Recv failed: SSL_ERROR_BAD_CERT_ALERT
[task 2022-06-23T02:49:22.842Z] 02:49:22     INFO -  "handling ocsp-stapling-none.example.com"
[task 2022-06-23T02:49:22.843Z] 02:49:22     INFO -  TEST-PASS | security/manager/ssl/tests/unit/test_ocsp_required.js |  - Actual and expected connection result should match - 2153389923 == 2153389923
[task 2022-06-23T02:49:22.843Z] 02:49:22     INFO -  (xpcshell/head.js) | test run_next_test 2 pending (2)
[task 2022-06-23T02:49:22.844Z] 02:49:22     INFO -  (xpcshell/head.js) | test finished (2)
[task 2022-06-23T02:49:22.844Z] 02:49:22     INFO -  security/manager/ssl/tests/unit/test_ocsp_required.js | Starting
[task 2022-06-23T02:49:22.844Z] 02:49:22     INFO -  (xpcshell/head.js) | test pending (2)
[task 2022-06-23T02:49:22.845Z] 02:49:22     INFO -  (xpcshell/head.js) | test run_next_test 2 finished (2)
[task 2022-06-23T02:49:22.845Z] 02:49:22     INFO -  PID 3428 | found pre-defined host 'ocsp-stapling-none.example.com'
[task 2022-06-23T02:49:22.845Z] 02:49:22     INFO -  PID 3428 | found pre-defined host 'ocsp-stapling-none.example.com'
[task 2022-06-23T02:49:22.845Z] 02:49:22     INFO -  "handling ocsp-stapling-none.example.com"
[task 2022-06-23T02:49:22.846Z] 02:49:22  WARNING -  TEST-UNEXPECTED-FAIL | security/manager/ssl/tests/unit/test_ocsp_required.js |  - Actual and expected connection result should match - 2152398868 == 2153389923
[task 2022-06-23T02:49:22.846Z] 02:49:22     INFO -  -e:null:1
[task 2022-06-23T02:49:22.846Z] 02:49:22     INFO -  exiting test
[task 2022-06-23T02:49:22.846Z] 02:49:22     INFO -  PID 3428 | JavaScript error: /builds/worker/workspace/build/tests/xpcshell/head.js, line 868: NS_ERROR_ABORT
[task 2022-06-23T02:49:22.846Z] 02:49:22     INFO -  PID 3428 | PR_Recv failed: SSL_ERROR_BAD_CERT_ALERT
[task 2022-06-23T02:49:22.846Z] 02:49:22     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "NS_ERROR_ABORT" {file: "/builds/worker/workspace/build/tests/xpcshell/head.js" line: 868}]"
[task 2022-06-23T02:49:22.846Z] 02:49:22  WARNING -  TEST-UNEXPECTED-FAIL | security/manager/ssl/tests/unit/test_ocsp_required.js |  - A promise chain failed to handle a rejection: [Exception... "Abort"  nsresult: "0x80004004 (NS_ERROR_ABORT)"  location: "JS frame :: /builds/worker/workspace/build/tests/xpcshell/head.js :: _abort_failed_test :: line 868"  data: no] - stack: _abort_failed_test@/builds/worker/workspace/build/tests/xpcshell/head.js:868:20
[task 2022-06-23T02:49:22.846Z] 02:49:22     INFO -  do_report_result@/builds/worker/workspace/build/tests/xpcshell/head.js:969:5
[task 2022-06-23T02:49:22.846Z] 02:49:22     INFO -  Assert<@/builds/worker/workspace/build/tests/xpcshell/head.js:75:21
[task 2022-06-23T02:49:22.847Z] 02:49:22     INFO -  proto.report@resource://testing-common/Assert.jsm:228:10
[task 2022-06-23T02:49:22.847Z] 02:49:22     INFO -  equal@resource://testing-common/Assert.jsm:270:8
[task 2022-06-23T02:49:22.847Z] 02:49:22     INFO -  asyncConnectTo/<@/builds/worker/workspace/build/tests/xpcshell/tests/security/manager/ssl/tests/unit/head_psm.js:627:12
[task 2022-06-23T02:49:22.847Z] 02:49:22     INFO -  _do_main@/builds/worker/workspace/build/tests/xpcshell/head.js:240:6
[task 2022-06-23T02:49:22.847Z] 02:49:22     INFO -  _execute_test@/builds/worker/workspace/build/tests/xpcshell/head.js:597:5
[task 2022-06-23T02:49:22.847Z] 02:49:22     INFO -  @-e:1:1
[task 2022-06-23T02:49:22.847Z] 02:49:22     INFO -  Rejection date: Thu Jun 23 2022 02:49:22 GMT+0000 (Coordinated Universal Time) - false == true
[task 2022-06-23T02:49:22.847Z] 02:49:22     INFO -  resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:271
[task 2022-06-23T02:49:22.847Z] 02:49:22     INFO -  /builds/worker/workspace/build/tests/xpcshell/head.js:_execute_test:598
[task 2022-06-23T02:49:22.848Z] 02:49:22     INFO -  -e:null:1
[task 2022-06-23T02:49:22.848Z] 02:49:22     INFO -  exiting test
[task 2022-06-23T02:49:22.848Z] 02:49:22     INFO -  PID 3428 | !!! error running onStopped callback: TypeError: callback is not a function
[task 2022-06-23T02:49:22.848Z] 02:49:22     INFO -  <<<<<<<
[task 2022-06-23T02:49:28.359Z] 02:49:28     INFO -  Created a conditioned-profile copy: /tmp/tmps2q6ya_u/profile
[task 2022-06-23T02:49:28.363Z] 02:49:28     INFO -  TEST-START | security/manager/ssl/tests/unit/test_ocsp_stapling.js

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1776118&startday=2022-06-20&endday=2022-06-26&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1776118&startday=2022-06-27&endday=2022-07-03&tree=all

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4

Platform and build breakdown:

• linux1804-64-shippable-qr: 3
  • opt: 3
• linux1804-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1776118&startday=2022-07-04&endday=2022-07-10&tree=all

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 2
• autoland: 1

Platform and build breakdown:

• linux1804-64-shippable-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1776118&startday=2022-07-11&endday=2022-07-17&tree=all

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 3
• autoland: 1

Platform and build breakdown:

• linux1804-64-shippable-qr: 3
  • opt: 3
• linux1804-64-qr: 1
  • opt: 1

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1
• try: 1
• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-qr: 2
  • opt: 2
• linux1804-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1776118&startday=2022-07-25&endday=2022-07-31&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.

Comment 8

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=408164396&repo=autoland

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• linux1804-64-shippable-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1776118&startday=2023-03-06&endday=2023-03-12&tree=all

Comment 10

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

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