Buildagent fails to start on Solaris10 sparc #823

kwiatek · 2 decades ago

Hi, I need help with buildagent for quickbuild 2.0.16 - the startup script stops responding when starting the framework. The agent runs on SunOS lonespdapp-zd1 5.10 Generic_138888-01 sun4u sparc SUNW,Sun-Fire-V440 and the server is on a Windows box. I have changed all the log topics to TRACE in log4j.properties but the only information I am getting is:


Running QuickBuild Build Agent...
wrapper  | --> Wrapper Started as Console
wrapper  | Java Service Wrapper Standard Edition 32-bit 3.3.5
wrapper  |   Copyright (C) 1999-2009 Tanuki Software, Ltd.  All Rights Reserved.
wrapper  |     http://wrapper.tanukisoftware.org
wrapper  |   Licensed to PMEase Inc. for QuickBuild
wrapper  | 
wrapper  | Launching a JVM...
jvm 1    | WrapperManager: Initializing...
jvm 1    | 2010-02-02 16:13:13,984 INFO  - Connecting to server 'http://ukesp156:8810'...
jvm 1    | 2010-02-02 16:13:14,210 TRACE - Connecting to 'http://ukesp156:8810/service/connect'...
jvm 1    | 2010-02-02 16:13:14,352 INFO  - Updating agent framework...
jvm 1    | 2010-02-02 16:13:14,352 TRACE - Connecting to 'http://ukesp156:8810/service/server'...
jvm 1    | 2010-02-02 16:13:14,372 INFO  - Downloading new bundle 'org.eclipse.equinox.http.servlet_1.0.200.v20090520-1800.jar'...
jvm 1    | 2010-02-02 16:13:14,396 INFO  - Downloading new bundle 'org.eclipse.osgi.services_3.2.0.v20090520-1800.jar'...
jvm 1    | 2010-02-02 16:13:14,412 INFO  - Downloading new bundle 'org.mortbay.jetty.util_6.1.15.v200905182336.jar'...
jvm 1    | 2010-02-02 16:13:14,442 INFO  - Downloading new bundle 'org.mortbay.jetty.server_6.1.15.v200905151201.jar'...
jvm 1    | 2010-02-02 16:13:14,502 INFO  - Downloading new bundle 'org.eclipse.equinox.http.jetty_2.0.0.v20090520-1800.jar'...
jvm 1    | 2010-02-02 16:13:14,514 INFO  - Downloading new bundle 'org.eclipse.osgi_3.5.0.v20090520.jar'...
jvm 1    | 2010-02-02 16:13:14,636 INFO  - Downloading new bundle 'com.pmease.quickbuild.jetty_1.0.4.jar'...
jvm 1    | 2010-02-02 16:13:14,644 INFO  - Downloading new bundle 'com.pmease.quickbuild_2.0.49.jar'...
jvm 1    | 2010-02-02 16:13:16,625 INFO  - Updating agent plugins...
jvm 1    | 2010-02-02 16:13:16,626 TRACE - Connecting to 'http://ukesp156:8810/service/server'...
jvm 1    | 2010-02-02 16:13:16,640 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.scm.perforce_1.0.8.jar'...
jvm 1    | 2010-02-02 16:13:16,683 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.scm.accurev_1.0.8.jar'...
jvm 1    | 2010-02-02 16:13:16,697 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.report.findbugs_1.0.7.jar'...
jvm 1    | 2010-02-02 16:13:16,708 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.basis_1.0.14.jar'...
jvm 1    | 2010-02-02 16:13:16,772 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.report.cobertura_1.0.9.jar'...
jvm 1    | 2010-02-02 16:13:16,781 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.notifier.email_1.0.5.jar'...
jvm 1    | 2010-02-02 16:13:16,790 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.scm.vss_1.0.5.jar'...
jvm 1    | 2010-02-02 16:13:16,804 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.authenticator.ldap_1.0.5.jar'...
jvm 1    | 2010-02-02 16:13:16,814 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.notifier.jabber_1.0.4.jar'...
jvm 1    | 2010-02-02 16:13:16,901 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.htmlreport_1.0.8.jar'...
jvm 1    | 2010-02-02 16:13:16,911 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.report.mbunit_1.0.10.jar'...
jvm 1    | 2010-02-02 16:13:16,921 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.report.junit_1.0.13.jar'...
jvm 1    | 2010-02-02 16:13:16,931 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.scm.subversion_1.0.11.jar'...
jvm 1    | 2010-02-02 16:13:16,944 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.report.changes_1.0.9.jar'...
jvm 1    | 2010-02-02 16:13:16,962 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.builder.nant_1.0.7.jar'...
jvm 1    | 2010-02-02 16:13:16,971 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.builder.maven_1.0.9.jar'...
jvm 1    | 2010-02-02 16:13:16,980 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.builder.ant_1.0.7.jar'...
jvm 1    | 2010-02-02 16:13:16,988 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.notifier.msn_1.0.4.jar'...
jvm 1    | 2010-02-02 16:13:17,041 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.scm.clearcase_1.0.6.jar'...
jvm 1    | 2010-02-02 16:13:17,057 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.builder.rake_1.0.7.jar'...
jvm 1    | 2010-02-02 16:13:17,066 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.scm.filesystem_1.0.4.jar'...
jvm 1    | 2010-02-02 16:13:17,075 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.scm.cvs_1.0.9.jar'...
jvm 1    | 2010-02-02 16:13:17,088 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.artifact_1.0.9.jar'...
jvm 1    | 2010-02-02 16:13:17,099 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.scm.starteam_1.0.6.jar'...
jvm 1    | 2010-02-02 16:13:17,118 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.report.pmd_1.0.8.jar'...
jvm 1    | 2010-02-02 16:13:17,126 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.report.buildstats_1.0.4.jar'...
jvm 1    | 2010-02-02 16:13:17,143 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.report.engine_1.0.23.jar'...
jvm 1    | 2010-02-02 16:13:17,276 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.report.checkstyle_1.0.7.jar'...
jvm 1    | 2010-02-02 16:13:17,285 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.report.testng_1.0.10.jar'...
jvm 1    | 2010-02-02 16:13:17,295 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.report.nunit_1.0.10.jar'...
jvm 1    | 2010-02-02 16:13:17,304 INFO  - Downloading new bundle 'com.pmease.quickbuild.plugin.report.emma_1.0.7.jar'...
jvm 1    | 2010-02-02 16:13:17,312 INFO  - Updating libs...
jvm 1    | 2010-02-02 16:13:17,313 TRACE - Connecting to 'http://ukesp156:8810/service/server'...
jvm 1    | 2010-02-02 16:13:17,318 INFO  - Downloading new lib 'jtds-1.2.2.jar'...
jvm 1    | 2010-02-02 16:13:17,360 INFO  - Updating misc files...
jvm 1    | 2010-02-02 16:13:17,360 TRACE - Connecting to 'http://ukesp156:8810/service/server'...
jvm 1    | 2010-02-02 16:13:17,365 INFO  - Downloading new misc file 'batch-download.zip'...
jvm 1    | 2010-02-02 16:13:17,372 INFO  - Downloading new misc file 'release'...
jvm 1    | 2010-02-02 16:13:17,378 INFO  - Booting application from '/home/mkwiatkowski/buildagent-sol32-sparc/buildagent'...
jvm 1    | 2010-02-02 16:13:17,388 INFO  - Starting framework...
^Cwrapper  | INT trapped.  Shutting down.
wrapper  | Shutdown failed: Timed out waiting for the JVM to terminate.
wrapper  | JVM did not exit on request, terminated
wrapper  | <-- Wrapper Stopped
[lonespdapp-zd1] /home/mkwiatkowski/buildagent-sol32-sparc/buildagent/bin> java -version
java version "1.6.0_02"
Java(TM) SE Runtime Environment (build 1.6.0_02-b05)
Java HotSpot(TM) Server VM (build 1.6.0_02-b05, mixed mode)
[lonespdapp-zd1] /home/mkwiatkowski/buildagent-sol32-sparc/buildagent/bin>

