1546090 - Intermittent devtools/client/webreplay/mochitest/browser_rr_inspector-03.js | Uncaught exception - Protocol error (unknownError): Recording divergence in objectApply

Status: RESOLVED INCOMPLETE

(Core Graveyard :: Web Replay, defect, P5)

Description

[Treeherder Bug Filer]

#[markdown(off)]
Filed by: cbrindusan [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=241760278&repo=autoland

https://queue.taskcluster.net/v1/task/Zh8O3MFlT3CCspnP7-nXmw/runs/0/artifacts/public/logs/live_backing.log

10:59:20 INFO - TEST-START | devtools/client/webreplay/mochitest/browser_rr_inspector-03.js
10:59:20 INFO - GECKO(1977) | MIDDLEMAN 2088 /var/folders/36/wdng5grn7qd8wbdj1pqlvkjw00000w/T/TempRecording.1977.20
10:59:20 INFO - GECKO(1977) | RECORDING 2089 /var/folders/36/wdng5grn7qd8wbdj1pqlvkjw00000w/T/TempRecording.1977.20
10:59:27 INFO - GECKO(1977) | REPLAYING 2090 /var/folders/36/wdng5grn7qd8wbdj1pqlvkjw00000w/T/TempRecording.1977.20
10:59:27 INFO - GECKO(1977) | REPLAYING 2091 /var/folders/36/wdng5grn7qd8wbdj1pqlvkjw00000w/T/TempRecording.1977.20
10:59:30 INFO - GECKO(1977) | [ACTION] ADD_SOURCES - {"type":"ADD_SOURCES","cx":{"navigateCounter":0},"sources":[{"id":"sourceURL-http://example.com/browser/devtools/client/webreplay/mochitest/examples/doc_inspector_styles.html","url":"http://example.com/browser/devtools/client/webreplay/mochitest/examples/doc_inspector_styles.html","relativeUrl":"http://example.com/browser/devtools/client/webreplay/mochitest/examples/doc_inspector_styles.html","isPrettyPrinted":false,"sourceMapURL":null,"introductionUrl":null,"introductionType":"scriptElement","isBlackBoxed":false,"loadedState":"unloaded","isWasm":false,"isExtension":false}]}
10:59:30 INFO - GECKO(1977) | [ACTION] INSERT_SOURCE_ACTORS - {"type":"INSERT_SOURCE_ACTORS","items":[{"id":"server1.conn18.child1/source20","actor":"server1.conn18.child1/source20","thread":"server1.conn18.child1/context18","source":"sourceURL-http://example.com/browser/devtools/client/webreplay/mochitest/examples/doc_inspector_styles.html","isBlackBoxed":false,"sourceMapURL":null,"url":"http://example.com/browser/devtools/client/webreplay/mochitest/examples/doc_inspector_styles.html","introductionUrl":null,"introductionType":"scriptElement"}]}
10:59:30 INFO - GECKO(1977) | [ACTION] CONNECT - {"type":"CONNECT","mainThread":{"url":"about:blank","actor":"server1.conn18.child1/context18","type":-1},"canRewind":true}
10:59:30 INFO - GECKO(1977) | [ACTION] ADD_SOURCES - {"type":"ADD_SOURCES","cx":{"navigateCounter":0},"sources":[]}
10:59:30 INFO - GECKO(1977) | [ACTION] INSERT_SOURCE_ACTORS - {"type":"INSERT_SOURCE_ACTORS","items":[]}
10:59:30 INFO - GECKO(1977) | [ACTION] SET_EXPANDED_STATE - {"type":"SET_EXPANDED_STATE","thread":"server1.conn18.child1/context18","expanded":{}}
10:59:30 INFO - GECKO(1977) | [ACTION] SET_ORIENTATION - {"type":"SET_ORIENTATION","orientation":"horizontal"}
10:59:31 INFO - GECKO(1977) | [ACTION] PAUSED - {"type":"PAUSED","thread":"server1.conn18.child1/context18","why":{"type":"interrupted","onNext":true},"frames":[],"loadedObjects":[],"pauseInfo":{"why":{"type":"interrupted","onNext":true}},"scopes":[]}
10:59:31 INFO - GECKO(1977) | console.log: "Please file an issue: reasonType=" "interrupted"
10:59:31 INFO - GECKO(1977) | [ACTION] MAP_FRAMES - {}
10:59:31 INFO - GECKO(1977) | [ACTION] EVALUATE_EXPRESSIONS - {"type":"EVALUATE_EXPRESSIONS","cx":{"navigateCounter":0,"thread":"server1.conn18.child1/context18","isPaused":true,"pauseCounter":1},"inputs":[],"results":[]}
10:59:31 INFO - GECKO(1977) | [ACTION] RESUME - {"type":"RESUME","thread":"server1.conn18.child1/context18","wasStepping":false}
10:59:31 INFO - GECKO(1977) | [ACTION] EVALUATE_EXPRESSIONS - {"type":"EVALUATE_EXPRESSIONS","cx":{"navigateCounter":0,"thread":"server1.conn18.child1/context18","isPaused":false,"pauseCounter":2},"inputs":[],"results":[]}
10:59:32 INFO - GECKO(1977) | [ACTION] PAUSED - {"type":"PAUSED","thread":"server1.conn18.child1/context18","why":{"type":"interrupted","onNext":true},"frames":[],"loadedObjects":[],"pauseInfo":{"why":{"type":"interrupted","onNext":true}},"scopes":[]}
10:59:32 INFO - GECKO(1977) | console.log: "Please file an issue: reasonType=" "interrupted"
10:59:32 INFO - GECKO(1977) | [ACTION] MAP_FRAMES - {}
10:59:32 INFO - GECKO(1977) | [ACTION] EVALUATE_EXPRESSIONS - {"type":"EVALUATE_EXPRESSIONS","cx":{"navigateCounter":0,"thread":"server1.conn18.child1/context18","isPaused":true,"pauseCounter":3},"inputs":[],"results":[]}
10:59:34 INFO - GECKO(1977) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
10:59:34 INFO - GECKO(1977) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
10:59:34 INFO - GECKO(1977) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
10:59:34 INFO - GECKO(1977) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
10:59:36 INFO - GECKO(1977) | [ACTION] RESUME - {"type":"RESUME","thread":"server1.conn18.child1/context18","wasStepping":false}
10:59:36 INFO - GECKO(1977) | console.error: "navigated; resolution of _defaultNode aborted"
10:59:36 INFO - GECKO(1977) | [ACTION] EVALUATE_EXPRESSIONS - {"type":"EVALUATE_EXPRESSIONS","cx":{"navigateCounter":0,"thread":"server1.conn18.child1/context18","isPaused":false,"pauseCounter":4},"inputs":[],"results":[]}
10:59:44 INFO - GECKO(1977) | [ACTION] SET_ORIENTATION - {"type":"SET_ORIENTATION","orientation":"horizontal"}
10:59:44 INFO - GECKO(1977) | [ACTION] PAUSED - {"type":"PAUSED","thread":"server1.conn18.child1/context18","why":{"type":"breakpoint","actors":[null]},"frames":[{"id":"server1.conn18.child1/frame44","location":{"sourceId":"sourceURL-http://example.com/browser/devtools/client/webreplay/mochitest/examples/doc_inspector_styles.html","line":11,"column":2},"displayName":"foo"}],"selectedFrameId":"server1.conn18.child1/frame44","loadedObjects":[],"pauseInfo":{"why":{"type":"breakpoint","actors":[null]}},"scopes":[]}
10:59:44 INFO - GECKO(1977) | [ACTION] LOAD_SOURCE_TEXT [start] - {"type":"LOAD_SOURCE_TEXT","sourceId":"sourceURL-http://example.com/browser/devtools/client/webreplay/mochitest/examples/doc_inspector_styles.html","epoch":1,"seqId":"1","status":"start"}
10:59:45 INFO - GECKO(1977) | ReplayInspector Server Error: Recording divergence in objectApply Stack: ThrowError@resource://devtools/server/actors/replay/inspector.js:475:17
10:59:45 INFO - GECKO(1977) | apply@resource://devtools/server/actors/replay/inspector.js:380:5
10:59:45 INFO - GECKO(1977) | querySelector@resource://devtools/server/actors/inspector/walker.js:1010:35
10:59:45 INFO - GECKO(1977) | handler@resource://devtools/shared/protocol.js:1189:37
10:59:45 INFO - GECKO(1977) | onPacket@resource://devtools/server/main.js:1291:58
10:59:45 INFO - GECKO(1977) | receiveMessage@resource://devtools/shared/transport/child-transport.js:66:16
10:59:45 INFO - GECKO(1977) | enter@resource://devtools/server/actors/utils/event-loop.js:118:18
10:59:45 INFO - GECKO(1977) | _pushThreadPause@resource://devtools/server/actors/thread.js:189:15
10:59:45 INFO - GECKO(1977) | _pauseAndRespond@resource://devtools/server/actors/thread.js:553:12
10:59:45 INFO - GECKO(1977) | hit@resource://devtools/server/actors/breakpoint.js:253:29
10:59:45 INFO - GECKO(1977) | setBreakpoint/<@resource://devtools/server/actors/replay/debugger.js:761:46
10:59:45 INFO - GECKO(1977) | _performPause@resource://devtools/server/actors/replay/debugger.js:262:11
10:59:45 INFO - GECKO(1977) | console.error: "Error while calling actor 'domwalker's method 'querySelector'" "Recording divergence in objectApply"
10:59:45 INFO - GECKO(1977) | console.error: "ThrowError@resource://devtools/server/actors/replay/inspector.js:475:17\napply@resource://devtools/server/actors/replay/inspector.js:380:5\nquerySelector@resource://devtools/server/actors/inspector/walker.js:1010:35\nhandler@resource://devtools/shared/protocol.js:1189:37\nonPacket@resource://devtools/server/main.js:1291:58\nreceiveMessage@resource://devtools/shared/transport/child-transport.js:66:16\nenter@resource://devtools/server/actors/utils/event-loop.js:118:18\n_pushThreadPause@resource://devtools/server/actors/thread.js:189:15\n_pauseAndRespond@resource://devtools/server/actors/thread.js:553:12\nhit@resource://devtools/server/actors/breakpoint.js:253:29\nsetBreakpoint/<@resource://devtools/server/actors/replay/debugger.js:761:46\n_performPause@resource://devtools/server/actors/replay/debugger.js:262:11\n"
10:59:45 INFO - GECKO(1977) | console.error: "Protocol error (unknownError): Recording divergence in objectApply"
10:59:46 INFO - GECKO(1977) | ReplayInspector Server Error: Recording divergence in objectApply Stack: ThrowError@resource://devtools/server/actors/replay/inspector.js:475:17
10:59:46 INFO - GECKO(1977) | apply@resource://devtools/server/actors/replay/inspector.js:380:5
10:59:46 INFO - GECKO(1977) | querySelector@resource://devtools/server/actors/inspector/walker.js:1010:35
10:59:46 INFO - GECKO(1977) | handler@resource://devtools/shared/protocol.js:1189:37
10:59:46 INFO - GECKO(1977) | onPacket@resource://devtools/server/main.js:1291:58
10:59:46 INFO - GECKO(1977) | receiveMessage@resource://devtools/shared/transport/child-transport.js:66:16
10:59:46 INFO - GECKO(1977) | enter@resource://devtools/server/actors/utils/event-loop.js:118:18
10:59:46 INFO - GECKO(1977) | _pushThreadPause@resource://devtools/server/actors/thread.js:189:15
10:59:46 INFO - GECKO(1977) | _pauseAndRespond@resource://devtools/server/actors/thread.js:553:12
10:59:46 INFO - GECKO(1977) | hit@resource://devtools/server/actors/breakpoint.js:253:29
10:59:46 INFO - GECKO(1977) | setBreakpoint/<@resource://devtools/server/actors/replay/debugger.js:761:46
10:59:46 INFO - GECKO(1977) | _performPause@resource://devtools/server/actors/replay/debugger.js:262:11
10:59:46 INFO - GECKO(1977) | console.error: "Error while calling actor 'domwalker's method 'querySelector'" "Recording divergence in objectApply"
10:59:46 INFO - GECKO(1977) | console.error: "ThrowError@resource://devtools/server/actors/replay/inspector.js:475:17\napply@resource://devtools/server/actors/replay/inspector.js:380:5\nquerySelector@resource://devtools/server/actors/inspector/walker.js:1010:35\nhandler@resource://devtools/shared/protocol.js:1189:37\nonPacket@resource://devtools/server/main.js:1291:58\nreceiveMessage@resource://devtools/shared/transport/child-transport.js:66:16\nenter@resource://devtools/server/actors/utils/event-loop.js:118:18\n_pushThreadPause@resource://devtools/server/actors/thread.js:189:15\n_pauseAndRespond@resource://devtools/server/actors/thread.js:553:12\nhit@resource://devtools/server/actors/breakpoint.js:253:29\nsetBreakpoint/<@resource://devtools/server/actors/replay/debugger.js:761:46\n_performPause@resource://devtools/server/actors/replay/debugger.js:262:11\n"
10:59:46 INFO - TEST-INFO | started process screencapture
10:59:47 INFO - TEST-INFO | screencapture: exit 0
10:59:47 INFO - Buffered messages logged at 10:59:20
10:59:47 INFO - Entering test bound
10:59:47 INFO - Waiting for event: 'RecordingFinished' on [object ParentProcessMessageManager].
10:59:47 INFO - Buffered messages logged at 10:59:27
10:59:47 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.com/browser/devtools/client/webreplay/mochitest/examples/doc_inspector_styles.html" line: 0}]
10:59:47 INFO - Got event: 'RecordingFinished' on [object ParentProcessMessageManager].
10:59:47 INFO - Buffered messages logged at 10:59:30
10:59:47 INFO - Console message: [JavaScript Warning: "Removed unsafe attribute. Element: svg. Attribute: xmlns." {file: "resource://devtools/client/debugger/src/components/Editor/Breakpoint.js" line: 29}]
10:59:47 INFO - Console message: [JavaScript Warning: "Removed unsafe attribute. Element: svg. Attribute: xmlns." {file: "resource://devtools/client/debugger/src/components/Editor/Breakpoint.js" line: 29}]
10:59:47 INFO - Console message: [JavaScript Warning: "Removed unsafe attribute. Element: svg. Attribute: xmlns." {file: "resource://devtools/client/debugger/src/components/Editor/Breakpoint.js" line: 29}]
10:59:47 INFO - Console message: [JavaScript Warning: "Removed unsafe attribute. Element: svg. Attribute: xmlns." {file: "resource://devtools/client/debugger/src/components/Editor/Breakpoint.js" line: 29}]
10:59:47 INFO - Console message: [JavaScript Warning: "Removed unsafe attribute. Element: svg. Attribute: xmlns." {file: "resource://devtools/client/debugger/src/components/Editor/ColumnBreakpoint.js" line: 26}]
10:59:47 INFO - Console message: [JavaScript Warning: "Removed unsafe attribute. Element: svg. Attribute: xmlns." {file: "resource://devtools/client/debugger/src/components/Editor/ColumnBreakpoint.js" line: 26}]
10:59:47 INFO - Console message: [JavaScript Warning: "Removed unsafe attribute. Element: svg. Attribute: xmlns." {file: "resource://devtools/client/debugger/src/components/Editor/ColumnBreakpoint.js" line: 26}]
10:59:47 INFO - Console message: [JavaScript Warning: "Removed unsafe attribute. Element: svg. Attribute: xmlns." {file: "resource://devtools/client/debugger/src/components/Editor/ColumnBreakpoint.js" line: 26}]
10:59:47 INFO - Buffered messages logged at 10:59:31
10:59:47 INFO - Opening the inspector
10:59:47 INFO - Opening the toolbox
10:59:47 INFO - Buffered messages logged at 10:59:34
10:59:47 INFO - Console message: [JavaScript Error: "Polling for changes failed: Unexpected content-type "text/html;charset=utf-8"." {file: "resource://services-settings/remote-settings.js" line: 208}]
10:59:47 INFO - remoteSettingsFunction/remoteSettings.pollChanges@resource://services-settings/remote-settings.js:208:13
10:59:47 INFO - async*notify@resource://services-settings/RemoteSettingsComponents.jsm:22:20
10:59:47 INFO - TM_notify/<@resource://gre/modules/UpdateTimerManager.jsm:192:48
10:59:47 INFO - TM_notify@resource://gre/modules/UpdateTimerManager.jsm:239:7
10:59:47 INFO -
10:59:47 INFO - Toolbox opened and focused
10:59:47 INFO - Selecting the computedview sidebar
10:59:47 INFO - Selecting the node for 'body'
10:59:47 INFO - Buffered messages logged at 10:59:35
10:59:47 INFO - TEST-PASS | devtools/client/webreplay/mochitest/browser_rr_inspector-03.js | undefined assertion name -
10:59:47 INFO - TEST-PASS | devtools/client/webreplay/mochitest/browser_rr_inspector-03.js | undefined assertion name -
10:59:47 INFO - Selecting the node for '#maindiv'
10:59:47 INFO - Buffered messages logged at 10:59:36
10:59:47 INFO - TEST-PASS | devtools/client/webreplay/mochitest/browser_rr_inspector-03.js | undefined assertion name -
10:59:47 INFO - TEST-PASS | devtools/client/webreplay/mochitest/browser_rr_inspector-03.js | undefined assertion name -
10:59:47 INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 245}]
10:59:47 INFO - TEST-PASS | devtools/client/webreplay/mochitest/browser_rr_inspector-03.js | Got one source -
10:59:47 INFO - TEST-PASS | devtools/client/webreplay/mochitest/browser_rr_inspector-03.js | Source is doc_inspector_styles.html -
10:59:47 INFO - Buffered messages logged at 10:59:44
10:59:47 INFO - Selecting the node for '#maindiv'
10:59:47 INFO - Buffered messages finished
10:59:47 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webreplay/mochitest/browser_rr_inspector-03.js | Uncaught exception - Protocol error (unknownError): Recording divergence in objectApply

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• osx-10-10-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1546090&startday=2019-04-22&endday=2019-04-28&tree=all

