This Bugzilla instance is a read-only archive of historic NetBeans bug reports. To report a bug in NetBeans please follow the project's instructions for reporting issues.

Bug 103603 - remote pack: java.io.IOException: JFluid wire protocol error
Summary: remote pack: java.io.IOException: JFluid wire protocol error
Status: VERIFIED INVALID
Alias: None
Product: profiler
Classification: Unclassified
Component: Base (show other bugs)
Version: 6.x
Hardware: PC Linux
: P1 blocker (vote)
Assignee: issues@profiler
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2007-05-10 01:37 UTC by ecaspole
Modified: 2007-09-10 13:41 UTC (History)
0 users

See Also:
Issue Type: DEFECT
Exception Reporter:


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description ecaspole 2007-05-10 01:37:49 UTC
I am trying to use the 6.0m9 remote pack. My IDE is on Windows, and my
application to profile is on Linux 2.4.21-138-smp, an older Suse. 

When I attach to the application and start its normal work, there is a flurry of
class loads from __VM_RedefineClasses__, then the exception shown below happens.
 At this point both the application and the IDE back on my Windows box are
frozen  and must be killed.


[Loaded java.lang.ClassLoader from __VM_RedefineClasses__]
[Loaded java.math.BigInteger from __VM_RedefineClasses__]
[Loaded com.sun.tools.profiler.wireprotocol.EventBufferDumpedCommand from
/home/ecaspole/junk/nb_6.0m9_remote_pack/lib//jfluid-server.jar]
[Loaded sun.net.ConnectionResetException from /usr/java/jdk1.5.0_06/jre/lib/rt.jar]
Profiler Agent Error: Exception when handling command from client:
java.io.IOException: JFluid wire protocol error: code does not correspond to
command or response. Value -1
Profiler Agent Error: error writing collected data to the socket: Broken pipe
Profiler Agent: Connection with agent closed
Profiler Agent Error: Exception when trying to send response or command to client:
java.net.SocketException: Socket closed
java.net.SocketException: Socket closed
        at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:99)
        at java.net.SocketOutputStream.write(SocketOutputStream.java:136)
        at
java.io.ObjectOutputStream$BlockDataOutputStream.writeBlockHeader(ObjectOutputStream.java:1689)
        at
java.io.ObjectOutputStream$BlockDataOutputStream.drain(ObjectOutputStream.java:1674)
        at
java.io.ObjectOutputStream$BlockDataOutputStream.flush(ObjectOutputStream.java:1621)
        at java.io.ObjectOutputStream.flush(ObjectOutputStream.java:655)
        at
com.sun.tools.profiler.wireprotocol.WireIO.sendComplexCommand(WireIO.java:61)
        at
com.sun.tools.profiler.server.ProfilerServer.sendComplexCmdToClient(ProfilerServer.java:739)
        at
com.sun.tools.profiler.server.ProfilerInterface.firstTimeMethodInvokeHook(ProfilerInterface.java:850)
        at
com.sun.tools.profiler.server.ProfilerInterface.access$000(ProfilerInterface.java:32)
        at
com.sun.tools.profiler.server.ProfilerInterface$1.handleFirstTimeMethodInvoke(ProfilerInterface.java:119)
        at
com.sun.tools.profiler.server.ProfilerRuntimeCPUFullInstr.methodEntry(ProfilerRuntimeCPUFullInstr.java:179)
        at java.math.BigInteger.<init>(BigInteger.java:943)
        at java.math.BigInteger.valueOf(BigInteger.java:937)
        at java.math.BigInteger.<clinit>(BigInteger.java:977)
        at HprofReader.convertAddrStringToLong(HprofReader.java:148)
        at HprofReader.handleObject(HprofReader.java:420)
        at HprofReader.processLine(HprofReader.java:630)
        at HprofReader.readAndProcessLines(HprofReader.java:599)
        at HprofReader.run(HprofReader.java:714)
Profiler Agent: Connection with agent closed


Here is the stack trace of the frozen profiled app at this point:

Full thread dump Java HotSpot(TM) Server VM (1.5.0_06-b05 mixed mode):

"HprofReader 3" prio=1 tid=0x8a91d5b0 nid=0x1e89 in Object.wait()
[0x8b5fe000..0x8b5ff5bc]
        at java.lang.Object.wait(Native Method)
        - waiting on <0x81f870c8> (a java.lang.Object)
        at java.lang.Object.wait(Object.java:474)
        at
com.sun.tools.profiler.server.ProfilerServer.getLastResponse(ProfilerServer.java:1234)
        - locked <0x81f870c8> (a java.lang.Object)
        - locked <0x45c53720> (a java.lang.Class)
        at
com.sun.tools.profiler.server.ProfilerInterface.getAndInstrumentClasses(ProfilerInterface.java:486)
        at
com.sun.tools.profiler.server.ProfilerInterface.firstTimeMethodInvokeHook(ProfilerInterface.java:851)
        at
com.sun.tools.profiler.server.ProfilerInterface.access$000(ProfilerInterface.java:32)
        at
