[ZEPPELIN-2071] Very slow parsing of large %html output - ASF JIRA

XML

Word

Printable

JSON

Details

Type: Bug
Status: Open
Priority: Major
Resolution: Unresolved
Affects Version/s: 0.7.0
Fix Version/s: None
Component/s: front-end, Interpreters
Labels:
- output
- parseoutputformat
Environment:

Ubuntu 16.04, 12 GB RAM

Description

The problem: open a notebook and run the following cell, which creates a string of length 100k and outputs it via %html in order to execute some javascript on it ~~(similar for other interpreters, e.g., %spark, %pyspark)~~

%python
print("%html ready")
s = "X" * 100000
print("<script>js='" + s + "'; alert(js.length)</script>")

The execution of this cell takes more than 1 minute!
Since running the same html directly in a browser takes only milliseconds, I believe that a bug causes this huge performance drop (for example erroneous event polling from some queue or so, see below...).

Why this is a major issue: In contrast to Jupyter, Zeppelin allows for an almost seamless integration of spark, python, scala, etc. into the front-end's JavaScript context. This allows to easily visualize results rendered by JavaScript librarys such as Plotly, D3, Vis, Cytoscape, crossfilter, etc., by outputting the corresponding JavaScript incl. the stringified data via print("%html <script>" + content + "</script>").
Indeed, this works very well in practice with all these libraries.
However, it is a total dealbreaker if we cannot pass more than just some hundreds of bytes.

Logfile: Please see some relevant DEBUG log output attached (from logs/zeppelin-user-host.log). Just a guess because of the repeated IDLE messages: probably the long output string is polled in small portions, and some event handler falls into some idle-delay in between these portions?

