Closed Bug 1745851 Opened 3 years ago Closed 3 years ago

Intermittent /fetch/http-cache/cc-request.any.sharedworker.html | HTTP cache doesn't use aged but fresh response when request contains Cache-Control: max-age=1 - assert_equals: Response 2 comes from cache expected 2 but got 1

Categories

(Core :: DOM: Networking, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=361107977&repo=mozilla-beta
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/W8NiPbc-R2mLjrr7q5k33Q/runs/0/artifacts/public/logs/live_backing.log


task 2021-12-13T23:23:40.401Z] 23:23:40     INFO - TEST-START | /fetch/http-cache/cc-request.any.sharedworker.html
[task 2021-12-13T23:23:40.414Z] 23:23:40     INFO - Closing window 0a14be53-0f78-4319-9698-6d80ebe9837c
[task 2021-12-13T23:23:50.975Z] 23:23:50     INFO - 
[task 2021-12-13T23:23:50.975Z] 23:23:50     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.html | HTTP cache does use aged stale response when request contains Cache-Control: max-stale that permits its use 
[task 2021-12-13T23:23:50.976Z] 23:23:50     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.html | HTTP cache does reuse stale response with Age header when request contains Cache-Control: max-stale that permits its use 
[task 2021-12-13T23:23:50.976Z] 23:23:50     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.html | HTTP cache doesn't reuse fresh response when request contains Cache-Control: min-fresh that wants it fresher 
[task 2021-12-13T23:23:50.976Z] 23:23:50     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.html | HTTP cache doesn't reuse fresh response with Age header when request contains Cache-Control: min-fresh that wants it fresher 
[task 2021-12-13T23:23:50.976Z] 23:23:50     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.html | HTTP cache doesn't reuse fresh response when request contains Cache-Control: no-cache 
[task 2021-12-13T23:23:50.976Z] 23:23:50     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.html | HTTP cache validates fresh response with Last-Modified when request contains Cache-Control: no-cache 
[task 2021-12-13T23:23:50.976Z] 23:23:50     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.html | HTTP cache validates fresh response with ETag when request contains Cache-Control: no-cache 
[task 2021-12-13T23:23:50.976Z] 23:23:50     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.html | HTTP cache doesn't reuse fresh response when request contains Cache-Control: no-store 
[task 2021-12-13T23:23:50.976Z] 23:23:50     INFO - TEST-FAIL | /fetch/http-cache/cc-request.any.html | HTTP cache generates 504 status code when nothing is in cache and request contains Cache-Control: only-if-cached - assert_equals: Response 1 status is 200, not 504 expected 504 but got 200
[task 2021-12-13T23:23:50.976Z] 23:23:50     INFO - checkResponse@http://web-platform.test:8000/fetch/http-cache/http-cache.js:154:20
[task 2021-12-13T23:23:50.976Z] 23:23:50     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.sharedworker.html | HTTP cache doesn't use aged but fresh response when request contains Cache-Control: max-age=0 
[task 2021-12-13T23:23:50.976Z] 23:23:50     INFO - TEST-UNEXPECTED-FAIL | /fetch/http-cache/cc-request.any.sharedworker.html | HTTP cache doesn't use aged but fresh response when request contains Cache-Control: max-age=1 - assert_equals: Response 2 comes from cache expected 2 but got 1
[task 2021-12-13T23:23:50.977Z] 23:23:50     INFO - checkResponse@http://web-platform.test:8000/fetch/http-cache/http-cache.js:150:22
[task 2021-12-13T23:23:51.103Z] 23:23:51     INFO - TEST-OK | /fetch/http-cache/cc-request.any.sharedworker.html | took 10701ms
[task 2021-12-13T23:23:51.497Z] 23:23:51     INFO - STDOUT: cleanup aborted: Unable to remount device
[task 2021-12-13T23:23:51.623Z] 23:23:51     INFO - STDOUT: cleanup aborted: Unable to remount device
[task 2021-12-13T23:23:51.625Z] 23:23:51     INFO - Closing logging queue
[task 2021-12-13T23:23:51.625Z] 23:23:51     INFO - queue closed
[task 2021-12-13T23:23:51.651Z] 23:23:51     INFO - Setting up ssl
[task 2021-12-13T23:23:51.674Z] 23:23:51     INFO - certutil | b''
[task 2021-12-13T23:23:51.703Z] 23:23:51     INFO - certutil | b''
[task 2021-12-13T23:23:51.725Z] 23:23:51     INFO - certutil | b'\nCertificate Nickname                                         Trust Attributes\n                                                             SSL,S/MIME,JAR/XPI\n\nweb-platform-tests                                           CT,, \n'
[task 2021-12-13T23:23:52.780Z] 23:23:52     INFO - adb Granting important runtime permissions to org.mozilla.geckoview.test_runner
[task 2021-12-13T23:23:54.120Z] 23:23:54     INFO - adb launch_application: am start -W -n org.mozilla.geckoview.test_runner/org.mozilla.geckoview.test_runner.TestRunnerActivity -a android.intent.action.MAIN --es env0 MOZ_CRASHREPORTER=1 --es env1 MOZ_CRASHREPORTER_NO_REPORT=1 --es env2 MOZ_CRASHREPORTER_SHUTDOWN=1 --es env3 MOZ_HIDE_RESULTS_TABLE=1 --es env4 MOZ_IN_AUTOMATION=1 --es env5 MOZ_LOG=signaling:3,mtransport:4,DataChannel:4,jsep:4 --es env6 R_LOG_LEVEL=6 --es env7 R_LOG_DESTINATION=stderr --es env8 R_LOG_VERBOSE=1 --es env9 MOZ_PROCESS_LOG=/tmp/tmpwqt4q7iepidlog --es env10 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env11 STYLO_THREADS=1 --es env12 MOZ_WEBRENDER=1 --es arg0 -no-remote --es arg1 -profile --es arg2 /data/local/tmp/test_root/profile --es arg3 --marionette --es arg4 about:blank --ez use_multiprocess True
[task 2021-12-13T23:23:55.218Z] 23:23:55     INFO - Starting runner
[task 2021-12-13T23:23:55.832Z] 23:23:55     INFO - TEST-START | /fetch/http-cache/cc-request.any.worker.html
[task 2021-12-13T23:23:55.850Z] 23:23:55     INFO - Setting pref network.http.rcwn.enabled (false)
[task 2021-12-13T23:23:55.868Z] 23:23:55     INFO - Setting pref privacy.partition.network_state (true)
[task 2021-12-13T23:24:06.446Z] 23:24:06     INFO - 
[task 2021-12-13T23:24:06.446Z] 23:24:06     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.sharedworker.html | HTTP cache does use aged stale response when request contains Cache-Control: max-stale that permits its use 
[task 2021-12-13T23:24:06.446Z] 23:24:06     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.sharedworker.html | HTTP cache does reuse stale response with Age header when request contains Cache-Control: max-stale that permits its use 
[task 2021-12-13T23:24:06.446Z] 23:24:06     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.sharedworker.html | HTTP cache doesn't reuse fresh response when request contains Cache-Control: min-fresh that wants it fresher 
[task 2021-12-13T23:24:06.446Z] 23:24:06     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.sharedworker.html | HTTP cache doesn't reuse fresh response with Age header when request contains Cache-Control: min-fresh that wants it fresher 
[task 2021-12-13T23:24:06.446Z] 23:24:06     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.sharedworker.html | HTTP cache doesn't reuse fresh response when request contains Cache-Control: no-cache 
[task 2021-12-13T23:24:06.447Z] 23:24:06     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.sharedworker.html | HTTP cache validates fresh response with Last-Modified when request contains Cache-Control: no-cache 
[task 2021-12-13T23:24:06.447Z] 23:24:06     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.sharedworker.html | HTTP cache validates fresh response with ETag when request contains Cache-Control: no-cache 
[task 2021-12-13T23:24:06.447Z] 23:24:06     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.sharedworker.html | HTTP cache doesn't reuse fresh response when request contains Cache-Control: no-store 
[task 2021-12-13T23:24:06.447Z] 23:24:06     INFO - TEST-FAIL | /fetch/http-cache/cc-request.any.sharedworker.html | HTTP cache generates 504 status code when nothing is in cache and request contains Cache-Control: only-if-cached - assert_equals: Response 1 status is 200, not 504 expected 504 but got 200
[task 2021-12-13T23:24:06.447Z] 23:24:06     INFO - checkResponse@http://web-platform.test:8000/fetch/http-cache/http-cache.js:154:20
[task 2021-12-13T23:24:06.447Z] 23:24:06     INFO - TEST-PASS | /fetch/http-cache/cc-request.any.worker.html | HTTP cache doesn't use aged but fresh response when request contains Cache-Control: max-age=0 
[task 2021-12-13T23:24:06.447Z] 23:24:06     INFO - TEST-UNEXPECTED-FAIL | /fetch/http-cache/cc-request.any.worker.html | HTTP cache doesn't use aged but fresh response when request contains Cache-Control: max-age=1 - assert_equals: Response 2 comes from cache expected 2 but got 1
[task 2021-12-13T23:24:06.447Z] 23:24:06     INFO - checkResponse@http://web-platform.test:8000/fetch/http-cache/http-cache.js:150:22
[task 2021-12-13T23:24:06.573Z] 23:24:06     INFO - TEST-OK | /fetch/http-cache/cc-request.any.worker.html | took 10741ms
[task 2021-12-13T23:24:06.933Z] 23:24:06     INFO - STDOUT: cleanup aborted: Unable to remount device
[task 2021-12-13T23:24:07.066Z] 23:24:07     INFO - STDOUT: cleanup aborted: Unable to remount device
[task 2021-12-13T23:24:07.068Z] 23:24:07     INFO - Closing logging queue
[task 2021-12-13T23:24:07.069Z] 23:24:07     INFO - queue closed
[task 2021-12-13T23:24:07.092Z] 23:24:07     INFO - Setting up ssl
[task 2021-12-13T23:24:07.117Z] 23:24:07     INFO - certutil | b''
[task 2021-12-13T23:24:07.145Z] 23:24:07     INFO - certutil | b''
[task 2021-12-13T23:24:07.169Z] 23:24:07     INFO - certutil | b'\nCertificate Nickname                                         Trust Attributes\n                                                             SSL,S/MIME,JAR/XPI\n\nweb-platform-tests                                           CT,, \n'
[task 2021-12-13T23:24:08.165Z] 23:24:08     INFO - adb Granting important runtime permissions to org.mozilla.geckoview.test_runner
[task 2021-12-13T23:24:09.568Z] 23:24:09     INFO - adb launch_application: am start -W -n org.mozilla.geckoview.test_runner/org.mozilla.geckoview.test_runner.TestRunnerActivity -a android.intent.action.MAIN --es env0 MOZ_CRASHREPORTER=1 --es env1 MOZ_CRASHREPORTER_NO_REPORT=1 --es env2 MOZ_CRASHREPORTER_SHUTDOWN=1 --es env3 MOZ_HIDE_RESULTS_TABLE=1 --es env4 MOZ_IN_AUTOMATION=1 --es env5 MOZ_LOG=signaling:3,mtransport:4,DataChannel:4,jsep:4 --es env6 R_LOG_LEVEL=6 --es env7 R_LOG_DESTINATION=stderr --es env8 R_LOG_VERBOSE=1 --es env9 MOZ_PROCESS_LOG=/tmp/tmpeppg42wtpidlog --es env10 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env11 STYLO_THREADS=1 --es env12 MOZ_WEBRENDER=1 --es arg0 -no-remote --es arg1 -profile --es arg2 /data/local/tmp/test_root/profile --es arg3 --marionette --es arg4 about:blank --ez use_multiprocess True
[task 2021-12-13T23:24:10.671Z] 23:24:10     INFO - Starting runner
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.