Closed Bug 1222355 Opened 9 years ago Closed 6 years ago

Intermittent tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js | client get logs from (nested) mozbrowser iframes

Categories

(Firefox OS Graveyard :: Gaia, defect)

ARM
Gonk (Firefox OS)
defect
Not set
normal

Tracking

(feature-b2g:2.6+)

RESOLVED WONTFIX
feature-b2g 2.6+

People

(Reporter: nigelb, Unassigned)

References

()

Details

(Keywords: intermittent-failure, Whiteboard: [MJS] [intermittents])

https://treeherder.mozilla.org/logviewer.html#?job_id=16898365&repo=mozilla-inbound

 05:24:56     INFO -  [marionette-mocha]   1) client Catches content errors:

 05:24:56     INFO -    Error: timeout exceeded!

 05:24:56     INFO -        at Object.Client.waitForSync (/home/worker/gaia/node_modules/marionette-client/lib/marionette/client.js:756:16)

 05:24:56     INFO -        at Object.Client.waitFor (/home/worker/gaia/node_modules/marionette-client/lib/marionette/client.js:722:60)

 05:24:56     INFO -        at Object.Rocketbar.enterText (/home/worker/gaia/apps/system/test/marionette/lib/rocketbar.js:105:17)

 05:24:56     INFO -        at Context.<anonymous> (/home/worker/gaia/tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js:170:15)

 05:24:56     INFO -        at callFn (/home/worker/gaia/node_modules/mocha/lib/runnable.js:223:21)

 05:24:56     INFO -        at Test.Runnable.run (/home/worker/gaia/node_modules/mocha/lib/runnable.js:216:7)

 05:24:56     INFO -        at Test.MarionetteTest.run (/home/worker/gaia/node_modules/marionette-js-runner/lib/ui.js:25:31)

 05:24:56     INFO -        at Runner.runTest (/home/worker/gaia/node_modules/mocha/lib/runner.js:373:10)

 05:24:56     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runner.js:451:12

 05:24:56     INFO -        at next (/home/worker/gaia/node_modules/mocha/lib/runner.js:298:14)

 05:24:56     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runner.js:308:7

 05:24:56     INFO -        at next (/home/worker/gaia/node_modules/mocha/lib/runner.js:246:23)

 05:24:56     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runner.js:270:7

 05:24:56     INFO -        at done (/home/worker/gaia/node_modules/mocha/lib/runnable.js:185:5)

 05:24:56     INFO -        at callFn (/home/worker/gaia/node_modules/mocha/lib/runnable.js:228:7)

 05:24:56     INFO -        at Hook.Runnable.run (/home/worker/gaia/node_modules/mocha/lib/runnable.js:216:7)

 05:24:56     INFO -        at next (/home/worker/gaia/node_modules/mocha/lib/runner.js:258:10)

 05:24:56     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runner.js:270:7

 05:24:56     INFO -        at done (/home/worker/gaia/node_modules/mocha/lib/runnable.js:185:5)

 05:24:56     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runnable.js:226:31

 05:24:56     INFO -        at /home/worker/gaia/node_modules/marionette-js-runner/node_modules/promise/lib/core.js:33:15

 05:24:56     INFO -        at flush (/home/worker/gaia/node_modules/marionette-js-runner/node_modules/promise/node_modules/asap/asap.js:27:13)

 05:24:56     INFO -        at process._tickCallback (node.js:442:13)

 05:24:56     INFO -  .

 05:24:56     INFO -  /home/worker/gaia/tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js failed. Will retry.

 05:26:22     INFO -  ..........[marionette-mocha] { [Error: Not connected. To write data you must call connect first.]

 05:26:22     INFO -    client:

 05:26:22     INFO -     { _scope: { context: 'chrome', scriptTimeout: 20000, searchTimeout: 10000 },

 05:26:22     INFO -       _bypassScopeChecks: true } }

 05:26:22     INFO -  [marionette-mocha] { [Error: Not connected. To write data you must call connect first.]

 05:26:22     INFO -    client:

 05:26:22     INFO -     { _scope: { context: 'chrome', scriptTimeout: 20000, searchTimeout: 10000 },

 05:26:22     INFO -       _bypassScopeChecks: true } }

 05:26:22     INFO -  [marionette-mocha] { [Error: Not connected. To write data you must call connect first.]

 05:26:22     INFO -    client:

 05:26:22     INFO -     { _scope: { context: 'chrome', scriptTimeout: 20000, searchTimeout: 10000 },

 05:26:22     INFO -       _bypassScopeChecks: true } }

 05:26:22     INFO -  ..[marionette-mocha]   2 failing

 05:26:22     INFO -    1) client get logs from (nested) mozbrowser iframes:

 05:26:22     INFO -    Error: timeout exceeded!

 05:26:22     INFO -        at Object.Client.waitForSync (/home/worker/gaia/node_modules/marionette-client/lib/marionette/client.js:756:16)

 05:26:22     INFO -        at Object.Client.waitFor (/home/worker/gaia/node_modules/marionette-client/lib/marionette/client.js:722:60)

 05:26:22     INFO -        at Object.Rocketbar.enterText (/home/worker/gaia/apps/system/test/marionette/lib/rocketbar.js:105:17)

 05:26:22     INFO -        at Context.<anonymous> (/home/worker/gaia/tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js:149:15)

 05:26:22     INFO -        at callFn (/home/worker/gaia/node_modules/mocha/lib/runnable.js:223:21)

 05:26:22     INFO -        at Test.Runnable.run (/home/worker/gaia/node_modules/mocha/lib/runnable.js:216:7)

 05:26:22     INFO -        at Test.MarionetteTest.run (/home/worker/gaia/node_modules/marionette-js-runner/lib/ui.js:25:31)

 05:26:22     INFO -        at Runner.runTest (/home/worker/gaia/node_modules/mocha/lib/runner.js:373:10)

 05:26:22     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runner.js:451:12

 05:26:22     INFO -        at next (/home/worker/gaia/node_modules/mocha/lib/runner.js:298:14)

 05:26:22     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runner.js:308:7

 05:26:22     INFO -        at next (/home/worker/gaia/node_modules/mocha/lib/runner.js:246:23)

 05:26:22     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runner.js:270:7

 05:26:22     INFO -        at done (/home/worker/gaia/node_modules/mocha/lib/runnable.js:185:5)

 05:26:22     INFO -        at callFn (/home/worker/gaia/node_modules/mocha/lib/runnable.js:228:7)

 05:26:22     INFO -        at Hook.Runnable.run (/home/worker/gaia/node_modules/mocha/lib/runnable.js:216:7)

 05:26:22     INFO -        at next (/home/worker/gaia/node_modules/mocha/lib/runner.js:258:10)

 05:26:22     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runner.js:270:7

 05:26:22     INFO -        at done (/home/worker/gaia/node_modules/mocha/lib/runnable.js:185:5)

 05:26:22     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runnable.js:226:31

 05:26:22     INFO -        at /home/worker/gaia/node_modules/marionette-js-runner/node_modules/promise/lib/core.js:33:15

 05:26:22     INFO -        at flush (/home/worker/gaia/node_modules/marionette-js-runner/node_modules/promise/node_modules/asap/asap.js:27:13)

 05:26:22     INFO -        at process._tickCallback (node.js:442:13)

 05:26:22     INFO -    2) client Catches content errors:

 05:26:22     INFO -    Error: timeout exceeded!

 05:26:22     INFO -        at Object.Client.waitForSync (/home/worker/gaia/node_modules/marionette-client/lib/marionette/client.js:756:16)

 05:26:22     INFO -        at Object.Client.waitFor (/home/worker/gaia/node_modules/marionette-client/lib/marionette/client.js:722:60)

 05:26:22     INFO -        at Object.Rocketbar.enterText (/home/worker/gaia/apps/system/test/marionette/lib/rocketbar.js:105:17)

 05:26:22     INFO -        at Context.<anonymous> (/home/worker/gaia/tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js:170:15)

 05:26:22     INFO -        at callFn (/home/worker/gaia/node_modules/mocha/lib/runnable.js:223:21)

 05:26:22     INFO -        at Test.Runnable.run (/home/worker/gaia/node_modules/mocha/lib/runnable.js:216:7)

 05:26:22     INFO -        at Test.MarionetteTest.run (/home/worker/gaia/node_modules/marionette-js-runner/lib/ui.js:25:31)

 05:26:22     INFO -        at Runner.runTest (/home/worker/gaia/node_modules/mocha/lib/runner.js:373:10)

 05:26:22     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runner.js:451:12

 05:26:22     INFO -        at next (/home/worker/gaia/node_modules/mocha/lib/runner.js:298:14)

 05:26:22     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runner.js:308:7

 05:26:22     INFO -        at next (/home/worker/gaia/node_modules/mocha/lib/runner.js:246:23)

 05:26:22     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runner.js:270:7

 05:26:22     INFO -        at done (/home/worker/gaia/node_modules/mocha/lib/runnable.js:185:5)

 05:26:22     INFO -        at callFn (/home/worker/gaia/node_modules/mocha/lib/runnable.js:228:7)

 05:26:22     INFO -        at Hook.Runnable.run (/home/worker/gaia/node_modules/mocha/lib/runnable.js:216:7)

 05:26:22     INFO -        at next (/home/worker/gaia/node_modules/mocha/lib/runner.js:258:10)

 05:26:22     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runner.js:270:7

 05:26:22     INFO -        at done (/home/worker/gaia/node_modules/mocha/lib/runnable.js:185:5)

 05:26:22     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runnable.js:226:31

 05:26:22     INFO -        at /home/worker/gaia/node_modules/marionette-js-runner/node_modules/promise/lib/core.js:33:15

 05:26:22     INFO -        at flush (/home/worker/gaia/node_modules/marionette-js-runner/node_modules/promise/node_modules/asap/asap.js:27:13)

 05:26:22     INFO -        at process._tickCallback (node.js:442:13)

 05:26:22     INFO -  /home/worker/gaia/tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js failed. Will retry.

 05:27:31     INFO -  ................[marionette-mocha]   1 failing

 05:27:31     INFO -    1) client get logs from (nested) mozbrowser iframes:

 05:27:31     INFO -    Error: timeout exceeded!

 05:27:31     INFO -        at Object.Client.waitForSync (/home/worker/gaia/node_modules/marionette-client/lib/marionette/client.js:756:16)

 05:27:31     INFO -        at Object.Client.waitFor (/home/worker/gaia/node_modules/marionette-client/lib/marionette/client.js:722:60)

 05:27:31     INFO -        at Object.Rocketbar.enterText (/home/worker/gaia/apps/system/test/marionette/lib/rocketbar.js:105:17)

 05:27:31     INFO -        at Context.<anonymous> (/home/worker/gaia/tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js:149:15)

 05:27:31     INFO -        at callFn (/home/worker/gaia/node_modules/mocha/lib/runnable.js:223:21)

 05:27:31     INFO -        at Test.Runnable.run (/home/worker/gaia/node_modules/mocha/lib/runnable.js:216:7)

 05:27:31     INFO -        at Test.MarionetteTest.run (/home/worker/gaia/node_modules/marionette-js-runner/lib/ui.js:25:31)

 05:27:31     INFO -        at Runner.runTest (/home/worker/gaia/node_modules/mocha/lib/runner.js:373:10)

 05:27:31     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runner.js:451:12

 05:27:31     INFO -        at next (/home/worker/gaia/node_modules/mocha/lib/runner.js:298:14)

 05:27:31     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runner.js:308:7

 05:27:31     INFO -        at next (/home/worker/gaia/node_modules/mocha/lib/runner.js:246:23)

 05:27:31     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runner.js:270:7

 05:27:31     INFO -        at done (/home/worker/gaia/node_modules/mocha/lib/runnable.js:185:5)

 05:27:31     INFO -        at callFn (/home/worker/gaia/node_modules/mocha/lib/runnable.js:228:7)

 05:27:31     INFO -        at Hook.Runnable.run (/home/worker/gaia/node_modules/mocha/lib/runnable.js:216:7)

 05:27:31     INFO -        at next (/home/worker/gaia/node_modules/mocha/lib/runner.js:258:10)

 05:27:31     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runner.js:270:7

 05:27:31     INFO -        at done (/home/worker/gaia/node_modules/mocha/lib/runnable.js:185:5)

 05:27:31     INFO -        at /home/worker/gaia/node_modules/mocha/lib/runnable.js:226:31

 05:27:31     INFO -        at /home/worker/gaia/node_modules/marionette-js-runner/node_modules/promise/lib/core.js:33:15

 05:27:31     INFO -        at flush (/home/worker/gaia/node_modules/marionette-js-runner/node_modules/promise/node_modules/asap/asap.js:27:13)

 05:27:31     INFO -        at process._tickCallback (node.js:442:13)

 05:27:31     INFO -  TEST-START | tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js | client console

 05:27:31     INFO -  TEST-PASS | tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js | client console

 05:27:31     INFO -  TEST-END | tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js | client console took 1168 ms

 05:27:31     INFO -  TEST-START | tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js | client get logs from (nested) mozbrowser iframes

 05:27:31     INFO -  TEST-UNEXPECTED-FAIL | tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js | client get logs from (nested) mozbrowser iframes

 05:27:31     INFO -  Error: timeout exceeded!

 05:27:31     INFO -      at Object.Client.waitForSync (/home/worker/gaia/node_modules/marionette-client/lib/marionette/client.js:756:16)

 05:27:31     INFO -      at Object.Client.waitFor (/home/worker/gaia/node_modules/marionette-client/lib/marionette/client.js:722:60)

 05:27:31     INFO -      at Object.Rocketbar.enterText (/home/worker/gaia/apps/system/test/marionette/lib/rocketbar.js:105:17)

 05:27:31     INFO -      at Context.<anonymous> (/home/worker/gaia/tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js:149:15)

 05:27:31     INFO -      at callFn (/home/worker/gaia/node_modules/mocha/lib/runnable.js:223:21)

 05:27:31     INFO -      at Test.Runnable.run (/home/worker/gaia/node_modules/mocha/lib/runnable.js:216:7)

 05:27:31     INFO -      at Test.MarionetteTest.run (/home/worker/gaia/node_modules/marionette-js-runner/lib/ui.js:25:31)

 05:27:31     INFO -      at Runner.runTest (/home/worker/gaia/node_modules/mocha/lib/runner.js:373:10)

 05:27:31     INFO -      at /home/worker/gaia/node_modules/mocha/lib/runner.js:451:12

 05:27:31     INFO -      at next (/home/worker/gaia/node_modules/mocha/lib/runner.js:298:14)

 05:27:31     INFO -      at /home/worker/gaia/node_modules/mocha/lib/runner.js:308:7

 05:27:31     INFO -      at next (/home/worker/gaia/node_modules/mocha/lib/runner.js:246:23)

 05:27:31     INFO -      at /home/worker/gaia/node_modules/mocha/lib/runner.js:270:7

 05:27:31     INFO -      at done (/home/worker/gaia/node_modules/mocha/lib/runnable.js:185:5)

 05:27:31     INFO -      at callFn (/home/worker/gaia/node_modules/mocha/lib/runnable.js:228:7)

 05:27:31     INFO -      at Hook.Runnable.run (/home/worker/gaia/node_modules/mocha/lib/runnable.js:216:7)

 05:27:31     INFO -      at next (/home/worker/gaia/node_modules/mocha/lib/runner.js:258:10)

 05:27:31     INFO -      at /home/worker/gaia/node_modules/mocha/lib/runner.js:270:7

 05:27:31     INFO -      at done (/home/worker/gaia/node_modules/mocha/lib/runnable.js:185:5)

 05:27:31     INFO -      at /home/worker/gaia/node_modules/mocha/lib/runnable.js:226:31

 05:27:31     INFO -      at /home/worker/gaia/node_modules/marionette-js-runner/node_modules/promise/lib/core.js:33:15

 05:27:31     INFO -      at flush (/home/worker/gaia/node_modules/marionette-js-runner/node_modules/promise/node_modules/asap/asap.js:27:13)

 05:27:31     INFO -      at process._tickCallback (node.js:442:13)

 05:27:31     INFO -  TEST-END | tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js | client get logs from (nested) mozbrowser iframes took 20844 ms

 05:27:31     INFO -  TEST-START | tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js | client Catches content errors

 05:27:31     INFO -  TEST-PASS | tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js | client Catches content errors

 05:27:31     INFO -  TEST-END | tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js | client Catches content errors took 3257 ms

 05:27:31     INFO -  TEST-START | tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js | client #pollMessages should catch all logs

 05:27:31     INFO -  TEST-PASS | tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js | client #pollMessages should catch all logs

 05:27:31     INFO -  TEST-END | tests/jsmarionette/plugins/marionette-js-logger/test/client_test.js | client #pollMessages should catch all logs took 745 ms

 05:27:31     INFO -  Exit code 1
Been seeing these a lot when we disable the retry logic in bug 1222215.

1.) https://treeherder.mozilla.org/#/jobs?repo=gaia&revision=f738dc459f86b50a4f331908c23f0a3df2366a0f

Gareth or Aus, are you guys looking at these marionette client tests?
Blocks: 1222215
Flags: needinfo?(gaye)
Flags: needinfo?(aus)
Yeah, we'll be trying to fix those.
Flags: needinfo?(aus)
feature-b2g: --- → 2.6+
Whiteboard: [MJS]
This is starting to show false positives on even gaia-master [1]. Let's disable this test for now.

1.) https://treeherder.mozilla.org/#/jobs?repo=gaia-master&revision=569ae6b53fb5
Flags: needinfo?(gaye)
Removing the dependency since this test is now disabled.
No longer blocks: 1222215
Whiteboard: [MJS] → [MJS] [intermittents]
Closing all intermittent test failures for Firefox OS (since we're not focusing on it anymore).

Please reopen if my search included your bug by mistake.
Firefox OS is not being worked on
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → WONTFIX
You need to log in before you can comment on or make changes to this bug.