(...) thousands of more lines before...
INFO [2017-02-07 09:56:01,928] (

Unknown macro: {pool-2-thread-2}
Paragraph.java[jobRun]:344) - run paragraph 20170206-210437_343934373 using null org.apache.zeppelin.interpreter.LazyOpenInterpreter@79f86c19
DEBUG [2017-02-07 09:56:01,928] (

Paragraph.java[jobRun]:380) - RUN : print("%html start...") # start html output
s = "X"*100000 # create some string of length 100k
print("ready for transfer..."); # notify that done.
print("<div class='hidden'>" + s + "'</div>") # copy the string to javascript and compute its length - takes more than a minute!
print("done")
DEBUG [2017-02-07 09:56:01,928] (

Unknown macro: {pool-2-thread-2}
InterpreterSetting.java[getInterpreterProcessKey]:143) - getInterpreterProcessKey: shared_process for InterpreterSetting Id: 2C7KPWF4Y, Name: python3
DEBUG [2017-02-07 09:56:01,928] (

InterpreterSetting.java[getInterpreterProcessKey]:143) - getInterpreterProcessKey: shared_process for InterpreterSetting Id: 2C7KPWF4Y, Name: python3
DEBUG [2017-02-07 09:56:01,928] (

Unknown macro: {pool-2-thread-2}

RemoteInterpreter.java[interpret]:297) - st:
print("%html start...") # start html output
s = "X"*100000 # create some string of length 100k
print("ready for transfer..."); # notify that done.
print("<div class='hidden'>" + s + "'</div>") # copy the string to javascript and compute its length - takes more than a minute!
print("done")
DEBUG [2017-02-07 09:56:01,930] (

Unknown macro: {Thread-39}
NotebookServer.java[broadcast]:472) - SEND >> PARAGRAPH
DEBUG [2017-02-07 09:56:01,931] (

WebSocketRemoteEndpoint.java[sendString]:385) - sendString with HeapByteBuffer@2c27cd44[p=0,l=978,c=978,r=978]={<<<

Unknown macro: {"op"}

>>>}
DEBUG [2017-02-07 09:56:01,935] (

Unknown macro: {Finalizer}
LoggingManagedHttpClientConnection.java[close]:79) - http-outgoing-4: Close connection
DEBUG [2017-02-07 09:56:01,935] (

PoolingHttpClientConnectionManager.java[shutdown]:357) - Connection manager shut down
DEBUG [2017-02-07 09:56:01,935] (

Unknown macro: {Finalizer}
PoolingHttpClientConnectionManager.java[shutdown]:351) - Connection manager is shutting down
DEBUG [2017-02-07 09:56:01,935] (

LoggingManagedHttpClientConnection.java[close]:79) - http-outgoing-5: Close connection
DEBUG [2017-02-07 09:56:01,939] (

Unknown macro: {Thread-39}
ExtensionStack.java[outgoingFrame]:288) - Queuing TEXT[len=978,fin=true,rsv=...,masked=false]
DEBUG [2017-02-07 09:56:01,939] (

ExtensionStack.java[process]:388) - Processing TEXT[len=978,fin=true,rsv=...,masked=false]
DEBUG [2017-02-07 09:56:01,939] (

Unknown macro: {Thread-39}
AbstractWebSocketConnection.java[outgoingFrame]:564) - outgoingFrame(TEXT[len=978,fin=true,rsv=...,masked=false], org.eclipse.jetty.websocket.common.extensions.ExtensionStack$Flusher@7d2522f0[PROCESSING])
DEBUG [2017-02-07 09:56:01,940] (

FrameFlusher.java[enqueue]:378) - Flusher[queueSize=1,aggregateSize=0,failure=null] queued FrameEntry[TEXT[len=978,fin=true,rsv=...,masked=false],org.eclipse.jetty.websocket.common.extensions.ExtensionStack$Flusher@7d2522f0[PROCESSING],AUTO,null]
DEBUG [2017-02-07 09:56:01,940] (

Unknown macro: {Thread-39}
FrameFlusher.java[process]:197) - Flusher[queueSize=0,aggregateSize=0,failure=null] processing 1 entries: [FrameEntry[TEXT[len=978,fin=true,rsv=...,masked=false],org.eclipse.jetty.websocket.common.extensions.ExtensionStack$Flusher@7d2522f0[PROCESSING],AUTO,null]]
DEBUG [2017-02-07 09:56:01,940] (

FrameFlusher.java[batch]:67) - Flusher[queueSize=0,aggregateSize=0,failure=null] acquired aggregate buffer java.nio.DirectByteBuffer[pos=0 lim=0 cap=32768]
DEBUG [2017-02-07 09:56:01,940] (

Unknown macro: {Thread-39}
FrameFlusher.java[batch]:86) - Flusher[queueSize=0,aggregateSize=0,failure=null] aggregated 1 frames: [FrameEntry[TEXT[len=0,fin=true,rsv=...,masked=false],org.eclipse.jetty.websocket.common.extensions.ExtensionStack$Flusher@7d2522f0[PROCESSING],AUTO,null]]
DEBUG [2017-02-07 09:56:01,940] (

FrameFlusher.java[process]:197) - Flusher[queueSize=0,aggregateSize=0,failure=null] processing 0 entries: []
DEBUG [2017-02-07 09:56:01,940] (

Unknown macro: {Thread-39}
FrameFlusher.java[process]:210) - Flusher[queueSize=0,aggregateSize=0,failure=null] auto flushing
DEBUG [2017-02-07 09:56:01,940] (

FrameFlusher.java[flush]:118) - Flusher[queueSize=0,aggregateSize=0,failure=null] flushing aggregate java.nio.DirectByteBuffer[pos=0 lim=982 cap=32768]
DEBUG [2017-02-07 09:56:01,940] (

Unknown macro: {Thread-39}
FrameFlusher.java[flush]:141) - Flusher[queueSize=0,aggregateSize=0,failure=null] flushing 0 frames: []
DEBUG [2017-02-07 09:56:01,940] (

WriteFlusher.java[write]:295) - write: WriteFlusher@296a7050