Note how the JVM fails to terminate cleanly. Thanks in advance for any help...

replies 19
views 6325
stars 0

robinshen ADMIN · 2 decades ago

I see a ^C after message "jvm 1 | 2010-02-02 16:13:17,388 INFO - Starting framework...".
Does this mean that QuickBuild agent hangs at this point and then you pressed "ctrl-c" trying to terminate it?

The latter message should be OK since at this time, the framework is not loaded and not prepared to be shutdown.

kwiatek · 2 decades ago

Yes, I killed it with Ctrl+C - the "Starting framework" step took 15 mins while on other platforms I tried it is usually few seconds...

robinshen ADMIN · 2 decades ago

This is odd. Just for testing purpose, are you able to run QB server from this machine? And can you start the same agent on other sparc machines?

kwiatek · 2 decades ago

I've just tried QB 2.0.16 and no - it fails to start up too:


Running QuickBuild Server...
wrapper  | --> Wrapper Started as Console
wrapper  | Java Service Wrapper Standard Edition 32-bit 3.3.5
wrapper  |   Copyright (C) 1999-2009 Tanuki Software, Ltd.  All Rights Reserved.
wrapper  |     http://wrapper.tanukisoftware.org
wrapper  |   Licensed to PMEase Inc. for QuickBuild
wrapper  | 
wrapper  | Launching a JVM...
jvm 1    | WrapperManager: Initializing...
^Cwrapper  | INT trapped.  Shutting down.
^Cwrapper  | INT trapped.  Forcing immediate shutdown.
wrapper  | JVM did not exit on request, terminated
wrapper  | JVM received a signal SIGKILL (9).
wrapper  | <-- Wrapper Stopped
[lonespdapp-zd1] /home/mkwiatkowski/quickbuild-2.0.16-solaris-sparc-32/bin> java -version
java version "1.6.0_02"
Java(TM) SE Runtime Environment (build 1.6.0_02-b05)
Java HotSpot(TM) Server VM (build 1.6.0_02-b05, mixed mode)

It works fine on both Sol8 sparc and Sol10 x86 (SunOS whale 5.8 Generic_117350-50 sun4u sparc SUNW,Sun-Fire-880 and SunOS lonespdgw-d1 5.10 Generic_138889-01 i86pc i386 i86pc)

robinshen ADMIN · 2 decades ago

Have never experienced this problem before. It seems that equinox (OSGi framework used in Eclipse) won't start. How about trying with latest JDK (1.6 update 18) or with JDK 1.5?

kwiatek · 2 decades ago

I get the same problem with jdk 1.5. Did not have a chance to try 1.6 u18 yet...

kwiatek · 2 decades ago

Hi, sorry for the delay. I have just tried the buildagent with jdk 1.6.0_18 and it still fails to start up. It is likely the problem is with our server however we need someone to help us debug it as we can't find anything in the logs no matter what the log level we set for log4j. We are considering buying a comercial license for around 10 buildagents and one server, unfortunately this problem prevents us from moving forward. Thanks

robinshen ADMIN · 2 decades ago

We will add more debugging code to see if there is any clue.

#10

robinshen ADMIN · 2 decades ago

Can you please test to see if QuickBuild server has the same problem on this machine?

#11

kwiatek · 2 decades ago

QuickBuild server fails to start up at an earlier stage (I tried different java versions too) - it hangs while initialising the wrapper:

[lonespdapp-zd1] /home/mkwiatkowski/quickbuild-2.1.16-solaris-sparc-32/bin> server.sh console
Running QuickBuild Server...
wrapper  | --> Wrapper Started as Console
wrapper  | Java Service Wrapper Standard Edition 32-bit 3.3.9
wrapper  |   Copyright (C) 1999-2009 Tanuki Software, Ltd.  All Rights Reserved.
wrapper  |     http://wrapper.tanukisoftware.org
wrapper  |   Licensed to PMEase Inc. for QuickBuild
wrapper  | 
wrapper  | Launching a JVM...
jvm 1    | WrapperManager: Initializing...

There is nothing in the logsand top shows minimal procesor load:

   PID USERNAME LWP PRI NICE  SIZE   RES STATE    TIME    CPU COMMAND
 14462 mkwiatko   1  59    0 5400K 4072K cpu      0:00  0.36% top
 13856 mkwiatko  15  59    0  224M   43M sleep    0:01  0.12% java
 13855 mkwiatko   2  59    0 3672K 2248K sleep    0:00  0.07% wrapper-solaris

#12

robinshen ADMIN · 2 decades ago

Are you able to find a log file "wrapper.log" under the "bin" directory? Also please make sure to edit file "conf/wrapper.conf" and define property "wrapper.java.command" like below:
/path/to/jdk1.6.0_18/bin/java

If you still can not get through, please download JSW (the binary wrapper used by QuickBuild) from "http://wrapper.tanukisoftware.org/doc/english/download.jsp" (select community edition with sparc 32 platform), and run file "bin/testwrapper" just to see if it works.

#13

kwiatek · 2 decades ago

The wrapper.log file does not have anything else than what I get on the console output. Yes, I tried paths to different java versions in the wrapper.conf.

The testwrapper looks good - here is the output after giving it a few commands to run:


