1632324 - Intermittent Automation Error: mozprocess timed out after 300 seconds running ['/Users/cltbld/tasks/task_1587591187/build/venv/bin/python', '/Users/cltbld/tasks/task_1587591187/build/venv/lib/python2.7/site-packages/telemetry_harness

Status: RESOLVED INCOMPLETE

(Toolkit :: Telemetry, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-04-22T21:34:47.294Z] 21:34:47 INFO - TEST-START | telemetry/marionette/tests/client/test_event_ping.py TestEventPing.test_event_ping
[task 2020-04-22T21:34:47.295Z] 21:34:47 INFO - Application command: /Users/cltbld/tasks/task_1587591187/build/application/Firefox Nightly.app/Contents/MacOS/firefox -no-remote -marionette -foreground -profile /Users/cltbld/tasks/task_1587591187/build/tmpObyXda.mozrunner
[task 2020-04-22T21:34:48.036Z] 21:34:48 INFO - 1587591287995 Marionette TRACE Marionette enabled
[task 2020-04-22T21:34:48.036Z] 21:34:48 INFO - 1587591287995 Marionette TRACE Received observer notification profile-after-change
[task 2020-04-22T21:34:48.036Z] 21:34:48 INFO - 1587591287997 Toolkit.Telemetry TRACE TelemetryController::observe - profile-after-change notified.
[task 2020-04-22T21:34:48.036Z] 21:34:48 INFO - 1587591287997 Toolkit.Telemetry TRACE TelemetryController::setupTelemetry
[task 2020-04-22T21:34:48.036Z] 21:34:48 INFO - 1587591287999 Toolkit.Telemetry TRACE TelemetryReportingPolicy::setup
[task 2020-04-22T21:34:48.036Z] 21:34:48 INFO - 1587591287999 Toolkit.Telemetry CONFIG TelemetryController::enableTelemetryRecording - canRecordBase:true, canRecordExtended: false
[task 2020-04-22T21:34:48.036Z] 21:34:48 INFO - 1587591287999 Toolkit.Telemetry TRACE TelemetrySession::earlyInit
[task 2020-04-22T21:34:48.036Z] 21:34:48 INFO - 1587591288004 Toolkit.Telemetry INFO TelemetryReportingPolicy::get dataSubmissionPolicyNotifiedDate - No date stored yet.
[task 2020-04-22T21:34:48.037Z] 21:34:48 INFO - 1587591288005 Toolkit.Telemetry TRACE UpdatePing::init - enabled: true
[task 2020-04-22T21:34:48.037Z] 21:34:48 INFO - 1587591288009 Toolkit.Telemetry TRACE TelemetryEnvironment::constructor
[task 2020-04-22T21:34:48.125Z] 21:34:48 INFO - 1587591288103 Toolkit.Telemetry TRACE TelemetryEnvironment::_getGFXData - Only one display adapter detected.
[task 2020-04-22T21:34:48.125Z] 21:34:48 INFO - 1587591288105 Toolkit.Telemetry TRACE TelemetryEnvironment::_updateSearchEngine - ignoring early call
[task 2020-04-22T21:34:48.126Z] 21:34:48 INFO - 1587591288105 Toolkit.Telemetry TRACE TelemetryEnvironment::_updateAddons
[task 2020-04-22T21:34:48.126Z] 21:34:48 INFO - 1587591288108 Toolkit.Telemetry TRACE TelemetryEnvironment::registerChangeListener for CrashAnnotator
[task 2020-04-22T21:34:48.143Z] 21:34:48 INFO - 1587591288120 Marionette TRACE Received observer notification command-line-startup
[task 2020-04-22T21:34:48.181Z] 21:34:48 INFO - 1587591288164 Toolkit.Telemetry TRACE TelemetryEnvironment::_updateAddons: addons differ
[task 2020-04-22T21:34:48.352Z] 21:34:48 INFO - 1587591288312 Marionette TRACE Received observer notification toplevel-window-ready
[task 2020-04-22T21:34:48.689Z] 21:34:48 INFO - 1587591288663 Toolkit.Telemetry TRACE TelemetryController::observe - app-startup notified.
[task 2020-04-22T21:34:48.690Z] 21:34:48 INFO - 1587591288663 Toolkit.Telemetry CONFIG TelemetryController::enableTelemetryRecording - canRecordBase:true, canRecordExtended: false
[task 2020-04-22T21:34:48.849Z] 21:34:48 INFO - 1587591288833 Toolkit.Telemetry TRACE TelemetryController::observe - app-startup notified.
[task 2020-04-22T21:34:48.849Z] 21:34:48 INFO - 1587591288834 Toolkit.Telemetry CONFIG TelemetryController::enableTelemetryRecording - canRecordBase:true, canRecordExtended: false
[task 2020-04-22T21:34:48.925Z] 21:34:48 INFO - 1587591288883 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: compositor:created, aData: null
[task 2020-04-22T21:34:48.975Z] 21:34:48 INFO - 1587591288967 Toolkit.Telemetry TRACE TelemetrySession::observe - xul-window-visible notified.
[task 2020-04-22T21:34:49.327Z] 21:34:49 INFO - 1587591289320 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: browser-search-service, aData: geoip-lookup-xhr-starting
[task 2020-04-22T21:34:49.352Z] 21:34:49 INFO - 1587591289343 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: sessionstore-windows-restored, aData: null
[task 2020-04-22T21:34:49.352Z] 21:34:49 INFO - 1587591289344 Toolkit.Telemetry TRACE TelemetrySession::observe - sessionstore-windows-restored notified.
[task 2020-04-22T21:34:49.352Z] 21:34:49 INFO - 1587591289344 Toolkit.Telemetry TRACE TelemetrySession::gatherStartup
[task 2020-04-22T21:34:49.352Z] 21:34:49 INFO - 1587591289345 Toolkit.Telemetry INFO TelemetryReportingPolicy::get dataSubmissionPolicyNotifiedDate - No date stored yet.
[task 2020-04-22T21:34:49.352Z] 21:34:49 INFO - 1587591289345 Toolkit.Telemetry TRACE TelemetryReportingPolicy::_shouldNotify - User already notified or bypassing the policy.
[task 2020-04-22T21:34:49.879Z] 21:34:49 INFO - 1587591289864 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: browser-search-service, aData: settings-update-complete
[task 2020-04-22T21:34:49.945Z] 21:34:49 INFO - 1587591289938 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: browser-search-service, aData: geoip-lookup-xhr-complete
[task 2020-04-22T21:34:49.945Z] 21:34:49 INFO - 1587591289938 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: browser-search-service, aData: ensure-known-region-done
[task 2020-04-22T21:34:50.086Z] 21:34:50 INFO - 1587591290054 Toolkit.Telemetry TRACE TelemetryEnvironment::_startWatchingPrefs - [object Map]
[task 2020-04-22T21:34:50.125Z] 21:34:50 INFO - 1587591290112 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: extensions.webextensions.uuids
[task 2020-04-22T21:34:50.125Z] 21:34:50 INFO - 1587591290114 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: extensions.databaseSchema
[task 2020-04-22T21:34:50.142Z] 21:34:50 INFO - 1587591290129 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: distribution-customization-complete, aData: null
[task 2020-04-22T21:34:50.142Z] 21:34:50 INFO - 1587591290129 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.bookmarks.restore_default_bookmarks
[task 2020-04-22T21:34:50.146Z] 21:34:50 INFO - 1587591290141 Toolkit.Telemetry TRACE TelemetryEnvironment::_onEnvironmentChange for system-info
[task 2020-04-22T21:34:50.146Z] 21:34:50 INFO - 1587591290141 Toolkit.Telemetry DEBUG TelemetryEnvironment::_onEnvironmentChange - calling CrashAnnotator
[task 2020-04-22T21:34:50.146Z] 21:34:50 INFO - 1587591290142 Toolkit.Telemetry TRACE ClientID::_doLoadClientID

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• macosx1014-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1632324&startday=2020-04-20&endday=2020-04-26&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• linux1804-64-asan: 1
• macosx1014-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1632324&startday=2020-04-27&endday=2020-05-03&tree=all

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32-mingwclang: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1632324&startday=2020-05-18&endday=2020-05-24&tree=all

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform breakdown:

• windows7-32: 1
• linux1804-64-asan: 1
• macosx1014-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1632324&startday=2020-06-01&endday=2020-06-07&tree=all

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64-asan: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1632324&startday=2020-06-15&endday=2020-06-21&tree=all

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1632324&startday=2020-07-06&endday=2020-07-12&tree=all

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1632324&startday=2020-07-20&endday=2020-07-26&tree=all

Comment 8

[BugBot [:suhaib / :marco]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.