[jacorb-developer] Odd slowdown using JacORB 3.2 with network adapter disabled

[Pierskalla, Eric]

Note: I am submitting this again, after updating my email address in my profile.

We have a client UI using JacORB 3.2 that communicates to a server program.  In this case the server program is running as a hardware emulator at the loopback address of 127.0.0.1.  Both client and server are running on the same PC.
We have noticed that the responsiveness of the CORBA calls to the server program suffers if the PC has no external network connection.
In other words, things run at normal speeds when connected to the local network.  If I disable the network adapter, the CORBA calls take longer.  When I re-enable the network adapter, they speed back up.  This all happens without restarting the client or server programs.

This only happens when running on Windows 7, it does not occur on XP.
A previous version of the client UI that uses JacORB  2.3.1 does not exhibit this behavior, whether run on Windows 7 or XP.
In all cases we are using the 1.6.0_25 JRE.

I set the logging level to 4, and performed the same call sequence with the network adapter enabled and disabled.

This is with the adapter enabled, notice the time stamp of the entry "ClientConnectionManager: found ClientGIOPConnection" compared to the entry before it:

2013-08-30 13:14:14.125 FINE GIOPConnection.sendMessage timeout (millis): 0
2013-08-30 13:14:14.125 FINE wrote 72 bytes to 127.0.0.1:10011
2013-08-30 13:14:14.126 FINE wrote GIOP message of size 72 to ClientGIOPConnection to 127.0.0.1:10011 (57ba39a5)
2013-08-30 13:14:14.126 FINE read 12 bytes from 127.0.0.1:10011
2013-08-30 13:14:14.126 FINE read 48 bytes from 127.0.0.1:10011
2013-08-30 13:14:14.126 FINE read GIOP message of size 60 from ClientGIOPConnection to 127.0.0.1:10011 (57ba39a5)
2013-08-30 13:14:14.126 FINE created with invocationContext: {REPLY_END_TIME=null, REQUEST_END_TIME=null}
2013-08-30 13:14:14.129 FINE GIOPConnection.sendMessage timeout (millis): 0
2013-08-30 13:14:14.129 FINE wrote 163 bytes to 127.0.0.1:10011
2013-08-30 13:14:14.129 FINE wrote GIOP message of size 163 to ClientGIOPConnection to 127.0.0.1:10011 (57ba39a5)
2013-08-30 13:14:14.130 FINE read 12 bytes from 127.0.0.1:10011
2013-08-30 13:14:14.130 FINE read 16 bytes from 127.0.0.1:10011
2013-08-30 13:14:14.130 FINE read GIOP message of size 28 from ClientGIOPConnection to 127.0.0.1:10011 (57ba39a5)
2013-08-30 13:14:14.131 FINE created with invocationContext: {REPLY_END_TIME=null, REQUEST_END_TIME=null}
2013-08-30 13:14:14.131 FINE GIOPConnection.sendMessage timeout (millis): 0
2013-08-30 13:14:14.132 FINE wrote 90 bytes to 127.0.0.1:10011
2013-08-30 13:14:14.132 FINE wrote GIOP message of size 90 to ClientGIOPConnection to 127.0.0.1:10011 (57ba39a5)
2013-08-30 13:14:14.132 FINE read 12 bytes from 127.0.0.1:10011
2013-08-30 13:14:14.134 FINE read 308164 bytes from 127.0.0.1:10011
2013-08-30 13:14:14.134 FINE read GIOP message of size 308176 from ClientGIOPConnection to 127.0.0.1:10011 (57ba39a5)
2013-08-30 13:14:14.134 FINE read 12 bytes from 127.0.0.1:10011
2013-08-30 13:14:14.134 FINE read 80 bytes from 127.0.0.1:10011
2013-08-30 13:14:14.134 FINE read GIOP message of size 92 from ClientGIOPConnection to 127.0.0.1:10011 (57ba39a5)
2013-08-30 13:14:14.136 INFO ClientConnectionManager: found ClientGIOPConnection to 127.0.0.1:10011 (57ba39a5)
2013-08-30 13:14:14.136 FINE findPOA: reference generated by foreign POA
    org.jacorb.poa.util.POAUtil.extractImplName(POAUtil.java:184)
    org.jacorb.orb.ORB.findPOA(ORB.java:572)
    org.jacorb.orb.Delegate.resolvePOA(Delegate.java:1072)
    org.jacorb.orb.Delegate.is_really_local(Delegate.java:1959)
    org.jacorb.orb.Delegate.is_a(Delegate.java:1787)
    org.omg.CORBA.portable.ObjectImpl._is_a(ObjectImpl.java:53)
    vdm.IToolHelper.narrow(IToolHelper.java:59)
    vdcomm.Tool.run(Tool.java:453)


This is with the adapter disabled, notice the time stamp of the entry "ClientConnectionManager: found ClientGIOPConnection" compared to the entry before it:

2013-08-30 13:15:28.051 FINE GIOPConnection.sendMessage timeout (millis): 0
2013-08-30 13:15:28.052 FINE wrote 90 bytes to 127.0.0.1:10011
2013-08-30 13:15:28.052 FINE wrote GIOP message of size 90 to ClientGIOPConnection to 127.0.0.1:10011 (57ba39a5)
2013-08-30 13:15:28.052 FINE read 12 bytes from 127.0.0.1:10011
2013-08-30 13:15:28.053 FINE read 308164 bytes from 127.0.0.1:10011
2013-08-30 13:15:28.053 FINE read GIOP message of size 308176 from ClientGIOPConnection to 127.0.0.1:10011 (57ba39a5)
2013-08-30 13:15:28.053 FINE read 12 bytes from 127.0.0.1:10011
2013-08-30 13:15:28.053 FINE read 80 bytes from 127.0.0.1:10011
2013-08-30 13:15:28.053 FINE read GIOP message of size 92 from ClientGIOPConnection to 127.0.0.1:10011 (57ba39a5)
2013-08-30 13:15:28.278 INFO ClientConnectionManager: found ClientGIOPConnection to 127.0.0.1:10011 (57ba39a5)
2013-08-30 13:15:28.278 FINE findPOA: reference generated by foreign POA
    org.jacorb.poa.util.POAUtil.extractImplName(POAUtil.java:184)
    org.jacorb.orb.ORB.findPOA(ORB.java:572)
    org.jacorb.orb.Delegate.resolvePOA(Delegate.java:1072)
    org.jacorb.orb.Delegate.is_really_local(Delegate.java:1959)
    org.jacorb.orb.Delegate.is_a(Delegate.java:1787)
    org.omg.CORBA.portable.ObjectImpl._is_a(ObjectImpl.java:53)
    vdm.IToolHelper.narrow(IToolHelper.java:59)
    vdcomm.Tool.run(Tool.java:453)

Any insights would be appreciated.
Eric



CONFIDENTIALITY NOTICE. This message is for the sole use of the intended recipient(s) and may contain confidential and/or privileged information. Any unauthorized review, use, disclosure, or distribution is prohibited.  If you are not the intended recipient, please destroy all copies of the original message, including any and all attachments, and notify the sender immediately.