569959 - Intermittent failure in test_install.js | Add-on was installed 2539ms ago

Status: VERIFIED FIXED

(Toolkit :: Add-ons Manager, defect)

Description

[(no longer active)]

http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1275566169.1275567321.30455.gz
Linux mozilla-central opt test xpcshell on 2010/06/03 04:56:09

TEST-UNEXPECTED-FAIL | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | test failed (with xpcshell return code: 0), see following log:
  >>>>>>>
  TEST-INFO | (xpcshell/head.js) | test 1 pending
*** LOG addons.manager: Application has been upgraded
*** LOG addons.xpi: startup
*** LOG addons.xpi: checkForChanges
*** LOG addons.xpi: Migrating data from schema 0
*** LOG addons.xpi: Database is missing, recreating
*** LOG addons.xpi: Creating database schema
*** LOG addons.xpi: No changes found
*** LOG addons.xpi: Add-ons list is missing, recreating
*** LOG addons.xpi: Writing add-ons list
*** LOG addons.xpi: shutdown
*** LOG addons.xpi: startup
*** LOG addons.xpi: checkForChanges
*** LOG addons.xpi: No changes found
TEST-INFO | (xpcshell/head.js) | test 2 pending
TEST-INFO | (xpcshell/head.js) | test 2 finished
TEST-INFO | (xpcshell/head.js) | running event loop
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/head_addons.js | [anonymous : 500] onNewInstall == onNewInstall
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/head_addons.js | [ensure_test_completed : 597] 0 == 0
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 53] [object Object] != null
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 54] extension == extension
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 55] 1.0 == 1.0
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 56] Test 1 == Test 1
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 57] 3 == 3
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 58] true == true
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 59] [object Object] == [object Object]
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 63] jar:file:///builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/addons/test_install1.xpi!/install.rdf == jar:file:///builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/addons/test_install1.xpi!/install.rdf
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 64] jar:file:///builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/addons/test_install1.xpi!/icon.png == jar:file:///builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/addons/test_install1.xpi!/icon.png
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 65] null == null
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 68] 1 == 1
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 69] [object Object] == [object Object]
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 72] 0 == 0
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 75] 1 == 1
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 76] [object Object] == [object Object]
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/head_addons.js | [anonymous : 529] 5 == 5
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/head_addons.js | [anonymous : 530] onInstallStarted == onInstallStarted
*** LOG addons.xpi: Starting install of file:///builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/addons/test_install1.xpi
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/head_addons.js | [anonymous : 460] onInstalling == onInstalling
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/head_addons.js | [anonymous : 461] true == true
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/head_addons.js | [anonymous : 463] true == true
*** LOG addons.xpi: Install of file:///builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/addons/test_install1.xpi completed.
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/head_addons.js | [anonymous : 535] 6 == 6
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/head_addons.js | [anonymous : 536] onInstallEnded == onInstallEnded
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/head_addons.js | [ensure_test_completed : 597] 0 == 0
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 96] null == null
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 99] 1 == 1
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 100] [e-mail] == [e-mail]
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 103] true == true
*** LOG addons.xpi: shutdown
*** LOG addons.xpi: startup
*** LOG addons.xpi: checkForChanges
*** LOG addons.xpi: Processing install of [e-mail] in app-profile
*** LOG addons.xpi: Found updated manifest for [e-mail] in app-profile
*** WARN addons.xpi: Ignoring file: /tmp/tmpsfnrwi/extensions/staged/[e-mail]
*** LOG addons.xpi: New add-on [e-mail] installed in app-profile
*** LOG addons.xpi: Restart necessary
*** LOG addons.xpi: Updating add-on states
*** LOG addons.xpi: Writing add-ons list
*** LOG addons.xpi: shutdown
*** LOG addons.xpi: startup
*** LOG addons.xpi: checkForChanges
*** LOG addons.xpi: No changes found
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 108]  == 0
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 111] [object Object] != null
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 112] extension == extension
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 113] 1.0 == 1.0
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 114] Test 1 == Test 1
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 115] true == true
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | [anonymous : 116] true == true
TEST-PASS | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/head_addons.js | [do_check_in_crash_annotation : 84] false == false
TEST-UNEXPECTED-FAIL | /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | Add-on was installed 2539ms ago - See following stack:
JS frame :: /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/head.js :: do_throw :: line 257
JS frame :: /builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js :: anonymous :: line 122
JS frame :: file:///builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/firefox/modules/AddonManager.jsm :: safeCall :: line 41
JS frame :: file:///builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/firefox/modules/AddonManager.jsm :: anonymous :: line 565
JS frame :: file:///builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/firefox/modules/XPIProvider.jsm :: anonymous :: line 1747
JS frame :: file:///builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/firefox/modules/XPIProvider.jsm :: anonymous :: line 3105
JS frame :: file:///builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/firefox/modules/XPIProvider.jsm :: anonymous :: line 2372
JS frame :: file:///builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/firefox/modules/XPIProvider.jsm :: anonymous :: line 2974
JS frame :: file:///builds/moz2_slave/mozilla-central-linux-opt-unittest-xpcshell/build/firefox/modules/XPIProvider.jsm :: anonymous :: line 2973
TEST-INFO | (xpcshell/head.js) | exiting test
*** WARN addons.manager: Exception calling callback: 2147500036
*** LOG addons.xpi: shutdown
!!! error running onStopped callback: TypeError: callback is not a function