Comment 2

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 3

[Andreea Pavel [:apavel]]

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=258270468&repo=autoland&lineNumber=1469

[task 2019-07-25T10:50:30.363Z] 10:50:30 INFO - TEST-PASS | devtools/client/webreplay/mochitest/browser_rr_inspector-03.js | Got one source -
[task 2019-07-25T10:50:30.363Z] 10:50:30 INFO - TEST-PASS | devtools/client/webreplay/mochitest/browser_rr_inspector-03.js | Source is doc_inspector_styles.html -
[task 2019-07-25T10:50:30.363Z] 10:50:30 INFO - Selecting the node for '#maindiv'
[task 2019-07-25T10:50:30.364Z] 10:50:30 INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 253}]
[task 2019-07-25T10:50:30.364Z] 10:50:30 INFO - Buffered messages logged at 10:50:29
[task 2019-07-25T10:50:30.364Z] 10:50:30 INFO - TEST-PASS | devtools/client/webreplay/mochitest/browser_rr_inspector-03.js | Paused at line 11 expected 11 -
[task 2019-07-25T10:50:30.364Z] 10:50:30 INFO - Buffered messages finished
[task 2019-07-25T10:50:30.365Z] 10:50:30 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webreplay/mochitest/browser_rr_inspector-03.js | Uncaught exception - Protocol error (unknownError): Recording divergence in objectApply
[task 2019-07-25T10:50:30.365Z] 10:50:30 INFO - Leaving test bound
[task 2019-07-25T10:50:31.694Z] 10:50:31 INFO - Removing tab.
[task 2019-07-25T10:50:31.694Z] 10:50:31 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2019-07-25T10:50:31.709Z] 10:50:31 INFO - Got event: 'TabClose' on [object XULElement].
[task 2019-07-25T10:50:31.710Z] 10:50:31 INFO - Tab removed and finished closing
[task 2019-07-25T10:50:31.755Z] 10:50:31 INFO - GECKO(1684) | MEMORY STAT | vsize 7895MB | residentFast 562MB | heapAllocated 197MB
[task 2019-07-25T10:50:31.755Z] 10:50:31 INFO - TEST-OK | devtools/client/webreplay/mochitest/browser_rr_inspector-03.js | took 10400ms

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• macosx1014-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1546090&startday=2019-07-22&endday=2019-07-28&tree=all

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1
• mozilla-central: 1

Platform breakdown:

• macosx1014-64-shippable: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1546090&startday=2019-08-12&endday=2019-08-18&tree=all

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• macosx1014-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1546090&startday=2019-08-19&endday=2019-08-25&tree=all

Comment 7

[Intermittent Failures Robot]

5 failures in 5047 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 4
• mozilla-central: 1

Platform breakdown:

• macosx1014-64-shippable: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1546090&startday=2019-08-26&endday=2019-09-01&tree=all

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• macosx1014-64-shippable: 2

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

Comment 9

[BugBot [:suhaib / :marco]]

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