Closed Bug 1656088 Opened 5 years ago Closed 5 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | browser/extensions/formautofill/test/unit/heuristics/third_party/test_HomeDepot.js | Test timed out

Categories

(Toolkit :: Form Autofill, defect, P5)

Product:
Toolkit
Component:
Form Autofill
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2020-07-29T18:03:58.624Z] 18:03:58 INFO - TEST-START | browser/extensions/formautofill/test/unit/heuristics/third_party/test_HomeDepot.js
[task 2020-07-29T18:08:58.627Z] 18:08:58 WARNING - TEST-UNEXPECTED-TIMEOUT | browser/extensions/formautofill/test/unit/heuristics/third_party/test_HomeDepot.js | Test timed out
[task 2020-07-29T18:08:58.627Z] 18:08:58 INFO - TEST-INFO took 300001ms
[task 2020-07-29T18:08:58.629Z] 18:08:58 INFO - >>>>>>>
[task 2020-07-29T18:08:58.629Z] 18:08:58 INFO - PID 5729 | [CodeCoverage] Setting handlers for process 5729.
[task 2020-07-29T18:08:58.630Z] 18:08:58 INFO - (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2020-07-29T18:08:58.630Z] 18:08:58 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2020-07-29T18:08:58.630Z] 18:08:58 INFO - (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2020-07-29T18:08:58.630Z] 18:08:58 INFO - running event loop
[task 2020-07-29T18:08:58.630Z] 18:08:58 INFO - browser/extensions/formautofill/test/unit/heuristics/third_party/test_HomeDepot.js | Starting head_initialize
[task 2020-07-29T18:08:58.630Z] 18:08:58 INFO - (xpcshell/head.js) | test head_initialize pending (2)
[task 2020-07-29T18:08:58.630Z] 18:08:58 INFO - "CONSOLE_MESSAGE: (info) 1596045839995 addons.manager DEBUG Application has been upgraded"
[task 2020-07-29T18:08:58.631Z] 18:08:58 INFO - "CONSOLE_MESSAGE: (info) 1596045839996 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]"
[task 2020-07-29T18:08:58.631Z] 18:08:58 INFO - "CONSOLE_MESSAGE: (info) 1596045840015 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm"
[task 2020-07-29T18:08:58.631Z] 18:08:58 INFO - "CONSOLE_MESSAGE: (info) 1596045840023 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/PluginProvider.jsm"
[task 2020-07-29T18:08:58.631Z] 18:08:58 INFO - "CONSOLE_MESSAGE: (info) 1596045840024 addons.manager DEBUG Starting provider: XPIProvider"
[task 2020-07-29T18:08:58.631Z] 18:08:58 INFO - "CONSOLE_MESSAGE: (info) 1596045840024 addons.xpi DEBUG startup"
[task 2020-07-29T18:08:58.631Z] 18:08:58 INFO - "CONSOLE_MESSAGE: (info) 1596045840026 addons.xpi INFO SystemAddonLocation directory is missing"
[task 2020-07-29T18:08:58.631Z] 18:08:58 INFO - "CONSOLE_MESSAGE: (info) 1596045840075 addons.xpi INFO Removing all system add-on upgrades."
[task 2020-07-29T18:08:58.632Z] 18:08:58 INFO - "CONSOLE_MESSAGE: (info) 1596045840076 addons.xpi DEBUG checkForChanges"
[task 2020-07-29T18:08:58.632Z] 18:08:58 INFO - "CONSOLE_MESSAGE: (info) 1596045840077 addons.xpi DEBUG Loaded add-on state: ${}"
[task 2020-07-29T18:08:58.632Z] 18:08:58 INFO - "CONSOLE_MESSAGE: (info) 1596045840079 addons.xpi DEBUG scanForChanges changed: false, state: {}"
[task 2020-07-29T18:08:58.632Z] 18:08:58 INFO - "CONSOLE_MESSAGE: (info) 1596045840081 addons.xpi DEBUG Empty XPI database, setting schema version preference to 32"
[task 2020-07-29T18:08:58.632Z] 18:08:58 INFO - "CONSOLE_MESSAGE: (info) 1596045840081 addons.xpi DEBUG No changes found"
[task 2020-07-29T18:08:58.632Z] 18:08:58 INFO - "CONSOLE_MESSAGE: (info) 1596045840132 addons.xpi-utils DEBUG Synchronously loading the add-ons database"
[task 2020-07-29T18:08:58.632Z] 18:08:58 INFO - "CONSOLE_MESSAGE: (info) 1596045840133 addons.xpi-utils DEBUG Starting async load of XPI database /tmp/xpc-profile-QTLHO2/extensions.json"
[task 2020-07-29T18:08:58.633Z] 18:08:58 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2)

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

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=315236384&repo=mozilla-central&lineNumber=3763

