Closed Bug 1445869 Opened 8 years ago Closed 8 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | toolkit/mozapps/extensions/test/xpcshell/test_system_update_custom.js | Test timed out

Categories

(Toolkit :: Add-ons Manager, defect, P5)

Product:
Toolkit
Component:
Add-ons Manager
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=167915987&repo=mozilla-central https://queue.taskcluster.net/v1/task/VcGKuBU8QiycnV08DWYckw/runs/0/artifacts/public/logs/live_backing.log [task 2018-03-14T12:50:29.878Z] 12:50:29 INFO - TEST-START | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_system_update_custom.js [task 2018-03-14T12:55:29.876Z] 12:55:29 WARNING - TEST-UNEXPECTED-TIMEOUT | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_system_update_custom.js | Test timed out [task 2018-03-14T12:55:29.878Z] 12:55:29 INFO - TEST-INFO took 300000ms [task 2018-03-14T12:55:29.879Z] 12:55:29 INFO - >>>>>>> [task 2018-03-14T12:55:29.879Z] 12:55:29 INFO - (xpcshell/head.js) | test MAIN run_test pending (1) [task 2018-03-14T12:55:29.880Z] 12:55:29 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2) [task 2018-03-14T12:55:29.881Z] 12:55:29 INFO - (xpcshell/head.js) | test MAIN run_test finished (2) [task 2018-03-14T12:55:29.881Z] 12:55:29 INFO - running event loop [task 2018-03-14T12:55:29.882Z] 12:55:29 INFO - xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_system_update_custom.js | Starting test_addon_update [task 2018-03-14T12:55:29.883Z] 12:55:29 INFO - (xpcshell/head.js) | test test_addon_update pending (2) [task 2018-03-14T12:55:29.884Z] 12:55:29 INFO - "Clearing existing database." [task 2018-03-14T12:55:29.884Z] 12:55:29 INFO - PID 15341 | 1521031830448 addons.manager DEBUG Application has been upgraded [task 2018-03-14T12:55:29.885Z] 12:55:29 INFO - PID 15341 | 1521031830552 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"] [task 2018-03-14T12:55:29.888Z] 12:55:29 INFO - PID 15341 | 1521031830562 addons.manager DEBUG Loaded provider scope for resource://gre/modules/LightweightThemeManager.jsm: ["LightweightThemeManager"] [task 2018-03-14T12:55:29.889Z] 12:55:29 INFO - PID 15341 | 1521031830580 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm [task 2018-03-14T12:55:29.890Z] 12:55:29 INFO - PID 15341 | 1521031830588 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/PluginProvider.jsm [task 2018-03-14T12:55:29.892Z] 12:55:29 INFO - PID 15341 | 1521031830588 addons.manager DEBUG Starting provider: XPIProvider [task 2018-03-14T12:55:29.893Z] 12:55:29 INFO - PID 15341 | 1521031830590 addons.xpi DEBUG startup [task 2018-03-14T12:55:29.894Z] 12:55:29 INFO - PID 15341 | 1521031830590 addons.xpi INFO SystemAddonInstallLocation directory is missing [task 2018-03-14T12:55:29.895Z] 12:55:29 INFO - PID 15341 | 1521031830592 addons.xpi INFO Removing all system add-on upgrades. [task 2018-03-14T12:55:29.896Z] 12:55:29 INFO - PID 15341 | 1521031830592 addons.xpi DEBUG checkForChanges [task 2018-03-14T12:55:29.896Z] 12:55:29 INFO - PID 15341 | 1521031830594 addons.xpi DEBUG Loaded add-on state: ${} [task 2018-03-14T12:55:29.897Z] 12:55:29 INFO - PID 15341 | 1521031830596 addons.xpi DEBUG getInstallState changed: false, state: {} [task 2018-03-14T12:55:29.898Z] 12:55:29 INFO - PID 15341 | 1521031830598 addons.xpi INFO SystemAddonInstallLocation directory is missing [task 2018-03-14T12:55:29.899Z] 12:55:29 INFO - PID 15341 | 1521031830600 addons.xpi DEBUG Empty XPI database, setting schema version preference to 24 [task 2018-03-14T12:55:29.900Z] 12:55:29 INFO - PID 15341 | 1521031830600 addons.xpi DEBUG No changes found [task 2018-03-14T12:55:29.901Z] 12:55:29 INFO - PID 15341 | 1521031830608 addons.manager DEBUG Registering shutdown blocker for XPIProvider [task 2018-03-14T12:55:29.902Z] 12:55:29 INFO - PID 15341 | 1521031830608 addons.manager DEBUG Provider finished startup: XPIProvider [task 2018-03-14T12:55:29.903Z] 12:55:29 INFO - PID 15341 | 1521031830610 addons.manager DEBUG Starting provider: LightweightThemeManager [task 2018-03-14T12:55:29.905Z] 12:55:29 INFO - PID 15341 | 1521031830610 addons.manager DEBUG Registering shutdown blocker for LightweightThemeManager [task 2018-03-14T12:55:29.906Z] 12:55:29 INFO - PID 15341 | 1521031830610 addons.manager DEBUG Provider finished startup: LightweightThemeManager [task 2018-03-14T12:55:29.907Z] 12:55:29 INFO - PID 15341 | 1521031830612 addons.manager DEBUG Starting provider: GMPProvider [task 2018-03-14T12:55:29.908Z] 12:55:29 INFO - PID 15341 | 1521031830616 addons.manager DEBUG Registering shutdown blocker for GMPProvider [task 2018-03-14T12:55:29.909Z] 12:55:29 INFO - PID 15341 | 1521031830618 addons.manager DEBUG Provider finished startup: GMPProvider [task 2018-03-14T12:55:29.911Z] 12:55:29 INFO - PID 15341 | 1521031830618 addons.manager DEBUG Starting provider: PluginProvider [task 2018-03-14T12:55:29.912Z] 12:55:29 INFO - PID 15341 | 1521031830618 addons.manager DEBUG Registering shutdown blocker for PluginProvider [task 2018-03-14T12:55:29.913Z] 12:55:29 INFO - PID 15341 | 1521031830620 addons.manager DEBUG Provider finished startup: PluginProvider [task 2018-03-14T12:55:29.914Z] 12:55:29 INFO - PID 15341 | 1521031830630 addons.manager DEBUG Completed startup sequence [task 2018-03-14T12:55:29.915Z] 12:55:29 INFO - PID 15341 | 1521031830660 addons.manager DEBUG shutdown [task 2018-03-14T12:55:29.916Z] 12:55:29 INFO - PID 15341 | 1521031830660 addons.manager DEBUG Calling shutdown blocker for XPIProvider [task 2018-03-14T12:55:29.917Z] 12:55:29 INFO - PID 15341 | 1521031830660 addons.xpi DEBUG shutdown [task 2018-03-14T12:55:29.918Z] 12:55:29 INFO - PID 15341 | 1521031830680 addons.xpi-utils DEBUG Starting async load of XPI database /tmp/xpc-profile-pkQ6zf/extensions.json [task 2018-03-14T12:55:29.919Z] 12:55:29 INFO - PID 15341 | 1521031830682 addons.manager DEBUG Calling shutdown blocker for LightweightThemeManager [task 2018-03-14T12:55:29.920Z] 12:55:29 INFO - PID 15341 | 1521031830682 addons.manager DEBUG Calling shutdown blocker for GMPProvider [task 2018-03-14T12:55:29.921Z] 12:55:29 INFO - PID 15341 | 1521031830682 addons.manager DEBUG Calling shutdown blocker for PluginProvider [task 2018-03-14T12:55:29.921Z] 12:55:29 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2) [task 2018-03-14T12:55:29.922Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830448 addons.manager DEBUG Application has been upgraded" [task 2018-03-14T12:55:29.923Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830552 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]" [task 2018-03-14T12:55:29.924Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830562 addons.manager DEBUG Loaded provider scope for resource://gre/modules/LightweightThemeManager.jsm: ["LightweightThemeManager"]" [task 2018-03-14T12:55:29.925Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830580 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm" [task 2018-03-14T12:55:29.926Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830588 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/PluginProvider.jsm" [task 2018-03-14T12:55:29.927Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830588 addons.manager DEBUG Starting provider: XPIProvider" [task 2018-03-14T12:55:29.928Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830590 addons.xpi DEBUG startup" [task 2018-03-14T12:55:29.929Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830590 addons.xpi INFO SystemAddonInstallLocation directory is missing" [task 2018-03-14T12:55:29.929Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830592 addons.xpi INFO Removing all system add-on upgrades." [task 2018-03-14T12:55:29.930Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830592 addons.xpi DEBUG checkForChanges" [task 2018-03-14T12:55:29.931Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830594 addons.xpi DEBUG Loaded add-on state: ${}" [task 2018-03-14T12:55:29.932Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830596 addons.xpi DEBUG getInstallState changed: false, state: {}" [task 2018-03-14T12:55:29.933Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830598 addons.xpi INFO SystemAddonInstallLocation directory is missing" [task 2018-03-14T12:55:29.934Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830600 addons.xpi DEBUG Empty XPI database, setting schema version preference to 24" [task 2018-03-14T12:55:29.935Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830600 addons.xpi DEBUG No changes found" [task 2018-03-14T12:55:29.936Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830608 addons.manager DEBUG Registering shutdown blocker for XPIProvider" [task 2018-03-14T12:55:29.937Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830608 addons.manager DEBUG Provider finished startup: XPIProvider" [task 2018-03-14T12:55:29.938Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830610 addons.manager DEBUG Starting provider: LightweightThemeManager" [task 2018-03-14T12:55:29.939Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830610 addons.manager DEBUG Registering shutdown blocker for LightweightThemeManager" [task 2018-03-14T12:55:29.940Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830610 addons.manager DEBUG Provider finished startup: LightweightThemeManager" [task 2018-03-14T12:55:29.941Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830612 addons.manager DEBUG Starting provider: GMPProvider" [task 2018-03-14T12:55:29.942Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830616 addons.manager DEBUG Registering shutdown blocker for GMPProvider" [task 2018-03-14T12:55:29.943Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830618 addons.manager DEBUG Provider finished startup: GMPProvider" [task 2018-03-14T12:55:29.944Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830618 addons.manager DEBUG Starting provider: PluginProvider" [task 2018-03-14T12:55:29.945Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830618 addons.manager DEBUG Registering shutdown blocker for PluginProvider" [task 2018-03-14T12:55:29.946Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830620 addons.manager DEBUG Provider finished startup: PluginProvider" [task 2018-03-14T12:55:29.947Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830630 addons.manager DEBUG Completed startup sequence" [task 2018-03-14T12:55:29.948Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830660 addons.manager DEBUG shutdown" [task 2018-03-14T12:55:29.949Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830660 addons.manager DEBUG Calling shutdown blocker for XPIProvider" [task 2018-03-14T12:55:29.950Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830660 addons.xpi DEBUG shutdown" [task 2018-03-14T12:55:29.951Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830680 addons.xpi-utils DEBUG Starting async load of XPI database /tmp/xpc-profile-pkQ6zf/extensions.json" [task 2018-03-14T12:55:29.952Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830682 addons.manager DEBUG Calling shutdown blocker for LightweightThemeManager" [task 2018-03-14T12:55:29.953Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830682 addons.manager DEBUG Calling shutdown blocker for GMPProvider" [task 2018-03-14T12:55:29.954Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830682 addons.manager DEBUG Calling shutdown blocker for PluginProvider" [task 2018-03-14T12:55:29.955Z] 12:55:29 INFO - PID 15341 | 1521031830834 addons.xpi-utils DEBUG Rebuilding XPI database with no extensions [task 2018-03-14T12:55:29.956Z] 12:55:29 INFO - PID 15341 | 1521031830836 addons.xpi-utils DEBUG shutdown [task 2018-03-14T12:55:29.957Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830834 addons.xpi-utils DEBUG Rebuilding XPI database with no extensions" [task 2018-03-14T12:55:29.958Z] 12:55:29 INFO - "CONSOLE_MESSAGE: (info) 1521031830836 addons.xpi-utils DEBUG shutdown" [task 2018-03-14T12:55:29.959Z] 12:55:29 INFO - PID 15341 | 1521031830838 addons.manager DEBUG Async provider shutdown done [task 2018-03-14T12:55:29.960Z] 12:55:29 INFO - TEST-PASS | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_system_update_custom.js | test_addon_update - [test_addon_update : 225] 0 == 0
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 8 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.