everytime new logion because visit not found in session

[hans_bakker]

Since about a couple of weeks  i have the problem that a lot of times,
not always, the system requests you to login again.
It is caused because the 'visit' is not found in the session in
requesthandler.java, it creates a new one and sees it is already
available in the cookie but forces the 'first visit' event to execute

Anybody have the same problem?

the log:
2007-08-31 09:48:12,005 (TP-Processor9) [
VisitHandler.java:222:INFO ] Found visitorId [108432] in cookie
2007-08-31 09:48:12,008 (TP-Processor9) [
RequestHandler.java:180:INFO ] This is the first request in this visit.
sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
2007-08-31 09:48:12,036 (TP-Processor9) [
JkInputStream.java:202:INFO ] Receiving: getting request body chunk 32
32
2007-08-31 09:48:12,039 (TP-Processor9) [
RequestHandler.java:239:INFO ] [Processing Request]: login
sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
2007-08-31 09:48:12,066 (TP-Processor9) [
LoginWorker.java:514:INFO ] Running autoLogin check.
2007-08-31 09:48:12,072 (TP-Processor9) [
RequestHandler.java:422:INFO ] [RequestHandler.doRequest]: Response is a
Request redirect with no parameters.
sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
2007-08-31 09:48:12,073 (TP-Processor9) [
RequestHandler.java:533:INFO ] [Sending
redirect]: /backend/control/activitiesMain
sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
2007-08-31 09:48:12,439 (TP-Processor11) [
ControlServlet.java:93 :INFO ] The character encoding of the request is:
[null]. The character encoding we will use for the request and response
is: [UTF-8]
2007-08-31 09:48:12,440 (TP-Processor11) [
RequestHandler.java:239:INFO ] [Processing Request]: activitiesMain
sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
2007-08-31 09:48:12,441 (TP-Processor11) [
RequestManager.java:116:INFO ] [RequestManager.getRequestAttribute]
Value for attribute "null" of uri "activitiesMain" not found
2007-08-31 09:48:12,441 (TP-Processor11) [
RequestHandler.java:427:INFO ] [RequestHandler.doRequest]: Response is a
view. sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
2007-08-31 09:48:12,441 (TP-Processor11) [
RequestHandler.java:576:INFO ] servletName=control, view=activitiesMain
sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
2007-08-31 09:48:12,782 (TP-Processor11) [
ModelForm.java:1225:INFO ] preparePager: low - high = 0 - 20
2007-08-31 09:48:12,783 (TP-Processor11) [
ModelForm.java:1242:INFO ] preparePager: Found rows = 20
2007-08-31 09:48:12,832 (TP-Processor11) [
ModelForm.java:1225:INFO ] preparePager: low - high = 0 - 20
2007-08-31 09:48:12,833 (TP-Processor11) [
ModelForm.java:1242:INFO ] preparePager: Found rows = 20
2007-08-31 09:48:32,971 (TP-Processor9)
[ControlEventListener.java:62 :INFO ] Creating session:
96A6796CF184E14027BC8788A4A46B5D.jvm1
2007-08-31 09:48:32,972 (TP-Processor9) [
ControlServlet.java:93 :INFO ] The character encoding of the request is:
[null]. The character encoding we will use for the request and response
is: [UTF-8]
2007-08-31 09:48:32,974 (TP-Processor9) [
VisitHandler.java:222:INFO ] Found visitorId [108432] in cookie
2007-08-31 09:48:32,978 (TP-Processor9) [
RequestHandler.java:180:INFO ] This is the first request in this visit.
sessionId=96A6796CF184E14027BC8788A4A46B5D.jvm1
2007-08-31 09:48:33,073 (TP-Processor9) [
RequestHandler.java:239:INFO ] [Processing Request]: logout
sessionId=96A6796CF184E14027BC8788A4A46B5D.jvm1
2007-08-31 09:48:33,074 (TP-Processor9) [
LoginWorker.java:249:INFO ] reqParams Map: []
2007-08-31 09:48:33,074 (TP-Processor9) [
LoginWorker.java:250:INFO ] queryString:
2007-08-31 09:48:33,075 (TP-Processor9) [
LoginWorker.java:260:INFO ] checkLogin: queryString=
2007-08-31 09:48:33,076 (TP-Processor9) [
LoginWorker.java:261:INFO ] checkLogin: PathInfo=/logout
2007-08-31 09:48:33,076 (TP-Processor9) [
RequestHandler.java:427:INFO ] [RequestHandler.doRequest]: Response is a
view. sessionId=96A6796CF184E14027BC8788A4A46B5D.jvm1
2007-08-31 09:48:33,077 (TP-Processor9) [
RequestHandler.java:576:INFO ] servletName=control, view=login
sessionId=96A6796CF184E14027BC8788A4A46B5D.jvm1
2007-08-31 09:48:33,079 (TP-Processor9) [
PrimaryKeyFinder.java:124:INFO ] Returning null because found incomplete
primary key in find: [GenericEntity:ProductStore][productStoreId,null()]




--
http://Antwebsystems.com : OFBiz Quality support for competitive rates.

[Willem Janssen]

We have experienced the same problem...

Regards,
Willem Janssen

OpenBiz Webservices
www.openbiz.nl


On Fri, August 31, 2007 11:09, Hans Bakker wrote:

> Since about a couple of weeks  i have the problem that a lot of times,
> not always, the system requests you to login again. It is caused because
> the 'visit' is not found in the session in requesthandler.java, it creates
> a new one and sees it is already available in the cookie but forces the
> 'first visit' event to execute
>
>
> Anybody have the same problem?
>
>
> the log: 2007-08-31 09:48:12,005 (TP-Processor9) [
> VisitHandler.java:222:INFO ] Found visitorId [108432] in cookie
> 2007-08-31 09:48:12,008 (TP-Processor9) [
> RequestHandler.java:180:INFO ] This is the first request in this visit.
> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1 2007-08-31 09:48:12,036
> (TP-Processor9) [
> JkInputStream.java:202:INFO ] Receiving: getting request body chunk 32
> 32
> 2007-08-31 09:48:12,039 (TP-Processor9) [
> RequestHandler.java:239:INFO ] [Processing Request]: login
> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1 2007-08-31 09:48:12,066
> (TP-Processor9) [
> LoginWorker.java:514:INFO ] Running autoLogin check.
> 2007-08-31 09:48:12,072 (TP-Processor9) [
> RequestHandler.java:422:INFO ] [RequestHandler.doRequest]: Response is a
> Request redirect with no parameters.
> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1 2007-08-31 09:48:12,073
> (TP-Processor9) [
> RequestHandler.java:533:INFO ] [Sending
> redirect]: /backend/control/activitiesMain
> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1 2007-08-31 09:48:12,439
> (TP-Processor11) [
> ControlServlet.java:93 :INFO ] The character encoding of the request is:
> [null]. The character encoding we will use for the request and response
> is: [UTF-8]
> 2007-08-31 09:48:12,440 (TP-Processor11) [
> RequestHandler.java:239:INFO ] [Processing Request]: activitiesMain
> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1 2007-08-31 09:48:12,441
> (TP-Processor11) [
> RequestManager.java:116:INFO ] [RequestManager.getRequestAttribute]
> Value for attribute "null" of uri "activitiesMain" not found
> 2007-08-31 09:48:12,441 (TP-Processor11) [
> RequestHandler.java:427:INFO ] [RequestHandler.doRequest]: Response is a
> view. sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1 2007-08-31
> 09:48:12,441 (TP-Processor11) [
> RequestHandler.java:576:INFO ] servletName=control, view=activitiesMain
> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1 2007-08-31 09:48:12,782
> (TP-Processor11) [
> ModelForm.java:1225:INFO ] preparePager: low - high = 0 - 20
> 2007-08-31 09:48:12,783 (TP-Processor11) [
> ModelForm.java:1242:INFO ] preparePager: Found rows = 20
> 2007-08-31 09:48:12,832 (TP-Processor11) [
> ModelForm.java:1225:INFO ] preparePager: low - high = 0 - 20
> 2007-08-31 09:48:12,833 (TP-Processor11) [
> ModelForm.java:1242:INFO ] preparePager: Found rows = 20
> 2007-08-31 09:48:32,971 (TP-Processor9)
> [ControlEventListener.java:62 :INFO ] Creating session:
> 96A6796CF184E14027BC8788A4A46B5D.jvm1
> 2007-08-31 09:48:32,972 (TP-Processor9) [
> ControlServlet.java:93 :INFO ] The character encoding of the request is:
> [null]. The character encoding we will use for the request and response
> is: [UTF-8]
> 2007-08-31 09:48:32,974 (TP-Processor9) [
> VisitHandler.java:222:INFO ] Found visitorId [108432] in cookie
> 2007-08-31 09:48:32,978 (TP-Processor9) [
> RequestHandler.java:180:INFO ] This is the first request in this visit.
> sessionId=96A6796CF184E14027BC8788A4A46B5D.jvm1 2007-08-31 09:48:33,073
> (TP-Processor9) [
> RequestHandler.java:239:INFO ] [Processing Request]: logout
> sessionId=96A6796CF184E14027BC8788A4A46B5D.jvm1 2007-08-31 09:48:33,074
> (TP-Processor9) [
> LoginWorker.java:249:INFO ] reqParams Map: []
> 2007-08-31 09:48:33,074 (TP-Processor9) [
> LoginWorker.java:250:INFO ] queryString:
> 2007-08-31 09:48:33,075 (TP-Processor9) [
> LoginWorker.java:260:INFO ] checkLogin: queryString=
> 2007-08-31 09:48:33,076 (TP-Processor9) [
> LoginWorker.java:261:INFO ] checkLogin: PathInfo=/logout
> 2007-08-31 09:48:33,076 (TP-Processor9) [
> RequestHandler.java:427:INFO ] [RequestHandler.doRequest]: Response is a
> view. sessionId=96A6796CF184E14027BC8788A4A46B5D.jvm1 2007-08-31
> 09:48:33,077 (TP-Processor9) [
> RequestHandler.java:576:INFO ] servletName=control, view=login
> sessionId=96A6796CF184E14027BC8788A4A46B5D.jvm1 2007-08-31 09:48:33,079
> (TP-Processor9) [
> PrimaryKeyFinder.java:124:INFO ] Returning null because found incomplete
> primary key in find: [GenericEntity:ProductStore][productStoreId,null()]
>
>
>
>
> --
> http://Antwebsystems.com : OFBiz Quality support for competitive rates.
>
>
>
>
>

