Appserver not starting

[pinne65]

Hello,

I'm trying to create & start a new appserver using Progress 10.1B on aix 5.3:

But I keep getting this message:

IOException reading message from server : java.io.EOFException: pipe to server broken (8117).

Which doesn't help me much.

Can anyone cast some more light on the situation.


Start & query commands + log snippet from testappsrv.broker.log below:

<root@p00p:/>$ asbman -host p00p -name testappsrv -start
OpenEdge Release 10.1B03 as of Fri Nov 2 22:54:54 EDT 2007

Connecting to Progress AdminServer using rmi://p00p:20931/Chimera (8280)
Searching for testappsrv (8288)
Connecting to testappsrv (8276)
Starting testappsrv. Check status. (8296)
<root@p00p:/>$ asbman -name testappsrv -query
OpenEdge Release 10.1B03 as of Fri Nov 2 22:54:54 EDT 2007

Connecting to Progress AdminServer using rmi://localhost:20931/Chimera (8280)
Searching for testappsrv (8288)
Connecting to testappsrv (8276)
Broker: testappsrv not running (8313)

[09/07/20@16:41:05.450-0700] P-2900346 T-S-0001 2 UB Basic Started server: /u/progress/dlc/bin/_proapsv -logginglevel 4 -logfile /home/progress/app/appservers/testappsrv.server.log -ubpid 2900346 -Ms 1 -logname testappsrv -l
ogentrytypes ASPlumbing,DB.Connects -logthreshold 0 -numlogfiles 3 -ASID 1 -ubpropfile /u/progress/dlc/properties/ubroker.properties (8108)
[09/07/20@16:41:05.942-0700] P-2900346 T-S-0001 1 UB ---------- IOException reading message from server : java.io.EOFException: pipe to server broken (8117)
[09/07/20@16:41:05.943-0700] P-2900346 T-S-0001 2 UB Basic ERROR: cannot start server. (8100)
[09/07/20@16:41:05.943-0700] P-2900346 T-S-0001 3 UB Basic Ending serverPool thread: S-0001. (8077)

 

[AndrewSmith]

Hi there,

Have you searched through the progress KB for entries involving errors 8117 and 8100? There are many potential reasons for this:

Bad port range specified in ubroker.properties.
Log file > 2Gb.
Bad parameters in PF or startup string.
Startup procedure CRC.
TERM setting.

First off, I would search the KB. Then, are you getting any output in the server logfile? And then, can you post the relevant entries from your ubroker.properties file?

 

[TomBascom]

What Java version?

 

[pinne65]

Thanks for responding,

I have been searching for those error message, just not in the Progress KB yet ;-).

Our Java version:

java version "1.4.2"
Java(TM) 2 Runtime Environment, Standard Edition (build 1.4.2)
Classic VM (build 1.4.2, J2RE 1.4.2 IBM AIX build ca142-20080515 (SR11) (JIT enabled: jitc))


