894549 - Intermittent Jetpack tests/test-places-history.testSearchSort | expected return count - 5 == 4 | successful order - "http://bandcamp.com/" == "http://developer.mozilla.com/" ... | Test output exceeded timeout (60s).

Status: RESOLVED FIXED

(Add-on SDK Graveyard :: General, defect, P2)

Description

[Ryan VanderMeulen [:RyanVM]]

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

Windows XP 32-bit mozilla-central opt test jetpack on 2013-07-16 10:29:27 PDT for push 5f79304fcefe
slave: t-xp32-ix-126

TEST-START | tests/test-places-history.testSearchSort
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | expected return count - 5 == 4
TEST-INFO | Traceback (most recent call last):
  File "resource://gre/modules/commonjs/sdk/timers.js", line 31, in notify
    callback.apply(null, args);
  File "resource://gre/modules/commonjs/sdk/lang/functional.js", line 66, in invoke
    function invoke(callee, params, self) callee.apply(self, params);
  File "resource://gre/modules/commonjs/sdk/addon/events.js", line 27, in null
    else request.resolve(clone(data));
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 189, in resolve
    result.then(observer.resolve, observer.reject);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 47, in then
    return { then: function then(fulfill) { fulfill(value); } };
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 122, in resolve
    deferred.resolve(onFulfill ? onFulfill(value) : value);
  File "resource://gre/modules/commonjs/sdk/places/history.js", line 55, in query/</<
    emit(emitter, 'end', results);
  File "resource://gre/modules/commonjs/sdk/event/core.js", line 85, in emit
    for each (let item in emit.lazy.apply(emit.lazy, arguments)) {
  File "resource://gre/modules/commonjs/sdk/event/core.js", line 112, in lazy
    if (~state.indexOf(listener)) yield listener.apply(target, args);
  File "resource://gre/modules/commonjs/sdk/places/utils.js", line 165, in null
    else resolve(items);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 189, in resolve
    result.then(observer.resolve, observer.reject);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 47, in then
    return { then: function then(fulfill) { fulfill(value); } };
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 122, in resolve
    deferred.resolve(onFulfill ? onFulfill(value) : value);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 122, in resolve
    deferred.resolve(onFulfill ? onFulfill(value) : value);
  File "resource://extensions.modules.2e87f45b-405a-482d-8bb1-caf741c884e5-at-jetpack.commonjs.path.tests/test-places-history.js", line 177, in exports.testSearchSort/<
    checkOrder(results, [4,3,0,2,1]);
  File "resource://extensions.modules.2e87f45b-405a-482d-8bb1-caf741c884e5-at-jetpack.commonjs.path.tests/test-places-history.js", line 209, in checkOrder
    assert.equal(results.length, nums.length, 'expected return count');
  File "resource://gre/modules/commonjs/sdk/test/assert.js", line 125, in equal
    operator: "=="
  File "resource://gre/modules/commonjs/sdk/test/assert.js", line 89, in fail
    this._log.fail(message);
  File "resource://gre/modules/commonjs/sdk/deprecated/unit-test.js", line 84, in fail
    this.console.testMessage(false, false, this.test.name, message);
  File "resource://gre/modules/commonjs/sdk/test/harness.js", line 523, in testMessage
    this.trace();
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | successful order - "http://bandcamp.com/" == "http://developer.mozilla.com/"
TEST-INFO | Traceback (most recent call last):
  File "resource://gre/modules/commonjs/sdk/timers.js", line 31, in notify
    callback.apply(null, args);
  File "resource://gre/modules/commonjs/sdk/lang/functional.js", line 66, in invoke
    function invoke(callee, params, self) callee.apply(self, params);
  File "resource://gre/modules/commonjs/sdk/addon/events.js", line 27, in null
    else request.resolve(clone(data));
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 189, in resolve
    result.then(observer.resolve, observer.reject);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 47, in then
    return { then: function then(fulfill) { fulfill(value); } };
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 122, in resolve
    deferred.resolve(onFulfill ? onFulfill(value) : value);
  File "resource://gre/modules/commonjs/sdk/places/history.js", line 55, in query/</<
    emit(emitter, 'end', results);
  File "resource://gre/modules/commonjs/sdk/event/core.js", line 85, in emit
    for each (let item in emit.lazy.apply(emit.lazy, arguments)) {
  File "resource://gre/modules/commonjs/sdk/event/core.js", line 112, in lazy
    if (~state.indexOf(listener)) yield listener.apply(target, args);
  File "resource://gre/modules/commonjs/sdk/places/utils.js", line 165, in null
    else resolve(items);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 189, in resolve
    result.then(observer.resolve, observer.reject);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 47, in then
    return { then: function then(fulfill) { fulfill(value); } };
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 122, in resolve
    deferred.resolve(onFulfill ? onFulfill(value) : value);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 122, in resolve
    deferred.resolve(onFulfill ? onFulfill(value) : value);
  File "resource://extensions.modules.2e87f45b-405a-482d-8bb1-caf741c884e5-at-jetpack.commonjs.path.tests/test-places-history.js", line 177, in exports.testSearchSort/<
    checkOrder(results, [4,3,0,2,1]);
  File "resource://extensions.modules.2e87f45b-405a-482d-8bb1-caf741c884e5-at-jetpack.commonjs.path.tests/test-places-history.js", line 211, in checkOrder
    assert.equal(results[i].url, places[nums[i]], 'successful order');
  File "resource://gre/modules/commonjs/sdk/test/assert.js", line 125, in equal
    operator: "=="
  File "resource://gre/modules/commonjs/sdk/test/assert.js", line 89, in fail
    this._log.fail(message);
  File "resource://gre/modules/commonjs/sdk/deprecated/unit-test.js", line 84, in fail
    this.console.testMessage(false, false, this.test.name, message);
  File "resource://gre/modules/commonjs/sdk/test/harness.js", line 523, in testMessage
    this.trace();
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | successful order - "http://developer.mozilla.com/" == "http://mozilla.com/"
TEST-INFO | Traceback (most recent call last):
  File "resource://gre/modules/commonjs/sdk/timers.js", line 31, in notify
    callback.apply(null, args);
  File "resource://gre/modules/commonjs/sdk/lang/functional.js", line 66, in invoke
    function invoke(callee, params, self) callee.apply(self, params);
  File "resource://gre/modules/commonjs/sdk/addon/events.js", line 27, in null
    else request.resolve(clone(data));
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 189, in resolve
    result.then(observer.resolve, observer.reject);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 47, in then
    return { then: function then(fulfill) { fulfill(value); } };
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 122, in resolve
    deferred.resolve(onFulfill ? onFulfill(value) : value);
  File "resource://gre/modules/commonjs/sdk/places/history.js", line 55, in query/</<
    emit(emitter, 'end', results);
  File "resource://gre/modules/commonjs/sdk/event/core.js", line 85, in emit
    for each (let item in emit.lazy.apply(emit.lazy, arguments)) {
  File "resource://gre/modules/commonjs/sdk/event/core.js", line 112, in lazy
    if (~state.indexOf(listener)) yield listener.apply(target, args);
  File "resource://gre/modules/commonjs/sdk/places/utils.js", line 165, in null
    else resolve(items);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 189, in resolve
    result.then(observer.resolve, observer.reject);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 47, in then
    return { then: function then(fulfill) { fulfill(value); } };
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 122, in resolve
    deferred.resolve(onFulfill ? onFulfill(value) : value);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 122, in resolve
    deferred.resolve(onFulfill ? onFulfill(value) : value);
  File "resource://extensions.modules.2e87f45b-405a-482d-8bb1-caf741c884e5-at-jetpack.commonjs.path.tests/test-places-history.js", line 177, in exports.testSearchSort/<
    checkOrder(results, [4,3,0,2,1]);
  File "resource://extensions.modules.2e87f45b-405a-482d-8bb1-caf741c884e5-at-jetpack.commonjs.path.tests/test-places-history.js", line 211, in checkOrder
    assert.equal(results[i].url, places[nums[i]], 'successful order');
  File "resource://gre/modules/commonjs/sdk/test/assert.js", line 125, in equal
    operator: "=="
  File "resource://gre/modules/commonjs/sdk/test/assert.js", line 89, in fail
    this._log.fail(message);
  File "resource://gre/modules/commonjs/sdk/deprecated/unit-test.js", line 84, in fail
    this.console.testMessage(false, false, this.test.name, message);
  File "resource://gre/modules/commonjs/sdk/test/harness.js", line 523, in testMessage
    this.trace();
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | successful order - "http://mozilla.com/" == "http://mozilla.com/webfwd/"
TEST-INFO | Traceback (most recent call last):
  File "resource://gre/modules/commonjs/sdk/timers.js", line 31, in notify
    callback.apply(null, args);
  File "resource://gre/modules/commonjs/sdk/lang/functional.js", line 66, in invoke
    function invoke(callee, params, self) callee.apply(self, params);
  File "resource://gre/modules/commonjs/sdk/addon/events.js", line 27, in null
    else request.resolve(clone(data));
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 189, in resolve
    result.then(observer.resolve, observer.reject);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 47, in then
    return { then: function then(fulfill) { fulfill(value); } };
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 122, in resolve
    deferred.resolve(onFulfill ? onFulfill(value) : value);
  File "resource://gre/modules/commonjs/sdk/places/history.js", line 55, in query/</<
    emit(emitter, 'end', results);
  File "resource://gre/modules/commonjs/sdk/event/core.js", line 85, in emit
    for each (let item in emit.lazy.apply(emit.lazy, arguments)) {
  File "resource://gre/modules/commonjs/sdk/event/core.js", line 112, in lazy
    if (~state.indexOf(listener)) yield listener.apply(target, args);
  File "resource://gre/modules/commonjs/sdk/places/utils.js", line 165, in null
    else resolve(items);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 189, in resolve
    result.then(observer.resolve, observer.reject);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 47, in then
    return { then: function then(fulfill) { fulfill(value); } };
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 122, in resolve
    deferred.resolve(onFulfill ? onFulfill(value) : value);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 122, in resolve
    deferred.resolve(onFulfill ? onFulfill(value) : value);
  File "resource://extensions.modules.2e87f45b-405a-482d-8bb1-caf741c884e5-at-jetpack.commonjs.path.tests/test-places-history.js", line 177, in exports.testSearchSort/<
    checkOrder(results, [4,3,0,2,1]);
  File "resource://extensions.modules.2e87f45b-405a-482d-8bb1-caf741c884e5-at-jetpack.commonjs.path.tests/test-places-history.js", line 211, in checkOrder
    assert.equal(results[i].url, places[nums[i]], 'successful order');
  File "resource://gre/modules/commonjs/sdk/test/assert.js", line 125, in equal
    operator: "=="
  File "resource://gre/modules/commonjs/sdk/test/assert.js", line 89, in fail
    this._log.fail(message);
  File "resource://gre/modules/commonjs/sdk/deprecated/unit-test.js", line 84, in fail
    this.console.testMessage(false, false, this.test.name, message);
  File "resource://gre/modules/commonjs/sdk/test/harness.js", line 523, in testMessage
    this.trace();
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | successful order - "http://mozilla.com/webfwd/" == "http://webaud.io/"
TEST-INFO | Traceback (most recent call last):
  File "resource://gre/modules/commonjs/sdk/timers.js", line 31, in notify
    callback.apply(null, args);
  File "resource://gre/modules/commonjs/sdk/lang/functional.js", line 66, in invoke
    function invoke(callee, params, self) callee.apply(self, params);
  File "resource://gre/modules/commonjs/sdk/addon/events.js", line 27, in null
    else request.resolve(clone(data));
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 189, in resolve
    result.then(observer.resolve, observer.reject);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 47, in then
    return { then: function then(fulfill) { fulfill(value); } };
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 122, in resolve
    deferred.resolve(onFulfill ? onFulfill(value) : value);
  File "resource://gre/modules/commonjs/sdk/places/history.js", line 55, in query/</<
    emit(emitter, 'end', results);
  File "resource://gre/modules/commonjs/sdk/event/core.js", line 85, in emit
    for each (let item in emit.lazy.apply(emit.lazy, arguments)) {
  File "resource://gre/modules/commonjs/sdk/event/core.js", line 112, in lazy
    if (~state.indexOf(listener)) yield listener.apply(target, args);
  File "resource://gre/modules/commonjs/sdk/places/utils.js", line 165, in null
    else resolve(items);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 189, in resolve
    result.then(observer.resolve, observer.reject);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 47, in then
    return { then: function then(fulfill) { fulfill(value); } };
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 122, in resolve
    deferred.resolve(onFulfill ? onFulfill(value) : value);
  File "resource://gre/modules/commonjs/sdk/core/promise.js", line 122, in resolve
    deferred.resolve(onFulfill ? onFulfill(value) : value);
  File "resource://extensions.modules.2e87f45b-405a-482d-8bb1-caf741c884e5-at-jetpack.commonjs.path.tests/test-places-history.js", line 177, in exports.testSearchSort/<
    checkOrder(results, [4,3,0,2,1]);
  File "resource://extensions.modules.2e87f45b-405a-482d-8bb1-caf741c884e5-at-jetpack.commonjs.path.tests/test-places-history.js", line 211, in checkOrder
    assert.equal(results[i].url, places[nums[i]], 'successful order');
  File "resource://gre/modules/commonjs/sdk/test/assert.js", line 125, in equal
    operator: "=="
  File "resource://gre/modules/commonjs/sdk/test/assert.js", line 89, in fail
    this._log.fail(message);
  File "resource://gre/modules/commonjs/sdk/deprecated/unit-test.js", line 84, in fail
    this.console.testMessage(false, false, this.test.name, message);
  File "resource://gre/modules/commonjs/sdk/test/harness.js", line 523, in testMessage
    this.trace();
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchQuery | Test output exceeded timeout (60s).
Error Cleaning up: [Error 5] Access is denied: 'c:\\docume~1\\cltbld~1.t-x\\locals~1\\temp\\tmpml_swp.mozrunner\\extensions\\[e-mail]'
Error Cleaning up: [Error 145] The directory is not empty: 'c:\\docume~1\\cltbld~1.t-x\\locals~1\\temp\\tmpml_swp.mozrunner\\extensions'
Error Cleaning up: [Error 5] Access is denied: 'c:\\docume~1\\cltbld~1.t-x\\locals~1\\temp\\tmpml_swp.mozrunner\\healthreport.sqlite-shm'
Error Cleaning up: [Error 5] Access is denied: 'c:\\docume~1\\cltbld~1.t-x\\locals~1\\temp\\tmpml_swp.mozrunner\\places.sqlite-shm'
Error Cleaning up: [Error 5] Access is denied: 'c:\\docume~1\\cltbld~1.t-x\\locals~1\\temp\\tmpml_swp.mozrunner\\startupCache\\startupCache.4.little'
Error Cleaning up: [Error 145] The directory is not empty: 'c:\\docume~1\\cltbld~1.t-x\\locals~1\\temp\\tmpml_swp.mozrunner\\startupCache'
Error Cleaning up: [Error 5] Access is denied: 'c:\\docume~1\\cltbld~1.t-x\\locals~1\\temp\\tmpml_swp.mozrunner\\webappsstore.sqlite-shm'
Error Cleaning up: [Error 145] The directory is not empty: 'c:\\docume~1\\cltbld~1.t-x\\locals~1\\temp\\tmpml_swp.mozrunner'
Traceback (most recent call last):
  File "jetpack/bin/cfx", line 33, in <module>
    cuddlefish.run()
  File "C:\slave\test\build\jetpack\python-lib\cuddlefish\__init__.py", line 608, in run
    test_all_packages(env_root, defaults=options.__dict__)
  File "C:\slave\test\build\jetpack\python-lib\cuddlefish\__init__.py", line 490, in test_all_packages
    env_root=env_root)
  File "C:\slave\test\build\jetpack\python-lib\cuddlefish\__init__.py", line 937, in run
    pkgdir=options.pkgdir)
  File "C:\slave\test\build\jetpack\python-lib\cuddlefish\runner.py", line 745, in run_app
    OUTPUT_TIMEOUT, test_name, parseable)
Exception: Test output exceeded timeout (60s).
program finished with exit code 1

Comment 1

[Legacy TBPL/Treeherder Robot]

RyanVM
https://tbpl.mozilla.org/php/getParsedLog.php?id=25793867&tree=Birch
Rev5 MacOSX Mountain Lion 10.8 birch opt test jetpack on 2013-07-26 14:04:38
revision: 5aa02ee02f4b
slave: talos-mtnlion-r5-042

TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | expected return count - 5 == 1
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | Test output exceeded timeout (60s).

Comment 2

[Legacy TBPL/Treeherder Robot]

KWierso
https://tbpl.mozilla.org/php/getParsedLog.php?id=25885719&tree=Fx-Team
Rev5 MacOSX Mountain Lion 10.8 fx-team opt test jetpack on 2013-07-29 17:17:00
revision: 8911e64ad234
slave: talos-mtnlion-r5-074

TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | Test output exceeded timeout (60s).

Comment 3

[Legacy TBPL/Treeherder Robot]

KWierso
https://tbpl.mozilla.org/php/getParsedLog.php?id=26246783&tree=Mozilla-Inbound
Windows XP 32-bit mozilla-inbound opt test jetpack on 2013-08-06 21:39:29
revision: 616e6e640f43
slave: t-xp32-ix-123

TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | Test output exceeded timeout (60s).

Comment 4

[Legacy TBPL/Treeherder Robot]

edmorley
https://tbpl.mozilla.org/php/getParsedLog.php?id=26582085&tree=Mozilla-Central
Windows XP 32-bit mozilla-central opt test jetpack on 2013-08-15 05:17:20
revision: 31c08ca022b3
slave: t-xp32-ix-023

TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | Timed out

Comment 5

[Legacy TBPL/Treeherder Robot]

RyanVM
https://tbpl.mozilla.org/php/getParsedLog.php?id=27109221&tree=Mozilla-Inbound
Windows XP 32-bit mozilla-inbound opt test jetpack on 2013-08-28 03:25:56
revision: 40db75af375f
slave: t-xp32-ix-047

TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | expected return count - 5 == 2
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | successful order - "http://bandcamp.com/" == "http://mozilla.com/"
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | successful order - "http://developer.mozilla.com/" == "http://webaud.io/"
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | Timed out

Comment 6

[Legacy TBPL/Treeherder Robot]

honzab.moz%firemni.cz
https://tbpl.mozilla.org/php/getParsedLog.php?id=27192422&tree=Gum
Windows XP 32-bit gum opt test jetpack on 2013-08-29 14:19:47
revision: f1e2c504a9e8
slave: t-xp32-ix-114

TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | Test output exceeded timeout (60s).

Comment 7

[Legacy TBPL/Treeherder Robot]

Gijs
https://tbpl.mozilla.org/php/getParsedLog.php?id=29511258&tree=UX
Windows XP 32-bit ux opt test jetpack on 2013-10-22 14:11:20
revision: 4d3ff167c46f
slave: t-xp32-ix-102

TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | expected return count - 5 == 3
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | successful order - "http://bandcamp.com/" == "http://mozilla.com/"
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | successful order - "http://developer.mozilla.com/" == "http://mozilla.com/webfwd/"
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | successful order - "http://mozilla.com/" == "http://webaud.io/"
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | Timed out

Comment 8

[Legacy TBPL/Treeherder Robot]

Gijs
https://tbpl.mozilla.org/php/getParsedLog.php?id=29981184&tree=UX
Windows XP 32-bit ux opt test jetpack on 2013-11-01 10:08:27
revision: b48128b81a1f
slave: t-xp32-ix-044

TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | expected return count - 5 == 3
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | successful order - "http://bandcamp.com/" == "http://mozilla.com/"
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | successful order - "http://developer.mozilla.com/" == "http://mozilla.com/webfwd/"
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | successful order - "http://mozilla.com/" == "http://webaud.io/"
TEST-UNEXPECTED-FAIL | tests/test-places-history.testSearchSort | Timed out

Comment 9

[Jordan Santell [:jsantell] [@jsantell]]

Moved into addon-test, seems to fix