1600940 - Intermittent security/manager/ssl/tests/unit/test_intermediate_preloads.js | xpcshell return code: 0

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

Filed by: shindli [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=279276243&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/G9K7ighnQiGr23jcn7mgoA/runs/0/artifacts/public/logs/live_backing.log

---

task 2019-12-03T10:56:38.751Z] 10:56:38 INFO - 'TASKCLUSTER_PROXY_URL': 'http://taskcluster',
[task 2019-12-03T10:56:38.751Z] 10:56:38 INFO - 'TASKCLUSTER_PUBLIC_IP': '54.162.100.201',
[task 2019-12-03T10:56:38.752Z] 10:56:38 INFO - 'TASKCLUSTER_ROOT_URL': 'https://firefox-ci-tc.services.mozilla.com',
[task 2019-12-03T10:56:38.752Z] 10:56:38 INFO - 'TASKCLUSTER_WORKER_GROUP': 'aws',
[task 2019-12-03T10:56:38.752Z] 10:56:38 INFO - 'TASKCLUSTER_WORKER_LOCATION': '{"availabilityZone":"us-east-1e","cloud":"aws","region":"us-east-1"}',
[task 2019-12-03T10:56:38.752Z] 10:56:38 INFO - 'TASKCLUSTER_WORKER_TYPE': 't-linux-xlarge',
[task 2019-12-03T10:56:38.752Z] 10:56:38 INFO - 'TASK_ID': 'G9K7ighnQiGr23jcn7mgoA',
[task 2019-12-03T10:56:38.753Z] 10:56:38 INFO - 'TERM': 'xterm',
[task 2019-12-03T10:56:38.753Z] 10:56:38 INFO - 'TOOLTOOL_CACHE': '/builds/worker/tooltool-cache',
[task 2019-12-03T10:56:38.753Z] 10:56:38 INFO - 'USER': 'worker',
[task 2019-12-03T10:56:38.753Z] 10:56:38 INFO - 'WORKING_DIR': '/builds/worker'}
[task 2019-12-03T10:56:38.753Z] 10:56:38 INFO - Calling ['/builds/worker/workspace/build/venv/bin/python', '-u', '/builds/worker/workspace/build/tests/xpcshell/runxpcshelltests.py', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', u'--symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/bpIwdAxZTv-Byw-6Pv1Jxg/artifacts/public/build/target.crashreporter-symbols.zip', '--test-plugin-path=/builds/worker/workspace/build/application/firefox/plugins', '--log-raw=/builds/worker/workspace/build/blobber_upload_dir/xpcshell_raw.log', '--log-errorsummary=/builds/worker/workspace/build/blobber_upload_dir/xpcshell_errorsummary.log', '--utility-path=tests/bin', '--xpcshell=/builds/worker/workspace/build/application/firefox/xpcshell', '--manifest=tests/xpcshell/tests/xpcshell.ini', u'security/manager/ssl/tests/unit/test_intermediate_preloads.js'] with output_timeout 1000
[task 2019-12-03T10:56:39.061Z] 10:56:39 INFO - Found node at /usr/local/bin/node
[task 2019-12-03T10:56:39.061Z] 10:56:39 INFO - Found moz-http2 at /builds/worker/workspace/build/tests/xpcshell/moz-http2/moz-http2.js
[task 2019-12-03T10:56:39.730Z] 10:56:39 INFO - Running tests sequentially.
[task 2019-12-03T10:56:39.730Z] 10:56:39 INFO - SUITE-START | Running 1 tests
[task 2019-12-03T10:56:39.730Z] 10:56:39 INFO - profile dir is /tmp/xpcshell/xpcshellprofile
[task 2019-12-03T10:56:39.738Z] 10:56:39 INFO - TEST-START | security/manager/ssl/tests/unit/test_intermediate_preloads.js
[task 2019-12-03T10:56:42.066Z] 10:56:42 WARNING - TEST-UNEXPECTED-FAIL | security/manager/ssl/tests/unit/test_intermediate_preloads.js | xpcshell return code: 0
[task 2019-12-03T10:56:42.066Z] 10:56:42 INFO - TEST-INFO took 2326ms
[task 2019-12-03T10:56:42.067Z] 10:56:42 INFO - >>>>>>>
[task 2019-12-03T10:56:42.067Z] 10:56:42 INFO - PID 16535 | [CodeCoverage] Setting handlers for process 16535.
[task 2019-12-03T10:56:42.067Z] 10:56:42 INFO - PID 16535 | [CodeCoverage] Requested flush for 16535.
[task 2019-12-03T10:56:42.068Z] 10:56:42 INFO - PID 16535 | [CodeCoverage] flush completed.
[task 2019-12-03T10:56:42.068Z] 10:56:42 INFO - PID 16535 | [CodeCoverage] JS flush completed.
[task 2019-12-03T10:56:42.068Z] 10:56:42 INFO - (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2019-12-03T10:56:42.068Z] 10:56:42 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2019-12-03T10:56:42.068Z] 10:56:42 INFO - (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2019-12-03T10:56:42.068Z] 10:56:42 INFO - running event loop
[task 2019-12-03T10:56:42.068Z] 10:56:42 INFO - security/manager/ssl/tests/unit/test_intermediate_preloads.js | Starting test_preload_empty
[task 2019-12-03T10:56:42.069Z] 10:56:42 INFO - (xpcshell/head.js) | test test_preload_empty pending (2)
[task 2019-12-03T10:56:42.070Z] 10:56:42 INFO - TEST-PASS | security/manager/ssl/tests/unit/test_intermediate_preloads.js | test_preload_empty - [test_preload_empty : 170] EE cert should have successfully loaded - {"emailAddress":"(no email address)","isBuiltInRoot":false,"getEmailAddresses":"function getEmailAddresses() {\n [native code]\n}","containsEmailAddress":"function containsEmailAddress() {\n [native code]\n}","subjectName":"CN=ee","subjectAltNames":"","commonName":"ee","organization":"","organizationalUnit":"","sha256Fingerprint":"2C:5A:1C:91:84:B0:C5:2E:27:15:DE:78:E7:73:86:25:E0:67:67:6D:F5:30:21:58:4C:E8:70:0C:02:96:B8:73","sha1Fingerprint":"9B:FA:D5:E1:E2:AE:62:98:3A:43:27:E3:7F:CA:FF:46:FB:4A:97:F7","tokenName":"Generic Crypto Services","issuerName":"CN=intermediate-preloading-intermediate","serialNumber":"29:67:15:81:2F:5E:C2:A1:D3:67:FC:D8:4F:74:D5:7F:3E:44:DC:61","issuerCommonName":"intermediate-preloading-intermediate","issuerOrganization":"","issuerOrganizationUnit":"","validity":{"QueryInterface":"function QueryInterface() {\n [native code]\n}","notBefore":1511740800000000,"notBeforeLocalTime":"November 27, 2017, 12:00:00 AM GMT","notBeforeLocalDay":"November 27, 2017","notBeforeGMT":"November 27, 2017, 12:00:00 AM GMT","notAfter":1580860800000000,"notAfterLocalTime":"February 5, 2020, 12:00:00 AM GMT","notAfterLocalDay":"February 5, 2020","notAfterGMT":"February 5, 2020, 12:00:00 AM GMT"},"dbKey":"AAAAAAAAAAAAAAAUAAAAMSlnFYEvXsKh02f82E901X8+RNxhMC8xLTArBgNVBAMMJGludGVybWVkaWF0ZS1wcmVsb2FkaW5nLWludGVybWVkaWF0ZQ==","displayName":"ee","certType":0,"isSelfSigned":false,"keyUsages":"","ASN1Structure":{"QueryInterface":"function QueryInterface() {\n [native code]\n}","type":0,"tag":0,"displayName":"ee","displayValue":"","ASN1_END_CONTENTS":0,"ASN1_BOOLEAN":1,"ASN1_INTEGER":2,"ASN1_BIT_STRING":3,"ASN1_OCTET_STRING":4,"ASN1_NULL":5,"ASN1_OBJECT_ID":6,"ASN1_ENUMERATED":10,"ASN1_UTF8_STRING":12,"ASN1_SEQUENCE":16,"ASN1_SET":17,"ASN1_PRINTABLE_STRING":19,"ASN1_T61_STRING":20,"ASN1_IA5_STRING":22,"ASN1_UTC_TIME":23,"ASN1_GEN_TIME":24,"ASN1_VISIBLE_STRING":26,"ASN1_UNIVERSAL_STRING":28,"ASN1_BMP_STRING":30,"ASN1_HIGH_TAG_NUMBER":31,"ASN1_CONTEXT_SPECIFIC":32,"ASN1_APPLICATION":33,"ASN1_PRIVATE":34},"getRawDER":"function getRawDER() {\n [native code]\n}","getBase64DERString":"function getBase64DERString() {\n [native code]\n}","equals":"function equals() {\n [native code]\n}","sha256SubjectPublicKeyInfoDigest":"VCIlmPM9NkgFQtrs4Oa5TeFcDu6MWRTKSNdePEhOgD8=","markForPermDeletion":"function markForPermDeletion() {\n [native code]\n}","UNKNOWN_CERT":0,"CA_CERT":1,"USER_CERT":2,"EMAIL_CERT":4,"SERVER_CERT":8,"ANY_CERT":65535} != null
[task 2019-12-03T10:56:42.070Z] 10:56:42 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2019-12-03T10:56:42.071Z] 10:56:42 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "IndexedDB UnknownErr: ActorsParent.cpp:614"]"
[task 2019-12-03T10:56:42.072Z] 10:56:42 INFO - Unexpected exception Error: IndexedDB clear() The operation failed for reasons unrelated to the database itself and not covered by any other error code. at resource://services-common/kinto-offline-client.js:603
[task 2019-12-03T10:56:42.072Z] 10:56:42 INFO - exiting test
[task 2019-12-03T10:56:42.072Z] 10:56:42 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "IndexedDB UnknownErr: ActorsParent.cpp:614"]"
[task 2019-12-03T10:56:42.072Z] 10:56:42 INFO - PID 16535 | [CodeCoverage] Requested flush for 16535.
[task 2019-12-03T10:56:42.072Z] 10:56:42 INFO - PID 16535 | [CodeCoverage] flush completed.
[task 2019-12-03T10:56:42.072Z] 10:56:42 INFO - PID 16535 | [CodeCoverage] JS flush completed.
[task 2019-12-03T10:56:42.072Z] 10:56:42 INFO - <<<<<<<
[task 2019-12-03T10:56:42.073Z] 10:56:42 INFO - INFO | Result summary:
[task 2019-12-03T10:56:42.074Z] 10:56:42 INFO - INFO | Passed: 0
[task 2019-12-03T10:56:42.074Z] 10:56:42 WARNING - INFO | Failed: 1
[task 2019-12-03T10:56:42.075Z] 10:56:42 WARNING - One or more unittests failed.
[task 2019-12-03T10:56:42.075Z] 10:56:42 INFO - INFO | Todo: 0
[task 2019-12-03T10:56:42.075Z] 10:56:42 INFO - INFO | Retried: 0
[task 2019-12-03T10:56:42.075Z] 10:56:42 INFO - SUITE-END | took 2s
[task 2019-12-03T10:56:42.075Z] 10:56:42 INFO - Node moz-http2 server shutting down ...

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 2

Platform breakdown:

• linux64-ccov: 2

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

Comment 2

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

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

Comment 3

[Stefan Hindli [:stefan_hindli]]

New failure: https://treeherder.mozilla.org/#/jobs?repo=autoland&resultStatus=testfailed%2Cbusted%2Cexception&classifiedState=unclassified&selectedJob=293750495

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4

Platform breakdown:

• linux64-tsan: 1
• macosx1014-64-shippable: 3

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

Comment 5

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

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