--
[phone]
www.willemjanssen.com

[Adrian Crum]

I have experienced that too.

Hans Bakker wrote:

> Since about a couple of weeks  i have the problem that a lot of times,
> not always, the system requests you to login again.
> It is caused because the 'visit' is not found in the session in
> requesthandler.java, it creates a new one and sees it is already
> available in the cookie but forces the 'first visit' event to execute
>
> Anybody have the same problem?
>
> the log:
> 2007-08-31 09:48:12,005 (TP-Processor9) [
> VisitHandler.java:222:INFO ] Found visitorId [108432] in cookie
> 2007-08-31 09:48:12,008 (TP-Processor9) [
> RequestHandler.java:180:INFO ] This is the first request in this visit.
> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
> 2007-08-31 09:48:12,036 (TP-Processor9) [
> JkInputStream.java:202:INFO ] Receiving: getting request body chunk 32
> 32
> 2007-08-31 09:48:12,039 (TP-Processor9) [
> RequestHandler.java:239:INFO ] [Processing Request]: login
> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
> 2007-08-31 09:48:12,066 (TP-Processor9) [
> LoginWorker.java:514:INFO ] Running autoLogin check.
> 2007-08-31 09:48:12,072 (TP-Processor9) [
> RequestHandler.java:422:INFO ] [RequestHandler.doRequest]: Response is a
> Request redirect with no parameters.
> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
> 2007-08-31 09:48:12,073 (TP-Processor9) [
> RequestHandler.java:533:INFO ] [Sending
> redirect]: /backend/control/activitiesMain
> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
> 2007-08-31 09:48:12,439 (TP-Processor11) [
> ControlServlet.java:93 :INFO ] The character encoding of the request is:
> [null]. The character encoding we will use for the request and response
> is: [UTF-8]
> 2007-08-31 09:48:12,440 (TP-Processor11) [
> RequestHandler.java:239:INFO ] [Processing Request]: activitiesMain
> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
> 2007-08-31 09:48:12,441 (TP-Processor11) [
> RequestManager.java:116:INFO ] [RequestManager.getRequestAttribute]
> Value for attribute "null" of uri "activitiesMain" not found
> 2007-08-31 09:48:12,441 (TP-Processor11) [
> RequestHandler.java:427:INFO ] [RequestHandler.doRequest]: Response is a
> view. sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
> 2007-08-31 09:48:12,441 (TP-Processor11) [
> RequestHandler.java:576:INFO ] servletName=control, view=activitiesMain
> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
> 2007-08-31 09:48:12,782 (TP-Processor11) [
> ModelForm.java:1225:INFO ] preparePager: low - high = 0 - 20
> 2007-08-31 09:48:12,783 (TP-Processor11) [
> ModelForm.java:1242:INFO ] preparePager: Found rows = 20
> 2007-08-31 09:48:12,832 (TP-Processor11) [
> ModelForm.java:1225:INFO ] preparePager: low - high = 0 - 20
> 2007-08-31 09:48:12,833 (TP-Processor11) [
> ModelForm.java:1242:INFO ] preparePager: Found rows = 20
> 2007-08-31 09:48:32,971 (TP-Processor9)
> [ControlEventListener.java:62 :INFO ] Creating session:
> 96A6796CF184E14027BC8788A4A46B5D.jvm1
> 2007-08-31 09:48:32,972 (TP-Processor9) [
> ControlServlet.java:93 :INFO ] The character encoding of the request is:
> [null]. The character encoding we will use for the request and response
> is: [UTF-8]
> 2007-08-31 09:48:32,974 (TP-Processor9) [
> VisitHandler.java:222:INFO ] Found visitorId [108432] in cookie
> 2007-08-31 09:48:32,978 (TP-Processor9) [
> RequestHandler.java:180:INFO ] This is the first request in this visit.
> sessionId=96A6796CF184E14027BC8788A4A46B5D.jvm1
> 2007-08-31 09:48:33,073 (TP-Processor9) [
> RequestHandler.java:239:INFO ] [Processing Request]: logout
> sessionId=96A6796CF184E14027BC8788A4A46B5D.jvm1
> 2007-08-31 09:48:33,074 (TP-Processor9) [
> LoginWorker.java:249:INFO ] reqParams Map: []
> 2007-08-31 09:48:33,074 (TP-Processor9) [
> LoginWorker.java:250:INFO ] queryString:
> 2007-08-31 09:48:33,075 (TP-Processor9) [
> LoginWorker.java:260:INFO ] checkLogin: queryString=
> 2007-08-31 09:48:33,076 (TP-Processor9) [
> LoginWorker.java:261:INFO ] checkLogin: PathInfo=/logout
> 2007-08-31 09:48:33,076 (TP-Processor9) [
> RequestHandler.java:427:INFO ] [RequestHandler.doRequest]: Response is a
> view. sessionId=96A6796CF184E14027BC8788A4A46B5D.jvm1
> 2007-08-31 09:48:33,077 (TP-Processor9) [
> RequestHandler.java:576:INFO ] servletName=control, view=login
> sessionId=96A6796CF184E14027BC8788A4A46B5D.jvm1
> 2007-08-31 09:48:33,079 (TP-Processor9) [
> PrimaryKeyFinder.java:124:INFO ] Returning null because found incomplete
> primary key in find: [GenericEntity:ProductStore][productStoreId,null()]
>
>
>
>