Running Test Wrapper Sample Application with test action: ...
wrapper  | --> Wrapper Started as Console
wrapper  | Java Service Wrapper Community Edition 32-bit 3.3.9
wrapper  |   Copyright (C) 1999-2009 Tanuki Software, Ltd.  All Rights Reserved.
wrapper  |     http://wrapper.tanukisoftware.org
wrapper  | 
wrapper  | Launching a JVM...
jvm 1    | TestWrapper: Initializing...
jvm 1    | WrapperManager: Initializing...
jvm 1    | TestWrapper: start()
jvm 1    | TestWrapper: An action was not specified.  Default to "dialog".  Use "help" for list of actions.
jvm 1    | TestWrapper: ActionServer Enabled. 
jvm 1    | TestWrapper:   Telnet localhost 9999
jvm 1    | TestWrapper:   Commands: 
jvm 1    | TestWrapper:     S: Shutdown
jvm 1    | TestWrapper:     H: Expected Halt
jvm 1    | TestWrapper:     R: Restart
jvm 1    | TestWrapper:     D: Thread Dump
jvm 1    | TestWrapper:     U: Unexpected Halt (Simulate crash)
jvm 1    | TestWrapper:     V: Access Violation (Actual crash)
jvm 1    | TestWrapper:     G: Make the JVM appear to be hung.
jvm 1    | TestWrapper:
jvm 1    | TestWrapper: Showing dialog...
help
jvm 1    | 
jvm 1    | Start prompting for actions.
jvm 1    | Input an action ('help' for a list of actions):
jvm 1    | Read action: help
jvm 1    | 
jvm 1    | [ACTIONS]
jvm 1    |    help                     : Shows this help message
jvm 1    |   Actions which should cause the Wrapper to exit cleanly:
jvm 1    |    stop0                    : Calls WrapperManager.stop(0)
jvm 1    |    exit0                    : Calls System.exit(0)
jvm 1    |    stopimmediate0           : Calls WrapperManager.stopImmediate(0)
jvm 1    |    stopandreturn0           : Calls WrapperManager.stopAndReturn(0)
jvm 1    |   Actions which should cause the Wrapper to exit in an error state:
jvm 1    |    stop1                    : Calls WrapperManager.stop(1)
jvm 1    |    exit1                    : Calls System.exit(1)
jvm 1    |    nestedexit1              : Calls System.exit(1) within WrapperListener.stop(1) callback
jvm 1    |    stopimmediate1           : Calls WrapperManager.stopImmediate(1)
jvm 1    |   Actions which should cause the Wrapper to restart the JVM:
jvm 1    |    access_violation         : Calls WrapperManager.accessViolation
jvm 1    |    access_violation_native  : Calls WrapperManager.accessViolationNative()
jvm 1    |    appear_hung              : Calls WrapperManager.appearHung()
jvm 1    |    halt0                    : Calls Runtime.getRuntime().halt(0)
jvm 1    |    halt1                    : Calls Runtime.getRuntime().halt(1)
jvm 1    |    restart                  : Calls WrapperManager.restart()
jvm 1    |    restartandreturn         : Calls WrapperManager.restartAndReturn()
jvm 1    |   Additional Tests:
jvm 1    |    ignore_events            : Makes this application ignore control events.
jvm 1    |    dump                     : Calls WrapperManager.requestThreadDump()
jvm 1    |    deadlock_out             : Deadlocks the JVM's System.out and err streams.
jvm 1    |    users                    : Start polling the current and interactive users.
jvm 1    |    groups                   : Start polling the current and interactive users with groups.
jvm 1    |    console                  : Prompt for actions in the console.
jvm 1    |    idle                     : Do nothing just run in idle mode.
jvm 1    |    properties               : Dump all System Properties to the console.
jvm 1    |    configuration            : Dump all Wrapper Configuration Properties to the console.
jvm 1    |    gc                       : Perform a GC sweep.
jvm 1    | 
jvm 1    | Input an action ('help' for a list of actions):
jvm 1    | Begin GC...
jvm 1    | GC complete.
jvm 1    | 2010-03-08 14:14:47
jvm 1    | Full thread dump Java HotSpot(TM) Server VM (1.6.0_02-b05 mixed mode):
jvm 1    | 
jvm 1    | "console-runner" daemon prio=10 tid=0x000e2000 nid=0x17 runnable [0xb6a7f000..0xb6a7f870]
jvm 1    |    java.lang.Thread.State: RUNNABLE
jvm 1    |      at java.io.FileInputStream.readBytes(Native Method)
jvm 1    |      at java.io.FileInputStream.read(FileInputStream.java:199)
jvm 1    |      at java.io.BufferedInputStream.read1(BufferedInputStream.java:256)
jvm 1    |      at java.io.BufferedInputStream.read(BufferedInputStream.java:317)
jvm 1    |      - locked <0xbbc02538> (a java.io.BufferedInputStream)
jvm 1    |      at sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:264)
jvm 1    |      at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:306)
jvm 1    |      at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:158)
jvm 1    |      - locked <0xbbc025c0> (a java.io.InputStreamReader)
jvm 1    |      at java.io.InputStreamReader.read(InputStreamReader.java:167)
jvm 1    |      at java.io.BufferedReader.fill(BufferedReader.java:136)
jvm 1    |      at java.io.BufferedReader.readLine(BufferedReader.java:299)
jvm 1    |      - locked <0xbbc025c0> (a java.io.InputStreamReader)
jvm 1    |      at java.io.BufferedReader.readLine(BufferedReader.java:362)
jvm 1    |      at org.tanukisoftware.wrapper.test.AbstractActionApp$2.run(AbstractActionApp.java:364)
jvm 1    | 
jvm 1    | "AWT-EventQueue-0" prio=10 tid=0x0030a400 nid=0x16 in Object.wait() [0xb5f7f000..0xb5f7fbf0]
jvm 1    |    java.lang.Thread.State: WAITING (on object monitor)
jvm 1    |      at java.lang.Object.wait(Native Method)
jvm 1    |      - waiting on <0xbbc16e88> (a java.awt.EventQueue)
jvm 1    |      at java.lang.Object.wait(Object.java:485)
jvm 1    |      at java.awt.EventQueue.getNextEvent(EventQueue.java:479)
jvm 1    |      - locked <0xbbc16e88> (a java.awt.EventQueue)
jvm 1    |      at java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:245)
jvm 1    |      at java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:183)
jvm 1    |      at java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:173)
jvm 1    |      at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:168)
jvm 1    |      at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:160)
jvm 1    |      at java.awt.EventDispatchThread.run(EventDispatchThread.java:121)
jvm 1    | 
jvm 1    | "AWT-Shutdown" prio=10 tid=0x00309400 nid=0x15 in Object.wait() [0xb607f000..0xb607fb70]
jvm 1    |    java.lang.Thread.State: WAITING (on object monitor)
jvm 1    |      at java.lang.Object.wait(Native Method)
jvm 1    |      - waiting on <0xbbc03a40> (a java.lang.Object)
jvm 1    |      at java.lang.Object.wait(Object.java:485)
jvm 1    |      at sun.awt.AWTAutoShutdown.run(AWTAutoShutdown.java:259)
jvm 1    |      - locked <0xbbc03a40> (a java.lang.Object)
jvm 1    |      at java.lang.Thread.run(Thread.java:619)
jvm 1    | 
jvm 1    | "AWT-XAWT" daemon prio=10 tid=0x002f5c00 nid=0x14 runnable [0xb617f000..0xb617faf0]
jvm 1    |    java.lang.Thread.State: RUNNABLE
jvm 1    |      at sun.awt.X11.XToolkit.waitForEvents(Native Method)
jvm 1    |      at sun.awt.X11.XToolkit.run(XToolkit.java:544)
jvm 1    |      at sun.awt.X11.XToolkit.run(XToolkit.java:519)
jvm 1    |      at java.lang.Thread.run(Thread.java:619)
jvm 1    | 
jvm 1    | "Java2D Disposer" daemon prio=10 tid=0x001db000 nid=0x13 in Object.wait() [0xb627f000..0xb627fa70]
jvm 1    |    java.lang.Thread.State: WAITING (on object monitor)
jvm 1    |      at java.lang.Object.wait(Native Method)
jvm 1    |      - waiting on <0xbbc0d1e8> (a java.lang.ref.ReferenceQueue$Lock)
jvm 1    |      at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
jvm 1    |      - locked <0xbbc0d1e8> (a java.lang.ref.ReferenceQueue$Lock)
jvm 1    |      at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)
jvm 1    |      at sun.java2d.Disposer.run(Disposer.java:125)
jvm 1    |      at java.lang.Thread.run(Thread.java:619)
jvm 1    | 
jvm 1    | "WrapperActionServer_runner" daemon prio=10 tid=0x001b9800 nid=0x12 runnable [0xb697f000..0xb697f9f0]
jvm 1    |    java.lang.Thread.State: RUNNABLE
jvm 1    |      at java.net.PlainSocketImpl.socketAccept(Native Method)
jvm 1    |      at java.net.PlainSocketImpl.accept(PlainSocketImpl.java:384)
jvm 1    |      - locked <0xbbc03660> (a java.net.SocksSocketImpl)
jvm 1    |      at java.net.ServerSocket.implAccept(ServerSocket.java:453)
jvm 1    |      at java.net.ServerSocket.accept(ServerSocket.java:421)
jvm 1    |      at org.tanukisoftware.wrapper.WrapperActionServer.run(WrapperActionServer.java:168)
jvm 1    |      at java.lang.Thread.run(Thread.java:619)
jvm 1    | 
jvm 1    | "DestroyJavaVM" prio=10 tid=0x00041800 nid=0x2 waiting on condition [0x00000000..0xfe5ffb58]
jvm 1    |    java.lang.Thread.State: RUNNABLE
jvm 1    | 
jvm 1    | "Wrapper-Connection" daemon prio=10 tid=0x0024cc00 nid=0x10 runnable [0xb6b7f000..0xb6b7f8f0]
jvm 1    |    java.lang.Thread.State: RUNNABLE
jvm 1    |      at java.net.SocketInputStream.socketRead0(Native Method)
jvm 1    |      at java.net.SocketInputStream.read(SocketInputStream.java:129)
jvm 1    |      at java.net.SocketInputStream.read(SocketInputStream.java:182)
jvm 1    |      at java.io.DataInputStream.readByte(DataInputStream.java:248)
jvm 1    |      at org.tanukisoftware.wrapper.WrapperManager.handleSocket(WrapperManager.java:4258)
jvm 1    |      at org.tanukisoftware.wrapper.WrapperManager.run(WrapperManager.java:4619)
jvm 1    |      at java.lang.Thread.run(Thread.java:619)
jvm 1    | 
jvm 1    | "Wrapper-Control-Event-Monitor" daemon prio=10 tid=0x0021c400 nid=0xe waiting on condition [0xb6d7f000..0xb6d7fbf0]
jvm 1    |    java.lang.Thread.State: TIMED_WAITING (sleeping)
jvm 1    |      at java.lang.Thread.sleep(Native Method)
jvm 1    |      at org.tanukisoftware.wrapper.WrapperManager$3.run(WrapperManager.java:833)
jvm 1    | 
jvm 1    | "WrapperActionTest_Runner" daemon prio=10 tid=0x0015b400 nid=0xd in Object.wait() [0xb6e7f000..0xb6e7fb70]
jvm 1    |    java.lang.Thread.State: TIMED_WAITING (on object monitor)
jvm 1    |      at java.lang.Object.wait(Native Method)
jvm 1    |      - waiting on <0xb7e007f8> (a java.lang.Class for org.tanukisoftware.wrapper.test.AbstractActionApp)
jvm 1    |      at org.tanukisoftware.wrapper.test.AbstractActionApp$1.run(AbstractActionApp.java:89)
jvm 1    |      - locked <0xb7e007f8> (a java.lang.Class for org.tanukisoftware.wrapper.test.AbstractActionApp)
jvm 1    | 
jvm 1    | "Low Memory Detector" daemon prio=10 tid=0x00147000 nid=0xb runnable [0x00000000..0x00000000]
jvm 1    |    java.lang.Thread.State: RUNNABLE
jvm 1    | 
jvm 1    | "CompilerThread1" daemon prio=10 tid=0x00144c00 nid=0xa waiting on condition [0x00000000..0xb717ee68]
jvm 1    |    java.lang.Thread.State: RUNNABLE
jvm 1    | 
jvm 1    | "CompilerThread0" daemon prio=10 tid=0x00143800 nid=0x9 waiting on condition [0x00000000..0xb727ede8]
jvm 1    |    java.lang.Thread.State: RUNNABLE
jvm 1    | 
jvm 1    | "Signal Dispatcher" daemon prio=10 tid=0x00142800 nid=0x8 waiting on condition [0x00000000..0x00000000]
jvm 1    |    java.lang.Thread.State: RUNNABLE
jvm 1    | 
jvm 1    | "Finalizer" daemon prio=10 tid=0x0012d800 nid=0x7 in Object.wait() [0xb747f000..0xb747f870]
jvm 1    |    java.lang.Thread.State: WAITING (on object monitor)
jvm 1    |      at java.lang.Object.wait(Native Method)
jvm 1    |      - waiting on <0xbbc596f0> (a java.lang.ref.ReferenceQueue$Lock)
jvm 1    |      at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
jvm 1    |      - locked <0xbbc596f0> (a java.lang.ref.ReferenceQueue$Lock)
jvm 1    |      at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)
jvm 1    |      at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)
jvm 1    | 
jvm 1    | "Reference Handler" daemon prio=10 tid=0x0012cc00 nid=0x6 in Object.wait() [0xb757f000..0xb757fbf0]
jvm 1    |    java.lang.Thread.State: WAITING (on object monitor)
jvm 1    |      at java.lang.Object.wait(Native Method)
jvm 1    |      - waiting on <0xbbc02778> (a java.lang.ref.Reference$Lock)
jvm 1    |      at java.lang.Object.wait(Object.java:485)
jvm 1    |      at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
jvm 1    |      - locked <0xbbc02778> (a java.lang.ref.Reference$Lock)
jvm 1    | 
jvm 1    | "VM Thread" prio=10 tid=0x0012a000 nid=0x5 runnable 
jvm 1    | 
jvm 1    | "GC task thread#0 (ParallelGC)" prio=10 tid=0x00047000 nid=0x3 runnable 
jvm 1    | 
jvm 1    | "GC task thread#1 (ParallelGC)" prio=10 tid=0x00048000 nid=0x4 runnable 
jvm 1    | 
jvm 1    | "VM Periodic Task Thread" prio=10 tid=0x00148400 nid=0xc waiting on condition 
jvm 1    | 
jvm 1    | JNI global references: 1172
jvm 1    | 
jvm 1    | Heap
jvm 1    |  PSYoungGen      total 39424K, used 2703K [0xe6800000, 0xe9400000, 0xfc000000)
jvm 1    |   eden space 33792K, 8% used [0xe6800000,0xe6aa3df8,0xe8900000)
jvm 1    |   from space 5632K, 0% used [0xe8900000,0xe8900000,0xe8e80000)
jvm 1    |   to   space 5632K, 0% used [0xe8e80000,0xe8e80000,0xe9400000)
jvm 1    |  PSOldGen        total 90112K, used 1023K [0xbbc00000, 0xc1400000, 0xe6800000)
jvm 1    |   object space 90112K, 1% used [0xbbc00000,0xbbcffdb8,0xc1400000)
jvm 1    |  PSPermGen       total 20480K, used 8715K [0xb7c00000, 0xb9000000, 0xbbc00000)
jvm 1    |   object space 20480K, 42% used [0xb7c00000,0xb8482ed8,0xb9000000)
jvm 1    | 
jvm 1    | Run idle.
jvm 1    | Dump System Properties:
jvm 1    |   java.runtime.name=Java(TM) SE Runtime Environment
jvm 1    |   sun.boot.library.path=/usr/jdk/instances/jdk1.6.0_02/jre/lib/sparc
jvm 1    |   java.vm.version=1.6.0_02-b05
jvm 1    |   java.vm.vendor=Sun Microsystems Inc.
jvm 1    |   java.vendor.url=http://java.sun.com/
jvm 1    |   path.separator=:
jvm 1    |   java.vm.name=Java HotSpot(TM) Server VM
jvm 1    |   file.encoding.pkg=sun.io
jvm 1    |   sun.java.launcher=SUN_STANDARD
jvm 1    |   sun.os.patch.level=unknown
jvm 1    |   wrapper.key=moU_NWS5eZHx3BL-
jvm 1    |   java.vm.specification.name=Java Virtual Machine Specification
jvm 1    |   user.dir=/home/mkwiatkowski/tmp/wrapper-solaris-sparc-32-3.3.9/bin
jvm 1    |   wrapper.version=3.3.9
jvm 1    |   java.runtime.version=1.6.0_02-b05
jvm 1    |   java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment
jvm 1    |   java.endorsed.dirs=/usr/jdk/instances/jdk1.6.0_02/jre/lib/endorsed
jvm 1    |   os.arch=sparc
jvm 1    |   java.io.tmpdir=/var/tmp/
jvm 1    |   line.separator=
jvm 1    | 
jvm 1    |   java.vm.specification.vendor=Sun Microsystems Inc.
jvm 1    |   wrapper.cpu.timeout=10
jvm 1    |   os.name=SunOS
jvm 1    |   sun.jnu.encoding=ISO646-US
jvm 1    |   java.library.path=../lib
jvm 1    |   wrapper.jvm.port.max=31999
jvm 1    |   java.specification.name=Java Platform API Specification
jvm 1    |   java.class.version=50.0
jvm 1    |   sun.management.compiler=HotSpot Server Compiler
jvm 1    |   wrapper.pid=8762
jvm 1    |   os.version=5.10
jvm 1    |   wrapper.java.pid=8763
jvm 1    |   user.home=/home/mkwiatkowski
jvm 1    |   user.timezone=GB-Eire
jvm 1    |   wrapper.jvmid=1
jvm 1    |   java.awt.printerjob=sun.print.PSPrinterJob
jvm 1    |   java.specification.version=1.6
jvm 1    |   file.encoding=ISO646-US
jvm 1    |   user.name=mkwiatkowski
jvm 1    |   java.class.path=../lib/wrappertest.jar:../lib/wrapper.jar
jvm 1    |   java.vm.specification.version=1.0
jvm 1    |   wrapper.port=32000
jvm 1    |   sun.arch.data.model=32
jvm 1    |   java.home=/usr/jdk/instances/jdk1.6.0_02/jre
jvm 1    |   java.specification.vendor=Sun Microsystems Inc.
jvm 1    |   user.language=en
jvm 1    |   java.vm.info=mixed mode
jvm 1    |   java.version=1.6.0_02
jvm 1    |   java.ext.dirs=/usr/jdk/instances/jdk1.6.0_02/jre/lib/ext:/usr/jdk/packages/lib/ext
jvm 1    |   sun.boot.class.path=/usr/jdk/instances/jdk1.6.0_02/jre/lib/resources.jar:/usr/jdk/instances/jdk1.6.0_02/jre/lib/rt.jar:/usr/jdk/instances/jdk1.6.0_02/jre/lib/sunrsasign.jar:/usr/jdk/instances/jdk1.6.0_02/jre/lib/jsse.jar:/usr/jdk/instances/jdk1.6.0_02/jre/lib/jce.jar:/usr/jdk/instances/jdk1.6.0_02/jre/lib/charsets.jar:/usr/jdk/instances/jdk1.6.0_02/jre/classes
jvm 1    |   java.vendor=Sun Microsystems Inc.
jvm 1    |   file.separator=/
jvm 1    |   java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi
jvm 1    |   sun.cpu.endian=big
jvm 1    |   sun.io.unicode.encoding=UnicodeBig
jvm 1    |   wrapper.native_library=wrapper
jvm 1    |   wrapper.jvm.port.min=31000
jvm 1    |   sun.cpu.isalist=
jvm 1    | 
jvm 1    | Dump Wrapper Properties:
jvm 1    |   wrapper.license.type=
jvm 1    |   wrapper.logfile=../logs/wrapper.log
jvm 1    |   wrapper.java.maxmemory=0
jvm 1    |   wrapper.java.classpath.2=../lib/wrapper.jar
jvm 1    |   wrapper.java.classpath.1=../lib/wrappertest.jar
jvm 1    |   wrapper.umask=18
jvm 1    |   wrapper.restart.delay=5
jvm 1    |   wrapper.app.parameter.1=
jvm 1    |   wrapper.java.command=java
jvm 1    |   wrapper.ping.interval=5
jvm 1    |   wrapper.java.command.loglevel=DEBUG
jvm 1    |   wrapper.command.poll_interval=5
jvm 1    |   wrapper.syslog.ident=testwrapper
jvm 1    |   wrapper.native_library=wrapper
jvm 1    |   wrapper.ignore_sequence_gaps=TRUE
jvm 1    |   wrapper.ping.interval.logged=1
jvm 1    |   wrapper.jvm_exit.timeout=15
jvm 1    |   wrapper.timer_fast_threshold=172800
jvm 1    |   wrapper.ntservice.name=wrapper
jvm 1    |   wrapper.anchor.poll_interval=5
jvm 1    |   set.WRAPPER_PID=8762
jvm 1    |   set.WRAPPER_OS=solaris
jvm 1    |   wrapper.startup.delay.service=0
jvm 1    |   wrapper.jvm.port.min=31000
jvm 1    |   wrapper.jvm.port=0
jvm 1    |   wrapper.displayname=Test Wrapper Sample Application
jvm 1    |   wrapper.ntservice.displayname=testwrapper
jvm 1    |   wrapper.name=testwrapper
jvm 1    |   wrapper.anchorfile.umask=18
jvm 1    |   wrapper.console.format=PM
jvm 1    |   set.WRAPPER_BITS=32
jvm 1    |   wrapper.console.title=Test Wrapper Sample Application
jvm 1    |   set.WRAPPER_HOSTNAME=lonespdapp-zd1
jvm 1    |   wrapper.ping.timeout=30
jvm 1    |   wrapper.console.title.solaris=Test Wrapper Sample Application
jvm 1    |   wrapper.logfile.maxsize=0
jvm 1    |   wrapper.description=Test Wrapper Sample Application Description
jvm 1    |   wrapper.java.statusfile.umask=18
jvm 1    |   set.WRAPPER_HOST_NAME=lonespdapp-zd1
jvm 1    |   wrapper.max_failed_invocations=5
jvm 1    |   wrapper.jvm.port.max=31999
jvm 1    |   wrapper.java.mainclass=org.tanukisoftware.wrapper.test.Main
jvm 1    |   wrapper.java.additional.auto_bits=TRUE
jvm 1    |   wrapper.shutdown.timeout=30
jvm 1    |   wrapper.successful_invocation_time=300
jvm 1    |   wrapper.ntservice.description=Test Wrapper Sample Application
jvm 1    |   wrapper.java.idfile.umask=18
jvm 1    |   wrapper.statusfile.umask=18
jvm 1    |   wrapper.syslog.facility=USER
jvm 1    |   wrapper.java.library.path.1=../lib
jvm 1    |   wrapper.ntservice.starttype=AUTO_START
jvm 1    |   wrapper.port.min=32000
jvm 1    |   wrapper.timer_slow_threshold=172800
jvm 1    |   wrapper.logfile.loglevel=INFO
jvm 1    |   wrapper.memory_output.interval=1
jvm 1    |   wrapper.startup.delay.console=0
jvm 1    |   wrapper.logfile.purge.pattern=
jvm 1    |   wrapper.console.loglevel=INFO
jvm 1    |   wrapper.java.initmemory=0
jvm 1    |   wrapper.logfile.format=LPTM
jvm 1    |   wrapper.logfile.inactivity.timeout=1
jvm 1    |   wrapper.lockfile.umask=18
jvm 1    |   wrapper.ntservice.interactive=false
jvm 1    |   wrapper.logfile.maxfiles=0
jvm 1    |   wrapper.cpu.timeout=10
jvm 1    |   set.WRAPPER_FILE_SEPARATOR=/
jvm 1    |   wrapper.logfile.rollmode=SIZE
jvm 1    |   wrapper.port.max=32999
jvm 1    |   wrapper.java.pidfile.umask=18
jvm 1    |   set.WRAPPER_ARCH=sparc
jvm 1    |   wrapper.java.umask=18
jvm 1    |   wrapper.logfile.purge.sort=TIMES
jvm 1    |   wrapper.ntservice.dependency.1=
jvm 1    |   wrapper.pidfile=/home/mkwiatkowski/tmp/wrapper-solaris-sparc-32-3.3.9/bin/./testwrapper.pid
jvm 1    |   set.WRAPPER_PATH_SEPARATOR=:
jvm 1    |   wrapper.startup.delay=0
jvm 1    |   wrapper.syslog.loglevel=NONE
jvm 1    |   wrapper.ignore_signals=FALSE
jvm 1    |   wrapper.cpu_output.interval=1
jvm 1    |   wrapper.pidfile.umask=18
jvm 1    |   wrapper.startup.timeout=30
jvm 1    |   wrapper.logfile.umask=18
jvm 1    |   wrapper.port=0
jvm 1    | 
jvm 1    | Exception in thread "AWT-EventQueue-0" java.lang.SecurityException: A SecurityManager has not yet been set.
jvm 1    |      at org.tanukisoftware.wrapper.WrapperManager.sendServiceControlCode(WrapperManager.java:2803)
jvm 1    |      at org.tanukisoftware.wrapper.test.AbstractActionApp.doAction(AbstractActionApp.java:480)
jvm 1    |      at org.tanukisoftware.wrapper.test.Main$MainFrame.actionPerformed(Main.java:316)
jvm 1    |      at java.awt.Button.processActionEvent(Button.java:392)
jvm 1    |      at java.awt.Button.processEvent(Button.java:360)
jvm 1    |      at java.awt.Component.dispatchEventImpl(Component.java:4410)
jvm 1    |      at java.awt.Component.dispatchEvent(Component.java:4240)
jvm 1    |      at java.awt.EventQueue.dispatchEvent(EventQueue.java:599)
jvm 1    |      at java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:273)
jvm 1    |      at java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:183)
jvm 1    |      at java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:173)
jvm 1    |      at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:168)
jvm 1    |      at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:160)
jvm 1    |      at java.awt.EventDispatchThread.run(EventDispatchThread.java:121)
jvm 1    | Exception in thread "AWT-EventQueue-0" java.lang.SecurityException: A SecurityManager has not yet been set.
jvm 1    |      at org.tanukisoftware.wrapper.WrapperManager.sendServiceControlCode(WrapperManager.java:2803)
jvm 1    |      at org.tanukisoftware.wrapper.test.AbstractActionApp.doAction(AbstractActionApp.java:493)
jvm 1    |      at org.tanukisoftware.wrapper.test.Main$MainFrame.actionPerformed(Main.java:316)
jvm 1    |      at java.awt.Button.processActionEvent(Button.java:392)
jvm 1    |      at java.awt.Button.processEvent(Button.java:360)
jvm 1    |      at java.awt.Component.dispatchEventImpl(Component.java:4410)
jvm 1    |      at java.awt.Component.dispatchEvent(Component.java:4240)
jvm 1    |      at java.awt.EventQueue.dispatchEvent(EventQueue.java:599)
jvm 1    |      at java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:273)
jvm 1    |      at java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:183)
jvm 1    |      at java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:173)
jvm 1    |      at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:168)
jvm 1    |      at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:160)
jvm 1    |      at java.awt.EventDispatchThread.run(EventDispatchThread.java:121)
jvm 1    | Professional Edition: false
jvm 1    | Standard Edition: false
jvm 1    | TestWrapper: stop(0)
wrapper  | <-- Wrapper Stopped

