951399 - Intermittent test_theme.js | test failed (with xpcshell return code: 0) | Add-on was installed 13588ms ago

Status: RESOLVED WORKSFORME

(Toolkit :: Add-ons Manager, defect)

Description

[Ryan VanderMeulen [:RyanVM]]

https://tbpl.mozilla.org/php/getParsedLog.php?id=32095200&tree=Mozilla-Central

WINNT 6.2 mozilla-central opt test xpcshell on 2013-12-17 08:22:28 PST for push 77e658ad2c26
slave: t-w864-ix-105

08:55:04     INFO -  TEST-INFO | C:\slave\test\build\tests\xpcshell\tests\toolkit\mozapps\extensions\test\xpcshell\test_theme.js | running test ...
08:55:55  WARNING -  TEST-UNEXPECTED-FAIL | C:\slave\test\build\tests\xpcshell\tests\toolkit\mozapps\extensions\test\xpcshell\test_theme.js | test failed (with xpcshell return code: 0), see following log:
08:55:55     INFO -  >>>>>>>
08:55:55     INFO -  TEST-INFO | (xpcshell/head.js) | test MAIN run_test pending (1)
08:55:55     INFO -  TEST-INFO | (xpcshell/head.js) | test pending (2)
08:55:55     INFO -  *** LOG addons.manager: Application has been upgraded
08:55:55     INFO -  TEST-INFO | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | "Mock profileBeforeChange blocker for 'AddonManager: shutting down providers'"
08:55:55     INFO -  *** LOG addons.xpi: startup
08:55:55     INFO -  *** LOG addons.xpi: checkForChanges
08:55:55     INFO -  *** LOG addons.xpi: Directory state JSON differs: cache [] state [{"name":"app-profile","addons":{"[e-mail]":{"descriptor":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpjaejrb\\extensions\\[e-mail]","mtime":1387299306933},"[e-mail]":{"descriptor":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpjaejrb\\extensions\\[e-mail]","mtime":1387299305952},"[e-mail]":{"descriptor":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpjaejrb\\extensions\\[e-mail]","mtime":1387299305952}}}]
08:55:55     INFO -  *** LOG addons.xpi-utils: Opening XPI database c:\users\cltbld~1.t-w\appdata\local\temp\tmpjaejrb\extensions.json
08:55:55     INFO -  *** LOG addons.xpi: New add-on [e-mail] installed in app-profile
08:55:55     INFO -  *** LOG addons.xpi-utils: Make addon app-profile:[e-mail] visible
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  *** LOG addons.xpi: New add-on [e-mail] installed in app-profile
08:55:55     INFO -  *** LOG addons.xpi-utils: Make addon app-profile:[e-mail] visible
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  *** LOG addons.xpi: New add-on [e-mail] installed in app-profile
08:55:55     INFO -  *** LOG addons.xpi-utils: Make addon app-profile:[e-mail] visible
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  *** LOG addons.xpi: Updating database with changes to installed add-ons
08:55:55     INFO -  *** LOG addons.xpi-utils: Updating add-on states
08:55:55     INFO -  *** LOG addons.xpi-utils: Writing add-ons list
08:55:55     INFO -  TEST-INFO | (xpcshell/head.js) | test MAIN run_test finished (2)
08:55:55     INFO -  TEST-INFO | (xpcshell/head.js) | running event loop
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Starting timer
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 95] [object Object] != null
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 96] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 97] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 99] [object Object] != null
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 100] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 101] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 102] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 103] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 104] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 105] null == null
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 106] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 107] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 108] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 110] 6 == 6
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 112] [object Object] != null
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 113] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 114] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 115] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 116] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 117] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 118] null == null
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 119] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 120] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 121] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test/< : 122] 1 == 1
08:55:55     INFO -  TEST-INFO | (xpcshell/head.js) | test run_test_1 pending (2)
08:55:55     INFO -  TEST-INFO | (xpcshell/head.js) | test run_test_1 finished (2)
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Starting write
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Data changed while write in progress
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onEnabling : 840] "onEnabling" == "onEnabling"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onEnabling : 841] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onEnabling : 843] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onEnabling : 844] false == false
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onDisabling : 857] "onDisabling" == "onDisabling"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onDisabling : 858] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onDisabling : 860] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onDisabling : 861] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_1/< : 147] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_1/< : 148] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_1/< : 150] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_1/< : 151] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_1/< : 152] true == true
08:55:55     INFO -  TEST-INFO | (xpcshell/head.js) | test check_test_1 pending (2)
08:55:55     INFO -  *** LOG addons.manager: shutdown
08:55:55     INFO -  *** LOG addons.xpi: shutdown
08:55:55     INFO -  *** LOG addons.xpi-utils: Updating add-on states
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  *** LOG addons.xpi-utils: Writing add-ons list
08:55:55     INFO -  *** LOG addons.xpi-utils: shutdown
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Flush called while data is dirty
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Write succeeded
08:55:55     INFO -  *** LOG addons.xpi-utils: XPI Database saved, setting schema version preference to 15
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Starting write
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Write succeeded
08:55:55     INFO -  *** LOG addons.xpi: Notifying XPI shutdown observers
08:55:55     INFO -  *** LOG addons.manager: Async provider shutdown done
08:55:55     INFO -  TEST-INFO | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | "Mock profileBeforeChange blocker for 'AddonManager: shutting down providers'"
08:55:55     INFO -  *** LOG addons.xpi: startup
08:55:55     INFO -  *** LOG addons.xpi: Changed skin to theme2/1.0
08:55:55     INFO -  *** LOG addons.xpi: checkForChanges
08:55:55     INFO -  *** LOG addons.xpi: No changes found
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1 : 160] "theme2/1.0" == "theme2/1.0"
08:55:55     INFO -  *** LOG addons.xpi-utils: Starting async load of XPI database c:\users\cltbld~1.t-w\appdata\local\temp\tmpjaejrb\extensions.json
08:55:55     INFO -  TEST-INFO | (xpcshell/head.js) | test check_test_1 finished (2)
08:55:55     INFO -  *** LOG addons.xpi-utils: Async JSON file read took 1 MS
08:55:55     INFO -  *** LOG addons.xpi-utils: Finished async read of XPI database, parsing...
08:55:55     INFO -  *** LOG addons.xpi-utils: Successfully read XPI database
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 164] [object Object] != null
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 165] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 166] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 167] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 168] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 169] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 170] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 171] 1 == 1
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 173] [object Object] != null
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 174] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 175] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 176] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 177] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 178] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 179] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 181] 6 == 6
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [check_test_1/< : 182] false == false
08:55:55     INFO -  TEST-INFO | (xpcshell/head.js) | test run_test_2 pending (2)
08:55:55     INFO -  *** LOG addons.manager: shutdown
08:55:55     INFO -  *** LOG addons.xpi: shutdown
08:55:55     INFO -  *** LOG addons.xpi-utils: shutdown
08:55:55     INFO -  *** LOG addons.xpi: Notifying XPI shutdown observers
08:55:55     INFO -  *** LOG addons.manager: Async provider shutdown done
08:55:55     INFO -  TEST-INFO | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | "Mock profileBeforeChange blocker for 'AddonManager: shutting down providers'"
08:55:55     INFO -  *** LOG addons.xpi: startup
08:55:55     INFO -  *** LOG addons.xpi: checkForChanges
08:55:55     INFO -  *** LOG addons.xpi: Directory state JSON differs: cache [{"name":"app-profile","addons":{"[e-mail]":{"descriptor":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpjaejrb\\extensions\\[e-mail]","mtime":1387299306933},"[e-mail]":{"descriptor":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpjaejrb\\extensions\\[e-mail]","mtime":1387299305952},"[e-mail]":{"descriptor":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpjaejrb\\extensions\\[e-mail]","mtime":1387299305952}}}] state [{"name":"app-profile","addons":{"[e-mail]":{"descriptor":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpjaejrb\\extensions\\[e-mail]","mtime":1387299306933},"[e-mail]":{"descriptor":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpjaejrb\\extensions\\[e-mail]","mtime":1387299305952}}}]
08:55:55     INFO -  *** LOG addons.xpi-utils: Opening XPI database c:\users\cltbld~1.t-w\appdata\local\temp\tmpjaejrb\extensions.json
08:55:55     INFO -  *** LOG addons.xpi-utils: Successfully read XPI database
08:55:55     INFO -  *** LOG addons.xpi: Add-on [e-mail] removed from app-profile
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  *** LOG addons.xpi: Activating default theme
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  *** LOG addons.xpi-utils: Updating active state for add-on [e-mail] to true
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  *** LOG addons.xpi: Updating database with changes to installed add-ons
08:55:55     INFO -  *** LOG addons.xpi-utils: Updating add-on states
08:55:55     INFO -  *** LOG addons.xpi-utils: Writing add-ons list
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_2 : 196] "classic/1.0" == "classic/1.0"
08:55:55     INFO -  TEST-INFO | (xpcshell/head.js) | test run_test_2 finished (2)
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Starting timer
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_2/< : 200] [object Object] != null
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_2/< : 201] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_2/< : 202] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_2/< : 203] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_2/< : 204] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_2/< : 205] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_2/< : 206] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_2/< : 208] null == null
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_2/< : 209] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_2/< : 210] false == false
08:55:55     INFO -  TEST-INFO | (xpcshell/head.js) | test run_test_3 pending (2)
08:55:55     INFO -  *** LOG addons.manager: shutdown
08:55:55     INFO -  *** LOG addons.xpi: shutdown
08:55:55     INFO -  *** LOG addons.xpi-utils: shutdown
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Starting write
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Write succeeded
08:55:55     INFO -  *** LOG addons.xpi-utils: XPI Database saved, setting schema version preference to 15
08:55:55     INFO -  *** LOG addons.xpi: Notifying XPI shutdown observers
08:55:55     INFO -  *** LOG addons.manager: Async provider shutdown done
08:55:55     INFO -  TEST-INFO | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | "Mock profileBeforeChange blocker for 'AddonManager: shutting down providers'"
08:55:55     INFO -  *** LOG addons.xpi: startup
08:55:55     INFO -  *** LOG addons.xpi: checkForChanges
08:55:55     INFO -  *** LOG addons.xpi: Directory state JSON differs: cache [{"name":"app-profile","addons":{"[e-mail]":{"descriptor":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpjaejrb\\extensions\\[e-mail]","mtime":1387299306933},"[e-mail]":{"descriptor":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpjaejrb\\extensions\\[e-mail]","mtime":1387299305952}}}] state [{"name":"app-profile","addons":{"[e-mail]":{"descriptor":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpjaejrb\\extensions\\[e-mail]","mtime":1387299306933},"[e-mail]":{"descriptor":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpjaejrb\\extensions\\[e-mail]","mtime":1387299305952},"[e-mail]":{"descriptor":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpjaejrb\\extensions\\[e-mail]","mtime":1387299332246}}}]
08:55:55     INFO -  *** LOG addons.xpi-utils: Opening XPI database c:\users\cltbld~1.t-w\appdata\local\temp\tmpjaejrb\extensions.json
08:55:55     INFO -  *** LOG addons.xpi-utils: Successfully read XPI database
08:55:55     INFO -  *** LOG addons.xpi: New add-on [e-mail] installed in app-profile
08:55:55     INFO -  *** LOG addons.xpi-utils: Make addon app-profile:[e-mail] visible
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  *** LOG addons.xpi: Updating database with changes to installed add-ons
08:55:55     INFO -  *** LOG addons.xpi-utils: Updating add-on states
08:55:55     INFO -  *** LOG addons.xpi-utils: Writing add-ons list
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [InstallListener.onExternalInstall : 978] "onExternalInstall" == "onExternalInstall"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [InstallListener.onExternalInstall : 979] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onInstalling : 874] "onInstalling" == "onInstalling"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onInstalling : 875] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onInstalled : 883] "onInstalled" == "onInstalled"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onEnabling : 840] "onEnabling" == "onEnabling"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onEnabling : 841] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onEnabling : 844] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onEnabled : 850] "onEnabled" == "onEnabled"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onEnabled : 851] false == false
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onDisabling : 857] "onDisabling" == "onDisabling"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onDisabling : 858] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onDisabling : 861] false == false
08:55:55     INFO -  *** LOG addons.xpi-utils: Updating active state for add-on [e-mail] to false
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Save changes
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onDisabled : 867] "onDisabled" == "onDisabled"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [AddonListener.onDisabled : 868] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/head_addons.js | [ensure_test_completed : 1027] 0 == 0
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 262] null != [object Object]
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 263] "Test LW Theme" == "Test LW Theme"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 264] "1" == "1"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 265] "theme" == "theme"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 266] "A test theme" == "A test theme"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 267] Mozilla == "Mozilla"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 268] "http://localhost/data/index.html" == "http://localhost/data/index.html"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 269] "http://localhost/data/icon.png" == "http://localhost/data/icon.png"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 270] 1 == 1
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 271] http://localhost/data/preview.png == "http://localhost/data/preview.png"
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 272] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 273] false == false
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 274] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 275] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 276] 0 == 0
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 277] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 278] 0 == 0
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 279] 5 == 5
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 280] 1 == 1
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 281] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 282] true == true
08:55:55     INFO -  TEST-PASS | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | [run_test_3/< : 283] 1387299332835 == 1387299332835
08:55:55  WARNING -  TEST-UNEXPECTED-FAIL | C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js | Add-on was installed 13588ms ago - See following stack:
08:55:55     INFO -  JS frame :: C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js :: run_test_3/< :: line 288
08:55:55     INFO -  JS frame :: resource://gre/modules/AddonManager.jsm :: safeCall :: line 102
08:55:55     INFO -  JS frame :: resource://gre/modules/AddonManager.jsm :: getAddonByID_safeCall :: line 1770
08:55:55     INFO -  JS frame :: resource://gre/modules/LightweightThemeManager.jsm :: LightweightThemeManager_getAddonByID :: line 373
08:55:55     INFO -  JS frame :: resource://gre/modules/AddonManager.jsm :: callProvider :: line 128
08:55:55     INFO -  JS frame :: resource://gre/modules/AddonManager.jsm :: getAddonByID_nextObject :: line 1768
08:55:55     INFO -  JS frame :: resource://gre/modules/AddonManager.jsm :: AOC_callNext :: line 203
08:55:55     INFO -  JS frame :: resource://gre/modules/AddonManager.jsm :: AsyncObjectCaller :: line 183
08:55:55     INFO -  JS frame :: resource://gre/modules/AddonManager.jsm :: AMI_getAddonByID :: line 1776
08:55:55     INFO -  JS frame :: resource://gre/modules/AddonManager.jsm :: AM_getAddonByID :: line 2439
08:55:55     INFO -  JS frame :: C:/slave/test/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_theme.js :: run_test_3 :: line 261
08:55:55     INFO -  JS frame :: C:\slave\test\build\tests\xpcshell\head.js :: do_execute_soon/<.run :: line 507
08:55:55     INFO -  native frame :: <unknown filename> :: <TOP_LEVEL> :: line 0
08:55:55     INFO -  TEST-INFO | (xpcshell/head.js) | exiting test
08:55:55     INFO -  *** WARN addons.manager: Exception calling callback: NS_ERROR_ABORT
08:55:55     INFO -  TEST-INFO | (xpcshell/head.js) | test run_test_3 finished (2)
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Starting timer
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Starting write
08:55:55     INFO -  *** LOG DeferredSave/extensions.json: Write succeeded
08:55:55     INFO -  *** LOG addons.xpi-utils: XPI Database saved, setting schema version preference to 15
08:55:55     INFO -  *** LOG addons.manager: shutdown
08:55:55     INFO -  *** LOG addons.xpi: shutdown
08:55:55     INFO -  *** LOG addons.xpi-utils: Updating add-on states
08:55:55     INFO -  *** LOG addons.xpi-utils: Writing add-ons list
08:55:55     INFO -  *** LOG addons.xpi-utils: shutdown
08:55:55     INFO -  *** LOG addons.xpi: Notifying XPI shutdown observers
08:55:55     INFO -  *** LOG addons.manager: Async provider shutdown done
08:55:55     INFO -  <<<<<<<

Comment 1

[Ed Morley [:emorley]]

Intermittent failure not seen for >3 months; filter on mass-intermittent-wfm-20140812.