Comment 1

[Dave Townsend [:mossop]]

Hrm, probably should increase the range of allowable times here. Anytime in the last minute is probably ok rather than just the 2 seconds we allow here, though it is odd it is taking so long.

Comment 2

[Legacy TBPL/Treeherder Robot]

philringnalda%gmail.com
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1276952642.1276955292.22148.gz
WINNT 5.2 mozilla-central opt test xpcshell on 2010/06/19 06:04:02

s: win32-slave01
TEST-UNEXPECTED-FAIL | e:\builds\moz2_slave\mozilla-central-win32-opt-unittest-xpcshell\build\xpcshell\tests\test_extensionmanager\xpcshell\test_install.js | test failed (with xpcshell return code: 0), see following log:
TEST-UNEXPECTED-FAIL | e:/builds/moz2_slave/mozilla-central-win32-opt-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | Add-on was installed 2672ms ago - See following stack:

Comment 3

[Legacy TBPL/Treeherder Robot]

philringnalda%gmail.com
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1277259351.1277261307.17186.gz
Rev3 Fedora 12x64 mozilla-central debug test xpcshell on 2010/06/22 19:15:51

s: talos-r3-fed64-048
TEST-UNEXPECTED-FAIL | /home/cltbld/talos-slave/mozilla-central-fedora64-debug-u-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | test failed (with xpcshell return code: 0), see following log:
TEST-UNEXPECTED-FAIL | /home/cltbld/talos-slave/mozilla-central-fedora64-debug-u-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | Add-on was installed 2012ms ago - See following stack:

Comment 4

[Legacy TBPL/Treeherder Robot]

ddahl%mozilla.com
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1277482739.1277486369.23678.gz
WINNT 5.2 mozilla-central debug test xpcshell on 2010/06/25 09:18:59

s: mw32-ix-slave04
TEST-UNEXPECTED-FAIL | e:\builds\moz2_slave\mozilla-central-win32-debug-unittest-xpcshell\build\xpcshell\tests\test_extensionmanager\xpcshell\test_install.js | test failed (with xpcshell return code: 0), see following log:
TEST-UNEXPECTED-FAIL | e:/builds/moz2_slave/mozilla-central-win32-debug-unittest-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | Add-on was installed 3422ms ago - See following stack:
TEST-UNEXPECTED-FAIL | e:\builds\moz2_slave\mozilla-central-win32-debug-unittest-xpcshell\build\xpcshell\tests\test_jetpack\unit\test_jetpack.js | test failed (with xpcshell return code: -2147483645), see following log:

Comment 5

[Legacy TBPL/Treeherder Robot]

dholbert%mozilla.com
http://tinderbox.mozilla.org/showlog.cgi?log=Firefox/1277588745.1277589857.30106.gz
Rev3 Fedora 12 mozilla-central opt test xpcshell on 2010/06/26 14:45:45

s: talos-r3-fed-014
TEST-UNEXPECTED-FAIL | /home/cltbld/talos-slave/mozilla-central-fedora-opt-u-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | test failed (with xpcshell return code: 0), see following log:
TEST-UNEXPECTED-FAIL | /home/cltbld/talos-slave/mozilla-central-fedora-opt-u-xpcshell/build/xpcshell/tests/test_extensionmanager/xpcshell/test_install.js | Add-on was installed 2247ms ago - See following stack:

Comment 6

[Dave Townsend [:mossop]]

Attachment: patch rev 1

Bumping the timeout to 10 seconds. Realistically if the API is getting times wrong then it is likely to be out by hours if not days so this should be fine I think.

Comment 7

[Dave Townsend [:mossop]]

Landed: http://hg.mozilla.org/mozilla-central/rev/659d4f681e39

Comment 8

[Henrik Skupin [:whimboo][⌚️UTC+1]]

Marking as verified fixed based on no failures in the last couple of months.