Let me know if you want me to run anything specific there...

#14

robinshen ADMIN · 2 decades ago

We've added more logging. Please install below version to a new directory and run command "bin/server.sh console" to see what happens:
http://build.pmease.com/download/709/ar ... arc-32.zip

#15

kwiatek · 2 decades ago

Thanks for the build, but I think the problem is actually with starting the wrapper. I have changed the wrapper log level to TRACE (previously I only changed log4j.propertioes - sorry <img src="{SMILIES_PATH}/icon_redface.gif" alt=":oops:" title="Embarassed" /> ) and it looks like it hangs just before starting your app. here is a correct log from Solaris 8 running the same 2.1-dev2 binary you provided:


. . .
jvm 1    | WrapperManager: Initializing...
jvm 1    | WrapperManager Debug: JVM #1
jvm 1    | WrapperManager Debug: Running a 32-bit JVM.
jvm 1    | WrapperManager Debug: Registering shutdown hook
jvm 1    | WrapperManager Debug: Using wrapper
jvm 1    | WrapperManager Debug: Load native library.  One or more attempts may fail if platform specific libraries do not exist.  This is NORMAL and is only a problem if they all fail.
jvm 1    | WrapperManager Debug:   Loaded native library: libwrapper-solaris-sparc-32.so
jvm 1    | WrapperManager Debug: Calling native initialization method.
jvm 1    | WrapperJNI Debug: Inside native WrapperManager initialization method
jvm 1    | WrapperManager Debug: Java Version   : 1.6.0_12-b04 Java HotSpot(TM) Server VM
jvm 1    | WrapperManager Debug: Java VM Vendor : Sun Microsystems Inc.
jvm 1    | WrapperManager Debug: OS Name        : SunOS
jvm 1    | WrapperManager Debug: OS Arch        : sparc
jvm 1    | WrapperManager Debug: 
jvm 1    | WrapperManager Debug: Startup runner thread started.
jvm 1    | WrapperManager Debug: Control event monitor thread started.
jvm 1    | WrapperManager Debug: WrapperManager.start(org.tanukisoftware.wrapper.WrapperSimpleApp@8fce95, args[]) called by thread: main
jvm 1    | WrapperManager Debug: Communications runner thread started.
jvm 1    | WrapperManager Debug: Open socket to wrapper...Wrapper-Connection
jvm 1    | WrapperManager Debug: Failed attempt to bind using local port 31000
jvm 1    | WrapperManager Debug: Failed attempt to bind using local port 31001
jvm 1    | WrapperManager Debug: Opened Socket from 31002 to 32000
jvm 1    | WrapperManager Debug: Send a packet KEY : bsfKImo6iPI_tHAVAaSjFIkdJuq5gCYv
jvm 1    | WrapperManager Debug: handleSocket(Socket[addr=/127.0.0.1,port=32000,localport=31002])
wrapperp | accepted a socket from 127.0.0.1 on port 31002
wrapperp | read a packet KEY : bsfKImo6iPI_tHAVAaSjFIkdJuq5gCYv
wrapper  | Got key from JVM: bsfKImo6iPI_tHAVAaSjFIkdJuq5gCYv
wrapperp | send a packet LOW_LOG_LEVEL : 1
wrapperp | send a packet PING_TIMEOUT : 0
wrapperp | send a packet PROPERTIES : (Property Values)
wrapper  | Start Application.
wrapperp | send a packet START : start
jvm 1    | WrapperManager Debug: Received a packet LOW_LOG_LEVEL : 1
jvm 1    | WrapperManager Debug: LowLogLevel from Wrapper is 1
jvm 1    | WrapperManager Debug: Received a packet PING_TIMEOUT : 0
jvm 1    | WrapperManager Debug: PingTimeout from Wrapper is 0
jvm 1    | WrapperManager Debug: Received a packet PROPERTIES : (Property Values)
jvm 1    | WrapperManager Debug: Received a packet START : start
jvm 1    | WrapperManager Debug: calling WrapperListener.start()
jvm 1    | WrapperManager Debug: WrapperListener.start runner thread started.
jvm 1    | WrapperSimpleApp Debug: start(args) Will wait up to 2 seconds for the main method to complete.
jvm 1    | WrapperSimpleApp Debug: invoking main method
jvm 1    | 2010-03-09 11:23:05,144 INFO  - Connecting to server 'http://apibuild3:8810'...
jvm 1    | 2010-03-09 11:23:05,608 INFO  - Updating agent framework...
jvm 1    | 2010-03-09 11:23:05,649 INFO  - Updating agent plugins...
jvm 1    | 2010-03-09 11:23:05,663 INFO  - Updating libs...
jvm 1    | 2010-03-09 11:23:05,671 INFO  - Updating misc files...
jvm 1    | 2010-03-09 11:23:05,676 INFO  - Booting application from '/home/mkwiatkowski/buildagent-SOL8'...
jvm 1    | 2010-03-09 11:23:05,684 INFO  - Starting framework...
jvm 1    | WrapperManager Debug: Send a packet START_PENDING : 5000
wrapperp | read a packet START_PENDING : 5000
wrapper  | JVM signalled a start pending with waitHint of 5000 millis.
jvm 1    | WrapperManager Debug: Send a packet START_PENDING : 5000
jvm 1    | WrapperSimpleApp Debug: start(args) end.  Main Completed=false, exitCode=null
jvm 1    | WrapperManager Debug: returned from WrapperListener.start()
jvm 1    | WrapperManager Debug: Send a packet STARTED : 
jvm 1    | WrapperManager Debug: WrapperListener.start runner thread stopped.
jvm 1    | WrapperManager Debug: Startup runner thread stopped.
wrapperp | read a packet START_PENDING : 5000
wrapper  | JVM signalled a start pending with waitHint of 5000 millis.
wrapperp | read a packet STARTED : 
wrapper  | JVM signalled that it was started.
jvm 1    | WrapperSimpleApp Debug: main method completed
jvm 1    | 2010-03-09 11:23:10,261 INFO  - Starting QuickBuild agent...
. . .

