Closed Bug 1692922 Opened 4 years ago Closed 4 years ago

Intermittent TV tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property domainLookupStart - false == true - JS frame :: chrome://*/tools/profiler/tests/browser/shared-head.js :: objectContains :: line

Tracking

()

Status:

RESOLVED INCOMPLETE

Tracking Flags:

Tracking

Status

firefox-esr78

---

unaffected

firefox85

---

unaffected

firefox86

---

unaffected

firefox87

---

fix-optional

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Treeherder Bug Filer

Reporter

Description

•

4 years ago

treeherder

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=330042685&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/aH9aqBNbSAGqphXdTR9yRw/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/aH9aqBNbSAGqphXdTR9yRw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2021-02-15T21:05:12.630Z] 21:05:12     INFO - Entering test bound test_network_markers_redirect_resources
[task 2021-02-15T21:05:12.631Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The profiler is not currently active - true == true - 
[task 2021-02-15T21:05:12.631Z] 21:05:12     INFO - [
[task 2021-02-15T21:05:12.632Z] 21:05:12     INFO -   {
[task 2021-02-15T21:05:12.632Z] 21:05:12     INFO -     "name": "Load 29: http://example.com/browser/tools/profiler/tests/browser/page_with_resources.html?cacheBust=0.7504467633148478",
[task 2021-02-15T21:05:12.632Z] 21:05:12     INFO -     "startTime": 9712.038038354036,
[task 2021-02-15T21:05:12.632Z] 21:05:12     INFO -     "endTime": 9712.19001297651,
[task 2021-02-15T21:05:12.632Z] 21:05:12     INFO -     "phase": 1,
[task 2021-02-15T21:05:12.632Z] 21:05:12     INFO -     "category": 6,
[task 2021-02-15T21:05:12.633Z] 21:05:12     INFO -     "data": {
[task 2021-02-15T21:05:12.633Z] 21:05:12     INFO -       "type": "Network",
[task 2021-02-15T21:05:12.633Z] 21:05:12     INFO -       "startTime": 9712.038038354036,
[task 2021-02-15T21:05:12.633Z] 21:05:12     INFO -       "endTime": 9712.19001297651,
[task 2021-02-15T21:05:12.633Z] 21:05:12     INFO -       "id": 16381005266973,
[task 2021-02-15T21:05:12.633Z] 21:05:12     INFO -       "status": "STATUS_START",
[task 2021-02-15T21:05:12.633Z] 21:05:12     INFO -       "cache": "Unresolved",
[task 2021-02-15T21:05:12.633Z] 21:05:12     INFO -       "pri": 0,
[task 2021-02-15T21:05:12.633Z] 21:05:12     INFO -       "URI": "http://example.com/browser/tools/profiler/tests/browser/page_with_resources.html?cacheBust=0.7504467633148478",
[task 2021-02-15T21:05:12.633Z] 21:05:12     INFO -       "requestMethod": "GET",
[task 2021-02-15T21:05:12.633Z] 21:05:12     INFO -       "contentType": null
[task 2021-02-15T21:05:12.634Z] 21:05:12     INFO -     }
[task 2021-02-15T21:05:12.634Z] 21:05:12     INFO -   },
[task 2021-02-15T21:05:12.634Z] 21:05:12     INFO -   {
[task 2021-02-15T21:05:12.634Z] 21:05:12     INFO -     "name": "Load 29: http://example.com/browser/tools/profiler/tests/browser/page_with_resources.html?cacheBust=0.7504467633148478",
[task 2021-02-15T21:05:12.635Z] 21:05:12     INFO -     "startTime": 9712.19001297651,
[task 2021-02-15T21:05:12.635Z] 21:05:12     INFO -     "endTime": 9938.676619514492,
[task 2021-02-15T21:05:12.635Z] 21:05:12     INFO -     "phase": 1,
[task 2021-02-15T21:05:12.635Z] 21:05:12     INFO -     "category": 6,
[task 2021-02-15T21:05:12.635Z] 21:05:12     INFO -     "data": {
[task 2021-02-15T21:05:12.636Z] 21:05:12     INFO -       "type": "Network",
[task 2021-02-15T21:05:12.636Z] 21:05:12     INFO -       "startTime": 9712.19001297651,
[task 2021-02-15T21:05:12.636Z] 21:05:12     INFO -       "endTime": 9938.676619514492,
[task 2021-02-15T21:05:12.636Z] 21:05:12     INFO -       "id": 16381005266973,
[task 2021-02-15T21:05:12.636Z] 21:05:12     INFO -       "status": "STATUS_STOP",
[task 2021-02-15T21:05:12.637Z] 21:05:12     INFO -       "cache": "Unresolved",
[task 2021-02-15T21:05:12.637Z] 21:05:12     INFO -       "pri": -20,
[task 2021-02-15T21:05:12.637Z] 21:05:12     INFO -       "count": 238,
[task 2021-02-15T21:05:12.637Z] 21:05:12     INFO -       "URI": "http://example.com/browser/tools/profiler/tests/browser/page_with_resources.html?cacheBust=0.7504467633148478",
[task 2021-02-15T21:05:12.637Z] 21:05:12     INFO -       "requestMethod": "GET",
[task 2021-02-15T21:05:12.638Z] 21:05:12     INFO -       "contentType": "text/html",
[task 2021-02-15T21:05:12.638Z] 21:05:12     INFO -       "domainLookupStart": 9905.6330343661,
[task 2021-02-15T21:05:12.638Z] 21:05:12     INFO -       "domainLookupEnd": 9905.992297903784,
[task 2021-02-15T21:05:12.638Z] 21:05:12     INFO -       "connectStart": 9906.942139294239,
[task 2021-02-15T21:05:12.638Z] 21:05:12     INFO -       "tcpConnectEnd": 9907.34386632938,
[task 2021-02-15T21:05:12.639Z] 21:05:12     INFO -       "connectEnd": 9907.34386632938,
[task 2021-02-15T21:05:12.639Z] 21:05:12     INFO -       "requestStart": 9907.645860018523,
[task 2021-02-15T21:05:12.639Z] 21:05:12     INFO -       "responseStart": 9914.57271804098,
[task 2021-02-15T21:05:12.639Z] 21:05:12     INFO -       "responseEnd": 9916.132134112017
[task 2021-02-15T21:05:12.639Z] 21:05:12     INFO -     }
[task 2021-02-15T21:05:12.640Z] 21:05:12     INFO -   },
[task 2021-02-15T21:05:12.640Z] 21:05:12     INFO -   {
[task 2021-02-15T21:05:12.640Z] 21:05:12     INFO -     "name": "Load 7: http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.640Z] 21:05:12     INFO -     "startTime": 9983.168028338798,
[task 2021-02-15T21:05:12.640Z] 21:05:12     INFO -     "endTime": 10021.827969755932,
[task 2021-02-15T21:05:12.640Z] 21:05:12     INFO -     "phase": 1,
[task 2021-02-15T21:05:12.641Z] 21:05:12     INFO -     "category": 6,
[task 2021-02-15T21:05:12.641Z] 21:05:12     INFO -     "data": {
[task 2021-02-15T21:05:12.641Z] 21:05:12     INFO -       "innerWindowID": 2147483650,
[task 2021-02-15T21:05:12.641Z] 21:05:12     INFO -       "type": "Network",
[task 2021-02-15T21:05:12.641Z] 21:05:12     INFO -       "startTime": 9983.168028338798,
[task 2021-02-15T21:05:12.641Z] 21:05:12     INFO -       "endTime": 10021.827969755932,
[task 2021-02-15T21:05:12.642Z] 21:05:12     INFO -       "id": 2362232012807,
[task 2021-02-15T21:05:12.642Z] 21:05:12     INFO -       "status": "STATUS_START",
[task 2021-02-15T21:05:12.642Z] 21:05:12     INFO -       "cache": "Unresolved",
[task 2021-02-15T21:05:12.642Z] 21:05:12     INFO -       "pri": 8,
[task 2021-02-15T21:05:12.642Z] 21:05:12     INFO -       "URI": "http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.642Z] 21:05:12     INFO -       "requestMethod": "GET",
[task 2021-02-15T21:05:12.643Z] 21:05:12     INFO -       "contentType": null
[task 2021-02-15T21:05:12.643Z] 21:05:12     INFO -     }
[task 2021-02-15T21:05:12.643Z] 21:05:12     INFO -   },
[task 2021-02-15T21:05:12.643Z] 21:05:12     INFO -   {
[task 2021-02-15T21:05:12.644Z] 21:05:12     INFO -     "name": "Load 8: http://example.com/browser/tools/profiler/tests/browser/redirect.sjs?firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.644Z] 21:05:12     INFO -     "startTime": 9983.778999844953,
[task 2021-02-15T21:05:12.644Z] 21:05:12     INFO -     "endTime": 10023.009125461476,
[task 2021-02-15T21:05:12.644Z] 21:05:12     INFO -     "phase": 1,
[task 2021-02-15T21:05:12.644Z] 21:05:12     INFO -     "category": 6,
[task 2021-02-15T21:05:12.644Z] 21:05:12     INFO -     "data": {
[task 2021-02-15T21:05:12.645Z] 21:05:12     INFO -       "innerWindowID": 2147483650,
[task 2021-02-15T21:05:12.645Z] 21:05:12     INFO -       "type": "Network",
[task 2021-02-15T21:05:12.645Z] 21:05:12     INFO -       "startTime": 9983.778999844953,
[task 2021-02-15T21:05:12.645Z] 21:05:12     INFO -       "endTime": 10023.009125461476,
[task 2021-02-15T21:05:12.645Z] 21:05:12     INFO -       "id": 2362232012808,
[task 2021-02-15T21:05:12.645Z] 21:05:12     INFO -       "status": "STATUS_START",
[task 2021-02-15T21:05:12.646Z] 21:05:12     INFO -       "cache": "Unresolved",
[task 2021-02-15T21:05:12.646Z] 21:05:12     INFO -       "pri": 8,
[task 2021-02-15T21:05:12.646Z] 21:05:12     INFO -       "URI": "http://example.com/browser/tools/profiler/tests/browser/redirect.sjs?firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.646Z] 21:05:12     INFO -       "requestMethod": "GET",
[task 2021-02-15T21:05:12.646Z] 21:05:12     INFO -       "contentType": null
[task 2021-02-15T21:05:12.647Z] 21:05:12     INFO -     }
[task 2021-02-15T21:05:12.647Z] 21:05:12     INFO -   },
[task 2021-02-15T21:05:12.647Z] 21:05:12     INFO -   {
[task 2021-02-15T21:05:12.647Z] 21:05:12     INFO -     "name": "Load 7: http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.647Z] 21:05:12     INFO -     "startTime": 10021.827969755932,
[task 2021-02-15T21:05:12.648Z] 21:05:12     INFO -     "endTime": 10043.86121699825,
[task 2021-02-15T21:05:12.648Z] 21:05:12     INFO -     "phase": 1,
[task 2021-02-15T21:05:12.648Z] 21:05:12     INFO -     "category": 6,
[task 2021-02-15T21:05:12.648Z] 21:05:12     INFO -     "data": {
[task 2021-02-15T21:05:12.648Z] 21:05:12     INFO -       "innerWindowID": 2147483650,
[task 2021-02-15T21:05:12.648Z] 21:05:12     INFO -       "type": "Network",
[task 2021-02-15T21:05:12.649Z] 21:05:12     INFO -       "startTime": 10021.827969755932,
[task 2021-02-15T21:05:12.649Z] 21:05:12     INFO -       "endTime": 10043.86121699825,
[task 2021-02-15T21:05:12.649Z] 21:05:12     INFO -       "id": 2362232012807,
[task 2021-02-15T21:05:12.649Z] 21:05:12     INFO -       "status": "STATUS_STOP",
[task 2021-02-15T21:05:12.650Z] 21:05:12     INFO -       "cache": "Hit",
[task 2021-02-15T21:05:12.650Z] 21:05:12     INFO -       "pri": 8,
[task 2021-02-15T21:05:12.650Z] 21:05:12     INFO -       "count": 19218,
[task 2021-02-15T21:05:12.650Z] 21:05:12     INFO -       "URI": "http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.650Z] 21:05:12     INFO -       "requestMethod": "GET",
[task 2021-02-15T21:05:12.651Z] 21:05:12     INFO -       "contentType": "image/svg+xml"
[task 2021-02-15T21:05:12.651Z] 21:05:12     INFO -     }
[task 2021-02-15T21:05:12.651Z] 21:05:12     INFO -   },
[task 2021-02-15T21:05:12.651Z] 21:05:12     INFO -   {
[task 2021-02-15T21:05:12.651Z] 21:05:12     INFO -     "name": "Load 8: http://example.com/browser/tools/profiler/tests/browser/redirect.sjs?firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.651Z] 21:05:12     INFO -     "startTime": 10023.009125461476,
[task 2021-02-15T21:05:12.652Z] 21:05:12     INFO -     "endTime": 10047.375909508051,
[task 2021-02-15T21:05:12.652Z] 21:05:12     INFO -     "phase": 1,
[task 2021-02-15T21:05:12.652Z] 21:05:12     INFO -     "category": 6,
[task 2021-02-15T21:05:12.652Z] 21:05:12     INFO -     "data": {
[task 2021-02-15T21:05:12.652Z] 21:05:12     INFO -       "innerWindowID": 2147483650,
[task 2021-02-15T21:05:12.652Z] 21:05:12     INFO -       "type": "Network",
[task 2021-02-15T21:05:12.652Z] 21:05:12     INFO -       "startTime": 10023.009125461476,
[task 2021-02-15T21:05:12.652Z] 21:05:12     INFO -       "endTime": 10047.375909508051,
[task 2021-02-15T21:05:12.653Z] 21:05:12     INFO -       "id": 2362232012808,
[task 2021-02-15T21:05:12.653Z] 21:05:12     INFO -       "status": "STATUS_REDIRECT",
[task 2021-02-15T21:05:12.653Z] 21:05:12     INFO -       "cache": "Hit",
[task 2021-02-15T21:05:12.653Z] 21:05:12     INFO -       "pri": 8,
[task 2021-02-15T21:05:12.654Z] 21:05:12     INFO -       "URI": "http://example.com/browser/tools/profiler/tests/browser/redirect.sjs?firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.654Z] 21:05:12     INFO -       "RedirectURI": "http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.654Z] 21:05:12     INFO -       "requestMethod": "GET",
[task 2021-02-15T21:05:12.654Z] 21:05:12     INFO -       "contentType": null
[task 2021-02-15T21:05:12.655Z] 21:05:12     INFO -     }
[task 2021-02-15T21:05:12.655Z] 21:05:12     INFO -   },
[task 2021-02-15T21:05:12.655Z] 21:05:12     INFO -   {
[task 2021-02-15T21:05:12.655Z] 21:05:12     INFO -     "name": "Load 33: http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.656Z] 21:05:12     INFO -     "startTime": 10047.425077768263,
[task 2021-02-15T21:05:12.656Z] 21:05:12     INFO -     "endTime": 10100.14211303392,
[task 2021-02-15T21:05:12.656Z] 21:05:12     INFO -     "phase": 1,
[task 2021-02-15T21:05:12.656Z] 21:05:12     INFO -     "category": 6,
[task 2021-02-15T21:05:12.656Z] 21:05:12     INFO -     "data": {
[task 2021-02-15T21:05:12.657Z] 21:05:12     INFO -       "innerWindowID": 2147483650,
[task 2021-02-15T21:05:12.657Z] 21:05:12     INFO -       "type": "Network",
[task 2021-02-15T21:05:12.657Z] 21:05:12     INFO -       "startTime": 10047.425077768263,
[task 2021-02-15T21:05:12.657Z] 21:05:12     INFO -       "endTime": 10100.14211303392,
[task 2021-02-15T21:05:12.657Z] 21:05:12     INFO -       "id": 16381005266977,
[task 2021-02-15T21:05:12.658Z] 21:05:12     INFO -       "status": "STATUS_START",
[task 2021-02-15T21:05:12.658Z] 21:05:12     INFO -       "cache": "Unresolved",
[task 2021-02-15T21:05:12.658Z] 21:05:12     INFO -       "pri": 8,
[task 2021-02-15T21:05:12.658Z] 21:05:12     INFO -       "URI": "http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.658Z] 21:05:12     INFO -       "requestMethod": "GET",
[task 2021-02-15T21:05:12.659Z] 21:05:12     INFO -       "contentType": null
[task 2021-02-15T21:05:12.659Z] 21:05:12     INFO -     }
[task 2021-02-15T21:05:12.659Z] 21:05:12     INFO -   },
[task 2021-02-15T21:05:12.659Z] 21:05:12     INFO -   {
[task 2021-02-15T21:05:12.659Z] 21:05:12     INFO -     "name": "Load 33: http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.660Z] 21:05:12     INFO -     "startTime": 10100.14211303392,
[task 2021-02-15T21:05:12.660Z] 21:05:12     INFO -     "endTime": 10107.856221949996,
[task 2021-02-15T21:05:12.660Z] 21:05:12     INFO -     "phase": 1,
[task 2021-02-15T21:05:12.660Z] 21:05:12     INFO -     "category": 6,
[task 2021-02-15T21:05:12.660Z] 21:05:12     INFO -     "data": {
[task 2021-02-15T21:05:12.661Z] 21:05:12     INFO -       "innerWindowID": 2147483650,
[task 2021-02-15T21:05:12.661Z] 21:05:12     INFO -       "type": "Network",
[task 2021-02-15T21:05:12.661Z] 21:05:12     INFO -       "startTime": 10100.14211303392,
[task 2021-02-15T21:05:12.661Z] 21:05:12     INFO -       "endTime": 10107.856221949996,
[task 2021-02-15T21:05:12.662Z] 21:05:12     INFO -       "id": 16381005266977,
[task 2021-02-15T21:05:12.662Z] 21:05:12     INFO -       "status": "STATUS_STOP",
[task 2021-02-15T21:05:12.662Z] 21:05:12     INFO -       "cache": "Hit",
[task 2021-02-15T21:05:12.663Z] 21:05:12     INFO -       "pri": 8,
[task 2021-02-15T21:05:12.663Z] 21:05:12     INFO -       "count": 19218,
[task 2021-02-15T21:05:12.663Z] 21:05:12     INFO -       "URI": "http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.663Z] 21:05:12     INFO -       "requestMethod": "GET",
[task 2021-02-15T21:05:12.664Z] 21:05:12     INFO -       "contentType": "image/svg+xml"
[task 2021-02-15T21:05:12.664Z] 21:05:12     INFO -     }
[task 2021-02-15T21:05:12.664Z] 21:05:12     INFO -   }
[task 2021-02-15T21:05:12.664Z] 21:05:12     INFO - ]
[task 2021-02-15T21:05:12.665Z] 21:05:12     INFO - [
[task 2021-02-15T21:05:12.665Z] 21:05:12     INFO -   {
[task 2021-02-15T21:05:12.665Z] 21:05:12     INFO -     "name": "Load 29: http://example.com/browser/tools/profiler/tests/browser/page_with_resources.html?cacheBust=0.7504467633148478",
[task 2021-02-15T21:05:12.665Z] 21:05:12     INFO -     "startTime": 7621.635617096586,
[task 2021-02-15T21:05:12.666Z] 21:05:12     INFO -     "endTime": 7840.9674036784,
[task 2021-02-15T21:05:12.666Z] 21:05:12     INFO -     "phase": 1,
[task 2021-02-15T21:05:12.666Z] 21:05:12     INFO -     "category": 6,
[task 2021-02-15T21:05:12.666Z] 21:05:12     INFO -     "data": {
[task 2021-02-15T21:05:12.666Z] 21:05:12     INFO -       "type": "Network",
[task 2021-02-15T21:05:12.666Z] 21:05:12     INFO -       "startTime": 7621.635617096586,
[task 2021-02-15T21:05:12.667Z] 21:05:12     INFO -       "endTime": 7840.9674036784,
[task 2021-02-15T21:05:12.667Z] 21:05:12     INFO -       "id": 16381005266973,
[task 2021-02-15T21:05:12.667Z] 21:05:12     INFO -       "status": "STATUS_START",
[task 2021-02-15T21:05:12.667Z] 21:05:12     INFO -       "pri": 0,
[task 2021-02-15T21:05:12.667Z] 21:05:12     INFO -       "URI": "http://example.com/browser/tools/profiler/tests/browser/page_with_resources.html?cacheBust=0.7504467633148478",
[task 2021-02-15T21:05:12.668Z] 21:05:12     INFO -       "requestMethod": "GET",
[task 2021-02-15T21:05:12.668Z] 21:05:12     INFO -       "contentType": null
[task 2021-02-15T21:05:12.668Z] 21:05:12     INFO -     }
[task 2021-02-15T21:05:12.668Z] 21:05:12     INFO -   },
[task 2021-02-15T21:05:12.669Z] 21:05:12     INFO -   {
[task 2021-02-15T21:05:12.669Z] 21:05:12     INFO -     "name": "Load 7: http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.669Z] 21:05:12     INFO -     "startTime": 7864.152752654319,
[task 2021-02-15T21:05:12.669Z] 21:05:12     INFO -     "endTime": 7865.064600389155,
[task 2021-02-15T21:05:12.669Z] 21:05:12     INFO -     "phase": 1,
[task 2021-02-15T21:05:12.670Z] 21:05:12     INFO -     "category": 6,
[task 2021-02-15T21:05:12.670Z] 21:05:12     INFO -     "data": {
[task 2021-02-15T21:05:12.670Z] 21:05:12     INFO -       "innerWindowID": 2147483650,
[task 2021-02-15T21:05:12.670Z] 21:05:12     INFO -       "type": "Network",
[task 2021-02-15T21:05:12.670Z] 21:05:12     INFO -       "startTime": 7864.152752654319,
[task 2021-02-15T21:05:12.671Z] 21:05:12     INFO -       "endTime": 7865.064600389155,
[task 2021-02-15T21:05:12.671Z] 21:05:12     INFO -       "id": 2362232012807,
[task 2021-02-15T21:05:12.671Z] 21:05:12     INFO -       "status": "STATUS_START",
[task 2021-02-15T21:05:12.671Z] 21:05:12     INFO -       "pri": 10,
[task 2021-02-15T21:05:12.672Z] 21:05:12     INFO -       "URI": "http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.672Z] 21:05:12     INFO -       "requestMethod": "GET",
[task 2021-02-15T21:05:12.672Z] 21:05:12     INFO -       "contentType": null
[task 2021-02-15T21:05:12.672Z] 21:05:12     INFO -     }
[task 2021-02-15T21:05:12.673Z] 21:05:12     INFO -   },
[task 2021-02-15T21:05:12.673Z] 21:05:12     INFO -   {
[task 2021-02-15T21:05:12.673Z] 21:05:12     INFO -     "name": "Load 8: http://example.com/browser/tools/profiler/tests/browser/redirect.sjs?firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.673Z] 21:05:12     INFO -     "startTime": 7865.550416323862,
[task 2021-02-15T21:05:12.673Z] 21:05:12     INFO -     "endTime": 7865.837044931689,
[task 2021-02-15T21:05:12.673Z] 21:05:12     INFO -     "phase": 1,
[task 2021-02-15T21:05:12.674Z] 21:05:12     INFO -     "category": 6,
[task 2021-02-15T21:05:12.674Z] 21:05:12     INFO -     "data": {
[task 2021-02-15T21:05:12.674Z] 21:05:12     INFO -       "innerWindowID": 2147483650,
[task 2021-02-15T21:05:12.675Z] 21:05:12     INFO -       "type": "Network",
[task 2021-02-15T21:05:12.675Z] 21:05:12     INFO -       "startTime": 7865.550416323862,
[task 2021-02-15T21:05:12.675Z] 21:05:12     INFO -       "endTime": 7865.837044931689,
[task 2021-02-15T21:05:12.675Z] 21:05:12     INFO -       "id": 2362232012808,
[task 2021-02-15T21:05:12.675Z] 21:05:12     INFO -       "status": "STATUS_START",
[task 2021-02-15T21:05:12.675Z] 21:05:12     INFO -       "pri": 10,
[task 2021-02-15T21:05:12.675Z] 21:05:12     INFO -       "URI": "http://example.com/browser/tools/profiler/tests/browser/redirect.sjs?firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.675Z] 21:05:12     INFO -       "requestMethod": "GET",
[task 2021-02-15T21:05:12.675Z] 21:05:12     INFO -       "contentType": null
[task 2021-02-15T21:05:12.675Z] 21:05:12     INFO -     }
[task 2021-02-15T21:05:12.676Z] 21:05:12     INFO -   },
[task 2021-02-15T21:05:12.676Z] 21:05:12     INFO -   {
[task 2021-02-15T21:05:12.676Z] 21:05:12     INFO -     "name": "Load 29: http://example.com/browser/tools/profiler/tests/browser/page_with_resources.html?cacheBust=0.7504467633148478",
[task 2021-02-15T21:05:12.676Z] 21:05:12     INFO -     "startTime": 7840.9674036784,
[task 2021-02-15T21:05:12.677Z] 21:05:12     INFO -     "endTime": 7866.297438640944,
[task 2021-02-15T21:05:12.677Z] 21:05:12     INFO -     "phase": 1,
[task 2021-02-15T21:05:12.677Z] 21:05:12     INFO -     "category": 6,
[task 2021-02-15T21:05:12.677Z] 21:05:12     INFO -     "data": {
[task 2021-02-15T21:05:12.677Z] 21:05:12     INFO -       "type": "Network",
[task 2021-02-15T21:05:12.678Z] 21:05:12     INFO -       "startTime": 7840.9674036784,
[task 2021-02-15T21:05:12.678Z] 21:05:12     INFO -       "endTime": 7866.297438640944,
[task 2021-02-15T21:05:12.678Z] 21:05:12     INFO -       "id": 16381005266973,
[task 2021-02-15T21:05:12.679Z] 21:05:12     INFO -       "status": "STATUS_STOP",
[task 2021-02-15T21:05:12.679Z] 21:05:12     INFO -       "pri": 0,
[task 2021-02-15T21:05:12.679Z] 21:05:12     INFO -       "count": 423,
[task 2021-02-15T21:05:12.679Z] 21:05:12     INFO -       "URI": "http://example.com/browser/tools/profiler/tests/browser/page_with_resources.html?cacheBust=0.7504467633148478",
[task 2021-02-15T21:05:12.679Z] 21:05:12     INFO -       "requestMethod": "GET",
[task 2021-02-15T21:05:12.679Z] 21:05:12     INFO -       "contentType": "text/html",
[task 2021-02-15T21:05:12.680Z] 21:05:12     INFO -       "domainLookupStart": 7815.230613108649,
[task 2021-02-15T21:05:12.680Z] 21:05:12     INFO -       "domainLookupEnd": 7815.589876646333,
[task 2021-02-15T21:05:12.680Z] 21:05:12     INFO -       "connectStart": 7816.53971803679,
[task 2021-02-15T21:05:12.681Z] 21:05:12     INFO -       "tcpConnectEnd": 7816.94144507193,
[task 2021-02-15T21:05:12.681Z] 21:05:12     INFO -       "connectEnd": 7816.94144507193,
[task 2021-02-15T21:05:12.681Z] 21:05:12     INFO -       "requestStart": 7817.243438761071,
[task 2021-02-15T21:05:12.681Z] 21:05:12     INFO -       "responseStart": 7824.17029678353,
[task 2021-02-15T21:05:12.681Z] 21:05:12     INFO -       "responseEnd": 7825.729712854566
[task 2021-02-15T21:05:12.682Z] 21:05:12     INFO -     }
[task 2021-02-15T21:05:12.682Z] 21:05:12     INFO -   },
[task 2021-02-15T21:05:12.682Z] 21:05:12     INFO -   {
[task 2021-02-15T21:05:12.682Z] 21:05:12     INFO -     "name": "Load 7: http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.682Z] 21:05:12     INFO -     "startTime": 7865.064600389155,
[task 2021-02-15T21:05:12.683Z] 21:05:12     INFO -     "endTime": 7960.531761718319,
[task 2021-02-15T21:05:12.683Z] 21:05:12     INFO -     "phase": 1,
[task 2021-02-15T21:05:12.683Z] 21:05:12     INFO -     "category": 6,
[task 2021-02-15T21:05:12.683Z] 21:05:12     INFO -     "data": {
[task 2021-02-15T21:05:12.684Z] 21:05:12     INFO -       "innerWindowID": 2147483650,
[task 2021-02-15T21:05:12.684Z] 21:05:12     INFO -       "type": "Network",
[task 2021-02-15T21:05:12.684Z] 21:05:12     INFO -       "startTime": 7865.064600389155,
[task 2021-02-15T21:05:12.684Z] 21:05:12     INFO -       "endTime": 7960.531761718319,
[task 2021-02-15T21:05:12.684Z] 21:05:12     INFO -       "id": 2362232012807,
[task 2021-02-15T21:05:12.685Z] 21:05:12     INFO -       "status": "STATUS_STOP",
[task 2021-02-15T21:05:12.685Z] 21:05:12     INFO -       "pri": 8,
[task 2021-02-15T21:05:12.685Z] 21:05:12     INFO -       "URI": "http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.686Z] 21:05:12     INFO -       "requestMethod": "GET",
[task 2021-02-15T21:05:12.686Z] 21:05:12     INFO -       "contentType": "image/svg+xml"
[task 2021-02-15T21:05:12.686Z] 21:05:12     INFO -     }
[task 2021-02-15T21:05:12.686Z] 21:05:12     INFO -   },
[task 2021-02-15T21:05:12.686Z] 21:05:12     INFO -   {
[task 2021-02-15T21:05:12.687Z] 21:05:12     INFO -     "name": "Load 8: http://example.com/browser/tools/profiler/tests/browser/redirect.sjs?firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.687Z] 21:05:12     INFO -     "startTime": 7865.837044931689,
[task 2021-02-15T21:05:12.687Z] 21:05:12     INFO -     "endTime": 7961.699787263466,
[task 2021-02-15T21:05:12.687Z] 21:05:12     INFO -     "phase": 1,
[task 2021-02-15T21:05:12.688Z] 21:05:12     INFO -     "category": 6,
[task 2021-02-15T21:05:12.688Z] 21:05:12     INFO -     "data": {
[task 2021-02-15T21:05:12.688Z] 21:05:12     INFO -       "innerWindowID": 2147483650,
[task 2021-02-15T21:05:12.688Z] 21:05:12     INFO -       "type": "Network",
[task 2021-02-15T21:05:12.688Z] 21:05:12     INFO -       "startTime": 7865.837044931689,
[task 2021-02-15T21:05:12.689Z] 21:05:12     INFO -       "endTime": 7961.699787263466,
[task 2021-02-15T21:05:12.689Z] 21:05:12     INFO -       "id": 2362232012808,
[task 2021-02-15T21:05:12.689Z] 21:05:12     INFO -       "status": "STATUS_REDIRECT",
[task 2021-02-15T21:05:12.689Z] 21:05:12     INFO -       "pri": 8,
[task 2021-02-15T21:05:12.689Z] 21:05:12     INFO -       "URI": "http://example.com/browser/tools/profiler/tests/browser/redirect.sjs?firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.690Z] 21:05:12     INFO -       "RedirectURI": "http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.690Z] 21:05:12     INFO -       "requestMethod": "GET",
[task 2021-02-15T21:05:12.690Z] 21:05:12     INFO -       "contentType": null
[task 2021-02-15T21:05:12.690Z] 21:05:12     INFO -     }
[task 2021-02-15T21:05:12.691Z] 21:05:12     INFO -   },
[task 2021-02-15T21:05:12.691Z] 21:05:12     INFO -   {
[task 2021-02-15T21:05:12.691Z] 21:05:12     INFO -     "name": "Load 33: http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.691Z] 21:05:12     INFO -     "startTime": 7961.759571398041,
[task 2021-02-15T21:05:12.691Z] 21:05:12     INFO -     "endTime": 8010.19868866015,
[task 2021-02-15T21:05:12.692Z] 21:05:12     INFO -     "phase": 1,
[task 2021-02-15T21:05:12.692Z] 21:05:12     INFO -     "category": 6,
[task 2021-02-15T21:05:12.692Z] 21:05:12     INFO -     "data": {
[task 2021-02-15T21:05:12.692Z] 21:05:12     INFO -       "innerWindowID": 2147483650,
[task 2021-02-15T21:05:12.693Z] 21:05:12     INFO -       "type": "Network",
[task 2021-02-15T21:05:12.693Z] 21:05:12     INFO -       "startTime": 7961.759571398041,
[task 2021-02-15T21:05:12.693Z] 21:05:12     INFO -       "endTime": 8010.19868866015,
[task 2021-02-15T21:05:12.693Z] 21:05:12     INFO -       "id": 16381005266977,
[task 2021-02-15T21:05:12.693Z] 21:05:12     INFO -       "status": "STATUS_START",
[task 2021-02-15T21:05:12.693Z] 21:05:12     INFO -       "pri": 8,
[task 2021-02-15T21:05:12.693Z] 21:05:12     INFO -       "URI": "http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.694Z] 21:05:12     INFO -       "requestMethod": "GET",
[task 2021-02-15T21:05:12.694Z] 21:05:12     INFO -       "contentType": null
[task 2021-02-15T21:05:12.694Z] 21:05:12     INFO -     }
[task 2021-02-15T21:05:12.694Z] 21:05:12     INFO -   },
[task 2021-02-15T21:05:12.694Z] 21:05:12     INFO -   {
[task 2021-02-15T21:05:12.695Z] 21:05:12     INFO -     "name": "Load 33: http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.695Z] 21:05:12     INFO -     "startTime": 8010.19868866015,
[task 2021-02-15T21:05:12.695Z] 21:05:12     INFO -     "endTime": 8023.2497887301315,
[task 2021-02-15T21:05:12.695Z] 21:05:12     INFO -     "phase": 1,
[task 2021-02-15T21:05:12.696Z] 21:05:12     INFO -     "category": 6,
[task 2021-02-15T21:05:12.696Z] 21:05:12     INFO -     "data": {
[task 2021-02-15T21:05:12.696Z] 21:05:12     INFO -       "innerWindowID": 2147483650,
[task 2021-02-15T21:05:12.696Z] 21:05:12     INFO -       "type": "Network",
[task 2021-02-15T21:05:12.696Z] 21:05:12     INFO -       "startTime": 8010.19868866015,
[task 2021-02-15T21:05:12.696Z] 21:05:12     INFO -       "endTime": 8023.2497887301315,
[task 2021-02-15T21:05:12.696Z] 21:05:12     INFO -       "id": 16381005266977,
[task 2021-02-15T21:05:12.697Z] 21:05:12     INFO -       "status": "STATUS_STOP",
[task 2021-02-15T21:05:12.697Z] 21:05:12     INFO -       "pri": 8,
[task 2021-02-15T21:05:12.697Z] 21:05:12     INFO -       "URI": "http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg",
[task 2021-02-15T21:05:12.697Z] 21:05:12     INFO -       "requestMethod": "GET",
[task 2021-02-15T21:05:12.697Z] 21:05:12     INFO -       "contentType": "image/svg+xml"
[task 2021-02-15T21:05:12.697Z] 21:05:12     INFO -     }
[task 2021-02-15T21:05:12.697Z] 21:05:12     INFO -   }
[task 2021-02-15T21:05:12.697Z] 21:05:12     INFO - ]
[task 2021-02-15T21:05:12.697Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | We should get 4 pairs of network markers in the parent thread. - 8 == 8 - 
[task 2021-02-15T21:05:12.697Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | We should get 4 pairs of network markers in the content thread. - 8 == 8 - 
[task 2021-02-15T21:05:12.698Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | For the URL http://example.com/browser/tools/profiler/tests/browser/page_with_resources.html?cacheBust=0.7504467633148478 we should get 2 markers in the parent process. - 2 == 2 - 
[task 2021-02-15T21:05:12.698Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | For the URL http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg we should get 2 markers in the parent process. - 2 == 2 - 
[task 2021-02-15T21:05:12.698Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | For the URL http://example.com/browser/tools/profiler/tests/browser/redirect.sjs?firefox-logo-nightly.svg we should get 2 markers in the parent process. - 2 == 2 - 
[task 2021-02-15T21:05:12.698Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | For the URL http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg we should get 2 markers in the parent process. - 2 == 2 - 
[task 2021-02-15T21:05:12.698Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | For the URL http://example.com/browser/tools/profiler/tests/browser/page_with_resources.html?cacheBust=0.7504467633148478 we should get 2 markers in the content process. - 2 == 2 - 
[task 2021-02-15T21:05:12.698Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | For the URL http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg we should get 2 markers in the content process. - 2 == 2 - 
[task 2021-02-15T21:05:12.698Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | For the URL http://example.com/browser/tools/profiler/tests/browser/redirect.sjs?firefox-logo-nightly.svg we should get 2 markers in the content process. - 2 == 2 - 
[task 2021-02-15T21:05:12.698Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | For the URL http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg we should get 2 markers in the content process. - 2 == 2 - 
[task 2021-02-15T21:05:12.698Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "name" with an expected value and type. - "Load 7: http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg" matches "/Load \\d+:.*firefox-logo-nightly\\.svg/" - 
[task 2021-02-15T21:05:12.698Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "requestMethod" with an expected value. - "GET" == "GET" - 
[task 2021-02-15T21:05:12.699Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "startTime" with an expected value and type. - 10021.827969755932 has type "number" - 
[task 2021-02-15T21:05:12.699Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "endTime" with an expected value and type. - 10043.86121699825 has type "number" - 
[task 2021-02-15T21:05:12.699Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "id" with an expected value and type. - 2362232012807 has type "number" - 
[task 2021-02-15T21:05:12.699Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "pri" with an expected value and type. - 8 has type "number" - 
[task 2021-02-15T21:05:12.699Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "status" with an expected value. - "STATUS_STOP" == "STATUS_STOP" - 
[task 2021-02-15T21:05:12.699Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "URI" with an expected value and type. - "http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg" includes "/firefox-logo-nightly.svg" - 
[task 2021-02-15T21:05:12.699Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "contentType" with an expected value. - "image/svg+xml" == "image/svg+xml" - 
[task 2021-02-15T21:05:12.699Z] 21:05:12     INFO - Buffered messages finished
[task 2021-02-15T21:05:12.699Z] 21:05:12     INFO - TEST-UNEXPECTED-FAIL | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property domainLookupStart - false == true - JS frame :: chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js :: objectContains :: line 364
[task 2021-02-15T21:05:12.699Z] 21:05:12     INFO - Stack trace:
[task 2021-02-15T21:05:12.699Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:364
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:Expect[assertChecker]/<:430
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:370
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/browser_test_marker_network_redirect.js:test_network_markers_redirect_resources/<:263
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - TEST-UNEXPECTED-FAIL | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property domainLookupEnd - false == true - JS frame :: chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js :: objectContains :: line 364
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - Stack trace:
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:364
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:Expect[assertChecker]/<:430
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:370
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/browser_test_marker_network_redirect.js:test_network_markers_redirect_resources/<:263
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - TEST-UNEXPECTED-FAIL | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property connectStart - false == true - JS frame :: chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js :: objectContains :: line 364
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - Stack trace:
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:364
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:Expect[assertChecker]/<:430
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:370
[task 2021-02-15T21:05:12.700Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/browser_test_marker_network_redirect.js:test_network_markers_redirect_resources/<:263
[task 2021-02-15T21:05:12.701Z] 21:05:12     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-02-15T21:05:12.701Z] 21:05:12     INFO - TEST-UNEXPECTED-FAIL | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property tcpConnectEnd - false == true - JS frame :: chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js :: objectContains :: line 364
[task 2021-02-15T21:05:12.701Z] 21:05:12     INFO - Stack trace:
[task 2021-02-15T21:05:12.701Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:364
[task 2021-02-15T21:05:12.701Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:Expect[assertChecker]/<:430
[task 2021-02-15T21:05:12.701Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:370
[task 2021-02-15T21:05:12.701Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/browser_test_marker_network_redirect.js:test_network_markers_redirect_resources/<:263
[task 2021-02-15T21:05:12.701Z] 21:05:12     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-02-15T21:05:12.701Z] 21:05:12     INFO - TEST-UNEXPECTED-FAIL | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property connectEnd - false == true - JS frame :: chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js :: objectContains :: line 364
[task 2021-02-15T21:05:12.701Z] 21:05:12     INFO - Stack trace:
[task 2021-02-15T21:05:12.701Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:364
[task 2021-02-15T21:05:12.701Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:Expect[assertChecker]/<:430
[task 2021-02-15T21:05:12.701Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:370
[task 2021-02-15T21:05:12.701Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/browser_test_marker_network_redirect.js:test_network_markers_redirect_resources/<:263
[task 2021-02-15T21:05:12.701Z] 21:05:12     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-02-15T21:05:12.702Z] 21:05:12     INFO - TEST-UNEXPECTED-FAIL | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property requestStart - false == true - JS frame :: chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js :: objectContains :: line 364
[task 2021-02-15T21:05:12.702Z] 21:05:12     INFO - Stack trace:
[task 2021-02-15T21:05:12.702Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:364
[task 2021-02-15T21:05:12.702Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:Expect[assertChecker]/<:430
[task 2021-02-15T21:05:12.702Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:370
[task 2021-02-15T21:05:12.702Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/browser_test_marker_network_redirect.js:test_network_markers_redirect_resources/<:263
[task 2021-02-15T21:05:12.702Z] 21:05:12     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-02-15T21:05:12.702Z] 21:05:12     INFO - TEST-UNEXPECTED-FAIL | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property responseStart - false == true - JS frame :: chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js :: objectContains :: line 364
[task 2021-02-15T21:05:12.702Z] 21:05:12     INFO - Stack trace:
[task 2021-02-15T21:05:12.702Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:364
[task 2021-02-15T21:05:12.702Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:Expect[assertChecker]/<:430
[task 2021-02-15T21:05:12.702Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:370
[task 2021-02-15T21:05:12.702Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/browser_test_marker_network_redirect.js:test_network_markers_redirect_resources/<:263
[task 2021-02-15T21:05:12.702Z] 21:05:12     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-02-15T21:05:12.703Z] 21:05:12     INFO - TEST-UNEXPECTED-FAIL | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property responseEnd - false == true - JS frame :: chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js :: objectContains :: line 364
[task 2021-02-15T21:05:12.703Z] 21:05:12     INFO - Stack trace:
[task 2021-02-15T21:05:12.703Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:364
[task 2021-02-15T21:05:12.703Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:Expect[assertChecker]/<:430
[task 2021-02-15T21:05:12.703Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:370
[task 2021-02-15T21:05:12.703Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/browser_test_marker_network_redirect.js:test_network_markers_redirect_resources/<:263
[task 2021-02-15T21:05:12.703Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "name" with an expected value and type. - "Load 7: http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg" matches "/Load \\d+:.*firefox-logo-nightly\\.svg/" - 
[task 2021-02-15T21:05:12.703Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "requestMethod" with an expected value. - "GET" == "GET" - 
[task 2021-02-15T21:05:12.703Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "startTime" with an expected value and type. - 7865.064600389155 has type "number" - 
[task 2021-02-15T21:05:12.703Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "endTime" with an expected value and type. - 7960.531761718319 has type "number" - 
[task 2021-02-15T21:05:12.703Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "id" with an expected value and type. - 2362232012807 has type "number" - 
[task 2021-02-15T21:05:12.703Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "pri" with an expected value and type. - 8 has type "number" - 
[task 2021-02-15T21:05:12.704Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "status" with an expected value. - "STATUS_STOP" == "STATUS_STOP" - 
[task 2021-02-15T21:05:12.704Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "URI" with an expected value and type. - "http://example.com/browser/tools/profiler/tests/browser/firefox-logo-nightly.svg" includes "/firefox-logo-nightly.svg" - 
[task 2021-02-15T21:05:12.704Z] 21:05:12     INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property "contentType" with an expected value. - "image/svg+xml" == "image/svg+xml" - 
[task 2021-02-15T21:05:12.704Z] 21:05:12     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-02-15T21:05:12.704Z] 21:05:12     INFO - TEST-UNEXPECTED-FAIL | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property domainLookupStart - false == true - JS frame :: chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js :: objectContains :: line 364
[task 2021-02-15T21:05:12.704Z] 21:05:12     INFO - Stack trace:
[task 2021-02-15T21:05:12.704Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:364
[task 2021-02-15T21:05:12.704Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:Expect[assertChecker]/<:430
[task 2021-02-15T21:05:12.704Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:370
[task 2021-02-15T21:05:12.704Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/browser_test_marker_network_redirect.js:test_network_markers_redirect_resources/<:267
[task 2021-02-15T21:05:12.704Z] 21:05:12     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-02-15T21:05:12.705Z] 21:05:12     INFO - TEST-UNEXPECTED-FAIL | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property domainLookupEnd - false == true - JS frame :: chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js :: objectContains :: line 364
[task 2021-02-15T21:05:12.705Z] 21:05:12     INFO - Stack trace:
[task 2021-02-15T21:05:12.705Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:364
[task 2021-02-15T21:05:12.705Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:Expect[assertChecker]/<:430
[task 2021-02-15T21:05:12.705Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:370
[task 2021-02-15T21:05:12.705Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/browser_test_marker_network_redirect.js:test_network_markers_redirect_resources/<:267
[task 2021-02-15T21:05:12.705Z] 21:05:12     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-02-15T21:05:12.705Z] 21:05:12     INFO - TEST-UNEXPECTED-FAIL | tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property connectStart - false == true - JS frame :: chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js :: objectContains :: line 364
[task 2021-02-15T21:05:12.705Z] 21:05:12     INFO - Stack trace:
[task 2021-02-15T21:05:12.705Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:364
[task 2021-02-15T21:05:12.705Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:Expect[assertChecker]/<:430
[task 2021-02-15T21:05:12.705Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/shared-head.js:objectContains:370
[task 2021-02-15T21:05:12.705Z] 21:05:12     INFO - chrome://mochitests/content/browser/tools/profiler/tests/browser/browser_test_marker_network_redirect.js:test_network_markers_redirect_resources/<:267
[task 2021-02-15T21:05:12.705Z] 21:05:12     INFO - Not taking screenshot here: see the one that was previously logged
...
...
...

Gerald Squelart (he/him) (not at Mozilla since 2022-09-15)

Comment 1

•

4 years ago

Julien, this looks related to your recent network work, could you please have a look? And please set the bug priority as appropriate.

Flags: needinfo?(felash)

Julien Wajsberg [:julienw] (PTO -> Aug 14)

Comment 2

•

4 years ago

So far it seems to happen in "verify" mode only. There's probably a race happening in the network code that makes that we have different data in some cases. It's possible we can tweak the network races with prefs but I'd prefer to not do it if I can avoid it.

I'll wait 1 or 2 days to understand the frequency, but I'm not so hopeful, given it appeared within 1 hour of the initial landing.

Keeping the NI for now.

BugBot [:suhaib / :marco/ :calixte]

Comment 3

•

4 years ago

Set release status flags based on info from the regressing bug 1688669

status-firefox85: --- → unaffected

status-firefox86: --- → unaffected

status-firefox87: --- → affected

status-firefox-esr78: --- → unaffected

Florian Quèze [:florian]

Comment 4

•

4 years ago

(In reply to Julien Wajsberg [:julienw] from comment #2)

I'll wait 1 or 2 days to understand the frequency, but I'm not so hopeful, given it appeared within 1 hour of the initial landing.

This bug seems to be only about TV failures. The TV jobs are only triggered when the test file itself is modified, so you'll not see more failures, unless you land patches tweaking this test.

Julien Cristau [:jcristau] (back August 18)

Updated

•

4 years ago

status-firefox87: affected → fix-optional

Julien Wajsberg [:julienw] (PTO -> Aug 14)

Comment 5

•

4 years ago

(In reply to Florian Quèze [:florian] from comment #4)

(In reply to Julien Wajsberg [:julienw] from comment #2)

I'll wait 1 or 2 days to understand the frequency, but I'm not so hopeful, given it appeared within 1 hour of the initial landing.

This bug seems to be only about TV failures. The TV jobs are only triggered when the test file itself is modified, so you'll not see more failures, unless you land patches tweaking this test.

ah I see, I thought they were still run from time to time.

Looking at the failures, we might still see this from time to time. I think the reason here is that the requests were more sent in sequence than in parallel, and reused a connection, and so we're missing some of the timings.

The solution would be to not expect these additional properties, because in this specific test we're not especially focused on them, so it's OK if we don't look for them.

I won't stop everything to work on this, but might fix this next time I work on these test files.

Flags: needinfo?(felash)

Comment hidden (Intermittent Failures Robot)

BugBot [:suhaib / :marco/ :calixte]

Comment 7

•

4 years ago

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

Status: NEW → RESOLVED

Closed: 4 years ago

Resolution: --- → INCOMPLETE

BMO Automation

Updated

•

4 years ago

Has Regression Range: --- → yes

You need to log in before you can comment on or make changes to this bug.

Bugzilla

Intermittent TV tools/profiler/tests/browser/browser_test_marker_network_redirect.js | The object should contain the property domainLookupStart - false == true - JS frame :: chrome://*/tools/profiler/tests/browser/shared-head.js :: objectContains :: line

Categories

(Core :: Gecko Profiler, defect, P5)

Tracking

()

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Crash Data

Security

(public)

User Story

Description

Comment 1

Comment 2

Comment 3

Comment 4

Updated

Comment 5

Comment 6

Comment 7

Updated