1935637 - Perma TEST-UNEXPECTED-TIMEOUT | extensions/permissions/test/unit/test_permmanager_remote.js | Test timed out - when Gecko 135 switches to beta on 2025-01-17

Status: VERIFIED FIXED

(Core :: Permission Manager, defect)

Description

[amarc]

Central-as-beta-simulation

How to run these simulations

Failure log
*Failure snippet:

[task 2024-12-06T11:18:53.626Z] 11:18:53     INFO -  TEST-PASS | uriloader/exthandler/tests/unit/test_punycodeURIs.js | took 1347ms
[task 2024-12-06T11:18:53.640Z] 11:18:53     INFO -  Retrying tests that failed when run in parallel.
[task 2024-12-06T11:18:53.642Z] 11:18:53     INFO -  TEST-START | extensions/permissions/test/unit/test_permmanager_remote.js
[task 2024-12-06T11:23:53.646Z] 11:23:53  WARNING -  TEST-UNEXPECTED-TIMEOUT | extensions/permissions/test/unit/test_permmanager_remote.js | Test timed out
[task 2024-12-06T11:23:53.647Z] 11:23:53     INFO -  TEST-INFO took 300005ms
[task 2024-12-06T11:23:53.647Z] 11:23:53     INFO -  >>>>>>>
[task 2024-12-06T11:23:53.648Z] 11:23:53     INFO -  PID 7600 | DLL blocklist was unable to intercept AppInit DLLs.
[task 2024-12-06T11:23:53.648Z] 11:23:53     INFO -  PID 7600 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2024-12-06T11:23:53.648Z] 11:23:53     INFO -  PID 7600 | [Parent 7600, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:148
[task 2024-12-06T11:23:53.649Z] 11:23:53     INFO -  PID 7600 | [Parent 7600, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:148
[task 2024-12-06T11:23:53.649Z] 11:23:53     INFO -  PID 7600 | [Parent 7600, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:2903
[task 2024-12-06T11:23:53.650Z] 11:23:53     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2024-12-06T11:23:53.650Z] 11:23:53     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2024-12-06T11:23:53.650Z] 11:23:53     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2024-12-06T11:23:53.650Z] 11:23:53     INFO -  running event loop
[task 2024-12-06T11:23:53.651Z] 11:23:53     INFO -  PID 7600 | [Parent 7600, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:467
[task 2024-12-06T11:23:53.651Z] 11:23:53     INFO -  extensions/permissions/test/unit/test_permmanager_remote.js | Starting setup
[task 2024-12-06T11:23:53.651Z] 11:23:53     INFO -  (xpcshell/head.js) | test pending (2)
[task 2024-12-06T11:23:53.651Z] 11:23:53     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2024-12-06T11:23:53.651Z] 11:23:53     INFO -  <<<<<<<
[task 2024-12-06T11:23:53.725Z] 11:23:53     INFO -  xpcshell return code: 1
[task 2024-12-06T11:23:53.741Z] 11:23:53     INFO -  TEST-START | dom/streams/test/xpcshell/large-pipeto.js
[task 2024-12-06T11:23:53.742Z] 11:23:53     INFO -  TEST-SKIP | dom/streams/test/xpcshell/large-pipeto.js | took 0ms
[task 2024-12-06T11:23:53.742Z] 11:23:53     INFO -  INFO | Result summary:
[task 2024-12-06T11:23:53.742Z] 11:23:53     INFO -  INFO | Passed: 661
[task 2024-12-06T11:23:53.742Z] 11:23:53  WARNING -  INFO | Failed: 1
[task 2024-12-06T11:23:53.742Z] 11:23:53  WARNING -  One or more unittests failed.
[task 2024-12-06T11:23:53.743Z] 11:23:53     INFO -  INFO | Todo: 0
[task 2024-12-06T11:23:53.743Z] 11:23:53     INFO -  INFO | Retried: 2
[task 2024-12-06T11:23:53.743Z] 11:23:53     INFO -  SUITE-END | took 1028s
[task 2024-12-06T11:23:53.743Z] 11:23:53     INFO -  Node moz-http2 server shutting down ...
[task 2024-12-06T11:23:53.743Z] 11:23:53     INFO -  http3Server server shutting down ...
[task 2024-12-06T11:23:53.890Z] 11:23:53     INFO - Return code: 1
[task 2024-12-06T11:23:53.894Z] 11:23:53     INFO - TinderboxPrint: xpcshell-xpcshell<br/>661/<em class="testfail">1</em>/0
[task 2024-12-06T11:23:53.894Z] 11:23:53  WARNING - setting return code to 2
[task 2024-12-06T11:23:53.894Z] 11:23:53     INFO - The xpcshell suite: xpcshell ran with return status: FAILURE
[task 2024-12-06T11:23:53.894Z] 11:23:53     INFO - Running post-action listener: _package_coverage_data
[task 2024-12-06T11:23:53.894Z] 11:23:53     INFO - Running post-action listener: _resource_record_post_action
[task 2024-12-06T11:23:53.894Z] 11:23:53     INFO - Running post-action listener: process_java_coverage_data
[task 2024-12-06T11:23:53.894Z] 11:23:53     INFO - [mozharness: 2024-12-06 11:23:53.894774Z] Finished run-tests step (success)
[task 2024-12-06T11:23:53.894Z] 11:23:53     INFO - [mozharness: 2024-12-06 11:23:53.894774Z] Running uninstall step.
[task 2024-12-06T11:23:53.894Z] 11:23:53     INFO - Running pre-action listener: _resource_record_pre_action
[task 2024-12-06T11:23:53.894Z] 11:23:53     INFO - Running main action method: uninstall
[task 2024-12-06T11:23:53.894Z] 11:23:53     INFO - Skipping uninstall for non-MSIX test
[task 2024-12-06T11:23:53.894Z] 11:23:53     INFO - Running post-action listener: _resource_record_post_action
[task 2024-12-06T11:23:53.894Z] 11:23:53     INFO - [mozharness: 2024-12-06 11:23:53.894774Z] Finished uninstall step (success)
[task 2024-12-06T11:23:53.894Z] 11:23:53     INFO - Running post-run listener: _resource_record_post_run
[task 2024-12-06T11:23:54.646Z] 11:23:54     INFO - Validating Perfherder data against D:\task_173348268860500\mozharness\external_tools\performance-artifact-schema.json
[task 2024-12-06T11:23:54.662Z] 11:23:54     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "xpcshell.xpcshell.4.overall", "extraOptions": ["e10s", "taskcluster-Standard_F8s_v2"], "subtests": [{"name": "cpu_percent", "value": 54.09922369507388}, {"name": "io_write_bytes", "value": 3013733888}, {"name": "io.read_bytes", "value": 516913152}, {"name": "io_write_time", "value": 8}, {"name": "io_read_time", "value": 11}]}, {"name": "xpcshell.xpcshell.4.start-pulseaudio", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}, {"name": "xpcshell.xpcshell.4.install", "subtests": [{"name": "time", "value": 3.843999999999994}, {"name": "cpu_percent", "value": 50.40568181818182}]}, {"name": "xpcshell.xpcshell.4.stage-files", "subtests": [{"name": "time", "value": 0.016000000000019554}, {"name": "cpu_percent", "value": 0}]}, {"name": "xpcshell.xpcshell.4.run-tests", "subtests": [{"name": "time", "value": 1086.969}, {"name": "cpu_percent", "value": 54.11024316738755}]}, {"name": "xpcshell.xpcshell.4.uninstall", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}]}
[task 2024-12-06T11:23:54.662Z] 11:23:54     INFO - Total resource usage - Wall time: 1091s; CPU: Can't collect data; Read bytes: 516913152; Write bytes: 3013733888; Read time: 11; Write time: 8
[task 2024-12-06T11:23:54.663Z] 11:23:54     INFO - TinderboxPrint: I/O read bytes / time<br/>516,913,152 / 11
[task 2024-12-06T11:23:54.663Z] 11:23:54     INFO - TinderboxPrint: I/O write bytes / time<br/>3,013,733,888 / 8
[task 2024-12-06T11:23:54.663Z] 11:23:54     INFO - TinderboxPrint: CPU idle<br/>4,004.4 (45.9%)
[task 2024-12-06T11:23:54.663Z] 11:23:54     INFO - TinderboxPrint: CPU system<br/>1,260.5 (14.4%)
[task 2024-12-06T11:23:54.663Z] 11:23:54     INFO - TinderboxPrint: CPU user<br/>3,457.3 (39.6%)
[task 2024-12-06T11:23:54.666Z] 11:23:54     INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-12-06T11:23:54.668Z] 11:23:54     INFO - install - Wall time: 4s; CPU: 50%; Read bytes: 122880; Write bytes: 77611008; Read time: 0; Write time: 0
[task 2024-12-06T11:23:54.671Z] 11:23:54     INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-12-06T11:23:54.729Z] 11:23:54     INFO - run-tests - Wall time: 1087s; CPU: 54%; Read bytes: 516790272; Write bytes: 2936122880; Read time: 11; Write time: 8
[task 2024-12-06T11:23:54.744Z] 11:23:54     INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-12-06T11:23:58.694Z] 11:23:58  WARNING - returning nonzero exit status 2
[taskcluster 2024-12-06T11:23:58.850Z]    Exit Code: 2
[taskcluster 2024-12-06T11:23:58.850Z]    User Time: 15.625ms
[taskcluster 2024-12-06T11:23:58.850Z]  Kernel Time: 31.25ms
[taskcluster 2024-12-06T11:23:58.850Z]    Wall Time: 20m16.8824981s
[taskcluster 2024-12-06T11:23:58.850Z]       Result: FAILED
[taskcluster 2024-12-06T11:23:58.850Z] === Task Finished ===
[taskcluster 2024-12-06T11:23:58.850Z] Task Duration: 20m16.8873129s
[taskcluster 2024-12-06T11:23:59.617Z] Uploading artifact public/logs/localconfig.json from file D:\task_173348268860500\logs\localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2025-01-03T10:01:50.699Z
[taskcluster 2024-12-06T11:23:59.618Z] Uploading artifact public/test_info/system-info.log from file D:\task_173348268860500\build\blobber_upload_dir\system-info.log with content encoding "gzip", mime type "text/plain" and expiry 2025-01-03T10:01:50.699Z
[taskcluster 2024-12-06T11:23:59.623Z] Uploading artifact public/test_info/resource-usage.json from file D:\task_173348268860500\build\blobber_upload_dir\resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2025-01-03T10:01:50.699Z
[taskcluster 2024-12-06T11:23:59.624Z] Uploading artifact public/test_info/6b461f4c-50ad-4288-b0d5-af8f934e771d.dmp from file D:\task_173348268860500\build\blobber_upload_dir\6b461f4c-50ad-4288-b0d5-af8f934e771d.dmp with content encoding "gzip", mime type "application/octet-stream" and expiry 2025-01-03T10:01:50.699Z
[taskcluster 2024-12-06T11:23:59.626Z] Uploading artifact public/test_info/xpcshell_errorsummary.log from file D:\task_173348268860500\build\blobber_upload_dir\xpcshell_errorsummary.log with content encoding "gzip", mime type "text/plain" and expiry 2025-01-03T10:01:50.699Z
[taskcluster 2024-12-06T11:23:59.628Z] Uploading artifact public/test_info/profile_resource-usage.json from file D:\task_173348268860500\build\blobber_upload_dir\profile_resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2025-01-03T10:01:50.699Z
[taskcluster 2024-12-06T11:23:59.629Z] Uploading artifact public/test_info/6b461f4c-50ad-4288-b0d5-af8f934e771d.extra from file D:\task_173348268860500\build\blobber_upload_dir\6b461f4c-50ad-4288-b0d5-af8f934e771d.extra with content encoding "gzip", mime type "application/octet-stream" and expiry 2025-01-03T10:01:50.699Z
[taskcluster 2024-12-06T11:24:00.175Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2025-01-03T10:01:50.699Z
[taskcluster:error] exit status 2

Comment 1

[Malte Jürgens [:maltejur]]

Attachment: Bug 1935637 - Force-enable remote permission service in test_permmanager_remote.js r?freddyb!

Otherwise this test will fail in beta and release, as the remote permission
service is still preffed off there.

Comment 2

[Intermittent Failures Robot]

33 failures in 774 pushes (0.043 failures/push) were associated with this bug yesterday.

Repository breakdown:

• comm-central: 9
• try: 24

Platform and build breakdown:

• linux1804-64-qr: 3
  • opt: 1
  • debug: 2
• linux1804-64-shippable-qr: 2
  • opt: 2
• macosx1015-64-qr: 2
  • opt: 1
  • debug: 1
• windows11-64-2009-shippable-qr: 4
  • opt: 4
• windows11-32-2009-shippable-qr: 2
  • opt: 2
• macosx1015-64-shippable-qr: 1
  • opt: 1
• windows10-64-2009-qr: 4
  • debug: 4
• linux1804-64-asan-qr: 1
  • opt: 1
• linux1804-64-tsan-qr: 1
  • opt: 1
• android-em-7-0-x86_64-shippable-lite-qr: 4
  • opt: 4
• android-em-7-0-x86_64-shippable-qr: 3
  • opt: 3
• windows11-64-2009-devedition-qr: 1
  • opt: 1
• windows11-64-2009-qr: 2
  • debug: 2
• windows11-32-2009-qr: 1
  • debug: 1
• linux1804-64-devedition-qr: 1
  • opt: 1
• windows10-64-2009-shippable-qr: 1
  • opt: 1

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

Comment 3

[Narcis Beleuzu [:NarcisB]]

.

Comment 4

[Sandor Molnar[:smolnar]]

(In reply to Malte Jürgens [:maltejur] from comment #1)

Created attachment 9442202 [details]
Bug 1935637 - Force-enable remote permission service in test_permmanager_remote.js r?freddyb!

Otherwise this test will fail in beta and release, as the remote permission
service is still preffed off there.

As you stated, with the patch imported, it fails on beta-sim.
Here's a failure log -> https://treeherder.mozilla.org/logviewer?job_id=485925224&repo=try&lineNumber=5852

Comment 5

[Intermittent Failures Robot]

34 failures in 4722 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• comm-central: 9
• try: 25

Platform and build breakdown:

• linux1804-64-qr: 3
  • opt: 1
  • debug: 2
• linux1804-64-shippable-qr: 2
  • opt: 2
• macosx1015-64-qr: 2
  • opt: 1
  • debug: 1
• windows11-64-2009-shippable-qr: 4
  • opt: 4
• windows11-32-2009-shippable-qr: 2
  • opt: 2
• macosx1015-64-shippable-qr: 1
  • opt: 1
• windows10-64-2009-qr: 4
  • debug: 4
• linux1804-64-asan-qr: 1
  • opt: 1
• linux1804-64-tsan-qr: 1
  • opt: 1
• android-em-7-0-x86_64-shippable-lite-qr: 4
  • opt: 4
• android-em-7-0-x86_64-shippable-qr: 3
  • opt: 3
• windows11-64-2009-devedition-qr: 1
  • opt: 1
• windows11-64-2009-qr: 2
  • debug: 2
• windows11-32-2009-qr: 1
  • debug: 1
• linux1804-64-devedition-qr: 1
  • opt: 1
• windows10-64-2009-shippable-qr: 1
  • opt: 1
• macosx1015-64-devedition-qr: 1
  • opt: 1

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

Comment 6

[Malte Jürgens [:maltejur]]

I fixed the attached patch, it should resolve the failure now.

https://treeherder.mozilla.org/jobs?repo=try&revision=89552694ed2fd19e9d1a4d5533c32644ff688297

Comment 7

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/608dd3d5933d
Force-enable remote permission service in test_permmanager_remote.js r=permissions-reviewers,pbz

Comment 8

[Serban Stanca [:SerbanS]]

https://hg.mozilla.org/mozilla-central/rev/608dd3d5933d

Comment 9

[Norisz Fay [:noriszfay]]

Verified fixed in today's central as beta sim