1579754 - Intermittent TEST-UNEXPECTED-CRASH | /service-workers/service-worker/activate-event-after-install-state-change.https.html | expected OK

Status: RESOLVED FIXED

(GeckoView :: General, defect, P3)

Description

[Treeherder Bug Filer]

Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=265618435&repo=try
Full log: https://queue.taskcluster.net/v1/task/eilxHUTKQNqj8_JG_Gx9EQ/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-09-08T22:01:21.435Z] 22:01:21 INFO - TEST-OK | /service-workers/service-worker/about-blank-replacement.https.html | took 1744ms
[task 2019-09-08T22:01:21.435Z] 22:01:21 INFO - TEST-START | /service-workers/service-worker/activate-event-after-install-state-change.https.html
[task 2019-09-08T22:01:21.435Z] 22:01:21 INFO - Closing window 14
[task 2019-09-08T22:01:21.456Z] 22:01:21 INFO - Closing window 2147483663
[task 2019-09-08T22:07:21.495Z] 22:07:21 INFO - IOError on command, setting status to CRASH
[task 2019-09-08T22:07:21.935Z] 22:07:21 INFO - TEST-UNEXPECTED-CRASH | /service-workers/service-worker/activate-event-after-install-state-change.https.html | expected OK
[task 2019-09-08T22:07:21.935Z] 22:07:21 INFO - TEST-INFO took 360499ms
[task 2019-09-08T22:07:22.575Z] 22:07:22 INFO - Closing logging queue
[task 2019-09-08T22:07:22.575Z] 22:07:22 INFO - queue closed
[task 2019-09-08T22:07:22.583Z] 22:07:22 INFO - Setting up ssl
[task 2019-09-08T22:07:22.603Z] 22:07:22 INFO - certutil |
[task 2019-09-08T22:07:22.619Z] 22:07:22 INFO - certutil |
[task 2019-09-08T22:07:22.634Z] 22:07:22 INFO - certutil |
[task 2019-09-08T22:07:22.634Z] 22:07:22 INFO - Certificate Nickname Trust Attributes
[task 2019-09-08T22:07:22.634Z] 22:07:22 INFO - SSL,S/MIME,JAR/XPI
[task 2019-09-08T22:07:22.634Z] 22:07:22 INFO -
[task 2019-09-08T22:07:22.634Z] 22:07:22 INFO - web-platform-tests CT,,
[task 2019-09-08T22:07:22.634Z] 22:07:22 INFO -
[task 2019-09-08T22:07:25.352Z] 22:07:25 INFO - adb Granting important runtime permissions to org.mozilla.geckoview.test
[task 2019-09-08T22:07:26.600Z] 22:07:26 INFO - adb launch_application: am start -W -n org.mozilla.geckoview.test/org.mozilla.geckoview.test.TestRunnerActivity -a android.intent.action.MAIN --es env9 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env8 R_LOG_DESTINATION=stderr --es args "-no-remote -profile /sdcard/tests/profile --marionette about:blank" --es env3 MOZ_HIDE_RESULTS_TABLE=1 --es env2 R_LOG_VERBOSE=1 --es env1 MOZ_WEBRENDER=0 --es env0 MOZ_CRASHREPORTER=1 --es env7 MOZ_CRASHREPORTER_SHUTDOWN=1 --es env6 MOZ_IN_AUTOMATION=1 --es env5 MOZ_LOG=signaling:3,mtransport:4,DataChannel:4,jsep:4 --es env4 STYLO_THREADS=4 --ez use_multiprocess True --es env12 R_LOG_LEVEL=6 --es env11 MOZ_PROCESS_LOG=/tmp/tmpvNxD5Zpidlog --es env10 MOZ_CRASHREPORTER_NO_REPORT=1
[task 2019-09-08T22:07:28.174Z] 22:07:28 INFO - Starting runner
[task 2019-09-08T22:07:28.340Z] 22:07:28 INFO - TEST-START | /service-workers/service-worker/activation-after-registration.https.html

Comment 1

[Intermittent Failures Robot]

3 failures in 4408 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 3

Platform breakdown:

• android-em-7-0-x86_64: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1579754&startday=2019-09-02&endday=2019-09-08&tree=all

Comment 2

[Andreea Pavel [:apavel]]

Appeared on this merge: https://hg.mozilla.org/integration/mozilla-inbound/pushloghtml?changeset=4048af298d81e3b08ce0adae307eaf93d194d60f

https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&group_state=expanded&searchStr=android%2C7.0%2Cx86-64%2Cdebug%2Cweb%2Cplatform%2Ctests%2Ctest-android-em-7.0-x86_64%2Fdebug-geckoview-web-platform-tests-e10s-14%2Cw%28wpt14%29&tochange=4048af298d81e3b08ce0adae307eaf93d194d60f&fromchange=5f9a594caeb93811dddbaa825b9d3b59e5c23d2e&selectedJob=266024954

Hsin-Yi, can you assign someone to take a look?

Comment 3

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

Hi Eden,
I failed at finding a quick answer from the above merge push log. Could you please help here?

Comment 4

[Eden Chuang[:edenchuang]]

Sure, will take it a look later.

Comment 5

[Intermittent Failures Robot]

58 failures in 4637 pushes (0.013 failures/push) were associated with this bug in the last 7 days.

This is the #22 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:

• mozilla-inbound: 7
• autoland: 46
• mozilla-central: 4
• try: 1

Platform breakdown:

• android-em-7-0-x86_64: 58

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1579754&startday=2019-09-09&endday=2019-09-15&tree=all

Comment 6

[Intermittent Failures Robot]

