Closed Bug 1144323 Opened 9 years ago Closed 9 years ago

Intermittent browser_perf_recordings-io-04.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort

Categories

(DevTools :: Performance Tools (Profiler/Timeline), defect)

Product:
DevTools
Component:
Performance Tools (Profiler/Timeline)
Platform:
x86_64
macOS
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

(Not tracked)

Status:
RESOLVED WORKSFORME

People

(Reporter: RyanVM, Assigned: vporof)

Details

(Keywords: intermittent-failure) 

10:36:28 INFO - 4005 INFO TEST-START | browser/devtools/performance/test/browser_perf_recordings-io-04.js
10:37:24 INFO - 4006 INFO checking window state
10:37:24 INFO - 4007 INFO Initializing a performance pane.
10:37:24 INFO - 4008 INFO Adding tab: http://example.com/browser/browser/devtools/performance/test/doc_simple-test.html
10:37:24 INFO - 4009 INFO Tab added and finished loading: http://example.com/browser/browser/devtools/performance/test/doc_simple-test.html
10:37:24 INFO - 4010 INFO Console message: [JavaScript Error: "couldn't look up addon: fx-devtools" {file: "jar:file:///builds/slave/talos-slave/test/build/application/NightlyDebug.app/Contents/Resources/browser/omni.ja!/components/nsBrowserGlue.js" line: 571}]
10:37:24 INFO - 4011 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The record button should not be checked yet.
10:37:24 INFO - 4012 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The record button should not be locked yet.
10:37:24 INFO - 4013 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The record button should now be checked.
10:37:24 INFO - 4014 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The record button should be locked.
10:37:24 INFO - 4015 INFO Waiting for event: 'Performance:UI:StateChanged' on [object PerformanceView].
10:37:24 INFO - 4016 INFO Waiting for event: 'Performance:UI:OverviewRendered' on [object OverviewView].
10:37:24 INFO - 4017 INFO Console message: [JavaScript Warning: "Error in parsing value for 'top'. Declaration dropped." {file: "chrome://browser/content/devtools/graphs-frame.xhtml" line: 0 column: 0 source: "NaNpx"}]
10:37:24 INFO - 4018 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The current state is 'recording'.
10:37:24 INFO - 4019 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The record button should still be checked.
10:37:24 INFO - 4020 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The record button should not be locked.
10:37:24 INFO - 4021 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The record button should already be checked.
10:37:24 INFO - 4022 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The record button should not be locked yet.
10:37:24 INFO - 4023 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The record button should not be checked.
10:37:24 INFO - 4024 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The record button should be locked.
10:37:24 INFO - 4025 INFO Waiting for event: 'Performance:UI:StateChanged' on [object PerformanceView].
10:37:24 INFO - 4026 INFO Waiting for event: 'Performance:UI:OverviewRendered' on [object OverviewView].
10:37:24 INFO - 4027 INFO Console message: [JavaScript Warning: "Expected color but found 'undefined'. Error in parsing value for 'color'. Declaration dropped." {file: "chrome://browser/content/devtools/performance.xul" line: 0 column: 0 source: "undefined"}]
10:37:24 INFO - 4028 INFO Console message: [JavaScript Warning: "Expected color but found 'undefined'. Error in parsing value for 'color'. Declaration dropped." {file: "chrome://browser/content/devtools/performance.xul" line: 0 column: 0 source: "undefined"}]
10:37:24 INFO - 4029 INFO Console message: [JavaScript Warning: "Expected color but found 'undefined'. Error in parsing value for 'color'. Declaration dropped." {file: "chrome://browser/content/devtools/performance.xul" line: 0 column: 0 source: "undefined"}]
10:37:24 INFO - 4030 INFO Console message: [JavaScript Error: "couldn't look up addon: fx-devtools" {file: "jar:file:///builds/slave/talos-slave/test/build/application/NightlyDebug.app/Contents/Resources/browser/omni.ja!/components/nsBrowserGlue.js" line: 571}]
10:37:24 INFO - 4031 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The current state is 'recorded'.
10:37:24 INFO - 4032 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The record button should not be checked.
10:37:24 INFO - 4033 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The record button should not be locked.
10:37:24 INFO - 4034 INFO Console message: [JavaScript Error: "couldn't look up addon: fx-devtools" {file: "jar:file:///builds/slave/talos-slave/test/build/application/NightlyDebug.app/Contents/Resources/browser/omni.ja!/components/nsBrowserGlue.js" line: 571}]
10:37:24 INFO - 4035 INFO Waiting for event: 'Performance:UI:OverviewMarkersRendered' on [object OverviewView].
10:37:24 INFO - 4036 INFO Waiting for event: 'Performance:UI:OverviewMemoryRendered' on [object OverviewView].
10:37:24 INFO - 4037 INFO Waiting for event: 'Performance:UI:OverviewFramerateRendered' on [object OverviewView].
10:37:24 INFO - 4038 INFO Waiting for event: 'Performance:UI:OverviewRendered' on [object OverviewView].
10:37:24 INFO - 4039 INFO Waiting for event: 'Performance:UI:WaterfallRendered' on [object WaterfallView].
10:37:24 INFO - 4040 INFO Waiting for event: 'Performance:UI:JsCallTreeRendered' on [object JsCallTreeView].
10:37:24 INFO - 4041 INFO Waiting for event: 'Performance:UI:JsFlameGraphRendered' on [object JsFlameGraphView].
10:37:24 INFO - 4042 INFO Waiting for event: 'Performance:UI:MemoryCallTreeRendered' on [object MemoryCallTreeView].
10:37:24 INFO - 4043 INFO Waiting for event: 'Performance:UI:MemoryFlameGraphRendered' on [object MemoryFlameGraphView].
10:37:24 INFO - 4044 INFO Waiting for event: 'Performance:RecordingImported' on [object PerformanceController].
10:37:24 INFO - 4045 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The original profiler data appears to have been successfully imported.
10:37:24 INFO - 4046 INFO Console message: [JavaScript Warning: "Expected color but found 'undefined'. Error in parsing value for 'color'. Declaration dropped." {file: "chrome://browser/content/devtools/performance.xul" line: 0 column: 0 source: "undefined"}]
10:37:24 INFO - 4047 INFO Console message: [JavaScript Warning: "Expected color but found 'undefined'. Error in parsing value for 'color'. Declaration dropped." {file: "chrome://browser/content/devtools/performance.xul" line: 0 column: 0 source: "undefined"}]
10:37:24 INFO - 4048 INFO Console message: [JavaScript Warning: "Expected color but found 'undefined'. Error in parsing value for 'color'. Declaration dropped." {file: "chrome://browser/content/devtools/performance.xul" line: 0 column: 0 source: "undefined"}]
10:37:24 INFO - 4049 INFO Console message: [JavaScript Warning: "Error in parsing value for 'top'. Declaration dropped." {file: "chrome://browser/content/devtools/graphs-frame.xhtml" line: 0 column: 0 source: "NaNpx"}]
10:37:24 INFO - 4050 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The imported data was re-rendered.
10:37:24 INFO - 4051 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The imported legacy data was successfully converted for the current tool (1).
10:37:24 INFO - 4052 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The imported legacy data was successfully converted for the current tool (2).
10:37:24 INFO - 4053 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The imported legacy data was successfully converted for the current tool (3).
10:37:24 INFO - 4054 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The imported legacy data was successfully converted for the current tool (4).
10:37:24 INFO - 4055 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The imported legacy data was successfully converted for the current tool (5).
10:37:24 INFO - 4056 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The imported legacy data was successfully converted for the current tool (6).
10:37:24 INFO - 4057 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The imported legacy data was successfully converted for the current tool (7).
10:37:24 INFO - 4058 INFO TEST-PASS | browser/devtools/performance/test/browser_perf_recordings-io-04.js | The imported legacy data was successfully converted for the current tool (8).
10:37:24 INFO - 4059 INFO Destroying the performance tool.
10:37:24 INFO - 4060 INFO Removing tab.
10:37:24 INFO - 4061 INFO Tab removed and finished closing.
10:37:24 INFO - 4062 INFO finish() was called, cleaning up...
10:37:24 INFO - 4063 INFO TEST-UNEXPECTED-FAIL | browser/devtools/performance/test/browser_perf_recordings-io-04.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. - expected PASS
10:37:24 INFO - 4064 INFO MEMORY STAT vsize after test: 3545280512
10:37:24 INFO - 4065 INFO MEMORY STAT residentFast after test: 413642752
10:37:24 INFO - 4066 INFO MEMORY STAT heapAllocated after test: 118961104
10:37:24 INFO - 4067 INFO TEST-OK | browser/devtools/performance/test/browser_perf_recordings-io-04.js | took 55324ms
No longer blocks: perf-tool-v2
This is getting hotter, can you check it out? Grabbing the other intermittents myself
Flags: needinfo?(vporof)
Assignee: nobody → vporof
Status: NEW → ASSIGNED
Flags: needinfo?(vporof)
Looks like this abruptly stopped.
Assignee: vporof → nobody
Status: ASSIGNED → NEW
Assignee: nobody → vporof
Status: NEW → ASSIGNED
Status: ASSIGNED → RESOLVED
Closed: 9 years ago
Resolution: --- → WORKSFORME
Product: Firefox → DevTools
You need to log in before you can comment on or make changes to this bug.