And here the broken one:


. . .
jvm 1    | WrapperManager: Initializing...
jvm 1    | WrapperManager Debug: JVM #1
jvm 1    | WrapperManager Debug: Running a 32-bit JVM.
jvm 1    | WrapperManager Debug: Registering shutdown hook
jvm 1    | WrapperManager Debug: Using wrapper
jvm 1    | WrapperManager Debug: Load native library.  One or more attempts may fail if platform specific libraries do not exist.  This is NORMAL and is only a problem if they all fail.
jvm 1    | WrapperManager Debug:   Loaded native library: libwrapper-solaris-sparc-32.so
jvm 1    | WrapperManager Debug: Calling native initialization method.
jvm 1    | WrapperJNI Debug: Initializing WrapperManager native library.
jvm 1    | WrapperManager Debug: Java Version   : 1.5.0_15-b04 Java HotSpot(TM) Server VM
jvm 1    | WrapperManager Debug: Java VM Vendor : Sun Microsystems Inc.
jvm 1    | WrapperManager Debug: OS Name        : SunOS
jvm 1    | WrapperManager Debug: OS Arch        : sparc
jvm 1    | WrapperManager Debug: 
jvm 1    | WrapperManager Debug: Startup runner thread started.
jvm 1    | WrapperManager Debug: Control event monitor thread started.
jvm 1    | WrapperManager Debug: WrapperManager.start(org.tanukisoftware.wrapper.WrapperSimpleApp@1908ca1, args[]) called by thread: main
jvm 1    | WrapperManager Debug: Communications runner thread started.
jvm 1    | WrapperManager Debug: Open socket to wrapper...Wrapper-Connection
jvm 1    | WrapperManager Debug: Opened Socket from 31000 to 32000
jvm 1    | WrapperManager Debug: Send a packet KEY : KApLOY-pAGb2S2XSg0wnEpH97t421Fuk
jvm 1    | WrapperManager Debug: handleSocket(Socket[addr=/127.0.0.1,port=32000,localport=31000])
wrapperp | accepted a socket from 127.0.0.1 on port 31000
wrapperp | read a packet KEY : KApLOY-pAGb2S2XSg0wnEpH97t421Fuk
wrapper  | Got key from JVM: KApLOY-pAGb2S2XSg0wnEpH97t421Fuk
wrapperp | send a packet LOW_LOG_LEVEL : 0
wrapperp | send a packet PING_TIMEOUT : 0
wrapperp | send a packet LOGFILE : ../logs/console.log
wrapperp | send a packet PROPERTIES : (Property Values)
wrapper  | Start Application.
wrapperp | send a packet START : start
jvm 1    | WrapperManager Debug: Received a packet LOW_LOG_LEVEL : 0
jvm 1    | WrapperManager Debug: LowLogLevel from Wrapper is 0
jvm 1    | WrapperManager Debug: Received a packet PING_TIMEOUT : 0
jvm 1    | WrapperManager Debug: PingTimeout from Wrapper is 0
jvm 1    | WrapperManager Debug: Received a packet LOGFILE : ../logs/console.log
jvm 1    | WrapperManager Debug: Received a packet PROPERTIES : (Property Values)
jvm 1    | WrapperManager Debug: Received a packet START : start
jvm 1    | WrapperManager Debug: calling WrapperListener.start()
jvm 1    | WrapperManager Debug: WrapperListener.start runner thread started.
jvm 1    | WrapperSimpleApp Debug: start(args) Will wait up to 2 seconds for the main method to complete.
jvm 1    | WrapperSimpleApp Debug: invoking main method
jvm 1    | Trusting all...
jvm 1    | WrapperManager Debug: Send a packet START_PENDING : 5000
wrapperp | read a packet START_PENDING : 5000
wrapper  | JVM signalled a start pending with waitHint of 5000 millis.
jvm 1    | WrapperManager Debug: Send a packet START_PENDING : 5000
jvm 1    | WrapperSimpleApp Debug: start(args) end.  Main Completed=false, exitCode=null
jvm 1    | WrapperManager Debug: returned from WrapperListener.start()
jvm 1    | WrapperManager Debug: Send a packet STARTED : 
jvm 1    | WrapperManager Debug: WrapperListener.start runner thread stopped.
wrapperp | read a packet START_PENDING : 5000
wrapper  | JVM signalled a start pending with waitHint of 5000 millis.
wrapperp | read a packet STARTED : 
wrapper  | JVM signalled that it was started.
jvm 1    | WrapperManager Debug: Startup runner thread stopped.
^Cwrapper  | Signal trapped.  No details available.
wrapper  | INT trapped.  Shutting down.
. . .

