1569414 - Intermittent browser/components/migration/tests/unit/test_Chrome_history.js | test_import - [test_import : 83] Should have stored an entry - null == true

Status: RESOLVED FIXED

(Firefox :: Migration, defect, P1)

Description

[Treeherder Bug Filer]

Filed by: ccoroiu [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=258647931&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/QZqBM4V-Q722UuTIF3MrHQ/runs/0/artifacts/public/logs/live_backing.log

---

task 2019-07-27T13:53:59.241Z] 13:53:59 INFO - TEST-START | browser/components/migration/tests/unit/test_Chrome_history.js
[task 2019-07-27T13:53:59.977Z] 13:53:59 WARNING - TEST-UNEXPECTED-FAIL | browser/components/migration/tests/unit/test_Chrome_history.js | xpcshell return code: 0
[task 2019-07-27T13:53:59.977Z] 13:53:59 INFO - TEST-INFO took 727ms
[task 2019-07-27T13:53:59.977Z] 13:53:59 INFO - >>>>>>>
[task 2019-07-27T13:53:59.977Z] 13:53:59 INFO - PID 3703 | [3703, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file /builds/worker/workspace/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 2634
[task 2019-07-27T13:53:59.977Z] 13:53:59 INFO - PID 3703 | [3703, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, kKnownEsrVersion) failed with result 0x80004002: file /builds/worker/workspace/build/src/toolkit/components/resistfingerprinting/nsRFPService.cpp, line 661
[task 2019-07-27T13:53:59.978Z] 13:53:59 INFO - PID 3703 | [3703, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/extensions/permissions/nsPermissionManager.cpp, line 2903
[task 2019-07-27T13:53:59.978Z] 13:53:59 INFO - (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2019-07-27T13:53:59.978Z] 13:53:59 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2019-07-27T13:53:59.978Z] 13:53:59 INFO - (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2019-07-27T13:53:59.978Z] 13:53:59 INFO - running event loop
[task 2019-07-27T13:53:59.978Z] 13:53:59 INFO - browser/components/migration/tests/unit/test_Chrome_history.js | Starting setup
[task 2019-07-27T13:53:59.978Z] 13:53:59 INFO - (xpcshell/head.js) | test setup pending (2)
[task 2019-07-27T13:53:59.978Z] 13:53:59 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2019-07-27T13:53:59.978Z] 13:53:59 INFO - (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2019-07-27T13:53:59.978Z] 13:53:59 INFO - (xpcshell/head.js) | test setup finished (2)
[task 2019-07-27T13:53:59.979Z] 13:53:59 INFO - browser/components/migration/tests/unit/test_Chrome_history.js | Starting test_import
[task 2019-07-27T13:53:59.979Z] 13:53:59 INFO - (xpcshell/head.js) | test test_import pending (2)
[task 2019-07-27T13:53:59.979Z] 13:53:59 INFO - (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2019-07-27T13:53:59.979Z] 13:53:59 INFO - TEST-PASS | browser/components/migration/tests/unit/test_Chrome_history.js | test_import - [test_import : 151] Sanity check the source exists - true == true
[task 2019-07-27T13:53:59.979Z] 13:53:59 INFO - TEST-PASS | browser/components/migration/tests/unit/test_Chrome_history.js | test_import - [test_import : 57] Resource supported by migrator - true == true
[task 2019-07-27T13:53:59.979Z] 13:53:59 INFO - "Checking url: http://example.com/"
[task 2019-07-27T13:53:59.979Z] 13:53:59 WARNING - TEST-UNEXPECTED-FAIL | browser/components/migration/tests/unit/test_Chrome_history.js | test_import - [test_import : 83] Should have stored an entry - null == true
[task 2019-07-27T13:53:59.979Z] 13:53:59 INFO - /Users/cltbld/tasks/task_1564230584/build/tests/xpcshell/tests/browser/components/migration/tests/unit/test_Chrome_history.js:assertEntryMatches:83
[task 2019-07-27T13:53:59.979Z] 13:53:59 INFO - /Users/cltbld/tasks/task_1564230584/build/tests/xpcshell/tests/browser/components/migration/tests/unit/test_Chrome_history.js:test_import:166
[task 2019-07-27T13:53:59.980Z] 13:53:59 INFO - exiting test
[task 2019-07-27T13:53:59.980Z] 13:53:59 INFO - Unexpected exception NS_ERROR_ABORT:
[task 2019-07-27T13:53:59.980Z] 13:53:59 INFO - _abort_failed_test@/Users/cltbld/tasks/task_1564230584/build/tests/xpcshell/head.js:788:20
[task 2019-07-27T13:53:59.980Z] 13:53:59 INFO - do_report_result@/Users/cltbld/tasks/task_1564230584/build/tests/xpcshell/head.js:889:5
[task 2019-07-27T13:53:59.980Z] 13:53:59 INFO - Assert<@/Users/cltbld/tasks/task_1564230584/build/tests/xpcshell/head.js:66:5
[task 2019-07-27T13:53:59.980Z] 13:53:59 INFO - proto.report@resource://testing-common/Assert.jsm:233:10
[task 2019-07-27T13:53:59.980Z] 13:53:59 INFO - proto.ok@resource://testing-common/Assert.jsm:259:10
[task 2019-07-27T13:53:59.980Z] 13:53:59 INFO - assertEntryMatches@/Users/cltbld/tasks/task_1564230584/build/tests/xpcshell/tests/browser/components/migration/tests/unit/test_Chrome_history.js:83:10
[task 2019-07-27T13:53:59.981Z] 13:53:59 INFO - test_import@/Users/cltbld/tasks/task_1564230584/build/tests/xpcshell/tests/browser/components/migration/tests/unit/test_Chrome_history.js:166:5
[task 2019-07-27T13:53:59.981Z] 13:53:59 INFO - async*run_next_test/_run_next_test/<@/Users/cltbld/tasks/task_1564230584/build/tests/xpcshell/head.js:1564:22
[task 2019-07-27T13:53:59.981Z] 13:53:59 INFO - _run_next_test@/Users/cltbld/tasks/task_1564230584/build/tests/xpcshell/head.js:1564:38
[task 2019-07-27T13:53:59.981Z] 13:53:59 INFO - run@/Users/cltbld/tasks/task_1564230584/build/tests/xpcshell/head.js:732:9
[task 2019-07-27T13:53:59.981Z] 13:53:59 INFO - _do_main@/Users/cltbld/tasks/task_1564230584/build/tests/xpcshell/head.js:245:6
[task 2019-07-27T13:53:59.981Z] 13:53:59 INFO - _execute_test@/Users/cltbld/tasks/task_1564230584/build/tests/xpcshell/head.js:572:5
[task 2019-07-27T13:53:59.981Z] 13:53:59 INFO - @-e:1:1
[task 2019-07-27T13:53:59.981Z] 13:53:59 INFO - exiting test
[task 2019-07-27T13:53:59.981Z] 13:53:59 INFO - PID 3703 | JavaScript error: resource://testing-common/PromiseTestUtils.jsm, line 112: uncaught exception: Object
[task 2019-07-27T13:53:59.982Z] 13:53:59 INFO - PID 3703 | [3703, Main Thread] WARNING: OOPDeinit() without successful OOPInit(): file /builds/worker/workspace/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 3116
[task 2019-07-27T13:53:59.982Z] 13:53:59 INFO - PID 3703 | [3703, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
[task 2019-07-27T13:53:59.982Z] 13:53:59 INFO - PID 3703 | [3703, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
[task 2019-07-27T13:53:59.982Z] 13:53:59 INFO - PID 3703 | nsStringStats
[task 2019-07-27T13:53:59.982Z] 13:53:59 INFO - PID 3703 | => mAllocCount: 12570
[task 2019-07-27T13:53:59.982Z] 13:53:59 INFO - PID 3703 | => mReallocCount: 0
[task 2019-07-27T13:53:59.982Z] 13:53:59 INFO - PID 3703 | => mFreeCount: 12570
[task 2019-07-27T13:53:59.982Z] 13:53:59 INFO - PID 3703 | => mShareCount: 10429
[task 2019-07-27T13:53:59.982Z] 13:53:59 INFO - PID 3703 | => mAdoptCount: 244
[task 2019-07-27T13:53:59.982Z] 13:53:59 INFO - PID 3703 | => mAdoptFreeCount: 248
[task 2019-07-27T13:53:59.983Z] 13:53:59 INFO - PID 3703 | => Process ID: 3703, Thread ID: 4495570368
[task 2019-07-27T13:53:59.983Z] 13:53:59 INFO - <<<<<<<

Comment 1

[Cristina Coroiu [:ccoroiu]]

Started to fail here:
https://treeherder.mozilla.org/#/jobs?repo=autoland&selectedJob=258648724&resultStatus=testfailed%2Cbusted%2Cexception&classifiedState=unclassified&searchStr=os%2Cx%2C10.14%2Cdebug%2Cxpcshell%2Ctests%2Ctest-macosx1014-64%2Fdebug-xpcshell-e10s-1%2Cx%28x1%29&revision=bc2fd0d9da259ab14a0eec550238c0dafac79d56

All the retriggers started to fail:
Autoland:
https://treeherder.mozilla.org/#/jobs?repo=autoland&selectedJob=258650001&resultStatus=testfailed%2Cbusted%2Cexception&classifiedState=unclassified&revision=6aa27e77e38f25d65fb4b534051765d03db5ed8b

Central:
https://treeherder.mozilla.org/#/jobs?repo=mozilla-central&searchStr=os%2Cx%2C10.14%2Cdebug%2Cxpcshell%2Ctests%2Ctest-macosx1014-64%2Fdebug-xpcshell-e10s-1%2Cx%28x1%29&selectedJob=258649401

Inbound:
https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&searchStr=os%2Cx%2C10.14%2Cdebug%2Cxpcshell%2Ctests%2Ctest-macosx1014-64%2Fdebug-xpcshell-e10s-1%2Cx%28x1%29&selectedJob=258649553

Comment 2

[Matthew N. [:MattN]]

The issue is a time bomb with the history records being older than 6 months:

ChromeMigrationUtils.chromeTimeToDate(13193151310368000)

Mon Jan 28 2019 04:15:10 GMT-0800

Comment 3

[Matthew N. [:MattN]]

Attachment: Bug 1569414 - Don't hardcode a too old history visit date in test_Chrome_history.js.

Comment 4

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/9391eb2b4fff
Don't hardcode a too old history visit date in test_Chrome_history.js.

Comment 5

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/mozilla-inbound/rev/8e3b9ca5a13b
Don't hardcode a too old history visit date in test_Chrome_history.js.

Comment 6

[Natalia Csoregi [:nataliaCs]]

https://hg.mozilla.org/mozilla-central/rev/9391eb2b4fff

Comment 7

[Natalia Csoregi [:nataliaCs]]

https://hg.mozilla.org/mozilla-central/rev/8e3b9ca5a13b

Comment 8

[Intermittent Failures Robot]

66 failures in 147 pushes (0.449 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 23
• mozilla-central: 15
• try: 17
• mozilla-inbound: 11

Platform breakdown:

• macosx1014-64: 47
• macosx1014-64-devedition: 7
• macosx1014-64-shippable: 12

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1569414&startday=2019-07-27&endday=2019-07-27&tree=all

Comment 9

[Intermittent Failures Robot]

76 failures in 4608 pushes (0.016 failures/push) were associated with this bug in the last 7 days.

This is the #15 most frequent failure this week.

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. **

Repository breakdown:

• try: 17
• autoland: 33
• mozilla-central: 15
• mozilla-inbound: 11

Platform breakdown:

• macosx1014-64-devedition: 7
• macosx1014-64-shippable: 12
• macosx1014-64: 57

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1569414&startday=2019-07-22&endday=2019-07-28&tree=all

Comment 10

[Ryan VanderMeulen [:RyanVM]]

https://hg.mozilla.org/releases/mozilla-beta/rev/52440e49ce3b

Comment 11

[Ryan VanderMeulen [:RyanVM]]

https://hg.mozilla.org/releases/mozilla-release/rev/f28fee4a9632

Comment 12

[Ryan VanderMeulen [:RyanVM]]

https://hg.mozilla.org/releases/mozilla-esr68/rev/35e9afa92893

Comment 13

[Razvan Maries]

New occurrence: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=259368350&repo=mozilla-esr68&lineNumber=2224

Comment 14

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

Added to FIREFOX_ESR_68_0_X_RELBRANCH: https://hg.mozilla.org/releases/mozilla-esr68/rev/b7bcd6d8069f6ce643bc24c6e26e50f830ac5613
This fixes the failure mentioned in comment 13.

Comment 15

[Intermittent Failures Robot]

9 failures in 4439 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-release: 2
• mozilla-esr68: 7

Platform breakdown:

• macosx1010-64: 2
• macosx1010-64-shippable: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1569414&startday=2019-07-29&endday=2019-08-04&tree=all