river-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Peter Firmstone <j...@zeus.net.au>
Subject Re: com.sun.jini.thread lock contention
Date Wed, 09 Jun 2010 08:04:29 GMT
Well it looks like the Hudson test that failed had nothing to do with 
TaskThread.

Keep you posted after I run some more tests.

Cheers,

Peter.

This is the test that failed:


[java] com/sun/jini/test/impl/discoverymanager/RemoveGroupsLocsDiscard.td

     [java] Test Failed: Setup Failed: com.sun.jini.qa.harness.TestException: Problem creating service for net.jini.core.lookup.ServiceRegistrar; nested exception is: 
     [java] 	RemoteException occurred in server thread; nested exception is: 
     [java] 	java.rmi.RemoteException: Create failed; nested exception is: 
     [java] 	java.lang.reflect.InvocationTargetException

     [java] TIME: 4:08:02 PM
     [java] 
     [java] Resolver.setToken FINEST: setting token <config> to none
     [java] QAConfig.loadTestConfiguration FINER: Test Configuration options:
     [java] QAConfig.loadTestConfiguration FINER:    -
     [java] QAConfig.loadTestConfiguration FINER:    net.jini.discovery.LookupDiscovery.multicastAnnouncementInterval = 20000
     [java] QAConfig.loadTestConfiguration FINER:    multicast.ttl = 0
     [java] Running com/sun/jini/test/impl/discoverymanager/RemoveGroupsLocsDiscard.td
     [java] Time is Tue Jun 08 16:08:02 UTC 2010
     [java] Starting test in separate process with command:
     [java] /home/hudson/tools/java/jdk1.6.0_20-32/jre/bin/java -Djava.security.policy=file:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/harness/policy/defaulttest.policy -Djava.rmi.server.codebase=http://minerva.apache.org:8082/qa1-share-dl.jar -cp /home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib/jiniharness.jar:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib/jinitests.jar:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/lib/jsk-platform.jar:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/lib/jsk-lib.jar -client -Djava.ext.dirs=/home/hudson/tools/java/jdk1.6.0_20-32/jre/lib/ext:/usr/java/packages/lib/ext:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib-ext:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/lib-ext -Dcom.sun.jini.jsk.port=8080 -Dcom.sun.jini.qa.port=8081 -Dcom.sun.jini.jsk.home=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk -Dcom.sun.jini.qa.home=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa -Dcom.sun.jini.qa.harness.harnessJar=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib/jiniharness.jar -Dcom.sun.jini.qa.harness.testJar=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib/jinitests.jar -Dcom.sun.jini.qa.harness.runjiniserver=true -Dcom.sun.jini.qa.harness.runkitserver=true -Djava.security.properties=file:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/harness/trust/dynamic-policy.properties -Djava.util.logging.config.file=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/src/com/sun/jini/test/resources/qa1.logging -Dcom.sun.jini.test.home=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa -Dcom.sun.jini.test.port=8082 -Dcom.sun.jini.qa.harness.policies=file:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/src/com/sun/jini/test/resources/jinitest.policy -Djava.ext.dirs=/home/hudson/tools/java/jdk1.6.0_20-32/jre/lib/ext:/usr/java/packages/lib/ext:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib-ext:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/lib-ext com.sun.jini.qa.harness.MasterTest com/sun/jini/test/impl/discoverymanager/RemoveGroupsLocsDiscard.td 
     [java] Jun 8, 2010 4:08:02 PM com.sun.jini.qa.harness.MasterTest main
     [java] FINE: Starting MasterTest
     [java] 
     [java] TIME: 4:08:03 PM
     [java] 
     [java] QAConfig.loadTestConfiguration FINER: Test Configuration options:
     [java] QAConfig.loadTestConfiguration FINER:    -
     [java] QAConfig.loadTestConfiguration FINER:    net.jini.discovery.LookupDiscovery.multicastAnnouncementInterval = 20000
     [java] QAConfig.loadTestConfiguration FINER:    multicast.ttl = 0
     [java] MasterTest.doTest INFO: 
     [java] ============================== CALLING SETUP() ==============================
     [java] 
     [java] AdminManager.startService FINE: starting qaClassServer
     [java] AdminManager.getAdmin FINEST: getAdmin called with prefix qaClassServer
     [java] FINE: 
     [java] FINE: Parameters for qaClassServer(.0):
     [java] FINE:      type              : classServer
     [java] FINE:      impl              : com.sun.jini.tool.ClassServer
     [java] FINE:      directory         : /home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib
     [java] FINE:      options           : 
     [java] Jun 8, 2010 4:08:03 PM com.sun.jini.tool.ClassServer run
     [java] INFO: ClassServer started [[/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib/], port 8081]
     [java] AdminManager.startService FINE: starting jiniClassServer
     [java] AdminManager.getAdmin FINEST: getAdmin called with prefix jiniClassServer
     [java] FINE: 
     [java] FINE: Parameters for jiniClassServer(.0):
     [java] FINE:      type              : classServer
     [java] FINE:      impl              : com.sun.jini.tool.ClassServer
     [java] FINE:      directory         : /home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/lib-dl
     [java] FINE:      options           : 
     [java] AdminManager.startService FINE: starting testClassServer
     [java] Jun 8, 2010 4:08:03 PM com.sun.jini.tool.ClassServer run
     [java] INFO: ClassServer started [[/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/lib-dl/], port 8080]
     [java] AdminManager.getAdmin FINEST: getAdmin called with prefix testClassServer
     [java] FINE: 
     [java] FINE: Parameters for testClassServer(.0):
     [java] FINE:      type              : classServer
     [java] FINE:      impl              : com.sun.jini.tool.ClassServer
     [java] FINE:      directory         : /home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib
     [java] FINE:      options           : 
     [java] BaseQATest.setup FINE:  setup()
     [java] Jun 8, 2010 4:08:03 PM com.sun.jini.tool.ClassServer run
     [java] INFO: ClassServer started [[/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib/], port 8082]
     [java] BaseQATest.getSetupInfo FINE:  ----- Harness Info ----- 
     [java] BaseQATest.getSetupInfo FINE:  harness codebase         -- http://minerva.apache.org:8082/qa1-share-dl.jar
     [java] BaseQATest.getSetupInfo FINE:  harness classpath        -- /home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib/jiniharness.jar:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib/jinitests.jar:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/lib/jsk-platform.jar:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/lib/jsk-lib.jar
     [java] BaseQATest.getSetupInfo FINE:  net.jini.discovery.debug        -- false
     [java] BaseQATest.getSetupInfo FINE:  com.sun.jini.reggie.proxy.debug -- false
     [java] BaseQATest.getSetupInfo FINE:  com.sun.jini.join.debug         -- false
     [java] BaseQATest.getSetupInfo FINE:  com.sun.jini.sdm.debug          -- false
     [java] BaseQATest.getSetupInfo FINE:  max secs event wait             -- 180
     [java] BaseQATest.getSetupInfo FINE:  ----- Lookup Service Info ----- 
     [java] BaseQATest.getSetupInfo FINE:  # of lookup services to start            -- 1
     [java] BaseQATest.getSetupInfo FINE:  # of additional lookup services to start -- 0
     [java] BaseQATest.getSetupInfo FINE:  seconds to wait for discovery            -- 10
     [java] BaseQATest.getSetupInfo FINE:  discard if no announcements in (nSecs =) -- 20
     [java] QAConfig.getServiceHost FINE: Selecting service host
     [java] QAConfig.getServiceHost FINE: Not distributed - selecting this host
     [java] BaseQATest.getTestLocator FINER: getServiceHost returned null
     [java] BaseQATest.startLookup FINE:  starting lookup service 0
     [java] AdminManager.startService FINE: starting net.jini.core.lookup.ServiceRegistrar
     [java] AdminManager.getAdmin FINEST: getAdmin called with prefix net.jini.core.lookup.ServiceRegistrar
     [java] QAConfig.getServiceHost FINE: Selecting service host
     [java] QAConfig.getServiceHost FINE: Not distributed - selecting this host
     [java] AbstractServiceAdmin.addServiceExporter FINER: no exporter definition provided
     [java] NonActivatableServiceStarterAdmin.getGroup FINER: Creating shared group
     [java] AdminManager.startService FINE: starting nonActivatableGroup
     [java] AdminManager.getAdmin FINEST: getAdmin called with prefix nonActivatableGroup
     [java] NonActivatableGroupAdmin.start FINER: NonActivatableGroup exec command line: '/home/hudson/tools/java/jdk1.6.0_20-32/jre/bin/java -Djava.rmi.server.codebase=http://minerva.apache.org:8081/nonactivatablegroup-dl.jar -Djava.security.policy=file:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/harness/policy/defaultnonactvm.policy -server -Djava.ext.dirs=/home/hudson/tools/java/jdk1.6.0_20-32/jre/lib/ext:/usr/java/packages/lib/ext:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib-ext:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/lib-ext -Dcom.sun.jini.jsk.port=8080 -Dcom.sun.jini.qa.port=8081 -Dcom.sun.jini.jsk.home=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk -Dcom.sun.jini.qa.home=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa -Dcom.sun.jini.qa.harness.harnessJar=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib/jiniharness.jar -Dcom.sun.jini.qa.harness.testJar=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib/jinitests.jar -Dcom.sun.jini.qa.harness.runjiniserver=true -Dcom.sun.jini.qa.harness.runkitserver=true -Djava.security.properties=file:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/harness/trust/dynamic-policy.properties -Djava.util.logging.config.file=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/src/com/sun/jini/test/resources/qa1.logging -Dcom.sun.jini.test.home=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa -Dcom.sun.jini.test.port=8082 -Dcom.sun.jini.qa.harness.policies=file:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/src/com/sun/jini/test/resources/jinitest.policy -cp /home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib/nonactivatablegroup.jar:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/lib/start.jar:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/lib/jsk-platform.jar com.sun.jini.qa.harness.NonActivatableGroupImpl'
     [java] FINE: 
     [java] FINE: Parameters for nonActivatableGroup(.0):
     [java] FINE:      type              : nonactivatablegroup
     [java] FINE:      codebase          : http://minerva.apache.org:8081/nonactivatablegroup-dl.jar
     [java] FINE:      impl              : com.sun.jini.qa.harness.NonActivatableGroupImpl
     [java] FINE:      policy file       : file:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/harness/policy/defaultnonactvm.policy
     [java] FINE:      classpath         : /home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib/nonactivatablegroup.jar:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/lib/start.jar:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/lib/jsk-platform.jar
     [java] FINE:      options           : -server
     [java] FINE:      properties        : -Djava.ext.dirs=/home/hudson/tools/java/jdk1.6.0_20-32/jre/lib/ext:/usr/java/packages/lib/ext:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib-ext:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/lib-ext
     [java] FINE:                        : -Dcom.sun.jini.jsk.port=8080
     [java] FINE:                        : -Dcom.sun.jini.qa.port=8081
     [java] FINE:                        : -Dcom.sun.jini.jsk.home=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk
     [java] FINE:                        : -Dcom.sun.jini.qa.home=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa
     [java] FINE:                        : -Dcom.sun.jini.qa.harness.harnessJar=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib/jiniharness.jar
     [java] FINE:                        : -Dcom.sun.jini.qa.harness.testJar=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/lib/jinitests.jar
     [java] FINE:                        : -Dcom.sun.jini.qa.harness.runjiniserver=true
     [java] FINE:                        : -Dcom.sun.jini.qa.harness.runkitserver=true
     [java] FINE:                        : -Djava.security.properties=file:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/harness/trust/dynamic-policy.properties
     [java] FINE:                        : -Djava.util.logging.config.file=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/src/com/sun/jini/test/resources/qa1.logging
     [java] FINE:                        : -Dcom.sun.jini.test.home=/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa
     [java] FINE:                        : -Dcom.sun.jini.test.port=8082
     [java] FINE:                        : -Dcom.sun.jini.qa.harness.policies=file:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/src/com/sun/jini/test/resources/jinitest.policy
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPEndpoint <clinit>
     [java] NonActGrp-out: FINE: main: localHostKnown = true, localHost = 67.195.138.8
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPTransport <init>
     [java] NonActGrp-out: FINE: main: Version = 2, ep = [67.195.138.8:0]
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPEndpoint getLocalEndpoint
     [java] NonActGrp-out: FINE: main: created local endpoint for socket factory null on port 0
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPTransport listen
     [java] NonActGrp-out: FINE: main: (port 0) create server socket
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPEndpoint newServerSocket
     [java] NonActGrp-out: FINER: main: creating server socket on [67.195.138.8:0]
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPEndpoint setDefaultPort
     [java] NonActGrp-out: FINE: main: default port for server socket factory null and client socket factory null set to 48290
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPTransport$AcceptLoop executeAcceptLoop
     [java] NonActGrp-out: FINE: RMI TCP Accept-0: listening on port 48290
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.WeakRef pin
     [java] NonActGrp-out: FINER: main: strongRef = sun.rmi.transport.DGCImpl@fe748f
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.ObjectTable putTarget
     [java] NonActGrp-out: FINER: main: add object [0:0:0, 2]
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.ObjectTable putTarget
     [java] NonActGrp-out: FINER: main: add object [-3b9058b:1291851876c:-7fff, 5108795070014869142]
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.server.Util computeMethodHash
     [java] NonActGrp-out: FINER: main: string used for method hash: "stop()V"
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.server.Util computeMethodHash
     [java] NonActGrp-out: FINER: main: string used for method hash: "startService(Ljava/lang/String;Ljava/lang/String;Ljava/lang/String;Ljava/lang/String;[Ljava/lang/String;Ljava/lang/String;Lcom/sun/jini/qa/harness/ServiceDescriptorTransformer;)Ljava/lang/Object;"
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl_Stub", codebase="http://minerva.apache.org:8081/nonactivatablegroup-dl.jar", defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@7d772e)
     [java] Jun 8, 2010 4:08:03 PM sun.net.www.protocol.http.HttpURLConnection writeRequests
     [java] FINE: sun.net.www.MessageHeader@119298d5 pairs: {GET /nonactivatablegroup-dl.jar HTTP/1.1: null}{User-Agent: Java/1.6.0_20}{Host: minerva.apache.org:8081}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}
     [java] Jun 8, 2010 4:08:03 PM com.sun.jini.tool.ClassServer$Task run
     [java] FINER: nonactivatablegroup-dl.jar requested from minerva.apache.org:49261
     [java] Jun 8, 2010 4:08:03 PM sun.net.www.protocol.http.HttpURLConnection getInputStream
     [java] FINE: sun.net.www.MessageHeader@f726173 pairs: {null: HTTP/1.0 200 OK}{Content-Length: 3429}{Content-Type: application/java}
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: class "com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl_Stub" found via defaultLoader, defined by sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="java.rmi.server.RemoteStub", codebase="http://minerva.apache.org:8081/nonactivatablegroup-dl.jar", defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@7d772e)
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: class "java.rmi.server.RemoteStub" found via defaultLoader, defined by null
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="java.rmi.server.RemoteObject", codebase="http://minerva.apache.org:8081/nonactivatablegroup-dl.jar", defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@7d772e)
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: class "java.rmi.server.RemoteObject" found via defaultLoader, defined by null
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPEndpoint <clinit>
     [java] FINE: main: localHostKnown = true, localHost = 67.195.138.8
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastRef newCall
     [java] FINE: main: get connection
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPTransport <init>
     [java] FINE: main: Version = 2, ep = [67.195.138.8:0]
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPEndpoint getLocalEndpoint
     [java] FINE: main: created local endpoint for socket factory null on port 0
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPChannel createConnection
     [java] FINE: main: create connection
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPEndpoint newSocket
     [java] FINER: main: opening socket to [67.195.138.8:48290]
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.proxy.RMIMasterSocketFactory createSocket
     [java] FINE: main: host: 67.195.138.8, port: 48290
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPTransport$ConnectionHandler run0
     [java] NonActGrp-out: FINE: RMI TCP Connection(1)-67.195.138.8: accepted socket from [67.195.138.8:33816]
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPTransport$ConnectionHandler run0
     [java] NonActGrp-out: FINER: RMI TCP Connection(1)-67.195.138.8: (port 48290) suggesting 67.195.138.8:33816
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPChannel createConnection
     [java] FINER: main: server suggested 67.195.138.8:33816
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPChannel createConnection
     [java] FINER: main: using 67.195.138.8:0
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPTransport$ConnectionHandler run0
     [java] NonActGrp-out: FINER: RMI TCP Connection(1)-67.195.138.8: (port 48290) client using 67.195.138.8:0
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastRef newCall
     [java] FINER: main: create call context
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastRef logClientCall
     [java] FINER: main: outbound call: [endpoint:[67.195.138.8:48290](remote),objID:[0:0:0, 2]] : sun.rmi.transport.DGCImpl_Stub[0:0:0, 2]: java.rmi.dgc.Lease dirty(java.rmi.server.ObjID[], long, java.rmi.dgc.Lease)
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.StreamRemoteCall <init>
     [java] FINER: main: write remote call header...
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.StreamRemoteCall getOutputStream
     [java] FINER: main: getting output stream
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastRef invoke
     [java] FINER: main: execute call
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPTransport handleMessages
     [java] NonActGrp-out: FINE: RMI TCP Connection(1)-67.195.138.8: (port 48290) op = 80
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.StreamRemoteCall getInputStream
     [java] NonActGrp-out: FINER: RMI TCP Connection(1)-67.195.138.8: getting input stream
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.Transport serviceCall
     [java] NonActGrp-out: FINER: RMI TCP Connection(1)-67.195.138.8: call dispatcher
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastServerRef logCall
     [java] NonActGrp-out: FINER: RMI TCP Connection(1)-67.195.138.8: [67.195.138.8: sun.rmi.transport.DGCImpl[0:0:0, 2]: java.rmi.dgc.Lease dirty(java.rmi.server.ObjID[], long, java.rmi.dgc.Lease)]
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINE: name="[Ljava.rmi.server.ObjID;", codebase="http://minerva.apache.org:8082/qa1-share-dl.jar", defaultLoader=null
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@16f0472)
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.net.www.protocol.http.HttpURLConnection writeRequests
     [java] NonActGrp-out: FINE: sun.net.www.MessageHeader@b01d435 pairs: {GET /qa1-share-dl.jar HTTP/1.1: null}{User-Agent: Java/1.6.0_20}{Host: minerva.apache.org:8082}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}
     [java] Jun 8, 2010 4:08:03 PM com.sun.jini.tool.ClassServer$Task run
     [java] FINER: qa1-share-dl.jar requested from minerva.apache.org:51805
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.net.www.protocol.http.HttpURLConnection getInputStream
     [java] NonActGrp-out: FINE: sun.net.www.MessageHeader@177b3cd3 pairs: {null: HTTP/1.0 200 OK}{Content-Length: 69656}{Content-Type: application/java}
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINEST: class "[Ljava.rmi.server.ObjID;" found via codebase loader, defined by null
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINE: name="java.rmi.server.ObjID", codebase="http://minerva.apache.org:8082/qa1-share-dl.jar", defaultLoader=null
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@16f0472)
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.StreamRemoteCall getInputStream
     [java] FINER: main: getting input stream
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINEST: class "java.rmi.server.ObjID" found via codebase loader, defined by null
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINE: name="java.rmi.server.UID", codebase="http://minerva.apache.org:8082/qa1-share-dl.jar", defaultLoader=null
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@16f0472)
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINEST: class "java.rmi.server.UID" found via codebase loader, defined by null
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINE: name="java.rmi.dgc.Lease", codebase="http://minerva.apache.org:8082/qa1-share-dl.jar", defaultLoader=null
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="java.rmi.dgc.Lease", codebase="http://minerva.apache.org:8081/nonactivatablegroup-dl.jar", defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@16f0472)
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINEST: class "java.rmi.dgc.Lease" found via codebase loader, defined by null
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINE: name="java.rmi.dgc.VMID", codebase="http://minerva.apache.org:8082/qa1-share-dl.jar", defaultLoader=null
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@7d772e)
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@16f0472)
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINEST: class "java.rmi.dgc.VMID" found via codebase loader, defined by null
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINE: name="[B", codebase=null, defaultLoader=null
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.DGCImpl dirty
     [java] NonActGrp-out: FINER: RMI TCP Connection(1)-67.195.138.8: vmid = dbf17c0820afd71b:12c6d08f:12918518845:-7fff
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.DGCImpl dirty
     [java] NonActGrp-out: FINER: RMI TCP Connection(1)-67.195.138.8: id = [-3b9058b:1291851876c:-7fff, 5108795070014869142], vmid = dbf17c0820afd71b:12c6d08f:12918518845:-7fff, duration = 600000
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.WeakRef pin
     [java] NonActGrp-out: FINER: RMI TCP Connection(1)-67.195.138.8: strongRef = com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl@1592174
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.Target referenced
     [java] NonActGrp-out: FINER: RMI TCP Connection(1)-67.195.138.8: add to dirty set: dbf17c0820afd71b:12c6d08f:12918518845:-7fff
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.StreamRemoteCall getOutputStream
     [java] NonActGrp-out: FINER: RMI TCP Connection(1)-67.195.138.8: getting output stream
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: class "java.rmi.dgc.Lease" found via defaultLoader, defined by null
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="java.rmi.dgc.VMID", codebase="http://minerva.apache.org:8081/nonactivatablegroup-dl.jar", defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@7d772e)
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: class "java.rmi.dgc.VMID" found via defaultLoader, defined by null
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="[B", codebase=null, defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="java.rmi.server.UID", codebase="http://minerva.apache.org:8081/nonactivatablegroup-dl.jar", defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@7d772e)
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: class "java.rmi.server.UID" found via defaultLoader, defined by null
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastRef done
     [java] FINE: main: free connection (reuse = true)
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPChannel free
     [java] FINE: main: reuse connection
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPChannel free
     [java] FINE: main: create reaper
     [java] FINE: 
     [java] FINE: Parameters for net.jini.core.lookup.ServiceRegistrar(.0):
     [java] FINE:      type              : transient
     [java] FINE:      codebase          : http://minerva.apache.org:8080/reggie-dl.jar http://minerva.apache.org:8080/jsk-dl.jar
     [java] FINE:      impl              : com.sun.jini.reggie.TransientRegistrarImpl
     [java] FINE:      component name    : com.sun.jini.reggie
     [java] FINE:      policy file       : file:/home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/qa/harness/policy/defaultreggie.policy
     [java] FINE:      classpath         : /home/hudson/hudson-slave/workspace/River-trunk/jtsk/trunk/lib/reggie.jar
     [java] FINE:      service conf file : -
     [java] FINE:      starter conf file : -
     [java] FINE:      proxy preparer    : test.reggiePreparer
     [java] FINE:      option args 0     : -
     [java] FINE:      option args 1     : com.sun.jini.reggie.initialMemberGroups = new String[]{"LDMGroup0_A_minerva.apache.org_1276013282827", "LDMGroup0_B_minerva.apache.org_1276013282827", "LDMGroup0_C_minerva.apache.org_1276013282827"}
     [java] FINE:      option args 2     : com.sun.jini.reggie.initialUnicastDiscoveryPort=8000
     [java] FINE:      option args 3     : net.jini.discovery.LookupDiscovery.multicastAnnouncementInterval=20000
     [java] FINE:      option args 4     : com.sun.jini.reggie.multicastAnnouncementInterval=20000
     [java] FINE:      option args 5     : multicast.ttl=0
     [java] FINE: 
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastRef invoke
     [java] FINER: main: method: public abstract java.lang.Object com.sun.jini.qa.harness.NonActivatableGroup.startService(java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String[],java.lang.String,com.sun.jini.qa.harness.ServiceDescriptorTransformer) throws java.rmi.RemoteException
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastRef logClientCall
     [java] FINER: main: outbound call: [endpoint:[67.195.138.8:48290](remote),objID:[-3b9058b:1291851876c:-7fff, 5108795070014869142]] : com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl_Stub[-3b9058b:1291851876c:-7fff, 5108795070014869142]: public abstract java.lang.Object com.sun.jini.qa.harness.NonActivatableGroup.startService(java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String[],java.lang.String,com.sun.jini.qa.harness.ServiceDescriptorTransformer) throws java.rmi.RemoteException
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPTransport handleMessages
     [java] NonActGrp-out: FINE: RMI TCP Connection(1)-67.195.138.8: (port 48290) op = 82
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPChannel newConnection
     [java] FINE: main: reuse connection
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastRef invoke
     [java] FINER: main: opnum = -3440598291276346209
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.StreamRemoteCall <init>
     [java] FINER: main: write remote call header...
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.StreamRemoteCall getOutputStream
     [java] FINER: main: getting output stream
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPTransport handleMessages
     [java] NonActGrp-out: FINE: RMI TCP Connection(1)-67.195.138.8: (port 48290) op = 80
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.StreamRemoteCall getInputStream
     [java] NonActGrp-out: FINER: RMI TCP Connection(1)-67.195.138.8: getting input stream
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.Transport serviceCall
     [java] NonActGrp-out: FINER: RMI TCP Connection(1)-67.195.138.8: call dispatcher
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastServerRef logCall
     [java] NonActGrp-out: FINER: RMI TCP Connection(1)-67.195.138.8: [67.195.138.8: com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl[-3b9058b:1291851876c:-7fff, 5108795070014869142]: public abstract java.lang.Object com.sun.jini.qa.harness.NonActivatableGroup.startService(java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String[],java.lang.String,com.sun.jini.qa.harness.ServiceDescriptorTransformer) throws java.rmi.RemoteException]
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINE: name="[Ljava.lang.String;", codebase="http://minerva.apache.org:8082/qa1-share-dl.jar", defaultLoader=null
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@16f0472)
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] NonActGrp-out: FINEST: class "[Ljava.lang.String;" found via codebase loader, defined by null
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.net.www.protocol.http.HttpURLConnection writeRequests
     [java] NonActGrp-out: FINE: sun.net.www.MessageHeader@19836ed5 pairs: {GET /reggie-dl.jar HTTP/1.1: null}{User-Agent: Java/1.6.0_20}{Host: minerva.apache.org:8080}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}
     [java] Jun 8, 2010 4:08:03 PM com.sun.jini.tool.ClassServer$Task run
     [java] FINER: reggie-dl.jar requested from minerva.apache.org:42569
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.net.www.protocol.http.HttpURLConnection getInputStream
     [java] NonActGrp-out: FINE: sun.net.www.MessageHeader@3e0ebb3 pairs: {null: HTTP/1.0 200 OK}{Content-Length: 50827}{Content-Type: application/java}
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.net.www.protocol.http.HttpURLConnection writeRequests
     [java] NonActGrp-out: FINE: sun.net.www.MessageHeader@39443f5 pairs: {GET /jsk-dl.jar HTTP/1.1: null}{User-Agent: Java/1.6.0_20}{Host: minerva.apache.org:8080}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}
     [java] Jun 8, 2010 4:08:03 PM com.sun.jini.tool.ClassServer$Task run
     [java] FINER: jsk-dl.jar requested from minerva.apache.org:42570
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.net.www.protocol.http.HttpURLConnection getInputStream
     [java] NonActGrp-out: FINE: sun.net.www.MessageHeader@1afae453 pairs: {null: HTTP/1.0 200 OK}{Content-Length: 57298}{Content-Type: application/java}
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM com.sun.jini.reggie.RegistrarImpl <init>
     [java] NonActGrp-out: SEVERE: Reggie initialization failed
     [java] NonActGrp-out: java.net.BindException: Address already in use
     [java] NonActGrp-out: 	at java.net.PlainSocketImpl.socketBind(Native Method)
     [java] NonActGrp-out: 	at java.net.PlainSocketImpl.bind(PlainSocketImpl.java:365)
     [java] NonActGrp-out: 	at java.net.ServerSocket.bind(ServerSocket.java:319)
     [java] NonActGrp-out: 	at java.net.ServerSocket.<init>(ServerSocket.java:185)
     [java] NonActGrp-out: 	at java.net.ServerSocket.<init>(ServerSocket.java:97)
     [java] NonActGrp-out: 	at javax.net.DefaultServerSocketFactory.createServerSocket(ServerSocketFactory.java:157)
     [java] NonActGrp-out: 	at com.sun.jini.reggie.RegistrarImpl$UnicastThread.<init>(Unknown Source)
     [java] NonActGrp-out: 	at com.sun.jini.reggie.RegistrarImpl.init(Unknown Source)
     [java] NonActGrp-out: 	at com.sun.jini.reggie.RegistrarImpl.access$000(Unknown Source)
     [java] NonActGrp-out: 	at com.sun.jini.reggie.RegistrarImpl$1.run(Unknown Source)
     [java] NonActGrp-out: 	at com.sun.jini.reggie.RegistrarImpl.<init>(Unknown Source)
     [java] NonActGrp-out: 	at com.sun.jini.reggie.TransientRegistrarImpl.<init>(Unknown Source)
     [java] NonActGrp-out: 	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
     [java] NonActGrp-out: 	at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
     [java] NonActGrp-out: 	at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
     [java] NonActGrp-out: 	at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
     [java] NonActGrp-out: 	at com.sun.jini.start.NonActivatableServiceDescriptor.create(Unknown Source)
     [java] NonActGrp-out: 	at com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl.startService(Unknown Source)
     [java] NonActGrp-out: 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
     [java] NonActGrp-out: 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
     [java] NonActGrp-out: 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
     [java] NonActGrp-out: 	at java.lang.reflect.Method.invoke(Method.java:597)
     [java] NonActGrp-out: 	at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:305)
     [java] NonActGrp-out: 	at sun.rmi.transport.Transport$1.run(Transport.java:159)
     [java] NonActGrp-out: 	at java.security.AccessController.doPrivileged(Native Method)
     [java] NonActGrp-out: 	at sun.rmi.transport.Transport.serviceCall(Transport.java:155)
     [java] NonActGrp-out: 	at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:535)
     [java] NonActGrp-out: 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:790)
     [java] NonActGrp-out: 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:649)
     [java] NonActGrp-out: 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
     [java] NonActGrp-out: 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
     [java] NonActGrp-out: 	at java.lang.Thread.run(Thread.java:619)
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastServerRef logCallException
     [java] NonActGrp-out: FINE: RMI TCP Connection(1)-67.195.138.8: [67.195.138.8] exception: 
     [java] NonActGrp-out: java.rmi.RemoteException: Create failed; nested exception is: 
     [java] NonActGrp-out: 	java.lang.reflect.InvocationTargetException
     [java] NonActGrp-out: 	at com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl.startService(Unknown Source)
     [java] NonActGrp-out: 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
     [java] NonActGrp-out: 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
     [java] NonActGrp-out: 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
     [java] NonActGrp-out: 	at java.lang.reflect.Method.invoke(Method.java:597)
     [java] NonActGrp-out: 	at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:305)
     [java] NonActGrp-out: 	at sun.rmi.transport.Transport$1.run(Transport.java:159)
     [java] NonActGrp-out: 	at java.security.AccessController.doPrivileged(Native Method)
     [java] NonActGrp-out: 	at sun.rmi.transport.Transport.serviceCall(Transport.java:155)
     [java] NonActGrp-out: 	at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:535)
     [java] NonActGrp-out: 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:790)
     [java] NonActGrp-out: 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:649)
     [java] NonActGrp-out: 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
     [java] NonActGrp-out: 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
     [java] NonActGrp-out: 	at java.lang.Thread.run(Thread.java:619)
     [java] NonActGrp-out: Caused by: java.lang.reflect.InvocationTargetException
     [java] NonActGrp-out: 	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
     [java] NonActGrp-out: 	at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
     [java] NonActGrp-out: 	at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
     [java] NonActGrp-out: 	at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
     [java] NonActGrp-out: 	at com.sun.jini.start.NonActivatableServiceDescriptor.create(Unknown Source)
     [java] NonActGrp-out: 	... 15 more
     [java] NonActGrp-out: Caused by: java.net.BindException: Address already in use
     [java] NonActGrp-out: 	at java.net.PlainSocketImpl.socketBind(Native Method)
     [java] NonActGrp-out: 	at java.net.PlainSocketImpl.bind(PlainSocketImpl.java:365)
     [java] NonActGrp-out: 	at java.net.ServerSocket.bind(ServerSocket.java:319)
     [java] NonActGrp-out: 	at java.net.ServerSocket.<init>(ServerSocket.java:185)
     [java] NonActGrp-out: 	at java.net.ServerSocket.<init>(ServerSocket.java:97)
     [java] NonActGrp-out: 	at javax.net.DefaultServerSocketFactory.createServerSocket(ServerSocketFactory.java:157)
     [java] NonActGrp-out: 	at com.sun.jini.reggie.RegistrarImpl$UnicastThread.<init>(Unknown Source)
     [java] NonActGrp-out: 	at com.sun.jini.reggie.RegistrarImpl.init(Unknown Source)
     [java] NonActGrp-out: 	at com.sun.jini.reggie.RegistrarImpl.access$000(Unknown Source)
     [java] NonActGrp-out: 	at com.sun.jini.reggie.RegistrarImpl$1.run(Unknown Source)
     [java] NonActGrp-out: 	at com.sun.jini.reggie.RegistrarImpl.<init>(Unknown Source)
     [java] NonActGrp-out: 	at com.sun.jini.reggie.TransientRegistrarImpl.<init>(Unknown Source)
     [java] NonActGrp-out: 	... 20 more
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.StreamRemoteCall getOutputStream
     [java] NonActGrp-out: FINER: RMI TCP Connection(1)-67.195.138.8: getting output stream
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.StreamRemoteCall getInputStream
     [java] FINER: main: getting input stream
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="java.rmi.ServerException", codebase="http://minerva.apache.org:8081/nonactivatablegroup-dl.jar", defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@7d772e)
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: class "java.rmi.ServerException" found via defaultLoader, defined by null
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="java.rmi.RemoteException", codebase="http://minerva.apache.org:8081/nonactivatablegroup-dl.jar", defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@7d772e)
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: class "java.rmi.RemoteException" found via defaultLoader, defined by null
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="java.io.IOException", codebase="http://minerva.apache.org:8081/nonactivatablegroup-dl.jar", defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@7d772e)
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: class "java.io.IOException" found via defaultLoader, defined by null
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="java.lang.Exception", codebase="http://minerva.apache.org:8081/nonactivatablegroup-dl.jar", defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@7d772e)
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: class "java.lang.Exception" found via defaultLoader, defined by null
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="java.lang.Throwable", codebase="http://minerva.apache.org:8081/nonactivatablegroup-dl.jar", defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@7d772e)
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: class "java.lang.Throwable" found via defaultLoader, defined by null
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="[Ljava.lang.StackTraceElement;", codebase="http://minerva.apache.org:8081/nonactivatablegroup-dl.jar", defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@7d772e)
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: class "[Ljava.lang.StackTraceElement;" found via defaultLoader, defined by null
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="java.lang.StackTraceElement", codebase="http://minerva.apache.org:8081/nonactivatablegroup-dl.jar", defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@7d772e)
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: class "java.lang.StackTraceElement" found via defaultLoader, defined by null
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="java.lang.reflect.InvocationTargetException", codebase="http://minerva.apache.org:8081/nonactivatablegroup-dl.jar", defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@7d772e)
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: class "java.lang.reflect.InvocationTargetException" found via defaultLoader, defined by null
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="java.net.BindException", codebase="http://minerva.apache.org:8081/nonactivatablegroup-dl.jar", defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@7d772e)
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: class "java.net.BindException" found via defaultLoader, defined by null
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINE: name="java.net.SocketException", codebase="http://minerva.apache.org:8081/nonactivatablegroup-dl.jar", defaultLoader=sun.misc.Launcher$AppClassLoader@7d772e
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: (thread context class loader: sun.misc.Launcher$AppClassLoader@7d772e)
     [java] Jun 8, 2010 4:08:03 PM net.jini.loader.pref.PreferredClassProvider loadClass
     [java] FINEST: class "java.net.SocketException" found via defaultLoader, defined by null
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.StreamRemoteCall exceptionReceivedFromServer
     [java] FINE: main: outbound call received exception: [67.195.138.8:48290] exception: 
     [java] java.rmi.ServerException: RemoteException occurred in server thread; nested exception is: 
     [java] 	java.rmi.RemoteException: Create failed; nested exception is: 
     [java] 	java.lang.reflect.InvocationTargetException
     [java] 	at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:336)
     [java] 	at sun.rmi.transport.Transport$1.run(Transport.java:159)
     [java] 	at java.security.AccessController.doPrivileged(Native Method)
     [java] 	at sun.rmi.transport.Transport.serviceCall(Transport.java:155)
     [java] 	at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:535)
     [java] 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:790)
     [java] 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:649)
     [java] 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
     [java] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
     [java] 	at java.lang.Thread.run(Thread.java:619)
     [java] 	at sun.rmi.transport.StreamRemoteCall.exceptionReceivedFromServer(StreamRemoteCall.java:255)
     [java] 	at sun.rmi.transport.StreamRemoteCall.executeCall(StreamRemoteCall.java:233)
     [java] 	at sun.rmi.server.UnicastRef.invoke(UnicastRef.java:142)
     [java] 	at com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl_Stub.startService(Unknown Source)
     [java] 	at com.sun.jini.qa.harness.NonActivatableServiceStarterAdmin.start(Unknown Source)
     [java] 	at com.sun.jini.qa.harness.AdminManager.startService(Unknown Source)
     [java] 	at com.sun.jini.qa.harness.AdminManager.startLookupService(Unknown Source)
     [java] 	at com.sun.jini.test.share.BaseQATest.startLookup(Unknown Source)
     [java] 	at com.sun.jini.test.share.BaseQATest.startInitLookups(Unknown Source)
     [java] 	at com.sun.jini.test.share.BaseQATest.setup(Unknown Source)
     [java] 	at com.sun.jini.test.spec.discoverymanager.AbstractBaseTest.setup(Unknown Source)
     [java] 	at com.sun.jini.qa.harness.MasterTest.doTest(Unknown Source)
     [java] 	at com.sun.jini.qa.harness.MasterTest.main(Unknown Source)
     [java] Caused by: java.rmi.RemoteException: Create failed; nested exception is: 
     [java] 	java.lang.reflect.InvocationTargetException
     [java] 	at com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl.startService(Unknown Source)
     [java] 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
     [java] 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
     [java] 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
     [java] 	at java.lang.reflect.Method.invoke(Method.java:597)
     [java] 	at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:305)
     [java] 	at sun.rmi.transport.Transport$1.run(Transport.java:159)
     [java] 	at java.security.AccessController.doPrivileged(Native Method)
     [java] 	at sun.rmi.transport.Transport.serviceCall(Transport.java:155)
     [java] 	at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:535)
     [java] 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:790)
     [java] 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:649)
     [java] 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
     [java] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
     [java] 	at java.lang.Thread.run(Thread.java:619)
     [java] Caused by: java.lang.reflect.InvocationTargetException
     [java] 	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
     [java] 	at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
     [java] 	at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
     [java] 	at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
     [java] 	at com.sun.jini.start.NonActivatableServiceDescriptor.create(Unknown Source)
     [java] 	... 15 more
     [java] Caused by: java.net.BindException: Address already in use
     [java] 	at java.net.PlainSocketImpl.socketBind(Native Method)
     [java] 	at java.net.PlainSocketImpl.bind(PlainSocketImpl.java:365)
     [java] 	at java.net.ServerSocket.bind(ServerSocket.java:319)
     [java] 	at java.net.ServerSocket.<init>(ServerSocket.java:185)
     [java] 	at java.net.ServerSocket.<init>(ServerSocket.java:97)
     [java] 	at javax.net.DefaultServerSocketFactory.createServerSocket(ServerSocketFactory.java:157)
     [java] 	at com.sun.jini.reggie.RegistrarImpl$UnicastThread.<init>(Unknown Source)
     [java] 	at com.sun.jini.reggie.RegistrarImpl.init(Unknown Source)
     [java] 	at com.sun.jini.reggie.RegistrarImpl.access$000(Unknown Source)
     [java] 	at com.sun.jini.reggie.RegistrarImpl$1.run(Unknown Source)
     [java] 	at com.sun.jini.reggie.RegistrarImpl.<init>(Unknown Source)
     [java] 	at com.sun.jini.reggie.TransientRegistrarImpl.<init>(Unknown Source)
     [java] 	... 20 more
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastRef invoke
     [java] FINE: main: free connection (reuse = false)
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPChannel free
     [java] FINE: main: close connection
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPConnection close
     [java] FINE: main: close connection
     [java] com.sun.jini.qa.harness.TestException: Problem creating service for net.jini.core.lookup.ServiceRegistrar; nested exception is: 
     [java] 	RemoteException occurred in server thread; nested exception is: 
     [java] 	java.rmi.RemoteException: Create failed; nested exception is: 
     [java] 	java.lang.reflect.InvocationTargetException
     [java] 	at com.sun.jini.qa.harness.NonActivatableServiceStarterAdmin.start(Unknown Source)
     [java] 	at com.sun.jini.qa.harness.AdminManager.startService(Unknown Source)
     [java] 	at com.sun.jini.qa.harness.AdminManager.startLookupService(Unknown Source)
     [java] 	at com.sun.jini.test.share.BaseQATest.startLookup(Unknown Source)
     [java] 	at com.sun.jini.test.share.BaseQATest.startInitLookups(Unknown Source)
     [java] 	at com.sun.jini.test.share.BaseQATest.setup(Unknown Source)
     [java] 	at com.sun.jini.test.spec.discoverymanager.AbstractBaseTest.setup(Unknown Source)
     [java] 	at com.sun.jini.qa.harness.MasterTest.doTest(Unknown Source)
     [java] 	at com.sun.jini.qa.harness.MasterTest.main(Unknown Source)
     [java] Caused by: java.rmi.ServerException: RemoteException occurred in server thread; nested exception is: 
     [java] 	java.rmi.RemoteException: Create failed; nested exception is: 
     [java] 	java.lang.reflect.InvocationTargetException
     [java] 	at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:336)
     [java] 	at sun.rmi.transport.Transport$1.run(Transport.java:159)
     [java] 	at java.security.AccessController.doPrivileged(Native Method)
     [java] 	at sun.rmi.transport.Transport.serviceCall(Transport.java:155)
     [java] 	at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:535)
     [java] 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:790)
     [java] 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:649)
     [java] 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
     [java] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
     [java] 	at java.lang.Thread.run(Thread.java:619)
     [java] 	at sun.rmi.transport.StreamRemoteCall.exceptionReceivedFromServer(StreamRemoteCall.java:255)
     [java] 	at sun.rmi.transport.StreamRemoteCall.executeCall(StreamRemoteCall.java:233)
     [java] 	at sun.rmi.server.UnicastRef.invoke(UnicastRef.java:142)
     [java] 	at com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl_Stub.startService(Unknown Source)
     [java] 	... 9 more
     [java] Caused by: java.rmi.RemoteException: Create failed; nested exception is: 
     [java] 	java.lang.reflect.InvocationTargetException
     [java] 	at com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl.startService(Unknown Source)
     [java] 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
     [java] 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
     [java] 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
     [java] 	at java.lang.reflect.Method.invoke(Method.java:597)
     [java] 	at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:305)
     [java] 	at sun.rmi.transport.Transport$1.run(Transport.java:159)
     [java] 	at java.security.AccessController.doPrivileged(Native Method)
     [java] 	at sun.rmi.transport.Transport.serviceCall(Transport.java:155)
     [java] 	at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:535)
     [java] 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:790)
     [java] 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:649)
     [java] 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
     [java] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
     [java] 	at java.lang.Thread.run(Thread.java:619)
     [java] Caused by: java.lang.reflect.InvocationTargetException
     [java] 	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
     [java] 	at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
     [java] 	at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
     [java] 	at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
     [java] 	at com.sun.jini.start.NonActivatableServiceDescriptor.create(Unknown Source)
     [java] 	... 15 more
     [java] Caused by: java.net.BindException: Address already in use
     [java] 	at java.net.PlainSocketImpl.socketBind(Native Method)
     [java] 	at java.net.PlainSocketImpl.bind(PlainSocketImpl.java:365)
     [java] 	at java.net.ServerSocket.bind(ServerSocket.java:319)
     [java] 	at java.net.ServerSocket.<init>(ServerSocket.java:185)
     [java] 	at java.net.ServerSocket.<init>(ServerSocket.java:97)
     [java] 	at javax.net.DefaultServerSocketFactory.createServerSocket(ServerSocketFactory.java:157)
     [java] 	at com.sun.jini.reggie.RegistrarImpl$UnicastThread.<init>(Unknown Source)
     [java] 	at com.sun.jini.reggie.RegistrarImpl.init(Unknown Source)
     [java] 	at com.sun.jini.reggie.RegistrarImpl.access$000(Unknown Source)
     [java] 	at com.sun.jini.reggie.RegistrarImpl$1.run(Unknown Source)
     [java] 	at com.sun.jini.reggie.RegistrarImpl.<init>(Unknown Source)
     [java] 	at com.sun.jini.reggie.TransientRegistrarImpl.<init>(Unknown Source)
     [java] 	... 20 more
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPTransport handleMessages
     [java] NonActGrp-out: FINE: RMI TCP Connection(1)-67.195.138.8: (port 48290) connection closed
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPConnection close
     [java] NonActGrp-out: FINE: RMI TCP Connection(1)-67.195.138.8: close connection
     [java] MasterTest.doTest INFO: 
     [java] ============================ CALLING TEARDOWN() =============================
     [java] 
     [java] QATest.tearDown FINE: Destroying remaining managed services
     [java] AdminManager.destroyService FINE: destroying service: class com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl_Stub
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastRef invoke
     [java] FINER: main: method: public abstract void com.sun.jini.qa.harness.NonActivatableGroup.stop() throws java.rmi.RemoteException
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastRef logClientCall
     [java] FINER: main: outbound call: [endpoint:[67.195.138.8:48290](remote),objID:[-3b9058b:1291851876c:-7fff, 5108795070014869142]] : com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl_Stub[-3b9058b:1291851876c:-7fff, 5108795070014869142]: public abstract void com.sun.jini.qa.harness.NonActivatableGroup.stop() throws java.rmi.RemoteException
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPChannel createConnection
     [java] FINE: main: create connection
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPEndpoint newSocket
     [java] FINER: main: opening socket to [67.195.138.8:48290]
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.proxy.RMIMasterSocketFactory createSocket
     [java] FINE: main: host: 67.195.138.8, port: 48290
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPTransport$ConnectionHandler run0
     [java] NonActGrp-out: FINE: RMI TCP Connection(2)-67.195.138.8: accepted socket from [67.195.138.8:33820]
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPTransport$ConnectionHandler run0
     [java] NonActGrp-out: FINER: RMI TCP Connection(2)-67.195.138.8: (port 48290) suggesting 67.195.138.8:33820
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPChannel createConnection
     [java] FINER: main: server suggested 67.195.138.8:33820
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPChannel createConnection
     [java] FINER: main: using 67.195.138.8:0
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastRef invoke
     [java] FINER: main: opnum = -2856118408655404442
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.StreamRemoteCall <init>
     [java] FINER: main: write remote call header...
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.StreamRemoteCall getOutputStream
     [java] FINER: main: getting output stream
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPTransport$ConnectionHandler run0
     [java] NonActGrp-out: FINER: RMI TCP Connection(2)-67.195.138.8: (port 48290) client using 67.195.138.8:0
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPTransport handleMessages
     [java] NonActGrp-out: FINE: RMI TCP Connection(2)-67.195.138.8: (port 48290) op = 80
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.StreamRemoteCall getInputStream
     [java] NonActGrp-out: FINER: RMI TCP Connection(2)-67.195.138.8: getting input stream
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.Transport serviceCall
     [java] NonActGrp-out: FINER: RMI TCP Connection(2)-67.195.138.8: call dispatcher
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastServerRef logCall
     [java] NonActGrp-out: FINER: RMI TCP Connection(2)-67.195.138.8: [67.195.138.8: com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl[-3b9058b:1291851876c:-7fff, 5108795070014869142]: public abstract void com.sun.jini.qa.harness.NonActivatableGroup.stop() throws java.rmi.RemoteException]
     [java] NonActGrp-out: Jun 8, 2010 4:08:03 PM sun.rmi.transport.StreamRemoteCall getOutputStream
     [java] NonActGrp-out: FINER: RMI TCP Connection(2)-67.195.138.8: getting output stream
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.StreamRemoteCall getInputStream
     [java] FINER: main: getting input stream
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.server.UnicastRef invoke
     [java] FINE: main: free connection (reuse = true)
     [java] Jun 8, 2010 4:08:03 PM sun.rmi.transport.tcp.TCPChannel free
     [java] FINE: main: reuse connection
     [java] NonActGrp-out: Jun 8, 2010 4:08:05 PM sun.rmi.transport.ObjectTable removeTarget
     [java] NonActGrp-out: FINER: destroy: remove object [-3b9058b:1291851876c:-7fff, 5108795070014869142]
     [java] AdminManager.destroyService FINE: destroying service: class com.sun.jini.tool.ClassServer
     [java] Jun 8, 2010 4:08:06 PM com.sun.jini.tool.ClassServer terminate
     [java] INFO: ClassServer terminated [port 8080]
     [java] AdminManager.destroyService FINE: destroying service: class com.sun.jini.tool.ClassServer
     [java] Jun 8, 2010 4:08:06 PM com.sun.jini.tool.ClassServer terminate
     [java] INFO: ClassServer terminated [port 8080]
     [java] Jun 8, 2010 4:08:06 PM com.sun.jini.tool.ClassServer terminate
     [java] INFO: ClassServer terminated [port 8082]
     [java] AdminManager.destroyService FINE: destroying service: class com.sun.jini.tool.ClassServer
     [java] Jun 8, 2010 4:08:06 PM com.sun.jini.tool.ClassServer terminate
     [java] INFO: ClassServer terminated [port 8082]
     [java] Jun 8, 2010 4:08:06 PM com.sun.jini.tool.ClassServer terminate
     [java] INFO: ClassServer terminated [port 8081]
     [java] Jun 8, 2010 4:08:06 PM com.sun.jini.tool.ClassServer terminate
     [java] INFO: ClassServer terminated [port 8081]
     [java] 
     [java] TIME: 4:08:06 PM
     [java] 
     [java] Test process was destroyed and returned code 1
     [java] com/sun/jini/test/impl/discoverymanager/RemoveGroupsLocsDiscard.td
     [java] Test Failed: Setup Failed: com.sun.jini.qa.harness.TestException: Problem creating service for net.jini.core.lookup.ServiceRegistrar; nested exception is: 
     [java] 	RemoteException occurred in server thread; nested exception is: 
     [java] 	java.rmi.RemoteException: Create failed; nested exception is: 
     [java] 	java.lang.reflect.InvocationTargetException
     [java] 
     [java] 
     [java] -----------------------------------------



