1910127 - Frequent Android debug dom/serviceworkers/test/performance/test_registration.html | Test timed out. -

Status: NEW

(Core :: DOM: Service Workers, defect)

Description

[Treeherder Bug Filer]

Filed by: tszentpeteri [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=468086606&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/eBk5zFosSPew91JfWcbHVg/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/eBk5zFosSPew91JfWcbHVg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2024-07-26T13:50:04.891Z] 13:50:04     INFO -  TEST-PASS | dom/serviceworkers/test/performance/test_registration.html | undefined assertion name
[task 2024-07-26T13:50:04.891Z] 13:50:04     INFO -  add_task | Leaving
[task 2024-07-26T13:50:04.892Z] 13:50:04     INFO -  add_task | Entering
[task 2024-07-26T13:50:04.892Z] 13:50:04     INFO -  perfMetrics | {"Registration":69,"Registration Internals":4.064599999998791,"Activation":13,"Unregistration":7}
[task 2024-07-26T13:50:04.892Z] 13:50:04     INFO -  add_task | Leaving
[task 2024-07-26T13:50:04.892Z] 13:50:04     INFO -  Buffered messages finished
[task 2024-07-26T13:50:04.892Z] 13:50:04  WARNING -  TEST-UNEXPECTED-FAIL | dom/serviceworkers/test/performance/test_registration.html | Test timed out. -
[task 2024-07-26T13:50:04.892Z] 13:50:04  WARNING -  TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2024-07-26T13:50:04.892Z] 13:50:04  WARNING -  TEST-UNEXPECTED-ERROR | dom/serviceworkers/test/performance/test_registration.html | called finish() multiple times
[task 2024-07-26T13:50:04.892Z] 13:50:04     INFO -  TEST-INFO took 302156ms
[task 2024-07-26T13:50:35.105Z] 13:50:35  WARNING -  TEST-UNEXPECTED-FAIL | dom/serviceworkers/test/performance/test_registration.html | Test timed out. -
[task 2024-07-26T13:50:35.105Z] 13:50:35  WARNING -  TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2024-07-26T13:50:35.105Z] 13:50:35  WARNING -  TEST-UNEXPECTED-ERROR | dom/serviceworkers/test/performance/test_registration.html | called finish() multiple times
[task 2024-07-26T13:50:35.105Z] 13:50:35     INFO -  TEST-INFO
[task 2024-07-26T13:51:05.312Z] 13:51:05  WARNING -  TEST-UNEXPECTED-FAIL | dom/serviceworkers/test/performance/test_registration.html | Test timed out. -
[task 2024-07-26T13:51:05.312Z] 13:51:05  WARNING -  TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2024-07-26T13:51:15.372Z] 13:51:15  WARNING -  TEST-UNEXPECTED-ERROR | dom/serviceworkers/test/performance/test_registration.html | called finish() multiple times
[task 2024-07-26T13:51:15.372Z] 13:51:15     INFO -  TEST-INFO
[task 2024-07-26T13:51:35.512Z] 13:51:35  WARNING -  TEST-UNEXPECTED-FAIL | dom/serviceworkers/test/performance/test_registration.html | Test timed out. -
[task 2024-07-26T13:51:35.512Z] 13:51:35  WARNING -  TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. -
[task 2024-07-26T13:51:35.512Z] 13:51:35  WARNING -  TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 1 remaining tests. -
[task 2024-07-26T13:51:35.513Z] 13:51:35  WARNING -  TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2024-07-26T13:51:35.513Z] 13:51:35  WARNING -  TEST-UNEXPECTED-ERROR | (SimpleTest/TestRunner.js) | called finish() multiple times
[task 2024-07-26T13:51:35.513Z] 13:51:35     INFO -  TEST-INFO
[task 2024-07-26T13:57:58.629Z] 13:57:58     INFO -  wait for org.mozilla.geckoview.test_runner complete; top activity=org.mozilla.geckoview.test_runner
[task 2024-07-26T13:57:58.629Z] 13:57:58     INFO -  org.mozilla.geckoview.test_runner unexpectedly found running. Killing...
[task 2024-07-26T13:58:11.821Z] 13:58:11  WARNING -  TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) (finished) | application timed out after 370 seconds with no output
[task 2024-07-26T13:58:11.821Z] 13:58:11     INFO -  runtestsremote.py | Application ran for: 0:13:13.507909
[task 2024-07-26T13:58:11.980Z] 13:58:11     INFO -  mozcrash Copy/paste: /builds/worker/fetches/minidump-stackwalk/minidump-stackwalk --symbols-url=https://symbols.mozilla.org/ --cyborg=/tmp/tmppntucq97/79a088de-3645-8c8d-050f-722604404e52.trace /tmp/tmp2j751di9/79a088de-3645-8c8d-050f-722604404e52.dmp /builds/worker/workspace/build/symbols
[task 2024-07-26T13:58:16.572Z] 13:58:16     INFO -  mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/79a088de-3645-8c8d-050f-722604404e52.dmp
[task 2024-07-26T13:58:16.572Z] 13:58:16     INFO -  mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/79a088de-3645-8c8d-050f-722604404e52.extra
[task 2024-07-26T13:58:16.573Z] 13:58:16  WARNING -  PROCESS-CRASH | application crashed [@ libc.so + 0x000000000008c66a] | dom/serviceworkers/test/performance/perftest.toml
[task 2024-07-26T13:58:16.573Z] 13:58:16     INFO -  Process type: main
[task 2024-07-26T13:58:16.573Z] 13:58:16     INFO -  Process pid: 5905
[task 2024-07-26T13:58:16.573Z] 13:58:16     INFO -  Crash dump filename: /tmp/tmp2j751di9/79a088de-3645-8c8d-050f-722604404e52.dmp
[task 2024-07-26T13:58:16.573Z] 13:58:16     INFO -  Operating system: Android
[task 2024-07-26T13:58:16.573Z] 13:58:16     INFO -                    0.0.0 Linux 3.10.0+ #260 SMP PREEMPT Fri May 19 12:48:14 PDT 2017 x86_64
[task 2024-07-26T13:58:16.573Z] 13:58:16     INFO -  CPU: amd64
[task 2024-07-26T13:58:16.573Z] 13:58:16     INFO -       family 6 model 6 stepping 3
[task 2024-07-26T13:58:16.574Z] 13:58:16     INFO -       4 CPUs
[task 2024-07-26T13:58:16.574Z] 13:58:16     INFO -  Crash reason:  SIGABRT
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -  Crash address: 0x0000000000001d86
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -  Crashing instruction: `cmp rax, -0xfff`
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -  No memory accessed by instruction
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -  Crashing address may be the result of a flipped bit:
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -    0. Valid address: 0x0000000020001d86 (0.250)
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -    1. Valid address: 0x0000000040001d86 (0.250)
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -  Process uptime: not available
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -  Thread 0 AndroidUI (crashed)
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -   0  libc.so + 0x8c66a
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -       rax = 0xfffffffffffffffc    rdx = 0x0000000000000010
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -       rcx = 0xffffffffffffffff    rbx = 0x00000000ffffffff
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -       rsi = 0x00007fff4cca73e0    rdi = 0x000000000000001f
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -       rbp = 0x00000000ffffffff    rsp = 0x00007fff4cca7368
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -        r8 = 0x0000000000000000     r9 = 0x0000000000000008
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -       r10 = 0x00000000ffffffff    r11 = 0x0000000000000246
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -       r12 = 0x00000000ffffffff    r13 = 0x00007c75ba12cf40
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -       r14 = 0x0000000000000000    r15 = 0x0000000000000000
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -       rip = 0x00007c75bc44f66a
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -      Found by: given as instruction pointer in context
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -   1  libc.so + 0x2a0e5
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -       rsp = 0x00007fff4cca7370    rip = 0x00007c75bc3ed0e6
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -      Found by: stack scanning
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -   2  libutils.so + 0x184e6
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -       rsp = 0x00007fff4cca7390    rip = 0x00007c75bb7114e7
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -      Found by: stack scanning
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -   3  libart.so + 0x14cd90
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -       rsp = 0x00007fff4cca73a0    rip = 0x00007c75b9a58d91
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -      Found by: stack scanning
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -   4  system@[e-mail] + 0x3d534f
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -       rsp = 0x00007fff4cca73b8    rip = 0x000000006fbfa350
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -      Found by: stack scanning
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -   5  boot-framework.oat + 0xfff
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -       rsp = 0x00007fff4cca73c8    rip = 0x00000000714d9000
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -      Found by: stack scanning
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -   6  dalvik-main space (deleted) + 0x2e49f
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -       rsp = 0x00007fff4cca73e0    rip = 0x0000000012c2e4a0
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -      Found by: stack scanning
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -   7  libart.so + 0x14cd90
[task 2024-07-26T13:58:16.577Z] 13:58:16     INFO -       rsp = 0x00007fff4cca7440    rip = 0x00007c75b9a58d91
<...>
[task 2024-07-26T13:58:16.611Z] 13:58:16     INFO -  196  app_process64 + 0x289a
[task 2024-07-26T13:58:16.611Z] 13:58:16     INFO -       rsp = 0x00007fff4cca8540    rip = 0x00007c75beb1889b
[task 2024-07-26T13:58:16.611Z] 13:58:16     INFO -      Found by: stack scanning
[task 2024-07-26T13:58:16.611Z] 13:58:16     INFO -  197  app_process64 + 0x3206
[task 2024-07-26T13:58:16.611Z] 13:58:16     INFO -       rsp = 0x00007fff4cca8570    rip = 0x00007c75beb19207
[task 2024-07-26T13:58:16.611Z] 13:58:16     INFO -      Found by: stack scanning
[task 2024-07-26T13:58:16.611Z] 13:58:16     INFO -  198  app_process64 + 0x2318
[task 2024-07-26T13:58:16.611Z] 13:58:16     INFO -       rsp = 0x00007fff4cca8590    rip = 0x00007c75beb18319
[task 2024-07-26T13:58:16.611Z] 13:58:16     INFO -      Found by: stack scanning
[task 2024-07-26T13:58:16.611Z] 13:58:16     INFO -  199  app_process64 + 0x35f8
[task 2024-07-26T13:58:16.611Z] 13:58:16     INFO -       rsp = 0x00007fff4cca8598    rip = 0x00007c75beb195f9
[task 2024-07-26T13:58:16.611Z] 13:58:16     INFO -      Found by: stack scanning
[task 2024-07-26T13:58:16.611Z] 13:58:16     INFO -  200  libandroid_runtime.so + 0x1d5b4f
[task 2024-07-26T13:58:16.612Z] 13:58:16     INFO -       rsp = 0x00007fff4cca8630    rip = 0x00007c75bb0a2b50
[task 2024-07-26T13:58:16.612Z] 13:58:16     INFO -      Found by: stack scanning

