Open Bug 1581657 Opened 10 months ago Updated 6 months ago

Intermittent org.mozilla.geckoview.test.ContentBlockingControllerTest.getLog | status -2

Categories

(GeckoView :: General, defect, P5)

Unspecified
All
defect

Tracking

(firefox71 wontfix, firefox72 disabled)

Tracking Status
firefox71 --- wontfix
firefox72 --- disabled

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Blocks 1 open bug)

Details

(4 keywords, Whiteboard: [stockwell disabled])

Attachments

(2 files)

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=266909686&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/OOnqY6JeR0-v0qmT-OKL8g/runs/0/artifacts/public/logs/live_backing.log


[task 2019-09-16T18:42:48.267Z] 18:42:48 INFO - TEST-START | org.mozilla.geckoview.test.ContentBlockingControllerTest.getLog
[task 2019-09-16T18:42:49.775Z] 18:42:49 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: numtests=518
[task 2019-09-16T18:42:49.775Z] 18:42:49 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stream=
[task 2019-09-16T18:42:49.775Z] 18:42:49 INFO - org.mozilla.geckoview.test | Error in getLog(org.mozilla.geckoview.test.ContentBlockingControllerTest):
[task 2019-09-16T18:42:49.775Z] 18:42:49 INFO - org.mozilla.geckoview.test | java.lang.AssertionError: Log must have at least one entry
[task 2019-09-16T18:42:49.775Z] 18:42:49 INFO - org.mozilla.geckoview.test | Expected: not <0>
[task 2019-09-16T18:42:49.775Z] 18:42:49 INFO - org.mozilla.geckoview.test | but: was <0>
[task 2019-09-16T18:42:49.775Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:20)
[task 2019-09-16T18:42:49.775Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.junit.Assert.assertThat(Assert.java:956)
[task 2019-09-16T18:42:49.775Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector$1.call(ErrorCollector.java:65)
[task 2019-09-16T18:42:49.775Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector.checkSucceeds(ErrorCollector.java:78)
[task 2019-09-16T18:42:49.775Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector.checkThat(ErrorCollector.java:63)
[task 2019-09-16T18:42:49.775Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.checkThat(GeckoSessionTestRule.java:796)
[task 2019-09-16T18:42:49.775Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.BaseSessionTest.assertThat(BaseSessionTest.kt:72)
[task 2019-09-16T18:42:49.776Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.ContentBlockingControllerTest$getLog$1.accept(ContentBlockingControllerTest.kt:153)
[task 2019-09-16T18:42:49.776Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.ContentBlockingControllerTest$getLog$1.accept(ContentBlockingControllerTest.kt:21)
[task 2019-09-16T18:42:49.782Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.GeckoResult.lambda$accept$0(GeckoResult.java:382)
[task 2019-09-16T18:42:49.782Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.-$$Lambda$GeckoResult$I3k4K0DCRrX6z4p5VGaRoaRBTZM.onValue(lambda)
[task 2019-09-16T18:42:49.782Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.GeckoResult.lambda$thenInternal$2$GeckoResult(GeckoResult.java:431)
[task 2019-09-16T18:42:49.782Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.-$$Lambda$GeckoResult$gwCgOUK_EYQn2g6GolfZvo6A_WE.run(lambda)
[task 2019-09-16T18:42:49.782Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.GeckoResult.lambda$dispatchLocked$3(GeckoResult.java:535)
[task 2019-09-16T18:42:49.782Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.-$$Lambda$GeckoResult$PehjG2jgyDa_p37vZrKrSi2I94s.run(lambda)
[task 2019-09-16T18:42:49.782Z] 18:42:49 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2019-09-16T18:42:49.782Z] 18:42:49 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2019-09-16T18:42:49.782Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:161)
[task 2019-09-16T18:42:49.782Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForResult(UiThreadUtils.java:86)
[task 2019-09-16T18:42:49.782Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2196)
[task 2019-09-16T18:42:49.782Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.ContentBlockingControllerTest.getLog(ContentBlockingControllerTest.kt:151)
[task 2019-09-16T18:42:49.782Z] 18:42:49 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2019-09-16T18:42:49.782Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1257)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$mzZNnl5Bu5F2_4xGxj0DHU4J33I.run(lambda)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test |
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: id=AndroidJUnitRunner
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: test=getLog
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: class=org.mozilla.geckoview.test.ContentBlockingControllerTest
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: stack=java.lang.AssertionError: Log must have at least one entry
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | Expected: not <0>
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | but: was <0>
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:20)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.junit.Assert.assertThat(Assert.java:956)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector$1.call(ErrorCollector.java:65)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector.checkSucceeds(ErrorCollector.java:78)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.junit.rules.ErrorCollector.checkThat(ErrorCollector.java:63)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.checkThat(GeckoSessionTestRule.java:796)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.BaseSessionTest.assertThat(BaseSessionTest.kt:72)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.ContentBlockingControllerTest$getLog$1.accept(ContentBlockingControllerTest.kt:153)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.ContentBlockingControllerTest$getLog$1.accept(ContentBlockingControllerTest.kt:21)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.GeckoResult.lambda$accept$0(GeckoResult.java:382)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.-$$Lambda$GeckoResult$I3k4K0DCRrX6z4p5VGaRoaRBTZM.onValue(lambda)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.GeckoResult.lambda$thenInternal$2$GeckoResult(GeckoResult.java:431)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.-$$Lambda$GeckoResult$gwCgOUK_EYQn2g6GolfZvo6A_WE.run(lambda)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.GeckoResult.lambda$dispatchLocked$3(GeckoResult.java:535)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.-$$Lambda$GeckoResult$PehjG2jgyDa_p37vZrKrSi2I94s.run(lambda)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForCondition(UiThreadUtils.java:161)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.util.UiThreadUtils.waitForResult(UiThreadUtils.java:86)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule.waitForResult(GeckoSessionTestRule.java:2196)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.ContentBlockingControllerTest.getLog(ContentBlockingControllerTest.kt:151)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.GeckoSessionTestRule$2.lambda$evaluate$0$GeckoSessionTestRule$2(GeckoSessionTestRule.java:1257)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at org.mozilla.geckoview.test.rule.-$$Lambda$GeckoSessionTestRule$2$mzZNnl5Bu5F2_4xGxj0DHU4J33I.run(lambda)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at android.app.Instrumentation$SyncRunnable.run(Instrumentation.java:1950)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at android.os.Handler.handleCallback(Handler.java:751)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at android.os.Handler.dispatchMessage(Handler.java:95)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at android.os.Looper.loop(Looper.java:154)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at android.app.ActivityThread.main(ActivityThread.java:6077)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at java.lang.reflect.Method.invoke(Native Method)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test |
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS: current=1
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - org.mozilla.geckoview.test | INSTRUMENTATION_STATUS_CODE: -2
[task 2019-09-16T18:42:49.783Z] 18:42:49 WARNING - TEST-UNEXPECTED-FAIL | org.mozilla.geckoview.test.ContentBlockingControllerTest.getLog | status -2
[task 2019-09-16T18:42:49.783Z] 18:42:49 INFO - TEST-INFO took 1513ms

@Alexandru worked on a disabling patch and tested it on try.
Leaving the ni: so he can attach it to this bug.

Flags: needinfo?(malexandru)
Pushed by rmaries@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/17e43e854a15
Disable ContentBlockingControllerTest.getLog() on Android 7.0 debug. r=gbrown
Keywords: leave-open
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]

Should we uplift this patch to disable the test on GV Beta (71)?

Geoff this is now failing on android opt, should we update the disabling patch?

Flags: needinfo?(gbrown)

Yes, I think that's appropriate.

Flags: needinfo?(gbrown)
Pushed by rmaries@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/ed588410fb6b
Disable ContentBlockingControllerTest.getLog() on Android r=gbrown

firefox71=wontfix because we don't need to uplift this test patch to Beta.

You need to log in before you can comment on or make changes to this bug.