15 failures in 869 pushes (0.017 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 13
• try: 1
• mozilla-inbound: 1

Platform breakdown:

• android-em-7-0-x86_64: 15

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1579754&startday=2019-09-20&endday=2019-09-20&tree=all

Comment 7

[Noemi Erli[:noemi_erli]]

This fail has reached 50 occurrences in the past week, all android-em-7-0-x86_64 opt and debug.
Recent log failure:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=267771657&repo=autoland&lineNumber=5000

[task 2019-09-21T01:30:50.860Z] 01:30:50 INFO - TEST-START | /service-workers/service-worker/activate-event-after-install-state-change.https.html
[task 2019-09-21T01:30:50.868Z] 01:30:50 INFO - Closing window 14
[task 2019-09-21T01:30:50.875Z] 01:30:50 INFO - Closing window 2147483663
[task 2019-09-21T01:36:50.969Z] 01:36:50 INFO - IOError on command, setting status to CRASH
[task 2019-09-21T01:36:51.407Z] 01:36:51 INFO - TEST-UNEXPECTED-CRASH | /service-workers/service-worker/activate-event-after-install-state-change.https.html | expected OK
[task 2019-09-21T01:36:51.407Z] 01:36:51 INFO - TEST-INFO took 360550ms
[task 2019-09-21T01:36:52.052Z] 01:36:52 INFO - Closing logging queue
[task 2019-09-21T01:36:52.052Z] 01:36:52 INFO - queue closed
[task 2019-09-21T01:36:52.068Z] 01:36:52 INFO - Setting up ssl
[task 2019-09-21T01:36:52.083Z] 01:36:52 INFO - certutil |
[task 2019-09-21T01:36:52.099Z] 01:36:52 INFO - certutil |
[task 2019-09-21T01:36:52.114Z] 01:36:52 INFO - certutil |
[task 2019-09-21T01:36:52.114Z] 01:36:52 INFO - Certificate Nickname Trust Attributes
[task 2019-09-21T01:36:52.114Z] 01:36:52 INFO - SSL,S/MIME,JAR/XPI
[task 2019-09-21T01:36:52.114Z] 01:36:52 INFO -
[task 2019-09-21T01:36:52.115Z] 01:36:52 INFO - web-platform-tests CT,,
[task 2019-09-21T01:36:52.115Z] 01:36:52 INFO -
[task 2019-09-21T01:36:54.827Z] 01:36:54 INFO - adb Granting important runtime permissions to org.mozilla.geckoview.test
[task 2019-09-21T01:36:56.082Z] 01:36:56 INFO - adb launch_application: am start -W -n org.mozilla.geckoview.test/org.mozilla.geckoview.test.TestRunnerActivity -a android.intent.action.MAIN --es env9 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env8 R_LOG_DESTINATION=stderr --es args "-no-remote -profile /sdcard/tests/profile --marionette about:blank" --es env3 MOZ_HIDE_RESULTS_TABLE=1 --es env2 R_LOG_VERBOSE=1 --es env1 MOZ_WEBRENDER=0 --es env0 MOZ_CRASHREPORTER=1 --es env7 MOZ_CRASHREPORTER_SHUTDOWN=1 --es env6 MOZ_IN_AUTOMATION=1 --es env5 MOZ_LOG=signaling:3,mtransport:4,DataChannel:4,jsep:4 --es env4 STYLO_THREADS=4 --ez use_multiprocess True --es env12 R_LOG_LEVEL=6 --es env11 MOZ_PROCESS_LOG=/tmp/tmpDyRF4Qpidlog --es env10 MOZ_CRASHREPORTER_NO_REPORT=1
[task 2019-09-21T01:36:57.458Z] 01:36:57 INFO - Starting runner

Comment 8

[Intermittent Failures Robot]

44 failures in 4350 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

This is the #31 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:

• mozilla-central: 5
• autoland: 33
• try: 3
• mozilla-inbound: 3

Platform breakdown:

• android-em-7-0-x86_64: 44

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1579754&startday=2019-09-16&endday=2019-09-22&tree=all

Comment 9

[Eden Chuang[:edenchuang]]

I think it might be related to the known intermittent fail on the previous test.

According to test logs, we always got TEST-KNOWN-INTERMITTENT-FAIL in /service-workers/service-worker/about-blank-replacement.https.html before we got TEST-UNEXPECTED-CRACH. And we always got strange info log "Closing window xxxxxxxx" when starting the test. I don't think the closing window id is valid, and we try to close an invalid window and cause IOError

TEST-KNOWN-INTERMITTENT-FAIL
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=267771657&repo=autoland&lineNumber=8583

INFO - Closing window 2147483663
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=267771657&repo=autoland&lineNumber=8589

I think it might be a platform issue to creating/closing a pop window on geckoveiw.
I will disable service-workers/service-worker/about-blank-replacement.https.html on geckoview first to check if this decreases the failure rate or not.

Comment 10

[Intermittent Failures Robot]

15 failures in 870 pushes (0.017 failures/push) were associated with this bug yesterday.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• autoland: 14
• mozilla-inbound: 1

Platform breakdown:

• android-em-7-0-x86_64: 15

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1579754&startday=2019-09-26&endday=2019-09-26&tree=all

Comment 11

[Intermittent Failures Robot]

17 failures in 850 pushes (0.02 failures/push) were associated with this bug yesterday.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• autoland: 17

Platform breakdown:

• android-em-7-0-x86_64: 17

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1579754&startday=2019-09-27&endday=2019-09-27&tree=all

Comment 12

[Andreea Pavel [:apavel]]

Hi Eden, are there updates here?

Comment 13

[Bogdan Tara[:bogdan_tara | bogdant]]

Occurrences seem to trend down here. Might be fixed?

Comment 14

[Intermittent Failures Robot]

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

This is the #20 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: 57
• mozilla-central: 3
• try: 3
• mozilla-inbound: 1

Platform breakdown:

• android-em-7-0-x86_64: 64

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1579754&startday=2019-09-23&endday=2019-09-29&tree=all

Comment 15

[Eden Chuang[:edenchuang]]

Attachment: Disable web-platform test serviceworker/about-blank-replacement.https.html.ini for geckoview

Popup window maybe fail in geckoview platform, and closing it makes IOError and crashes the test.

Comment 16

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/ac1436cdbaed
Disable web-platform test serviceworker/about-blank-replacement.https.html.ini for geckoview r=gbrown

Comment 17

[Andreea Pavel [:apavel]]

https://hg.mozilla.org/mozilla-central/rev/ac1436cdbaed

Comment 18

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

(In reply to Eden Chuang[:edenchuang] from comment #15)

Created attachment 9097341 [details]
Disable web-platform test serviceworker/about-blank-replacement.https.html.ini for geckoview

Popup window maybe fail in geckoview platform, and closing it makes IOError and crashes the test.

According to this, Chris may be able to help bring this to the right person's radar.

Comment 19

[Chris Peterson [:cpeterson]]

A recent test log shows an Marionette error: InvalidArgumentException: Unknown pointerType: [object String] "touch". Is that the cause of this test crash? Why would that be an intermittent problem?

https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=268920216&repo=autoland&lineNumber=8605

The marionette code says TODO For now, only mouse is supported:

https://searchfox.org/mozilla-central/rev/01d1011ca4a460f751da030d455d35c267c3e210/testing/marionette/action.js#384-403

[task 2019-09-28T13:17:06.910Z] 13:17:06  WARNING - InvalidArgumentException: Unknown pointerType: [object String] "touch"
[task 2019-09-28T13:17:06.910Z] 13:17:06  WARNING - stacktrace:
[task 2019-09-28T13:17:06.910Z] 13:17:06  WARNING - 	WebDriverError@chrome://marionette/content/error.js:175:5
[task 2019-09-28T13:17:06.910Z] 13:17:06  WARNING - 	InvalidArgumentError@chrome://marionette/content/error.js:304:5
[task 2019-09-28T13:17:06.910Z] 13:17:06  WARNING - 	assert.that/<@chrome://marionette/content/assert.js:428:13
[task 2019-09-28T13:17:06.910Z] 13:17:06  WARNING - 	assert.in@chrome://marionette/content/assert.js:385:47
[task 2019-09-28T13:17:06.910Z] 13:17:06  WARNING - 	action.PointerType.get@chrome://marionette/content/action.js:403:10
[task 2019-09-28T13:17:06.910Z] 13:17:06  WARNING - 	Pointer@chrome://marionette/content/action.js:593:39
[task 2019-09-28T13:17:06.910Z] 13:17:06  WARNING - 	fromJSON@chrome://marionette/content/action.js:481:14
[task 2019-09-28T13:17:06.910Z] 13:17:06  WARNING - 	fromJSON@chrome://marionette/content/action.js:847:39
[task 2019-09-28T13:17:06.910Z] 13:17:06  WARNING - 	fromJSON@chrome://marionette/content/action.js:810:48
[task 2019-09-28T13:17:06.910Z] 13:17:06  WARNING - 	performActions@chrome://marionette/content/listener.js:843:28
[task 2019-09-28T13:17:06.910Z] 13:17:06  WARNING - 	dispatch/</req<@chrome://marionette/content/listener.js:527:14
[task 2019-09-28T13:17:06.910Z] 13:17:06  WARNING - 	dispatch/<@chrome://marionette/content/listener.js:520:15

Comment 20

[Intermittent Failures Robot]

15 failures in 4292 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 11
• mozilla-central: 2
• try: 1
• mozilla-inbound: 1

Platform breakdown:

• android-em-7-0-x86_64: 15

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1579754&startday=2019-09-30&endday=2019-10-06&tree=all

Comment 21

[Chris Peterson [:cpeterson]]

Moving this bug to the Marionette component. This looks like a Marionette bug. Marionette's "TODO For now, only mouse is supported" comment is no longer true. GeckoView is sending touch events. But why is this a test failing intermittently instead of all the time??

https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=269175747&repo=mozilla-inbound&lineNumber=5020

[task 2019-10-01T01:27:20.995Z] 01:27:20  WARNING - InvalidArgumentException: Unknown pointerType: [object String] "touch"
[task 2019-10-01T01:27:20.995Z] 01:27:20  WARNING - stacktrace:
[task 2019-10-01T01:27:20.995Z] 01:27:20  WARNING - 	WebDriverError@chrome://marionette/content/error.js:175:5
[task 2019-10-01T01:27:20.995Z] 01:27:20  WARNING - 	InvalidArgumentError@chrome://marionette/content/error.js:304:5
[task 2019-10-01T01:27:20.995Z] 01:27:20  WARNING - 	assert.that/<@chrome://marionette/content/assert.js:428:13
[task 2019-10-01T01:27:20.995Z] 01:27:20  WARNING - 	assert.in@chrome://marionette/content/assert.js:385:47
[task 2019-10-01T01:27:20.995Z] 01:27:20  WARNING - 	action.PointerType.get@chrome://marionette/content/action.js:403:10
[task 2019-10-01T01:27:20.995Z] 01:27:20  WARNING - 	Pointer@chrome://marionette/content/action.js:593:39
[task 2019-10-01T01:27:20.995Z] 01:27:20  WARNING - 	fromJSON@chrome://marionette/content/action.js:481:14
[task 2019-10-01T01:27:20.995Z] 01:27:20  WARNING - 	fromJSON@chrome://marionette/content/action.js:847:39
[task 2019-10-01T01:27:20.995Z] 01:27:20  WARNING - 	fromJSON@chrome://marionette/content/action.js:810:48
[task 2019-10-01T01:27:20.995Z] 01:27:20  WARNING - 	performActions@chrome://marionette/content/listener.js:843:28
[task 2019-10-01T01:27:20.995Z] 01:27:20  WARNING - 	dispatch/</req<@chrome://marionette/content/listener.js:527:14
[task 2019-10-01T01:27:20.995Z] 01:27:20  WARNING - 	dispatch/<@chrome://marionette/content/listener.js:520:15

Comment 22

[Henrik Skupin [:whimboo][⌚️UTC+2]]

I would like to leave this bug under GeckoView. For the Marionette specific warning we have bug 1543337, which should be a dependency.

Comment 23

[Chris Peterson [:cpeterson]]

I would like to leave this bug under GeckoView. For the Marionette specific warning we have bug 1543337, which should be a dependency.

Tracking the Marionette fix separately from this particular GeckoView test failure makes sense. Marking this bug P3 because the Marionette bug 1543337 is P3.

Comment 24

[Intermittent Failures Robot]

1 failures in 4182 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-inbound: 1

Platform breakdown:

• android-em-7-0-x86_64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1579754&startday=2019-10-21&endday=2019-10-27&tree=all

Comment 25

[Henrik Skupin [:whimboo][⌚️UTC+2]]

(In reply to Pulsebot from comment #16)

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/ac1436cdbaed
Disable web-platform test
serviceworker/about-blank-replacement.https.html.ini for geckoview r=gbrown

Eden, do we still need the test to be disabled now that Marionette supports touch?

Comment 26

[Eden Chuang[:edenchuang]]

Henrik, yes, I think we can enable it. Do you want me to write the patch to enable? Or you will do it?

Comment 27

[Henrik Skupin [:whimboo][⌚️UTC+2]]

In case of other possible side-effects it would be great if you could create and test the patch.

Comment 28

[Andreea Pavel [:apavel]]

The test is enabled: https://searchfox.org/mozilla-central/source/testing/web-platform/meta/service-workers/service-worker/activate-event-after-install-state-change.https.html.ini

Comment 29

[Henrik Skupin [:whimboo][⌚️UTC+2]]

(In reply to Andreea Pavel [:apavel] from comment #28)

The test is enabled: https://searchfox.org/mozilla-central/source/testing/web-platform/meta/service-workers/service-worker/activate-event-after-install-state-change.https.html.ini

This is not the test that was disabled. It is the following and the disable flag is still there:
https://searchfox.org/mozilla-central/source/testing/web-platform/meta/service-workers/service-worker/about-blank-replacement.https.html.ini

Comment 30

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

Attachment: Bug 1579754 - re-enable service-worker/about-blank-replacement.https.html on Android

Comment 31

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/122d865083ca
re-enable service-worker/about-blank-replacement.https.html on Android

Comment 32

[Marian-Vasile Laza]

https://hg.mozilla.org/mozilla-central/rev/122d865083ca

Comment 33

[Henrik Skupin [:whimboo][⌚️UTC+2]]

No more crashes since the test got re-enabled. Marking as fixed.