Closed Bug 1776700 Opened 3 years ago Closed 3 years ago

Intermittent toolkit/components/extensions/test/xpcshell/test_ext_alarms_does_not_fire.js | single tracking bug

Categories

(WebExtensions :: General, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

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


[task 2022-06-27T14:20:25.541Z] 14:20:25     INFO -  TEST-START | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_alarms_does_not_fire.js
[task 2022-06-27T14:20:29.656Z] 14:20:29  WARNING -  TEST-UNEXPECTED-FAIL | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_alarms_does_not_fire.js | xpcshell return code: -5
[task 2022-06-27T14:20:29.657Z] 14:20:29     INFO -  TEST-INFO took 4111ms
[task 2022-06-27T14:20:29.658Z] 14:20:29     INFO -  >>>>>>>
[task 2022-06-27T14:20:29.659Z] 14:20:29     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2022-06-27T14:20:29.660Z] 14:20:29     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2022-06-27T14:20:29.661Z] 14:20:29     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2022-06-27T14:20:29.663Z] 14:20:29     INFO -  running event loop
[task 2022-06-27T14:20:29.664Z] 14:20:29     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_alarms_does_not_fire.js | Starting setup check_remote
[task 2022-06-27T14:20:29.665Z] 14:20:29     INFO -  (xpcshell/head.js) | test check_remote pending (2)
[task 2022-06-27T14:20:29.666Z] 14:20:29     INFO -  TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_alarms_does_not_fire.js | check_remote - [check_remote : 1] useRemoteWebExtensions matches - true == true
[task 2022-06-27T14:20:29.667Z] 14:20:29     INFO -  TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_alarms_does_not_fire.js | check_remote - [check_remote : 1] testing from extension process - false == false
[task 2022-06-27T14:20:29.668Z] 14:20:29     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2022-06-27T14:20:29.669Z] 14:20:29     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2022-06-27T14:20:29.670Z] 14:20:29     INFO -  (xpcshell/head.js) | test check_remote finished (2)
[task 2022-06-27T14:20:29.671Z] 14:20:29     INFO -  xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_alarms_does_not_fire.js | Starting test_cleared_alarm_does_not_fire
[task 2022-06-27T14:20:29.672Z] 14:20:29     INFO -  (xpcshell/head.js) | test test_cleared_alarm_does_not_fire pending (2)
[task 2022-06-27T14:20:29.674Z] 14:20:29     INFO -  "Extension attached"
[task 2022-06-27T14:20:29.675Z] 14:20:29     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2022-06-27T14:20:29.676Z] 14:20:29     INFO -  "CONSOLE_MESSAGE: (info) 1656339627289	addons.webextension.{b042f6b5-b0f1-4ab1-acb9-1979650eaaf9}	WARN	Loading extension '{b042f6b5-b0f1-4ab1-acb9-1979650eaaf9}': Event pages are not currently supported."
[task 2022-06-27T14:20:29.680Z] 14:20:29     INFO -  PID 17511 | (xpcshell:17511): Gdk-ERROR **: 14:20:28.624: The program 'xpcshell' received an X Window System error.
[task 2022-06-27T14:20:29.681Z] 14:20:29     INFO -  PID 17511 | This probably reflects a bug in the program.
[task 2022-06-27T14:20:29.682Z] 14:20:29     INFO -  PID 17511 | The error was 'BadWindow (invalid Window parameter)'.
[task 2022-06-27T14:20:29.683Z] 14:20:29     INFO -  PID 17511 |   (Details: serial 327 error_code 3 request_code 18 (core protocol) minor_code 0)
[task 2022-06-27T14:20:29.684Z] 14:20:29     INFO -  PID 17511 |   (Note to programmers: normally, X errors are reported asynchronously;
[task 2022-06-27T14:20:29.685Z] 14:20:29     INFO -  PID 17511 |    that is, you will receive the error a while after causing it.
[task 2022-06-27T14:20:29.686Z] 14:20:29     INFO -  PID 17511 |    To debug your program, run it with the GDK_SYNCHRONIZE environment
[task 2022-06-27T14:20:29.687Z] 14:20:29     INFO -  PID 17511 |    variable to change this behavior. You can then get a meaningful
[task 2022-06-27T14:20:29.688Z] 14:20:29     INFO -  PID 17511 |    backtrace from your debugger if you break on the gdk_x_error() function.)
[task 2022-06-27T14:20:29.689Z] 14:20:29     INFO -  PID 17511 | Exiting due to channel error.
[task 2022-06-27T14:20:29.691Z] 14:20:29     INFO -  PID 17511 | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=1.14219)
[task 2022-06-27T14:20:29.692Z] 14:20:29     INFO -  <<<<<<<
[task 2022-06-27T14:20:29.693Z] 14:20:29     INFO -  TEST-START | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_alarms_periodic.js
[task 2022-06-27T14:20:40.515Z] 14:20:40     INFO -  TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_alarms_periodic.js | took 10844ms
[task 2022-06-27T14:20:40.524Z] 14:20:40     INFO -  TEST-START | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_alarms_replaces.js
[task 2022-06-27T14:20:49.668Z] 14:20:49     INFO -  TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_alarms_replaces.js | took 9144ms
[task 2022-06-27T14:20:49.679Z] 14:20:49     INFO -  TEST-START | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_runtime_getBackgroundPage.js
[task 2022-06-27T14:20:59.522Z] 14:20:59     INFO -  TEST-PASS | xpcshell.ini:toolkit/components/extensions/test/xpcshell/test_ext_runtime_getBackgroundPage.js | took 9843ms
[task 2022-06-27T14:20:59.528Z] 14:20:59     INFO -  INFO | Result summary:
[task 2022-06-27T14:20:59.529Z] 14:20:59     INFO -  INFO | Passed: 411
[task 2022-06-27T14:20:59.529Z] 14:20:59  WARNING -  INFO | Failed: 1
[task 2022-06-27T14:20:59.529Z] 14:20:59  WARNING -  One or more unittests failed.
[task 2022-06-27T14:20:59.529Z] 14:20:59     INFO -  INFO | Todo: 0
[task 2022-06-27T14:20:59.530Z] 14:20:59     INFO -  INFO | Retried: 0
[task 2022-06-27T14:20:59.530Z] 14:20:59     INFO -  SUITE-END | took 938s
[task 2022-06-27T14:20:59.530Z] 14:20:59     INFO -  Node moz-http2 server shutting down ...
[task 2022-06-27T14:20:59.531Z] 14:20:59     INFO -  http3Server server shutting down ...
[task 2022-06-27T14:20:59.673Z] 14:20:59    ERROR - Return code: 1
[task 2022-06-27T14:20:59.674Z] 14:20:59     INFO - TinderboxPrint: xpcshell-xpcshell<br/>411/<em class="testfail">1</em>/0
[task 2022-06-27T14:20:59.674Z] 14:20:59  WARNING - # TBPL FAILURE #
[task 2022-06-27T14:20:59.674Z] 14:20:59  WARNING - setting return code to 2
[task 2022-06-27T14:20:59.674Z] 14:20:59  WARNING - The xpcshell suite: xpcshell ran with return status: FAILURE
[task 2022-06-27T14:20:59.674Z] 14:20:59     INFO - Running post-action listener: _package_coverage_data
[task 2022-06-27T14:20:59.674Z] 14:20:59     INFO - Running post-action listener: _resource_record_post_action
[task 2022-06-27T14:20:59.674Z] 14:20:59     INFO - Running post-action listener: process_java_coverage_data
[task 2022-06-27T14:20:59.674Z] 14:20:59     INFO - [mozharness: 2022-06-27 14:20:59.674469Z] Finished run-tests step (success)
[task 2022-06-27T14:20:59.675Z] 14:20:59     INFO - Running post-run listener: _resource_record_post_run
[task 2022-06-27T14:20:59.764Z] 14:20:59     INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2022-06-27T14:20:59.769Z] 14:20:59     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "xpcshell.xpcshell.overall", "extraOptions": ["e10s", "taskcluster-projects/887720501152/machineTypes/n2-standard-2"], "subtests": [{"name": "cpu_percent", "value": 84.29625000000001}, {"name": "io_write_bytes", "value": 4351991808}, {"name": "io.read_bytes", "value": 69337088}, {"name": "io_write_time", "value": 1290388}, {"name": "io_read_time", "value": 2516}]}, {"name": "xpcshell.xpcshell.start-pulseaudio", "subtests": [{"name": "time", "value": 0.04019522666931152}]}, {"name": "xpcshell.xpcshell.install", "subtests": [{"name": "time", "value": 42.97397422790527}, {"name": "cpu_percent", "value": 50.75609756097561}]}, {"name": "xpcshell.xpcshell.stage-files", "subtests": [{"name": "time", "value": 0.06625699996948242}]}, {"name": "xpcshell.xpcshell.run-tests", "subtests": [{"name": "time", "value": 1078.9293253421783}, {"name": "cpu_percent", "value": 85.65780669144984}]}]}
[task 2022-06-27T14:20:59.769Z] 14:20:59     INFO - Total resource usage - Wall time: 1122s; CPU: 86%; Read bytes: 69337088; Write bytes: 4351991808; Read time: 2516; Write time: 1290388
[task 2022-06-27T14:20:59.770Z] 14:20:59     INFO - TinderboxPrint: CPU usage<br/>85.7%
[task 2022-06-27T14:20:59.771Z] 14:20:59     INFO - TinderboxPrint: I/O read bytes / time<br/>69,337,088 / 2,516
[task 2022-06-27T14:20:59.771Z] 14:20:59     INFO - TinderboxPrint: I/O write bytes / time<br/>4,351,991,808 / 1,290,388
[task 2022-06-27T14:20:59.772Z] 14:20:59     INFO - TinderboxPrint: CPU idle<br/>350.5 (15.6%)
[task 2022-06-27T14:20:59.773Z] 14:20:59     INFO - TinderboxPrint: CPU system<br/>172.2 (7.7%)
[task 2022-06-27T14:20:59.773Z] 14:20:59     INFO - TinderboxPrint: CPU user<br/>1,717.2 (76.6%)
[task 2022-06-27T14:20:59.774Z] 14:20:59     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2022-06-27T14:20:59.775Z] 14:20:59     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-06-27T14:20:59.775Z] 14:20:59     INFO - install - Wall time: 43s; CPU: 51%; Read bytes: 212992; Write bytes: 1781559296; Read time: 0; Write time: 1211544
[task 2022-06-27T14:20:59.776Z] 14:20:59     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-06-27T14:20:59.779Z] 14:20:59     INFO - run-tests - Wall time: 1079s; CPU: 86%; Read bytes: 69124096; Write bytes: 2570432512; Read time: 2516; Write time: 78844
[task 2022-06-27T14:20:59.911Z] 14:20:59  WARNING - returning nonzero exit status 2
[task 2022-06-27T14:20:59.944Z] cleanup
[task 2022-06-27T14:20:59.944Z] + cleanup
[task 2022-06-27T14:20:59.944Z] + local rv=2
[task 2022-06-27T14:20:59.944Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2022-06-27T14:20:59.944Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2022-06-27T14:20:59.947Z] + '[' ']'
[task 2022-06-27T14:20:59.947Z] + true
[task 2022-06-27T14:20:59.947Z] + cleanup_xvfb
[task 2022-06-27T14:20:59.949Z] ++ pidof Xvfb
[task 2022-06-27T14:20:59.958Z] + local xvfb_pid=49
[task 2022-06-27T14:20:59.961Z] + local vnc=false
[task 2022-06-27T14:20:59.962Z] + local interactive=false
[task 2022-06-27T14:20:59.962Z] + '[' -n 49 ']'
[task 2022-06-27T14:20:59.963Z] + [[ false == false ]]
[task 2022-06-27T14:20:59.964Z] + [[ false == false ]]
[task 2022-06-27T14:20:59.965Z] + kill 49
[task 2022-06-27T14:20:59.965Z] + screen -XS xvfb quit
[task 2022-06-27T14:21:00.012Z] No screen session found.
[task 2022-06-27T14:21:00.014Z] + true
[task 2022-06-27T14:21:00.015Z] + exit 2
[taskcluster 2022-06-27 14:21:00.319Z] === Task Finished ===
[taskcluster 2022-06-27 14:21:02.834Z] Unsuccessful task run with exit code: 2 completed in 1240.488 seconds
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.