1675238 - Intermittent /fetch/http-cache/304-<anything>.html | HTTP cache updates stored headers from a Last-Modified 304 - assert_less_than: Response 3 does not come from cache expected a number less than 3 but got 3

Status: RESOLVED INVALID

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

Description

[Treeherder Bug Filer]

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

---

[task 2020-11-04T10:46:50.276Z] 10:46:50 INFO - TEST-START | /content-security-policy/connect-src/worker-from-guid.sub.html
[task 2020-11-04T10:46:50.280Z] 10:46:50 INFO - Closing window 68
[task 2020-11-04T10:46:50.593Z] 10:46:50 INFO - .
[task 2020-11-04T10:46:50.593Z] 10:46:50 INFO - TEST-OK | /content-security-policy/connect-src/worker-from-guid.sub.html | took 316ms
[task 2020-11-04T10:46:50.594Z] 10:46:50 INFO - Restarting browser for new test group
[task 2020-11-04T10:46:50.609Z] 10:46:50 INFO - PID 11832 | 1604486810603 Marionette INFO Stopped listening on port 53051
[task 2020-11-04T10:46:50.772Z] 10:46:50 INFO - PID 11832 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-04T10:46:50.776Z] 10:46:50 INFO - PID 11832 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-04T10:46:50.776Z] 10:46:50 INFO - PID 11832 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-04T10:46:50.784Z] 10:46:50 INFO - PID 11832 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-04T10:46:50.873Z] 10:46:50 INFO - PID 11832 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-04T10:46:50.873Z] 10:46:50 INFO - PID 11832 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-04T10:46:50.873Z] 10:46:50 INFO - PID 11832 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-04T10:46:51.597Z] 10:46:51 INFO - PID 11832 | error: address range table at offset 0x5c90 has an invalid tuple (length = 0) at offset 0x5ca0
[task 2020-11-04T10:46:51.652Z] 10:46:51 INFO - PID 11832 | error: address range table at offset 0x5c90 has an invalid tuple (length = 0) at offset 0x5ca0
[task 2020-11-04T10:46:51.693Z] 10:46:51 INFO - PID 11832 | error: address range table at offset 0x5c90 has an invalid tuple (length = 0) at offset 0x5ca0
[task 2020-11-04T10:46:51.795Z] 10:46:51 INFO - PID 11832 | -----------------------------------------------------
[task 2020-11-04T10:46:51.795Z] 10:46:51 INFO - PID 11832 | Suppressions used:
[task 2020-11-04T10:46:51.795Z] 10:46:51 INFO - PID 11832 | count bytes template
[task 2020-11-04T10:46:51.795Z] 10:46:51 INFO - PID 11832 | 14 448 nsComponentManagerImpl
[task 2020-11-04T10:46:51.798Z] 10:46:51 INFO - PID 11832 | 633 18287 libfontconfig.so
[task 2020-11-04T10:46:51.799Z] 10:46:51 INFO - PID 11832 | -----------------------------------------------------
[task 2020-11-04T10:46:51.897Z] 10:46:51 INFO - PID 11832 | -----------------------------------------------------
[task 2020-11-04T10:46:51.898Z] 10:46:51 INFO - PID 11832 | Suppressions used:
[task 2020-11-04T10:46:51.898Z] 10:46:51 INFO - PID 11832 | count bytes template
[task 2020-11-04T10:46:51.898Z] 10:46:51 INFO - PID 11832 | 14 448 nsComponentManagerImpl
[task 2020-11-04T10:46:51.898Z] 10:46:51 INFO - PID 11832 | 633 18287 libfontconfig.so
[task 2020-11-04T10:46:51.899Z] 10:46:51 INFO - PID 11832 | -----------------------------------------------------
[task 2020-11-04T10:46:51.954Z] 10:46:51 INFO - PID 11832 | -----------------------------------------------------
[task 2020-11-04T10:46:51.954Z] 10:46:51 INFO - PID 11832 | Suppressions used:
[task 2020-11-04T10:46:51.954Z] 10:46:51 INFO - PID 11832 | count bytes template
[task 2020-11-04T10:46:51.954Z] 10:46:51 INFO - PID 11832 | 14 448 nsComponentManagerImpl
[task 2020-11-04T10:46:51.954Z] 10:46:51 INFO - PID 11832 | 2 288 libfontconfig.so
[task 2020-11-04T10:46:51.954Z] 10:46:51 INFO - PID 11832 | -----------------------------------------------------
[task 2020-11-04T10:46:52.090Z] 10:46:52 INFO - PID 11832 | error: address range table at offset 0x5c90 has an invalid tuple (length = 0) at offset 0x5ca0
[task 2020-11-04T10:46:52.238Z] 10:46:52 INFO - PID 11832 | -----------------------------------------------------
[task 2020-11-04T10:46:52.238Z] 10:46:52 INFO - PID 11832 | Suppressions used:
[task 2020-11-04T10:46:52.238Z] 10:46:52 INFO - PID 11832 | count bytes template
[task 2020-11-04T10:46:52.238Z] 10:46:52 INFO - PID 11832 | 14 448 nsComponentManagerImpl
[task 2020-11-04T10:46:52.238Z] 10:46:52 INFO - PID 11832 | 633 18287 libfontconfig.so
[task 2020-11-04T10:46:52.238Z] 10:46:52 INFO - PID 11832 | -----------------------------------------------------
[task 2020-11-04T10:46:53.177Z] 10:46:53 INFO - PID 11832 | error: address range table at offset 0x5c90 has an invalid tuple (length = 0) at offset 0x5ca0
[task 2020-11-04T10:46:53.298Z] 10:46:53 INFO - PID 11832 | -----------------------------------------------------
[task 2020-11-04T10:46:53.299Z] 10:46:53 INFO - PID 11832 | Suppressions used:
[task 2020-11-04T10:46:53.299Z] 10:46:53 INFO - PID 11832 | count bytes template
[task 2020-11-04T10:46:53.299Z] 10:46:53 INFO - PID 11832 | 11 344 nsComponentManagerImpl
[task 2020-11-04T10:46:53.299Z] 10:46:53 INFO - PID 11832 | 633 18083 libfontconfig.so
[task 2020-11-04T10:46:53.299Z] 10:46:53 INFO - PID 11832 | 3 18 libglib-2.0.so
[task 2020-11-04T10:46:53.299Z] 10:46:53 INFO - PID 11832 | 3 624 mozJSComponentLoader
[task 2020-11-04T10:46:53.299Z] 10:46:53 INFO - PID 11832 | -----------------------------------------------------
[task 2020-11-04T10:46:53.360Z] 10:46:53 INFO - Browser exited with return code 0
[task 2020-11-04T10:46:53.360Z] 10:46:53 INFO - PROCESS LEAKS None
[task 2020-11-04T10:46:53.360Z] 10:46:53 INFO - LeakSanitizer | Leak checks disabled
[task 2020-11-04T10:46:53.360Z] 10:46:53 INFO - Closing logging queue
[task 2020-11-04T10:46:53.361Z] 10:46:53 INFO - queue closed
[task 2020-11-04T10:46:53.377Z] 10:46:53 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2020-11-04T10:46:53.393Z] 10:46:53 INFO - LSan enabled.
[task 2020-11-04T10:46:53.393Z] 10:46:53 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2020-11-04T10:46:53.394Z] 10:46:53 INFO - UBSan enabled.
[task 2020-11-04T10:46:53.395Z] 10:46:53 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpVub8kw
[task 2020-11-04T10:46:53.410Z] 10:46:53 INFO - Starting runner
[task 2020-11-04T10:46:54.640Z] 10:46:54 INFO -
[task 2020-11-04T10:46:54.640Z] 10:46:54 INFO - TEST-PASS | /fetch/http-cache/304-update.any.serviceworker.html | HTTP cache updates returned headers from a Last-Modified 304
[task 2020-11-04T10:46:54.640Z] 10:46:54 INFO - TEST-UNEXPECTED-FAIL | /fetch/http-cache/304-update.any.serviceworker.html | HTTP cache updates stored headers from a Last-Modified 304 - assert_less_than: Response 3 does not come from cache expected a number less than 3 but got 3
[task 2020-11-04T10:46:54.640Z] 10:46:54 INFO - checkResponse@https://web-platform.test:8443/fetch/http-cache/http-cache.js:145:25
[task 2020-11-04T10:46:54.641Z] 10:46:54 INFO - ...
[task 2020-11-04T10:46:54.641Z] 10:46:54 INFO - TEST-OK | /fetch/http-cache/304-update.any.serviceworker.html | took 11567ms
[task 2020-11-04T10:46:54.670Z] 10:46:54 INFO - PID 11260 | 1604486814663 Marionette INFO Stopped listening on port 36784
[task 2020-11-04T10:46:54.894Z] 10:46:54 INFO - PID 11260 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-04T10:46:54.894Z] 10:46:54 INFO - PID 11260 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-04T10:46:54.895Z] 10:46:54 INFO - PID 11260 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-04T10:46:54.896Z] 10:46:54 INFO - PID 11260 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-04T10:46:54.978Z] 10:46:54 INFO - PID 11260 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-04T10:46:55.017Z] 10:46:55 INFO - PID 11260 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-04T10:46:55.784Z] 10:46:55 INFO - PID 11260 | error: address range table at offset 0x5c90 has an invalid tuple (length = 0) at offset 0x5ca0
[task 2020-11-04T10:46:56.061Z] 10:46:56 INFO - PID 11260 | -----------------------------------------------------
[task 2020-11-04T10:46:56.063Z] 10:46:56 INFO - PID 11260 | Suppressions used:
[task 2020-11-04T10:46:56.063Z] 10:46:56 INFO - PID 11260 | count bytes template
[task 2020-11-04T10:46:56.064Z] 10:46:56 INFO - PID 11260 | 14 448 nsComponentManagerImpl
[task 2020-11-04T10:46:56.066Z] 10:46:56 INFO - PID 11260 | 633 18287 libfontconfig.so
[task 2020-11-04T10:46:56.067Z] 10:46:56 INFO - PID 11260 | -----------------------------------------------------
[task 2020-11-04T10:46:56.068Z] 10:46:56 INFO - PID 11260 | error: address range table at offset 0x5c90 has an invalid tuple (length = 0) at offset 0x5ca0
[task 2020-11-04T10:46:56.084Z] 10:46:56 INFO - PID 11260 | error: address range table at offset 0x5c90 has an invalid tuple (length = 0) at offset 0x5ca0
[task 2020-11-04T10:46:56.160Z] 10:46:56 INFO - PID 11260 | error: address range table at offset 0x5c90 has an invalid tuple (length = 0) at offset 0x5ca0
[task 2020-11-04T10:46:56.276Z] 10:46:56 INFO - PID 11260 | -----------------------------------------------------
[task 2020-11-04T10:46:56.277Z] 10:46:56 INFO - PID 11260 | Suppressions used:
[task 2020-11-04T10:46:56.277Z] 10:46:56 INFO - PID 11260 | count bytes template
[task 2020-11-04T10:46:56.278Z] 10:46:56 INFO - PID 11260 | 14 448 nsComponentManagerImpl
[task 2020-11-04T10:46:56.278Z] 10:46:56 INFO - PID 11260 | 633 18287 libfontconfig.so
[task 2020-11-04T10:46:56.278Z] 10:46:56 INFO - PID 11260 | -----------------------------------------------------
[task 2020-11-04T10:46:56.327Z] 10:46:56 INFO - PID 11260 | -----------------------------------------------------
[task 2020-11-04T10:46:56.327Z] 10:46:56 INFO - PID 11260 | Suppressions used:
[task 2020-11-04T10:46:56.327Z] 10:46:56 INFO - PID 11260 | count bytes template
[task 2020-11-04T10:46:56.328Z] 10:46:56 INFO - PID 11260 | 14 448 nsComponentManagerImpl
[task 2020-11-04T10:46:56.328Z] 10:46:56 INFO - PID 11260 | 633 18287 libfontconfig.so
[task 2020-11-04T10:46:56.328Z] 10:46:56 INFO - PID 11260 | -----------------------------------------------------
[task 2020-11-04T10:46:56.364Z] 10:46:56 INFO - PID 11260 | -----------------------------------------------------
[task 2020-11-04T10:46:56.364Z] 10:46:56 INFO - PID 11260 | Suppressions used:
[task 2020-11-04T10:46:56.364Z] 10:46:56 INFO - PID 11260 | count bytes template
[task 2020-11-04T10:46:56.364Z] 10:46:56 INFO - PID 11260 | 14 448 nsComponentManagerImpl
[task 2020-11-04T10:46:56.365Z] 10:46:56 INFO - PID 11260 | 2 288 libfontconfig.so
[task 2020-11-04T10:46:56.365Z] 10:46:56 INFO - PID 11260 | -----------------------------------------------------
[task 2020-11-04T10:46:57.551Z] 10:46:57 INFO - PID 11260 | error: address range table at offset 0x5c90 has an invalid tuple (length = 0) at offset 0x5ca0
[task 2020-11-04T10:46:57.667Z] 10:46:57 INFO - PID 11260 | -----------------------------------------------------
[task 2020-11-04T10:46:57.668Z] 10:46:57 INFO - PID 11260 | Suppressions used:
[task 2020-11-04T10:46:57.668Z] 10:46:57 INFO - PID 11260 | count bytes template
[task 2020-11-04T10:46:57.668Z] 10:46:57 INFO - PID 11260 | 11 344 nsComponentManagerImpl
[task 2020-11-04T10:46:57.669Z] 10:46:57 INFO - PID 11260 | 633 18083 libfontconfig.so
[task 2020-11-04T10:46:57.669Z] 10:46:57 INFO - PID 11260 | 3 624 mozJSComponentLoader
[task 2020-11-04T10:46:57.669Z] 10:46:57 INFO - PID 11260 | -----------------------------------------------------
[task 2020-11-04T10:46:57.757Z] 10:46:57 INFO - Browser exited with return code 0
[task 2020-11-04T10:46:57.757Z] 10:46:57 INFO - PROCESS LEAKS None
[task 2020-11-04T10:46:57.758Z] 10:46:57 INFO - Closing logging queue
[task 2020-11-04T10:46:57.759Z] 10:46:57 INFO - queue closed
[task 2020-11-04T10:46:57.774Z] 10:46:57 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2020-11-04T10:46:57.790Z] 10:46:57 INFO - LSan enabled.
[task 2020-11-04T10:46:57.790Z] 10:46:57 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2020-11-04T10:46:57.790Z] 10:46:57 INFO - UBSan enabled.
[task 2020-11-04T10:46:57.791Z] 10:46:57 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpD0c3ZA
[task 2020-11-04T10:46:57.798Z] 10:46:57 INFO - Starting runner
[task 2020-11-04T10:46:58.344Z] 10:46:58 INFO - PID 12182 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new Error("", "(unknown module)"))
[task 2020-11-04T10:47:00.406Z] 10:47:00 INFO - PID 12182 | 1604486820404 Marionette INFO Listening on port 42089
[task 2020-11-04T10:47:00.941Z] 10:47:00 INFO - TEST-START | /wasm/jsapi/global/constructor.any.worker.html

