1516832 - Intermittent browser/components/resistfingerprinting/test/mochitest/test_reduce_time_precision.html | Test timed out.

Status: RESOLVED INCOMPLETE

(Core :: Security, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: apavel [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=219270670&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/Ols-mvq-SPeX7rIS9WNi2A/runs/0/artifacts/public/logs/live_backing.log

9:33:54     INFO - TEST-START | browser/components/resistfingerprinting/test/mochitest/test_reduce_time_precision.html
19:33:54     INFO - GECKO(939) | ++DOMWINDOW == 18 (0x119cda400) [pid = 941] [serial = 50] [outer = 0x122611c00]
19:33:54     INFO - GECKO(939) | --DOMWINDOW == 7 (0x11ebabc00) [pid = 940] [serial = 4] [outer = 0x0] [url = moz-extension://69b1963b-c90d-954e-9821-9d1daef7c7b3/_generated_background_page.html]
19:33:57     INFO - GECKO(939) | --DOMWINDOW == 6 (0x126dc3400) [pid = 940] [serial = 12] [outer = 0x0] [url = moz-extension://69b1963b-c90d-954e-9821-9d1daef7c7b3/_generated_background_page.html]
19:33:58     INFO - GECKO(939) | [Parent 939, StreamTrans #69] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 372
19:33:58     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 1010
19:34:03     INFO - GECKO(939) | --DOMWINDOW == 2 (0x12040a000) [pid = 944] [serial = 2] [outer = 0x0] [url = about:blank]
19:34:25     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: NS_FAILED internal_GetScalarByEnum for CHILD: file /builds/worker/workspace/build/src/toolkit/components/telemetry/core/TelemetryScalar.cpp, line 2161
19:34:30     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: NS_FAILED internal_GetScalarByEnum for CHILD: file /builds/worker/workspace/build/src/toolkit/components/telemetry/core/TelemetryScalar.cpp, line 2161
19:38:39     INFO - GECKO(939) | [Parent 939, StreamTrans #73] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 372
19:38:39     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 1010
19:38:39     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/url-classifier/UrlClassifierCommon.cpp, line 121
19:38:39     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
19:38:39     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
19:38:39     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
19:40:06     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/url-classifier/UrlClassifierCommon.cpp, line 121
19:40:06     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
19:40:06     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
19:40:06     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
19:43:39     INFO - GECKO(939) | [Parent 939, StreamTrans #78] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 372
19:43:39     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 1010
19:43:39     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/url-classifier/UrlClassifierCommon.cpp, line 121
19:43:39     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
19:43:39     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
19:43:39     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
19:44:06     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/url-classifier/UrlClassifierCommon.cpp, line 121
19:44:06     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
19:44:06     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
19:44:06     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
19:47:49     INFO - GECKO(939) | [Child 941, MediaStreamGrph] WARNING: No audio stream!: 'mDriver->mAudioStream || aOperation == INIT', file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 423
19:47:49     INFO - GECKO(939) | [Child 941, CubebOperation #1] WARNING: Could not stop cubeb stream for MSG.: file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 702
19:47:49     INFO - TEST-INFO | started process screencapture
19:47:49     INFO - TEST-INFO | screencapture: exit 0
19:47:49     INFO - Buffered messages logged at 19:33:54
19:47:49     INFO - TEST-PASS | browser/components/resistfingerprinting/test/mochitest/test_reduce_time_precision.html | A valid string reason is expected 
19:47:49     INFO - TEST-PASS | browser/components/resistfingerprinting/test/mochitest/test_reduce_time_precision.html | Reason cannot be empty 
19:47:49     INFO - Buffered messages finished
19:47:49     INFO - TEST-UNEXPECTED-FAIL | browser/components/resistfingerprinting/test/mochitest/test_reduce_time_precision.html | Test timed out. 
19:47:49     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:275:7
19:47:49     INFO -     reportError@SimpleTest/TestRunner.js:121:7
19:47:49     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
19:47:49     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
19:47:49     INFO -     TestRunner.runTests/<@SimpleTest/TestRunner.js:381:9
19:47:49     INFO -     promise callback*TestRunner.runTests@SimpleTest/TestRunner.js:368:50
19:47:49     INFO -     RunSet.runtests@SimpleTest/setup.js:201:3
19:47:49     INFO -     RunSet.runall@SimpleTest/setup.js:180:5
19:47:49     INFO -     hookupTests@SimpleTest/setup.js:273:5
19:47:49     INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
19:47:49     INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
19:47:49     INFO - EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
19:47:49     INFO -     hookup@SimpleTest/setup.js:253:5
19:47:49     INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&hideResultsTable=1&manifestFile=tests.json&dumpOutputDirectory=%2Fvar%2Ffolders%2Frp%2Fl3vvxgfn6lzfdlsn77my7rg400000x%2FT&cleanupCrashes=true:11:1
19:47:49     INFO - GECKO(939) | [Child 941, MediaStreamGrph] WARNING: No audio stream!: 'mDriver->mAudioStream || aOperation == INIT', file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 423
19:47:49     INFO - GECKO(939) | [Child 941, CubebOperation #1] WARNING: Could not stop cubeb stream for MSG.: file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 702
19:47:49     INFO - AddTask.js | Entering test testWorkerRFP
19:47:49     INFO - GECKO(939) | [Child 941, MediaStreamGrph] WARNING: No audio stream!: 'mDriver->mAudioStream || aOperation == INIT', file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 423
19:47:49     INFO - GECKO(939) | [Child 941, CubebOperation #1] WARNING: Could not stop cubeb stream for MSG.: file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 702
19:47:49     INFO - GECKO(939) | [Child 941, MediaStreamGrph] WARNING: No audio stream!: 'mDriver->mAudioStream || aOperation == INIT', file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 423
19:47:49     INFO - GECKO(939) | [Child 941, CubebOperation #1] WARNING: Could not stop cubeb stream for MSG.: file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 702
19:47:49     INFO - GECKO(939) | [Child 941, MediaStreamGrph] WARNING: No audio stream!: 'mDriver->mAudioStream || aOperation == INIT', file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 423
19:47:49     INFO - GECKO(939) | [Child 941, CubebOperation #1] WARNING: Could not stop cubeb stream for MSG.: file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 702
19:47:49     INFO - GECKO(939) | [Parent 939, Main Thread] WARNING: Ignoring duplicate observer.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2673
19:47:49     INFO - GECKO(939) | [Child 941, MediaStreamGrph] WARNING: No audio stream!: 'mDriver->mAudioStream || aOperation == INIT', file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 423
19:47:49     INFO - GECKO(939) | [Child 941, CubebOperation #1] WARNING: Could not stop cubeb stream for MSG.: file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 702
19:47:49     INFO - TEST-FAIL | browser/components/resistfingerprinting/test/mochitest/test_reduce_time_precision.html | The author of the test has indicated that flaky timeouts are expected.  Reason: testing JS time-based fingerprinting

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* osx-10-10: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1516832&startday=2018-12-24&endday=2018-12-30&tree=all

Comment 2

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 3

[Noemi Erli[:noemi_erli]]

Recent log failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=234439913&repo=autoland&lineNumber=2833

21:01:54 INFO - TEST-START | browser/components/resistfingerprinting/test/mochitest/test_reduce_time_precision.html
21:01:54 INFO - GECKO(2267) | ++DOMWINDOW == 12 (0x112d6dc00) [pid = 2270] [serial = 50] [outer = 0x11c72ad40]
21:01:54 INFO - GECKO(2267) | --DOMWINDOW == 8 (0x12aa96000) [pid = 2267] [serial = 7] [outer = 0x0] [url = about:blank]
21:01:55 INFO - GECKO(2267) | --DOMWINDOW == 14 (0x11cfa8800) [pid = 2269] [serial = 2] [outer = 0x0] [url = about:blank]
21:01:55 INFO - GECKO(2267) | --DOMWINDOW == 13 (0x124ea8800) [pid = 2269] [serial = 10] [outer = 0x0] [url = about:blank]
21:01:55 INFO - GECKO(2267) | --DOMWINDOW == 12 (0x124ea5000) [pid = 2269] [serial = 4] [outer = 0x0] [url = about:blank]
21:01:55 INFO - GECKO(2267) | --DOMWINDOW == 11 (0x124ea8000) [pid = 2269] [serial = 8] [outer = 0x0] [url = about:blank]
21:01:55 INFO - GECKO(2267) | --DOMWINDOW == 10 (0x124ea6800) [pid = 2269] [serial = 6] [outer = 0x0] [url = about:blank]
21:01:57 INFO - GECKO(2267) | [Parent 2267, StreamTrans #284] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 371
21:01:57 INFO - GECKO(2267) | [Parent 2267, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 994
21:01:59 INFO - GECKO(2267) | --DOCSHELL 0x124ebf800 == 4 [pid = 2269] [id = {4803114c-e91a-c64f-a6e6-9b720ecdb117}] [url = moz-extension://5894a5ce-00c3-1143-8cab-2e2d81e164ab/_generated_background_page.html]
21:01:59 INFO - GECKO(2267) | --DOMWINDOW == 9 (0x124e38100) [pid = 2269] [serial = 9] [outer = 0x0] [url = moz-extension://5894a5ce-00c3-1143-8cab-2e2d81e164ab/_generated_background_page.html]
21:01:59 INFO - GECKO(2267) | --DOMWINDOW == 2 (0x11e0b4400) [pid = 2272] [serial = 2] [outer = 0x0] [url = about:blank]
21:02:04 INFO - GECKO(2267) | --DOMWINDOW == 8 (0x1257dc800) [pid = 2269] [serial = 15] [outer = 0x0] [url = moz-extension://5894a5ce-00c3-1143-8cab-2e2d81e164ab/_generated_background_page.html]
21:08:05 INFO - GECKO(2267) | [Parent 2267, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/url-classifier/UrlClassifierCommon.cpp, line 117
21:08:05 INFO - GECKO(2267) | [Parent 2267, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/url-classifier/AsyncUrlChannelClassifier.cpp, line 758
21:12:05 INFO - GECKO(2267) | [Parent 2267, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/url-classifier/UrlClassifierCommon.cpp, line 117
21:12:05 INFO - GECKO(2267) | [Parent 2267, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/url-classifier/AsyncUrlChannelClassifier.cpp, line 758
21:15:50 INFO - TEST-INFO | started process screencapture
21:15:50 INFO - TEST-INFO | screencapture: exit 0
21:15:50 INFO - Buffered messages logged at 21:01:54
21:15:50 INFO - TEST-PASS | browser/components/resistfingerprinting/test/mochitest/test_reduce_time_precision.html | A valid string reason is expected
21:15:50 INFO - TEST-PASS | browser/components/resistfingerprinting/test/mochitest/test_reduce_time_precision.html | Reason cannot be empty
21:15:50 INFO - Buffered messages finished
21:15:50 INFO - TEST-UNEXPECTED-FAIL | browser/components/resistfingerprinting/test/mochitest/test_reduce_time_precision.html | Test timed out.
21:15:50 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
21:15:50 INFO - reportError@SimpleTest/TestRunner.js:121:22
21:15:50 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
21:15:50 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
21:15:50 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:381:20
21:15:50 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:368:50
21:15:50 INFO - RunSet.runtests@SimpleTest/setup.js:201:14
21:15:50 INFO - RunSet.runall@SimpleTest/setup.js:180:12
21:15:50 INFO - hookupTests@SimpleTest/setup.js:273:12
21:15:50 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
21:15:50 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
21:15:50 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
21:15:50 INFO - hookup@SimpleTest/setup.js:253:5
21:15:50 INFO - EventHandlerNonNull@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&hideResultsTable=1&manifestFile=tests.json&dumpOutputDirectory=%2Fvar%2Ffolders%2F7c%2Fdwv0_7bj0252x_jl3jbxy9x800000x%2FT&cleanupCrashes=true:11:1
21:15:50 INFO - GECKO(2267) | [Child 2270, MediaStreamGrph] WARNING: No audio stream!: 'mDriver->mAudioStream || aOperation == INIT', file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 429
21:15:50 INFO - GECKO(2267) | [Child 2270, CubebOperation #1] WARNING: Could not stop cubeb stream for MSG.: file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 708
21:15:50 INFO - GECKO(2267) | [Child 2270, MediaStreamGrph] WARNING: No audio stream!: 'mDriver->mAudioStream || aOperation == INIT', file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 429
21:15:50 INFO - GECKO(2267) | [Child 2270, CubebOperation #1] WARNING: Could not stop cubeb stream for MSG.: file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 708
21:15:50 INFO - AddTask.js | Entering test testWorkerRFP
21:15:50 INFO - GECKO(2267) | [Child 2270, MediaStreamGrph] WARNING: No audio stream!: 'mDriver->mAudioStream || aOperation == INIT', file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 429
21:15:50 INFO - GECKO(2267) | [Child 2270, CubebOperation #1] WARNING: Could not stop cubeb stream for MSG.: file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 708
21:15:50 INFO - GECKO(2267) | [Child 2270, MediaStreamGrph] WARNING: No audio stream!: 'mDriver->mAudioStream || aOperation == INIT', file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 429
21:15:50 INFO - GECKO(2267) | [Child 2270, CubebOperation #1] WARNING: Could not stop cubeb stream for MSG.: file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 708
21:15:50 INFO - GECKO(2267) | [Parent 2267, Main Thread] WARNING: Ignoring duplicate observer.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2665
21:15:50 INFO - GECKO(2267) | [Child 2270, MediaStreamGrph] WARNING: No audio stream!: 'mDriver->mAudioStream || aOperation == INIT', file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 429
21:15:50 INFO - GECKO(2267) | [Child 2270, CubebOperation #1] WARNING: Could not stop cubeb stream for MSG.: file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 708
21:15:50 INFO - GECKO(2267) | [Child 2270, MediaStreamGrph] WARNING: No audio stream!: 'mDriver->mAudioStream || aOperation == INIT', file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 429
21:15:50 INFO - GECKO(2267) | [Child 2270, CubebOperation #1] WARNING: Could not stop cubeb stream for MSG.: file /builds/worker/workspace/build/src/dom/media/GraphDriver.cpp, line 708
21:15:50 INFO - TEST-FAIL | browser/components/resistfingerprinting/test/mochitest/test_reduce_time_precision.html | The author of the test has indicated that flaky timeouts are expected. Reason: testing JS time-based fingerprinting

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• osx-10-10: 1

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

Comment 5

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup