1721310 - Intermittent TEST-UNEXPECTED-TIMEOUT | /html/cross-origin-opener-policy/popup-coop-by-sw-from-coop.https.html?x-x | TestRunner hit external timeout (this may indicate a hang)

Status: RESOLVED FIXED

(Core :: DOM: Core & HTML, defect)

Description

[Treeherder Bug Filer]

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

---

[task 2021-07-20T02:12:00.959Z] 02:12:00     INFO - TEST-START | /html/cross-origin-opener-policy/popup-coop-by-sw-from-coop.https.html?4-last
[task 2021-07-20T02:12:00.965Z] 02:12:00     INFO - Closing window 90e65707-3621-4d4e-bf5d-77e0a2ea61de
[task 2021-07-20T02:15:15.961Z] 02:15:15     INFO - Got timeout in harness
[task 2021-07-20T02:15:16.075Z] 02:15:16     INFO - TEST-UNEXPECTED-TIMEOUT | /html/cross-origin-opener-policy/popup-coop-by-sw-from-coop.https.html?4-last | TestRunner hit external timeout (this may indicate a hang)
[task 2021-07-20T02:15:16.075Z] 02:15:16     INFO - TEST-INFO took 195116ms
[task 2021-07-20T02:15:16.481Z] 02:15:16     INFO - STDOUT: cleanup aborted: Unable to remount device
[task 2021-07-20T02:15:16.605Z] 02:15:16     INFO - STDOUT: cleanup aborted: Unable to remount device
[task 2021-07-20T02:15:26.615Z] 02:15:26  WARNING - Forcibly terminating runner process
[task 2021-07-20T02:15:26.620Z] 02:15:26 CRITICAL - Test harness output was not a valid structured log message: 
[task 2021-07-20T02:15:26.620Z] 02:15:26 CRITICAL - /usr/lib/python3.6/multiprocessing/semaphore_tracker.py:143: UserWarning: semaphore_tracker: There appear to be 3 leaked semaphores to clean up at shutdown
[task 2021-07-20T02:15:26.621Z] 02:15:26 CRITICAL -   len(cache))
[task 2021-07-20T02:15:26.637Z] 02:15:26     INFO - Setting up ssl
[task 2021-07-20T02:15:26.657Z] 02:15:26     INFO - certutil | b''
[task 2021-07-20T02:15:26.684Z] 02:15:26     INFO - certutil | b''
[task 2021-07-20T02:15:26.711Z] 02:15:26     INFO - certutil | b'\nCertificate Nickname                                         Trust Attributes\n                                                             SSL,S/MIME,JAR/XPI\n\nweb-platform-tests                                           CT,, \n'
[task 2021-07-20T02:15:27.722Z] 02:15:27     INFO - adb Granting important runtime permissions to org.mozilla.geckoview.test
[task 2021-07-20T02:15:29.334Z] 02:15:29     INFO - adb launch_application: am start -W -n org.mozilla.geckoview.test/org.mozilla.geckoview.test.TestRunnerActivity -a android.intent.action.MAIN --es env0 MOZ_CRASHREPORTER=1 --es env1 MOZ_CRASHREPORTER_NO_REPORT=1 --es env2 MOZ_CRASHREPORTER_SHUTDOWN=1 --es env3 MOZ_HIDE_RESULTS_TABLE=1 --es env4 MOZ_IN_AUTOMATION=1 --es env5 MOZ_LOG=signaling:3,mtransport:4,DataChannel:4,jsep:4 --es env6 R_LOG_LEVEL=6 --es env7 R_LOG_DESTINATION=stderr --es env8 R_LOG_VERBOSE=1 --es env9 MOZ_PROCESS_LOG=/tmp/tmpim721zuopidlog --es env10 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env11 STYLO_THREADS=1 --es env12 MOZ_WEBRENDER=1 --es arg0 -no-remote --es arg1 -profile --es arg2 /data/local/tmp/test_root/profile --es arg3 --marionette --es arg4 about:blank --ez use_multiprocess True
[task 2021-07-20T02:15:30.432Z] 02:15:30     INFO - Starting runner

Comment 5

[Jens Stutte [:jstutte]]

So this is the output of a successful run:

[task 2021-07-22T09:50:39.478Z] 09:50:39     INFO - TEST-START | /html/cross-origin-opener-policy/iframe-popup-same-origin-to-unsafe-none.https.html
[task 2021-07-22T09:50:39.486Z] 09:50:39     INFO - Closing window 3e6b7075-2f3b-4036-bc97-88360b5a7cfd
[task 2021-07-22T09:50:52.097Z] 09:50:52     INFO - TEST-OK | /html/cross-origin-opener-policy/iframe-popup-same-origin-to-unsafe-none.https.html | took 12620ms

And this is the output of the failure:

[task 2021-07-20T02:07:35.304Z] 02:07:35     INFO - TEST-START | /html/cross-origin-opener-policy/iframe-popup-same-origin-to-unsafe-none.https.html
[task 2021-07-20T02:07:35.316Z] 02:07:35     INFO - Closing window 0e31fc61-6d00-4642-8a37-1cbd0ff74628
[task 2021-07-20T02:10:50.305Z] 02:10:50     INFO - Got timeout in harness

There is no clue in this log on what might have gone wrong. We might want to modify run_coop_test_iframe to be more verbose in order to see if there is a pattern?

Comment 6

[Anne (:annevk)]

I created https://github.com/web-platform-tests/wpt/pull/29746 which mainly will turn this into a test timeout I think (but should also do less aggressive polling on slower machines). From the log output it seems like it takes too long to update openee.closed and therefore the test does not complete which results in a test harness timeout.

Comment 7

[Intermittent Failures Robot]

32 failures in 2940 pushes (0.011 failures/push) were associated with this bug in the last 7 days.

This is the #34 most frequent failure this week.

** 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:

• autoland: 30
• mozilla-central: 2

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 13
  • debug: 8
  • opt: 5
• android-em-7-0-x86_64-lite: 5
  • debug: 3
  • opt: 2
• android-em-7-0-x86_64: 3
  • debug: 3
• android-em-7-0-x86_64-lite-qr: 10
  • opt: 8
  • debug: 2
• android-em-7-0-x86_64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1721310&startday=2021-07-19&endday=2021-07-25&tree=all

Comment 8

[Intermittent Failures Robot]

26 failures in 3131 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

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

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 12
  • opt: 4
  • debug: 8
• android-em-7-0-x86_64-lite-qr: 11
  • debug: 7
  • opt: 4
• android-em-7-0-x86_64-shippable-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1721310&startday=2021-07-26&endday=2021-08-01&tree=all

Comment 9

[Hsin-Yi Tsai (she/her) [:hsinyi]]

The test was rewritten in bug 1722505. The file popup-coop-by-sw-from-coop.https.html no longer exists. I think we can close this bug and track the new failure patter (if any) in a new bug.