Comment 1

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

Attachment: Bug 1675238 - update expectation for fetch/http-cache/304-update.any.js.ini (after update from bug 1674879). DONTBUILD

Comment 2

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/702526095ecc
update expectation for fetch/http-cache/304-update.any.js.ini (after update from bug 1674879). DONTBUILD

Comment 3

[Cristian Brindusan [:cbrindusan]]

https://hg.mozilla.org/mozilla-central/rev/702526095ecc

Comment 4

[Cristian Brindusan [:cbrindusan]]

This is still happening. New occurrence:
https://treeherder.mozilla.org/logviewer?job_id=320768164&repo=mozilla-central&lineNumber=2078

Comment 5

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

Attachment: Bug 1675238 - more expectation changes for fetch/http-cache/304-update.any.js.ini (after update from bug 1674879). DONTBUILD

Comment 6

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/03d9dcfad5b5
more expectation changes for fetch/http-cache/304-update.any.js.ini (after update from bug 1674879). DONTBUILD

Comment 7

[Andreea Pavel [:apavel]]

https://hg.mozilla.org/mozilla-central/rev/03d9dcfad5b5

Comment 8

[Intermittent Failures Robot]

19 failures in 4128 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 12
• mozilla-central: 5
• try: 2

Platform breakdown:

• linux1804-64-asan: 2
• linux1804-64-qr: 1
• linux1804-64: 2
• android-em-7-0-x86_64: 5
• android-em-7-0-x86_64-qr: 1
• windows10-64-qr: 2
• linux1804-64-shippable: 2
• macosx1014-64-shippable-qr: 1
• windows7-32-shippable: 1
• windows10-64-shippable-qr: 1
• macosx1014-64-devedition-qr: 1

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

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1
• try: 1

Platform breakdown:

• windows10-64: 1
• macosx1014-64-devedition-qr: 1

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

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• autoland: 1

Platform breakdown:

• windows10-64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1675238&startday=2020-11-16&endday=2020-11-22&tree=all