Closed Bug 1645058 Opened 4 years ago Closed 4 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | toolkit/components/telemetry/tests/unit/test_TelemetrySendOldPings.js | Test timed out

Categories

(Toolkit :: Telemetry, defect, P5)

Product:
Toolkit
Component:
Telemetry
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2020-06-11T14:09:22.886Z] 14:09:22 INFO - TEST-START | toolkit/components/telemetry/tests/unit/test_TelemetrySendOldPings.js

task 2020-06-11T14:11:04.741Z] 14:11:04 INFO - Failed to remove directory: c:\users\task_1591880307\appdata\local\temp\xpc-other-kiewvv. Waiting.
[task 2020-06-11T14:11:05.727Z] 14:11:05 INFO - rmtree() failed for "('c:\users\task_1591880307\appdata\local\temp\xpc-other-fm90sc',)". Reason: The process cannot access the file because it is being used by another process (13). Retrying...
[task 2020-06-11T14:11:07.725Z] 14:11:07 INFO - rmtree() failed for "('c:\users\task_1591880307\appdata\local\temp\xpc-other-fm90sc',)". Reason: The process cannot access the file because it is being used by another process (13). Retrying...
[task 2020-06-11T14:11:10.226Z] 14:11:10 INFO - Failed to remove directory: c:\users\task_1591880307\appdata\local\temp\xpc-other-fm90sc. Waiting.
[task 2020-06-11T14:12:02.121Z] 14:12:02 INFO - toolkit/crashreporter/test/unit_ipc/test_content_phc.js failed or timed out, will retry.
[task 2020-06-11T14:12:25.862Z] 14:12:25 INFO - toolkit/crashreporter/test/unit_ipc/test_content_phc3.js failed or timed out, will retry.
[task 2020-06-11T14:14:22.900Z] 14:14:22 WARNING - TEST-UNEXPECTED-TIMEOUT | toolkit/components/telemetry/tests/unit/test_TelemetrySendOldPings.js | Test timed out
[task 2020-06-11T14:14:22.900Z] 14:14:22 INFO - TEST-INFO took 300009ms
[task 2020-06-11T14:14:22.900Z] 14:14:22 INFO - >>>>>>>
[task 2020-06-11T14:14:22.901Z] 14:14:22 INFO - (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2020-06-11T14:14:22.901Z] 14:14:22 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2020-06-11T14:14:22.901Z] 14:14:22 INFO - (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2020-06-11T14:14:22.902Z] 14:14:22 INFO - running event loop
[task 2020-06-11T14:14:22.902Z] 14:14:22 INFO - toolkit/components/telemetry/tests/unit/test_TelemetrySendOldPings.js | Starting test_setup
[task 2020-06-11T14:14:22.902Z] 14:14:22 INFO - (xpcshell/head.js) | test test_setup pending (2)
[task 2020-06-11T14:14:22.902Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564022 addons.manager DEBUG Application has been upgraded"
[task 2020-06-11T14:14:22.902Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564023 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]"
[task 2020-06-11T14:14:22.902Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564045 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm"
[task 2020-06-11T14:14:22.903Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564053 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/PluginProvider.jsm"
[task 2020-06-11T14:14:22.903Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564054 addons.manager DEBUG Starting provider: XPIProvider"
[task 2020-06-11T14:14:22.903Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564054 addons.xpi DEBUG startup"
[task 2020-06-11T14:14:22.903Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564057 addons.xpi INFO SystemAddonLocation directory is missing"
[task 2020-06-11T14:14:22.903Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564117 addons.xpi INFO Removing all system add-on upgrades."
[task 2020-06-11T14:14:22.904Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564118 addons.xpi DEBUG checkForChanges"
[task 2020-06-11T14:14:22.904Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564119 addons.xpi DEBUG Loaded add-on state: ${}"
[task 2020-06-11T14:14:22.904Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564123 addons.xpi DEBUG New add-on tel-system-xpi@tests.mozilla.org in app-system-defaults"
[task 2020-06-11T14:14:22.904Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564127 addons.xpi WARN Can't get modified time of c:\users\task_1591880307\appdata\local\temp\xpc-profile-wgblty\sysfeatures\app0\tel-system-xpi@tests.mozilla.org.xpi"
[task 2020-06-11T14:14:22.904Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564128 addons.xpi DEBUG scanForChanges changed: true, state: {}"
[task 2020-06-11T14:14:22.905Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564173 addons.xpi-utils DEBUG Synchronously loading the add-ons database"
[task 2020-06-11T14:14:22.905Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564175 addons.xpi-utils DEBUG Starting async load of XPI database c:\users\task_1591880307\appdata\local\temp\xpc-profile-wgblty\extensions.json"
[task 2020-06-11T14:14:22.905Z] 14:14:22 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2020-06-11T14:14:22.905Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564377 addons.xpi-utils DEBUG New add-on tel-system-xpi@tests.mozilla.org installed in app-system-defaults"
[task 2020-06-11T14:14:22.905Z] 14:14:22 INFO - "CONSOLE_MESSAGE: (info) 1591884564386 addons.xpi-utils WARN addMetadata: Add-on tel-system-xpi@tests.mozilla.org is invalid: [Exception... "Component returned failure code: 0x80520012 (NS_ERROR_FILE_NOT_FOUND) [nsIFile.isFile]" nsresult: "0x80520012 (NS_ERROR_FILE_NOT_FOUND)" location: "JS frame :: resource://gre/modules/addons/XPIInstall.jsm :: get :: line 234" data: no] Stack trace: get()@resource://gre/modules/addons/XPIInstall.jsm:234
[task 2020-06-11T14:14:22.906Z] 14:14:22 INFO - syncLoadManifest()@resource://gre/modules/addons/XPIInstall.jsm:753
[task 2020-06-11T14:14:22.906Z] 14:14:22 INFO - addMetadata()@resource://gre/modules/addons/XPIDatabase.jsm:2811
[task 2020-06-11T14:14:22.906Z] 14:14:22 INFO - processFileChanges()@resource://gre/modules/addons/XPIDatabase.jsm:3255
[task 2020-06-11T14:14:22.906Z] 14:14:22 INFO - checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:2996
[task 2020-06-11T14:14:22.906Z] 14:14:22 INFO - startup()@resource://gre/modules/addons/XPIProvider.jsm:2456
[task 2020-06-11T14:14:22.906Z] 14:14:22 INFO - callProvider()@resource://gre/modules/AddonManager.jsm:212
[task 2020-06-11T14:14:22.907Z] 14:14:22 INFO - _startProvider()@resource://gre/modules/AddonManager.jsm:583
[task 2020-06-11T14:14:22.907Z] 14:14:22 INFO - startup()@resource://gre/modules/AddonManager.jsm:807
[task 2020-06-11T14:14:22.907Z] 14:14:22 INFO - startup()@resource://gre/modules/AddonManager.jsm:3462
[task 2020-06-11T14:14:22.907Z] 14:14:22 INFO - observe()@resource://gre/modules/addonManager.js:87

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.