------- Lightstreamer Server Log ------------------------ 2012-11-05 06:25:06,851 INFO [dk.autocom.lib.ejb.RemoteInvoker] calling authenticateUser.. 2012-11-05 06:25:06,857 INFO [dk.autocom.lib.ejb.RemoteInvoker] calling authenticateUser success.. 2012-11-05 06:25:06,857 INFO [dk.autocom.lightstreamer.bid.BidMetaAdapter] 835F70C586C2E5897EF665B8FE5C4D49 was authenticated userId: 1624289 2012-11-05 06:25:07,322 INFO [dk.autocom.lightstreamer.bid.BidJmsAdapter] Subscribing to bidBroadcast_aucId:119015_enrollId:4958286 2012-11-05 06:25:07,322 INFO [dk.autocom.lightstreamer.bid.BidJmsAdapter] (Subscribing) Valid item: bidBroadcast_aucId:119015_enrollId:4958286 2012-11-05 06:25:07,322 INFO [dk.autocom.lib.ejb.RemoteInvoker] calling authenticateUser.. 2012-11-05 06:25:07,327 INFO [dk.autocom.lib.ejb.RemoteInvoker] calling authenticateUser success.. 2012-11-05 06:25:07,327 INFO [dk.autocom.lib.ejb.RemoteInvoker] calling authenticateUser.. 2012-11-05 06:25:07,331 INFO [dk.autocom.lightstreamer.bid.BidJmsAdapter] BidJmsAdapter received message from auction on item bidBroadcast_aucId:119015_enrollId:4958286 2012-11-05 06:25:07,332 INFO [dk.autocom.lib.ejb.RemoteInvoker] calling authenticateUser success.. 2012-11-05 06:25:07,333 INFO [dk.autocom.lightstreamer.bid.BidMetaAdapter] Customizing message for user (UserId: 1624289) item (bidBroadcast_aucId:119015_enrollId:4958286) took 0 ms 2012-11-05 06:25:14,331 INFO [dk.autocom.lib.ejb.RemoteInvoker] calling authenticateUser.. 2012-11-05 06:25:14,337 INFO [dk.autocom.lib.ejb.RemoteInvoker] calling authenticateUser success.. 2012-11-05 06:25:14,337 INFO [dk.autocom.lightstreamer.bid.BidMetaAdapter] 835F70C586C2E5897EF665B8FE5C4D49 was authenticated userId: 1624289 2012-11-05 06:25:15,165 INFO [dk.autocom.lightstreamer.bid.BidMetaAdapter] Customizing message for user (UserId: 1624289) item (bidBroadcast_aucId:119015_enrollId:4958286) took 1 ms 2012-11-05 06:25:21,931 INFO [dk.autocom.lightstreamer.bid.BidJmsAdapter] Unsubscribing from bidBroadcast_aucId:119015_enrollId:4958286 2012-11-05 06:25:30,354 WARN [dk.autocom.lightstreamer.bid.BidJmsAdapter] Received update for unsubscribed handle: bidBroadcast_aucId:119015_enrollId:4958160 2012-11-05 06:25:30,376 INFO [dk.autocom.lightstreamer.auctioneerscreen.AuctioneerScreenJmsAdapter] BidJmsAdapter received message from auction on item asBroadcast_aucId:119015_enrollId:4958160 2012-11-05 06:25:30,377 INFO [dk.autocom.lightstreamer.auctioneerscreen.AuctioneerScreenMetaAdapter] Null event skipped 2012-11-05 06:25:30,378 INFO [dk.autocom.lightstreamer.auctioneerscreen.AuctioneerScreenMetaAdapter] Customizing message for user (UserId: 1624289) item (asBroadcast_aucId:119015_enrollId:4958160) took 1 ms 2012-11-05 06:25:30,400 WARN [dk.autocom.lightstreamer.bid.BidJmsAdapter] Received update for unsubscribed handle: bidBroadcast_aucId:119015_enrollId:4958099 2012-11-05 06:25:30,402 INFO [dk.autocom.lightstreamer.auctioneerscreen.AuctioneerScreenJmsAdapter] BidJmsAdapter received message from auction on item asBroadcast_aucId:119015_enrollId:4958099 2012-11-05 06:25:30,411 INFO [dk.autocom.lightstreamer.auctioneerscreen.AuctioneerScreenMetaAdapter] Null event skipped 2012-11-05 06:25:30,412 INFO [dk.autocom.lightstreamer.auctioneerscreen.AuctioneerScreenMetaAdapter] Customizing message for user (UserId: 1624289) item (asBroadcast_aucId:119015_enrollId:4958099) took 1 ms 2012-11-05 06:25:30,483 WARN [dk.autocom.lightstreamer.bid.BidJmsAdapter] Received update for unsubscribed handle: bidBroadcast_aucId:119015_enrollId:4958038 2012-11-05 06:25:30,484 INFO [dk.autocom.lightstreamer.auctioneerscreen.AuctioneerScreenJmsAdapter] BidJmsAdapter received message from auction on item asBroadcast_aucId:119015_enrollId:4958038 2012-11-05 06:25:30,485 INFO [dk.autocom.lightstreamer.auctioneerscreen.AuctioneerScreenMetaAdapter] Null event skipped 2012-11-05 06:25:30,485 INFO [dk.autocom.lightstreamer.auctioneerscreen.AuctioneerScreenMetaAdapter] Customizing message for user (UserId: 1624289) item (asBroadcast_aucId:119015_enrollId:4958038) took 0 ms 2012-11-05 06:25:30,550 WARN [dk.autocom.lightstreamer.bid.BidJmsAdapter] Received update for unsubscribed handle: bidBroadcast_aucId:119015_enrollId:4958221 2012-11-05 06:25:30,551 INFO [dk.autocom.lightstreamer.auctioneerscreen.AuctioneerScreenJmsAdapter] BidJmsAdapter received message from auction on item asBroadcast_aucId:119015_enrollId:4958221 2012-11-05 06:25:30,552 INFO [dk.autocom.lightstreamer.auctioneerscreen.AuctioneerScreenMetaAdapter] Null event skipped 2012-11-05 06:25:30,552 INFO [dk.autocom.lightstreamer.auctioneerscreen.AuctioneerScreenMetaAdapter] Customizing message for user (UserId: 1624289) item (asBroadcast_aucId:119015_enrollId:4958221) took 0 ms 2012-11-05 06:25:30,602 WARN [dk.autocom.lightstreamer.bid.BidJmsAdapter] Received update for unsubscribed handle: bidBroadcast_aucId:119015_enrollId:4958282 2012-11-05 06:25:30,604 INFO [dk.autocom.lightstreamer.auctioneerscreen.AuctioneerScreenJmsAdapter] BidJmsAdapter received message from auction on item asBroadcast_aucId:119015_enrollId:4958282 2012-11-05 06:25:30,768 INFO [dk.autocom.lightstreamer.auctioneerscreen.AuctioneerScreenMetaAdapter] Null event skipped 2012-11-05 06:25:30,769 INFO [dk.autocom.lightstreamer.auctioneerscreen.AuctioneerScreenMetaAdapter] Customizing message for user (UserId: 1624289) item (asBroadcast_aucId:119015_enrollId:4958282) took 1 ms 2012-11-05 06:25:34,311 INFO [dk.autocom.lib.ejb.RemoteInvoker] calling authenticateUser.. 2012-11-05 06:25:34,317 INFO [dk.autocom.lib.ejb.RemoteInvoker] calling authenticateUser success.. 2012-11-05 06:25:34,317 INFO [dk.autocom.lightstreamer.bid.BidMetaAdapter] 835F70C586C2E5897EF665B8FE5C4D49 was authenticated userId: 1624289 ----- LightStreamer Log------------------- 05-Nov-12 06:25:01,734|INFO |LightstreamerLogger.requests |FOR PUMPS PARKING DESTROYER|Closed session Sfc845bc879b2ee25T2432518 with internal cause code: 38 (Interrupted) 05-Nov-12 06:25:06,850|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 2 |Serving request: /lightstreamer/create_session.js --> LS_phase=4101&LS_cause=new.api&LS_polling=true&LS_polling_millis=0&LS_idle_millis=0&LS_client_version=6.0&LS_adapter_set=BIDADAPTERSET&LS_user=835F70C586C2E5897EF665B8FE5C4D49&LS_password=[...]&LS_container=lsc& on "LS-02" from 115.187.236.168:51440 05-Nov-12 06:25:06,859|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 8 |Starting new session: S10eb94b88dc59d89T2506857 on "LS-02" from 115.187.236.168:51440 05-Nov-12 06:25:07,249|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 9 |Serving request: /lightstreamer/bind_session.js --> LS_session=S10eb94b88dc59d89T2506857&LS_phase=4104&LS_cause=ws.notgood&LS_content_length=500000&LS_container=lsc& on "LS-02" from 115.187.236.168:51439 05-Nov-12 06:25:07,250|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 9 |Attaching session: S10eb94b88dc59d89T2506857 on "LS-02" from 115.187.236.168:51439 05-Nov-12 06:25:07,320|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 1 |Serving request: /lightstreamer/control.js --> LS_table=1&LS_win_phase=3&LS_req_phase=544&LS_op=add&LS_mode=MERGE&LS_id=bidBroadcast_aucId%3A119015_enrollId%3A4958286&LS_schema=ENROLL_ID%20NEXT_QUICK_BID%20ENROLL_END_DATE%20ENROLL_END_DATE_FORMATTED%20CURRENT_BID%20CURRENT_BID_COLOR%20STATUS%20STATUS_COLOR%20ADDITIONAL_STATUS%20CURRENT_BID_OTHER_CURRENCY_FORMATTED%20IS_EXTENDED%20ENROLL_STATUS&LS_data_adapter=BIDADAPTER&LS_snapshot=true&LS_unique=1&LS_session=S10eb94b88dc59d89T2506857& on "LS-02" from 115.187.236.168:51440 05-Nov-12 06:25:07,321|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 5 |Controlling session: S10eb94b88dc59d89T2506857 on "LS-02" from 115.187.236.168:51440 05-Nov-12 06:25:12,956|INFO |LightstreamerLogger.requests |FOR PUMPS PARKING DESTROYER|Closed session S10eb94b88dc59d89T2506857 with internal cause code: 38 (Interrupted) 05-Nov-12 06:25:14,330|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 7 |Serving request: /lightstreamer/create_session.js --> LS_phase=6801&LS_cause=new.api&LS_polling=true&LS_polling_millis=0&LS_idle_millis=0&LS_client_version=6.0&LS_adapter_set=BIDADAPTERSET&LS_user=835F70C586C2E5897EF665B8FE5C4D49&LS_password=[...]&LS_container=lsc& on "LS-02" from 115.187.236.168:51440 05-Nov-12 06:25:14,339|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 10 |Starting new session: S423e1d9dca15546cT2514338 on "LS-02" from 115.187.236.168:51440 05-Nov-12 06:25:14,721|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 9 |Serving request: /lightstreamer/bind_session.js --> LS_session=S423e1d9dca15546cT2514338&LS_phase=6804&LS_cause=ws.notgood&LS_content_length=500000&LS_container=lsc& on "LS-02" from 115.187.236.168:51440 05-Nov-12 06:25:14,722|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 9 |Attaching session: S423e1d9dca15546cT2514338 on "LS-02" from 115.187.236.168:51440 05-Nov-12 06:25:15,162|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 4 |Serving request: /lightstreamer/control.js --> LS_table=1&LS_win_phase=80&LS_req_phase=79&LS_op=add&LS_mode=MERGE&LS_id=bidBroadcast_aucId%3A119015_enrollId%3A4958286&LS_schema=ENROLL_ID%20NEXT_QUICK_BID%20ENROLL_END_DATE%20ENROLL_END_DATE_FORMATTED%20CURRENT_BID%20CURRENT_BID_COLOR%20STATUS%20STATUS_COLOR%20ADDITIONAL_STATUS%20CURRENT_BID_OTHER_CURRENCY_FORMATTED%20IS_EXTENDED%20ENROLL_STATUS&LS_data_adapter=BIDADAPTER&LS_snapshot=true&LS_unique=1&LS_session=S423e1d9dca15546cT2514338& on "LS-02" from 115.187.236.168:51442 05-Nov-12 06:25:15,163|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 5 |Controlling session: S423e1d9dca15546cT2514338 on "LS-02" from 115.187.236.168:51442 05-Nov-12 06:25:16,928|INFO |LightstreamerLogger.requests |FOR PUMPS PARKING DESTROYER|Closed session S423e1d9dca15546cT2514338 with internal cause code: 38 (Interrupted) 05-Nov-12 06:25:34,310|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 1 |Serving request: /lightstreamer/create_session.js --> LS_phase=2801&LS_cause=new.api&LS_polling=true&LS_polling_millis=0&LS_idle_millis=0&LS_client_version=6.0&LS_adapter_set=BIDADAPTERSET&LS_user=835F70C586C2E5897EF665B8FE5C4D49&LS_password=[...]&LS_container=lsc& on "LS-02" from 115.187.236.168:51442 05-Nov-12 06:25:34,318|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 1 |Starting new session: Se3f8fe0b82ff9a4cT2534317 on "LS-02" from 115.187.236.168:51442 05-Nov-12 06:25:34,795|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 2 |Serving request: /lightstreamer/bind_session.js --> LS_session=Se3f8fe0b82ff9a4cT2534317&LS_phase=2804&LS_cause=ws.notgood&LS_content_length=500000&LS_container=lsc& on "LS-02" from 115.187.236.168:51442 05-Nov-12 06:25:34,797|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 2 |Attaching session: Se3f8fe0b82ff9a4cT2534317 on "LS-02" from 115.187.236.168:51442 05-Nov-12 06:25:38,952|INFO |LightstreamerLogger.requests |FOR PUMPS PARKING DESTROYER|Closed session Se3f8fe0b82ff9a4cT2534317 with internal cause code: 38 (Interrupted) ------------- Our Server Log ---------------------------- 2012-11-05 06:25:07,326 INFO [dk.autocom.auction.auction.AuctionManager] Broadcasting bid for 4958286 current bid: 9200 2012-11-05 06:25:13,734 INFO [STDOUT] 1352093111470, 2012-11-05 06:25:13,734 INFO [STDOUT] 1352093113734, 2012-11-05 06:25:17,355 INFO [STDOUT] 1352093115090, 2012-11-05 06:25:17,355 INFO [STDOUT] 1352093117355, 2012-11-05 06:25:18,038 ERROR [dk.autocom.auction.actions.JSErrorLogAction] Humanreadable: require(...) failed to subscribe in lightstreamer server Optional info: undefined Url: http://www-blah-blah-blah.com:10080/dealer/ol_ls.do?olrenrmt=4958286 User: User: 1624289 / Jason / Autorola / Tolderlundsvej 3E 2. null, 5000 5260 BrowserInfo: en-us, true, x86, 0, Microsoft Internet Explorer, true, Win32, en-au, Mozilla/5.0 (compatible; MSIE 9.0; Windows NT 6.1; Trident/5.0; SLCC2; .NET CLR 2.0.50727; .NET CLR 3.5.30729; .NET CLR 3.0.30729; Media Center PC 6.0; .NET4.0C; Tablet PC 2.0), en-au, 5.0 (compatible; MSIE 9.0; Windows NT 6.1; Trident/5.0; SLCC2; .NET CLR 2.0.50727; .NET CLR 3.5.30729; .NET CLR 3.0.30729; Media Center PC 6.0; .NET4.0C; Tablet PC 2.0) Exception: Please specify at least one cell Stacktrace: 1. {anonymous}(#object) 2. printStackTrace(#object) 3. {anonymous}("require(...) failed to subscribe in lightstreamer server",#object,null,http://www-blah-blah-blah.com:10080/dealer/ol_ls.do?olrenrmt=4958286) 4. {anonymous}("require(...) failed to subscribe in lightstreamer server",#object,null) 5. {anonymous}(#object,#function,#function) 6. {anonymous}("_@r4",#function,[#object...#function],undefined) 7. {anonymous}() 8. {anonymous}(#function) 9. {anonymous}(#function) 10. {anonymous}(#function,0,[#function]) java.lang.Exception: Auto generated exception - Please make application log one instead at dk.autocom.lib.log.AutocomLogger.error(AutocomLogger.java:93) at dk.autocom.lib.log.AutocomLogger.error(AutocomLogger.java:152) at dk.autocom.lib.log.AutocomLogger.error(AutocomLogger.java:141) at dk.autocom.auction.actions.JSErrorLogAction.logError(JSErrorLogAction.java:92) at dk.autocom.auction.actions.JSErrorLogAction.execute(JSErrorLogAction.java:30) at org.apache.struts.action.RequestProcessor.processActionPerform(RequestProcessor.java:484) at dk.autocom.auction.struts.AutocomRequestProcessor.processActionPerform(AutocomRequestProcessor.java:194) at org.apache.struts.action.RequestProcessor.process(RequestProcessor.java:274) at org.apache.struts.action.ActionServlet.process(ActionServlet.java:1482) at org.apache.struts.action.ActionServlet.doGet(ActionServlet.java:507) at javax.servlet.http.HttpServlet.service(HttpServlet.java:690) at dk.autocom.auction.servlets.StrutsServlet.processRequest(StrutsServlet.java:542) at dk.autocom.auction.servlets.StrutsServlet.service(StrutsServlet.java:165) at javax.servlet.http.HttpServlet.service(HttpServlet.java:803) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at dk.autocom.lib.servletutils.CharsetFilter.doFilter(CharsetFilter.java:38) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:96) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:230) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175) at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:182) at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:84) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102) at org.jboss.web.tomcat.service.jca.CachedConnectionValve.invoke(CachedConnectionValve.java:157) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:262) at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844) at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583) at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:446) at java.lang.Thread.run(Thread.java:662)