Closed Bug 1141370 Opened 11 years ago Closed 10 years ago

Intermittent test_resource_timing.html | PerformanceEntry has correct order of timing attributes (xmlhttprequest) - assert_greater_than_equal: domainLookupEnd after domainLookupStart expected a number greater than or equal to 279.720519 but got 235.321995

Categories

(Core :: Networking, defect)

39 Branch
x86
Linux
defect
Not set
normal

Tracking

()

RESOLVED FIXED

People

(Reporter: KWierso, Assigned: valentin)

References

Details

(Keywords: intermittent-failure, regression, Whiteboard: [necko-active])

17:05:31 INFO - TEST-START | /resource-timing/test_resource_timing.html 17:05:31 INFO - PROCESS | 2767 | WARNING: content window passed to PrivateBrowsingUtils.isWindowPrivate. Use isContentWindowPrivate instead (but only for frame scripts). 17:05:31 INFO - PROCESS | 2767 | pbu_isWindowPrivate@resource://gre/modules/PrivateBrowsingUtils.jsm:25:14 17:05:31 INFO - PROCESS | 2767 | nsBrowserAccess.prototype.openURI@chrome://browser/content/browser.js:14851:21 17:05:31 INFO - PROCESS | 2767 | __marionetteFunc@dummy file:18:30 17:05:31 INFO - PROCESS | 2767 | @dummy file:27:3 17:05:31 INFO - PROCESS | 2767 | executeWithCallback@chrome://marionette/content/marionette-listener.js:700:5 17:05:31 INFO - PROCESS | 2767 | executeAsyncScript@chrome://marionette/content/marionette-listener.js:593:3 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | window.performance.getEntriesByName() and window.performance.getEntriesByNameType() return same data (iframe) 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | PerformanceEntry has correct name, initiatorType, startTime, and duration (iframe) 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | PerformanceEntry has correct order of timing attributes (iframe) 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | window.performance.getEntriesByName() and window.performance.getEntriesByNameType() return same data (img) 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | PerformanceEntry has correct name, initiatorType, startTime, and duration (img) 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | PerformanceEntry has correct order of timing attributes (img) 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | window.performance.getEntriesByName() and window.performance.getEntriesByNameType() return same data (link) 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | PerformanceEntry has correct name, initiatorType, startTime, and duration (link) 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | PerformanceEntry has correct order of timing attributes (link) 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | window.performance.getEntriesByName() and window.performance.getEntriesByNameType() return same data (script) 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | PerformanceEntry has correct name, initiatorType, startTime, and duration (script) 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | PerformanceEntry has correct order of timing attributes (script) 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | window.performance.getEntriesByName() and window.performance.getEntriesByNameType() return same data (xmlhttprequest) 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | PerformanceEntry has correct name, initiatorType, startTime, and duration (xmlhttprequest) 17:05:32 INFO - TEST-UNEXPECTED-FAIL | /resource-timing/test_resource_timing.html | PerformanceEntry has correct order of timing attributes (xmlhttprequest) - assert_greater_than_equal: domainLookupEnd after domainLookupStart expected a number greater than or equal to 279.720519 but got 235.32199500000002 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | window.performance Resource Timing Entries exist 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | window.performance Resource Timing Entries exist 1 17:05:32 INFO - TEST-PASS | /resource-timing/test_resource_timing.html | window.performance Resource Timing Entries exist 2 17:05:32 INFO - TEST-OK | /resource-timing/test_resource_timing.html | took 782ms 17:05:32 WARNING - u'runner_teardown' () 17:05:32 INFO - Setting up ssl 17:05:32 INFO - PROCESS | certutil | 17:05:32 INFO - PROCESS | certutil | 17:05:32 INFO - PROCESS | certutil | 17:05:32 INFO - Certificate Nickname Trust Attributes 17:05:32 INFO - SSL,S/MIME,JAR/XPI 17:05:32 INFO - 17:05:32 INFO - web-platform-tests CT,, 17:05:32 INFO - 17:05:32 INFO - Starting runner 17:05:32 INFO - PROCESS | 3384 | Xlib: extension "RANDR" missing on display ":0". 17:05:34 INFO - PROCESS | 3384 | -*- Webapps.jsm : Saving /tmp/tmpfKNsCl.mozrunner/webapps/webapps.json 17:05:34 INFO - PROCESS | 3384 | -*- Webapps.jsm : Saving /tmp/tmpfKNsCl.mozrunner/webapps/webapps.json 17:05:34 INFO - PROCESS | 3384 | -*- Webapps.jsm : Success saving /tmp/tmpfKNsCl.mozrunner/webapps/webapps.json 17:05:34 INFO - PROCESS | 3384 | -*- Webapps.jsm : Success saving /tmp/tmpfKNsCl.mozrunner/webapps/webapps.json 17:05:36 INFO - PROCESS | 3384 | WARNING: content window passed to PrivateBrowsingUtils.isWindowPrivate. Use isContentWindowPrivate instead (but only for frame scripts). 17:05:36 INFO - PROCESS | 3384 | pbu_isWindowPrivate@resource://gre/modules/PrivateBrowsingUtils.jsm:25:14 17:05:36 INFO - PROCESS | 3384 | nsBrowserAccess.prototype.openURI@chrome://browser/content/browser.js:14851:21 17:05:36 INFO - PROCESS | 3384 | __marionetteFunc@dummy file:18:30 17:05:36 INFO - PROCESS | 3384 | @dummy file:27:3 17:05:36 INFO - PROCESS | 3384 | executeWithCallback@chrome://marionette/content/marionette-listener.js:700:5 17:05:36 INFO - PROCESS | 3384 | executeAsyncScript@chrome://marionette/content/marionette-listener.js:593:3 17:05:36 INFO - TEST-START | /selectors-api/tests/submissions/Opera/ParentNode-query-queryAll.html
ni?'ing baku and ehsan on this, as it's in the performance timing core, not the user timing stuff I worked on. Not sure what could be causing this.
Flags: needinfo?(kyle)
Flags: needinfo?(ehsan)
Flags: needinfo?(amarchesini)
Valentin, can this be caused by bug 1123920?
Flags: needinfo?(ehsan) → needinfo?(valentin.gosu)
I think this is an issue left over from Bug 1079837 - which bug 1123920 doesn't fully fix. I am currently investigating it.
Flags: needinfo?(valentin.gosu)
Flags: needinfo?(amarchesini)
(In reply to Valentin Gosu [:valentin] from comment #13) > I think this is an issue left over from Bug 1079837 - which bug 1123920 > doesn't fully fix. > I am currently investigating it. Did you find anything out? This becomes super-unstable in debug builds (as you might expect), so it's one of the issues blocking enabling debug testing of wpt.
Flags: needinfo?(valentin.gosu)
I posted a patch in bug 1139831. It doesn't really solve the underlying problem, for which I filed bug 1155008, but it should at least return entries with the correct order.
Depends on: 1139831
Flags: needinfo?(valentin.gosu)
This might have regressed in the past week. I don't see anything clear in the log, but I might not be looking far enough.
Component: DOM → Networking
Keywords: regression
Whiteboard: [necko-backlog]
have to sort out potential regressions @ high priority.
Assignee: nobody → valentin.gosu
Whiteboard: [necko-backlog] → [necko-active]
Someone filed bug 1263428 for the latest regression. Closing as fixed and continuing the investigation in that bug.
Status: NEW → RESOLVED
Closed: 10 years ago
Resolution: --- → FIXED
You need to log in before you can comment on or make changes to this bug.