ubroker.properties for testappsrv (enclosed in # # #... followed by testappsrv.server.log):
# # # # # # # # # # # # # # # # # # # # # # # # # # # # # # # # #
#
# Default properties for all unified broker instances
#
[UBroker]
4glSrcCompile=0
appserviceNameList=
autoStart=0
autoTrimTimeout=1800
brkrLogAppend=1
brkrLogEntries=0
brkrLogEntryTypes=UBroker.Basic
brkrLoggingLevel=2
brkrLogThreshold=0
brkrNumLogFiles=3
brokerLogFile=$WRKDIR/broker.log
certStorePath=$DLC/certs/
collectStatsData=0
connectingTimeout=60
controllingNameServer=
defaultService=0
description=
environment=
flushStatsData=255
groupName=
hostName=
infoVersion=9010
initialSrvrInstance=5
jvmArgs=
keyAlias=
keyAliasPasswd=
keyStorePasswd=
keyStorePath=$DLC/keys/
maxClientInstance=512
maxSrvrInstance=10
minSrvrInstance=1
noSessionCache=0
operatingMode=Stateless
password=
portNumber=3001
priorityWeight=0
PROPATH=${PROPATH}:${WRKDIR}
registerNameServer=1
registrationMode=Register-IP
registrationRetry=30
requestTimeout=15
serverLifespan=0
serverLifespanPadding=5
sessionTimeout=180
srvrExecFile=
srvrLogAppend=1
srvrLogEntries=0
srvrLogEntryTypes=
srvrLogFile=$WRKDIR/server.log
srvrLoggingLevel=2
srvrLogThreshold=0
srvrLogWatchdogInterval=60
srvrMaxPort=3502
srvrMinPort=3202
srvrNumLogFiles=3
srvrSelectionScheme=0
srvrStartupParam=-p web/objects/web_disp.p -cpstream iso8859-1 -weblogerror
srvrStartupTimeout=3
sslAlgorithms=
sslEnable=0
userName=
uuid=
workDir=$WRKDIR
#
# Default properties for broker instances serving AppServers
#
[UBroker.AS]
4glSrcCompile=0
appServerKeepaliveCapabilities=denyClientASK,denyServerASK
classMain=com.progress.ubroker.broker.ubroker
compressionEnable=0
debuggerEnabled=0
defaultService=0
description=AppServer Broker
mqBrkrLogAppend=1
mqBrkrLogEntries=0
mqBrkrLogEntryTypes=UBroker.Basic
mqBrkrLoggingLevel=2
mqBrkrLogThreshold=0
mqBrkrNumLogFiles=3
mqBrokerLogFile=$WRKDIR/mqbroker.log
mqEnabled=0
mqServerLogFile=$WRKDIR/mqserver.log
mqSrvrLogAppend=1
mqSrvrLogEntries=0
mqSrvrLogEntryTypes=UBroker.Basic
mqSrvrLoggingLevel=2
mqSrvrLogThreshold=0
mqSrvrNumLogFiles=3
operatingMode=State-reset
portNumber=3090
serverASKActivityTimeout=60
serverASKResponseTimeout=60
srvrActivateProc=
srvrConnectProc=
srvrDeactivateProc=
srvrDisconnProc=
srvrExecFile=$DLC/bin/_proapsv
srvrLogEntryTypes=ASPlumbing,DB.Connects
srvrMaxPort=2202
srvrMinPort=2002
srvrShutdownProc=
srvrStartupParam=
srvrStartupProc=
srvrStartupProcParam=
[UBroker.AS.testappsrv]
appserviceNameList=testappsrv
brkrLoggingLevel=4
brokerLogFile=/home/progress/app/appservers/testappsrv.broker.log
controllingNameServer=NS1
environment=testappsrv
maxClientInstance=32
mqBrokerLogFile=/home/progress/app/appservers/testappsrv.mqbroker.log
mqServerLogFile=/home/progress/app/appservers/testappsrv.mqserver.log
operatingMode=State-free
portNumber=6666
PROPATH=/home/progress/app:${PROPATH}
srvrLogFile=/home/progress/app/appservers/testappsrv.server.log
srvrLoggingLevel=4
uuid=a8602eba31bbf09f:-7c1dfc86:1228716c7c5:-5f5b
workDir=/home/progress/app
# # # # # # # # # # # # # # # # # # # # # # # # # # # # # # # # #


[09/07/20@16:41:05.457-0700] P-2490824 T-000000 1 AS -- Logging level set to = 4
[09/07/20@16:41:05.457-0700] P-2490824 T-000000 1 AS -- Log entry types activated: ASPlumbing,DB.Connects
[09/07/20@16:41:05.457-0700] P-2490824 T-000000 2 AS AS Starting application server for testappsrv. (5560)
[09/07/20@16:41:05.468-0700] P-2490824 T-000000 2 AS AS Application Server Startup. (5473)
[09/07/20@16:41:05.942-0700] P-2490824 T-000000 1 AS -- Service transport TCP is busy. (5485)
[09/07/20@16:41:05.942-0700] P-2490824 T-000000 2 AS AS Application Server initialization failure. (5475)
[09/07/20@16:41:05.942-0700] P-2490824 T-000000 2 AS AS Application Server Shutdown. (5476)
[09/07/20@16:41:06.451-0700] P-1658998 T-000000 1 AS -- Logging level set to = 4
[09/07/20@16:41:06.451-0700] P-1658998 T-000000 1 AS -- Log entry types activated: ASPlumbing,DB.Connects
[09/07/20@16:41:06.451-0700] P-1658998 T-000000 2 AS AS Starting application server for testappsrv. (5560)
[09/07/20@16:41:06.463-0700] P-1658998 T-000000 2 AS AS Application Server Startup. (5473)
[09/07/20@16:41:06.934-0700] P-1658998 T-000000 1 AS -- Service transport TCP is busy. (5485)
[09/07/20@16:41:06.934-0700] P-1658998 T-000000 2 AS AS Application Server initialization failure. (5475)
[09/07/20@16:41:06.934-0700] P-1658998 T-000000 2 AS AS Application Server Shutdown. (5476)
[09/07/20@16:41:07.461-0700] P-1020402 T-000000 1 AS -- Logging level set to = 4
[09/07/20@16:41:07.461-0700] P-1020402 T-000000 1 AS -- Log entry types activated: ASPlumbing,DB.Connects
[09/07/20@16:41:07.461-0700] P-1020402 T-000000 2 AS AS Starting application server for testappsrv. (5560)
[09/07/20@16:41:07.472-0700] P-1020402 T-000000 2 AS AS Application Server Startup. (5473)
[09/07/20@16:41:07.941-0700] P-1020402 T-000000 1 AS -- Service transport TCP is busy. (5485)
[09/07/20@16:41:07.942-0700] P-1020402 T-000000 2 AS AS Application Server initialization failure. (5475)
[09/07/20@16:41:07.942-0700] P-1020402 T-000000 2 AS AS Application Server Shutdown. (5476)
[09/07/20@16:41:08.461-0700] P-2134394 T-000000 1 AS -- Logging level set to = 4
[09/07/20@16:41:08.461-0700] P-2134394 T-000000 1 AS -- Log entry types activated: ASPlumbing,DB.Connects
[09/07/20@16:41:08.461-0700] P-2134394 T-000000 2 AS AS Starting application server for testappsrv. (5560)
[09/07/20@16:41:08.472-0700] P-2134394 T-000000 2 AS AS Application Server Startup. (5473)
[09/07/20@16:41:08.938-0700] P-2134394 T-000000 1 AS -- Service transport TCP is busy. (5485)
[09/07/20@16:41:08.938-0700] P-2134394 T-000000 2 AS AS Application Server initialization failure. (5475)
[09/07/20@16:41:08.938-0700] P-2134394 T-000000 2 AS AS Application Server Shutdown. (5476)
[09/07/20@16:41:09.463-0700] P-1020406 T-000000 1 AS -- Logging level set to = 4
[09/07/20@16:41:09.463-0700] P-1020406 T-000000 1 AS -- Log entry types activated: ASPlumbing,DB.Connects
[09/07/20@16:41:09.463-0700] P-1020406 T-000000 2 AS AS Starting application server for testappsrv. (5560)
[09/07/20@16:41:09.474-0700] P-1020406 T-000000 2 AS AS Application Server Startup. (5473)
[09/07/20@16:41:09.948-0700] P-1020406 T-000000 1 AS -- Service transport TCP is busy. (5485)
[09/07/20@16:41:09.948-0700] P-1020406 T-000000 2 AS AS Application Server initialization failure. (5475)
[09/07/20@16:41:09.948-0700] P-1020406 T-000000 2 AS AS Application Server Shutdown. (5476)

 

[rstanciu]

verify if these TCP ports are already in use: from 3502 to 3202
if yes, change the config to anothers values like:

srvrMaxPort=25000
srvrMinPort=25010

 

[pinne65]

Thanks!

It was a apparently some sort of port conflict. I changed to another port range and it worked.