Is XWikiAuthServiceImpl.authenticate called on every page view?

classic Classic list List threaded Threaded
5 messages Options
Reply | Threaded
Open this post in threaded view
|

Is XWikiAuthServiceImpl.authenticate called on every page view?

Robin Fernandes
Hi,

Assuming "Always Authenticate on Viewing" is enabled in the wiki
preferences, should XWikiAuthServiceImpl.authenticate(user, password,
context) actually get called on every page view? Or is some cookie
used to prevent this?

I ask because this behaviour seems to have changed on my local xwiki
installation, and I'm not what I've done to cause the change (it used
to get called on every page view, and doesn't any more).

Thanks,
Robin.



--
You receive this message as a subscriber of the [hidden email] mailing list.
To unsubscribe: mailto:[hidden email]
For general help: mailto:[hidden email]?subject=help
ObjectWeb mailing lists service home page: http://www.objectweb.org/wws
Reply | Threaded
Open this post in threaded view
|

Re: Is XWikiAuthServiceImpl.authenticate called on every page view?

jeremi joslin
Hi,
As I can see in the code, it's call before opening every page.
I'm not sure, but i don't think some cookie used to prevent this.

I configure my wiki like yours (Always Authenticate on Viewing
enabled) and it's always called.

Can you give us more informations?

Jérémi

On 2/10/06, Robin Fernandes <[hidden email]> wrote:

> Hi,
>
> Assuming "Always Authenticate on Viewing" is enabled in the wiki
> preferences, should XWikiAuthServiceImpl.authenticate(user, password,
> context) actually get called on every page view? Or is some cookie
> used to prevent this?
>
> I ask because this behaviour seems to have changed on my local xwiki
> installation, and I'm not what I've done to cause the change (it used
> to get called on every page view, and doesn't any more).
>
> Thanks,
> Robin.
>
>
>
>
> --
> You receive this message as a subscriber of the [hidden email] mailing list.
> To unsubscribe: mailto:[hidden email]
> For general help: mailto:[hidden email]?subject=help
> ObjectWeb mailing lists service home page: http://www.objectweb.org/wws
>
>
>

--
Blog: http://www.jeremi.info
LinkedIn: https://www.linkedin.com/profile?viewProfile=&key=1437724
http://www.xwiki.org
skype: jeremi23 -- msn et gtalk : [hidden email]



--
You receive this message as a subscriber of the [hidden email] mailing list.
To unsubscribe: mailto:[hidden email]
For general help: mailto:[hidden email]?subject=help
ObjectWeb mailing lists service home page: http://www.objectweb.org/wws
Reply | Threaded
Open this post in threaded view
|

Re: Is XWikiAuthServiceImpl.authenticate called on every page view?

Robin Fernandes
Ludovic, Jérémi,  thank you for answering my probably dumb questions. :)

Yes I do see the name at the top. Here's what is confusing me. I have
written a very simple extension to the authentication service
implementation like this:

public class myAuthService extends XWikiAuthServiceImpl {
        private static final Log log = LogFactory.getLog(myAuthService.class);
       
        public Principal authenticate(String username, String password,
XWikiContext context) throws XWikiException {
                log.debug("myAuthService: robinf - in authenticate.");
                return super.authenticate(username, password, context);
        }
}

I have set in xwiki.cfg:
        xwiki.authentication.authclass=com.ibm.robinf.xwiki.authentication.myAuthService
and in the log4j.properties I have added:
        log4j.logger.com.ibm.robinf.xwiki.authentication.myAuthService=debug

Here is my xwiki.log after several page visits. You'll see that there
is just one occurence of the trace string "myAuthService: robinf - in
authenticate".
I'm on XWiki v0.9.840, and I'm assuming the exceptions are explained
by http://mail-archive.objectweb.org/xwiki-users/2005-12/msg00077.html
.

--------------------------
16:48:17,265  WARN http-8080-Processor25
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'login'
16:48:19,098  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/login/XWiki/XWikiLogin
Configurator:configure:126 - No configuration found. Configuring
ehcache from ehcache-failsafe.xml found in the classpath:
jar:file:/C:/Program%20Files/Apache%20Software%20Foundation/Tomcat%205.5/webapps/xwiki/WEB-INF/lib/ehcache-1.1.jar!/ehcache-failsafe.xml
16:48:21,882 ERROR http-8080-Processor25
http://localhost:8080/xwiki/bin/login/XWiki/XWikiLogin
XWikiHibernateStore:updateSchema:177 - Schema update for wiki xwiki
16:48:25,817 DEBUG http-8080-Processor25
http://localhost:8080/xwiki/bin/login/XWiki/XWikiLogin
myAuthService:authenticate:16 - myAuthService: robinf - in
authenticate (user: robintest)
16:48:26,368  WARN http-8080-Processor25
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'view'
16:48:40,228  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/XWiki/robintest
XWikiStatsServiceImpl:addCookie:474 - Setting cookie
WMKS8KK6ODUTD58A0ZOK4FNRM0IQ13OP for name visitid with domain
localhost and path / and maxage -2074260
16:48:40,228  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/XWiki/robintest
XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
0C98371BFD9D704F6C4E732928ABD862 for request with cookie
WMKS8KK6ODUTD58A0ZOK4FNRM0IQ13OP
16:48:40,949 ERROR http-8080-Processor25
http://localhost:8080/xwiki/bin/view/XWiki/robintest
AssertionFailure:<init>:22 - an assertion failure occured (this may
indicate a bug in Hibernate, but is more likely due to unsafe use of
the session)
org.hibernate.AssertionFailure: possible nonthreadsafe access to session
        at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
        at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
        at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:223)
        at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:141)
        at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:274)
        at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:27)
        at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:726)
        at org.hibernate.impl.SessionImpl.managedFlush(SessionImpl.java:320)
        at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:86)
        at com.xpn.xwiki.store.XWikiHibernateStore.endTransaction(XWikiHibernateStore.java:350)
        at com.xpn.xwiki.store.XWikiHibernateStore.deleteXWikiCollection(XWikiHibernateStore.java:982)
        at com.xpn.xwiki.stats.impl.XWikiStatsServiceImpl.notify(XWikiStatsServiceImpl.java:209)
        at com.xpn.xwiki.notify.XWikiActionRule.verify(XWikiActionRule.java:51)
        at com.xpn.xwiki.notify.XWikiNotificationManager.verify(XWikiNotificationManager.java:93)
        at com.xpn.xwiki.web.ViewEditAction.execute(ViewEditAction.java:268)
        at org.apache.struts.action.RequestProcessor.processActionPerform(RequestProcessor.java:421)
        at org.apache.struts.action.RequestProcessor.process(RequestProcessor.java:226)
        at org.apache.struts.action.ActionServlet.process(ActionServlet.java:1164)
        at org.apache.struts.action.ActionServlet.doGet(ActionServlet.java:397)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:689)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:802)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
        at com.xpn.xwiki.web.SetCharacterEncodingFilter.doFilter(SetCharacterEncodingFilter.java:127)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126)
        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105)
        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107)
        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148)
        at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:868)
        at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:663)
        at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527)
        at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:80)
        at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:684)
        at java.lang.Thread.run(Thread.java:788)