Comment 1

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

:joshuacmarshall, since you are the author of the regressor, bug 1899749, could you take a look?

For more information, please visit BugBot documentation.

Comment 2

[Iulian Moraru]

This started failing on tier 1: https://treeherder.mozilla.org/logviewer?job_id=468125802&repo=autoland&lineNumber=2354

Comment 3

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

Set release status flags based on info from the regressing bug 1899749

Comment 4

[Intermittent Failures Robot]

22 failures in 193 pushes (0.114 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 12
• autoland: 10

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 22
  • debug: 22

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

Comment 5

[Intermittent Failures Robot]

43 failures in 4124 pushes (0.01 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:

• mozilla-central: 15
• autoland: 28

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 43
  • debug: 42
  • debug-isolated-process: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1910127&startday=2024-07-22&endday=2024-07-28&tree=all

Comment 6

[Sandor Molnar[:smolnar]]

@Joshua, can you look at this frequent android failure?

Comment 7

[Intermittent Failures Robot]

22 failures in 665 pushes (0.033 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 13
• mozilla-central: 9

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 22
  • debug: 22

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

Comment 8

[Joshua Marshall]

Attachment: Bug 1910127 - disable pertest because of android timeouts

Comment 9

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/1d5b2d8914f5
disable pertest because of android timeouts r=dom-worker-reviewers,smaug DONTBUILD

Comment 10

[Sandor Molnar[:smolnar]]

https://hg.mozilla.org/mozilla-central/rev/1d5b2d8914f5

Comment 11

[Joshua Marshall]

I'm unclear how this is happening.

[task 2024-07-26T13:50:04.892Z] 13:50:04 WARNING - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2024-07-26T13:50:04.892Z] 13:50:04 WARNING - TEST-UNEXPECTED-ERROR | dom/serviceworkers/test/performance/test_registration.html | called finish() multiple times

Comment 12

[Intermittent Failures Robot]

52 failures in 3839 pushes (0.014 failures/push) were associated with this bug in the last 7 days.

This is the #17 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: 28
• mozilla-central: 19
• try: 5

Platform and build breakdown:

• android-em-7-0-x86_64-qr: 52
  • debug: 52

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1910127&startday=2024-07-29&endday=2024-08-04&tree=all

Comment 13

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

Set release status flags based on info from the regressing bug 1899749