[task 2020-09-09T19:19:16.517Z] 19:19:16 INFO - TEST-START | browser/extensions/formautofill/test/unit/heuristics/third_party/test_HomeDepot.js
[task 2020-09-09T19:24:16.516Z] 19:24:16 WARNING - TEST-UNEXPECTED-TIMEOUT | browser/extensions/formautofill/test/unit/heuristics/third_party/test_HomeDepot.js | Test timed out
[task 2020-09-09T19:24:16.517Z] 19:24:16 INFO - TEST-INFO took 300000ms
[task 2020-09-09T19:24:16.517Z] 19:24:16 INFO - >>>>>>>
[task 2020-09-09T19:24:16.517Z] 19:24:16 INFO - PID 26473 | [CodeCoverage] Setting handlers for process 26473.
[task 2020-09-09T19:24:16.517Z] 19:24:16 INFO - (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2020-09-09T19:24:16.518Z] 19:24:16 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2020-09-09T19:24:16.518Z] 19:24:16 INFO - (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2020-09-09T19:24:16.518Z] 19:24:16 INFO - running event loop
[task 2020-09-09T19:24:16.518Z] 19:24:16 INFO - browser/extensions/formautofill/test/unit/heuristics/third_party/test_HomeDepot.js | Starting head_initialize
[task 2020-09-09T19:24:16.527Z] 19:24:16 INFO - (xpcshell/head.js) | test head_initialize pending (2)
[task 2020-09-09T19:24:16.527Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679157830 addons.manager DEBUG Application has been upgraded"
[task 2020-09-09T19:24:16.527Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679157831 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]"
[task 2020-09-09T19:24:16.527Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679157849 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm"
[task 2020-09-09T19:24:16.527Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679157857 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/PluginProvider.jsm"
[task 2020-09-09T19:24:16.527Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679157857 addons.manager DEBUG Starting provider: XPIProvider"
[task 2020-09-09T19:24:16.527Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679157858 addons.xpi DEBUG startup"
[task 2020-09-09T19:24:16.527Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679157859 addons.xpi INFO SystemAddonLocation directory is missing"
[task 2020-09-09T19:24:16.527Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679157906 addons.xpi INFO Removing all system add-on upgrades."
[task 2020-09-09T19:24:16.527Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679157907 addons.xpi DEBUG checkForChanges"
[task 2020-09-09T19:24:16.527Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679157907 addons.xpi DEBUG Loaded add-on state: ${}"
[task 2020-09-09T19:24:16.527Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679157910 addons.xpi DEBUG scanForChanges changed: false, state: {}"
[task 2020-09-09T19:24:16.527Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679157911 addons.xpi DEBUG Empty XPI database, setting schema version preference to 32"
[task 2020-09-09T19:24:16.527Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679157911 addons.xpi DEBUG No changes found"
[task 2020-09-09T19:24:16.527Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679157959 addons.xpi-utils DEBUG Synchronously loading the add-ons database"
[task 2020-09-09T19:24:16.528Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679157959 addons.xpi-utils DEBUG Starting async load of XPI database /tmp/xpc-profile-5i66pP/extensions.json"
[task 2020-09-09T19:24:16.528Z] 19:24:16 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2020-09-09T19:24:16.528Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679158071 addons.xpi-utils DEBUG Rebuilding XPI database with no extensions"
[task 2020-09-09T19:24:16.528Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679158077 addons.manager DEBUG Registering shutdown blocker for XPIProvider"
[task 2020-09-09T19:24:16.528Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679158077 addons.manager DEBUG Provider finished startup: XPIProvider"
[task 2020-09-09T19:24:16.528Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679158077 addons.manager DEBUG Starting provider: PluginProvider"
[task 2020-09-09T19:24:16.528Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679158077 addons.manager DEBUG Registering shutdown blocker for PluginProvider"
[task 2020-09-09T19:24:16.528Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679158078 addons.manager DEBUG Provider finished startup: PluginProvider"
[task 2020-09-09T19:24:16.528Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679158078 addons.manager DEBUG Completed startup sequence"
[task 2020-09-09T19:24:16.528Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679158683 addons.xpi-utils DEBUG Make addon app-temporary:formautofill@mozilla.org visible"
[task 2020-09-09T19:24:16.528Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679158684 addons.xpi DEBUG XPIStates adding add-on formautofill@mozilla.org in {}: /builds/worker/workspace/build/application/firefox/browser/features/formautofill@mozilla.org.xpi"
[task 2020-09-09T19:24:16.529Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679158685 addons.xpi DEBUG Updating XPIState for {"id":"formautofill@mozilla.org","syncGUID":"{8f6cc31a-44fe-4458-a9fc-ac6a691de68e}","version":"1.0","type":"extension","loader":null,"updateURL":null,"optionsURL":null,"optionsType":null,"optionsBrowserStyle":true,"aboutURL":null,"defaultLocale":{"name":"Form Autofill","creator":null,"developers":null,"translators":null,"contributors":null},"visible":true,"active":true,"userDisabled":false,"appDisabled":false,"embedderDisabled":false,"installDate":1599679158683,"applyBackgroundUpdates":1,"path":"/builds/worker/workspace/build/application/firefox/browser/features/formautofill@mozilla.org.xpi","skinnable":false,"sourceURI":null,"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:///builds/worker/workspace/build/application/firefox/browser/features/formautofill@mozilla.org.xpi!/","location":"app-temporary"}"
[task 2020-09-09T19:24:16.529Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679158686 addons.xpi DEBUG Loading bootstrap scope from jar:file:///builds/worker/workspace/build/application/firefox/browser/features/formautofill@mozilla.org.xpi!/"
[task 2020-09-09T19:24:16.529Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679158686 addons.xpi DEBUG Calling bootstrap method install on formautofill@mozilla.org version 1.0"
[task 2020-09-09T19:24:16.529Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679158687 addons.xpi DEBUG Calling bootstrap method startup on formautofill@mozilla.org version 1.0"
[task 2020-09-09T19:24:16.529Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679158722 addons.xpi-utils DEBUG XPI Database saved, setting schema version preference to 32"
[task 2020-09-09T19:24:16.529Z] 19:24:16 INFO - PID 26473 | profiling:/tmp/tmpif5pJP/js/src/Unified_cpp_js_src18.gcda:Merge mismatch for function 0
[task 2020-09-09T19:24:16.529Z] 19:24:16 INFO - PID 26473 | profiling:/tmp/tmpif5pJP/dom/html/Unified_cpp_dom_html0.gcda:Merge mismatch for function 39
[task 2020-09-09T19:24:16.529Z] 19:24:16 INFO - PID 26473 | profiling:/tmp/tmpif5pJP/gfx/layers/X11TextureHost.gcda:Merge mismatch for function 147
[task 2020-09-09T19:24:16.529Z] 19:24:16 INFO - PID 26473 | profiling:/tmp/tmpif5pJP/caps/BasePrincipal.gcda:Merge mismatch for function 32
[task 2020-09-09T19:24:16.530Z] 19:24:16 INFO - PID 26473 | profiling:/tmp/tmpif5pJP/ipc/ipdl/UnifiedProtocols0.gcda:Merge mismatch for function 44
[task 2020-09-09T19:24:16.530Z] 19:24:16 INFO - PID 26473 | profiling:/tmp/tmpif5pJP/xpcom/components/StaticComponents.gcda:Merge mismatch for function 32
[task 2020-09-09T19:24:16.530Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679159238 addons.manager DEBUG Starting provider: GMPProvider"
[task 2020-09-09T19:24:16.530Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679159244 addons.manager DEBUG Registering shutdown blocker for GMPProvider"
[task 2020-09-09T19:24:16.530Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679159245 addons.manager DEBUG Provider finished startup: GMPProvider"
[task 2020-09-09T19:24:16.530Z] 19:24:16 INFO - PID 26473 | profiling:/tmp/tmpif5pJP/js/src/Unified_cpp_js_src18.gcda:Merge mismatch for function 0
[task 2020-09-09T19:24:16.530Z] 19:24:16 INFO - PID 26473 | profiling:/tmp/tmpif5pJP/dom/html/Unified_cpp_dom_html0.gcda:Merge mismatch for function 39
[task 2020-09-09T19:24:16.530Z] 19:24:16 INFO - PID 26473 | profiling:/tmp/tmpif5pJP/gfx/layers/X11TextureHost.gcda:Merge mismatch for function 147
[task 2020-09-09T19:24:16.531Z] 19:24:16 INFO - PID 26473 | profiling:/tmp/tmpif5pJP/caps/BasePrincipal.gcda:Merge mismatch for function 32
[task 2020-09-09T19:24:16.531Z] 19:24:16 INFO - PID 26473 | profiling:/tmp/tmpif5pJP/ipc/ipdl/UnifiedProtocols0.gcda:Merge mismatch for function 44
[task 2020-09-09T19:24:16.531Z] 19:24:16 INFO - PID 26473 | profiling:/tmp/tmpif5pJP/xpcom/components/StaticComponents.gcda:Merge mismatch for function 32
[task 2020-09-09T19:24:16.531Z] 19:24:16 INFO - PID 26473 | [CodeCoverage] Setting handlers for process 26512.
[task 2020-09-09T19:24:16.531Z] 19:24:16 INFO - (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2020-09-09T19:24:16.531Z] 19:24:16 INFO - (xpcshell/head.js) | test head_initialize finished (2)
[task 2020-09-09T19:24:16.531Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679160872 addons.manager DEBUG Registering upgrade listener for formautofill@mozilla.org"
[task 2020-09-09T19:24:16.531Z] 19:24:16 INFO - "CONSOLE_MESSAGE: (info) 1599679160875 addons.xpi DEBUG Install of temporary addon in /builds/worker/workspace/build/application/firefox/browser/features/formautofill@mozilla.org.xpi completed."
[task 2020-09-09T19:24:16.531Z] 19:24:16 INFO - browser/extensions/formautofill/test/unit/heuristics/third_party/test_HomeDepot.js | Starting os_key_store_setup
[task 2020-09-09T19:24:16.531Z] 19:24:16 INFO - (xpcshell/head.js) | test os_key_store_setup pending (2)
[task 2020-09-09T19:24:16.531Z] 19:24:16 INFO - (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2020-09-09T19:24:16.531Z] 19:24:16 INFO - (xpcshell/head.js) | test run_next_test 2 pending (2)
[task 2020-09-09T19:24:16.531Z] 19:24:16 INFO - (xpcshell/head.js) | test os_key_store_setup finished (2)
[task 2020-09-09T19:24:16.531Z] 19:24:16 INFO - browser/extensions/formautofill/test/unit/heuristics/third_party/test_HomeDepot.js | Starting setup
[task 2020-09-09T19:24:16.532Z] 19:24:16 INFO - (xpcshell/head.js) | test setup pending (2)
[task 2020-09-09T19:24:16.532Z] 19:24:16 INFO - (xpcshell/head.js) | test run_next_test 2 finished (2)
[task 2020-09-09T19:24:16.532Z] 19:24:16 INFO - (xpcshell/head.js) | test run_next_test 3 pending (2)
[task 2020-09-09T19:24:16.532Z] 19:24:16 INFO - (xpcshell/head.js) | test setup finished (2)
[task 2020-09-09T19:24:16.532Z] 19:24:16 INFO - browser/extensions/formautofill/test/unit/heuristics/third_party/test_HomeDepot.js | Starting
[task 2020-09-09T19:24:16.532Z] 19:24:16 INFO - (xpcshell/head.js) | test pending (2)
[task 2020-09-09T19:24:16.532Z] 19:24:16 INFO - "Starting test fixture: Checkout_ShippingPayment.html"
[task 2020-09-09T19:24:16.532Z] 19:24:16 INFO - TEST-PASS | browser/extensions/formautofill/test/unit/heuristics/third_party/test_HomeDepot.js | - Expected form count. - 1 == 1
[task 2020-09-09T19:24:16.532Z] 19:24:16 INFO - TEST-PASS | browser/extensions/formautofill/test/unit/heuristics/third_party/test_HomeDepot.js | - Expected section count. - 3 == 3

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

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