Unknown macro: {IDLE}
[DirectByteBuffer@5f97bfd0[p=0,l=982,c=32768,r=982]={<<<\x81~\x03\xD2

Unknown macro: {"op"}

>>>"sql","class":"or...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
DEBUG [2017-02-07 09:56:01,940] (

Unknown macro: {Thread-39}
WriteFlusher.java[updateState]:118) - update WriteFlusher@296a7050

Unknown macro: {WRITING}

:IDLE-->WRITING
DEBUG [2017-02-07 09:56:01,941] (

ChannelEndPoint.java[flush]:188) - flushed 982 SelectChannelEndPoint@646c7187

Unknown macro: {/0}

Unknown macro: {io=1,kio=1,kro=1}

DEBUG [2017-02-07 09:56:01,941] (

Unknown macro: {Thread-39}

WriteFlusher.java[updateState]:118) - update WriteFlusher@296a7050

:WRITING-->IDLE
DEBUG [2017-02-07 09:56:01,941] (

Unknown macro: {Thread-39}
FrameFlusher.java[process]:197) - Flusher[queueSize=0,aggregateSize=982,failure=null] processing 0 entries: []
DEBUG [2017-02-07 09:56:01,941] (

FrameFlusher.java[process]:210) - Flusher[queueSize=0,aggregateSize=982,failure=null] auto flushing
DEBUG [2017-02-07 09:56:01,941] (

Unknown macro: {Thread-39}
FrameFlusher.java[flush]:141) - Flusher[queueSize=0,aggregateSize=982,failure=null] flushing 0 frames: []
DEBUG [2017-02-07 09:56:01,941] (

ExtensionStack.java[process]:384) - Entering IDLE
DEBUG [2017-02-07 09:56:01,941] (

Unknown macro: {Thread-39}

RemoteInterpreterEventPoller.java[run]:251) - Event from remote process OUTPUT_UPDATE_ALL
DEBUG [2017-02-07 09:56:01,949] (

Unknown macro: {Finalizer}
LoggingManagedHttpClientConnection.java[close]:79) - http-outgoing-5: Close connection
DEBUG [2017-02-07 09:56:01,950] (

PoolingHttpClientConnectionManager.java[shutdown]:357) - Connection manager shut down
DEBUG [2017-02-07 09:56:01,950] (

Unknown macro: {Finalizer}
PoolingHttpClientConnectionManager.java[shutdown]:351) - Connection manager is shutting down
DEBUG [2017-02-07 09:56:01,950] (

LoggingManagedHttpClientConnection.java[close]:79) - http-outgoing-6: Close connection
DEBUG [2017-02-07 09:56:01,951] (

Unknown macro: {Finalizer}
LoggingManagedHttpClientConnection.java[close]:79) - http-outgoing-6: Close connection
DEBUG [2017-02-07 09:56:01,951] (

PoolingHttpClientConnectionManager.java[shutdown]:357) - Connection manager shut down
DEBUG [2017-02-07 09:56:01,951] (

Unknown macro: {Finalizer}
PoolingHttpClientConnectionManager.java[shutdown]:351) - Connection manager is shutting down
DEBUG [2017-02-07 09:56:01,951] (

LoggingManagedHttpClientConnection.java[close]:79) - http-outgoing-7: Close connection
DEBUG [2017-02-07 09:56:01,951] (

Unknown macro: {Finalizer}
LoggingManagedHttpClientConnection.java[close]:79) - http-outgoing-7: Close connection
DEBUG [2017-02-07 09:56:01,951] (

PoolingHttpClientConnectionManager.java[shutdown]:357) - Connection manager shut down
DEBUG [2017-02-07 09:56:01,951] (

Unknown macro: {Finalizer}
PoolingHttpClientConnectionManager.java[shutdown]:351) - Connection manager is shutting down
DEBUG [2017-02-07 09:56:01,952] (

LoggingManagedHttpClientConnection.java[close]:79) - http-outgoing-8: Close connection
DEBUG [2017-02-07 09:56:01,953] (

Unknown macro: {Finalizer}
LoggingManagedHttpClientConnection.java[close]:79) - http-outgoing-8: Close connection
DEBUG [2017-02-07 09:56:01,953] (

PoolingHttpClientConnectionManager.java[shutdown]:357) - Connection manager shut down
DEBUG [2017-02-07 09:56:02,047] (

Unknown macro: {Thread-44}
NotebookServer.java[broadcast]:472) - SEND >> PARAGRAPH
DEBUG [2017-02-07 09:56:02,048] (

WebSocketRemoteEndpoint.java[sendString]:385) - sendString with HeapByteBuffer@6402a789[p=0,l=978,c=978,r=978]={<<<

Unknown macro: {"op"}

>>>}
DEBUG [2017-02-07 09:56:02,048] (

Unknown macro: {Thread-44}
ExtensionStack.java[outgoingFrame]:288) - Queuing TEXT[len=978,fin=true,rsv=...,masked=false]
DEBUG [2017-02-07 09:56:02,048] (

ExtensionStack.java[process]:388) - Processing TEXT[len=978,fin=true,rsv=...,masked=false]
DEBUG [2017-02-07 09:56:02,048] (

Unknown macro: {Thread-44}
AbstractWebSocketConnection.java[outgoingFrame]:564) - outgoingFrame(TEXT[len=978,fin=true,rsv=...,masked=false], org.eclipse.jetty.websocket.common.extensions.ExtensionStack$Flusher@7d2522f0[PROCESSING])
DEBUG [2017-02-07 09:56:02,048] (

FrameFlusher.java[enqueue]:378) - Flusher[queueSize=1,aggregateSize=0,failure=null] queued FrameEntry[TEXT[len=978,fin=true,rsv=...,masked=false],org.eclipse.jetty.websocket.common.extensions.ExtensionStack$Flusher@7d2522f0[PROCESSING],AUTO,null]
DEBUG [2017-02-07 09:56:02,048] (

Unknown macro: {Thread-44}
FrameFlusher.java[process]:197) - Flusher[queueSize=0,aggregateSize=0,failure=null] processing 1 entries: [FrameEntry[TEXT[len=978,fin=true,rsv=...,masked=false],org.eclipse.jetty.websocket.common.extensions.ExtensionStack$Flusher@7d2522f0[PROCESSING],AUTO,null]]
DEBUG [2017-02-07 09:56:02,048] (

FrameFlusher.java[batch]:67) - Flusher[queueSize=0,aggregateSize=0,failure=null] acquired aggregate buffer java.nio.DirectByteBuffer[pos=0 lim=0 cap=32768]
DEBUG [2017-02-07 09:56:02,048] (

Unknown macro: {Thread-44}
FrameFlusher.java[batch]:86) - Flusher[queueSize=0,aggregateSize=0,failure=null] aggregated 1 frames: [FrameEntry[TEXT[len=0,fin=true,rsv=...,masked=false],org.eclipse.jetty.websocket.common.extensions.ExtensionStack$Flusher@7d2522f0[PROCESSING],AUTO,null]]
DEBUG [2017-02-07 09:56:02,048] (

FrameFlusher.java[process]:197) - Flusher[queueSize=0,aggregateSize=0,failure=null] processing 0 entries: []
DEBUG [2017-02-07 09:56:02,049] (

Unknown macro: {Thread-44}
FrameFlusher.java[process]:210) - Flusher[queueSize=0,aggregateSize=0,failure=null] auto flushing
DEBUG [2017-02-07 09:56:02,049] (

FrameFlusher.java[flush]:118) - Flusher[queueSize=0,aggregateSize=0,failure=null] flushing aggregate java.nio.DirectByteBuffer[pos=0 lim=982 cap=32768]
DEBUG [2017-02-07 09:56:02,049] (

Unknown macro: {Thread-44}
FrameFlusher.java[flush]:141) - Flusher[queueSize=0,aggregateSize=0,failure=null] flushing 0 frames: []
DEBUG [2017-02-07 09:56:02,049] (

WriteFlusher.java[write]:295) - write: WriteFlusher@296a7050

Unknown macro: {IDLE}
[DirectByteBuffer@5f97bfd0[p=0,l=982,c=32768,r=982]={<<<\x81~\x03\xD2

Unknown macro: {"op"}

>>>"sql","class":"or...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
DEBUG [2017-02-07 09:56:02,049] (

Unknown macro: {Thread-44}
WriteFlusher.java[updateState]:118) - update WriteFlusher@296a7050

Unknown macro: {WRITING}

:IDLE-->WRITING
DEBUG [2017-02-07 09:56:02,049] (

ChannelEndPoint.java[flush]:188) - flushed 982 SelectChannelEndPoint@646c7187

Unknown macro: {/0}

Unknown macro: {io=1,kio=1,kro=1}

DEBUG [2017-02-07 09:56:02,049] (

Unknown macro: {Thread-44}

WriteFlusher.java[updateState]:118) - update WriteFlusher@296a7050

:WRITING-->IDLE
DEBUG [2017-02-07 09:56:02,049] (

Unknown macro: {Thread-44}
FrameFlusher.java[process]:197) - Flusher[queueSize=0,aggregateSize=982,failure=null] processing 0 entries: []
DEBUG [2017-02-07 09:56:02,050] (

FrameFlusher.java[process]:210) - Flusher[queueSize=0,aggregateSize=982,failure=null] auto flushing
DEBUG [2017-02-07 09:56:02,050] (

Unknown macro: {Thread-44}
FrameFlusher.java[flush]:141) - Flusher[queueSize=0,aggregateSize=982,failure=null] flushing 0 frames: []
DEBUG [2017-02-07 09:56:02,050] (

ExtensionStack.java[process]:384) - Entering IDLE
DEBUG [2017-02-07 09:56:02,453] (

Unknown macro: {Thread-45}
InterpreterSetting.java[getInterpreterProcessKey]:143) - getInterpreterProcessKey: shared_process for InterpreterSetting Id: 2C7KPWF4Y, Name: python3
DEBUG [2017-02-07 09:56:02,453] (

InterpreterFactory.java[getInterpreterSessionKey]:1239) - Interpreter session key: shared_session, for note: 2C7TKVQEW, user: admin, InterpreterSetting Name: python3
DEBUG [2017-02-07 09:56:02,453] (

Unknown macro: {Thread-45}
InterpreterSetting.java[getInterpreterProcessKey]:143) - getInterpreterProcessKey: shared_process for InterpreterSetting Id: 2C7KPWF4Y, Name: python3
DEBUG [2017-02-07 09:56:02,453] (

InterpreterSetting.java[getInterpreterProcessKey]:143) - getInterpreterProcessKey: shared_process for InterpreterSetting Id: 2C7KPWF4Y, Name: python3
DEBUG [2017-02-07 09:56:02,481] (

Unknown macro: {Thread-45}
InterpreterSetting.java[getInterpreterProcessKey]:143) - getInterpreterProcessKey: shared_process for InterpreterSetting Id: 2C7KPWF4Y, Name: python3
DEBUG [2017-02-07 09:56:02,481] (

InterpreterFactory.java[getInterpreterSessionKey]:1239) - Interpreter session key: shared_session, for note: 2C7TKVQEW, user: admin, InterpreterSetting Name: python3
DEBUG [2017-02-07 09:56:02,481] (

Unknown macro: {Thread-45}
InterpreterSetting.java[getInterpreterProcessKey]:143) - getInterpreterProcessKey: shared_process for InterpreterSetting Id: 2C7KPWF4Y, Name: python3
DEBUG [2017-02-07 09:56:02,482] (

InterpreterSetting.java[getInterpreterProcessKey]:143) - getInterpreterProcessKey: shared_process for InterpreterSetting Id: 2C7KPWF4Y, Name: python3
DEBUG [2017-02-07 09:56:02,486] (

Unknown macro: {Thread-45}
NotebookServer.java[broadcast]:472) - SEND >> PROGRESS
DEBUG [2017-02-07 09:56:02,486] (

WebSocketRemoteEndpoint.java[sendString]:385) - sendString with HeapByteBuffer@ed7f6db[p=0,l=128,c=128,r=128]={<<<

Unknown macro: {"op"}

>>>}
DEBUG [2017-02-07 09:56:02,486] (

Unknown macro: {Thread-45}
ExtensionStack.java[outgoingFrame]:288) - Queuing TEXT[len=128,fin=true,rsv=...,masked=false]
DEBUG [2017-02-07 09:56:02,486] (

ExtensionStack.java[process]:388) - Processing TEXT[len=128,fin=true,rsv=...,masked=false]
DEBUG [2017-02-07 09:56:02,486] (

Unknown macro: {Thread-45}
AbstractWebSocketConnection.java[outgoingFrame]:564) - outgoingFrame(TEXT[len=128,fin=true,rsv=...,masked=false], org.eclipse.jetty.websocket.common.extensions.ExtensionStack$Flusher@7d2522f0[PROCESSING])
DEBUG [2017-02-07 09:56:02,486] (

FrameFlusher.java[enqueue]:378) - Flusher[queueSize=1,aggregateSize=0,failure=null] queued FrameEntry[TEXT[len=128,fin=true,rsv=...,masked=false],org.eclipse.jetty.websocket.common.extensions.ExtensionStack$Flusher@7d2522f0[PROCESSING],AUTO,null]
DEBUG [2017-02-07 09:56:02,486] (

Unknown macro: {Thread-45}
FrameFlusher.java[process]:197) - Flusher[queueSize=0,aggregateSize=0,failure=null] processing 1 entries: [FrameEntry[TEXT[len=128,fin=true,rsv=...,masked=false],org.eclipse.jetty.websocket.common.extensions.ExtensionStack$Flusher@7d2522f0[PROCESSING],AUTO,null]]
DEBUG [2017-02-07 09:56:02,486] (

FrameFlusher.java[batch]:67) - Flusher[queueSize=0,aggregateSize=0,failure=null] acquired aggregate buffer java.nio.DirectByteBuffer[pos=0 lim=0 cap=32768]
DEBUG [2017-02-07 09:56:02,487] (

Unknown macro: {Thread-45}
FrameFlusher.java[batch]:86) - Flusher[queueSize=0,aggregateSize=0,failure=null] aggregated 1 frames: [FrameEntry[TEXT[len=0,fin=true,rsv=...,masked=false],org.eclipse.jetty.websocket.common.extensions.ExtensionStack$Flusher@7d2522f0[PROCESSING],AUTO,null]]
DEBUG [2017-02-07 09:56:02,487] (

FrameFlusher.java[process]:197) - Flusher[queueSize=0,aggregateSize=0,failure=null] processing 0 entries: []
DEBUG [2017-02-07 09:56:02,487] (

Unknown macro: {Thread-45}
FrameFlusher.java[process]:210) - Flusher[queueSize=0,aggregateSize=0,failure=null] auto flushing
DEBUG [2017-02-07 09:56:02,487] (

FrameFlusher.java[flush]:118) - Flusher[queueSize=0,aggregateSize=0,failure=null] flushing aggregate java.nio.DirectByteBuffer[pos=0 lim=132 cap=32768]
DEBUG [2017-02-07 09:56:02,487] (

Unknown macro: {Thread-45}
FrameFlusher.java[flush]:141) - Flusher[queueSize=0,aggregateSize=0,failure=null] flushing 0 frames: []
DEBUG [2017-02-07 09:56:02,487] (

WriteFlusher.java[write]:295) - write: WriteFlusher@296a7050

Unknown macro: {IDLE}
[DirectByteBuffer@5f97bfd0[p=0,l=132,c=32768,r=132]={<<<\x81~\x00\x80

Unknown macro: {"op"}

>>>e string of lengt...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
DEBUG [2017-02-07 09:56:02,487] (

Unknown macro: {Thread-45}
WriteFlusher.java[updateState]:118) - update WriteFlusher@296a7050

Unknown macro: {WRITING}

:IDLE-->WRITING
DEBUG [2017-02-07 09:56:02,487] (

ChannelEndPoint.java[flush]:188) - flushed 132 SelectChannelEndPoint@646c7187

Unknown macro: {/0}

Unknown macro: {io=1,kio=1,kro=1}

DEBUG [2017-02-07 09:56:02,487] (

Unknown macro: {Thread-45}

WriteFlusher.java[updateState]:118) - update WriteFlusher@296a7050

:WRITING-->IDLE
DEBUG [2017-02-07 09:56:02,487] (

Unknown macro: {Thread-45}
FrameFlusher.java[process]:197) - Flusher[queueSize=0,aggregateSize=132,failure=null] processing 0 entries: []
DEBUG [2017-02-07 09:56:02,487] (

FrameFlusher.java[process]:210) - Flusher[queueSize=0,aggregateSize=132,failure=null] auto flushing
DEBUG [2017-02-07 09:56:02,487] (

Unknown macro: {Thread-45}
FrameFlusher.java[flush]:141) - Flusher[queueSize=0,aggregateSize=132,failure=null] flushing 0 frames: []
DEBUG [2017-02-07 09:56:02,488] (

ExtensionStack.java[process]:384) - Entering IDLE
DEBUG [2017-02-07 09:56:02,988] (

Unknown macro: {Thread-45}
InterpreterSetting.java[getInterpreterProcessKey]:143) - getInterpreterProcessKey: shared_process for InterpreterSetting Id: 2C7KPWF4Y, Name: python3
DEBUG [2017-02-07 09:56:02,988] (

InterpreterFactory.java[getInterpreterSessionKey]:1239) - Interpreter session key: shared_session, for note: 2C7TKVQEW, user: admin, InterpreterSetting Name: python3
DEBUG [2017-02-07 09:56:02,989] (

Unknown macro: {Thread-45}
InterpreterSetting.java[getInterpreterProcessKey]:143) - getInterpreterProcessKey: shared_process for InterpreterSetting Id: 2C7KPWF4Y, Name: python3
DEBUG [2017-02-07 09:56:02,989] (

InterpreterSetting.java[getInterpreterProcessKey]:143) - getInterpreterProcessKey: shared_process for InterpreterSetting Id: 2C7KPWF4Y, Name: python3
DEBUG [2017-02-07 09:56:02,994] (

Unknown macro: {Thread-45}
InterpreterSetting.java[getInterpreterProcessKey]:143) - getInterpreterProcessKey: shared_process for InterpreterSetting Id: 2C7KPWF4Y, Name: python3
DEBUG [2017-02-07 09:56:02,994] (

InterpreterFactory.java[getInterpreterSessionKey]:1239) - Interpreter session key: shared_session, for note: 2C7TKVQEW, user: admin, InterpreterSetting Name: python3
DEBUG [2017-02-07 09:56:02,994] (

Unknown macro: {Thread-45}
InterpreterSetting.java[getInterpreterProcessKey]:143) - getInterpreterProcessKey: shared_process for InterpreterSetting Id: 2C7KPWF4Y, Name: python3
DEBUG [2017-02-07 09:56:02,994] (

InterpreterSetting.java[getInterpreterProcessKey]:143) - getInterpreterProcessKey: shared_process for InterpreterSetting Id: 2C7KPWF4Y, Name: python3
DEBUG [2017-02-07 09:56:02,998] (

Unknown macro: {Thread-45}
NotebookServer.java[broadcast]:472) - SEND >> PROGRESS
DEBUG [2017-02-07 09:56:02,998] (

WebSocketRemoteEndpoint.java[sendString]:385) - sendString with HeapByteBuffer@bd7ddbf[p=0,l=128,c=128,r=128]={<<<

Unknown macro: {"op"}

>>>}
DEBUG [2017-02-07 09:56:02,998] (

Unknown macro: {Thread-45}
ExtensionStack.java[outgoingFrame]:288) - Queuing TEXT[len=128,fin=true,rsv=...,masked=false]
DEBUG [2017-02-07 09:56:02,998] (

ExtensionStack.java[process]:388) - Processing TEXT[len=128,fin=true,rsv=...,masked=false]
DEBUG [2017-02-07 09:56:02,998] (

Unknown macro: {Thread-45}
AbstractWebSocketConnection.java[outgoingFrame]:564) - outgoingFrame(TEXT[len=128,fin=true,rsv=...,masked=false], org.eclipse.jetty.websocket.common.extensions.ExtensionStack$Flusher@7d2522f0[PROCESSING])
DEBUG [2017-02-07 09:56:02,998] (

FrameFlusher.java[enqueue]:378) - Flusher[queueSize=1,aggregateSize=0,failure=null] queued FrameEntry[TEXT[len=128,fin=true,rsv=...,masked=false],org.eclipse.jetty.websocket.common.extensions.ExtensionStack$Flusher@7d2522f0[PROCESSING],AUTO,null]
DEBUG [2017-02-07 09:56:02,998] (

Unknown macro: {Thread-45}
FrameFlusher.java[process]:197) - Flusher[queueSize=0,aggregateSize=0,failure=null] processing 1 entries: [FrameEntry[TEXT[len=128,fin=true,rsv=...,masked=false],org.eclipse.jetty.websocket.common.extensions.ExtensionStack$Flusher@7d2522f0[PROCESSING],AUTO,null]]
DEBUG [2017-02-07 09:56:02,998] (

FrameFlusher.java[batch]:67) - Flusher[queueSize=0,aggregateSize=0,failure=null] acquired aggregate buffer java.nio.DirectByteBuffer[pos=0 lim=0 cap=32768]
DEBUG [2017-02-07 09:56:02,998] (

Unknown macro: {Thread-45}
FrameFlusher.java[batch]:86) - Flusher[queueSize=0,aggregateSize=0,failure=null] aggregated 1 frames: [FrameEntry[TEXT[len=0,fin=true,rsv=...,masked=false],org.eclipse.jetty.websocket.common.extensions.ExtensionStack$Flusher@7d2522f0[PROCESSING],AUTO,null]]
DEBUG [2017-02-07 09:56:02,999] (

FrameFlusher.java[process]:197) - Flusher[queueSize=0,aggregateSize=0,failure=null] processing 0 entries: []
DEBUG [2017-02-07 09:56:02,999] (

Unknown macro: {Thread-45}
FrameFlusher.java[process]:210) - Flusher[queueSize=0,aggregateSize=0,failure=null] auto flushing
DEBUG [2017-02-07 09:56:02,999] (

FrameFlusher.java[flush]:118) - Flusher[queueSize=0,aggregateSize=0,failure=null] flushing aggregate java.nio.DirectByteBuffer[pos=0 lim=132 cap=32768]
DEBUG [2017-02-07 09:56:02,999] (

Unknown macro: {Thread-45}
FrameFlusher.java[flush]:141) - Flusher[queueSize=0,aggregateSize=0,failure=null] flushing 0 frames: []
DEBUG [2017-02-07 09:56:02,999] (

WriteFlusher.java[write]:295) - write: WriteFlusher@296a7050

Unknown macro: {IDLE}
[DirectByteBuffer@5f97bfd0[p=0,l=132,c=32768,r=132]={<<<\x81~\x00\x80

Unknown macro: {"op"}

>>>e string of lengt...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
DEBUG [2017-02-07 09:56:02,999] (

Unknown macro: {Thread-45}
WriteFlusher.java[updateState]:118) - update WriteFlusher@296a7050

Unknown macro: {WRITING}

:IDLE-->WRITING
DEBUG [2017-02-07 09:56:02,999] (

ChannelEndPoint.java[flush]:188) - flushed 132 SelectChannelEndPoint@646c7187

Unknown macro: {/0}

Unknown macro: {io=1,kio=1,kro=1}

DEBUG [2017-02-07 09:56:02,999] (

Unknown macro: {Thread-45}

WriteFlusher.java[updateState]:118) - update WriteFlusher@296a7050

:WRITING-->IDLE
DEBUG [2017-02-07 09:56:02,999] (

Unknown macro: {Thread-45}
FrameFlusher.java[process]:197) - Flusher[queueSize=0,aggregateSize=132,failure=null] processing 0 entries: []
DEBUG [2017-02-07 09:56:02,999] (

FrameFlusher.java[process]:210) - Flusher[queueSize=0,aggregateSize=132,failure=null] auto flushing
DEBUG [2017-02-07 09:56:02,999] (

Unknown macro: {Thread-45}
FrameFlusher.java[flush]:141) - Flusher[queueSize=0,aggregateSize=132,failure=null] flushing 0 frames: []
DEBUG [2017-02-07 09:56:02,999] (

ExtensionStack.java[process]:384) - Entering IDLE
(...) thousands of more lines...

Attachments

Issue Links

duplicates

ZEPPELIN-1360 Printing long strings is very slow in the python interpreter

Resolved

Activity

People

Assignee:: Unassigned

Reporter:: Sven

Votes:: 1 Vote for this issue

Watchers:: 3 Start watching this issue

Dates

Created:: 07/Feb/17 09:51

Updated:: 07/Feb/17 14:36