16:48:43,393  WARN http-8080-Processor25
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'view'
16:48:46,067  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/WebHome
XWikiStatsServiceImpl:addCookie:474 - Setting cookie
AAYI0BUY6AZCPDBPUVB6SYQF7W4GOXSN for name visitid with domain
localhost and path / and maxage -2074265
16:48:46,077  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/WebHome
XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
0C98371BFD9D704F6C4E732928ABD862 for request with cookie
AAYI0BUY6AZCPDBPUVB6SYQF7W4GOXSN
16:48:46,097 ERROR http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/WebHome
AssertionFailure:<init>:22 - an assertion failure occured (this may
indicate a bug in Hibernate, but is more likely due to unsafe use of
the session)
org.hibernate.AssertionFailure: possible nonthreadsafe access to session
        at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
        at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
[*snip* removing recurring stacktrace for legibility *snip*]
        at java.lang.Thread.run(Thread.java:788)
16:48:54,739  WARN http-8080-Processor25
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'view'
16:48:59,866  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/XWiki/robintest
XWikiStatsServiceImpl:addCookie:474 - Setting cookie
HYUPZKDBBPIRN8SOMQ4WGKURIE5GEXMX for name visitid with domain
localhost and path / and maxage -2074279
16:48:59,956  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/XWiki/robintest
XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
0C98371BFD9D704F6C4E732928ABD862 for request with cookie
HYUPZKDBBPIRN8SOMQ4WGKURIE5GEXMX
16:49:00,537 ERROR http-8080-Processor25
http://localhost:8080/xwiki/bin/view/XWiki/robintest
AssertionFailure:<init>:22 - an assertion failure occured (this may
indicate a bug in Hibernate, but is more likely due to unsafe use of
the session)
org.hibernate.AssertionFailure: possible nonthreadsafe access to session
        at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
        at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
[*snip* removing recurring stacktrace for legibility *snip*]
        at java.lang.Thread.run(Thread.java:788)
16:49:02,610  WARN http-8080-Processor25
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'view'
16:49:04,533  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/Teams
XWikiStatsServiceImpl:addCookie:474 - Setting cookie
DIXM1WAGYLHJQNYFVSCWUFKZ85ZFVKN2 for name visitid with domain
localhost and path / and maxage -2074284
16:49:04,593  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/Teams
XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
0C98371BFD9D704F6C4E732928ABD862 for request with cookie
DIXM1WAGYLHJQNYFVSCWUFKZ85ZFVKN2
16:49:04,673 ERROR http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/Teams
AssertionFailure:<init>:22 - an assertion failure occured (this may
indicate a bug in Hibernate, but is more likely due to unsafe use of
the session)
org.hibernate.AssertionFailure: possible nonthreadsafe access to session
        at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
        at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
[*snip* removing recurring stacktrace for legibility *snip*]
        at java.lang.Thread.run(Thread.java:788)
16:49:07,457  WARN http-8080-Processor25
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'view'
16:49:08,779  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/Teams
XWikiStatsServiceImpl:addCookie:474 - Setting cookie
XIO9V3257B1UPP6WB82BLCHOYYKDZAGQ for name visitid with domain
localhost and path / and maxage -2074288
16:49:08,919  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/Teams
XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
0C98371BFD9D704F6C4E732928ABD862 for request with cookie
XIO9V3257B1UPP6WB82BLCHOYYKDZAGQ
16:49:09,010 ERROR http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/Teams
AssertionFailure:<init>:22 - an assertion failure occured (this may
indicate a bug in Hibernate, but is more likely due to unsafe use of
the session)
org.hibernate.AssertionFailure: possible nonthreadsafe access to session
        at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
        at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
[*snip* removing recurring stacktrace for legibility *snip*]
        at java.lang.Thread.run(Thread.java:788)
16:49:09,080  WARN http-8080-Processor25
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'skin'
16:49:09,110  WARN http-8080-Processor25
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'skin'
16:49:09,130  WARN http-8080-Processor24
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'skin'
16:49:09,200  WARN http-8080-Processor22
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'skin'
16:49:09,220  WARN http-8080-Processor23
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'skin'
16:49:09,400  WARN http-8080-Processor24
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'skin'
16:49:09,430  WARN http-8080-Processor22
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'skin'
16:49:09,440  WARN http-8080-Processor23
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'skin'
16:49:10,101  WARN http-8080-Processor25
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'skin'
16:49:12,224  WARN http-8080-Processor25
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'view'
16:49:12,394  WARN Finalizer thread  JDBCContext:finalize:333 -
afterTransactionCompletion() was never called
16:49:12,394  WARN Finalizer thread  JDBCContext:finalize:333 -
afterTransactionCompletion() was never called
16:49:12,394  WARN Finalizer thread  JDBCContext:finalize:333 -
afterTransactionCompletion() was never called
16:49:12,394  WARN Finalizer thread  JDBCContext:finalize:333 -
afterTransactionCompletion() was never called
16:49:12,394  WARN Finalizer thread  JDBCContext:finalize:333 -
afterTransactionCompletion() was never called
16:49:14,748  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/JTC/ArchitectureAndDesign
XWikiStatsServiceImpl:addCookie:474 - Setting cookie
B6LRLQQ1YOETNSAIZLGNEYHJRGAEFK0R for name visitid with domain
localhost and path / and maxage -2074294
16:49:14,748  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/JTC/ArchitectureAndDesign
XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
0C98371BFD9D704F6C4E732928ABD862 for request with cookie
B6LRLQQ1YOETNSAIZLGNEYHJRGAEFK0R
16:49:14,838 ERROR http-8080-Processor25
http://localhost:8080/xwiki/bin/view/JTC/ArchitectureAndDesign
AssertionFailure:<init>:22 - an assertion failure occured (this may
indicate a bug in Hibernate, but is more likely due to unsafe use of
the session)
org.hibernate.AssertionFailure: possible nonthreadsafe access to session
        at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
        at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
[*snip* removing recurring stacktrace for legibility *snip*]
        at java.lang.Thread.run(Thread.java:788)
--------------------------






Since my last mail I established what I had changed on my system: I
set the cookie domain to localhost.
If I remove the cookie domain from my xwiki.cfg as so:
        #xwiki.authentication.cookiedomains=localhost
then my trace point _does_ get hit on every page view:

--------------------------
17:00:18,052  WARN http-8080-Processor25
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'view'
17:00:19,734  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/WebHome
Configurator:configure:126 - No configuration found. Configuring
ehcache from ehcache-failsafe.xml found in the classpath:
jar:file:/C:/Program%20Files/Apache%20Software%20Foundation/Tomcat%205.5/webapps/xwiki/WEB-INF/lib/ehcache-1.1.jar!/ehcache-failsafe.xml
17:00:22,218 ERROR http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/WebHome
XWikiHibernateStore:updateSchema:177 - Schema update for wiki xwiki
17:00:26,604 DEBUG http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/WebHome
myAuthService:authenticate:16 - myAuthService: robinf - in
authenticate (user: robintest)
17:00:36,308  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/WebHome
XWikiStatsServiceImpl:addCookie:474 - Setting cookie
ZLYJYGUWZXCMUR8OKGRPEKRLNJU4Y6BG for name visitid with domain null and
path / and maxage -2074255
17:00:36,308  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/WebHome
XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
0C98371BFD9D704F6C4E732928ABD862 for request with cookie
ZLYJYGUWZXCMUR8OKGRPEKRLNJU4Y6BG
17:00:36,758 ERROR http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/WebHome
AssertionFailure:<init>:22 - an assertion failure occured (this may
indicate a bug in Hibernate, but is more likely due to unsafe use of
the session)
org.hibernate.AssertionFailure: possible nonthreadsafe access to session
        at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
        at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
[*snip* removing recurring stacktrace for legibility *snip*]
        at java.lang.Thread.run(Thread.java:788)
