cluster can't be started when not ending with % in the specification of the key word of the HTTP access log format.(GLASSFISH V4)

glassfishrobot commented 12 years ago

1、change the format in http-service like "%client.name% %datetime %status%", where the key word of "%" is deleted. 2、fail in starting the cluster.

Environment

all

Affected Versions

[9.0pe, 9.0peur1_dev, 9.0peur1, 9.1pe_dev, 9.1pe, 9.1peur1_dev, 9.1peur1, 9.1peur2_b03, 9.1peur2, 9.1.1_dev, 9.1.1, v2.1, v2.1.1_dev, v2.1.1, V3, v3.0.1, 3.1_dev, 3.1, 3.1.1_dev, 3.1.1, 3.1.2_dev, 3.1.2, 4.0_dev]

glassfishrobot commented 6 years ago

Issue Imported From: https://github.com/javaee/glassfish/issues/18601
Original Issue Raised By:@glassfishrobot
Original Issue Assigned To: @amyroh
Original Issue Closed By:@glassfishrobot

glassfishrobot commented 12 years ago

@glassfishrobot Commented jeremy_lv said: Reappear step 1. Create a cluster and instance related to this cluster.

2. Open cluster config related to the created cluster.

3. Access the Http-service and changed the format like " %client.name% %datetime %status% " where the datetime is not end with %.

4. start the related cluster but the cluster can't be start. Then check the serverlog but there's no obvious information about this phenomenon.

glassfishrobot commented 12 years ago

@glassfishrobot Commented jeremy_lv said: Because I can't commit the code, so I have uploaded my revised file in this trac.

glassfishrobot commented 12 years ago

@glassfishrobot Commented jeremy_lv said: the information in serverlog when start the cluster:

[#|2012-03-29T20:31:19.312+0800|INFO|44.0|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=44;_ThreadName=Thread-2;|Starting Cluster...|#]

[#|2012-03-29T20:31:19.312+0800|INFO|44.0|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=44;_ThreadName=Thread-2;|http://localhost:4848/management/domain/clusters/cluster/cluster008/start-cluster|#]

[#|2012-03-29T20:31:19.328+0800|INFO|44.0|ShoalLogger|_ThreadID=22;_ThreadName=Thread-2;|GMS1098: GMS:Announcing GroupStartup[INITIATED] for group: cluster008 members: instance008,|#]

[#|2012-03-29T20:31:19.328+0800|INFO|44.0|ShoalLogger|_ThreadID=22;_ThreadName=Thread-2;|GMS1062: GroupStart for group: cluster008 State: INITIATED Starting Members: instance008,|#]

[#|2012-03-29T20:31:19.328+0800|INFO|44.0|javax.enterprise.system.tools.admin.com.sun.enterprise.v3.admin.cluster|_ThreadID=22;_ThreadName=Thread-2;|Starting cluster cluster008|#]

[#|2012-03-29T20:31:19.328+0800|INFO|44.0|javax.enterprise.system.tools.admin.com.sun.enterprise.v3.admin.cluster|_ThreadID=22;_ThreadName=Thread-2;|Executing start-instance on 1 instances using a thread pool of size 1: localhost-domain1:instance008|#]

[#|2012-03-29T20:31:19.328+0800|INFO|44.0|javax.enterprise.system.tools.admin.com.sun.enterprise.v3.admin.cluster|_ThreadID=22;_ThreadName=Thread-2;|start-instance instance008|#]

[#|2012-03-29T20:31:59.156+0800|INFO|44.0|javax.enterprise.system.std.com.sun.enterprise.server.logging|_ThreadID=45;_ThreadName=Thread-2;|Attempting to start instance008.... Please look at the server log for more details.....|#]

[#|2012-03-29T20:32:49.109+0800|WARNING|44.0|javax.enterprise.system.tools.admin.com.sun.enterprise.v3.admin.cluster|_ThreadID=46;_ThreadName=Thread-2;|Could not start instance instance008 on node localhost-domain1 (localhost).: Command ' E:\glassfish3\glassfish\lib\nadmin.bat --_auxinput - --interactive=false start-local-instance --node localhost-domain1 --sync normal instance008' failed on node localhost-domain1 (localhost): Attempting to start instance008.... Please look at the server log for more details.....: To complete this operation run the following command locally on host localhost from the GlassFish install location E:\glassfish3:

lib/nadmin start-local-instance --node localhost-domain1 --sync normal instance008|#]

[#|2012-03-29T20:32:49.109+0800|SEVERE|44.0|javax.enterprise.system.tools.admin.com.sun.enterprise.v3.admin.cluster|_ThreadID=22;_ThreadName=Thread-2;|instance008: Could not start instance instance008 on node localhost-domain1 (localhost).

Command failed on node localhost-domain1 (localhost): Attempting to start instance008.... Please look at the server log for more details.....

To complete this operation run the following command locally on host localhost from the GlassFish install location E:\glassfish3:

lib/nadmin start-local-instance --node localhost-domain1 --sync normal instance008|#]

[#|2012-03-29T20:32:49.140+0800|SEVERE|44.0|org.glassfish.admingui|_ThreadID=44;_ThreadName=Thread-2;|RestResponse.getResponse() gives FAILURE. endpoint = 'http://localhost:4848/management/domain/clusters/cluster/cluster008/start-cluster'; attrs = '{}'|#]

glassfishrobot commented 12 years ago

@glassfishrobot Commented @shingwaichan said: Please check the current behavior of incorrect log format pattern.

glassfishrobot commented 12 years ago

@glassfishrobot Commented @amyroh said: Incorrect access logging pattern "%client.name% %auth-user-name% %datetime %request% %status% %response.length%" fails to start a cluster with appropriate logging message "WEB0325: Invalid component: datetime in access log pattern: %client.name% %auth-user-name% %datetime %request% %status% %response.length%" for me.

Can you start the cluster if you correct the access log pattern?

[#|2012-04-06T13:26:41.082-0700|INFO|44.0|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=10;_ThreadName=main;|WEB0169: Created HTTP listener [http-listener-1] on host/port [0.0.0.0:28080]|#]

[#|2012-04-06T13:26:41.091-0700|INFO|44.0|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=10;_ThreadName=main;|WEB0169: Created HTTP listener [http-listener-2] on host/port [0.0.0.0:28181]|#]

[#|2012-04-06T13:26:41.098-0700|INFO|44.0|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=10;_ThreadName=main;|WEB0169: Created HTTP listener [admin-listener] on host/port [0.0.0.0:24848]|#]

[#|2012-04-06T13:26:41.134-0700|SEVERE|44.0|javax.enterprise.system.container.web.com.sun.enterprise.web.accesslog|_ThreadID=10;_ThreadName=main;|WEB0325: Invalid component: datetime in access log pattern: %client.name% %auth-user-name% %datetime %request% %status% %response.length%|#]

[#|2012-04-06T13:26:41.135-0700|SEVERE|44.0|javax.enterprise.system.container.web.com.sun.enterprise.web.accesslog|_ThreadID=10;_ThreadName=main;|WEB0325: Invalid component: in access log pattern: %client.name% %auth-user-name% %datetime %request% %status% %response.length%|#]

[#|2012-04-06T13:26:41.135-0700|SEVERE|44.0|javax.enterprise.system.container.web.com.sun.enterprise.web.accesslog|_ThreadID=10;_ThreadName=main;|WEB0324: Missing end delimiter in access log pattern: %client.name% %auth-user-name% %datetime %request% %status% %response.length%|#]

[#|2012-04-06T13:26:41.135-0700|SEVERE|44.0|com.sun.enterprise.v3.server.RunLevelBridge|_ThreadID=10;_ThreadName=main;|problem starting LazyInhabitant-22926625(org.glassfish.kernel.javaee.WebContainerStarter, active: SingletonInhabitant-22257291(names=[],typeName=org.glassfish.kernel.javaee.WebContainerStarter,qualifiers=[],contracts=[],metadata=

{class: [org.glassfish.kernel.javaee.WebContainerStarter] index: [org.glassfish.api.Startup] }

) ): null|#]

[#|2012-04-06T13:26:41.136-0700|SEVERE|44.0|javax.enterprise.system.core.com.sun.enterprise.v3.server|_ThreadID=10;_ThreadName=main;|Startup service failed to start com.sun.enterprise.v3.server.StartupRunLevelBridge due to null|#]

[#|2012-04-06T13:26:41.176-0700|INFO|44.0|javax.enterprise.system.tools.admin.com.sun.enterprise.v3.admin.cluster|_ThreadID=11;_ThreadName=Thread-7;|Server shutdown initiated|#]

[#|2012-04-06T13:26:41.184-0700|INFO|44.0|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=10;_ThreadName=main;|WEB0169: Created HTTP listener [http-listener-1] on host/port [0.0.0.0:28080]|#]

[#|2012-04-06T13:26:41.188-0700|INFO|44.0|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=10;_ThreadName=main;|WEB0169: Created HTTP listener [http-listener-2] on host/port [0.0.0.0:28181]|#]

[#|2012-04-06T13:26:41.196-0700|INFO|44.0|javax.enterprise.system.container.web.com.sun.enterprise.web|_ThreadID=10;_ThreadName=main;|WEB0169: Created HTTP listener [admin-listener] on host/port [0.0.0.0:24848]|#]

[#|2012-04-06T13:26:41.209-0700|SEVERE|44.0|javax.enterprise.system.container.web.com.sun.enterprise.web.accesslog|_ThreadID=10;_ThreadName=main;|WEB0325: Invalid component: datetime in access log pattern: %client.name% %auth-user-name% %datetime %request% %status% %response.length%|#]

[#|2012-04-06T13:26:41.210-0700|SEVERE|44.0|javax.enterprise.system.container.web.com.sun.enterprise.web.accesslog|_ThreadID=10;_ThreadName=main;|WEB0325: Invalid component: in access log pattern: %client.name% %auth-user-name% %datetime %request% %status% %response.length%|#]

[#|2012-04-06T13:26:41.210-0700|SEVERE|44.0|javax.enterprise.system.container.web.com.sun.enterprise.web.accesslog|_ThreadID=10;_ThreadName=main;|WEB0324: Missing end delimiter in access log pattern: %client.name% %auth-user-name% %datetime %request% %status% %response.length%|#]

[#|2012-04-06T13:26:41.210-0700|SEVERE|44.0|com.sun.enterprise.v3.server.RunLevelBridge|_ThreadID=10;_ThreadName=main;|problem starting LazyInhabitant-22926625(org.glassfish.kernel.javaee.WebContainerStarter, active: SingletonInhabitant-22257291(names=[],typeName=org.glassfish.kernel.javaee.WebContainerStarter,qualifiers=[],contracts=[],metadata=

{class: [org.glassfish.kernel.javaee.WebContainerStarter] index: [org.glassfish.api.Startup] }

) ): String index out of range: -78|#]

glassfishrobot commented 12 years ago

@glassfishrobot Commented @amyroh said: Full server.log displaying the correct error message - "WEB0325: Invalid component: datetime in access log pattern: %client.name% %auth-user-name% %datetime %request% %status% %response.length%"

glassfishrobot commented 12 years ago

@glassfishrobot Commented @amyroh said: Fixed in svn 53475. The fix will be available in b33.

glassfishrobot commented 12 years ago

@glassfishrobot Commented File: DefaultAccessLogFormatterImpl.java Attached By: jeremy_lv

glassfishrobot commented 12 years ago

@glassfishrobot Commented File: DefaultAccessLogFormatterImpl.patch Attached By: jeremy_lv

glassfishrobot commented 12 years ago

@glassfishrobot Commented File: screenshot-1.jpg Attached By: jeremy_lv

glassfishrobot commented 12 years ago

@glassfishrobot Commented File: screenshot-2.jpg Attached By: jeremy_lv

glassfishrobot commented 12 years ago

@glassfishrobot Commented File: server.log Attached By: @amyroh

glassfishrobot commented 7 years ago

@glassfishrobot Commented This issue was imported from java.net JIRA GLASSFISH-18601

glassfishrobot commented 12 years ago

@glassfishrobot Commented Reported by jeremy_lv

glassfishrobot commented 12 years ago

@glassfishrobot Commented Marked as fixed on Wednesday, April 11th 2012, 2:48:37 pm

eclipse-ee4j / glassfish

cluster can't be started when not ending with % in the specification of the key word of the HTTP access log format.(GLASSFISH V4) #18601

Environment

Affected Versions