1597923 - Intermittent toolkit/components/search/tests/xpcshell/test_searchSuggest.js | fetch_twice_in_a_row - [fetch_twice_in_a_row : 261] 0 == 1

Status: RESOLVED INCOMPLETE

(Firefox :: Search, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2019-11-20T06:21:02.711Z] 06:21:02 INFO - TEST-PASS | xpcshell.ini:toolkit/components/search/tests/xpcshell/test_searchSuggest.js | both_local_remote_result_dedupe3 - [both_local_remote_result_dedupe3 : 242] 0 == 0
[task 2019-11-20T06:21:02.711Z] 06:21:02 INFO - (xpcshell/head.js) | test run_next_test 14 pending (2)
[task 2019-11-20T06:21:02.711Z] 06:21:02 INFO - (xpcshell/head.js) | test both_local_remote_result_dedupe3 finished (2)
[task 2019-11-20T06:21:02.712Z] 06:21:02 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "XML Parsing Error: syntax error
[task 2019-11-20T06:21:02.712Z] 06:21:02 INFO - Location: http://localhost:53559/data/searchSuggestions.sjs?q=mo
[task 2019-11-20T06:21:02.712Z] 06:21:02 INFO - Line Number 1, Column 1:" {file: "http://localhost:53559/data/searchSuggestions.sjs?q=mo" line: 1 column: 1 source: "["mo",["Mozilla","modern","mom"]]"}]"
[task 2019-11-20T06:21:02.712Z] 06:21:02 INFO - xpcshell.ini:toolkit/components/search/tests/xpcshell/test_searchSuggest.js | Starting fetch_twice_in_a_row
[task 2019-11-20T06:21:02.712Z] 06:21:02 INFO - (xpcshell/head.js) | test fetch_twice_in_a_row pending (2)
[task 2019-11-20T06:21:02.712Z] 06:21:02 INFO - (xpcshell/head.js) | test run_next_test 14 finished (2)
[task 2019-11-20T06:21:02.712Z] 06:21:02 INFO - PID 3328 | *** Search: getSubmission: In data: "delay"; Purpose: "null"
[task 2019-11-20T06:21:02.712Z] 06:21:02 INFO - PID 3328 | *** Search: getSubmission: Out data: "delay"
[task 2019-11-20T06:21:02.712Z] 06:21:02 INFO - PID 3328 | *** Search: getSubmission: In data: "delayed "; Purpose: "null"
[task 2019-11-20T06:21:02.712Z] 06:21:02 INFO - PID 3328 | *** Search: getSubmission: Out data: "delayed+"
[task 2019-11-20T06:21:02.712Z] 06:21:02 INFO - TEST-PASS | xpcshell.ini:toolkit/components/search/tests/xpcshell/test_searchSuggest.js | fetch_twice_in_a_row - [fetch_twice_in_a_row : 255] null == null
[task 2019-11-20T06:21:02.713Z] 06:21:02 INFO - "CONSOLE_MESSAGE: (info) getSubmission: In data: "delay"; Purpose: "null""
[task 2019-11-20T06:21:02.713Z] 06:21:02 INFO - "CONSOLE_MESSAGE: (info) getSubmission: Out data: "delay""
[task 2019-11-20T06:21:02.713Z] 06:21:02 INFO - "CONSOLE_MESSAGE: (info) getSubmission: In data: "delayed "; Purpose: "null""
[task 2019-11-20T06:21:02.713Z] 06:21:02 INFO - "CONSOLE_MESSAGE: (info) getSubmission: Out data: "delayed+""
[task 2019-11-20T06:21:02.713Z] 06:21:02 INFO - TEST-PASS | xpcshell.ini:toolkit/components/search/tests/xpcshell/test_searchSuggest.js | fetch_twice_in_a_row - [fetch_twice_in_a_row : 258] "delayed " == "delayed "
[task 2019-11-20T06:21:02.713Z] 06:21:02 INFO - TEST-PASS | xpcshell.ini:toolkit/components/search/tests/xpcshell/test_searchSuggest.js | fetch_twice_in_a_row - [fetch_twice_in_a_row : 259] 1 == 1
[task 2019-11-20T06:21:02.713Z] 06:21:02 INFO - TEST-PASS | xpcshell.ini:toolkit/components/search/tests/xpcshell/test_searchSuggest.js | fetch_twice_in_a_row - [fetch_twice_in_a_row : 260] "delayed local" == "delayed local"
[task 2019-11-20T06:21:02.713Z] 06:21:02 WARNING - TEST-UNEXPECTED-FAIL | xpcshell.ini:toolkit/components/search/tests/xpcshell/test_searchSuggest.js | fetch_twice_in_a_row - [fetch_twice_in_a_row : 261] 0 == 1
[task 2019-11-20T06:21:02.713Z] 06:21:02 INFO - Z:/task_1574227999/build/tests/xpcshell/tests/toolkit/components/search/tests/xpcshell/test_searchSuggest.js:fetch_twice_in_a_row:261
[task 2019-11-20T06:21:02.713Z] 06:21:02 INFO - exiting test
[task 2019-11-20T06:21:02.713Z] 06:21:02 INFO - Unexpected exception NS_ERROR_ABORT:
[task 2019-11-20T06:21:02.713Z] 06:21:02 INFO - _abort_failed_test@Z:\task_1574227999\build\tests\xpcshell\head.js:791:20
[task 2019-11-20T06:21:02.713Z] 06:21:02 INFO - do_report_result@Z:\task_1574227999\build\tests\xpcshell\head.js:892:5
[task 2019-11-20T06:21:02.713Z] 06:21:02 INFO - Assert<@Z:\task_1574227999\build\tests\xpcshell\head.js:67:21
[task 2019-11-20T06:21:02.714Z] 06:21:02 INFO - proto.report@resource://testing-common/Assert.jsm:233:10
[task 2019-11-20T06:21:02.714Z] 06:21:02 INFO - equal@resource://testing-common/Assert.jsm:275:8
[task 2019-11-20T06:21:02.714Z] 06:21:02 INFO - fetch_twice_in_a_row@Z:/task_1574227999/build/tests/xpcshell/tests/toolkit/components/search/tests/xpcshell/test_searchSuggest.js:261:10
[task 2019-11-20T06:21:02.714Z] 06:21:02 INFO - async*run_next_test/_run_next_test/<@Z:\task_1574227999\build\tests\xpcshell\head.js:1567:22
[task 2019-11-20T06:21:02.714Z] 06:21:02 INFO - _run_next_test@Z:\task_1574227999\build\tests\xpcshell\head.js:1567:38
[task 2019-11-20T06:21:02.714Z] 06:21:02 INFO - run@Z:\task_1574227999\build\tests\xpcshell\head.js:735:9
[task 2019-11-20T06:21:02.714Z] 06:21:02 INFO - _do_main@Z:\task_1574227999\build\tests\xpcshell\head.js:246:6
[task 2019-11-20T06:21:02.714Z] 06:21:02 INFO - _execute_test@Z:\task_1574227999\build\tests\xpcshell\head.js:573:5
[task 2019-11-20T06:21:02.714Z] 06:21:02 INFO - @-e:1:1
[task 2019-11-20T06:21:02.714Z] 06:21:02 INFO - exiting test
[task 2019-11-20T06:21:02.714Z] 06:21:02 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "SearchSuggestionController: HTTP Timeout" {file: "resource://gre/modules/SearchSuggestionController.jsm" line: 444}]
[task 2019-11-20T06:21:02.714Z] 06:21:02 INFO - _dedupeAndReturnResults@resource://gre/modules/SearchSuggestionController.jsm:444:12
[task 2019-11-20T06:21:02.715Z] 06:21:02 INFO - _do_main@Z:\task_1574227999\build\tests\xpcshell\head.js:246:6
[task 2019-11-20T06:21:02.715Z] 06:21:02 INFO - _execute_test@Z:\task_1574227999\build\tests\xpcshell\head.js:573:5
[task 2019-11-20T06:21:02.715Z] 06:21:02 INFO - @-e:1:1
[task 2019-11-20T06:21:02.715Z] 06:21:02 INFO - "
[task 2019-11-20T06:21:02.715Z] 06:21:02 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "XML Parsing Error: syntax error
[task 2019-11-20T06:21:02.715Z] 06:21:02 INFO - Location: http://localhost:53559/data/searchSuggestions.sjs?q=delayed+
[task 2019-11-20T06:21:02.715Z] 06:21:02 INFO - Line Number 1, Column 1:" {file: "http://localhost:53559/data/searchSuggestions.sjs?q=delayed+" line: 1 column: 1 source: "["delayed ",["delayed "]]"}]"
[task 2019-11-20T06:21:02.715Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: NS_ENSURE_TRUE(mReady) failed: file z:/build/build/src/xpfe/appshell/nsWindowMediator.cpp, line 164
[task 2019-11-20T06:21:02.715Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x8000FFFF: file z:/build/build/src/dom/base/nsCCUncollectableMarker.cpp, line 335
[task 2019-11-20T06:21:02.715Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: '!mIOThread', file z:/build/build/src/xpcom/io/nsSegmentedBuffer.cpp, line 165
[task 2019-11-20T06:21:02.715Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: '!mIOThread', file z:/build/build/src/xpcom/io/nsSegmentedBuffer.cpp, line 165
[task 2019-11-20T06:21:02.715Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: '!mIOThread', file z:/build/build/src/xpcom/io/nsSegmentedBuffer.cpp, line 165
[task 2019-11-20T06:21:02.716Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: '!mIOThread', file z:/build/build/src/xpcom/io/nsSegmentedBuffer.cpp, line 165
[task 2019-11-20T06:21:02.716Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: '!mIOThread', file z:/build/build/src/xpcom/io/nsSegmentedBuffer.cpp, line 165
[task 2019-11-20T06:21:02.716Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: '!mIOThread', file z:/build/build/src/xpcom/io/nsSegmentedBuffer.cpp, line 165
[task 2019-11-20T06:21:02.716Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: '!mIOThread', file z:/build/build/src/xpcom/io/nsSegmentedBuffer.cpp, line 165
[task 2019-11-20T06:21:02.716Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: '!mIOThread', file z:/build/build/src/xpcom/io/nsSegmentedBuffer.cpp, line 165
[task 2019-11-20T06:21:02.716Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: '!mIOThread', file z:/build/build/src/xpcom/io/nsSegmentedBuffer.cpp, line 165
[task 2019-11-20T06:21:02.716Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: '!mIOThread', file z:/build/build/src/xpcom/io/nsSegmentedBuffer.cpp, line 165
[task 2019-11-20T06:21:02.716Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: '!mIOThread', file z:/build/build/src/xpcom/io/nsSegmentedBuffer.cpp, line 165
[task 2019-11-20T06:21:02.716Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: '!mIOThread', file z:/build/build/src/xpcom/io/nsSegmentedBuffer.cpp, line 165
[task 2019-11-20T06:21:02.716Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: OOPDeinit() without successful OOPInit(): file z:/build/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 3114
[task 2019-11-20T06:21:02.716Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file z:/build/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-11-20T06:21:02.716Z] 06:21:02 INFO - PID 3328 | [3328, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file z:/build/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-11-20T06:21:02.716Z] 06:21:02 INFO - PID 3328 | nsStringStats
[task 2019-11-20T06:21:02.716Z] 06:21:02 INFO - PID 3328 | => mAllocCount: 61262
[task 2019-11-20T06:21:02.716Z] 06:21:02 INFO - PID 3328 | => mReallocCount: 0
[task 2019-11-20T06:21:02.717Z] 06:21:02 INFO - PID 3328 | => mFreeCount: 61262
[task 2019-11-20T06:21:02.717Z] 06:21:02 INFO - PID 3328 | => mShareCount: 45441
[task 2019-11-20T06:21:02.717Z] 06:21:02 INFO - PID 3328 | => mAdoptCount: 1263
[task 2019-11-20T06:21:02.717Z] 06:21:02 INFO - PID 3328 | => mAdoptFreeCount: 1271
[task 2019-11-20T06:21:02.717Z] 06:21:02 INFO - PID 3328 | => Process ID: 3328, Thread ID: 13164
[task 2019-11-20T06:21:02.717Z] 06:21:02 INFO - <<<<<<<
[task 2019-11-20T06:21:02.735Z] 06:21:02 INFO - TEST-START | toolkit/components/url-classifier/tests/unit/test_listmanager.js

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 12

Platform breakdown:

• windows10-64-ccov: 12

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597923&startday=2019-11-18&endday=2019-11-24&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 10

Platform breakdown:

• windows10-64-ccov: 10

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597923&startday=2019-11-25&endday=2019-12-01&tree=all

Comment 3

[Intermittent Failures Robot]

6 failures in 5144 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 6

Platform breakdown:

• windows10-64-ccov: 6

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

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 11

Platform breakdown:

• windows10-64-ccov: 11

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597923&startday=2019-12-09&endday=2019-12-15&tree=all

Comment 5

[Intermittent Failures Robot]

5 failures in 4582 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 4
• autoland: 1

Platform breakdown:

• windows10-64-ccov: 5

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

Comment 6

[Intermittent Failures Robot]

2 failures in 1365 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 2

Platform breakdown:

• windows10-64-ccov: 2

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

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 3

Platform breakdown:

• windows10-64-ccov: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597923&startday=2019-12-30&endday=2020-01-05&tree=all

Comment 8

[Intermittent Failures Robot]

2 failures in 4004 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 2

Platform breakdown:

• windows10-64-ccov: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597923&startday=2020-01-20&endday=2020-01-26&tree=all

Comment 9

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 10

[Intermittent Failures Robot]

1 failures in 4045 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1

Platform breakdown:

• linux64-tsan: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597923&startday=2020-02-17&endday=2020-02-23&tree=all