com.sun.tools.profiler.server.ProfilerInterface$1.handleFirstTimeMethodInvoke(ProfilerInterface.java:119)
        at
com.sun.tools.profiler.server.ProfilerRuntimeCPUFullInstr.methodEntry(ProfilerRuntimeCPUFullInstr.java:179)
        at java.math.BigInteger.<init>(BigInteger.java:943)
        at java.math.BigInteger.valueOf(BigInteger.java:937)
        at java.math.BigInteger.<clinit>(BigInteger.java:977)
        at HprofReader.convertAddrStringToLong(HprofReader.java:148)
        at HprofReader.handleObject(HprofReader.java:420)
        at HprofReader.processLine(HprofReader.java:630)
        at HprofReader.readAndProcessLines(HprofReader.java:599)
        at HprofReader.run(HprofReader.java:714)

"HprofReader 2" prio=1 tid=0x8a9148f0 nid=0x1e88 in Object.wait()
[0x8b57f000..0x8b57f63c]
        at HprofReader.convertAddrStringToLong(HprofReader.java:148)
        at HprofReader.handleArray(HprofReader.java:354)
        at HprofReader.processLine(HprofReader.java:637)
        at HprofReader.readAndProcessLines(HprofReader.java:599)
        at HprofReader.run(HprofReader.java:714)

"HprofReader 1" prio=1 tid=0x8a914418 nid=0x1e87 in Object.wait()
[0x8b2db000..0x8b2db4bc]
        at HprofReader.convertAddrStringToLong(HprofReader.java:148)
        at HprofReader.handleArray(HprofReader.java:269)
        at HprofReader.processLine(HprofReader.java:637)
        at HprofReader.readAndProcessLines(HprofReader.java:599)
        at HprofReader.run(HprofReader.java:714)

"HprofReader 0" prio=1 tid=0x8a914060 nid=0x1e86 in Object.wait()
[0x8b25b000..0x8b25b53c]
        at java.lang.Object.wait(Native Method)
        - waiting on <0x81c06338> (a java.lang.Object)
        at java.lang.Object.wait(Object.java:474)
        at
com.sun.tools.profiler.global.TransactionalSupport.beginTrans(TransactionalSupport.java:89)
        - locked <0x81c06338> (a java.lang.Object)
        at
com.sun.tools.profiler.global.TransactionalSupport.beginTrans(TransactionalSupport.java:33)
        at
com.sun.tools.profiler.server.ProfilerInterface.firstTimeMethodInvokeHook(ProfilerInterface.java:842)
        at
com.sun.tools.profiler.server.ProfilerInterface.access$000(ProfilerInterface.java:32)
        at
com.sun.tools.profiler.server.ProfilerInterface$1.handleFirstTimeMethodInvoke(ProfilerInterface.java:119)
        at
com.sun.tools.profiler.server.ProfilerRuntimeCPUFullInstr.methodEntry(ProfilerRuntimeCPUFullInstr.java:179)
        at HprofReader.handleTrace(HprofReader.java:529)
        at HprofReader.processLine(HprofReader.java:649)
        at HprofReader.readAndProcessLines(HprofReader.java:599)
        at HprofReader.run(HprofReader.java:714)

"TimerQueue" daemon prio=1 tid=0x8a776c98 nid=0x1e83 in Object.wait()
[0x8b1b0000..0x8b1b06bc]
        at java.lang.Object.wait(Native Method)
        - waiting on <0x8332c6e0> (a javax.swing.TimerQueue)
        at javax.swing.TimerQueue.run(TimerQueue.java:233)
        - locked <0x8332c6e0> (a javax.swing.TimerQueue)
        at java.lang.Thread.run(Thread.java:595)

"AWT-EventQueue-0" prio=1 tid=0x8a78b120 nid=0x1e69 in Object.wait()
[0x8b130000..0x8b1304bc]
        at java.lang.Object.wait(Native Method)
        - waiting on <0x81c70738> (a java.awt.EventQueue)
        at java.lang.Object.wait(Object.java:474)
        at java.awt.EventQueue.getNextEvent(EventQueue.java:345)
        - locked <0x81c70738> (a java.awt.EventQueue)
        at
java.awt.EventDispatchThread.pumpOneEventForHierarchy(EventDispatchThread.java:189)
        at
java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:163)
        at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:157)
        at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:149)
        at java.awt.EventDispatchThread.run(EventDispatchThread.java:110)

"AWT-Shutdown" prio=1 tid=0x8a78a638 nid=0x1e68 in Object.wait()
[0x8b0b0000..0x8b0b053c]
        at java.lang.Object.wait(Native Method)
        - waiting on <0x81c39f20> (a java.lang.Object)
        at java.lang.Object.wait(Object.java:474)
        at sun.awt.AWTAutoShutdown.run(AWTAutoShutdown.java:259)
        - locked <0x81c39f20> (a java.lang.Object)
        at java.lang.Thread.run(Thread.java:595)

