Hello,
I'm trying a fresh install of Tomcat 6.0.18 on Windows Server 2003 R2
enterprise edition with SP2. After selecting "Full install" and default port
8080 in the installation steps, the Tomcat logs are showing the dreaded
"Socket bind failed" error. It doesn't seem to be because some other
application is using the port. Please refer to the debug logs below.
I've have pretty much run out of options. I've changed port numbers to many
random variants (8191, 8029 etc.) with the same results. I've switched
between the JDK and the JRE. I have the firewall disabled (even though port
8080 is open) and my setup does not have SCW (security configuration wizard)
installed. IIS is working fine on port 80. Any clues would be greatly
appreciated.
Thanks!
============================================================
C:\>netsh firewall show state
Firewall status:
--------------------------------------------
Profile = Domain
Operational mode = Disable
Exception mode = Enable
Multicast/broadcast response mode = Enable
Notification mode = Enable
Group policy version = None
Remote admin mode = Disable
c:\>java -version
java version "1.6.0_11"
Java(TM) SE Runtime Environment (build 1.6.0_11-b03)
Java HotSpot(TM) Client VM (build 11.0-b16, mixed mode, sharing)
c:\>set JAVA_HOME
JAVA_HOME=C:\Program Files\Java\jdk1.6.0_11
c:\>set CATALINA_HOME
CATALINA_HOME=C:\Tomcat
PortQry running on Server 2003:
c:\PortQryV2>PortQry -local -wport 8080 -wt 1
PortQry Version 2.0
Watching port: 8080
Checking for changes every 1 second
**press escape to stop watching port
============
System Date: Fri Apr 03 15:18:48 2009
Service Name: Tomcat6
Display Name: Apache Tomcat
Service Type: runs in its own process
PID Port Local IP State Remote IP:Port
3244 TCP 8080 0.0.0.0 LISTENING 0.0.0.0:63577
Port Statistics
TCP mappings: 1
UDP mappings: 0
TCP ports in a LISTENING state: 1 = 100.00%
============
System Date: Fri Apr 03 15:18:50 2009
============
System Date: Fri Apr 03 15:18:50 2009
Specified port currently does not have any port mappings
TCP mappings: 0
UDP mappings: 0
PID Port Local IP State Remote IP:Port
Port Statistics
TCP mappings: 0
UDP mappings: 0
press escape key to stop watching port
Catalina.2009-04-03.log contains:
Apr 3, 2009 3:18:48 PM org.apache.catalina.core.AprLifecycleListener init
INFO: Loaded APR based Apache Tomcat Native library 1.1.14.
Apr 3, 2009 3:18:48 PM org.apache.catalina.core.AprLifecycleListener init
INFO: APR capabilities: IPv6 [false], sendfile [true], accept filters
[false], random [true].
Apr 3, 2009 3:18:48 PM org.apache.coyote.http11.Http11AprProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-8080
Apr 3, 2009 3:18:48 PM org.apache.coyote.ajp.AjpAprProtocol init
SEVERE: Error initializing endpoint
java.lang.Exception: Socket bind failed: [730048] Only one usage of each
socket address (protocol/network address/port) is normally permitted.
at org.apache.tomcat.util.net.AprEndpoint.init(AprEndpoint.java:612)
at org.apache.coyote.ajp.AjpAprProtocol.init(AjpAprProtocol.java:160)
at
org.apache.catalina.connector.Connector.initialize(Connector.java:1058)
at
org.apache.catalina.core.StandardService.initialize(StandardService.java:677)
at
org.apache.catalina.core.StandardServer.initialize(StandardServer.java:795)
at org.apache.catalina.startup.Catalina.load(Catalina.java:530)
at org.apache.catalina.startup.Catalina.load(Catalina.java:550)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
at java.lang.reflect.Method.invoke(Unknown Source)
at org.apache.catalina.startup.Bootstrap.load(Bootstrap.java:260)
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:412)
Apr 3, 2009 3:18:48 PM org.apache.catalina.startup.Catalina load
SEVERE: Catalina.start
LifecycleException: Protocol handler initialization failed:
java.lang.Exception: Socket bind failed: [730048] Only one usage of each
socket address (protocol/network address/port) is normally permitted.
at
org.apache.catalina.connector.Connector.initialize(Connector.java:1060)
at
org.apache.catalina.core.StandardService.initialize(StandardService.java:677)
at
org.apache.catalina.core.StandardServer.initialize(StandardServer.java:795)
at org.apache.catalina.startup.Catalina.load(Catalina.java:530)
at org.apache.catalina.startup.Catalina.load(Catalina.java:550)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
at java.lang.reflect.Method.invoke(Unknown Source)
at org.apache.catalina.startup.Bootstrap.load(Bootstrap.java:260)
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:412)
Apr 3, 2009 3:18:48 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 521 ms
Apr 3, 2009 3:18:48 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Apr 3, 2009 3:18:48 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.18
Apr 3, 2009 3:18:48 PM org.apache.coyote.http11.Http11AprProtocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
Apr 3, 2009 3:18:48 PM org.apache.coyote.ajp.AjpAprProtocol start
SEVERE: Error starting endpoint
java.lang.Exception: Socket bind failed: [730048] Only one usage of each
socket address (protocol/network address/port) is normally permitted.
at org.apache.tomcat.util.net.AprEndpoint.init(AprEndpoint.java:612)
at org.apache.tomcat.util.net.AprEndpoint.start(AprEndpoint.java:717)
at org.apache.coyote.ajp.AjpAprProtocol.start(AjpAprProtocol.java:188)
at org.apache.catalina.connector.Connector.start(Connector.java:1131)
at
org.apache.catalina.core.StandardService.start(StandardService.java:531)
at
org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
at org.apache.catalina.startup.Catalina.start(Catalina.java:578)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
at java.lang.reflect.Method.invoke(Unknown Source)
at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:288)
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:413)
Apr 3, 2009 3:18:48 PM org.apache.catalina.startup.Catalina start
SEVERE: Catalina.start:
LifecycleException: service.getName(): "Catalina"; Protocol handler start
failed: java.lang.Exception: Socket bind failed: [730048] Only one usage of
each socket address (protocol/network address/port) is normally permitted.
at org.apache.catalina.connector.Connector.start(Connector.java:1138)
at
org.apache.catalina.core.StandardService.start(StandardService.java:531)
at
org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
at org.apache.catalina.startup.Catalina.start(Catalina.java:578)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
at java.lang.reflect.Method.invoke(Unknown Source)
at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:288)
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:413)
Apr 3, 2009 3:18:48 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 463 ms
Apr 3, 2009 3:18:48 PM org.apache.catalina.core.StandardServer await
SEVERE: StandardServer.await: create[8005]:
java.net.BindException: Address already in use: JVM_Bind
at java.net.PlainSocketImpl.socketBind(Native Method)
at java.net.PlainSocketImpl.bind(Unknown Source)
at java.net.ServerSocket.bind(Unknown Source)
at java.net.ServerSocket.<init>(Unknown Source)
at
org.apache.catalina.core.StandardServer.await(StandardServer.java:373)
at org.apache.catalina.startup.Catalina.await(Catalina.java:642)
at org.apache.catalina.startup.Catalina.start(Catalina.java:602)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
at java.lang.reflect.Method.invoke(Unknown Source)
at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:288)
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:413)
Apr 3, 2009 3:18:48 PM org.apache.coyote.http11.Http11AprProtocol pause
INFO: Pausing Coyote HTTP/1.1 on http-8080
Apr 3, 2009 3:18:48 PM org.apache.coyote.ajp.AjpAprProtocol pause
INFO: Pausing Coyote AJP/1.3 on ajp-8009
Apr 3, 2009 3:18:49 PM org.apache.catalina.core.StandardService stop
INFO: Stopping service Catalina
Apr 3, 2009 3:18:49 PM org.apache.coyote.http11.Http11AprProtocol destroy
INFO: Stopping Coyote HTTP/1.1 on http-8080
Apr 3, 2009 3:18:50 PM org.apache.catalina.connector.MapperListener destroy
WARNING: Error unregistering MBeanServerDelegate
java.lang.NullPointerException
at
org.apache.catalina.connector.MapperListener.destroy(MapperListener.java:165)
at org.apache.catalina.connector.Connector.stop(Connector.java:1179)
at
org.apache.catalina.core.StandardService.stop(StandardService.java:593)
at org.apache.catalina.core.StandardServer.stop(StandardServer.java:744)
at org.apache.catalina.startup.Catalina.stop(Catalina.java:628)
at
org.apache.catalina.startup.Catalina$CatalinaShutdownHook.run(Catalina.java:671)
Apr 3, 2009 3:18:50 PM org.apache.coyote.ajp.AjpAprProtocol destroy
INFO: Stopping Coyote AJP/1.3 on ajp-8009
jakarta_service_20090403.log contains:
[2009-04-03 15:18:47] [1343 prunsrv.c] [debug] Procrun log initialized
[2009-04-03 15:18:47] [info] Procrun (2.0.4.0) started
[2009-04-03 15:18:47] [info] Running Service...
[2009-04-03 15:18:47] [1166 prunsrv.c] [debug] Inside ServiceMain...
[2009-04-03 15:18:47] [info] Starting service...
[2009-04-03 15:18:47] [385 javajni.c] [debug] Jvm Option[0]
-Dcatalina.home=C:\Tomcat
[2009-04-03 15:18:47] [385 javajni.c] [debug] Jvm Option[1]
-Dcatalina.base=C:\Tomcat
[2009-04-03 15:18:47] [385 javajni.c] [debug] Jvm Option[2]
-Djava.endorsed.dirs=C:\Tomcat\endorsed
[2009-04-03 15:18:47] [385 javajni.c] [debug] Jvm Option[3]
-Djava.io.tmpdir=C:\Tomcat\temp
[2009-04-03 15:18:47] [385 javajni.c] [debug] Jvm Option[4]
-Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager
[2009-04-03 15:18:47] [385 javajni.c] [debug] Jvm Option[5]
-Djava.util.logging.config.file=C:\Tomcat\conf\logging.properties
[2009-04-03 15:18:47] [385 javajni.c] [debug] Jvm Option[6]
-Djava.class.path=C:\Tomcat\bin\bootstrap.jar
[2009-04-03 15:18:47] [385 javajni.c] [debug] Jvm Option[7] vfprintf
[2009-04-03 15:18:47] [471 javajni.c] [debug] argv[0] = start
[2009-04-03 15:18:48] [1015 prunsrv.c] [debug] Java started
org/apache/catalina/startup/Bootstrap
[2009-04-03 15:18:48] [info] Service started in 1062 ms.
[2009-04-03 15:18:48] [1259 prunsrv.c] [debug] Waiting worker to finish...
localhost.2009-04-03.log contains:
Apr 3, 2009 3:18:48 PM org.apache.catalina.core.ApplicationContext log
INFO: ContextListener: contextInitialized()
Apr 3, 2009 3:18:48 PM org.apache.catalina.core.ApplicationContext log
INFO: SessionListener: contextInitialized()
Apr 3, 2009 3:18:49 PM org.apache.catalina.core.ApplicationContext log
INFO: SessionListener: contextDestroyed()
Apr 3, 2009 3:18:49 PM org.apache.catalina.core.ApplicationContext log
INFO: ContextListener: contextDestroyed()
Other log files are empty.
