Closed Bug 1753787 Opened 5 months ago Closed 5 months ago

Perma toolkit/mozapps/extensions/test/xpcshell/test_ProductAddonChecker_signatures.js | test_valid_content_signature - [test_valid_content_signature : 117] Should successfully get addon list, instead failed with Error: Content signature is

Categories

(Toolkit :: Add-ons Manager, defect)

defect

Tracking

()

RESOLVED FIXED
99 Branch
Tracking Status
firefox-esr91 --- fixed
firefox96 --- wontfix
firefox97 --- fixed
firefox98 --- fixed
firefox99 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: jcristau)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell needswork:owner])

Attachments

(2 files)

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


[task 2022-02-05T00:03:15.351Z] 00:03:15     INFO -  TEST-START | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_ProductAddonChecker_signatures.js
[task 2022-02-05T00:03:15.539Z] 00:03:15  WARNING -  TEST-UNEXPECTED-FAIL | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_ProductAddonChecker_signatures.js | xpcshell return code: 0
[task 2022-02-05T00:03:15.539Z] 00:03:15     INFO -  TEST-INFO took 187ms
[task 2022-02-05T00:03:15.539Z] 00:03:15     INFO -  >>>>>>>
[task 2022-02-05T00:03:15.539Z] 00:03:15     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2022-02-05T00:03:15.539Z] 00:03:15     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2022-02-05T00:03:15.540Z] 00:03:15     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2022-02-05T00:03:15.540Z] 00:03:15     INFO -  running event loop
[task 2022-02-05T00:03:15.540Z] 00:03:15     INFO -  xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_ProductAddonChecker_signatures.js | Starting load_good_xml
[task 2022-02-05T00:03:15.540Z] 00:03:15     INFO -  (xpcshell/head.js) | test load_good_xml pending (2)
[task 2022-02-05T00:03:15.540Z] 00:03:15     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2022-02-05T00:03:15.540Z] 00:03:15     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2022-02-05T00:03:15.541Z] 00:03:15     INFO -  (xpcshell/head.js) | test load_good_xml finished (2)
[task 2022-02-05T00:03:15.541Z] 00:03:15     INFO -  xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_ProductAddonChecker_signatures.js | Starting test_valid_content_signature
[task 2022-02-05T00:03:15.541Z] 00:03:15     INFO -  (xpcshell/head.js) | test test_valid_content_signature pending (2)
[task 2022-02-05T00:03:15.541Z] 00:03:15     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2022-02-05T00:03:15.542Z] 00:03:15     INFO -  "CONSOLE_MESSAGE: (info) 1644019395507	addons.productaddons	INFO	sending request to: http://localhost:55480/good.xml?validSignature"
[task 2022-02-05T00:03:15.542Z] 00:03:15     INFO -  "CONSOLE_MESSAGE: (info) 1644019395514	addons.productaddons	INFO	Completed downloading document"
[task 2022-02-05T00:03:15.542Z] 00:03:15     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "XML Parsing Error: syntax error
[task 2022-02-05T00:03:15.542Z] 00:03:15     INFO -  Location: http://localhost:55480/valid_x5u
[task 2022-02-05T00:03:15.543Z] 00:03:15     INFO -  Line Number 1, Column 1:" {file: "http://localhost:55480/valid_x5u" line: 1 column: 1 source: "-----BEGIN CERTIFICATE-----"}]"
[task 2022-02-05T00:03:15.543Z] 00:03:15  WARNING -  TEST-UNEXPECTED-FAIL | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_ProductAddonChecker_signatures.js | test_valid_content_signature - [test_valid_content_signature : 117] Should successfully get addon list, instead failed with Error: Content signature is not valid - false == true
[task 2022-02-05T00:03:15.544Z] 00:03:15     INFO -  /opt/worker/tasks/task_164401895204175/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_ProductAddonChecker_signatures.js:test_valid_content_signature:117
[task 2022-02-05T00:03:15.544Z] 00:03:15     INFO -  /opt/worker/tasks/task_164401895204175/build/tests/xpcshell/head.js:_do_main:240
[task 2022-02-05T00:03:15.544Z] 00:03:15     INFO -  /opt/worker/tasks/task_164401895204175/build/tests/xpcshell/head.js:_execute_test:604
[task 2022-02-05T00:03:15.544Z] 00:03:15     INFO -  -e:null:1
[task 2022-02-05T00:03:15.544Z] 00:03:15     INFO -  exiting test
[task 2022-02-05T00:03:15.545Z] 00:03:15     INFO -  Unexpected exception NS_ERROR_ABORT:
[task 2022-02-05T00:03:15.545Z] 00:03:15     INFO -  _abort_failed_test@/opt/worker/tasks/task_164401895204175/build/tests/xpcshell/head.js:875:20
[task 2022-02-05T00:03:15.545Z] 00:03:15     INFO -  do_report_result@/opt/worker/tasks/task_164401895204175/build/tests/xpcshell/head.js:976:5
[task 2022-02-05T00:03:15.545Z] 00:03:15     INFO -  Assert<@/opt/worker/tasks/task_164401895204175/build/tests/xpcshell/head.js:75:21
[task 2022-02-05T00:03:15.546Z] 00:03:15     INFO -  proto.report@resource://testing-common/Assert.jsm:228:10
[task 2022-02-05T00:03:15.546Z] 00:03:15     INFO -  proto.ok@resource://testing-common/Assert.jsm:254:10
[task 2022-02-05T00:03:15.546Z] 00:03:15     INFO -  test_valid_content_signature@/opt/worker/tasks/task_164401895204175/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_ProductAddonChecker_signatures.js:117:12
[task 2022-02-05T00:03:15.546Z] 00:03:15     INFO -  _do_main@/opt/worker/tasks/task_164401895204175/build/tests/xpcshell/head.js:240:6
[task 2022-02-05T00:03:15.547Z] 00:03:15     INFO -  _execute_test@/opt/worker/tasks/task_164401895204175/build/tests/xpcshell/head.js:604:5
[task 2022-02-05T00:03:15.547Z] 00:03:15     INFO -  @-e:1:1
[task 2022-02-05T00:03:15.547Z] 00:03:15     INFO -  exiting test
[task 2022-02-05T00:03:15.547Z] 00:03:15     INFO -  "CONSOLE_MESSAGE: (info) 1644019395518	addons.productaddons	WARN	Unexpected invalid content signature found during validation"
[task 2022-02-05T00:03:15.547Z] 00:03:15     INFO -  PID 4617 | !!! error running onStopped callback: TypeError: callback is not a function
[task 2022-02-05T00:03:15.548Z] 00:03:15     INFO -  <<<<<<<
[task 2022-02-05T00:03:15.548Z] 00:03:15     INFO -  INFO | Result summary:
[task 2022-02-05T00:03:15.548Z] 00:03:15     INFO -  INFO | Passed: 1665
[task 2022-02-05T00:03:15.548Z] 00:03:15  WARNING -  INFO | Failed: 1
[task 2022-02-05T00:03:15.548Z] 00:03:15  WARNING -  One or more unittests failed.
[task 2022-02-05T00:03:15.548Z] 00:03:15     INFO -  INFO | Todo: 4
[task 2022-02-05T00:03:15.549Z] 00:03:15     INFO -  INFO | Retried: 2
[task 2022-02-05T00:03:15.549Z] 00:03:15     INFO -  SUITE-END | took 270s
[task 2022-02-05T00:03:15.549Z] 00:03:15     INFO -  Node moz-http2 server shutting down ...
[task 2022-02-05T00:03:15.550Z] 00:03:15     INFO -  http3Server server shutting down ...
[task 2022-02-05T00:03:15.780Z] 00:03:15    ERROR - Return code: 1
[task 2022-02-05T00:03:15.780Z] 00:03:15     INFO - TinderboxPrint: xpcshell-xpcshell<br/>1665/<em class="testfail">1</em>/4
[task 2022-02-05T00:03:15.780Z] 00:03:15  WARNING - # TBPL FAILURE #
[task 2022-02-05T00:03:15.780Z] 00:03:15  WARNING - setting return code to 2
[task 2022-02-05T00:03:15.780Z] 00:03:15  WARNING - The xpcshell suite: xpcshell ran with return status: FAILURE
[task 2022-02-05T00:03:15.780Z] 00:03:15     INFO - Running post-action listener: _package_coverage_data
[task 2022-02-05T00:03:15.780Z] 00:03:15     INFO - Running post-action listener: _resource_record_post_action
[task 2022-02-05T00:03:15.780Z] 00:03:15     INFO - Running post-action listener: process_java_coverage_data
[task 2022-02-05T00:03:15.780Z] 00:03:15     INFO - [mozharness: 2022-02-05 00:03:15.780881Z] Finished run-tests step (success)
[task 2022-02-05T00:03:15.781Z] 00:03:15     INFO - Running post-run listener: _resource_record_post_run
[task 2022-02-05T00:03:15.859Z] 00:03:15     INFO - instance_metadata.json not found; unable to determine instance type
[task 2022-02-05T00:03:15.862Z] 00:03:15     INFO - Validating Perfherder data against /opt/worker/tasks/task_164401895204175/mozharness/external_tools/performance-artifact-schema.json
[task 2022-02-05T00:03:15.864Z] 00:03:15     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "xpcshell.xpcshell.overall", "extraOptions": ["e10s", "buildbot-unknown"], "subtests": [{"name": "cpu_percent", "value": 32.01062242798354}, {"name": "io_write_bytes", "value": 9589653504}, {"name": "io.read_bytes", "value": 152166400}, {"name": "io_write_time", "value": 21981}, {"name": "io_read_time", "value": 4781}]}, {"name": "xpcshell.xpcshell.start-pulseaudio", "subtests": [{"name": "time", "value": 0.00045490264892578125}]}, {"name": "xpcshell.xpcshell.install", "subtests": [{"name": "time", "value": 32.13335585594177}, {"name": "cpu_percent", "value": 14.095000000000002}]}, {"name": "xpcshell.xpcshell.stage-files", "subtests": [{"name": "time", "value": 0.025403976440429688}]}, {"name": "xpcshell.xpcshell.run-tests", "subtests": [{"name": "time", "value": 312.3785021305084}, {"name": "cpu_percent", "value": 33.908077360637094}]}]}
[task 2022-02-05T00:03:15.864Z] 00:03:15     INFO - Total resource usage - Wall time: 344s; CPU: 34%; Read bytes: 152166400; Write bytes: 9589653504; Read time: 4781; Write time: 21981
[task 2022-02-05T00:03:15.864Z] 00:03:15     INFO - TinderboxPrint: CPU usage<br/>33.9%
[task 2022-02-05T00:03:15.864Z] 00:03:15     INFO - TinderboxPrint: I/O read bytes / time<br/>152,166,400 / 4,781
[task 2022-02-05T00:03:15.865Z] 00:03:15     INFO - TinderboxPrint: I/O write bytes / time<br/>9,589,653,504 / 21,981
[task 2022-02-05T00:03:15.865Z] 00:03:15     INFO - TinderboxPrint: CPU idle<br/>1,829.0 (44.3%)
[task 2022-02-05T00:03:15.865Z] 00:03:15     INFO - TinderboxPrint: CPU system<br/>556.9 (13.5%)
[task 2022-02-05T00:03:15.865Z] 00:03:15     INFO - TinderboxPrint: CPU user<br/>1,742.4 (42.2%)
[task 2022-02-05T00:03:15.865Z] 00:03:15     INFO - TinderboxPrint: Swap in / out<br/>681,791,488 / 0
[task 2022-02-05T00:03:15.865Z] 00:03:15     INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2022-02-05T00:03:15.866Z] 00:03:15     INFO - install - Wall time: 32s; CPU: 14%; Read bytes: 482932224; Write bytes: 413655040; Read time: 29030; Write time: 399
[task 2022-02-05T00:03:15.866Z] 00:03:15     INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2022-02-05T00:03:15.869Z] 00:03:15     INFO - run-tests - Wall time: 312s; CPU: 34%; Read bytes: 82161664; Write bytes: 9140400128; Read time: 3674; Write time: 21548
[task 2022-02-05T00:03:15.949Z] 00:03:15  WARNING - returning nonzero exit status 2
[taskcluster 2022-02-05T00:03:15.978Z]    Exit Code: 2
[taskcluster 2022-02-05T00:03:15.978Z]    User Time: 18m54.992426s
[taskcluster 2022-02-05T00:03:15.978Z]  Kernel Time: 6m26.092331s
[taskcluster 2022-02-05T00:03:15.978Z]    Wall Time: 7m10.365739s
[taskcluster 2022-02-05T00:03:15.978Z]       Result: FAILED
[taskcluster 2022-02-05T00:03:15.978Z] === Task Finished ===
[taskcluster 2022-02-05T00:03:15.978Z] Task Duration: 7m10.370415s
[taskcluster 2022-02-05T00:03:16.166Z] Uploading artifact public/logs/localconfig.json from file logs/localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2023-02-04T21:45:50.213Z
[taskcluster 2022-02-05T00:03:16.453Z] Uploading artifact public/test_info/4390B811-6842-47EE-B06D-9F2452A0EDB4.dmp from file build/blobber_upload_dir/4390B811-6842-47EE-B06D-9F2452A0EDB4.dmp with content encoding "gzip", mime type "application/vnd.tcpdump.pcap" and expiry 2023-02-04T21:45:50.213Z
[taskcluster 2022-02-05T00:03:16.626Z] Uploading artifact public/test_info/4390B811-6842-47EE-B06D-9F2452A0EDB4.extra from file build/blobber_upload_dir/4390B811-6842-47EE-B06D-9F2452A0EDB4.extra with content encoding "gzip", mime type "application/octet-stream" and expiry 2023-02-04T21:45:50.213Z
[taskcluster 2022-02-05T00:03:16.727Z] Uploading artifact public/test_info/resource-usage.json from file build/blobber_upload_dir/resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2023-02-04T21:45:50.213Z
[taskcluster 2022-02-05T00:03:16.924Z] Uploading artifact public/test_info/system-info.log from file build/blobber_upload_dir/system-info.log with content encoding "gzip", mime type "text/plain" and expiry 2023-02-04T21:45:50.213Z
[taskcluster 2022-02-05T00:03:17.145Z] Uploading artifact public/test_info/xpcshell_errorsummary.log from file build/blobber_upload_dir/xpcshell_errorsummary.log with content encoding "gzip", mime type "text/plain" and expiry 2023-02-04T21:45:50.213Z
[taskcluster 2022-02-05T00:03:17.252Z] Uploading artifact public/test_info/xpcshell_raw.log from file build/blobber_upload_dir/xpcshell_raw.log with content encoding "gzip", mime type "text/plain" and expiry 2023-02-04T21:45:50.213Z
[taskcluster 2022-02-05T00:03:17.404Z] Uploading redirect artifact public/logs/live.log to URL https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/AsZGW4ncR8aHQRs6CtTJeg/runs/0/artifacts/public%2Flogs%2Flive_backing.log with mime type "text/plain; charset=utf-8" and expiry 2023-02-04T21:45:50.213Z
[taskcluster:error] exit status 2
Assignee: nobody → ctuns
Status: NEW → ASSIGNED

Fallout from bug 1714621 from the looks of it. I think we can hold off on disabling these tests on mozilla-release for the time-being in favor of just landing whatever certificate bump got missed previously.

Keywords: leave-open
Assignee: ctuns → nobody
Status: ASSIGNED → NEW

(In reply to Ryan VanderMeulen [:RyanVM] from comment #3)

Fallout from bug 1714621 from the looks of it. I think we can hold off on disabling these tests on mozilla-release for the time-being in favor of just landing whatever certificate bump got missed previously.

Flags: needinfo?(dkeeler)
Flags: needinfo?(bvandyk)
Keywords: leave-open
Assignee: nobody → jcristau
Status: NEW → ASSIGNED
Pushed by jcristau@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/882d3e3619dc
renew content-signing test certificates.  r=Gijs
Status: ASSIGNED → RESOLVED
Closed: 5 months ago
Resolution: --- → FIXED
Target Milestone: --- → 99 Branch
You need to log in before you can comment on or make changes to this bug.