"AWT-XAWT" daemon prio=1 tid=0x8a70c4c8 nid=0x1e67 runnable [0x8b030000..0x8b0307bc]
        at sun.awt.X11.XToolkit.waitForEvents(Native Method)
        at sun.awt.X11.XToolkit.run(XToolkit.java:463)
        at sun.awt.X11.XToolkit.run(XToolkit.java:438)
        at java.lang.Thread.run(Thread.java:595)

"Java2D Disposer" daemon prio=1 tid=0x0826da60 nid=0x1e66 in Object.wait()
[0x8af80000..0x8af8083c]
        at java.lang.Object.wait(Native Method)
        - waiting on <0x81c598d0> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
        - locked <0x81c598d0> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)
        at sun.java2d.Disposer.run(Disposer.java:107)
        at java.lang.Thread.run(Thread.java:595)

"Low Memory Detector" daemon prio=1 tid=0x8a701bb8 nid=0x1e64 runnable
[0x00000000..0x00000000]

"CompilerThread1" daemon prio=1 tid=0x8a7008c8 nid=0x1e63 waiting on condition
[0x00000000..0x8ab81724]

"CompilerThread0" daemon prio=1 tid=0x0817ac08 nid=0x1e62 waiting on condition
[0x00000000..0x8ab00864]

"AdapterThread" daemon prio=1 tid=0x08173390 nid=0x1e61 waiting on condition
[0x00000000..0x00000000]

"Signal Dispatcher" daemon prio=1 tid=0x081795d8 nid=0x1e60 waiting on condition
[0x00000000..0x00000000]

"*** Profiler Agent Special Execution Thread 6" daemon prio=1 tid=0x0816bae8
nid=0x1e5d in Object.wait() [0x8a6b8000..0x8a6b86bc]
        at java.lang.Object.wait(Native Method)
        - waiting on <0x81c06638> (a java.lang.Object)
        at java.lang.Object.wait(Object.java:474)
        at
com.sun.tools.profiler.server.ProfilerServer$SeparateCmdExecutionThread.run(ProfilerServer.java:1175)
        - locked <0x81c06638> (a java.lang.Object)

"*** JFluid Monitor thread ***" daemon prio=1 tid=0x0816ab00 nid=0x1e5c waiting
on condition [0x8a638000..0x8a63873c]
        at java.lang.Thread.sleep(Native Method)
        at
com.sun.tools.profiler.server.Monitors$SurvGenAndThreadsMonitor.run(Monitors.java:133)

"Finalizer" daemon prio=1 tid=0x08116540 nid=0x1e5a in Object.wait()
[0x8a335000..0x8a33563c]
        at java.lang.Object.wait(Native Method)
        - waiting on <0x81bb0848> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
        - locked <0x81bb0848> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)
        at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)

"Reference Handler" daemon prio=1 tid=0x08115cf0 nid=0x1e59 in Object.wait()
[0x8a2b5000..0x8a2b54bc]
        at java.lang.Object.wait(Native Method)
        - waiting on <0x81bb0758> (a java.lang.ref.Reference$Lock)
        at java.lang.Object.wait(Object.java:474)
        at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
        - locked <0x81bb0758> (a java.lang.ref.Reference$Lock)

"main" prio=1 tid=0x0805ca08 nid=0x1e52 waiting on condition
[0xbfffc000..0xbfffcb38]
        at java.lang.Thread.sleep(Native Method)
        at HprofTool.init(HprofTool.java:215)
        at HprofTool.main(HprofTool.java:1078)

"VM Thread" prio=1 tid=0x08111c30 nid=0x1e58 runnable 

"GC task thread#0 (ParallelGC)" prio=1 tid=0x08076ee8 nid=0x1e54 runnable 

"GC task thread#1 (ParallelGC)" prio=1 tid=0x08079ab8 nid=0x1e55 runnable 

"GC task thread#2 (ParallelGC)" prio=1 tid=0x0807a6a0 nid=0x1e56 runnable 

"GC task thread#3 (ParallelGC)" prio=1 tid=0x0807b288 nid=0x1e57 runnable 

"VM Periodic Task Thread" prio=1 tid=0x8a7030a0 nid=0x1e65 waiting on condition 



I may be able to provide a simple test case if necessary.
Comment 1 Alexander Kouznetsov 2007-05-10 10:06:37 UTC
I also experienced the same problem many times. It makes the whole remote
profiling functionality useless. Raising the priority.
Comment 2 nleck 2007-05-29 00:10:18 UTC
This seems to happen on Solaris also. I can't seem to profile memory at all. No
matter which options I select.
Comment 3 Tomas Hurka 2007-05-29 14:09:47 UTC
Please attach NetBeans IDE log <userdir>/var/log/messages.log and log from profiled application. 
Thanks.
Comment 4 Alexander Kouznetsov 2007-08-13 17:08:01 UTC
Closing due to no response on INCOMPLETE status. Please reopen if necessary.
Comment 5 Alexander Kouznetsov 2007-09-10 13:41:59 UTC
No response - verified as invalid