Ok it appears as if the session has made it to the server but the values are null. This has happened on 1 of the 5 servers approx 500 times in one day so you can imagine how big this problem is for me. Please excuse the long log file but please if anybody can advice me in why a call to a session object is null when redirected to a new server. The object being implements serlizable.
2006-02-24 12:31:21,628 DEBUG [org.jboss.cache.interceptors.ReplicationInterceptor] replicate(): received prepare(<vrx14-in:32849>:43372, [_put(<vrx14-in:32849>:43372, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, nPL1hi7Yl6gh6QXewnz35w**, [B@682b9c, true), _put(<vrx14-in:32849>:43372, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, VERSION, 1, true)], vrx14-in:32849, true)
2006-02-24 12:31:21,630 DEBUG [org.jboss.cache.Node] createChild: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, child_name=nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:31:21,630 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:31:21,630 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx14-in:32849>:43372, lock=
2006-02-24 12:31:21,630 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx14-in:32849>:43372, lock=write owner=<vrx14-in:32849>:43372
2006-02-24 12:31:21,630 DEBUG [org.jboss.cache.TreeCache] _put(<vrx14-in:32849>:43372, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**, [B@682b9c)
2006-02-24 12:31:21,631 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx14-in:32849>:43372, lock=write owner=<vrx14-in:32849>:43372
2006-02-24 12:31:21,631 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx14-in:32849>:43372 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:31:21,631 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx14-in:32849>:43372, lock=write owner=<vrx14-in:32849>:43372
2006-02-24 12:31:21,631 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:31:21,639 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx14-in:32849>:43372, lock=write owner=<vrx14-in:32849>:43372
2006-02-24 12:31:21,639 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (caller=<vrx14-in:32849>:43372)
2006-02-24 12:31:21,640 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx14-in:32849>:43372, lock=write owner=<vrx14-in:32849>:43372
2006-02-24 12:31:21,640 DEBUG [org.jboss.cache.TreeCache] _put(<vrx14-in:32849>:43372, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, 1)
2006-02-24 12:31:21,640 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx14-in:32849>:43372, lock=write owner=<vrx14-in:32849>:43372
2006-02-24 12:31:21,640 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx14-in:32849>:43372 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:31:21,640 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx14-in:32849>:43372, lock=write owner=<vrx14-in:32849>:43372
2006-02-24 12:31:21,641 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:31:21,649 DEBUG [org.jboss.cache.interceptors.LockInterceptor] releasing lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (write owner=<vrx14-in:32849>:43372)
2006-02-24 12:31:26,792 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-18,5,jboss], lock=
2006-02-24 12:31:26,792 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-18,5,jboss], lock=read owners=[Thread[http-0.0.0.0-8080-18,5,jboss]]
2006-02-24 12:31:26,792 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**, "true")
2006-02-24 12:31:26,792 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**: read owners=[Thread[http-0.0.0.0-8080-18,5,jboss]]
2006-02-24 12:31:27,889 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[ContainerBackgroundProcessor[StandardEngine[jboss.web]],5,jboss], lock=
2006-02-24 12:31:27,889 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[ContainerBackgroundProcessor[StandardEngine[jboss.web]],5,jboss], lock=read owners=[Thread[ContainerBackgroundProcessor[StandardEngine[jboss.web]],5,jboss]]
2006-02-24 12:31:27,889 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**, "true")
2006-02-24 12:31:27,889 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**: read owners=[Thread[ContainerBackgroundProcessor[StandardEngine[jboss.web]],5,jboss]]
2006-02-24 12:31:29,029 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:24691, lock=
2006-02-24 12:31:29,029 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:24691, lock=write owner=<vrx11:33377>:24691
2006-02-24 12:31:29,029 DEBUG [org.jboss.cache.TreeCache] _put(<vrx11:33377>:24691, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**, [B@53c280)
2006-02-24 12:31:29,030 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:24691, lock=write owner=<vrx11:33377>:24691
2006-02-24 12:31:29,030 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx11:33377>:24691 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:31:29,030 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:24691, lock=write owner=<vrx11:33377>:24691
2006-02-24 12:31:29,030 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:31:29,055 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:24691, lock=write owner=<vrx11:33377>:24691
2006-02-24 12:31:29,055 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (caller=<vrx11:33377>:24691)
2006-02-24 12:31:29,055 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:24691, lock=write owner=<vrx11:33377>:24691
2006-02-24 12:31:29,055 DEBUG [org.jboss.cache.TreeCache] _put(<vrx11:33377>:24691, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, 2)
2006-02-24 12:31:29,056 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:24691, lock=write owner=<vrx11:33377>:24691
2006-02-24 12:31:29,056 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx11:33377>:24691 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:31:29,056 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:24691, lock=write owner=<vrx11:33377>:24691
2006-02-24 12:31:29,056 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:31:29,086 DEBUG [org.jboss.cache.interceptors.ReplicationInterceptor] (vrx11:33377): running remote prepare for <vrx11:33377>:24691 with async mode=true and coord=vrx11:33377 (2 modifications): [_put(<vrx11:33377>:24691, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, nPL1hi7Yl6gh6QXewnz35w**, [B@53c280, true), _put(<vrx11:33377>:24691, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, VERSION, 2, true)]
2006-02-24 12:31:29,087 DEBUG [org.jboss.cache.interceptors.LockInterceptor] releasing lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (write owner=<vrx11:33377>:24691)
2006-02-24 12:31:30,705 DEBUG [org.jboss.cache.interceptors.ReplicationInterceptor] replicate(): received prepare(<vrx14-in:32849>:43375, [_put(<vrx14-in:32849>:43375, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, nPL1hi7Yl6gh6QXewnz35w**, [B@7a9b8f, true), _put(<vrx14-in:32849>:43375, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, VERSION, 2, true)], vrx14-in:32849, true)
2006-02-24 12:31:30,705 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx14-in:32849>:43375, lock=
2006-02-24 12:31:30,706 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx14-in:32849>:43375, lock=write owner=<vrx14-in:32849>:43375
2006-02-24 12:31:30,706 DEBUG [org.jboss.cache.TreeCache] _put(<vrx14-in:32849>:43375, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**, [B@7a9b8f)
2006-02-24 12:31:30,706 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx14-in:32849>:43375, lock=write owner=<vrx14-in:32849>:43375
2006-02-24 12:31:30,706 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx14-in:32849>:43375 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:31:30,706 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx14-in:32849>:43375, lock=write owner=<vrx14-in:32849>:43375
2006-02-24 12:31:30,706 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:31:30,715 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx14-in:32849>:43375, lock=write owner=<vrx14-in:32849>:43375
2006-02-24 12:31:30,715 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (caller=<vrx14-in:32849>:43375)
2006-02-24 12:31:30,715 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx14-in:32849>:43375, lock=write owner=<vrx14-in:32849>:43375
2006-02-24 12:31:30,715 DEBUG [org.jboss.cache.TreeCache] _put(<vrx14-in:32849>:43375, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, 2)
2006-02-24 12:31:30,716 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx14-in:32849>:43375, lock=write owner=<vrx14-in:32849>:43375
2006-02-24 12:31:30,716 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx14-in:32849>:43375 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:31:30,716 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx14-in:32849>:43375, lock=write owner=<vrx14-in:32849>:43375
2006-02-24 12:31:30,716 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:31:30,724 DEBUG [org.jboss.cache.interceptors.LockInterceptor] releasing lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (write owner=<vrx14-in:32849>:43375)
2006-02-24 12:31:33,360 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-18,5,jboss], lock=
2006-02-24 12:31:33,360 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-18,5,jboss], lock=write owner=Thread[http-0.0.0.0-8080-18,5,jboss]
2006-02-24 12:31:33,360 DEBUG [org.jboss.cache.TreeCache] _remove(null, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**)
2006-02-24 12:31:33,360 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-18,5,jboss], lock=write owner=Thread[http-0.0.0.0-8080-18,5,jboss]
2006-02-24 12:31:33,361 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller Thread[http-0.0.0.0-8080-18,5,jboss] already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:31:33,361 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-18,5,jboss], lock=write owner=Thread[http-0.0.0.0-8080-18,5,jboss]
2006-02-24 12:31:33,361 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:31:33,361 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**: write owner=Thread[http-0.0.0.0-8080-18,5,jboss]
2006-02-24 12:31:33,362 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-18,5,jboss], lock=
2006-02-24 12:31:33,362 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-18,5,jboss], lock=write owner=Thread[http-0.0.0.0-8080-18,5,jboss]
2006-02-24 12:31:33,362 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-18,5,jboss], lock=write owner=Thread[http-0.0.0.0-8080-18,5,jboss]
2006-02-24 12:31:33,362 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (caller=Thread[http-0.0.0.0-8080-18,5,jboss])
2006-02-24 12:31:33,362 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-18,5,jboss], lock=write owner=Thread[http-0.0.0.0-8080-18,5,jboss]
2006-02-24 12:31:33,362 DEBUG [org.jboss.cache.TreeCache] _remove(null, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**")
2006-02-24 12:31:33,362 DEBUG [org.jboss.cache.Node] removed child nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:31:33,363 DEBUG [org.jboss.cache.interceptors.LockInterceptor] failed finding child nPL1hi7Yl6gh6QXewnz35w** of node /JSESSION/localhost/ui
2006-02-24 12:31:33,363 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:31:33,363 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**:
2006-02-24 12:31:33,364 INFO session: nPL1hi7Yl6gh6QXewnz35w**
I try to get the account from session, user is forced to login wegpage.
2006-02-24 12:31:33,368 DEBUG [org.jboss.cache.interceptors.LockInterceptor] failed finding child nPL1hi7Yl6gh6QXewnz35w** of node /JSESSION/localhost/ui
2006-02-24 12:31:33,368 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**, "true")
2006-02-24 12:37:49,309 DEBUG [org.jboss.cache.interceptors.LockInterceptor] failed finding child nPL1hi7Yl6gh6QXewnz35w** of node /JSESSION/localhost/ui
2006-02-24 12:37:49,309 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**, "true")
2006-02-24 12:37:49,311 DEBUG [org.jboss.cache.interceptors.LockInterceptor] failed finding child nPL1hi7Yl6gh6QXewnz35w** of node /JSESSION/localhost/ui
2006-02-24 12:37:49,311 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**, "true")
2006-02-24 12:37:49,312 DEBUG [org.jboss.cache.Node] createChild: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, child_name=nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:37:49,312 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:37:49,312 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25039, lock=
2006-02-24 12:37:49,312 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25039, lock=write owner=<vrx11:33377>:25039
2006-02-24 12:37:49,312 DEBUG [org.jboss.cache.TreeCache] _put(<vrx11:33377>:25039, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**, [B@11fb8ef)
2006-02-24 12:37:49,313 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25039, lock=write owner=<vrx11:33377>:25039
2006-02-24 12:37:49,313 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx11:33377>:25039 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:37:49,313 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25039, lock=write owner=<vrx11:33377>:25039
2006-02-24 12:37:49,313 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:37:49,347 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25039, lock=write owner=<vrx11:33377>:25039
2006-02-24 12:37:49,347 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (caller=<vrx11:33377>:25039)
2006-02-24 12:37:49,347 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25039, lock=write owner=<vrx11:33377>:25039
2006-02-24 12:37:49,347 DEBUG [org.jboss.cache.TreeCache] _put(<vrx11:33377>:25039, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, 1)
2006-02-24 12:37:49,347 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25039, lock=write owner=<vrx11:33377>:25039
2006-02-24 12:37:49,347 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx11:33377>:25039 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:37:49,347 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25039, lock=write owner=<vrx11:33377>:25039
2006-02-24 12:37:49,348 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:37:49,389 DEBUG [org.jboss.cache.interceptors.ReplicationInterceptor] (vrx11:33377): running remote prepare for <vrx11:33377>:25039 with async mode=true and coord=vrx11:33377 (2 modifications): [_put(<vrx11:33377>:25039, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, nPL1hi7Yl6gh6QXewnz35w**, [B@11fb8ef, true), _put(<vrx11:33377>:25039, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, VERSION, 1, true)]
2006-02-24 12:37:49,391 DEBUG [org.jboss.cache.interceptors.LockInterceptor] releasing lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (write owner=<vrx11:33377>:25039)
2006-02-24 12:37:49,872 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-2,5,jboss], lock=
2006-02-24 12:37:49,872 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-2,5,jboss], lock=write owner=Thread[http-0.0.0.0-8080-2,5,jboss]
2006-02-24 12:37:49,872 DEBUG [org.jboss.cache.TreeCache] _remove(null, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**)
2006-02-24 12:37:49,872 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-2,5,jboss], lock=write owner=Thread[http-0.0.0.0-8080-2,5,jboss]
2006-02-24 12:37:49,872 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller Thread[http-0.0.0.0-8080-2,5,jboss] already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:37:49,872 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-2,5,jboss], lock=write owner=Thread[http-0.0.0.0-8080-2,5,jboss]
2006-02-24 12:37:49,872 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:37:49,873 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**: write owner=Thread[http-0.0.0.0-8080-2,5,jboss]
2006-02-24 12:37:49,874 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-2,5,jboss], lock=
2006-02-24 12:37:49,874 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-2,5,jboss], lock=write owner=Thread[http-0.0.0.0-8080-2,5,jboss]
2006-02-24 12:37:49,874 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-2,5,jboss], lock=write owner=Thread[http-0.0.0.0-8080-2,5,jboss]
2006-02-24 12:37:49,874 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (caller=Thread[http-0.0.0.0-8080-2,5,jboss])
2006-02-24 12:37:49,874 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=Thread[http-0.0.0.0-8080-2,5,jboss], lock=write owner=Thread[http-0.0.0.0-8080-2,5,jboss]
2006-02-24 12:37:49,874 DEBUG [org.jboss.cache.TreeCache] _remove(null, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**")
2006-02-24 12:37:49,874 DEBUG [org.jboss.cache.Node] removed child nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:37:49,875 DEBUG [org.jboss.cache.interceptors.LockInterceptor] failed finding child nPL1hi7Yl6gh6QXewnz35w** of node /JSESSION/localhost/ui
2006-02-24 12:37:49,875 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:37:49,875 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**:
2006-02-24 12:37:49,876 DEBUG [org.jboss.cache.interceptors.LockInterceptor] failed finding child nPL1hi7Yl6gh6QXewnz35w** of node /JSESSION/localhost/ui
2006-02-24 12:37:49,876 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**, "true")
2006-02-24 12:37:49,877 DEBUG [org.jboss.cache.Node] createChild: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, child_name=nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:37:49,877 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:37:49,877 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25043, lock=
2006-02-24 12:37:49,877 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25043, lock=write owner=<vrx11:33377>:25043
2006-02-24 12:37:49,877 DEBUG [org.jboss.cache.TreeCache] _put(<vrx11:33377>:25043, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**, [B@7317e4)
2006-02-24 12:37:49,878 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25043, lock=write owner=<vrx11:33377>:25043
2006-02-24 12:37:49,878 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx11:33377>:25043 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:37:49,878 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25043, lock=write owner=<vrx11:33377>:25043
2006-02-24 12:37:49,878 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:37:49,892 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25043, lock=write owner=<vrx11:33377>:25043
2006-02-24 12:37:49,892 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (caller=<vrx11:33377>:25043)
2006-02-24 12:37:49,892 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25043, lock=write owner=<vrx11:33377>:25043
2006-02-24 12:37:49,892 DEBUG [org.jboss.cache.TreeCache] _put(<vrx11:33377>:25043, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, 1)
2006-02-24 12:37:49,893 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25043, lock=write owner=<vrx11:33377>:25043
2006-02-24 12:37:49,893 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx11:33377>:25043 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:37:49,893 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25043, lock=write owner=<vrx11:33377>:25043
2006-02-24 12:37:49,893 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:37:49,920 DEBUG [org.jboss.cache.interceptors.ReplicationInterceptor] (vrx11:33377): running remote prepare for <vrx11:33377>:25043 with async mode=true and coord=vrx11:33377 (2 modifications): [_put(<vrx11:33377>:25043, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, nPL1hi7Yl6gh6QXewnz35w**, [B@7317e4, true), _put(<vrx11:33377>:25043, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, VERSION, 1, true)]
2006-02-24 12:37:49,921 DEBUG [org.jboss.cache.interceptors.LockInterceptor] releasing lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (write owner=<vrx11:33377>:25043)
users logs back in and has same session id and reports no problems.
2006-02-24 12:37:50,279 INFO [STDOUT] USER LOGIN with session id: nPL1hi7Yl6gh6QXewnz35w**, session creation time: 1140813469876, last access time: 1140813469876, max inactive interval: 3600, is new: true
2006-02-24 12:38:00,711 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25062, lock=
2006-02-24 12:38:00,711 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25062, lock=write owner=<vrx11:33377>:25062
2006-02-24 12:38:00,711 DEBUG [org.jboss.cache.TreeCache] _put(<vrx11:33377>:25062, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**, [B@5862ea)
2006-02-24 12:38:00,712 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25062, lock=write owner=<vrx11:33377>:25062
2006-02-24 12:38:00,712 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx11:33377>:25062 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:38:00,712 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25062, lock=write owner=<vrx11:33377>:25062
2006-02-24 12:38:00,712 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:38:00,734 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25062, lock=write owner=<vrx11:33377>:25062
2006-02-24 12:38:00,734 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (caller=<vrx11:33377>:25062)
2006-02-24 12:38:00,735 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25062, lock=write owner=<vrx11:33377>:25062
2006-02-24 12:38:00,735 DEBUG [org.jboss.cache.TreeCache] _put(<vrx11:33377>:25062, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, 2)
2006-02-24 12:38:00,735 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25062, lock=write owner=<vrx11:33377>:25062
2006-02-24 12:38:00,735 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx11:33377>:25062 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:38:00,736 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25062, lock=write owner=<vrx11:33377>:25062
2006-02-24 12:38:00,736 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:38:00,765 DEBUG [org.jboss.cache.interceptors.ReplicationInterceptor] (vrx11:33377): running remote prepare for <vrx11:33377>:25062 with async mode=true and coord=vrx11:33377 (2 modifications): [_put(<vrx11:33377>:25062, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, nPL1hi7Yl6gh6QXewnz35w**, [B@5862ea, true), _put(<vrx11:33377>:25062, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, VERSION, 2, true)]
2006-02-24 12:38:00,766 DEBUG [org.jboss.cache.interceptors.LockInterceptor] releasing lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (write owner=<vrx11:33377>:25062)
2006-02-24 12:38:48,050 INFO [STDOUT] USER GOES THROUGH SECURITY CHECK session id: nPL1hi7Yl6gh6QXewnz35w** session creation time: 1140813469876, last access time: 1140813474347, max inactive interval: 3600, is new: false
2006-02-24 12:38:54,612 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25095, lock=
2006-02-24 12:38:54,612 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25095, lock=write owner=<vrx11:33377>:25095
2006-02-24 12:38:54,613 DEBUG [org.jboss.cache.TreeCache] _put(<vrx11:33377>:25095, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**, [B@7ea58c)
2006-02-24 12:38:54,613 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25095, lock=write owner=<vrx11:33377>:25095
2006-02-24 12:38:54,613 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx11:33377>:25095 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:38:54,613 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25095, lock=write owner=<vrx11:33377>:25095
2006-02-24 12:38:54,613 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:38:54,627 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25095, lock=write owner=<vrx11:33377>:25095
2006-02-24 12:38:54,628 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (caller=<vrx11:33377>:25095)
2006-02-24 12:38:54,628 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25095, lock=write owner=<vrx11:33377>:25095
2006-02-24 12:38:54,628 DEBUG [org.jboss.cache.TreeCache] _put(<vrx11:33377>:25095, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, 3)
2006-02-24 12:38:54,628 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25095, lock=write owner=<vrx11:33377>:25095
2006-02-24 12:38:54,628 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx11:33377>:25095 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:38:54,628 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25095, lock=write owner=<vrx11:33377>:25095
2006-02-24 12:38:54,628 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:38:54,652 DEBUG [org.jboss.cache.interceptors.ReplicationInterceptor] (vrx11:33377): running remote prepare for <vrx11:33377>:25095 with async mode=true and coord=vrx11:33377 (2 modifications): [_put(<vrx11:33377>:25095, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, nPL1hi7Yl6gh6QXewnz35w**, [B@7ea58c, true), _put(<vrx11:33377>:25095, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, VERSION, 3, true)]
2006-02-24 12:38:54,653 DEBUG [org.jboss.cache.interceptors.LockInterceptor] releasing lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (write owner=<vrx11:33377>:25095)
2006-02-24 12:38:55,112 INFO [STDOUT] USER DOES ANOTHER REQUEST session id: nPL1hi7Yl6gh6QXewnz35w** session creation time: 1140813469876, last access time: 1140813531097, max inactive interval: 3600, is new: false
2006-02-24 12:38:56,388 INFO [STDOUT] USER DOES ANOTHER REQUEST user_id: 11345, session id: nPL1hi7Yl6gh6QXewnz35w**, session creation time: 1140813469876, last access time: 1140813536204, max inactive interval: 3600, is new: false
2006-02-24 12:39:04,929 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25102, lock=
2006-02-24 12:39:04,929 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25102, lock=write owner=<vrx11:33377>:25102
2006-02-24 12:39:04,929 DEBUG [org.jboss.cache.TreeCache] _put(<vrx11:33377>:25102, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**, [B@13a1ca1)
2006-02-24 12:39:04,930 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25102, lock=write owner=<vrx11:33377>:25102
2006-02-24 12:39:04,930 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx11:33377>:25102 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:39:04,930 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25102, lock=write owner=<vrx11:33377>:25102
2006-02-24 12:39:04,930 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:39:04,944 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25102, lock=write owner=<vrx11:33377>:25102
2006-02-24 12:39:04,944 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (caller=<vrx11:33377>:25102)
2006-02-24 12:39:04,944 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25102, lock=write owner=<vrx11:33377>:25102
2006-02-24 12:39:04,944 DEBUG [org.jboss.cache.TreeCache] _put(<vrx11:33377>:25102, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, 4)
2006-02-24 12:39:04,945 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25102, lock=write owner=<vrx11:33377>:25102
2006-02-24 12:39:04,945 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx11:33377>:25102 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:39:04,945 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25102, lock=write owner=<vrx11:33377>:25102
2006-02-24 12:39:04,945 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:39:04,969 DEBUG [org.jboss.cache.interceptors.ReplicationInterceptor] (vrx11:33377): running remote prepare for <vrx11:33377>:25102 with async mode=true and coord=vrx11:33377 (2 modifications): [_put(<vrx11:33377>:25102, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, nPL1hi7Yl6gh6QXewnz35w**, [B@13a1ca1, true), _put(<vrx11:33377>:25102, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, VERSION, 4, true)]
2006-02-24 12:39:04,970 DEBUG [org.jboss.cache.interceptors.LockInterceptor] releasing lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (write owner=<vrx11:33377>:25102)
2006-02-24 12:39:41,157 INFO [STDOUT] USER DOES ANOTHER REQUEST session id: nPL1hi7Yl6gh6QXewnz35w**, session creation time: 1140813469876, last access time: 1140813537423, max inactive interval: 3600, is new: false
2006-02-24 12:39:49,472 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25132, lock=
2006-02-24 12:39:49,472 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25132, lock=write owner=<vrx11:33377>:25132
2006-02-24 12:39:49,472 DEBUG [org.jboss.cache.TreeCache] _put(<vrx11:33377>:25132, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", nPL1hi7Yl6gh6QXewnz35w**, [B@c0a024)
2006-02-24 12:39:49,473 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25132, lock=write owner=<vrx11:33377>:25132
2006-02-24 12:39:49,473 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx11:33377>:25132 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:39:49,473 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25132, lock=write owner=<vrx11:33377>:25132
2006-02-24 12:39:49,473 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:39:49,488 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25132, lock=write owner=<vrx11:33377>:25132
2006-02-24 12:39:49,488 DEBUG [org.jboss.cache.lock.IdentityLock] acquireWriteLock(): caller already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (caller=<vrx11:33377>:25132)
2006-02-24 12:39:49,488 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25132, lock=write owner=<vrx11:33377>:25132
2006-02-24 12:39:49,488 DEBUG [org.jboss.cache.TreeCache] _put(<vrx11:33377>:25132, "/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, 5)
2006-02-24 12:39:49,488 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25132, lock=write owner=<vrx11:33377>:25132
2006-02-24 12:39:49,488 DEBUG [org.jboss.cache.lock.IdentityLock] acquireReadLock(): caller <vrx11:33377>:25132 already owns lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**
2006-02-24 12:39:49,488 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, caller=<vrx11:33377>:25132, lock=write owner=<vrx11:33377>:25132
2006-02-24 12:39:49,488 DEBUG [org.jboss.cache.TreeCache] _get("/JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**", VERSION, "true")
2006-02-24 12:39:49,516 DEBUG [org.jboss.cache.interceptors.ReplicationInterceptor] (vrx11:33377): running remote prepare for <vrx11:33377>:25132 with async mode=true and coord=vrx11:33377 (2 modifications): [_put(<vrx11:33377>:25132, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, nPL1hi7Yl6gh6QXewnz35w**, [B@c0a024, true), _put(<vrx11:33377>:25132, /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w**, VERSION, 5, true)]
2006-02-24 12:39:49,516 DEBUG [org.jboss.cache.interceptors.LockInterceptor] releasing lock for /JSESSION/localhost/ui/nPL1hi7Yl6gh6QXewnz35w** (write owner=<vrx11:33377>:25132)