Closed Bug 1768294 Opened 4 years ago Closed 4 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | toolkit/mozapps/extensions/test/xpcshell/test_system_profile_location.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: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=377216159&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/UWhO4bGQS4-MMsTmawcGMw/runs/0/artifacts/public/logs/live_backing.log

[task 2022-05-07T01:25:15.625Z] 01:25:15     INFO -  TEST-PASS | netwerk/test/unit_ipc/test_predictor_wrap.js | took 1137ms
[task 2022-05-07T01:25:15.636Z] 01:25:15     INFO -  TEST-START | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_system_profile_location.js
[task 2022-05-07T01:30:15.787Z] 01:30:15  WARNING -  TEST-UNEXPECTED-TIMEOUT | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_system_profile_location.js | Test timed out
[task 2022-05-07T01:30:15.787Z] 01:30:15     INFO -  TEST-INFO took 300150ms
[task 2022-05-07T01:30:15.787Z] 01:30:15     INFO -  >>>>>>>
[task 2022-05-07T01:30:15.788Z] 01:30:15     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2022-05-07T01:30:15.788Z] 01:30:15     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2022-05-07T01:30:15.788Z] 01:30:15     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2022-05-07T01:30:15.788Z] 01:30:15     INFO -  running event loop
[task 2022-05-07T01:30:15.789Z] 01:30:15     INFO -  xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_system_profile_location.js | Starting test_system_profile_location
[task 2022-05-07T01:30:15.789Z] 01:30:15     INFO -  (xpcshell/head.js) | test test_system_profile_location pending (2)
[task 2022-05-07T01:30:15.789Z] 01:30:15     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2022-05-07T01:30:15.790Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715774	addons.manager	DEBUG	Application has been upgraded"
[task 2022-05-07T01:30:15.790Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715775	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm"
[task 2022-05-07T01:30:15.791Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715775	addons.manager	DEBUG	Starting provider: XPIProvider"
[task 2022-05-07T01:30:15.791Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715775	addons.xpi	DEBUG	startup"
[task 2022-05-07T01:30:15.791Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715775	addons.xpi	INFO	SystemAddonLocation directory is missing"
[task 2022-05-07T01:30:15.792Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715778	addons.xpi	INFO	Removing all system add-on upgrades."
[task 2022-05-07T01:30:15.792Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715778	addons.xpi	DEBUG	checkForChanges"
[task 2022-05-07T01:30:15.793Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715778	addons.xpi	DEBUG	Loaded add-on state: ${}"
[task 2022-05-07T01:30:15.793Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715778	addons.xpi	DEBUG	scanForChanges changed: false, state: {}"
[task 2022-05-07T01:30:15.793Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715778	addons.xpi	DEBUG	Empty XPI database, setting schema version preference to 35"
[task 2022-05-07T01:30:15.793Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715778	addons.xpi	DEBUG	No changes found"
[task 2022-05-07T01:30:15.794Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715780	addons.manager	DEBUG	Registering shutdown blocker for XPIProvider"
[task 2022-05-07T01:30:15.794Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715780	addons.manager	DEBUG	Provider finished startup: XPIProvider"
[task 2022-05-07T01:30:15.795Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715780	addons.manager	DEBUG	Completed startup sequence"
[task 2022-05-07T01:30:15.795Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715801	addons.xpi-utils	WARN	Synchronous load of XPI database due to getAddonsByType([theme]) Stack: getAddonsByType@resource://gre/modules/addons/XPIDatabase.jsm:2246:21
[task 2022-05-07T01:30:15.795Z] 01:30:15     INFO -  installBuiltinAddon@resource://gre/modules/addons/XPIInstall.jsm:4468:24
[task 2022-05-07T01:30:15.795Z] 01:30:15     INFO -  _do_main@/opt/worker/tasks/task_1651885828/build/tests/xpcshell/head.js:240:6
[task 2022-05-07T01:30:15.796Z] 01:30:15     INFO -  _execute_test@/opt/worker/tasks/task_1651885828/build/tests/xpcshell/head.js:603:5
[task 2022-05-07T01:30:15.796Z] 01:30:15     INFO -  @-e:1:1
[task 2022-05-07T01:30:15.796Z] 01:30:15     INFO -  "
[task 2022-05-07T01:30:15.796Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715801	addons.xpi-utils	DEBUG	Synchronously loading the add-ons database"
[task 2022-05-07T01:30:15.797Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715801	addons.xpi-utils	DEBUG	Starting async load of XPI database /var/folders/v3/jkm3y7lx07l978ldjr_zxxym000014/T/xpc-profile-w5gd9b26/extensions.json"
[task 2022-05-07T01:30:15.797Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715801	addons.xpi-utils	DEBUG	Rebuilding XPI database with no extensions"
[task 2022-05-07T01:30:15.797Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715801	addons.xpi-utils	DEBUG	Make addon app-builtin:default-theme@mozilla.org visible"
[task 2022-05-07T01:30:15.798Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715801	addons.xpi	DEBUG	XPIStates adding add-on default-theme@mozilla.org in {"addons":{},"staged":{}}: null"
[task 2022-05-07T01:30:15.800Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715802	addons.xpi	DEBUG	Updating XPIState for {"id":"default-theme@mozilla.org","syncGUID":"{0df255ab-bc7f-49f6-bbf3-507b4c96928c}","version":"1.3","type":"theme","loader":null,"updateURL":null,"installOrigins":null,"manifestVersion":2,"optionsURL":null,"optionsType":null,"optionsBrowserStyle":true,"aboutURL":null,"defaultLocale":{"name":"System theme — auto","description":"Follow the operating system setting for buttons, menus, and windows.","creator":"Mozilla","developers":null,"translators":null,"contributors":null},"visible":true,"active":true,"userDisabled":false,"appDisabled":false,"embedderDisabled":false,"installDate":1651886715801,"applyBackgroundUpdates":1,"path":null,"skinnable":false,"sourceURI":null,"releaseNotesURI":null,"softDisabled":false,"foreignInstall":false,"strictCompatibility":true,"locales":[],"targetApplications":[{"id":"toolkit@mozilla.org","minVersion":null,"maxVersion":null}],"targetPlatforms":[],"signedDate":null,"seen":true,"dependencies":[],"userPermissions":null,"optionalPermissions":null,"icons":{"32":"icon.svg"},"iconURL":null,"blocklistState":0,"blocklistURL":null,"startupData":null,"hidden":false,"installTelemetryInfo":null,"recommendationState":null,"rootURI":"resource://default-theme/","location":"app-builtin"}"
[task 2022-05-07T01:30:15.800Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715802	addons.xpi	DEBUG	Loading bootstrap scope from resource://default-theme/"
[task 2022-05-07T01:30:15.802Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715802	addons.xpi	DEBUG	Calling bootstrap method install on default-theme@mozilla.org version 1.3"
[task 2022-05-07T01:30:15.802Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715802	addons.xpi	DEBUG	Calling bootstrap method startup on default-theme@mozilla.org version 1.3"
[task 2022-05-07T01:30:15.803Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715842	addons.repository	DEBUG	cacheAddons: enabled false IDs ["system@tests.mozilla.org"]"
[task 2022-05-07T01:30:15.804Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715842	addons.xpi	DEBUG	Starting install of system@tests.mozilla.org from file:///var/folders/v3/jkm3y7lx07l978ldjr_zxxym000014/T/xpc-profile-w5gd9b26/temp/generated-extension.xpi"
[task 2022-05-07T01:30:15.804Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715844	addons.xpi-utils	DEBUG	XPI Database saved, setting schema version preference to 35"
[task 2022-05-07T01:30:15.805Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715844	addons.xpi	DEBUG	Addon system@tests.mozilla.org will be installed as a packed xpi"
[task 2022-05-07T01:30:15.805Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715851	addons.xpi-utils	DEBUG	Make addon app-system-profile:system@tests.mozilla.org visible"
[task 2022-05-07T01:30:15.805Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715851	addons.xpi	DEBUG	XPIStates adding add-on system@tests.mozilla.org in {"addons":{},"staged":{},"path":"/var/folders/v3/jkm3y7lx07l978ldjr_zxxym000014/T/xpc-profile-w5gd9b26/system-extensions"}: /var/folders/v3/jkm3y7lx07l978ldjr_zxxym000014/T/xpc-profile-w5gd9b26/system-extensions/system@tests.mozilla.org.xpi"
[task 2022-05-07T01:30:15.807Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715851	addons.xpi	DEBUG	Updating XPIState for {"id":"system@tests.mozilla.org","syncGUID":"{77f19b1f-1d0b-4a93-b38f-624e77ddd14d}","version":"1.0","type":"extension","loader":null,"updateURL":null,"installOrigins":null,"manifestVersion":2,"optionsURL":null,"optionsType":null,"optionsBrowserStyle":true,"aboutURL":null,"defaultLocale":{"name":"Generated extension","creator":null,"developers":null,"translators":null,"contributors":null},"visible":true,"active":true,"userDisabled":false,"appDisabled":false,"embedderDisabled":false,"installDate":1651886715842,"updateDate":1651886715842,"applyBackgroundUpdates":1,"path":"/var/folders/v3/jkm3y7lx07l978ldjr_zxxym000014/T/xpc-profile-w5gd9b26/system-extensions/system@tests.mozilla.org.xpi","skinnable":false,"sourceURI":"file:///var/folders/v3/jkm3y7lx07l978ldjr_zxxym000014/T/xpc-profile-w5gd9b26/temp/generated-extension.xpi","releaseNotesURI":null,"softDisabled":false,"foreignInstall":false,"strictCompatibility":true,"locales":[],"targetApplications":[{"id":"toolkit@mozilla.org","minVersion":null,"maxVersion":null}],"targetPlatforms":[],"signedState":4,"signedDate":null,"seen":true,"dependencies":[],"incognito":"spanning","userPermissions":{"permissions":[],"origins":[]},"optionalPermissions":{"permissions":[],"origins":[]},"icons":{},"iconURL":null,"blocklistState":0,"blocklistURL":null,"startupData":null,"hidden":false,"installTelemetryInfo":null,"recommendationState":null,"rootURI":"jar:file:///var/folders/v3/jkm3y7lx07l978ldjr_zxxym000014/T/xpc-profile-w5gd9b26/system-extensions/system@tests.mozilla.org.xpi!/","location":"app-system-profile"}"
[task 2022-05-07T01:30:15.808Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715851	addons.xpi	DEBUG	Install of file:///var/folders/v3/jkm3y7lx07l978ldjr_zxxym000014/T/xpc-profile-w5gd9b26/temp/generated-extension.xpi completed."
[task 2022-05-07T01:30:15.808Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715851	addons.xpi	DEBUG	Loading bootstrap scope from jar:file:///var/folders/v3/jkm3y7lx07l978ldjr_zxxym000014/T/xpc-profile-w5gd9b26/system-extensions/system@tests.mozilla.org.xpi!/"
[task 2022-05-07T01:30:15.808Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715852	addons.xpi	DEBUG	Calling bootstrap method install on system@tests.mozilla.org version 1.0"
[task 2022-05-07T01:30:15.808Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715852	addons.xpi	DEBUG	Calling bootstrap method startup on system@tests.mozilla.org version 1.0"
[task 2022-05-07T01:30:15.809Z] 01:30:15     INFO -  "Extension attached"
[task 2022-05-07T01:30:15.809Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715924	addons.manager	DEBUG	Starting provider: GMPProvider"
[task 2022-05-07T01:30:15.809Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715925	addons.manager	DEBUG	Registering shutdown blocker for GMPProvider"
[task 2022-05-07T01:30:15.809Z] 01:30:15     INFO -  "CONSOLE_MESSAGE: (info) 1651886715925	addons.manager	DEBUG	Provider finished startup: GMPProvider"
[task 2022-05-07T01:30:15.809Z] 01:30:15     INFO -  TEST-PASS | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_system_profile_location.js | test_system_profile_location - [test_system_profile_location : 53] Addon is installed - {} != null

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

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