[Vince Clark]

Ditto.

Adrian Crum wrote:

> I have experienced that too.
>
> Hans Bakker wrote:
>
>> Since about a couple of weeks i have the problem that a lot of times,
>> not always, the system requests you to login again.
>> It is caused because the 'visit' is not found in the session in
>> requesthandler.java, it creates a new one and sees it is already
>> available in the cookie but forces the 'first visit' event to execute
>>
>> Anybody have the same problem?
>>
>> the log:
>> 2007-08-31 09:48:12,005 (TP-Processor9) [
>> VisitHandler.java:222:INFO ] Found visitorId [108432] in cookie
>> 2007-08-31 09:48:12,008 (TP-Processor9) [
>> RequestHandler.java:180:INFO ] This is the first request in this visit.
>> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
>> 2007-08-31 09:48:12,036 (TP-Processor9) [
>> JkInputStream.java:202:INFO ] Receiving: getting request body chunk 32
>> 32
>> 2007-08-31 09:48:12,039 (TP-Processor9) [
>> RequestHandler.java:239:INFO ] [Processing Request]: login
>> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
>> 2007-08-31 09:48:12,066 (TP-Processor9) [
>> LoginWorker.java:514:INFO ] Running autoLogin check.
>> 2007-08-31 09:48:12,072 (TP-Processor9) [
>> RequestHandler.java:422:INFO ] [RequestHandler.doRequest]: Response is a
>> Request redirect with no parameters.
>> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
>> 2007-08-31 09:48:12,073 (TP-Processor9) [
>> RequestHandler.java:533:INFO ] [Sending
>> redirect]: /backend/control/activitiesMain
>> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
>> 2007-08-31 09:48:12,439 (TP-Processor11) [
>> ControlServlet.java:93 :INFO ] The character encoding of the request is:
>> [null]. The character encoding we will use for the request and response
>> is: [UTF-8]
>> 2007-08-31 09:48:12,440 (TP-Processor11) [
>> RequestHandler.java:239:INFO ] [Processing Request]: activitiesMain
>> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
>> 2007-08-31 09:48:12,441 (TP-Processor11) [
>> RequestManager.java:116:INFO ] [RequestManager.getRequestAttribute]
>> Value for attribute "null" of uri "activitiesMain" not found
>> 2007-08-31 09:48:12,441 (TP-Processor11) [
>> RequestHandler.java:427:INFO ] [RequestHandler.doRequest]: Response is a
>> view. sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
>> 2007-08-31 09:48:12,441 (TP-Processor11) [
>> RequestHandler.java:576:INFO ] servletName=control, view=activitiesMain
>> sessionId=6CF568543A61507C6B964AAF27AAA2AE.jvm1
>> 2007-08-31 09:48:12,782 (TP-Processor11) [
>> ModelForm.java:1225:INFO ] preparePager: low - high = 0 - 20
>> 2007-08-31 09:48:12,783 (TP-Processor11) [
>> ModelForm.java:1242:INFO ] preparePager: Found rows = 20
>> 2007-08-31 09:48:12,832 (TP-Processor11) [
>> ModelForm.java:1225:INFO ] preparePager: low - high = 0 - 20
>> 2007-08-31 09:48:12,833 (TP-Processor11) [
>> ModelForm.java:1242:INFO ] preparePager: Found rows = 20
>> 2007-08-31 09:48:32,971 (TP-Processor9)
>> [ControlEventListener.java:62 :INFO ] Creating session:
>> 96A6796CF184E14027BC8788A4A46B5D.jvm1
>> 2007-08-31 09:48:32,972 (TP-Processor9) [
>> ControlServlet.java:93 :INFO ] The character encoding of the request is:
>> [null]. The character encoding we will use for the request and response
>> is: [UTF-8]
>> 2007-08-31 09:48:32,974 (TP-Processor9) [
>> VisitHandler.java:222:INFO ] Found visitorId [108432] in cookie
>> 2007-08-31 09:48:32,978 (TP-Processor9) [
>> RequestHandler.java:180:INFO ] This is the first request in this visit.
>> sessionId=96A6796CF184E14027BC8788A4A46B5D.jvm1
>> 2007-08-31 09:48:33,073 (TP-Processor9) [
>> RequestHandler.java:239:INFO ] [Processing Request]: logout
>> sessionId=96A6796CF184E14027BC8788A4A46B5D.jvm1
>> 2007-08-31 09:48:33,074 (TP-Processor9) [
>> LoginWorker.java:249:INFO ] reqParams Map: []
>> 2007-08-31 09:48:33,074 (TP-Processor9) [
>> LoginWorker.java:250:INFO ] queryString: 2007-08-31 09:48:33,075
>> (TP-Processor9) [
>> LoginWorker.java:260:INFO ] checkLogin: queryString=
>> 2007-08-31 09:48:33,076 (TP-Processor9) [
>> LoginWorker.java:261:INFO ] checkLogin: PathInfo=/logout
>> 2007-08-31 09:48:33,076 (TP-Processor9) [
>> RequestHandler.java:427:INFO ] [RequestHandler.doRequest]: Response is a
>> view. sessionId=96A6796CF184E14027BC8788A4A46B5D.jvm1
>> 2007-08-31 09:48:33,077 (TP-Processor9) [
>> RequestHandler.java:576:INFO ] servletName=control, view=login
>> sessionId=96A6796CF184E14027BC8788A4A46B5D.jvm1
>> 2007-08-31 09:48:33,079 (TP-Processor9) [
>> PrimaryKeyFinder.java:124:INFO ] Returning null because found incomplete
>> primary key in find: [GenericEntity:ProductStore][productStoreId,null()]
>>
>>
>>
>>

--
Vince Clark
Global Era
The freedom of open source.
[phone]
[phone] fax
[hidden email] <mailto:[hidden email]>
www.globalera.com