17:00:42,977  WARN http-8080-Processor25
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'view'
17:00:42,987 DEBUG http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/Teams
myAuthService:authenticate:16 - myAuthService: robinf - in
authenticate (user: robintest)
17:00:46,432  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/Teams
XWikiStatsServiceImpl:addCookie:474 - Setting cookie
VDJ4LC3FPKAT0XVU8ITI7SLGNPSBKN1W for name visitid with domain null and
path / and maxage -2074265
17:00:46,442  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/Teams
XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
0C98371BFD9D704F6C4E732928ABD862 for request with cookie
VDJ4LC3FPKAT0XVU8ITI7SLGNPSBKN1W
17:00:46,462 ERROR http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/Teams
AssertionFailure:<init>:22 - an assertion failure occured (this may
indicate a bug in Hibernate, but is more likely due to unsafe use of
the session)
org.hibernate.AssertionFailure: possible nonthreadsafe access to session
        at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
        at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
[*snip* removing recurring stacktrace for legibility *snip*]
        at java.lang.Thread.run(Thread.java:788)
17:01:04,959  WARN http-8080-Processor25
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'view'
17:01:04,979 DEBUG http-8080-Processor25
http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
myAuthService:authenticate:16 - myAuthService: robinf - in
authenticate (user: robintest)
17:01:06,361  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
XWikiStatsServiceImpl:addCookie:474 - Setting cookie
KWWHZOWMIHI3IBN0APUGERVCDLFNXTQS for name visitid with domain null and
path / and maxage -2074285
17:01:06,361  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
0C98371BFD9D704F6C4E732928ABD862 for request with cookie
KWWHZOWMIHI3IBN0APUGERVCDLFNXTQS
17:01:06,511 ERROR http-8080-Processor25
http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
AssertionFailure:<init>:22 - an assertion failure occured (this may
indicate a bug in Hibernate, but is more likely due to unsafe use of
the session)
org.hibernate.AssertionFailure: possible nonthreadsafe access to session
        at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
        at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
[*snip* removing recurring stacktrace for legibility *snip*]
        at java.lang.Thread.run(Thread.java:788)
17:01:09,095  WARN http-8080-Processor25
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'view'
17:01:09,105 DEBUG http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/WebHome
myAuthService:authenticate:16 - myAuthService: robinf - in
authenticate (user: robintest)
17:01:10,267  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/WebHome
XWikiStatsServiceImpl:addCookie:474 - Setting cookie
DBT70UT0NV9ZGNVK92UP5NDPH1YTGSML for name visitid with domain null and
path / and maxage -2074289
17:01:10,267  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/WebHome
XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
0C98371BFD9D704F6C4E732928ABD862 for request with cookie
DBT70UT0NV9ZGNVK92UP5NDPH1YTGSML
17:01:10,357 ERROR http-8080-Processor25
http://localhost:8080/xwiki/bin/view/Main/WebHome
AssertionFailure:<init>:22 - an assertion failure occured (this may
indicate a bug in Hibernate, but is more likely due to unsafe use of
the session)
org.hibernate.AssertionFailure: possible nonthreadsafe access to session
        at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
        at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
[*snip* removing recurring stacktrace for legibility *snip*]
        at java.lang.Thread.run(Thread.java:788)
17:01:24,397  WARN http-8080-Processor25
RequestUtils:createActionForm:177 - No FormBeanConfig found under
'view'
17:01:24,407 DEBUG http-8080-Processor25
http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
myAuthService:authenticate:16 - myAuthService: robinf - in
authenticate (user: robintest)
17:01:25,629  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
XWikiStatsServiceImpl:addCookie:474 - Setting cookie
PZ8I4VLKA1U18EFQRV64M7W11OMPNA2F for name visitid with domain null and
path / and maxage -2074304
17:01:25,629  WARN http-8080-Processor25
http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
0C98371BFD9D704F6C4E732928ABD862 for request with cookie
PZ8I4VLKA1U18EFQRV64M7W11OMPNA2F
17:01:25,739 ERROR http-8080-Processor25
http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
AssertionFailure:<init>:22 - an assertion failure occured (this may
indicate a bug in Hibernate, but is more likely due to unsafe use of
the session)
org.hibernate.AssertionFailure: possible nonthreadsafe access to session
        at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
        at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
[*snip* removing recurring stacktrace for legibility *snip*]
        at java.lang.Thread.run(Thread.java:788)
--------------------------

Is this behaviour expected? If so, could you explain a little bit what
is going on?

Thanks again!
Robin.


On 10/02/06, jeremi joslin <[hidden email]> wrote:

> Hi,
> As I can see in the code, it's call before opening every page.
> I'm not sure, but i don't think some cookie used to prevent this.
>
> I configure my wiki like yours (Always Authenticate on Viewing
> enabled) and it's always called.
>
> Can you give us more informations?
>
> Jérémi
>
> On 2/10/06, Robin Fernandes <[hidden email]> wrote:
> > Hi,
> >
> > Assuming "Always Authenticate on Viewing" is enabled in the wiki
> > preferences, should XWikiAuthServiceImpl.authenticate(user, password,
> > context) actually get called on every page view? Or is some cookie
> > used to prevent this?
> >
> > I ask because this behaviour seems to have changed on my local xwiki
> > installation, and I'm not what I've done to cause the change (it used
> > to get called on every page view, and doesn't any more).
> >
> > Thanks,
> > Robin.
> >
> >
> >
> >
> > --
> > You receive this message as a subscriber of the [hidden email] mailing list.
> > To unsubscribe: mailto:[hidden email]
> > For general help: mailto:[hidden email]?subject=help
> > ObjectWeb mailing lists service home page: http://www.objectweb.org/wws
> >
> >
> >
>
>
> --
> Blog: http://www.jeremi.info
> LinkedIn: https://www.linkedin.com/profile?viewProfile=&key=1437724
> http://www.xwiki.org
> skype: jeremi23 -- msn et gtalk : [hidden email]
>
>
>
>
> --
> You receive this message as a subscriber of the [hidden email] mailing list.
> To unsubscribe: mailto:[hidden email]
> For general help: mailto:[hidden email]?subject=help
> ObjectWeb mailing lists service home page: http://www.objectweb.org/wws
>
>
>


--
You receive this message as a subscriber of the [hidden email] mailing list.
To unsubscribe: mailto:[hidden email]
For general help: mailto:[hidden email]?subject=help
ObjectWeb mailing lists service home page: http://www.objectweb.org/wws
Reply | Threaded
Open this post in threaded view
|

Re: Is XWikiAuthServiceImpl.authenticate called on every page view?

jeremi joslin
Hi,
I use the last version of XWiki, I do the same as you and
myAuthService is always called.

Maybe ludovic have an idea about that?

Jérémi

On 2/11/06, Robin Fernandes <[hidden email]> wrote:

> Ludovic, Jérémi,  thank you for answering my probably dumb questions. :)
>
> Yes I do see the name at the top. Here's what is confusing me. I have
> written a very simple extension to the authentication service
> implementation like this:
>
> public class myAuthService extends XWikiAuthServiceImpl {
>         private static final Log log = LogFactory.getLog(myAuthService.class);
>
>         public Principal authenticate(String username, String password,
> XWikiContext context) throws XWikiException {
>                 log.debug("myAuthService: robinf - in authenticate.");
>                 return super.authenticate(username, password, context);
>         }
> }
>
> I have set in xwiki.cfg:
>         xwiki.authentication.authclass=com.ibm.robinf.xwiki.authentication.myAuthService
> and in the log4j.properties I have added:
>         log4j.logger.com.ibm.robinf.xwiki.authentication.myAuthService=debug
>
> Here is my xwiki.log after several page visits. You'll see that there
> is just one occurence of the trace string "myAuthService: robinf - in
> authenticate".
> I'm on XWiki v0.9.840, and I'm assuming the exceptions are explained
> by http://mail-archive.objectweb.org/xwiki-users/2005-12/msg00077.html
> .
>
> --------------------------
> 16:48:17,265  WARN http-8080-Processor25
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'login'
> 16:48:19,098  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/login/XWiki/XWikiLogin
> Configurator:configure:126 - No configuration found. Configuring
> ehcache from ehcache-failsafe.xml found in the classpath:
> jar:file:/C:/Program%20Files/Apache%20Software%20Foundation/Tomcat%205.5/webapps/xwiki/WEB-INF/lib/ehcache-1.1.jar!/ehcache-failsafe.xml
> 16:48:21,882 ERROR http-8080-Processor25
> http://localhost:8080/xwiki/bin/login/XWiki/XWikiLogin
> XWikiHibernateStore:updateSchema:177 - Schema update for wiki xwiki
> 16:48:25,817 DEBUG http-8080-Processor25
> http://localhost:8080/xwiki/bin/login/XWiki/XWikiLogin
> myAuthService:authenticate:16 - myAuthService: robinf - in
> authenticate (user: robintest)
> 16:48:26,368  WARN http-8080-Processor25
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'view'
> 16:48:40,228  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/XWiki/robintest
> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
> WMKS8KK6ODUTD58A0ZOK4FNRM0IQ13OP for name visitid with domain
> localhost and path / and maxage -2074260
> 16:48:40,228  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/XWiki/robintest
> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
> WMKS8KK6ODUTD58A0ZOK4FNRM0IQ13OP
> 16:48:40,949 ERROR http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/XWiki/robintest
> AssertionFailure:<init>:22 - an assertion failure occured (this may
> indicate a bug in Hibernate, but is more likely due to unsafe use of
> the session)
> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
>         at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:223)
>         at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:141)
>         at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:274)
>         at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:27)
>         at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:726)
>         at org.hibernate.impl.SessionImpl.managedFlush(SessionImpl.java:320)
>         at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:86)
>         at com.xpn.xwiki.store.XWikiHibernateStore.endTransaction(XWikiHibernateStore.java:350)
>         at com.xpn.xwiki.store.XWikiHibernateStore.deleteXWikiCollection(XWikiHibernateStore.java:982)
>         at com.xpn.xwiki.stats.impl.XWikiStatsServiceImpl.notify(XWikiStatsServiceImpl.java:209)
>         at com.xpn.xwiki.notify.XWikiActionRule.verify(XWikiActionRule.java:51)
>         at com.xpn.xwiki.notify.XWikiNotificationManager.verify(XWikiNotificationManager.java:93)
>         at com.xpn.xwiki.web.ViewEditAction.execute(ViewEditAction.java:268)
>         at org.apache.struts.action.RequestProcessor.processActionPerform(RequestProcessor.java:421)
>         at org.apache.struts.action.RequestProcessor.process(RequestProcessor.java:226)
>         at org.apache.struts.action.ActionServlet.process(ActionServlet.java:1164)
>         at org.apache.struts.action.ActionServlet.doGet(ActionServlet.java:397)
>         at javax.servlet.http.HttpServlet.service(HttpServlet.java:689)
>         at javax.servlet.http.HttpServlet.service(HttpServlet.java:802)
>         at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252)
>         at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
>         at com.xpn.xwiki.web.SetCharacterEncodingFilter.doFilter(SetCharacterEncodingFilter.java:127)
>         at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202)
>         at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
>         at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
>         at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178)
>         at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126)
>         at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105)
>         at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107)
>         at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148)
>         at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:868)
>         at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:663)
>         at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527)
>         at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:80)
>         at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:684)
>         at java.lang.Thread.run(Thread.java:788)
> 16:48:43,393  WARN http-8080-Processor25
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'view'
> 16:48:46,067  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/WebHome
> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
> AAYI0BUY6AZCPDBPUVB6SYQF7W4GOXSN for name visitid with domain
> localhost and path / and maxage -2074265
> 16:48:46,077  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/WebHome
> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
> AAYI0BUY6AZCPDBPUVB6SYQF7W4GOXSN
> 16:48:46,097 ERROR http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/WebHome
> AssertionFailure:<init>:22 - an assertion failure occured (this may
> indicate a bug in Hibernate, but is more likely due to unsafe use of
> the session)
> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
> [*snip* removing recurring stacktrace for legibility *snip*]
>         at java.lang.Thread.run(Thread.java:788)
> 16:48:54,739  WARN http-8080-Processor25
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'view'
> 16:48:59,866  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/XWiki/robintest
> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
> HYUPZKDBBPIRN8SOMQ4WGKURIE5GEXMX for name visitid with domain
> localhost and path / and maxage -2074279
> 16:48:59,956  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/XWiki/robintest
> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
> HYUPZKDBBPIRN8SOMQ4WGKURIE5GEXMX
> 16:49:00,537 ERROR http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/XWiki/robintest
> AssertionFailure:<init>:22 - an assertion failure occured (this may
> indicate a bug in Hibernate, but is more likely due to unsafe use of
> the session)
> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
> [*snip* removing recurring stacktrace for legibility *snip*]
>         at java.lang.Thread.run(Thread.java:788)
> 16:49:02,610  WARN http-8080-Processor25
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'view'
> 16:49:04,533  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/Teams
> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
> DIXM1WAGYLHJQNYFVSCWUFKZ85ZFVKN2 for name visitid with domain
> localhost and path / and maxage -2074284
> 16:49:04,593  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/Teams
> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
> DIXM1WAGYLHJQNYFVSCWUFKZ85ZFVKN2
> 16:49:04,673 ERROR http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/Teams
> AssertionFailure:<init>:22 - an assertion failure occured (this may
> indicate a bug in Hibernate, but is more likely due to unsafe use of
> the session)
> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
> [*snip* removing recurring stacktrace for legibility *snip*]
>         at java.lang.Thread.run(Thread.java:788)
> 16:49:07,457  WARN http-8080-Processor25
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'view'
> 16:49:08,779  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/Teams
> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
> XIO9V3257B1UPP6WB82BLCHOYYKDZAGQ for name visitid with domain
> localhost and path / and maxage -2074288
> 16:49:08,919  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/Teams
> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
> XIO9V3257B1UPP6WB82BLCHOYYKDZAGQ
> 16:49:09,010 ERROR http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/Teams
> AssertionFailure:<init>:22 - an assertion failure occured (this may
> indicate a bug in Hibernate, but is more likely due to unsafe use of
> the session)
> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
> [*snip* removing recurring stacktrace for legibility *snip*]
>         at java.lang.Thread.run(Thread.java:788)
> 16:49:09,080  WARN http-8080-Processor25
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'skin'
> 16:49:09,110  WARN http-8080-Processor25
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'skin'
> 16:49:09,130  WARN http-8080-Processor24
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'skin'
> 16:49:09,200  WARN http-8080-Processor22
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'skin'
> 16:49:09,220  WARN http-8080-Processor23
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'skin'
> 16:49:09,400  WARN http-8080-Processor24
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'skin'
> 16:49:09,430  WARN http-8080-Processor22
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'skin'
> 16:49:09,440  WARN http-8080-Processor23
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'skin'
> 16:49:10,101  WARN http-8080-Processor25
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'skin'
> 16:49:12,224  WARN http-8080-Processor25
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'view'
> 16:49:12,394  WARN Finalizer thread  JDBCContext:finalize:333 -
> afterTransactionCompletion() was never called
> 16:49:12,394  WARN Finalizer thread  JDBCContext:finalize:333 -
> afterTransactionCompletion() was never called
> 16:49:12,394  WARN Finalizer thread  JDBCContext:finalize:333 -
> afterTransactionCompletion() was never called
> 16:49:12,394  WARN Finalizer thread  JDBCContext:finalize:333 -
> afterTransactionCompletion() was never called
> 16:49:12,394  WARN Finalizer thread  JDBCContext:finalize:333 -
> afterTransactionCompletion() was never called
> 16:49:14,748  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/JTC/ArchitectureAndDesign
> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
> B6LRLQQ1YOETNSAIZLGNEYHJRGAEFK0R for name visitid with domain
> localhost and path / and maxage -2074294
> 16:49:14,748  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/JTC/ArchitectureAndDesign
> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
> B6LRLQQ1YOETNSAIZLGNEYHJRGAEFK0R
> 16:49:14,838 ERROR http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/JTC/ArchitectureAndDesign
> AssertionFailure:<init>:22 - an assertion failure occured (this may
> indicate a bug in Hibernate, but is more likely due to unsafe use of
> the session)
> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
> [*snip* removing recurring stacktrace for legibility *snip*]
>         at java.lang.Thread.run(Thread.java:788)
> --------------------------
>
>
>
>
>
>
> Since my last mail I established what I had changed on my system: I
> set the cookie domain to localhost.
> If I remove the cookie domain from my xwiki.cfg as so:
>         #xwiki.authentication.cookiedomains=localhost
> then my trace point _does_ get hit on every page view:
>
> --------------------------
> 17:00:18,052  WARN http-8080-Processor25
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'view'
> 17:00:19,734  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/WebHome
> Configurator:configure:126 - No configuration found. Configuring
> ehcache from ehcache-failsafe.xml found in the classpath:
> jar:file:/C:/Program%20Files/Apache%20Software%20Foundation/Tomcat%205.5/webapps/xwiki/WEB-INF/lib/ehcache-1.1.jar!/ehcache-failsafe.xml
> 17:00:22,218 ERROR http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/WebHome
> XWikiHibernateStore:updateSchema:177 - Schema update for wiki xwiki
> 17:00:26,604 DEBUG http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/WebHome
> myAuthService:authenticate:16 - myAuthService: robinf - in
> authenticate (user: robintest)
> 17:00:36,308  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/WebHome
> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
> ZLYJYGUWZXCMUR8OKGRPEKRLNJU4Y6BG for name visitid with domain null and
> path / and maxage -2074255
> 17:00:36,308  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/WebHome
> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
> ZLYJYGUWZXCMUR8OKGRPEKRLNJU4Y6BG
> 17:00:36,758 ERROR http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/WebHome
> AssertionFailure:<init>:22 - an assertion failure occured (this may
> indicate a bug in Hibernate, but is more likely due to unsafe use of
> the session)
> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
> [*snip* removing recurring stacktrace for legibility *snip*]
>         at java.lang.Thread.run(Thread.java:788)
> 17:00:42,977  WARN http-8080-Processor25
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'view'
> 17:00:42,987 DEBUG http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/Teams
> myAuthService:authenticate:16 - myAuthService: robinf - in
> authenticate (user: robintest)
> 17:00:46,432  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/Teams
> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
> VDJ4LC3FPKAT0XVU8ITI7SLGNPSBKN1W for name visitid with domain null and
> path / and maxage -2074265
> 17:00:46,442  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/Teams
> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
> VDJ4LC3FPKAT0XVU8ITI7SLGNPSBKN1W
> 17:00:46,462 ERROR http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/Teams
> AssertionFailure:<init>:22 - an assertion failure occured (this may
> indicate a bug in Hibernate, but is more likely due to unsafe use of
> the session)
> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
> [*snip* removing recurring stacktrace for legibility *snip*]
>         at java.lang.Thread.run(Thread.java:788)
> 17:01:04,959  WARN http-8080-Processor25
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'view'
> 17:01:04,979 DEBUG http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
> myAuthService:authenticate:16 - myAuthService: robinf - in
> authenticate (user: robintest)
> 17:01:06,361  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
> KWWHZOWMIHI3IBN0APUGERVCDLFNXTQS for name visitid with domain null and
> path / and maxage -2074285
> 17:01:06,361  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
> KWWHZOWMIHI3IBN0APUGERVCDLFNXTQS
> 17:01:06,511 ERROR http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
> AssertionFailure:<init>:22 - an assertion failure occured (this may
> indicate a bug in Hibernate, but is more likely due to unsafe use of
> the session)
> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
> [*snip* removing recurring stacktrace for legibility *snip*]
>         at java.lang.Thread.run(Thread.java:788)
> 17:01:09,095  WARN http-8080-Processor25
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'view'
> 17:01:09,105 DEBUG http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/WebHome
> myAuthService:authenticate:16 - myAuthService: robinf - in
> authenticate (user: robintest)
> 17:01:10,267  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/WebHome
> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
> DBT70UT0NV9ZGNVK92UP5NDPH1YTGSML for name visitid with domain null and
> path / and maxage -2074289
> 17:01:10,267  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/WebHome
> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
> DBT70UT0NV9ZGNVK92UP5NDPH1YTGSML
> 17:01:10,357 ERROR http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/Main/WebHome
> AssertionFailure:<init>:22 - an assertion failure occured (this may
> indicate a bug in Hibernate, but is more likely due to unsafe use of
> the session)
> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
> [*snip* removing recurring stacktrace for legibility *snip*]
>         at java.lang.Thread.run(Thread.java:788)
> 17:01:24,397  WARN http-8080-Processor25
> RequestUtils:createActionForm:177 - No FormBeanConfig found under
> 'view'
> 17:01:24,407 DEBUG http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
> myAuthService:authenticate:16 - myAuthService: robinf - in
> authenticate (user: robintest)
> 17:01:25,629  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
> PZ8I4VLKA1U18EFQRV64M7W11OMPNA2F for name visitid with domain null and
> path / and maxage -2074304
> 17:01:25,629  WARN http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
> PZ8I4VLKA1U18EFQRV64M7W11OMPNA2F
> 17:01:25,739 ERROR http-8080-Processor25
> http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
> AssertionFailure:<init>:22 - an assertion failure occured (this may
> indicate a bug in Hibernate, but is more likely due to unsafe use of
> the session)
> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
> [*snip* removing recurring stacktrace for legibility *snip*]
>         at java.lang.Thread.run(Thread.java:788)
> --------------------------
>
> Is this behaviour expected? If so, could you explain a little bit what
> is going on?
>
> Thanks again!
> Robin.
>
>
> On 10/02/06, jeremi joslin <[hidden email]> wrote:
> > Hi,
> > As I can see in the code, it's call before opening every page.
> > I'm not sure, but i don't think some cookie used to prevent this.
> >
> > I configure my wiki like yours (Always Authenticate on Viewing
> > enabled) and it's always called.
> >
> > Can you give us more informations?
> >
> > Jérémi
> >
> > On 2/10/06, Robin Fernandes <[hidden email]> wrote:
> > > Hi,
> > >
> > > Assuming "Always Authenticate on Viewing" is enabled in the wiki
> > > preferences, should XWikiAuthServiceImpl.authenticate(user, password,
> > > context) actually get called on every page view? Or is some cookie
> > > used to prevent this?
> > >
> > > I ask because this behaviour seems to have changed on my local xwiki
> > > installation, and I'm not what I've done to cause the change (it used
> > > to get called on every page view, and doesn't any more).
> > >
> > > Thanks,
> > > Robin.
> > >
> > >
> > >
> > >
> > > --
> > > You receive this message as a subscriber of the [hidden email] mailing list.
> > > To unsubscribe: mailto:[hidden email]
> > > For general help: mailto:[hidden email]?subject=help
> > > ObjectWeb mailing lists service home page: http://www.objectweb.org/wws
> > >
> > >
> > >
> >
> >
> > --
> > Blog: http://www.jeremi.info
> > LinkedIn: https://www.linkedin.com/profile?viewProfile=&key=1437724
> > http://www.xwiki.org
> > skype: jeremi23 -- msn et gtalk : [hidden email]
> >
> >
> >
> >
> > --
> > You receive this message as a subscriber of the [hidden email] mailing list.
> > To unsubscribe: mailto:[hidden email]
> > For general help: mailto:[hidden email]?subject=help
> > ObjectWeb mailing lists service home page: http://www.objectweb.org/wws
> >
> >
> >
>
>
>
>
> --
> You receive this message as a subscriber of the [hidden email] mailing list.
> To unsubscribe: mailto:[hidden email]
> For general help: mailto:[hidden email]?subject=help
> ObjectWeb mailing lists service home page: http://www.objectweb.org/wws
>
>
>

--
Blog: http://www.jeremi.info
LinkedIn: https://www.linkedin.com/profile?viewProfile=&key=1437724
http://www.xwiki.org
skype: jeremi23 -- msn et gtalk : [hidden email]


--
You receive this message as a subscriber of the [hidden email] mailing list.
To unsubscribe: mailto:[hidden email]
For general help: mailto:[hidden email]?subject=help
ObjectWeb mailing lists service home page: http://www.objectweb.org/wws
Reply | Threaded
Open this post in threaded view
|

Re: Is XWikiAuthServiceImpl.authenticate called on every page view?

Ludovic Dubost

I looked at the code, and as I expected:

1/ First login, the form is presented and login is performed from the
form data
2/ Second login, the username/password is extracted from the cookie and
passed to the authenticate function

So the authenticate function should be called each time..
I think debugging would be the best for you to understand what's going on

Ludovic

jeremi joslin a écrit :

> Hi,
> I use the last version of XWiki, I do the same as you and
> myAuthService is always called.
>
> Maybe ludovic have an idea about that?
>
> Jérémi
>
> On 2/11/06, Robin Fernandes <[hidden email]> wrote:
>  
>> Ludovic, Jérémi,  thank you for answering my probably dumb questions. :)
>>
>> Yes I do see the name at the top. Here's what is confusing me. I have
>> written a very simple extension to the authentication service
>> implementation like this:
>>
>> public class myAuthService extends XWikiAuthServiceImpl {
>>         private static final Log log = LogFactory.getLog(myAuthService.class);
>>
>>         public Principal authenticate(String username, String password,
>> XWikiContext context) throws XWikiException {
>>                 log.debug("myAuthService: robinf - in authenticate.");
>>                 return super.authenticate(username, password, context);
>>         }
>> }
>>
>> I have set in xwiki.cfg:
>>         xwiki.authentication.authclass=com.ibm.robinf.xwiki.authentication.myAuthService
>> and in the log4j.properties I have added:
>>         log4j.logger.com.ibm.robinf.xwiki.authentication.myAuthService=debug
>>
>> Here is my xwiki.log after several page visits. You'll see that there
>> is just one occurence of the trace string "myAuthService: robinf - in
>> authenticate".
>> I'm on XWiki v0.9.840, and I'm assuming the exceptions are explained
>> by http://mail-archive.objectweb.org/xwiki-users/2005-12/msg00077.html
>> .
>>
>> --------------------------
>> 16:48:17,265  WARN http-8080-Processor25
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'login'
>> 16:48:19,098  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/login/XWiki/XWikiLogin
>> Configurator:configure:126 - No configuration found. Configuring
>> ehcache from ehcache-failsafe.xml found in the classpath:
>> jar:file:/C:/Program%20Files/Apache%20Software%20Foundation/Tomcat%205.5/webapps/xwiki/WEB-INF/lib/ehcache-1.1.jar!/ehcache-failsafe.xml
>> 16:48:21,882 ERROR http-8080-Processor25
>> http://localhost:8080/xwiki/bin/login/XWiki/XWikiLogin
>> XWikiHibernateStore:updateSchema:177 - Schema update for wiki xwiki
>> 16:48:25,817 DEBUG http-8080-Processor25
>> http://localhost:8080/xwiki/bin/login/XWiki/XWikiLogin
>> myAuthService:authenticate:16 - myAuthService: robinf - in
>> authenticate (user: robintest)
>> 16:48:26,368  WARN http-8080-Processor25
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'view'
>> 16:48:40,228  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/XWiki/robintest
>> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
>> WMKS8KK6ODUTD58A0ZOK4FNRM0IQ13OP for name visitid with domain
>> localhost and path / and maxage -2074260
>> 16:48:40,228  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/XWiki/robintest
>> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
>> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
>> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
>> WMKS8KK6ODUTD58A0ZOK4FNRM0IQ13OP
>> 16:48:40,949 ERROR http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/XWiki/robintest
>> AssertionFailure:<init>:22 - an assertion failure occured (this may
>> indicate a bug in Hibernate, but is more likely due to unsafe use of
>> the session)
>> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
>>         at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:223)
>>         at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:141)
>>         at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:274)
>>         at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:27)
>>         at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:726)
>>         at org.hibernate.impl.SessionImpl.managedFlush(SessionImpl.java:320)
>>         at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:86)
>>         at com.xpn.xwiki.store.XWikiHibernateStore.endTransaction(XWikiHibernateStore.java:350)
>>         at com.xpn.xwiki.store.XWikiHibernateStore.deleteXWikiCollection(XWikiHibernateStore.java:982)
>>         at com.xpn.xwiki.stats.impl.XWikiStatsServiceImpl.notify(XWikiStatsServiceImpl.java:209)
>>         at com.xpn.xwiki.notify.XWikiActionRule.verify(XWikiActionRule.java:51)
>>         at com.xpn.xwiki.notify.XWikiNotificationManager.verify(XWikiNotificationManager.java:93)
>>         at com.xpn.xwiki.web.ViewEditAction.execute(ViewEditAction.java:268)
>>         at org.apache.struts.action.RequestProcessor.processActionPerform(RequestProcessor.java:421)
>>         at org.apache.struts.action.RequestProcessor.process(RequestProcessor.java:226)
>>         at org.apache.struts.action.ActionServlet.process(ActionServlet.java:1164)
>>         at org.apache.struts.action.ActionServlet.doGet(ActionServlet.java:397)
>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java:689)
>>         at javax.servlet.http.HttpServlet.service(HttpServlet.java:802)
>>         at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252)
>>         at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
>>         at com.xpn.xwiki.web.SetCharacterEncodingFilter.doFilter(SetCharacterEncodingFilter.java:127)
>>         at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202)
>>         at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
>>         at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
>>         at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178)
>>         at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126)
>>         at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105)
>>         at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107)
>>         at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148)
>>         at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:868)
>>         at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:663)
>>         at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527)
>>         at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:80)
>>         at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:684)
>>         at java.lang.Thread.run(Thread.java:788)
>> 16:48:43,393  WARN http-8080-Processor25
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'view'
>> 16:48:46,067  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/WebHome
>> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
>> AAYI0BUY6AZCPDBPUVB6SYQF7W4GOXSN for name visitid with domain
>> localhost and path / and maxage -2074265
>> 16:48:46,077  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/WebHome
>> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
>> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
>> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
>> AAYI0BUY6AZCPDBPUVB6SYQF7W4GOXSN
>> 16:48:46,097 ERROR http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/WebHome
>> AssertionFailure:<init>:22 - an assertion failure occured (this may
>> indicate a bug in Hibernate, but is more likely due to unsafe use of
>> the session)
>> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
>> [*snip* removing recurring stacktrace for legibility *snip*]
>>         at java.lang.Thread.run(Thread.java:788)
>> 16:48:54,739  WARN http-8080-Processor25
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'view'
>> 16:48:59,866  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/XWiki/robintest
>> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
>> HYUPZKDBBPIRN8SOMQ4WGKURIE5GEXMX for name visitid with domain
>> localhost and path / and maxage -2074279
>> 16:48:59,956  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/XWiki/robintest
>> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
>> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
>> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
>> HYUPZKDBBPIRN8SOMQ4WGKURIE5GEXMX
>> 16:49:00,537 ERROR http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/XWiki/robintest
>> AssertionFailure:<init>:22 - an assertion failure occured (this may
>> indicate a bug in Hibernate, but is more likely due to unsafe use of
>> the session)
>> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
>> [*snip* removing recurring stacktrace for legibility *snip*]
>>         at java.lang.Thread.run(Thread.java:788)
>> 16:49:02,610  WARN http-8080-Processor25
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'view'
>> 16:49:04,533  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/Teams
>> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
>> DIXM1WAGYLHJQNYFVSCWUFKZ85ZFVKN2 for name visitid with domain
>> localhost and path / and maxage -2074284
>> 16:49:04,593  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/Teams
>> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
>> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
>> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
>> DIXM1WAGYLHJQNYFVSCWUFKZ85ZFVKN2
>> 16:49:04,673 ERROR http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/Teams
>> AssertionFailure:<init>:22 - an assertion failure occured (this may
>> indicate a bug in Hibernate, but is more likely due to unsafe use of
>> the session)
>> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
>> [*snip* removing recurring stacktrace for legibility *snip*]
>>         at java.lang.Thread.run(Thread.java:788)
>> 16:49:07,457  WARN http-8080-Processor25
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'view'
>> 16:49:08,779  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/Teams
>> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
>> XIO9V3257B1UPP6WB82BLCHOYYKDZAGQ for name visitid with domain
>> localhost and path / and maxage -2074288
>> 16:49:08,919  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/Teams
>> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
>> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
>> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
>> XIO9V3257B1UPP6WB82BLCHOYYKDZAGQ
>> 16:49:09,010 ERROR http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/Teams
>> AssertionFailure:<init>:22 - an assertion failure occured (this may
>> indicate a bug in Hibernate, but is more likely due to unsafe use of
>> the session)
>> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
>> [*snip* removing recurring stacktrace for legibility *snip*]
>>         at java.lang.Thread.run(Thread.java:788)
>> 16:49:09,080  WARN http-8080-Processor25
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'skin'
>> 16:49:09,110  WARN http-8080-Processor25
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'skin'
>> 16:49:09,130  WARN http-8080-Processor24
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'skin'
>> 16:49:09,200  WARN http-8080-Processor22
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'skin'
>> 16:49:09,220  WARN http-8080-Processor23
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'skin'
>> 16:49:09,400  WARN http-8080-Processor24
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'skin'
>> 16:49:09,430  WARN http-8080-Processor22
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'skin'
>> 16:49:09,440  WARN http-8080-Processor23
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'skin'
>> 16:49:10,101  WARN http-8080-Processor25
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'skin'
>> 16:49:12,224  WARN http-8080-Processor25
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'view'
>> 16:49:12,394  WARN Finalizer thread  JDBCContext:finalize:333 -
>> afterTransactionCompletion() was never called
>> 16:49:12,394  WARN Finalizer thread  JDBCContext:finalize:333 -
>> afterTransactionCompletion() was never called
>> 16:49:12,394  WARN Finalizer thread  JDBCContext:finalize:333 -
>> afterTransactionCompletion() was never called
>> 16:49:12,394  WARN Finalizer thread  JDBCContext:finalize:333 -
>> afterTransactionCompletion() was never called
>> 16:49:12,394  WARN Finalizer thread  JDBCContext:finalize:333 -
>> afterTransactionCompletion() was never called
>> 16:49:14,748  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/JTC/ArchitectureAndDesign
>> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
>> B6LRLQQ1YOETNSAIZLGNEYHJRGAEFK0R for name visitid with domain
>> localhost and path / and maxage -2074294
>> 16:49:14,748  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/JTC/ArchitectureAndDesign
>> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
>> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
>> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
>> B6LRLQQ1YOETNSAIZLGNEYHJRGAEFK0R
>> 16:49:14,838 ERROR http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/JTC/ArchitectureAndDesign
>> AssertionFailure:<init>:22 - an assertion failure occured (this may
>> indicate a bug in Hibernate, but is more likely due to unsafe use of
>> the session)
>> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
>> [*snip* removing recurring stacktrace for legibility *snip*]
>>         at java.lang.Thread.run(Thread.java:788)
>> --------------------------
>>
>>
>>
>>
>>
>>
>> Since my last mail I established what I had changed on my system: I
>> set the cookie domain to localhost.
>> If I remove the cookie domain from my xwiki.cfg as so:
>>         #xwiki.authentication.cookiedomains=localhost
>> then my trace point _does_ get hit on every page view:
>>
>> --------------------------
>> 17:00:18,052  WARN http-8080-Processor25
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'view'
>> 17:00:19,734  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/WebHome
>> Configurator:configure:126 - No configuration found. Configuring
>> ehcache from ehcache-failsafe.xml found in the classpath:
>> jar:file:/C:/Program%20Files/Apache%20Software%20Foundation/Tomcat%205.5/webapps/xwiki/WEB-INF/lib/ehcache-1.1.jar!/ehcache-failsafe.xml
>> 17:00:22,218 ERROR http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/WebHome
>> XWikiHibernateStore:updateSchema:177 - Schema update for wiki xwiki
>> 17:00:26,604 DEBUG http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/WebHome
>> myAuthService:authenticate:16 - myAuthService: robinf - in
>> authenticate (user: robintest)
>> 17:00:36,308  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/WebHome
>> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
>> ZLYJYGUWZXCMUR8OKGRPEKRLNJU4Y6BG for name visitid with domain null and
>> path / and maxage -2074255
>> 17:00:36,308  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/WebHome
>> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
>> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
>> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
>> ZLYJYGUWZXCMUR8OKGRPEKRLNJU4Y6BG
>> 17:00:36,758 ERROR http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/WebHome
>> AssertionFailure:<init>:22 - an assertion failure occured (this may
>> indicate a bug in Hibernate, but is more likely due to unsafe use of
>> the session)
>> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
>> [*snip* removing recurring stacktrace for legibility *snip*]
>>         at java.lang.Thread.run(Thread.java:788)
>> 17:00:42,977  WARN http-8080-Processor25
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'view'
>> 17:00:42,987 DEBUG http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/Teams
>> myAuthService:authenticate:16 - myAuthService: robinf - in
>> authenticate (user: robintest)
>> 17:00:46,432  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/Teams
>> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
>> VDJ4LC3FPKAT0XVU8ITI7SLGNPSBKN1W for name visitid with domain null and
>> path / and maxage -2074265
>> 17:00:46,442  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/Teams
>> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
>> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
>> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
>> VDJ4LC3FPKAT0XVU8ITI7SLGNPSBKN1W
>> 17:00:46,462 ERROR http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/Teams
>> AssertionFailure:<init>:22 - an assertion failure occured (this may
>> indicate a bug in Hibernate, but is more likely due to unsafe use of
>> the session)
>> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
>> [*snip* removing recurring stacktrace for legibility *snip*]
>>         at java.lang.Thread.run(Thread.java:788)
>> 17:01:04,959  WARN http-8080-Processor25
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'view'
>> 17:01:04,979 DEBUG http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
>> myAuthService:authenticate:16 - myAuthService: robinf - in
>> authenticate (user: robintest)
>> 17:01:06,361  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
>> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
>> KWWHZOWMIHI3IBN0APUGERVCDLFNXTQS for name visitid with domain null and
>> path / and maxage -2074285
>> 17:01:06,361  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
>> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
>> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
>> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
>> KWWHZOWMIHI3IBN0APUGERVCDLFNXTQS
>> 17:01:06,511 ERROR http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
>> AssertionFailure:<init>:22 - an assertion failure occured (this may
>> indicate a bug in Hibernate, but is more likely due to unsafe use of
>> the session)
>> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
>> [*snip* removing recurring stacktrace for legibility *snip*]
>>         at java.lang.Thread.run(Thread.java:788)
>> 17:01:09,095  WARN http-8080-Processor25
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'view'
>> 17:01:09,105 DEBUG http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/WebHome
>> myAuthService:authenticate:16 - myAuthService: robinf - in
>> authenticate (user: robintest)
>> 17:01:10,267  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/WebHome
>> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
>> DBT70UT0NV9ZGNVK92UP5NDPH1YTGSML for name visitid with domain null and
>> path / and maxage -2074289
>> 17:01:10,267  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/WebHome
>> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
>> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
>> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
>> DBT70UT0NV9ZGNVK92UP5NDPH1YTGSML
>> 17:01:10,357 ERROR http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/Main/WebHome
>> AssertionFailure:<init>:22 - an assertion failure occured (this may
>> indicate a bug in Hibernate, but is more likely due to unsafe use of
>> the session)
>> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
>> [*snip* removing recurring stacktrace for legibility *snip*]
>>         at java.lang.Thread.run(Thread.java:788)
>> 17:01:24,397  WARN http-8080-Processor25
>> RequestUtils:createActionForm:177 - No FormBeanConfig found under
>> 'view'
>> 17:01:24,407 DEBUG http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
>> myAuthService:authenticate:16 - myAuthService: robinf - in
>> authenticate (user: robintest)
>> 17:01:25,629  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
>> XWikiStatsServiceImpl:addCookie:474 - Setting cookie
>> PZ8I4VLKA1U18EFQRV64M7W11OMPNA2F for name visitid with domain null and
>> path / and maxage -2074304
>> 17:01:25,629  WARN http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
>> XWikiStatsServiceImpl:findVisit:314 - Found visit with cookie
>> UB3FYZV9J09JZ2UBWPRVMAQPJAQATSTS in session
>> 0C98371BFD9D704F6C4E732928ABD862 for request with cookie
>> PZ8I4VLKA1U18EFQRV64M7W11OMPNA2F
>> 17:01:25,739 ERROR http-8080-Processor25
>> http://localhost:8080/xwiki/bin/view/JTC/RolesAndResponsibilities
>> AssertionFailure:<init>:22 - an assertion failure occured (this may
>> indicate a bug in Hibernate, but is more likely due to unsafe use of
>> the session)
>> org.hibernate.AssertionFailure: possible nonthreadsafe access to session
>>         at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:68)
>>         at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
>> [*snip* removing recurring stacktrace for legibility *snip*]
>>         at java.lang.Thread.run(Thread.java:788)
>> --------------------------
>>
>> Is this behaviour expected? If so, could you explain a little bit what
>> is going on?
>>
>> Thanks again!
>> Robin.
>>
>>
>> On 10/02/06, jeremi joslin <[hidden email]> wrote:
>>    
>>> Hi,
>>> As I can see in the code, it's call before opening every page.
>>> I'm not sure, but i don't think some cookie used to prevent this.
>>>
>>> I configure my wiki like yours (Always Authenticate on Viewing
>>> enabled) and it's always called.
>>>
>>> Can you give us more informations?
>>>
>>> Jérémi
>>>
>>> On 2/10/06, Robin Fernandes <[hidden email]> wrote:
>>>      
>>>> Hi,
>>>>
>>>> Assuming "Always Authenticate on Viewing" is enabled in the wiki
>>>> preferences, should XWikiAuthServiceImpl.authenticate(user, password,
>>>> context) actually get called on every page view? Or is some cookie
>>>> used to prevent this?
>>>>
>>>> I ask because this behaviour seems to have changed on my local xwiki
>>>> installation, and I'm not what I've done to cause the change (it used
>>>> to get called on every page view, and doesn't any more).
>>>>
>>>> Thanks,
>>>> Robin.
>>>>
>>>>
>>>>
>>>>
>>>> --
>>>> You receive this message as a subscriber of the [hidden email] mailing list.
>>>> To unsubscribe: mailto:[hidden email]
>>>> For general help: mailto:[hidden email]?subject=help
>>>> ObjectWeb mailing lists service home page: http://www.objectweb.org/wws
>>>>
>>>>
>>>>
>>>>        
>>> --
>>> Blog: http://www.jeremi.info
>>> LinkedIn: https://www.linkedin.com/profile?viewProfile=&key=1437724
>>> http://www.xwiki.org
>>> skype: jeremi23 -- msn et gtalk : [hidden email]
>>>
>>>
>>>
>>>
>>> --
>>> You receive this message as a subscriber of the [hidden email] mailing list.
>>> To unsubscribe: mailto:[hidden email]
>>> For general help: mailto:[hidden email]?subject=help
>>> ObjectWeb mailing lists service home page: http://www.objectweb.org/wws
>>>
>>>
>>>
>>>      
>>
>>
>> --
>> You receive this message as a subscriber of the [hidden email] mailing list.
>> To unsubscribe: mailto:[hidden email]
>> For general help: mailto:[hidden email]?subject=help
>> ObjectWeb mailing lists service home page: http://www.objectweb.org/wws
>>
>>
>>
>>    
>
>
> --
> Blog: http://www.jeremi.info
> LinkedIn: https://www.linkedin.com/profile?viewProfile=&key=1437724
> http://www.xwiki.org
> skype: jeremi23 -- msn et gtalk : [hidden email]
>  
> ------------------------------------------------------------------------
>
>
> --
> You receive this message as a subscriber of the [hidden email] mailing list.
> To unsubscribe: mailto:[hidden email]
> For general help: mailto:[hidden email]?subject=help
> ObjectWeb mailing lists service home page: http://www.objectweb.org/wws
>  

--
Ludovic Dubost
XPertNet: http://www.xpertnet.fr/
Blog: http://www.ludovic.org/blog/
XWiki: http://www.xwiki.com
Skype: ldubost AIM: nvludo Yahoo: ludovic




--
You receive this message as a subscriber of the [hidden email] mailing list.
To unsubscribe: mailto:[hidden email]
For general help: mailto:[hidden email]?subject=help
ObjectWeb mailing lists service home page: http://www.objectweb.org/wws