Details
-
Type: Bug
-
Status: Closed
-
Priority: Major
-
Resolution: Fixed
-
Affects Version/s: EE-3.0.0.GA_P01, 3.2
-
Fix Version/s: EE-3.0.0.GA_P01, 3.2
-
Component/s: None
-
Labels:None
-
Environment:ICEFaces 3.0 maintenance branch + WebSphere Portal + eps
http://server.ice:8888/svn/repo/icefaces-ee3/branches/icefaces-ee-3.0.0.GA-maintenance
-
Assignee Priority:P1
Description
ICEpush in WebSphere Portal requires EPS to work. Old version of eps.jar from March 5 still works, but new version generated from maintenance branch does not, which indicates the IBM infrastructure is configured properly.
Symptom include a loop requesting the following:
[620.01 10:06:26.258s] -- POST /eps/listen.icepush HTTP/1.1
[620.02 10:06:26.268s] -- POST /eps/listen.icepush HTTP/1.1
[620.02 10:06:26.277s] -- POST /eps/listen.icepush HTTP/1.1
[620.14 10:06:26.394s] -- POST /eps/listen.icepush HTTP/1.1
The responses are all <noop/>
logging on the console contains:
[22/08/12 10:28:05:852 PDT] 00000031 BlockingConne 1 ICEpush metric: IP: 127.0.0.1 pushIds: [v59mrr05, v59mrr06] Cloud Push ID: null Browser: 5h66oci3l last request: 6 Latency: 5 connectionRecreationTimeout: 500
[22/08/12 10:28:05:852 PDT] 00000031 BlockingConne 1 Request does not contain pushIDs.
[22/08/12 10:28:05:853 PDT] 00000031 BlockingConne 3 Sending NoOp.
[22/08/12 10:28:05:858 PDT] 00000031 BlockingConne 1 ICEpush metric: IP: 127.0.0.1 pushIds: [v59mrr05, v59mrr06] Cloud Push ID: null Browser: 5h66oci3l last request: 6 Latency: 5 connectionRecreationTimeout: 500
[22/08/12 10:28:05:858 PDT] 00000031 BlockingConne 1 Request does not contain pushIDs.
[22/08/12 10:28:05:858 PDT] 00000031 BlockingConne 3 Sending NoOp.
[22/08/12 10:28:06:055 PDT] 0000003a BlockingConne 1 ICEpush metric: IP: 127.0.0.1 pushIds: [v59mrr05, v59mrr06] Cloud Push ID: null Browser: 5h66oci3l last request: 197 Latency: 197 connectionRecreationTimeout: 500
[22/08/12 10:28:06:055 PDT] 0000003a BlockingConne 1 Request does not contain pushIDs.
[22/08/12 10:28:06:055 PDT] 0000003a BlockingConne 3 Sending NoOp.
I'll post further logging statements as they become available.
Symptom include a loop requesting the following:
[620.01 10:06:26.258s] -- POST /eps/listen.icepush HTTP/1.1
[620.02 10:06:26.268s] -- POST /eps/listen.icepush HTTP/1.1
[620.02 10:06:26.277s] -- POST /eps/listen.icepush HTTP/1.1
[620.14 10:06:26.394s] -- POST /eps/listen.icepush HTTP/1.1
The responses are all <noop/>
logging on the console contains:
[22/08/12 10:28:05:852 PDT] 00000031 BlockingConne 1 ICEpush metric: IP: 127.0.0.1 pushIds: [v59mrr05, v59mrr06] Cloud Push ID: null Browser: 5h66oci3l last request: 6 Latency: 5 connectionRecreationTimeout: 500
[22/08/12 10:28:05:852 PDT] 00000031 BlockingConne 1 Request does not contain pushIDs.
[22/08/12 10:28:05:853 PDT] 00000031 BlockingConne 3 Sending NoOp.
[22/08/12 10:28:05:858 PDT] 00000031 BlockingConne 1 ICEpush metric: IP: 127.0.0.1 pushIds: [v59mrr05, v59mrr06] Cloud Push ID: null Browser: 5h66oci3l last request: 6 Latency: 5 connectionRecreationTimeout: 500
[22/08/12 10:28:05:858 PDT] 00000031 BlockingConne 1 Request does not contain pushIDs.
[22/08/12 10:28:05:858 PDT] 00000031 BlockingConne 3 Sending NoOp.
[22/08/12 10:28:06:055 PDT] 0000003a BlockingConne 1 ICEpush metric: IP: 127.0.0.1 pushIds: [v59mrr05, v59mrr06] Cloud Push ID: null Browser: 5h66oci3l last request: 197 Latency: 197 connectionRecreationTimeout: 500
[22/08/12 10:28:06:055 PDT] 0000003a BlockingConne 1 Request does not contain pushIDs.
[22/08/12 10:28:06:055 PDT] 0000003a BlockingConne 3 Sending NoOp.
I'll post further logging statements as they become available.
Here's some logging from startup detection. The message about it being to late to set the outgoing cookie looks timely and suspicious.
[22/08/12 10:05:16:133 PDT] 000000b8 CoreMessageSe I Enterprise Push Server detected: "Enterprise Push Server 3.0.0 GA_P01"
[22/08/12 10:05:16:151 PDT] 000000c7 CoreMessageSe I Enterprise Push Server detected: "Enterprise Push Server 3.0.0 GA_P01"
[22/08/12 10:05:16:199 PDT] 00000030 MainServlet I
ICEsoft Technologies Inc.
ICEpush-EE 3.0.0.GA
Build number: 10
Revision: 30951
[22/08/12 10:05:25:832 PDT] 00000030 flash W JSF1095: The response was already committed by the time we tried to set the outgoing cookie for the flash. Any values stored to the flash will not be available on the next request.
[22/08/12 10:05:25:838 PDT] 00000030 srt W com.ibm.ws.webcontainer.srt.SRTServletResponse setHeader WARNING: Cannot set header. Response already committed.
[22/08/12 10:05:25:839 PDT] 00000030 srt W com.ibm.ws.webcontainer.srt.SRTServletResponse setHeader WARNING: Cannot set header. Response already committed.
[22/08/12 10:05:25:840 PDT] 00000030 srt W com.ibm.ws.webcontainer.srt.SRTServletResponse setHeader WARNING: Cannot set header. Response already committed.
[22/08/12 10:06:08:317 PDT] 00000031 servlet I com.ibm.ws.webcontainer.servlet.ServletWrapper init SRVE0242I: [PA_ICE3CnentSuiteDemo] [/wps/PA_ICE3CnentSuiteDemo] [progressBarPush]: Initialization successful.
[22/08/12 10:06:14:767 PDT] 00000037 EnvironmentAd I Adapting to Thread Blocking environment
[22/08/12 10:06:15:058 PDT] 00000037 EnvironmentAd I Adapting to Thread Blocking environment
[22/08/12 10:21:28:323 PDT] 00000037 ConfigFileHel I ConfigFileHelper checkForAutoRefreshWorkSpace Master repository refresh
Here's some logging from the startup protocol once I go to visit the Progress Bar push page:
[22/08/12 10:27:00:366 PDT] 00000037 ManagerAdmin I TRAS0018I: The trace state has changed. The new trace state is =info:com.icesoft.icepush.=finest:com.icesoft.net.=detail:org.icepush.=finest.
[22/08/12 10:27:36:299 PDT] 0000003a BrowserDispat 3 Discard browser with ID 'null' since is no longer used.
[22/08/12 10:27:36:421 PDT] 0000003a BrowserDispat 1 Discarded browser bound server for ID=null
[22/08/12 10:27:36:678 PDT] 0000003a EnvironmentAd I Adapting to Thread Blocking environment
[22/08/12 10:27:36:681 PDT] 0000003a BrowserDispat 3 New browser detected, assigning ID '5h66oci3l'.
[22/08/12 10:27:36:690 PDT] 0000003a Configuration 1 Re-configured bridge.
[22/08/12 10:27:36:707 PDT] 0000003a BrowserDispat 3 Discard browser with ID '5h66oci3l' since is no longer used.
[22/08/12 10:27:36:708 PDT] 0000003a BrowserDispat 1 Discarded browser bound server for ID=5h66oci3l
[22/08/12 10:27:36:724 PDT] 0000003a EnvironmentAd I Adapting to Thread Blocking environment
[22/08/12 10:27:36:730 PDT] 0000003a BrowserDispat 3 New browser detected, assigning ID '5h66oci3l'.
[22/08/12 10:27:36:730 PDT] 0000003a BlockingConne 1 ICEpush metric: IP: 127.0.0.1 pushIds: [v59mrr05, v59mrr06] Cloud Push ID: null Browser: 5h66oci3l last request: 22 Latency: 22 connectionRecreationTimeout: 4800
[22/08/12 10:27:36:730 PDT] 0000003a BlockingConne 1 Request does not contain pushIDs.
[22/08/12 10:27:36:732 PDT] 0000003a BlockingConne 3 Sending NoOp.