Peter Firmstone wrote:
> Hey Thanks Chris,  Awesome to see some contribution.
>
> I committed something very similar last night, it could use some 
> refactoring too, the only difference from what you have here is the 
> TaskThread creates the thread in its constructor, but it doesn't start 
> the thread in the constructor.  I added an additional method 
> TaskTread.start() to start the thread.  It avoids the null reference 
> and thread could be final, can't remember if I made the Thread 
> reference final or not.
>
> Oddly enough though , Hudson failed shortly after, so I'll need to 
> investigate why, here's the Hudson web site:
>
> http://hudson.zones.apache.org/hudson/view/River/job/River-trunk/
>
> Cheers,
>
> Peter.
>
> Christopher Dolan wrote:
>> OK, sorry for the repeated messages. The list apparently doesn't allow
>> attachments...
>>
>> --- TaskManager.java    2010-03-02 11:49:30.629703600 -0600
>> +++ TaskManager2.java    2010-06-08 11:11:54.309412900 -0500
>> @@ -190,10 +190,13 @@
>>      logAdd(t);  //DAS 6/06 add
>>      boolean poke = true;
>>      while (threads.size() < maxThreads && needThread()) {
>> -        Thread th;
>> +        TaskThread th;
>>          try {
>>          th = new TaskThread();
>> -        th.start();
>> +        Thread thread = new Thread(new TaskThread());
>> +        thread.setName("task");
>> +        thread.setDaemon(true);
>> +        thread.start();
>>          } catch (Throwable tt) {
>>          try {
>>              logger.log(threads.isEmpty() ?
>> @@ -290,8 +293,8 @@
>>              for (int j = threads.size(); --j >= 0; ) {
>>              TaskThread thread = (TaskThread)threads.get(j);
>>              if (thread.task == t) {
>> -                if (thread != Thread.currentThread())
>> -                thread.interrupt();
>> +                if (thread.myThread !=
>> Thread.currentThread())
>> +                thread.myThread.interrupt();
>>                  break;
>>              }
>>              }
>> @@ -327,15 +330,11 @@
>>      return maxThreads;
>>      }
>>  
>> -    private class TaskThread extends Thread {
>> +    private class TaskThread implements Runnable {
>>  
>>      /** The task being run, if any */
>>      public Task task = null;
>> -
>> -    public TaskThread() {
>> -        super("task");
>> -        setDaemon(true);
>> -    }
>> +    public Thread myThread = null;
>>  
>>      /**
>>       * Find the next task that can be run, and mark it taken by
>> @@ -363,8 +362,9 @@
>>      }
>>  
>>      public void run() {
>> +        myThread = Thread.currentThread();
>>          while (true) {
>>          synchronized (TaskManager.this) {
>>              if (terminated)
>>              return;
>>              if (task != null) {
>> @@ -376,11 +376,11 @@
>>                  }
>>              }
>>              task = null;
>> -            interrupted(); // clear interrupt bit
>> +            myThread.interrupted(); // clear interrupt bit
>>              }
>>              if (!takeTask()) {
>>              try {
>>                  TaskManager.this.wait(timeout);
>>              } catch (InterruptedException e) {
>>              }
>>              if (terminated || !takeTask()) {
>>
>>
>> -----Original Message-----
>> From: Christopher Dolan [mailto:christopher.dolan@avid.com] Sent: 
>> Tuesday, June 08, 2010 11:13 AM
>> To: river-dev@incubator.apache.org
>> Subject: RE: com.sun.jini.thread lock contention
>>
>> Oops, let me try that patch one more time...
>> Chris
>>
>>
>> -----Original Message-----
>> From: Christopher Dolan Sent: Tuesday, June 08, 2010 11:11 AM
>> To: 'river-dev@incubator.apache.org'
>> Subject: RE: com.sun.jini.thread lock contention
>>
>> Attached is a minimalist initial patch.  I'm not happy that I had to
>> store a reference to the thread in the Runnable...  But otherwise I
>> wasn't sure how to interrupt it from removeTask() without a separate map
>> of TaskThread to Thread.  There's all a null pointer race in this patch
>> if you try to remove a task before it's run() method executes.
>>
>> Chris
>>
>>   
>
>



Mime
View raw message