Closed Bug 1640359 Opened 5 years ago Closed 5 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | services/sync/tests/unit/test_service_sync_remoteSetup.js | Test timed out

Categories

(Firefox :: Sync, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2020-05-23T00:52:57.929Z] 00:52:57 INFO - TEST-START | services/sync/tests/unit/test_service_sync_remoteSetup.js
[task 2020-05-23T00:57:57.926Z] 00:57:57 WARNING - TEST-UNEXPECTED-TIMEOUT | services/sync/tests/unit/test_service_sync_remoteSetup.js | Test timed out
[task 2020-05-23T00:57:57.927Z] 00:57:57 INFO - TEST-INFO took 300000ms
[task 2020-05-23T00:57:57.927Z] 00:57:57 INFO - >>>>>>>
[task 2020-05-23T00:57:57.928Z] 00:57:57 INFO - (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2020-05-23T00:57:57.928Z] 00:57:57 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2020-05-23T00:57:57.929Z] 00:57:57 INFO - (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2020-05-23T00:57:57.929Z] 00:57:57 INFO - running event loop
[task 2020-05-23T00:57:57.930Z] 00:57:57 INFO - services/sync/tests/unit/test_service_sync_remoteSetup.js | Starting head_setup
[task 2020-05-23T00:57:57.930Z] 00:57:57 INFO - (xpcshell/head.js) | test head_setup pending (2)
[task 2020-05-23T00:57:57.930Z] 00:57:57 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2020-05-23T00:57:57.931Z] 00:57:57 INFO - PID 11868 | 1590195179263 Sync.Engine.Addons DEBUG SyncEngine initialized: addons
[task 2020-05-23T00:57:57.931Z] 00:57:57 INFO - PID 11868 | 1590195179264 Sync.AddonsReconciler TRACE Loading json from disk: addonsreconciler
[task 2020-05-23T00:57:57.932Z] 00:57:57 INFO - PID 11868 | 1590195179265 Sync.AddonsReconciler DEBUG No data seen in loaded file: addonsreconciler
[task 2020-05-23T00:57:57.932Z] 00:57:57 INFO - PID 11868 | 1590195179273 Sync.Engine.Forms DEBUG Engine constructed
[task 2020-05-23T00:57:57.933Z] 00:57:57 INFO - PID 11868 | 1590195179280 Sync.Engine.Forms DEBUG SyncEngine initialized: forms
[task 2020-05-23T00:57:57.933Z] 00:57:57 INFO - PID 11868 | 1590195179294 Sync.Engine.History DEBUG Engine constructed
[task 2020-05-23T00:57:57.933Z] 00:57:57 INFO - PID 11868 | 1590195179300 Sync.Engine.History DEBUG SyncEngine initialized: history
[task 2020-05-23T00:57:57.933Z] 00:57:57 INFO - PID 11868 | 1590195179311 Sync.Engine.Passwords DEBUG Engine constructed
[task 2020-05-23T00:57:57.933Z] 00:57:57 INFO - PID 11868 | 1590195179316 Sync.Engine.Passwords DEBUG SyncEngine initialized: passwords
[task 2020-05-23T00:57:57.933Z] 00:57:57 INFO - PID 11868 | 1590195179325 Sync.Engine.Prefs DEBUG Engine constructed
[task 2020-05-23T00:57:57.934Z] 00:57:57 INFO - PID 11868 | 1590195179330 Sync.Engine.Prefs DEBUG SyncEngine initialized: prefs
[task 2020-05-23T00:57:57.934Z] 00:57:57 INFO - PID 11868 | 1590195179337 Sync.Engine.Tabs DEBUG Engine constructed
[task 2020-05-23T00:57:57.935Z] 00:57:57 INFO - PID 11868 | 1590195179341 Sync.Engine.Tabs DEBUG SyncEngine initialized: tabs
[task 2020-05-23T00:57:57.935Z] 00:57:57 INFO - PID 11868 | 1590195179342 Sync.Engine.Tabs DEBUG Resetting tabs last sync time
[task 2020-05-23T00:57:57.936Z] 00:57:57 INFO - PID 11868 | 1590195179358 Sync.Engine.Bookmarks DEBUG Engine constructed
[task 2020-05-23T00:57:57.936Z] 00:57:57 INFO - PID 11868 | 1590195179364 Sync.Engine.Bookmarks DEBUG SyncEngine initialized: bookmarks
[task 2020-05-23T00:57:57.936Z] 00:57:57 INFO - PID 11868 | 1590195179375 Sync.Engine.Extension-Storage DEBUG Engine constructed
[task 2020-05-23T00:57:57.937Z] 00:57:57 INFO - PID 11868 | 1590195179379 Sync.Engine.Extension-Storage DEBUG SyncEngine initialized: extension-storage
[task 2020-05-23T00:57:57.937Z] 00:57:57 INFO - PID 11868 | 1590195179381 Sync.Service INFO Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:78.0) Gecko/20100101 XPCShell/1
[task 2020-05-23T00:57:57.938Z] 00:57:57 INFO - PID 11868 | 1590195179421 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
[task 2020-05-23T00:57:57.938Z] 00:57:57 INFO - PID 11868 | 1590195179421 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
[task 2020-05-23T00:57:57.939Z] 00:57:57 INFO - PID 11868 | 1590195179421 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
[task 2020-05-23T00:57:57.939Z] 00:57:57 INFO - (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2020-05-23T00:57:57.939Z] 00:57:57 INFO - (xpcshell/head.js) | test head_setup finished (2)
[task 2020-05-23T00:57:57.940Z] 00:57:57 INFO - PID 11868 | 1590195179427 Sync.SyncScheduler TRACE Handling weave:service:ready
[task 2020-05-23T00:57:57.940Z] 00:57:57 INFO - PID 11868 | 1590195179428 Sync.Telemetry TRACE observed weave:service:ready null
[task 2020-05-23T00:57:57.941Z] 00:57:57 INFO - PID 11868 | 1590195179428 Sync.Telemetry TRACE Not sending telemetry ping for self-hosted Sync user
[task 2020-05-23T00:57:57.941Z] 00:57:57 INFO - services/sync/tests/unit/test_service_sync_remoteSetup.js | Starting run_test
[task 2020-05-23T00:57:57.942Z] 00:57:57 INFO - (xpcshell/head.js) | test run_test pending (2)
[task 2020-05-23T00:57:57.942Z] 00:57:57 INFO - (xpcshell/head.js) | test pending (3)
[task 2020-05-23T00:57:57.943Z] 00:57:57 INFO - PID 11868 | Checking Status.sync with no credentials.
[task 2020-05-23T00:57:57.943Z] 00:57:57 INFO - PID 11868 | 1590195179437 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys.
[task 2020-05-23T00:57:57.943Z] 00:57:57 INFO - PID 11868 | 1590195179437 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys.
[task 2020-05-23T00:57:57.944Z] 00:57:57 INFO - PID 11868 | 1590195179438 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_recoverykey
[task 2020-05-23T00:57:57.944Z] 00:57:57 INFO - PID 11868 | 1590195179438 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_recoverykey
[task 2020-05-23T00:57:57.945Z] 00:57:57 INFO - PID 11868 | 1590195179438 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
[task 2020-05-23T00:57:57.945Z] 00:57:57 INFO - PID 11868 | 1590195179438 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
[task 2020-05-23T00:57:57.946Z] 00:57:57 INFO - PID 11868 | 1590195179438 Sync.Status DEBUG Status.sync: success.sync => error.sync.reason.credentials_changed
[task 2020-05-23T00:57:57.946Z] 00:57:57 INFO - PID 11868 | 1590195179438 Sync.Status DEBUG Status.sync: success.sync => error.sync.reason.credentials_changed
[task 2020-05-23T00:57:57.946Z] 00:57:57 INFO - PID 11868 | 1590195179439 Sync.Status DEBUG Status.service: service.client_not_configured => error.sync.failed
[task 2020-05-23T00:57:57.947Z] 00:57:57 INFO - PID 11868 | 1590195179439 Sync.Status DEBUG Status.service: service.client_not_configured => error.sync.failed
[task 2020-05-23T00:57:57.947Z] 00:57:57 INFO - (xpcshell/head.js) | test run_next_test 1 finished (3)
[task 2020-05-23T00:57:57.947Z] 00:57:57 INFO - TEST-PASS | services/sync/tests/unit/test_service_sync_remoteSetup.js | run_test - [run_test : 80] "error.sync.reason.credentials_changed" == "error.sync.reason.credentials_changed"
[task 2020-05-23T00:57:57.947Z] 00:57:57 INFO - TEST-PASS | services/sync/tests/unit/test_service_sync_remoteSetup.js | run_test - [run_test : 81] "error.login.reason.no_recoverykey" == "error.login.reason.no_recoverykey"

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.