Seems like WrapperManager has not completed its work as it lacks the following line:


jvm 1    | WrapperSimpleApp Debug: main method completed

However I am not getting it in the testwrapper log:


. . .
jvm 1    | WrapperManager: Initializing...
jvm 1    | WrapperManager Debug: JVM #1
jvm 1    | WrapperManager Debug: Running a 32-bit JVM.
jvm 1    | WrapperManager Debug: Registering shutdown hook
jvm 1    | WrapperManager Debug: Using wrapper
jvm 1    | WrapperManager Debug: Load native library.  One or more attempts may fail if platform specific libraries do not exist.  This is NORMAL and is only a problem if they all fail.
jvm 1    | WrapperManager Debug:   Unable to load native library: libwrapper-solaris-sparc-32.so  Cause: no wrapper-solaris-sparc-32 in java.library.path
jvm 1    | WrapperManager Debug:   Loaded native library: libwrapper.so
jvm 1    | WrapperManager Debug: Calling native initialization method.
jvm 1    | WrapperJNI Debug: Initializing WrapperManager native library.
jvm 1    | WrapperManager Debug: Java Version   : 1.5.0_15-b04 Java HotSpot(TM) Server VM
jvm 1    | WrapperManager Debug: Java VM Vendor : Sun Microsystems Inc.
jvm 1    | WrapperManager Debug: OS Name        : SunOS
jvm 1    | WrapperManager Debug: OS Arch        : sparc
jvm 1    | WrapperManager Debug: 
jvm 1    | WrapperManager Debug: Control event monitor thread started.
jvm 1    | WrapperManager Debug: WrapperManager.start(org.tanukisoftware.wrapper.test.Main@1415de6, args[]) called by thread: main
jvm 1    | WrapperManager Debug: Communications runner thread started.
jvm 1    | WrapperManager Debug: Open socket to wrapper...Wrapper-Connection
jvm 1    | WrapperManager Debug: Startup runner thread started.
jvm 1    | WrapperManager Debug: Opened Socket from 31000 to 32000
jvm 1    | WrapperManager Debug: Send a packet KEY : CEwcciSind-JTnji
jvm 1    | WrapperManager Debug: handleSocket(Socket[addr=/127.0.0.1,port=32000,localport=31000])
wrapperp | accepted a socket from 127.0.0.1 on port 31000
wrapperp | read a packet KEY : CEwcciSind-JTnji
wrapper  | Got key from JVM: CEwcciSind-JTnji
wrapperp | send a packet LOW_LOG_LEVEL : 0
wrapperp | send a packet PING_TIMEOUT : 30
wrapperp | send a packet LOGFILE : ../logs/wrapper.log
wrapperp | send a packet PROPERTIES : (Property Values)
wrapper  | Start Application.
wrapperp | send a packet START : start
jvm 1    | WrapperManager Debug: Received a packet LOW_LOG_LEVEL : 0
jvm 1    | WrapperManager Debug: LowLogLevel from Wrapper is 0
jvm 1    | WrapperManager Debug: Received a packet PING_TIMEOUT : 30
jvm 1    | WrapperManager Debug: PingTimeout from Wrapper is 30000
jvm 1    | WrapperManager Debug: Received a packet LOGFILE : ../logs/wrapper.log
jvm 1    | WrapperManager Debug: Received a packet PROPERTIES : (Property Values)
jvm 1    | WrapperManager Debug: Received a packet START : start
jvm 1    | WrapperManager Debug: calling WrapperListener.start()
jvm 1    | WrapperManager Debug: WrapperListener.start runner thread started.
jvm 1    | TestWrapper: start()
jvm 1    | TestWrapper: An action was not specified.  Default to "dialog".  Use "help" for list of actions.
jvm 1    | TestWrapper: ActionServer Enabled. 
jvm 1    | TestWrapper:   Telnet localhost 9999
jvm 1    | TestWrapper:   Commands: 
jvm 1    | TestWrapper:     S: Shutdown
jvm 1    | TestWrapper:     H: Expected Halt
jvm 1    | TestWrapper:     R: Restart
jvm 1    | TestWrapper:     D: Thread Dump
jvm 1    | TestWrapper:     U: Unexpected Halt (Simulate crash)
jvm 1    | TestWrapper:     V: Access Violation (Actual crash)
jvm 1    | TestWrapper:     G: Make the JVM appear to be hung.
jvm 1    | TestWrapper:
jvm 1    | TestWrapper: Showing dialog...
jvm 1    | WrapperManager Debug: returned from WrapperListener.start()
jvm 1    | WrapperManager Debug: Send a packet STARTED : 
jvm 1    | WrapperManager Debug: WrapperListener.start runner thread stopped.
wrapperp | read a packet STARTED : 
wrapper  | JVM signalled that it was started.
jvm 1    | WrapperManager Debug: Startup runner thread stopped.
wrapperp | send a packet PING : ping
jvm 1    | WrapperManager Debug: Received a packet PING : ping
jvm 1    | WrapperManager Debug: Send a packet PING : ping
wrapperp | read a packet PING : ping
. . .

Any ideas <img src="{SMILIES_PATH}/icon_question.gif" alt=":?:" title="Question" />

#16

robinshen ADMIN · 2 decades ago

Looks like QuickBuild is stuck at calling JDK API TrustManager. I've removed that snippet of code and build a new version:
http://build.pmease.com/build/712
Please test to see if it works now.

#17

kwiatek · 2 decades ago

I tried the 2.1-dev3 build but it still fails to initialise <img src="{SMILIES_PATH}/icon_confused.gif" alt=":?" title="Confused" /> I used it against both jdk 1.5 and the latest jdk 1.6 b18. The logs are still missing this line:


jvm 1    | WrapperSimpleApp Debug: main method completed

(all log lavels set to TRACE)


. . .
wrapper  | Start Application.
wrapperp | send a packet START : start
jvm 1    | WrapperManager Debug: Received a packet LOW_LOG_LEVEL : 0
jvm 1    | WrapperManager Debug: LowLogLevel from Wrapper is 0
jvm 1    | WrapperManager Debug: Received a packet PING_TIMEOUT : 0
jvm 1    | WrapperManager Debug: PingTimeout from Wrapper is 0
jvm 1    | WrapperManager Debug: Received a packet LOGFILE : ../logs/console.log
jvm 1    | WrapperManager Debug: Received a packet PROPERTIES : (Property Values)
jvm 1    | WrapperManager Debug: Received a packet START : start
jvm 1    | WrapperManager Debug: calling WrapperListener.start()
jvm 1    | WrapperManager Debug: WrapperListener.start runner thread started.
jvm 1    | WrapperSimpleApp Debug: start(args) Will wait up to 2 seconds for the main method to complete.
jvm 1    | WrapperSimpleApp Debug: invoking main method
jvm 1    | Trusting all...
jvm 1    | Calculating install directory...
jvm 1    | Redirecting temp directory...
jvm 1    | Initializing logging system...
jvm 1    | Loading node properties...
jvm 1    | WrapperManager Debug: Send a packet START_PENDING : 5000
wrapperp | read a packet START_PENDING : 5000
wrapper  | JVM signalled a start pending with waitHint of 5000 millis.
jvm 1    | WrapperManager Debug: Send a packet START_PENDING : 5000
jvm 1    | WrapperSimpleApp Debug: start(args) end.  Main Completed=false, exitCode=null
jvm 1    | WrapperManager Debug: returned from WrapperListener.start()
jvm 1    | WrapperManager Debug: Send a packet STARTED : 
jvm 1    | WrapperManager Debug: WrapperListener.start runner thread stopped.
wrapperp | read a packet START_PENDING : 5000
wrapper  | JVM signalled a start pending with waitHint of 5000 millis.
wrapperp | read a packet STARTED : 
wrapper  | JVM signalled that it was started.
jvm 1    | WrapperManager Debug: Startup runner thread stopped.


5 mins passed ...

^Cwrapper  | Signal trapped.  No details available.
wrapper  | INT trapped.  Shutting down.
wrapper  | wrapperStopProcess(0) called.
wrapper  | Sending stop signal to JVM
wrapperp | send a packet STOP : NULL
jvm 1    | WrapperManager Debug: Received a packet STOP : 
jvm 1    | WrapperManager Debug: Thread, Wrapper-Connection, handling the shutdown process.
jvm 1    | WrapperManager Debug: calling listener.stop()
jvm 1    | WrapperManager Debug: Stopped checking for control events.
^Cwrapper  | Signal trapped.  No details available.
wrapper  | INT trapped.  Forcing immediate shutdown.
wrapper  | JVM did not exit on request, terminated
wrapper  | Signal trapped.  Details:
wrapper  |   signal number=18 (SIGCHLD), source="unknown"
wrapper  | Received SIGCHLD, checking JVM process status.
wrapper  | JVM received a signal SIGKILL (9).
wrapper  | <-- Wrapper Stopped
[lonespdapp-zd1] /home/mkwiatkowski/quickbuild-2.1-dev3-solaris-sparc-32/bin>

#18

robinshen ADMIN · 2 decades ago

This seems really odd. The code just halt randomly (previously when calling trust manager, and now halt when loading node properties). Is it possible that you open a limited user account temporarily on this machine so that I can login and investigate the problem?

#19

kwiatek · 2 decades ago

I am afraid that is not possible... Our internal network can't be accessed from outside. I will try to get our admins to set up another Sol10 sparc box and try it there. If that does not help we could try to do some investigation together over the phone. I am off for the next week, wil come back to you as soon as I am back. Thanks

#20

kwiatek · 2 decades ago

I have just managed to successfully start QuickBuild server up on a different Sol10 sparc box. We will try to get the broken one rebuilt. Thanks for your help!