Skip to content

Instantly share code, notes, and snippets.

@Daan-
Created May 9, 2012 17:38
Embed
What would you like to do?
Catalina.out from storing Balsamiq mockup in XWiki
2012-05-09 19:32:51,194 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG o.a.s.u.ModuleUtils - Get module name for path /bin
2012-05-09 19:32:51,200 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG o.a.s.u.ModuleUtils - Module name found: default
2012-05-09 19:32:51,200 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG o.a.s.a.RequestProcessor - Processing a 'POST' for path '/mockup/'
2012-05-09 19:32:51,200 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG o.a.s.a.RequestProcessor - Looking for Action instance for class com.balsamiq.xwiki.plugins.mockups.struts.MockupAction
2012-05-09 19:32:51,205 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,205 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,205 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,205 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,205 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,205 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,205 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,205 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,205 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Sandbox.TestPage2 in cache
2012-05-09 19:32:51,205 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.TestPage2 from cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:Sandbox.TestPage2 from cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Sandbox.TestPage2 in cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - checkAccess for /mockup/, Sandbox.TestPage2
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,206 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,207 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,207 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,207 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,207 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,207 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,207 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,207 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,207 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,208 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] INFO x.x.u.i.x.XWikiAuthServiceImpl - User XWiki.daan is authentified
2012-05-09 19:32:51,208 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG x.x.u.i.x.XWikiAuthServiceImpl - XWikiAuthServiceImpl.checkAuth(XWikiContext) took 2 milliseconds to run.
2012-05-09 19:32:51,208 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - hasAccessLevel for edit, xwiki:XWiki.daan, xwiki:Sandbox.TestPage2
2012-05-09 19:32:51,208 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,208 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,208 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,208 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,208 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,XWiki.XWikiPreferences,admin,true,true,true
2012-05-09 19:32:51,208 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 4
2012-05-09 19:32:51,208 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,208 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in admin,edit,undelete
2012-05-09 19:32:51,208 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found a right for true
2012-05-09 19:32:51,209 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in
2012-05-09 19:32:51,209 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in
2012-05-09 19:32:51,209 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Failed match: xwiki:XWiki.daan in
2012-05-09 19:32:51,209 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 1
2012-05-09 19:32:51,209 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view,edit,comment,undelete,register,delete
2012-05-09 19:32:51,209 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 2
2012-05-09 19:32:51,209 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view
2012-05-09 19:32:51,209 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 3
2012-05-09 19:32:51,209 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view,comment
2012-05-09 19:32:51,209 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,XWiki.XWikiPreferences,admin,false,true,true
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 4
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in admin,edit,undelete
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found a right for true
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAdminGroup,
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAdminGroup
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Failed match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAdminGroup,
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 1
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view,edit,comment,undelete,register,delete
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 2
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 3
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view,comment
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [0] groups: []
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.XWikiAllGroup: true
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.daan: true
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Sandbox.TestPage2 in cache
2012-05-09 19:32:51,210 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.TestPage2 from cache
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:Sandbox.TestPage2 from cache
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Sandbox.TestPage2 in cache
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,XWiki.XWikiPreferences,admin,true,true,true
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 4
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in admin,edit,undelete
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found a right for true
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Failed match: xwiki:XWiki.daan in
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 1
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view,edit,comment,undelete,register,delete
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 2
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 3
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view,comment
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,XWiki.XWikiPreferences,admin,false,true,true
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 4
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in admin,edit,undelete
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found a right for true
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAdminGroup,
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAdminGroup
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Failed match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAdminGroup,
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 1
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view,edit,comment,undelete,register,delete
2012-05-09 19:32:51,211 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 2
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 3
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view,comment
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [0] groups: []
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.XWikiAllGroup: true
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.daan: true
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Sandbox.WebPreferences in cache
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.WebPreferences from cache
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:Sandbox.WebPreferences from cache
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Sandbox.WebPreferences in cache
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,Sandbox.WebPreferences,admin,true,true,true
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,Sandbox.WebPreferences,admin,false,true,true
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [0] groups: []
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.XWikiAllGroup: false
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.daan: false
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,Sandbox.TestPage2,edit,true,false,false
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,Sandbox.TestPage2,edit,false,false,false
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [0] groups: []
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.XWikiAllGroup: false
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.daan: false
2012-05-09 19:32:51,212 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,Sandbox.TestPage2,edit,true,true,false
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,Sandbox.TestPage2,edit,false,true,false
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [0] groups: []
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.XWikiAllGroup: false
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.daan: false
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Sandbox.WebPreferences in cache
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.WebPreferences from cache
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:Sandbox.WebPreferences from cache
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Sandbox.WebPreferences in cache
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,Sandbox.WebPreferences,edit,true,false,true
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,Sandbox.WebPreferences,edit,false,false,true
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [0] groups: []
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.XWikiAllGroup: false
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.daan: false
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,Sandbox.WebPreferences,edit,true,true,true
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,Sandbox.WebPreferences,edit,false,true,true
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [0] groups: []
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.XWikiAllGroup: false
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.daan: false
2012-05-09 19:32:51,213 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,XWiki.XWikiPreferences,edit,true,false,true
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 4
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 0 because wrong allow/deny
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 1
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 1 because wrong allow/deny
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 2
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 2 because wrong allow/deny
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 3
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 3 because wrong allow/deny
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,XWiki.XWikiPreferences,edit,false,false,true
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 4
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 0 because wrong allow/deny
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 1
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 1 because wrong allow/deny
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 2
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 2 because wrong allow/deny
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 3
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 3 because wrong allow/deny
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [0] groups: []
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.XWikiAllGroup: false
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.daan: false
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,XWiki.XWikiPreferences,edit,true,true,true
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 4
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: edit in admin,edit,undelete
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found a right for true
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Failed match: xwiki:XWiki.daan in
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 1
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: edit in view,edit,comment,undelete,register,delete
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found a right for true
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Failed match: xwiki:XWiki.daan in
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 2
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: edit in view
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 3
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: edit in view,comment
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,214 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,XWiki.XWikiPreferences,edit,false,true,true
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 4
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: edit in admin,edit,undelete
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found a right for true
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAdminGroup,
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAdminGroup
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Failed match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAdminGroup,
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 1
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: edit in view,edit,comment,undelete,register,delete
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found a right for true
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAllGroup,
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAllGroup
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found matching right in XWiki.XWikiAllGroup, for XWiki.XWikiAllGroup
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Access has been granted for (xwiki:XWiki.daan,Sandbox.TestPage2,edit): xwiki level
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Access has been granted for (xwiki:XWiki.daan,xwiki:Sandbox.TestPage2,/mockup/): access manager granted right
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.daan in cache
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.daan from cache
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.daan from cache
2012-05-09 19:32:51,215 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.daan in cache
2012-05-09 19:32:51,216 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR /mockup/ multipart: 0ms null
2012-05-09 19:32:51,216 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR Sandbox.TestPage2 /mockup/ prenotify: 0ms null
2012-05-09 19:32:51,216 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.b.x.p.m.s.MockupAction - MockupAction - requestURI: /xwiki/bin/mockup/Sandbox/TestPage2
2012-05-09 19:32:51,216 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.b.x.p.m.s.MockupAction - MockupAction - do : saveandcontinue
2012-05-09 19:32:51,216 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.b.x.p.m.s.MockupAction - MockupAction - mockup : test10
2012-05-09 19:32:51,236 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Trying to get session from pool
2012-05-09 19:32:51,237 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taken session from pool SessionImpl(PersistenceContext[entityKeys=[],collectionKeys=[]];ActionQueue[insertions=[] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:32:51,237 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] INFO c.x.x.s.DBCPConnectionProvider - active: 1 (max: 50) idle: 1(max: 5)
2012-05-09 19:32:51,238 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Trying to open transaction
2012-05-09 19:32:51,238 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Opened transaction org.hibernate.transaction.JDBCTransaction@2fc923f2
2012-05-09 19:32:51,261 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taking session from context SessionImpl(PersistenceContext[entityKeys=[EntityKey[com.xpn.xwiki.doc.XWikiAttachment#531467555], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1173089254], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1252104178], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1230347556], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1187460050], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1840790473], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1839866952], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1201718405], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1956820240], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1841713994], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1252168286], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1838943431], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1843861855], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1258976707]],collectionKeys=[]];ActionQueue[insertions=[EntityInsertAction[com.xpn.xwiki.doc.XWikiAttachment#-1252168286]] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:32:51,261 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taking transaction from context org.hibernate.transaction.JDBCTransaction@2fc923f2
2012-05-09 19:32:51,263 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR: error recursive timers for hibernate
2012-05-09 19:32:51,263 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taking session from context SessionImpl(PersistenceContext[entityKeys=[EntityKey[com.xpn.xwiki.doc.XWikiAttachment#531467555], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1173089254], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1252104178], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1230347556], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1187460050], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1840790473], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1839866952], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1201718405], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1956820240], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1841713994], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1252168286], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1838943431], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1843861855], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1258976707]],collectionKeys=[]];ActionQueue[insertions=[EntityInsertAction[com.xpn.xwiki.doc.XWikiAttachment#-1252168286]] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:32:51,263 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taking transaction from context org.hibernate.transaction.JDBCTransaction@2fc923f2
2012-05-09 19:32:51,266 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR Sandbox.TestPage2 /mockup/ hibernate: 13ms null
2012-05-09 19:32:51,276 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR Sandbox.TestPage2 /mockup/ hibernate: 3ms null
2012-05-09 19:32:51,278 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taking session from context SessionImpl(PersistenceContext[entityKeys=[EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=18}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=7}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1252104178], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=20}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=5}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1187460050], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1840790473], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=10}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1201718405], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1841713994], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=1}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=15}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1838943431], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1252168286], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=16}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=3}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=14}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=12}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=21}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeContent#component[docId,version1,version2]{docId=-82400406, version2=1, version1=21}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#531467555], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1173089254], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=8}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=19}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=9}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1230347556], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=11}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1839866952], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1956820240], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=6}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=2}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=13}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1843861855], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1258976707], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=17}], EntityKey[com.xpn.xwiki.doc.XWikiDocument#-82400406], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=4}]],collectionKeys=[]];ActionQueue[insertions=[EntityInsertAction[com.xpn.xwiki.doc.XWikiAttachment#-1252168286], EntityInsertAction[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#com.xpn.xwiki.doc.rcs.XWikiRCSNodeId@3ccd99a5[docId=-82400406,version=21.1]]] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:32:51,278 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taking transaction from context org.hibernate.transaction.JDBCTransaction@2fc923f2
2012-05-09 19:32:51,283 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR: could not find timer for hibernate
2012-05-09 19:32:51,284 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Releasing hibernate session SessionImpl(PersistenceContext[entityKeys=[EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=18}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=7}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1252104178], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=20}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=5}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1187460050], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1840790473], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=10}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1201718405], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1841713994], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=1}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=15}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1838943431], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1252168286], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=16}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=3}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=14}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=12}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=21}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeContent#component[docId,version1,version2]{docId=-82400406, version2=1, version1=21}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#531467555], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1173089254], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=8}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=19}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=9}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1230347556], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=11}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1839866952], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1956820240], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=6}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=2}], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=13}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1843861855], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1258976707], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=17}], EntityKey[com.xpn.xwiki.doc.XWikiDocument#-82400406], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=4}]],collectionKeys=[]];ActionQueue[insertions=[EntityInsertAction[com.xpn.xwiki.doc.XWikiAttachment#-1252168286], EntityInsertAction[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#com.xpn.xwiki.doc.rcs.XWikiRCSNodeId@3ccd99a5[docId=-82400406,version=21.1]]] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:32:51,284 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Releasing hibernate transaction org.hibernate.transaction.JDBCTransaction@2fc923f2
2012-05-09 19:32:51,441 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] INFO c.x.x.s.DBCPConnectionProvider - active: 0 (max: 50) idle: 2(max: 5)
2012-05-09 19:32:51,442 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Trying to get session from pool
2012-05-09 19:32:51,442 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taken session from pool SessionImpl(PersistenceContext[entityKeys=[],collectionKeys=[]];ActionQueue[insertions=[] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:32:51,442 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] INFO c.x.x.s.DBCPConnectionProvider - active: 1 (max: 50) idle: 1(max: 5)
2012-05-09 19:32:51,444 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Trying to open transaction
2012-05-09 19:32:51,444 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Opened transaction org.hibernate.transaction.JDBCTransaction@1cf93cb
2012-05-09 19:32:51,455 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taking session from context SessionImpl(PersistenceContext[entityKeys=[EntityKey[com.xpn.xwiki.doc.XWikiAttachment#531467555], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1173089254], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1252104178], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1230347556], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1187460050], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1840790473], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1839866952], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1201718405], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1956820240], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1841713994], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1287305039], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1252168286], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1838943431], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1843861855], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1258976707]],collectionKeys=[]];ActionQueue[insertions=[EntityInsertAction[com.xpn.xwiki.doc.XWikiAttachment#-1287305039]] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:32:51,455 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taking transaction from context org.hibernate.transaction.JDBCTransaction@1cf93cb
2012-05-09 19:32:51,460 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR: error recursive timers for hibernate
2012-05-09 19:32:51,461 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR Sandbox.TestPage2 /mockup/ hibernate: 16ms null
2012-05-09 19:32:51,462 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taking session from context SessionImpl(PersistenceContext[entityKeys=[EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeContent#component[docId,version1,version2]{docId=-82400406, version2=1, version1=21}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#531467555], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1173089254], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1252104178], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1230347556], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1187460050], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1840790473], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1839866952], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1201718405], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1956820240], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1841713994], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1287305039], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1252168286], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1838943431], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1843861855], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1258976707], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeContent#component[docId,version1,version2]{docId=-82400406, version2=1, version1=22}], EntityKey[com.xpn.xwiki.doc.XWikiDocument#-82400406], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=22}]],collectionKeys=[]];ActionQueue[insertions=[EntityInsertAction[com.xpn.xwiki.doc.XWikiAttachment#-1287305039], EntityInsertAction[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#com.xpn.xwiki.doc.rcs.XWikiRCSNodeId@6ad248c6[docId=-82400406,version=22.1]]] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:32:51,462 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taking transaction from context org.hibernate.transaction.JDBCTransaction@1cf93cb
2012-05-09 19:32:51,462 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR: could not find timer for hibernate
2012-05-09 19:32:51,463 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Releasing hibernate session SessionImpl(PersistenceContext[entityKeys=[EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeContent#component[docId,version1,version2]{docId=-82400406, version2=1, version1=21}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#531467555], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1173089254], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1252104178], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1230347556], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1187460050], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1840790473], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1839866952], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1201718405], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1956820240], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1841713994], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1287305039], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1252168286], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1838943431], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1843861855], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1258976707], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeContent#component[docId,version1,version2]{docId=-82400406, version2=1, version1=22}], EntityKey[com.xpn.xwiki.doc.XWikiDocument#-82400406], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=22}]],collectionKeys=[]];ActionQueue[insertions=[EntityInsertAction[com.xpn.xwiki.doc.XWikiAttachment#-1287305039], EntityInsertAction[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#com.xpn.xwiki.doc.rcs.XWikiRCSNodeId@6ad248c6[docId=-82400406,version=22.1]]] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:32:51,463 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Releasing hibernate transaction org.hibernate.transaction.JDBCTransaction@1cf93cb
2012-05-09 19:32:51,538 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] INFO c.x.x.s.DBCPConnectionProvider - active: 0 (max: 50) idle: 2(max: 5)
2012-05-09 19:32:51,539 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Trying to get session from pool
2012-05-09 19:32:51,539 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taken session from pool SessionImpl(PersistenceContext[entityKeys=[],collectionKeys=[]];ActionQueue[insertions=[] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:32:51,539 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] INFO c.x.x.s.DBCPConnectionProvider - active: 1 (max: 50) idle: 1(max: 5)
2012-05-09 19:32:51,541 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Trying to open transaction
2012-05-09 19:32:51,541 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Opened transaction org.hibernate.transaction.JDBCTransaction@4317343f
2012-05-09 19:32:51,558 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR: error recursive timers for hibernate
2012-05-09 19:32:51,559 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR Sandbox.TestPage2 /mockup/ hibernate: 20ms null
2012-05-09 19:32:51,560 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taking session from context SessionImpl(PersistenceContext[entityKeys=[EntityKey[com.xpn.xwiki.doc.XWikiAttachment#531467555], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1173089254], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1252104178], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1230347556], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1187460050], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1840790473], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1839866952], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=23}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1201718405], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1956820240], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1841713994], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1287305039], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1252168286], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1838943431], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1843861855], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1258976707], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeContent#component[docId,version1,version2]{docId=-82400406, version2=1, version1=22}], EntityKey[com.xpn.xwiki.doc.XWikiDocument#-82400406], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeContent#component[docId,version1,version2]{docId=-82400406, version2=1, version1=23}]],collectionKeys=[]];ActionQueue[insertions=[EntityInsertAction[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#com.xpn.xwiki.doc.rcs.XWikiRCSNodeId@18f1231d[docId=-82400406,version=23.1]]] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:32:51,560 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taking transaction from context org.hibernate.transaction.JDBCTransaction@4317343f
2012-05-09 19:32:51,563 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Releasing hibernate session SessionImpl(PersistenceContext[entityKeys=[EntityKey[com.xpn.xwiki.doc.XWikiAttachment#531467555], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1173089254], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1252104178], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1230347556], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1187460050], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1840790473], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1839866952], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#component[docId,version1,version2]{docId=-82400406, version2=1, version1=23}], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1201718405], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1956820240], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1841713994], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1287305039], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1252168286], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1838943431], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1843861855], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1258976707], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeContent#component[docId,version1,version2]{docId=-82400406, version2=1, version1=22}], EntityKey[com.xpn.xwiki.doc.XWikiDocument#-82400406], EntityKey[com.xpn.xwiki.doc.rcs.XWikiRCSNodeContent#component[docId,version1,version2]{docId=-82400406, version2=1, version1=23}]],collectionKeys=[]];ActionQueue[insertions=[EntityInsertAction[com.xpn.xwiki.doc.rcs.XWikiRCSNodeInfo#com.xpn.xwiki.doc.rcs.XWikiRCSNodeId@18f1231d[docId=-82400406,version=23.1]]] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:32:51,563 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Releasing hibernate transaction org.hibernate.transaction.JDBCTransaction@4317343f
2012-05-09 19:32:51,622 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] INFO c.x.x.s.DBCPConnectionProvider - active: 0 (max: 50) idle: 2(max: 5)
2012-05-09 19:32:51,622 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR: could not find timer for hibernate
2012-05-09 19:32:51,624 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.daan in cache
2012-05-09 19:32:51,624 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.daan from cache
2012-05-09 19:32:51,624 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.daan from cache
2012-05-09 19:32:51,624 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.daan in cache
2012-05-09 19:32:51,625 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.daan in cache
2012-05-09 19:32:51,625 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.daan from cache
2012-05-09 19:32:51,625 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.daan from cache
2012-05-09 19:32:51,625 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.daan in cache
2012-05-09 19:32:51,625 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Sandbox.TestPage2 in cache
2012-05-09 19:32:51,625 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.TestPage2 from cache
2012-05-09 19:32:51,625 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.TestPage2 from persistent storage
2012-05-09 19:32:51,625 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Trying to get session from pool
2012-05-09 19:32:51,625 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taken session from pool SessionImpl(PersistenceContext[entityKeys=[],collectionKeys=[]];ActionQueue[insertions=[] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:32:51,625 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] INFO c.x.x.s.DBCPConnectionProvider - active: 1 (max: 50) idle: 1(max: 5)
2012-05-09 19:32:51,626 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Trying to open transaction
2012-05-09 19:32:51,626 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Opened transaction org.hibernate.transaction.JDBCTransaction@6752573e
2012-05-09 19:32:51,628 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Releasing hibernate session SessionImpl(PersistenceContext[entityKeys=[EntityKey[com.xpn.xwiki.doc.XWikiAttachment#531467555], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1173089254], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1252104178], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1187460050], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1230347556], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1840790473], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1839866952], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#1956820240], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1201718405], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1841713994], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1287305039], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1838943431], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1252168286], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1843861855], EntityKey[com.xpn.xwiki.doc.XWikiAttachment#-1258976707], EntityKey[com.xpn.xwiki.doc.XWikiDocument#-82400406]],collectionKeys=[]];ActionQueue[insertions=[] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:32:51,629 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Releasing hibernate transaction org.hibernate.transaction.JDBCTransaction@6752573e
2012-05-09 19:32:51,629 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] INFO c.x.x.s.DBCPConnectionProvider - active: 0 (max: 50) idle: 2(max: 5)
2012-05-09 19:32:51,629 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR Sandbox.TestPage2 /mockup/ hibernate: 4ms null
2012-05-09 19:32:51,629 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiHibernateStore - Loaded XWikiDocument: name = [TestPage2], type = [DOCUMENT], parent = [name = [Sandbox], type = [SPACE], parent = [name = [xwiki], type = [WIKI], parent = [null]]]
2012-05-09 19:32:51,629 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Got doc xwiki:Sandbox.TestPage2 from storage
2012-05-09 19:32:51,629 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: put doc xwiki:Sandbox.TestPage2 in cache
2012-05-09 19:32:51,629 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Sandbox.TestPage2 in cache
2012-05-09 19:32:51,630 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Sandbox.TestPage2 in cache
2012-05-09 19:32:51,630 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.TestPage2 from cache
2012-05-09 19:32:51,630 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:Sandbox.TestPage2 from cache
2012-05-09 19:32:51,630 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Sandbox.TestPage2 in cache
2012-05-09 19:32:51,633 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:AnnotationCode.AnnotationConfig in cache
2012-05-09 19:32:51,633 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:AnnotationCode.AnnotationConfig from cache
2012-05-09 19:32:51,633 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:AnnotationCode.AnnotationConfig from cache
2012-05-09 19:32:51,633 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:AnnotationCode.AnnotationConfig in cache
2012-05-09 19:32:51,633 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Sandbox.WebPreferences in cache
2012-05-09 19:32:51,633 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.WebPreferences from cache
2012-05-09 19:32:51,633 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:Sandbox.WebPreferences from cache
2012-05-09 19:32:51,633 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Sandbox.WebPreferences in cache
2012-05-09 19:32:51,633 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,633 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,633 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,633 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,635 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Mockups.MacrosV2 in cache
2012-05-09 19:32:51,635 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Mockups.MacrosV2 from cache
2012-05-09 19:32:51,635 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:Mockups.MacrosV2 from cache
2012-05-09 19:32:51,635 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Mockups.MacrosV2 in cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Sandbox.TestPage2 in cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.TestPage2 from cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:Sandbox.TestPage2 from cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Sandbox.TestPage2 in cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.daan in cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.daan from cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.daan from cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.daan in cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Sandbox.WebPreferences in cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.WebPreferences from cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:Sandbox.WebPreferences from cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Sandbox.WebPreferences in cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.XWiki - Configured skin in user preferences: [XWiki.DefaultSkin]
2012-05-09 19:32:51,636 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - hasAccessLevel for view, XWiki.daan, XWiki.DefaultSkin
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,XWiki.XWikiPreferences,admin,true,true,true
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 4
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in admin,edit,undelete
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found a right for true
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Failed match: xwiki:XWiki.daan in
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 1
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view,edit,comment,undelete,register,delete
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 2
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 3
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view,comment
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,XWiki.XWikiPreferences,admin,false,true,true
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 4
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in admin,edit,undelete
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found a right for true
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAdminGroup,
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAdminGroup
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Failed match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAdminGroup,
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 1
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view,edit,comment,undelete,register,delete
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 2
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 3
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view,comment
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [0] groups: []
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.XWikiAllGroup: true
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.daan: true
2012-05-09 19:32:51,637 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.DefaultSkin in cache
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.DefaultSkin from cache
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.DefaultSkin from cache
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.DefaultSkin in cache
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,XWiki.XWikiPreferences,admin,true,true,true
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 4
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in admin,edit,undelete
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found a right for true
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Failed match: xwiki:XWiki.daan in
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 1
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view,edit,comment,undelete,register,delete
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 2
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view
2012-05-09 19:32:51,638 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 3
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view,comment
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,XWiki.XWikiPreferences,admin,false,true,true
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 4
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in admin,edit,undelete
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found a right for true
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAdminGroup,
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAdminGroup
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Failed match: xwiki:XWiki.XWikiAllGroup in XWiki.XWikiAdminGroup,
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 1
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view,edit,comment,undelete,register,delete
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 2
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 3
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in view,comment
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [0] groups: []
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.XWikiAllGroup: true
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.daan: true
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.WebPreferences in cache
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.WebPreferences from cache
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.WebPreferences from cache
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.WebPreferences in cache
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,XWiki.WebPreferences,admin,true,true,true
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 1
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in edit,
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,XWiki.WebPreferences,admin,false,true,true
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 1
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: admin in edit,
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [0] groups: []
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.XWikiAllGroup: false
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.daan: false
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,XWiki.DefaultSkin,view,true,false,false
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,XWiki.DefaultSkin,view,false,false,false
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [0] groups: []
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.XWikiAllGroup: false
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.daan: false
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,XWiki.DefaultSkin,view,true,true,false
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,XWiki.DefaultSkin,view,false,true,false
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [0] groups: []
2012-05-09 19:32:51,639 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.XWikiAllGroup: false
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.daan: false
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.WebPreferences in cache
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.WebPreferences from cache
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.WebPreferences from cache
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.WebPreferences in cache
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,XWiki.WebPreferences,view,true,false,true
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 1
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 0 because wrong allow/deny
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,XWiki.WebPreferences,view,false,false,true
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 1
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 0 because wrong allow/deny
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [0] groups: []
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.XWikiAllGroup: false
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.daan: false
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,XWiki.WebPreferences,view,true,true,true
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 1
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: view in edit,
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,XWiki.WebPreferences,view,false,true,true
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 1
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: view in edit,
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [0] groups: []
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.XWikiAllGroup: false
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.daan: false
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,XWiki.XWikiPreferences,view,true,false,true
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 4
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 0 because wrong allow/deny
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 1
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 1 because wrong allow/deny
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 2
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 2 because wrong allow/deny
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 3
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 3 because wrong allow/deny
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [1] groups: [xwiki:XWiki.XWikiAllGroup]
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.XWikiAllGroup,XWiki.XWikiPreferences,view,false,false,true
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 4
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 0 because wrong allow/deny
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 1
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 1 because wrong allow/deny
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 2
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 2 because wrong allow/deny
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 3
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Bypass object 3 because wrong allow/deny
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights at group level
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Searching for matching rights for [0] groups: []
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.XWikiAllGroup: false
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Finished searching for rights for xwiki:XWiki.daan: false
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking right: xwiki:XWiki.daan,XWiki.XWikiPreferences,view,true,true,true
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking objects 4
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 0
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: view in admin,edit,undelete
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 1
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: view in view,edit,comment,undelete,register,delete
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found a right for true
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Failed match: xwiki:XWiki.daan in
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 2
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: view in view
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found a right for true
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in XWiki.XWikiGuest
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in XWiki.XWikiGuest
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Failed match: xwiki:XWiki.daan in XWiki.XWikiGuest
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking object 3
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: view in view,comment
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found a right for true
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in XWiki.daan
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Checking match: xwiki:XWiki.daan in XWiki.daan
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Found matching right in XWiki.daan for XWiki.daan
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.u.i.x.XWikiRightServiceImpl - Access has been granted for (xwiki:XWiki.daan,XWiki.DefaultSkin,view): xwiki level
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.DefaultSkin in cache
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.DefaultSkin from cache
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.DefaultSkin from cache
2012-05-09 19:32:51,640 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.DefaultSkin in cache
2012-05-09 19:32:51,641 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.DefaultSkin in cache
2012-05-09 19:32:51,641 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.DefaultSkin from cache
2012-05-09 19:32:51,641 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.DefaultSkin from cache
2012-05-09 19:32:51,641 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.DefaultSkin in cache
2012-05-09 19:32:51,641 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Sandbox.colibri in cache
2012-05-09 19:32:51,641 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.colibri from cache
2012-05-09 19:32:51,641 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:Sandbox.colibri from cache
2012-05-09 19:32:51,641 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Sandbox.colibri in cache
2012-05-09 19:32:51,648 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,648 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,648 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,648 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,648 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,648 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,648 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,648 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,648 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,648 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,648 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,648 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,648 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,648 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,648 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,648 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,649 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Trying to get session from pool
2012-05-09 19:32:51,649 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Taken session from pool SessionImpl(PersistenceContext[entityKeys=[],collectionKeys=[]];ActionQueue[insertions=[] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:32:51,650 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] INFO c.x.x.s.DBCPConnectionProvider - active: 1 (max: 50) idle: 1(max: 5)
2012-05-09 19:32:51,651 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Trying to open transaction
2012-05-09 19:32:51,651 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Opened transaction org.hibernate.transaction.JDBCTransaction@17b4239f
2012-05-09 19:32:51,651 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Releasing hibernate session SessionImpl(PersistenceContext[entityKeys=[EntityKey[com.xpn.xwiki.plugin.activitystream.impl.ActivityEventImpl#1015293257-1336584771000-nib4cP6X]],collectionKeys=[]];ActionQueue[insertions=[EntityInsertAction[com.xpn.xwiki.plugin.activitystream.impl.ActivityEventImpl#1015293257-1336584771000-nib4cP6X]] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:32:51,652 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG .x.x.s.XWikiHibernateBaseStore - Releasing hibernate transaction org.hibernate.transaction.JDBCTransaction@17b4239f
2012-05-09 19:32:51,718 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] INFO c.x.x.s.DBCPConnectionProvider - active: 0 (max: 50) idle: 2(max: 5)
2012-05-09 19:32:51,719 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Sandbox.WebPreferences in cache
2012-05-09 19:32:51,719 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.WebPreferences from cache
2012-05-09 19:32:51,719 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:Sandbox.WebPreferences from cache
2012-05-09 19:32:51,719 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Sandbox.WebPreferences in cache
2012-05-09 19:32:51,719 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,719 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,720 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.XWikiPreferences from cache
2012-05-09 19:32:51,720 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.XWikiPreferences in cache
2012-05-09 19:32:51,720 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Mockups.MacrosV2 in cache
2012-05-09 19:32:51,720 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Mockups.MacrosV2 from cache
2012-05-09 19:32:51,720 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:Mockups.MacrosV2 from cache
2012-05-09 19:32:51,720 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Mockups.MacrosV2 in cache
2012-05-09 19:32:51,721 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Sandbox.TestPage2 in cache
2012-05-09 19:32:51,721 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.TestPage2 from cache
2012-05-09 19:32:51,721 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:Sandbox.TestPage2 from cache
2012-05-09 19:32:51,721 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Sandbox.TestPage2 in cache
2012-05-09 19:32:51,721 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.DefaultSkin in cache
2012-05-09 19:32:51,721 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.DefaultSkin from cache
2012-05-09 19:32:51,721 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.DefaultSkin from cache
2012-05-09 19:32:51,721 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.DefaultSkin in cache
2012-05-09 19:32:51,721 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:XWiki.DefaultSkin in cache
2012-05-09 19:32:51,721 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:XWiki.DefaultSkin from cache
2012-05-09 19:32:51,721 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:XWiki.DefaultSkin from cache
2012-05-09 19:32:51,721 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:XWiki.DefaultSkin in cache
2012-05-09 19:32:51,721 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Sandbox.colibri in cache
2012-05-09 19:32:51,721 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.colibri from cache
2012-05-09 19:32:51,721 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:Sandbox.colibri from cache
2012-05-09 19:32:51,721 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Sandbox.colibri in cache
2012-05-09 19:32:51,722 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR: could not find timer for request
2012-05-09 19:32:51,722 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR Sandbox.TestPage2 /mockup/ notify: 0ms null
2012-05-09 19:32:51,722 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR Sandbox.TestPage2: 506ms
2012-05-09 19:32:51,722 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR Sandbox.TestPage2 /mockup/ prenotify: 0ms 1
2012-05-09 19:32:51,722 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR Sandbox.TestPage2 /mockup/ hibernate: 56ms 5
2012-05-09 19:32:51,722 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR Sandbox.TestPage2 /mockup/ multipart: 0ms 1
2012-05-09 19:32:51,722 [http://localhost:8080/xwiki/bin/mockup/Sandbox/TestPage2] DEBUG c.x.x.m.a.MonitorData - MONITOR Sandbox.TestPage2 /mockup/ notify: 0ms 1
2012-05-09 19:33:03,880 [Lucene Index Updater] DEBUG c.x.x.p.l.IndexUpdater - IndexUpdater: documents in queue, start indexing
2012-05-09 19:33:03,885 [Lucene Index Updater] DEBUG c.x.x.p.l.IndexUpdater - addToIndex: [xwiki:Sandbox.TestPage2.default]
2012-05-09 19:33:03,885 [Lucene Index Updater] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Sandbox.TestPage2 in cache
2012-05-09 19:33:03,885 [Lucene Index Updater] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.TestPage2 from cache
2012-05-09 19:33:03,885 [Lucene Index Updater] DEBUG c.x.x.s.XWikiCacheStore - Cache: got doc xwiki:Sandbox.TestPage2 from cache
2012-05-09 19:33:03,885 [Lucene Index Updater] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Sandbox.TestPage2 in cache
2012-05-09 19:33:03,885 [Lucene Index Updater] DEBUG c.x.x.s.XWikiCacheStore - Cache: begin for doc xwiki:Sandbox.TestPage2:default in cache
2012-05-09 19:33:03,885 [Lucene Index Updater] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.TestPage2:default from cache
2012-05-09 19:33:03,885 [Lucene Index Updater] DEBUG c.x.x.s.XWikiCacheStore - Cache: Trying to get doc xwiki:Sandbox.TestPage2:default from persistent storage
2012-05-09 19:33:03,885 [Lucene Index Updater] DEBUG .x.x.s.XWikiHibernateBaseStore - Trying to get session from pool
2012-05-09 19:33:03,886 [Lucene Index Updater] DEBUG .x.x.s.XWikiHibernateBaseStore - Taken session from pool SessionImpl(PersistenceContext[entityKeys=[],collectionKeys=[]];ActionQueue[insertions=[] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:33:03,886 [Lucene Index Updater] INFO c.x.x.s.DBCPConnectionProvider - active: 1 (max: 50) idle: 1(max: 5)
2012-05-09 19:33:03,887 [Lucene Index Updater] DEBUG .x.x.s.XWikiHibernateBaseStore - Trying to open transaction
2012-05-09 19:33:03,887 [Lucene Index Updater] DEBUG .x.x.s.XWikiHibernateBaseStore - Opened transaction org.hibernate.transaction.JDBCTransaction@66f746cc
2012-05-09 19:33:03,888 [Lucene Index Updater] DEBUG .x.x.s.XWikiHibernateBaseStore - Releasing hibernate session SessionImpl(PersistenceContext[entityKeys=[],collectionKeys=[]];ActionQueue[insertions=[] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2012-05-09 19:33:03,889 [Lucene Index Updater] DEBUG .x.x.s.XWikiHibernateBaseStore - Releasing hibernate transaction org.hibernate.transaction.JDBCTransaction@66f746cc
2012-05-09 19:33:03,889 [Lucene Index Updater] INFO c.x.x.s.DBCPConnectionProvider - active: 0 (max: 50) idle: 2(max: 5)
2012-05-09 19:33:03,889 [Lucene Index Updater] DEBUG c.x.x.s.XWikiCacheStore - Cache: Got doc xwiki:Sandbox.TestPage2:default from storage
2012-05-09 19:33:03,890 [Lucene Index Updater] DEBUG c.x.x.s.XWikiCacheStore - Cache: put doc xwiki:Sandbox.TestPage2:default in cache
2012-05-09 19:33:03,890 [Lucene Index Updater] DEBUG c.x.x.s.XWikiCacheStore - Cache: end for doc xwiki:Sandbox.TestPage2:default in cache
2012-05-09 19:33:03,895 [Lucene Index Updater] INFO c.x.x.p.l.IndexUpdater - indexed [1] docs to lucene index
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment