Topic
IC4NOTICE: developerWorks Community will be offline May 29-30, 2015 while we upgrade to the latest version of IBM Connections. For more information, read our upgrade FAQ.
3 replies Latest Post - ‏2012-10-05T19:58:32Z by SystemAdmin
SystemAdmin
SystemAdmin
2327 Posts
ACCEPTED ANSWER

Pinned topic Double JSESSIONID sent by Worklight Server in Adapter

‏2012-09-18T15:26:59Z |
There are some strange double JSESSIONIDs in the Worklight server log. On clients' side, sometimes the adapter invocation is failed. Has anyone seen this?

Server log:

2012-09-18 10:54:54.0297 DEBUG pool-7-thread-7 com.worklight.integration.services.impl.DataAccessServiceImpl ? Procedure 'HttpRequest' invocation started.
2012-09-18 10:54:54.0297 DEBUG pool-7-thread-7 com.worklight.integration.model.ConnectionManager ? ADDING connection user for -879300225971046287
2012-09-18 10:54:54.0297 DEBUG pool-7-thread-7 com.worklight.integration.model.ConnectionManager ? there are now 2 connection users for request id -879300225971046287
2012-09-18 10:54:54.0297 DEBUG pool-7-thread-7 org.apache.http.impl.conn.tsccm.ThreadSafeClientConnManager ? Get connection: HttpRoute{}->http://host.com:48348, timeout = 0
2012-09-18 10:54:54.0297 DEBUG pool-7-thread-7 org.apache.http.impl.conn.tsccm.ConnPoolByRoute ? [HttpRoute{}->http://host.com:48348] total kept alive: 1, total issued: 0, total allocated: 1 out of 8
2012-09-18 10:54:54.0297 DEBUG pool-7-thread-7 org.apache.http.impl.conn.tsccm.ConnPoolByRoute ? Getting free connection [HttpRoute{}->http://host.com:48348]null
2012-09-18 10:54:54.0297 DEBUG pool-7-thread-7 org.apache.http.impl.client.DefaultHttpClient ? Stale connection check
2012-09-18 10:54:54.0297 DEBUG pool-7-thread-7 org.apache.http.impl.client.DefaultHttpClient ? Stale connection detected
2012-09-18 10:54:54.0297 DEBUG pool-7-thread-7 org.apache.http.impl.conn.DefaultClientConnection ? Connection closed
2012-09-18 10:54:54.0297 DEBUG pool-7-thread-7 org.apache.http.impl.conn.DefaultClientConnectionOperator ? Connecting to host.com:48348
2012-09-18 10:54:54.0297 DEBUG pool-7-thread-7 org.apache.http.client.protocol.RequestAddCookies ? CookieSpec selected: best-match
2012-09-18 10:54:54.0297 DEBUG pool-7-thread-7 org.apache.http.client.protocol.RequestAddCookies ? Cookie version: 0name: JSESSIONIDvalue: 2FFC06B7089487CB4897BF0B30B7CCD2domain: host.compath: /realexpiry: null match host.com:48348/real/services
2012-09-18 10:54:54.0297 DEBUG pool-7-thread-7 org.apache.http.client.protocol.RequestAddCookies ? Cookie version: 0name: JSESSIONIDvalue: B139C7B14CE3EEA98F6402EB580C2B4Fdomain: host.compath: /real/servicesexpiry: null match host.com:48348/real/services
2012-09-18 10:54:54.0297 DEBUG pool-7-thread-7 org.apache.http.client.protocol.RequestAddCookies ? Cookie version: 0name: Pathvalue: /realdomain: host.compath: /real/servicesexpiry: null match host.com:48348/real/services
2012-09-18 10:54:54.0297 DEBUG pool-7-thread-7 org.apache.http.client.protocol.RequestAddCookies ? Cookie version: 0name: Securevalue: domain: host.compath: /real/servicesexpiry: null match host.com:48348/real/services
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.client.protocol.RequestAuthCache ? Auth cache not set in the context
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.impl.client.DefaultHttpClient ? Attempt 1 to execute request
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.impl.conn.DefaultClientConnection ? Sending request: POST /real/services HTTP/1.1
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.wire ? >> "POST /real/services HTTP/1.1\r\n"
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.wire ? >> "header_data: data_value\r\n"
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.wire ? >> "Content-Length: 686\r\n"
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.wire ? >> "Content-Type: application/x-www-form-urlencoded\r\n"
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.wire ? >> "Host: host.com:48348\r\n"
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.wire ? >> "Connection: Keep-Alive\r\n"
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.wire ? >> "User-Agent: Apache-HttpClient/4.1.2 (java 1.5)\r\n"
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.wire ? >> "Cookie: *JSESSIONID*=2FFC06B7089487CB4897BF0B30B7CCD2; *JSESSIONID*=B139C7B14CE3EEA98F6402EB580C2B4F; Path=/real; Secure=\r\n"
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.wire ? >> "Cookie2: $Version=1\r\n"
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.wire ? >> "\r\n"
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.headers ? >> POST /real/services HTTP/1.1
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.headers ? >> header_data: data_value
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.headers ? >> Content-Length: 686
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.headers ? >> Content-Type: application/x-www-form-urlencoded
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.headers ? >> Host: host.com:48348
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.headers ? >> Connection: Keep-Alive
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.headers ? >> User-Agent: Apache-HttpClient/4.1.2 (java 1.5)
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.headers ? >> Cookie: *JSESSIONID*=2FFC06B7089487CB4897BF0B30B7CCD2; *JSESSIONID*=B139C7B14CE3EEA98F6402EB580C2B4F; Path=/real; Secure=
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.headers ? >> Cookie2: $Version=1
2012-09-18 10:54:54.0312 DEBUG pool-7-thread-7 org.apache.http.wire ? >> "<?xml version="1.0" encoding="UTF-8" standalone="yes"?><ServiceRequest ></ServiceRequest>"
2012-09-18 10:54:54.0719 DEBUG TaskCoordinatorTimer com.worklight.core.tasks.TaskThread ? Triggered task ClusterDataSynchronizationTask
2012-09-18 10:54:54.0719 DEBUG ClusterDataSynchronizationTask com.worklight.gadgets.bean.ClusterDataSynchronizationTask ? Running cluster synchronization task.
2012-09-18 10:54:54.0719 DEBUG ClusterDataSynchronizationTask com.worklight.core.util.RssBrokerUtils ? Transaction begin : 9549660 (new:true; completed:false; rollbackOnly:false)REQUIRED
2012-09-18 10:54:54.0719 DEBUG ClusterDataSynchronizationTask com.worklight.core.util.RssBrokerUtils ? Transaction commit: 9549660 (new:true; completed:false; rollbackOnly:false)
2012-09-18 10:54:54.0719 DEBUG ClusterDataSynchronizationTask com.worklight.core.util.RssBrokerUtils ? Transaction begin : 32330832 (new:true; completed:false; rollbackOnly:false)REQUIRED
2012-09-18 10:54:54.0719 DEBUG ClusterDataSynchronizationTask com.worklight.core.util.RssBrokerUtils ? Transaction commit: 32330832 (new:true; completed:false; rollbackOnly:false)
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.wire ? << "HTTP/1.1 200 OK\r\n"
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.wire ? << "Server: Apache-Coyote/1.1\r\n"
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.wire ? << "Content-Type: text/xml;charset=UTF-8\r\n"
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.wire ? << "Transfer-Encoding: chunked\r\n"
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.wire ? << "Date: Tue, 18 Sep 2012 14:54:55 GMT\r\n"
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.wire ? << "\r\n"
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.impl.conn.DefaultClientConnection ? Receiving response: HTTP/1.1 200 OK
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.headers ? << HTTP/1.1 200 OK
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.headers ? << Server: Apache-Coyote/1.1
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.headers ? << Content-Type: text/xml;charset=UTF-8
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.headers ? << Transfer-Encoding: chunked
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.headers ? << Date: Tue, 18 Sep 2012 14:54:55 GMT
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.impl.client.DefaultHttpClient ? Connection can be kept alive indefinitely
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.wire ? << "47b\r\n"
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.wire ? << "<?xml version="1.0" encoding="UTF-8" standalone="yes"?><ServiceResponse></ServicResponse>"
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.wire ? << "\r\n"
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.wire ? << "0\r\n"
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.wire ? << "\r\n"
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.impl.conn.tsccm.ThreadSafeClientConnManager ? Released connection is reusable.
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.impl.conn.tsccm.ConnPoolByRoute ? Releasing connection [HttpRoute{}->http://host.com:48348]null
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.impl.conn.tsccm.ConnPoolByRoute ? Pooling connection [HttpRoute{}->http://host.com:48348]null; keep alive indefinitely
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 org.apache.http.impl.conn.tsccm.ConnPoolByRoute ? Notifying no-one, there are no waiting threads
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 com.worklight.adapters.http.HTTP ? Response:
<?xml version="1.0" encoding="UTF-8" standalone="yes"?><ServicResponse></ServicResponse>
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 com.worklight.integration.model.ConnectionManager ? REMOVING connection user for -879300225971046287
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 com.worklight.integration.model.ConnectionManager ? there are now 1 connection users for request id -879300225971046287
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 com.worklight.integration.services.impl.DataAccessServiceImpl ? Procedure 'HttpRequest' invocation finished.
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 development.developer ? {
"ServicResponse": {
"errors": [
],
"info": [
],
"isSuccessful": true,
"responseHeaders": {
"Content-Type": "text\/xml;charset=UTF-8",
"Date": "Tue, 18 Sep 2012 14:54:55 GMT",
"Server": "Apache-Coyote\/1.1",
"Transfer-Encoding": "chunked"
},
"statusCode": 200,
"statusReason": "OK",
"warnings": [
]
}
2012-09-18 10:54:55.0375 DEBUG pool-7-thread-7 com.worklight.integration.model.ConnectionManager ? CLOSING connection for request id -879300225971046287
2012-09-18 10:54:55.0375 DEBUG http-bio-5080-exec-10 com.worklight.integration.services.impl.DataAccessServiceImpl ? Procedure 'serviceAdapterGood' invocation finished.
  • SystemAdmin
    SystemAdmin
    2327 Posts
    ACCEPTED ANSWER

    Re: Double JSESSIONID sent by Worklight Server in Adapter

    ‏2012-09-20T15:38:12Z  in response to SystemAdmin
    Does any one experience the same issue?
    • tiny_gipxy
      tiny_gipxy
      15 Posts
      ACCEPTED ANSWER

      Re: Double JSESSIONID sent by Worklight Server in Adapter

      ‏2012-10-05T07:13:04Z  in response to SystemAdmin
      I got similar problem on android, any help ?
      • SystemAdmin
        SystemAdmin
        2327 Posts
        ACCEPTED ANSWER

        Re: Double JSESSIONID sent by Worklight Server in Adapter

        ‏2012-10-05T19:58:32Z  in response to tiny_gipxy
        Don't use cookie on server side

        Jerry