1596268 - Intermittent netwerk/test/unit/test_cache2-27-force-valid-for.js | run_test/</< - [run_test/</< : 20] false == true | xpcshell return code: 0

Status: RESOLVED WORKSFORME

(Core :: Networking, defect, P3)

Description

[Treeherder Bug Filer]

Filed by: ncsoregi [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=276082388&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/d-bqNDmsTdCpASc5SEidJg/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-11-13T22:06:51.317Z] 22:06:51 INFO - TEST-PASS | netwerk/test/unit/test_cache2-27-force-valid-for.js | run_test/< - [run_test/< : 15] false == false
[task 2019-11-13T22:06:51.317Z] 22:06:51 INFO - (xpcshell/head.js) | test pending (2)
[task 2019-11-13T22:06:51.317Z] 22:06:51 INFO - (xpcshell/head.js) | test pending (3)
[task 2019-11-13T22:06:51.317Z] 22:06:51 INFO - (xpcshell/head.js) | test MAIN run_test finished (3)
[task 2019-11-13T22:06:51.317Z] 22:06:51 INFO - running event loop
[task 2019-11-13T22:06:51.317Z] 22:06:51 INFO - (xpcshell/head.js) | test pending (3)
[task 2019-11-13T22:06:51.317Z] 22:06:51 INFO - (xpcshell/head.js) | test finished (3)
[task 2019-11-13T22:06:51.317Z] 22:06:51 INFO - netwerk/test/unit/test_cache2-27-force-valid-for.js | TEST-INFO | CACHE2: callback #1(data) openOutputStream @ 0
[task 2019-11-13T22:06:51.317Z] 22:06:51 INFO - netwerk/test/unit/test_cache2-27-force-valid-for.js | TEST-INFO | CACHE2: callback #1(data) writing data
[task 2019-11-13T22:06:51.317Z] 22:06:51 INFO - TEST-PASS | netwerk/test/unit/test_cache2-27-force-valid-for.js | undefined assertion name - 4 == 4
[task 2019-11-13T22:06:51.317Z] 22:06:51 INFO - (xpcshell/head.js) | test finished (2)
[task 2019-11-13T22:06:51.317Z] 22:06:51 WARNING - TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_cache2-27-force-valid-for.js | run_test/</< - [run_test/</< : 20] false == true
[task 2019-11-13T22:06:51.318Z] 22:06:51 INFO - test_cache2-27-force-valid-for.js:run_test/</<:20
[task 2019-11-13T22:06:51.318Z] 22:06:51 INFO - /sdcard/tests/xpc/head.js:notify:219
[task 2019-11-13T22:06:51.318Z] 22:06:51 INFO - /sdcard/tests/xpc/head.js:_do_main:246
[task 2019-11-13T22:06:51.318Z] 22:06:51 INFO - /sdcard/tests/xpc/head.js:_execute_test:573
[task 2019-11-13T22:06:51.318Z] 22:06:51 INFO - -e:null:1
[task 2019-11-13T22:06:51.318Z] 22:06:51 INFO - exiting test
[task 2019-11-13T22:06:51.318Z] 22:06:51 INFO - exception thrown from do_timeout callback: [Exception... "Abort" nsresult: "0x80004004 (NS_ERROR_ABORT)" location: "JS frame :: /sdcard/tests/xpc/head.js :: _abort_failed_test :: line 791" data: no]
[task 2019-11-13T22:06:51.318Z] 22:06:51 INFO - /sdcard/tests/xpc/head.js:notify:221
[task 2019-11-13T22:06:51.318Z] 22:06:51 INFO - /sdcard/tests/xpc/head.js:_do_main:246
[task 2019-11-13T22:06:51.318Z] 22:06:51 INFO - /sdcard/tests/xpc/head.js:_execute_test:573
[task 2019-11-13T22:06:51.318Z] 22:06:51 INFO - -e:null:1
[task 2019-11-13T22:06:51.318Z] 22:06:51 INFO - exiting test
[task 2019-11-13T22:06:51.318Z] 22:06:51 INFO - netwerk/test/unit/test_cache2-27-force-valid-for.js | JavaScript error: /sdcard/tests/xpc/head.js, line 791: NS_ERROR_ABORT:
[task 2019-11-13T22:06:51.319Z] 22:06:51 INFO - TEST-PASS | netwerk/test/unit/test_cache2-27-force-valid-for.js | run_test/</< - [run_test/</< : 26] false == false
[task 2019-11-13T22:06:51.319Z] 22:06:51 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "NS_ERROR_ABORT: " {file: "/sdcard/tests/xpc/head.js" line: 791}]"
[task 2019-11-13T22:06:51.319Z] 22:06:51 INFO - netwerk/test/unit/test_cache2-27-force-valid-for.js | [17861, Main Thread] WARNING: OOPDeinit() without successful OOPInit(): file /builds/worker/workspace/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 3114
[task 2019-11-13T22:06:51.319Z] 22:06:51 INFO - netwerk/test/unit/test_cache2-27-force-valid-for.js | [17861, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-11-13T22:06:51.319Z] 22:06:51 INFO - netwerk/test/unit/test_cache2-27-force-valid-for.js | [17861, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-11-13T22:06:51.319Z] 22:06:51 INFO - netwerk/test/unit/test_cache2-27-force-valid-for.js | [17861, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-11-13T22:06:51.319Z] 22:06:51 INFO - netwerk/test/unit/test_cache2-27-force-valid-for.js | [17861, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-11-13T22:06:51.319Z] 22:06:51 INFO - netwerk/test/unit/test_cache2-27-force-valid-for.js | nsStringStats
[task 2019-11-13T22:06:51.320Z] 22:06:51 INFO - netwerk/test/unit/test_cache2-27-force-valid-for.js | => mAllocCount: 10128
[task 2019-11-13T22:06:51.320Z] 22:06:51 INFO - netwerk/test/unit/test_cache2-27-force-valid-for.js | => mReallocCount: 0
[task 2019-11-13T22:06:51.320Z] 22:06:51 INFO - netwerk/test/unit/test_cache2-27-force-valid-for.js | => mFreeCount: 10128
[task 2019-11-13T22:06:51.320Z] 22:06:51 INFO - netwerk/test/unit/test_cache2-27-force-valid-for.js | => mShareCount: 6623
[task 2019-11-13T22:06:51.320Z] 22:06:51 INFO - netwerk/test/unit/test_cache2-27-force-valid-for.js | => mAdoptCount: 159
[task 2019-11-13T22:06:51.320Z] 22:06:51 INFO - netwerk/test/unit/test_cache2-27-force-valid-for.js | => mAdoptFreeCount: 159
[task 2019-11-13T22:06:51.320Z] 22:06:51 INFO - netwerk/test/unit/test_cache2-27-force-valid-for.js | => Process ID: 17861, Thread ID: 133292618091328
[task 2019-11-13T22:06:51.320Z] 22:06:51 INFO - <<<<<<<

Comment 2

[Honza Bambas (:mayhemer)]

Hmm.. a timer-based test, this is probably racy. We can split the test to two to lower the chance of the race (one with setValidFor(100000) and a check we are valid after a short time like 100ms, second with setValidFor(100) and a check after ~5 seconds we are no longer valid)

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• android-em-7-0-x86_64: 1

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

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• android-em-7-0-x86_64: 2

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

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform breakdown:

• android-em-7-0-x86_64: 3

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• android-em-7-0-x86_64: 1

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

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• android-em-7-0-x86_64: 1

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

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• android-em-7-0-x86_64: 2

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

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• android-em-7-0-x86_64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1596268&startday=2020-04-13&endday=2020-04-19&tree=all