Καλησπέρα σε όλους, Αντιμετωπίζω το εξής πρόβλημα, Όταν προσπαθώ να δημιουργήσω μια νέα εγγραφή σαν απλός χρήστης, το σύστημα μου βγάζει σφάλμα "Παρουσιάστηκε σφάλμα κατά την εισαγωγή πρωτοκόλλου". Το περίεργο είναι ότι κρατάει τον Α/Α και την επόμενη φορά που θα προσπαθήσω να καταχωρήσω έγγραφο το αυξάνει, παρά την αποτυχία εισαγωγής. Σαν διαχειριστής δεν το αντιμετωπίζω το συγκεκριμένο πρόβλημα. Ευχαριστώ εκ των προτέρων. ______________________________________________ Giorgos Kourvoulis Information Technology Center (KYTP) Aristotle University of Thessaloniki 1st fl. Biology bldg, 54124, Thessaloniki, Hellas Tel. +302310998910, Fax +302310998302
2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 2 of 15 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.context.HttpSessionSecurityContextRepository] Obtained a valid SecurityContext from SPRING_SECURITY_CONTEXT: 'org.springframework.security.core.context.SecurityContextImpl@1071009b: Authentication: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 3 of 15 in additional filter chain; firing Filter: 'LogoutFilter' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 4 of 15 in additional filter chain; firing Filter: 'ZkError403Filter' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 5 of 15 in additional filter chain; firing Filter: 'ZkDisableSessionInvalidateFilter' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 6 of 15 in additional filter chain; firing Filter: 'UsernamePasswordAuthenticationFilter' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 7 of 15 in additional filter chain; firing Filter: 'BasicAuthenticationFilter' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 8 of 15 in additional filter chain; firing Filter: 'RequestCacheAwareFilter' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 9 of 15 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 10 of 15 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.authentication.AnonymousAuthenticationFilter] SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 11 of 15 in additional filter chain; firing Filter: 'SessionManagementFilter' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 12 of 15 in additional filter chain; firing Filter: 'ExceptionTranslationFilter' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 13 of 15 in additional filter chain; firing Filter: 'ZkEnableSessionInvalidateFilter' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 14 of 15 in additional filter chain; firing Filter: 'FilterSecurityInterceptor' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Converted URL to lowercase, from: '/zkau'; to: '/zkau' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /login.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /index.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /incoming.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /incomingprint.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /outgoing.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /outgoingprint.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contacts.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contact.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contact_win.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /documenttypes.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /documenttype.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /links.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /about.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /upload.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companies.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /company.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companytypes.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companytype.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /distributionmethods.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /distributionmethod.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /parameters.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /parameter.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /users.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /user.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /protocolbook.zul; matched=false 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /**; matched=true 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Secure object: FilterInvocation: URL: /zkau; Attributes: [IS_AUTHENTICATED_ANONYMOUSLY] 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Previously Authenticated: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.RoleVoter@4bf329a4, returned: 0 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.AuthenticatedVoter@4932f4f0, returned: 1 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Authorization successful 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] RunAsManager did not change Authentication object 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 15 of 15 in additional filter chain; firing Filter: 'ZkLoginOKFilter' 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau reached end of additional filter chain; proceeding with original chain 2011-11-03 15:57:29,534 INFO [gr.scriptum.eprotocol.util.JTAOpenSessionInViewListener] Started transaction: org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:29,534 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:29,534 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:29,534 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:29,534 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:29,534 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:29,534 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:29,534 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:29,534 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:29,534 INFO [gr.scriptum.eprotocol.util.JTAOpenSessionInViewListener] Committed transaction: org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.access.ExceptionTranslationFilter] Chain processed normally 2011-11-03 15:57:29,534 DEBUG [org.springframework.security.web.context.SecurityContextPersistenceFilter] SecurityContextHolder now cleared, as request processing completed 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] Converted URL to lowercase, from: '/zkau'; to: '/zkau' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] Candidate is: '/zkau'; pattern is /**; matched=true 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 1 of 15 in additional filter chain; firing Filter: 'ZkDesktopReuseFilter' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 2 of 15 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.context.HttpSessionSecurityContextRepository] Obtained a valid SecurityContext from SPRING_SECURITY_CONTEXT: 'org.springframework.security.core.context.SecurityContextImpl@1071009b: Authentication: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 3 of 15 in additional filter chain; firing Filter: 'LogoutFilter' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 4 of 15 in additional filter chain; firing Filter: 'ZkError403Filter' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 5 of 15 in additional filter chain; firing Filter: 'ZkDisableSessionInvalidateFilter' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 6 of 15 in additional filter chain; firing Filter: 'UsernamePasswordAuthenticationFilter' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 7 of 15 in additional filter chain; firing Filter: 'BasicAuthenticationFilter' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 8 of 15 in additional filter chain; firing Filter: 'RequestCacheAwareFilter' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 9 of 15 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 10 of 15 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.authentication.AnonymousAuthenticationFilter] SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 11 of 15 in additional filter chain; firing Filter: 'SessionManagementFilter' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 12 of 15 in additional filter chain; firing Filter: 'ExceptionTranslationFilter' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 13 of 15 in additional filter chain; firing Filter: 'ZkEnableSessionInvalidateFilter' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 14 of 15 in additional filter chain; firing Filter: 'FilterSecurityInterceptor' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Converted URL to lowercase, from: '/zkau'; to: '/zkau' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /login.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /index.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /incoming.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /incomingprint.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /outgoing.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /outgoingprint.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contacts.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contact.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contact_win.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /documenttypes.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /documenttype.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /links.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /about.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /upload.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companies.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /company.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companytypes.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companytype.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /distributionmethods.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /distributionmethod.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /parameters.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /parameter.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /users.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /user.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /protocolbook.zul; matched=false 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /**; matched=true 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Secure object: FilterInvocation: URL: /zkau; Attributes: [IS_AUTHENTICATED_ANONYMOUSLY] 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Previously Authenticated: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.RoleVoter@4bf329a4, returned: 0 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.AuthenticatedVoter@4932f4f0, returned: 1 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Authorization successful 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] RunAsManager did not change Authentication object 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 15 of 15 in additional filter chain; firing Filter: 'ZkLoginOKFilter' 2011-11-03 15:57:29,769 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau reached end of additional filter chain; proceeding with original chain 2011-11-03 15:57:29,769 INFO [gr.scriptum.eprotocol.util.JTAOpenSessionInViewListener] Started transaction: org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:29,769 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:29,769 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:29,769 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:29,769 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:29,784 INFO [STDOUT] Hibernate: select this_.id as id8_0_, this_.name as name8_0_, this_.description as descript3_8_0_, this_.is_periodic as is4_8_0_, this_.days as days8_0_, this_.create_user as create6_8_0_, this_.update_user as update7_8_0_, this_.create_dt as create8_8_0_, this_.update_ts as update9_8_0_ from ellak.document_type this_ 2011-11-03 15:57:29,784 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:29,784 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:29,784 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:29,784 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:29,784 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:29,784 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:29,784 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:29,784 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:29,784 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:29,784 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:29,800 INFO [gr.scriptum.eprotocol.util.JTAOpenSessionInViewListener] Committed transaction: org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:29,800 DEBUG [org.springframework.security.web.access.ExceptionTranslationFilter] Chain processed normally 2011-11-03 15:57:29,800 DEBUG [org.springframework.security.web.context.SecurityContextPersistenceFilter] SecurityContextHolder now cleared, as request processing completed 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] Converted URL to lowercase, from: '/zkau/upload'; to: '/zkau/upload' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] Candidate is: '/zkau/upload'; pattern is /**; matched=true 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800 at position 1 of 15 in additional filter chain; firing Filter: 'ZkDesktopReuseFilter' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800 at position 2 of 15 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.context.HttpSessionSecurityContextRepository] Obtained a valid SecurityContext from SPRING_SECURITY_CONTEXT: 'org.springframework.security.core.context.SecurityContextImpl@1071009b: Authentication: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800 at position 3 of 15 in additional filter chain; firing Filter: 'LogoutFilter' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800 at position 4 of 15 in additional filter chain; firing Filter: 'ZkError403Filter' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800 at position 5 of 15 in additional filter chain; firing Filter: 'ZkDisableSessionInvalidateFilter' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800 at position 6 of 15 in additional filter chain; firing Filter: 'UsernamePasswordAuthenticationFilter' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800 at position 7 of 15 in additional filter chain; firing Filter: 'BasicAuthenticationFilter' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800 at position 8 of 15 in additional filter chain; firing Filter: 'RequestCacheAwareFilter' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800 at position 9 of 15 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800 at position 10 of 15 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.authentication.AnonymousAuthenticationFilter] SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800 at position 11 of 15 in additional filter chain; firing Filter: 'SessionManagementFilter' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800 at position 12 of 15 in additional filter chain; firing Filter: 'ExceptionTranslationFilter' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800 at position 13 of 15 in additional filter chain; firing Filter: 'ZkEnableSessionInvalidateFilter' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800 at position 14 of 15 in additional filter chain; firing Filter: 'FilterSecurityInterceptor' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Converted URL to lowercase, from: '/zkau/upload'; to: '/zkau/upload' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /login.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /index.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /incoming.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /incomingprint.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /outgoing.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /outgoingprint.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /contacts.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /contact.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /contact_win.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /documenttypes.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /documenttype.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /links.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /about.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /upload.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /companies.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /company.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /companytypes.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /companytype.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /distributionmethods.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /distributionmethod.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /parameters.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /parameter.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /users.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /user.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /protocolbook.zul; matched=false 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /**; matched=true 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Secure object: FilterInvocation: URL: /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800; Attributes: [IS_AUTHENTICATED_ANONYMOUSLY] 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Previously Authenticated: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.RoleVoter@4bf329a4, returned: 0 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.AuthenticatedVoter@4932f4f0, returned: 1 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Authorization successful 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] RunAsManager did not change Authentication object 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800 at position 15 of 15 in additional filter chain; firing Filter: 'ZkLoginOKFilter' 2011-11-03 15:57:33,269 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload?uuid=xOEQy2&dtid=z_sfo&sid=0&maxsize=204800 reached end of additional filter chain; proceeding with original chain 2011-11-03 15:57:33,347 DEBUG [org.springframework.security.web.access.ExceptionTranslationFilter] Chain processed normally 2011-11-03 15:57:33,347 DEBUG [org.springframework.security.web.context.SecurityContextPersistenceFilter] SecurityContextHolder now cleared, as request processing completed 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] Converted URL to lowercase, from: '/zkau'; to: '/zkau' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] Candidate is: '/zkau'; pattern is /**; matched=true 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 1 of 15 in additional filter chain; firing Filter: 'ZkDesktopReuseFilter' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 2 of 15 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.context.HttpSessionSecurityContextRepository] Obtained a valid SecurityContext from SPRING_SECURITY_CONTEXT: 'org.springframework.security.core.context.SecurityContextImpl@1071009b: Authentication: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 3 of 15 in additional filter chain; firing Filter: 'LogoutFilter' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 4 of 15 in additional filter chain; firing Filter: 'ZkError403Filter' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 5 of 15 in additional filter chain; firing Filter: 'ZkDisableSessionInvalidateFilter' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 6 of 15 in additional filter chain; firing Filter: 'UsernamePasswordAuthenticationFilter' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 7 of 15 in additional filter chain; firing Filter: 'BasicAuthenticationFilter' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 8 of 15 in additional filter chain; firing Filter: 'RequestCacheAwareFilter' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 9 of 15 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 10 of 15 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.authentication.AnonymousAuthenticationFilter] SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 11 of 15 in additional filter chain; firing Filter: 'SessionManagementFilter' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 12 of 15 in additional filter chain; firing Filter: 'ExceptionTranslationFilter' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 13 of 15 in additional filter chain; firing Filter: 'ZkEnableSessionInvalidateFilter' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 14 of 15 in additional filter chain; firing Filter: 'FilterSecurityInterceptor' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Converted URL to lowercase, from: '/zkau'; to: '/zkau' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /login.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /index.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /incoming.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /incomingprint.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /outgoing.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /outgoingprint.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contacts.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contact.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contact_win.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /documenttypes.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /documenttype.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /links.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /about.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /upload.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companies.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /company.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companytypes.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companytype.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /distributionmethods.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /distributionmethod.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /parameters.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /parameter.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /users.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /user.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /protocolbook.zul; matched=false 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /**; matched=true 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Secure object: FilterInvocation: URL: /zkau; Attributes: [IS_AUTHENTICATED_ANONYMOUSLY] 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Previously Authenticated: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.RoleVoter@4bf329a4, returned: 0 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.AuthenticatedVoter@4932f4f0, returned: 1 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Authorization successful 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] RunAsManager did not change Authentication object 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 15 of 15 in additional filter chain; firing Filter: 'ZkLoginOKFilter' 2011-11-03 15:57:33,519 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau reached end of additional filter chain; proceeding with original chain 2011-11-03 15:57:33,519 INFO [gr.scriptum.eprotocol.util.JTAOpenSessionInViewListener] Started transaction: org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:33,519 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:33,519 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:33,519 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:33,519 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:33,519 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:33,519 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:33,519 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:33,519 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:33,519 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:33,519 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:33,535 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:33,535 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:33,535 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:33,535 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:33,535 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:33,535 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:33,535 INFO [gr.scriptum.eprotocol.util.JTAOpenSessionInViewListener] Committed transaction: org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:33,535 DEBUG [org.springframework.security.web.access.ExceptionTranslationFilter] Chain processed normally 2011-11-03 15:57:33,535 DEBUG [org.springframework.security.web.context.SecurityContextPersistenceFilter] SecurityContextHolder now cleared, as request processing completed 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] Converted URL to lowercase, from: '/zkau/upload'; to: '/zkau/upload' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] Candidate is: '/zkau/upload'; pattern is /**; matched=true 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload at position 1 of 15 in additional filter chain; firing Filter: 'ZkDesktopReuseFilter' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload at position 2 of 15 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.context.HttpSessionSecurityContextRepository] Obtained a valid SecurityContext from SPRING_SECURITY_CONTEXT: 'org.springframework.security.core.context.SecurityContextImpl@1071009b: Authentication: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload at position 3 of 15 in additional filter chain; firing Filter: 'LogoutFilter' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload at position 4 of 15 in additional filter chain; firing Filter: 'ZkError403Filter' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload at position 5 of 15 in additional filter chain; firing Filter: 'ZkDisableSessionInvalidateFilter' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload at position 6 of 15 in additional filter chain; firing Filter: 'UsernamePasswordAuthenticationFilter' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload at position 7 of 15 in additional filter chain; firing Filter: 'BasicAuthenticationFilter' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload at position 8 of 15 in additional filter chain; firing Filter: 'RequestCacheAwareFilter' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload at position 9 of 15 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload at position 10 of 15 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.authentication.AnonymousAuthenticationFilter] SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload at position 11 of 15 in additional filter chain; firing Filter: 'SessionManagementFilter' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload at position 12 of 15 in additional filter chain; firing Filter: 'ExceptionTranslationFilter' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload at position 13 of 15 in additional filter chain; firing Filter: 'ZkEnableSessionInvalidateFilter' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload at position 14 of 15 in additional filter chain; firing Filter: 'FilterSecurityInterceptor' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Converted URL to lowercase, from: '/zkau/upload'; to: '/zkau/upload' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /login.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /index.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /incoming.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /incomingprint.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /outgoing.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /outgoingprint.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /contacts.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /contact.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /contact_win.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /documenttypes.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /documenttype.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /links.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /about.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /upload.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /companies.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /company.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /companytypes.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /companytype.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /distributionmethods.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /distributionmethod.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /parameters.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /parameter.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /users.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /user.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /protocolbook.zul; matched=false 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau/upload'; pattern is /**; matched=true 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Secure object: FilterInvocation: URL: /zkau/upload; Attributes: [IS_AUTHENTICATED_ANONYMOUSLY] 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Previously Authenticated: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.RoleVoter@4bf329a4, returned: 0 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.AuthenticatedVoter@4932f4f0, returned: 1 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Authorization successful 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] RunAsManager did not change Authentication object 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload at position 15 of 15 in additional filter chain; firing Filter: 'ZkLoginOKFilter' 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau/upload reached end of additional filter chain; proceeding with original chain 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.access.ExceptionTranslationFilter] Chain processed normally 2011-11-03 15:57:34,253 DEBUG [org.springframework.security.web.context.SecurityContextPersistenceFilter] SecurityContextHolder now cleared, as request processing completed 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] Converted URL to lowercase, from: '/zkau'; to: '/zkau' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] Candidate is: '/zkau'; pattern is /**; matched=true 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 1 of 15 in additional filter chain; firing Filter: 'ZkDesktopReuseFilter' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 2 of 15 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.context.HttpSessionSecurityContextRepository] Obtained a valid SecurityContext from SPRING_SECURITY_CONTEXT: 'org.springframework.security.core.context.SecurityContextImpl@1071009b: Authentication: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 3 of 15 in additional filter chain; firing Filter: 'LogoutFilter' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 4 of 15 in additional filter chain; firing Filter: 'ZkError403Filter' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 5 of 15 in additional filter chain; firing Filter: 'ZkDisableSessionInvalidateFilter' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 6 of 15 in additional filter chain; firing Filter: 'UsernamePasswordAuthenticationFilter' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 7 of 15 in additional filter chain; firing Filter: 'BasicAuthenticationFilter' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 8 of 15 in additional filter chain; firing Filter: 'RequestCacheAwareFilter' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 9 of 15 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 10 of 15 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.authentication.AnonymousAuthenticationFilter] SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 11 of 15 in additional filter chain; firing Filter: 'SessionManagementFilter' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 12 of 15 in additional filter chain; firing Filter: 'ExceptionTranslationFilter' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 13 of 15 in additional filter chain; firing Filter: 'ZkEnableSessionInvalidateFilter' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 14 of 15 in additional filter chain; firing Filter: 'FilterSecurityInterceptor' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Converted URL to lowercase, from: '/zkau'; to: '/zkau' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /login.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /index.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /incoming.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /incomingprint.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /outgoing.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /outgoingprint.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contacts.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contact.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contact_win.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /documenttypes.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /documenttype.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /links.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /about.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /upload.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companies.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /company.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companytypes.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companytype.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /distributionmethods.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /distributionmethod.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /parameters.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /parameter.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /users.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /user.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /protocolbook.zul; matched=false 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /**; matched=true 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Secure object: FilterInvocation: URL: /zkau; Attributes: [IS_AUTHENTICATED_ANONYMOUSLY] 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Previously Authenticated: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.RoleVoter@4bf329a4, returned: 0 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.AuthenticatedVoter@4932f4f0, returned: 1 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Authorization successful 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] RunAsManager did not change Authentication object 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 15 of 15 in additional filter chain; firing Filter: 'ZkLoginOKFilter' 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau reached end of additional filter chain; proceeding with original chain 2011-11-03 15:57:37,910 INFO [gr.scriptum.eprotocol.util.JTAOpenSessionInViewListener] Started transaction: org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:37,910 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:37,910 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:37,910 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:37,910 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:37,910 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:37,910 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:37,910 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:37,910 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:37,910 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:37,910 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:37,910 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:37,910 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:37,910 INFO [gr.scriptum.eprotocol.util.JTAOpenSessionInViewListener] Committed transaction: org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.access.ExceptionTranslationFilter] Chain processed normally 2011-11-03 15:57:37,910 DEBUG [org.springframework.security.web.context.SecurityContextPersistenceFilter] SecurityContextHolder now cleared, as request processing completed 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] Converted URL to lowercase, from: '/zkau'; to: '/zkau' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] Candidate is: '/zkau'; pattern is /**; matched=true 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 1 of 15 in additional filter chain; firing Filter: 'ZkDesktopReuseFilter' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 2 of 15 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.context.HttpSessionSecurityContextRepository] Obtained a valid SecurityContext from SPRING_SECURITY_CONTEXT: 'org.springframework.security.core.context.SecurityContextImpl@1071009b: Authentication: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 3 of 15 in additional filter chain; firing Filter: 'LogoutFilter' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 4 of 15 in additional filter chain; firing Filter: 'ZkError403Filter' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 5 of 15 in additional filter chain; firing Filter: 'ZkDisableSessionInvalidateFilter' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 6 of 15 in additional filter chain; firing Filter: 'UsernamePasswordAuthenticationFilter' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 7 of 15 in additional filter chain; firing Filter: 'BasicAuthenticationFilter' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 8 of 15 in additional filter chain; firing Filter: 'RequestCacheAwareFilter' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 9 of 15 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 10 of 15 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.authentication.AnonymousAuthenticationFilter] SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 11 of 15 in additional filter chain; firing Filter: 'SessionManagementFilter' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 12 of 15 in additional filter chain; firing Filter: 'ExceptionTranslationFilter' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 13 of 15 in additional filter chain; firing Filter: 'ZkEnableSessionInvalidateFilter' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 14 of 15 in additional filter chain; firing Filter: 'FilterSecurityInterceptor' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Converted URL to lowercase, from: '/zkau'; to: '/zkau' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /login.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /index.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /incoming.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /incomingprint.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /outgoing.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /outgoingprint.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contacts.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contact.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contact_win.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /documenttypes.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /documenttype.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /links.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /about.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /upload.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companies.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /company.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companytypes.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companytype.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /distributionmethods.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /distributionmethod.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /parameters.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /parameter.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /users.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /user.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /protocolbook.zul; matched=false 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /**; matched=true 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Secure object: FilterInvocation: URL: /zkau; Attributes: [IS_AUTHENTICATED_ANONYMOUSLY] 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Previously Authenticated: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.RoleVoter@4bf329a4, returned: 0 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.AuthenticatedVoter@4932f4f0, returned: 1 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Authorization successful 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] RunAsManager did not change Authentication object 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 15 of 15 in additional filter chain; firing Filter: 'ZkLoginOKFilter' 2011-11-03 15:57:38,551 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau reached end of additional filter chain; proceeding with original chain 2011-11-03 15:57:38,551 INFO [gr.scriptum.eprotocol.util.JTAOpenSessionInViewListener] Started transaction: org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:38,551 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:38,551 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:38,551 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:38,551 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:38,551 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:38,551 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:38,551 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:38,551 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:38,551 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:38,551 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:38,551 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:38,551 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:38,551 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:38,551 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:38,551 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:38,551 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:38,551 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:38,551 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:38,551 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:38,551 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:38,551 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:38,551 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:38,551 INFO [gr.scriptum.eprotocol.util.JTAOpenSessionInViewListener] Committed transaction: org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:38,566 DEBUG [org.springframework.security.web.access.ExceptionTranslationFilter] Chain processed normally 2011-11-03 15:57:38,566 DEBUG [org.springframework.security.web.context.SecurityContextPersistenceFilter] SecurityContextHolder now cleared, as request processing completed 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] Converted URL to lowercase, from: '/zkau'; to: '/zkau' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] Candidate is: '/zkau'; pattern is /**; matched=true 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 1 of 15 in additional filter chain; firing Filter: 'ZkDesktopReuseFilter' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 2 of 15 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.context.HttpSessionSecurityContextRepository] Obtained a valid SecurityContext from SPRING_SECURITY_CONTEXT: 'org.springframework.security.core.context.SecurityContextImpl@1071009b: Authentication: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 3 of 15 in additional filter chain; firing Filter: 'LogoutFilter' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 4 of 15 in additional filter chain; firing Filter: 'ZkError403Filter' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 5 of 15 in additional filter chain; firing Filter: 'ZkDisableSessionInvalidateFilter' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 6 of 15 in additional filter chain; firing Filter: 'UsernamePasswordAuthenticationFilter' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 7 of 15 in additional filter chain; firing Filter: 'BasicAuthenticationFilter' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 8 of 15 in additional filter chain; firing Filter: 'RequestCacheAwareFilter' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 9 of 15 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 10 of 15 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.authentication.AnonymousAuthenticationFilter] SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 11 of 15 in additional filter chain; firing Filter: 'SessionManagementFilter' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 12 of 15 in additional filter chain; firing Filter: 'ExceptionTranslationFilter' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 13 of 15 in additional filter chain; firing Filter: 'ZkEnableSessionInvalidateFilter' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 14 of 15 in additional filter chain; firing Filter: 'FilterSecurityInterceptor' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Converted URL to lowercase, from: '/zkau'; to: '/zkau' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /login.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /index.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /incoming.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /incomingprint.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /outgoing.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /outgoingprint.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contacts.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contact.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contact_win.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /documenttypes.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /documenttype.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /links.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /about.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /upload.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companies.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /company.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companytypes.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companytype.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /distributionmethods.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /distributionmethod.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /parameters.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /parameter.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /users.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /user.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /protocolbook.zul; matched=false 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /**; matched=true 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Secure object: FilterInvocation: URL: /zkau; Attributes: [IS_AUTHENTICATED_ANONYMOUSLY] 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Previously Authenticated: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.RoleVoter@4bf329a4, returned: 0 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.AuthenticatedVoter@4932f4f0, returned: 1 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Authorization successful 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] RunAsManager did not change Authentication object 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 15 of 15 in additional filter chain; firing Filter: 'ZkLoginOKFilter' 2011-11-03 15:57:43,645 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau reached end of additional filter chain; proceeding with original chain 2011-11-03 15:57:43,645 INFO [gr.scriptum.eprotocol.util.JTAOpenSessionInViewListener] Started transaction: org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:43,645 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:43,645 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:43,645 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:43,645 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:43,645 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:43,645 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:43,645 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:43,645 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:43,645 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:43,645 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:43,645 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:43,645 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:43,660 INFO [gr.scriptum.eprotocol.controller.IncomingController] Committed transaction: org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:43,660 INFO [gr.scriptum.eprotocol.controller.IncomingController] Getting Protocol Number (Started transaction): org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:43,660 INFO [STDOUT] Hibernate: select protocolnu0_.id as id18_, protocolnu0_.version as version18_, protocolnu0_.number as number18_, protocolnu0_.series as series18_, protocolnu0_.year as year18_, protocolnu0_.type as type18_, protocolnu0_.create_dt as create7_18_, protocolnu0_.update_ts as update8_18_ from ellak.protocol_number protocolnu0_ where protocolnu0_.id=(select max(protocolnu1_.id) from ellak.protocol_number protocolnu1_ where protocolnu1_.type=?) 2011-11-03 15:57:43,660 INFO [STDOUT] Hibernate: update ellak.protocol_number set version=?, number=?, series=?, year=?, type=?, create_dt=?, update_ts=? where id=? and version=? 2011-11-03 15:57:43,660 INFO [gr.scriptum.eprotocol.controller.IncomingController] Got Protocol Number (Commited transaction): org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:43,660 INFO [gr.scriptum.eprotocol.controller.IncomingController] Saving Protocol (Started transaction): org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:43,660 INFO [STDOUT] Hibernate: select this_.id as id12_0_, this_.area as area12_0_, this_.type as type12_0_, this_.name as name12_0_, this_.description as descript5_12_0_, this_.initial_value as initial6_12_0_, this_.value as value12_0_, this_.create_user as create8_12_0_, this_.update_user as update9_12_0_, this_.create_dt as create10_12_0_, this_.update_ts as update11_12_0_ from ellak.parameter this_ where (this_.name=?) 2011-11-03 15:57:43,676 INFO [gr.scriptum.dao.GenericDAO] Rows fetched:1 2011-11-03 15:57:43,676 INFO [STDOUT] Hibernate: select this_.id as id12_0_, this_.area as area12_0_, this_.type as type12_0_, this_.name as name12_0_, this_.description as descript5_12_0_, this_.initial_value as initial6_12_0_, this_.value as value12_0_, this_.create_user as create8_12_0_, this_.update_user as update9_12_0_, this_.create_dt as create10_12_0_, this_.update_ts as update11_12_0_ from ellak.parameter this_ where (this_.name=?) 2011-11-03 15:57:43,676 INFO [gr.scriptum.dao.GenericDAO] Rows fetched:1 2011-11-03 15:57:43,676 INFO [STDOUT] Hibernate: select this_.id as id12_0_, this_.area as area12_0_, this_.type as type12_0_, this_.name as name12_0_, this_.description as descript5_12_0_, this_.initial_value as initial6_12_0_, this_.value as value12_0_, this_.create_user as create8_12_0_, this_.update_user as update9_12_0_, this_.create_dt as create10_12_0_, this_.update_ts as update11_12_0_ from ellak.parameter this_ where (this_.name=?) 2011-11-03 15:57:43,676 INFO [gr.scriptum.dao.GenericDAO] Rows fetched:1 2011-11-03 15:57:43,676 INFO [STDOUT] Hibernate: select this_.id as id12_0_, this_.area as area12_0_, this_.type as type12_0_, this_.name as name12_0_, this_.description as descript5_12_0_, this_.initial_value as initial6_12_0_, this_.value as value12_0_, this_.create_user as create8_12_0_, this_.update_user as update9_12_0_, this_.create_dt as create10_12_0_, this_.update_ts as update11_12_0_ from ellak.parameter this_ where (this_.name=?) 2011-11-03 15:57:43,676 INFO [gr.scriptum.dao.GenericDAO] Rows fetched:1 2011-11-03 15:57:43,676 INFO [STDOUT] Hibernate: insert into ellak.incoming_protocol (distribution_method_id, contact_sender_id, protocol_number, protocol_date, protocol_series, protocol_year, subject, comments, incoming_protocol_number, incoming_date, incoming_place, create_user_id, update_user_id, create_dt, update_ts, is_deleted, relative_protocol) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) 2011-11-03 15:57:43,676 INFO [gr.scriptum.dao.GenericDAO] persist successfull 2011-11-03 15:57:43,676 INFO [STDOUT] Hibernate: insert into ellak.protocol_document (outgoing_protocol_id, incoming_protocol_id, document_types_id, document_name, document_size, document_mime, document_number, document_keywords, okm_path, okm_uuid, diavgeia_ada_code) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) 2011-11-03 15:57:43,676 INFO [gr.scriptum.dao.GenericDAO] persist successfull 2011-11-03 15:57:43,692 ERROR [STDERR] 3 Íïå 2011 3:57:43 ìì gr.scriptum.eprotocol.ws.OkmProtocolDispatcherImpl getInstance WARNING: Binding with http://localhost:8080/OpenKM/OKMFolder 2011-11-03 15:57:43,692 DEBUG [org.jboss.wsf.stack.jbws.RequestHandlerImpl] doPost: /OpenKM/OKMFolder 2011-11-03 15:57:43,692 DEBUG [org.jboss.wsf.stack.jbws.RequestHandlerImpl] handleRequest: jboss.ws:context=OpenKM,endpoint=OKMFolder 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.soap.MessageContextAssociation] pushMessageContext: org.jboss.ws.core.jaxws.handler.SOAPMessageContextJAXWS@406039a4 (Thread http-0.0.0.0-8080-13) 2011-11-03 15:57:43,692 DEBUG [org.jboss.wsf.stack.jbws.RequestHandlerImpl] BEGIN handleRequest: jboss.ws:context=OpenKM,endpoint=OKMFolder 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.soap.MessageFactoryImpl] createMessage: [contentType=text/xml; charset=utf-8] 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] callRequestHandlerChain: POST 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerResolverImpl] getHandlerChain: [type=POST,info=[service={http://endpoint.ws.openkm.com/}OKMFolderService,port={http://endpoint.ws.openkm.com/}OKMFolderPort,binding=http://schemas.xmlsoap.org/wsdl/soap/http]] 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] Create a handler executor: [] 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.soap.SOAPMessageDispatcher] getDispatchDestination: {http://endpoint.ws.openkm.com/}createSimple 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.jaxws.binding.SOAP11BindingJAXWS] unbindRequestMessage: {http://endpoint.ws.openkm.com/}createSimple 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.EndpointInvocation] setRequestParamValue: [name=token,value=org.jboss.ws.core.soap.SOAPContentElement] 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.EndpointInvocation] setRequestParamValue: [name=fldPath,value=org.jboss.ws.core.soap.SOAPContentElement] 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] callRequestHandlerChain: ENDPOINT 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerResolverImpl] getHandlerChain: [type=ENDPOINT,info=[service={http://endpoint.ws.openkm.com/}OKMFolderService,port={http://endpoint.ws.openkm.com/}OKMFolderPort,binding=http://schemas.xmlsoap.org/wsdl/soap/http]] 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] Create a handler executor: [] 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] callRequestHandlerChain: PRE 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerResolverImpl] getHandlerChain: [type=PRE,info=[service={http://endpoint.ws.openkm.com/}OKMFolderService,port={http://endpoint.ws.openkm.com/}OKMFolderPort,binding=http://schemas.xmlsoap.org/wsdl/soap/http]] 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] Create a handler executor: [] 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.EndpointInvocation] getRequestPayload 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.EndpointInvocation] getRequestParamValue: token 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] ----------------------------------- 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] Transitioning from XML_VALID to OBJECT_VALID 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.soap.XMLContent] getObjectValue [xmlType={http://www.w3.org/2001/XMLSchema}string,javaType=class java.lang.String] 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.jaxrpc.binding.SimpleDeserializer] deserialize: [xmlName=token,xmlType={http://www.w3.org/2001/XMLSchema}string] 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.soap.XMLContent] objectValue: java.lang.String 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] ----------------------------------- 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.EndpointInvocation] transformPayloadValue: org.jboss.ws.core.soap.SOAPContentElement -> java.lang.String 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.EndpointInvocation] syncEndpointInputParam: java.lang.String -> java.lang.String(0) 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.EndpointInvocation] getRequestParamValue: fldPath 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] ----------------------------------- 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] Transitioning from XML_VALID to OBJECT_VALID 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.soap.XMLContent] getObjectValue [xmlType={http://www.w3.org/2001/XMLSchema}string,javaType=class java.lang.String] 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.jaxrpc.binding.SimpleDeserializer] deserialize: [xmlName=fldPath,xmlType={http://www.w3.org/2001/XMLSchema}string] 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.soap.XMLContent] objectValue: java.lang.String 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] ----------------------------------- 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.EndpointInvocation] transformPayloadValue: org.jboss.ws.core.soap.SOAPContentElement -> java.lang.String 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.EndpointInvocation] syncEndpointInputParam: java.lang.String -> java.lang.String(1) 2011-11-03 15:57:43,692 WARN [com.openkm.module.direct.DirectFolderModule] okm:root/IncomingProtocol javax.jcr.PathNotFoundException: okm:root/IncomingProtocol at org.apache.jackrabbit.core.NodeImpl.getNode(NodeImpl.java:2760) at com.openkm.module.direct.DirectFolderModule.create(DirectFolderModule.java:214) at com.openkm.ws.endpoint.OKMFolder.createSimple(OKMFolder.java:77) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at org.jboss.wsf.container.jboss42.InvocationHandlerJSE.invoke(InvocationHandlerJSE.java:102) at org.jboss.ws.core.server.ServiceEndpointInvoker.invoke(ServiceEndpointInvoker.java:221) at org.jboss.wsf.stack.jbws.RequestHandlerImpl.processRequest(RequestHandlerImpl.java:466) at org.jboss.wsf.stack.jbws.RequestHandlerImpl.handleRequest(RequestHandlerImpl.java:284) at org.jboss.wsf.stack.jbws.RequestHandlerImpl.doPost(RequestHandlerImpl.java:201) at org.jboss.wsf.stack.jbws.RequestHandlerImpl.handleHttpRequest(RequestHandlerImpl.java:134) at org.jboss.wsf.stack.jbws.EndpointServlet.service(EndpointServlet.java:84) at javax.servlet.http.HttpServlet.service(HttpServlet.java:803) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:96) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:230) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175) at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:182) at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:432) at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:84) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102) at org.jboss.web.tomcat.service.jca.CachedConnectionValve.invoke(CachedConnectionValve.java:157) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:262) at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844) at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583) at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:446) at java.lang.Thread.run(Thread.java:662) 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.jaxws.handler.MessageContextJAXWS] Begin response processing 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.soap.MessageContextAssociation] popMessageContext: org.jboss.ws.core.jaxws.handler.SOAPMessageContextJAXWS@406039a4 (Thread http-0.0.0.0-8080-13) 2011-11-03 15:57:43,692 DEBUG [org.jboss.ws.core.soap.MessageContextAssociation] pushMessageContext: org.jboss.ws.core.jaxws.handler.SOAPMessageContextJAXWS@5f35311d (Thread http-0.0.0.0-8080-13) 2011-11-03 15:57:43,692 ERROR [org.jboss.ws.core.jaxws.SOAPFaultHelperJAXWS] SOAP request exception com.openkm.core.PathNotFoundException: okm:root/IncomingProtocol at com.openkm.module.direct.DirectFolderModule.create(DirectFolderModule.java:233) at com.openkm.ws.endpoint.OKMFolder.createSimple(OKMFolder.java:77) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at org.jboss.wsf.container.jboss42.InvocationHandlerJSE.invoke(InvocationHandlerJSE.java:102) at org.jboss.ws.core.server.ServiceEndpointInvoker.invoke(ServiceEndpointInvoker.java:221) at org.jboss.wsf.stack.jbws.RequestHandlerImpl.processRequest(RequestHandlerImpl.java:466) at org.jboss.wsf.stack.jbws.RequestHandlerImpl.handleRequest(RequestHandlerImpl.java:284) at org.jboss.wsf.stack.jbws.RequestHandlerImpl.doPost(RequestHandlerImpl.java:201) at org.jboss.wsf.stack.jbws.RequestHandlerImpl.handleHttpRequest(RequestHandlerImpl.java:134) at org.jboss.wsf.stack.jbws.EndpointServlet.service(EndpointServlet.java:84) at javax.servlet.http.HttpServlet.service(HttpServlet.java:803) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:96) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:230) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175) at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:182) at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:432) at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:84) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102) at org.jboss.web.tomcat.service.jca.CachedConnectionValve.invoke(CachedConnectionValve.java:157) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:262) at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844) at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583) at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:446) at java.lang.Thread.run(Thread.java:662) Caused by: javax.jcr.PathNotFoundException: okm:root/IncomingProtocol at org.apache.jackrabbit.core.NodeImpl.getNode(NodeImpl.java:2760) at com.openkm.module.direct.DirectFolderModule.create(DirectFolderModule.java:214) ... 32 more 2011-11-03 15:57:43,707 DEBUG [org.jboss.ws.core.jaxws.JAXBSerializer] serialize: [xmlName={http://endpoint.ws.openkm.com/}PathNotFoundException,xmlType={http://endpoint.ws.openkm.com/}PathNotFoundException] 2011-11-03 15:57:43,707 DEBUG [org.jboss.ws.core.jaxws.JAXBSerializer] serialized: <ns2:PathNotFoundException xmlns:ns2="http://endpoint.ws.openkm.com/";><message>okm:root/IncomingProtocol</message></ns2:PathNotFoundException> 2011-11-03 15:57:43,707 DEBUG [org.jboss.ws.core.jaxws.SOAPFaultHelperJAXWS] Fault detail: <ns2:PathNotFoundException xmlns:ns2="http://endpoint.ws.openkm.com/";><message>okm:root/IncomingProtocol</message></ns2:PathNotFoundException> 2011-11-03 15:57:43,707 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] callFaultHandlerChain: PRE 2011-11-03 15:57:43,707 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] callFaultHandlerChain: ENDPOINT 2011-11-03 15:57:43,707 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] callFaultHandlerChain: POST 2011-11-03 15:57:43,707 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] closeHandlerChain 2011-11-03 15:57:43,707 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] close 2011-11-03 15:57:43,707 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] closeHandlerChain 2011-11-03 15:57:43,707 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] close 2011-11-03 15:57:43,707 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] closeHandlerChain 2011-11-03 15:57:43,707 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] close 2011-11-03 15:57:43,707 DEBUG [org.jboss.wsf.stack.jbws.RequestHandlerImpl] END handleRequest: jboss.ws:context=OpenKM,endpoint=OKMFolder 2011-11-03 15:57:43,707 DEBUG [org.jboss.ws.core.soap.MessageContextAssociation] popMessageContext: org.jboss.ws.core.jaxws.handler.SOAPMessageContextJAXWS@5f35311d (Thread http-0.0.0.0-8080-13) 2011-11-03 15:57:43,707 ERROR [STDERR] 3 Íïå 2011 3:57:43 ìì gr.scriptum.eprotocol.ws.OkmProtocolDispatcherImpl createErrorResponse SEVERE: Exception : null 2011-11-03 15:57:43,707 ERROR [gr.scriptum.eprotocol.controller.IncomingController] Response [requestId=d8d4b87f-ef18-4ce4-a616-d13b2c44215e, completed=Thu Nov 03 15:57:43 EET 2011, eCode=0003, eMessage=null] 2011-11-03 15:57:43,707 ERROR [gr.scriptum.eprotocol.controller.IncomingController] java.lang.RuntimeException: Response [requestId=d8d4b87f-ef18-4ce4-a616-d13b2c44215e, completed=Thu Nov 03 15:57:43 EET 2011, eCode=0003, eMessage=null] 2011-11-03 15:57:43,707 INFO [gr.scriptum.eprotocol.controller.IncomingController] Rolled back transaction: org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:43,707 ERROR [gr.scriptum.eprotocol.controller.IncomingController] java.lang.RuntimeException: Response [requestId=d8d4b87f-ef18-4ce4-a616-d13b2c44215e, completed=Thu Nov 03 15:57:43 EET 2011, eCode=0003, eMessage=null] 2011-11-03 15:57:43,723 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:43,723 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:43,723 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:43,723 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:43,723 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:43,723 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:43,723 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:43,723 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:43,723 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:43,723 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:43,723 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:43,723 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:43,723 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:43,723 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:43,723 DEBUG [org.springframework.security.web.access.ExceptionTranslationFilter] Chain processed normally 2011-11-03 15:57:43,723 DEBUG [org.springframework.security.web.context.SecurityContextPersistenceFilter] SecurityContextHolder now cleared, as request processing completed 2011-11-03 15:57:44,489 INFO [gr.scriptum.eprotocol.mailclients.FetchIncomingMailsWork] performWork()72 2011-11-03 15:57:44,489 INFO [STDOUT] Hibernate: select this_.id as id12_0_, this_.area as area12_0_, this_.type as type12_0_, this_.name as name12_0_, this_.description as descript5_12_0_, this_.initial_value as initial6_12_0_, this_.value as value12_0_, this_.create_user as create8_12_0_, this_.update_user as update9_12_0_, this_.create_dt as create10_12_0_, this_.update_ts as update11_12_0_ from ellak.parameter this_ where (this_.name=?) 2011-11-03 15:57:44,489 INFO [gr.scriptum.dao.GenericDAO] Rows fetched:1 2011-11-03 15:57:44,489 INFO [STDOUT] Hibernate: select this_.id as id12_0_, this_.area as area12_0_, this_.type as type12_0_, this_.name as name12_0_, this_.description as descript5_12_0_, this_.initial_value as initial6_12_0_, this_.value as value12_0_, this_.create_user as create8_12_0_, this_.update_user as update9_12_0_, this_.create_dt as create10_12_0_, this_.update_ts as update11_12_0_ from ellak.parameter this_ where (this_.name=?) 2011-11-03 15:57:44,489 INFO [gr.scriptum.dao.GenericDAO] Rows fetched:1 2011-11-03 15:57:44,489 INFO [STDOUT] Hibernate: select this_.id as id12_0_, this_.area as area12_0_, this_.type as type12_0_, this_.name as name12_0_, this_.description as descript5_12_0_, this_.initial_value as initial6_12_0_, this_.value as value12_0_, this_.create_user as create8_12_0_, this_.update_user as update9_12_0_, this_.create_dt as create10_12_0_, this_.update_ts as update11_12_0_ from ellak.parameter this_ where (this_.name=?) 2011-11-03 15:57:44,489 INFO [gr.scriptum.dao.GenericDAO] Rows fetched:1 2011-11-03 15:57:44,489 INFO [STDOUT] Hibernate: select this_.id as id12_0_, this_.area as area12_0_, this_.type as type12_0_, this_.name as name12_0_, this_.description as descript5_12_0_, this_.initial_value as initial6_12_0_, this_.value as value12_0_, this_.create_user as create8_12_0_, this_.update_user as update9_12_0_, this_.create_dt as create10_12_0_, this_.update_ts as update11_12_0_ from ellak.parameter this_ where (this_.name=?) 2011-11-03 15:57:44,489 INFO [gr.scriptum.dao.GenericDAO] Rows fetched:1 2011-11-03 15:57:44,489 ERROR [STDERR] 3 Íïå 2011 3:57:44 ìì gr.scriptum.eprotocol.ws.OkmProtocolDispatcherImpl getInstance WARNING: Binding with http://localhost:8080/OpenKM/OKMAuth 2011-11-03 15:57:44,504 DEBUG [org.jboss.wsf.stack.jbws.RequestHandlerImpl] doPost: /OpenKM/OKMAuth 2011-11-03 15:57:44,504 DEBUG [org.jboss.wsf.stack.jbws.RequestHandlerImpl] handleRequest: jboss.ws:context=OpenKM,endpoint=OKMAuth 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.soap.MessageContextAssociation] pushMessageContext: org.jboss.ws.core.jaxws.handler.SOAPMessageContextJAXWS@91d3b18 (Thread http-0.0.0.0-8080-13) 2011-11-03 15:57:44,504 DEBUG [org.jboss.wsf.stack.jbws.RequestHandlerImpl] BEGIN handleRequest: jboss.ws:context=OpenKM,endpoint=OKMAuth 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.soap.MessageFactoryImpl] createMessage: [contentType=text/xml; charset=utf-8] 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] callRequestHandlerChain: POST 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerResolverImpl] getHandlerChain: [type=POST,info=[service={http://endpoint.ws.openkm.com/}OKMAuthService,port={http://endpoint.ws.openkm.com/}OKMAuthPort,binding=http://schemas.xmlsoap.org/wsdl/soap/http]] 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] Create a handler executor: [] 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.soap.SOAPMessageDispatcher] getDispatchDestination: {http://endpoint.ws.openkm.com/}login 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.binding.SOAP11BindingJAXWS] unbindRequestMessage: {http://endpoint.ws.openkm.com/}login 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.EndpointInvocation] setRequestParamValue: [name=user,value=org.jboss.ws.core.soap.SOAPContentElement] 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.EndpointInvocation] setRequestParamValue: [name=password,value=org.jboss.ws.core.soap.SOAPContentElement] 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] callRequestHandlerChain: ENDPOINT 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerResolverImpl] getHandlerChain: [type=ENDPOINT,info=[service={http://endpoint.ws.openkm.com/}OKMAuthService,port={http://endpoint.ws.openkm.com/}OKMAuthPort,binding=http://schemas.xmlsoap.org/wsdl/soap/http]] 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] Create a handler executor: [] 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] callRequestHandlerChain: PRE 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerResolverImpl] getHandlerChain: [type=PRE,info=[service={http://endpoint.ws.openkm.com/}OKMAuthService,port={http://endpoint.ws.openkm.com/}OKMAuthPort,binding=http://schemas.xmlsoap.org/wsdl/soap/http]] 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] Create a handler executor: [] 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.EndpointInvocation] getRequestPayload 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.EndpointInvocation] getRequestParamValue: user 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] ----------------------------------- 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] Transitioning from XML_VALID to OBJECT_VALID 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.soap.XMLContent] getObjectValue [xmlType={http://www.w3.org/2001/XMLSchema}string,javaType=class java.lang.String] 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxrpc.binding.SimpleDeserializer] deserialize: [xmlName=user,xmlType={http://www.w3.org/2001/XMLSchema}string] 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.soap.XMLContent] objectValue: null 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] ----------------------------------- 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.EndpointInvocation] transformPayloadValue: org.jboss.ws.core.soap.SOAPContentElement -> null 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.EndpointInvocation] syncEndpointInputParam: null -> null(0) 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.EndpointInvocation] getRequestParamValue: password 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] ----------------------------------- 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] Transitioning from XML_VALID to OBJECT_VALID 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.soap.XMLContent] getObjectValue [xmlType={http://www.w3.org/2001/XMLSchema}string,javaType=class java.lang.String] 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxrpc.binding.SimpleDeserializer] deserialize: [xmlName=password,xmlType={http://www.w3.org/2001/XMLSchema}string] 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.soap.XMLContent] objectValue: null 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] ----------------------------------- 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.EndpointInvocation] transformPayloadValue: org.jboss.ws.core.soap.SOAPContentElement -> null 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.EndpointInvocation] syncEndpointInputParam: null -> null(1) 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.MessageContextJAXWS] Begin response processing 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.soap.MessageContextAssociation] popMessageContext: org.jboss.ws.core.jaxws.handler.SOAPMessageContextJAXWS@91d3b18 (Thread http-0.0.0.0-8080-13) 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.soap.MessageContextAssociation] pushMessageContext: org.jboss.ws.core.jaxws.handler.SOAPMessageContextJAXWS@5e12a954 (Thread http-0.0.0.0-8080-13) 2011-11-03 15:57:44,504 ERROR [org.jboss.ws.core.jaxws.SOAPFaultHelperJAXWS] SOAP request exception java.lang.NullPointerException at com.openkm.module.direct.DirectAuthModule.login(DirectAuthModule.java:85) at com.openkm.ws.endpoint.OKMAuth.login(OKMAuth.java:67) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at org.jboss.wsf.container.jboss42.InvocationHandlerJSE.invoke(InvocationHandlerJSE.java:102) at org.jboss.ws.core.server.ServiceEndpointInvoker.invoke(ServiceEndpointInvoker.java:221) at org.jboss.wsf.stack.jbws.RequestHandlerImpl.processRequest(RequestHandlerImpl.java:466) at org.jboss.wsf.stack.jbws.RequestHandlerImpl.handleRequest(RequestHandlerImpl.java:284) at org.jboss.wsf.stack.jbws.RequestHandlerImpl.doPost(RequestHandlerImpl.java:201) at org.jboss.wsf.stack.jbws.RequestHandlerImpl.handleHttpRequest(RequestHandlerImpl.java:134) at org.jboss.wsf.stack.jbws.EndpointServlet.service(EndpointServlet.java:84) at javax.servlet.http.HttpServlet.service(HttpServlet.java:803) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:96) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:230) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175) at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:182) at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:432) at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:84) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102) at org.jboss.web.tomcat.service.jca.CachedConnectionValve.invoke(CachedConnectionValve.java:157) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:262) at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844) at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583) at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:446) at java.lang.Thread.run(Thread.java:662) 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.SOAPFaultHelperJAXWS] Cannot obtain fault meta data for: class java.lang.NullPointerException 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] callFaultHandlerChain: PRE 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] callFaultHandlerChain: ENDPOINT 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] callFaultHandlerChain: POST 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] closeHandlerChain 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] close 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] closeHandlerChain 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] close 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerDelegateJAXWS] closeHandlerChain 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] close 2011-11-03 15:57:44,504 DEBUG [org.jboss.wsf.stack.jbws.RequestHandlerImpl] END handleRequest: jboss.ws:context=OpenKM,endpoint=OKMAuth 2011-11-03 15:57:44,504 DEBUG [org.jboss.ws.core.soap.MessageContextAssociation] popMessageContext: org.jboss.ws.core.jaxws.handler.SOAPMessageContextJAXWS@5e12a954 (Thread http-0.0.0.0-8080-13) 2011-11-03 15:57:44,504 ERROR [STDERR] 3 Íïå 2011 3:57:44 ìì gr.scriptum.eprotocol.ws.OkmProtocolDispatcherImpl createErrorResponse SEVERE: Exception : java.lang.NullPointerException 2011-11-03 15:57:44,504 WARN [gr.scriptum.eprotocol.mailclients.FetchIncomingMailsWork] Could not login to OpenKM Response [requestId=ec260e00-75fd-451d-8049-77408467bf96, completed=Thu Nov 03 15:57:44 EET 2011, eCode=0001, eMessage=java.lang.NullPointerException] 2011-11-03 15:57:44,504 ERROR [gr.scriptum.eprotocol.mailclients.FetchIncomingMailsWork] java.lang.Exception: Response [requestId=ec260e00-75fd-451d-8049-77408467bf96, completed=Thu Nov 03 15:57:44 EET 2011, eCode=0001, eMessage=java.lang.NullPointerException] 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] Converted URL to lowercase, from: '/zkau'; to: '/zkau' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] Candidate is: '/zkau'; pattern is /**; matched=true 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 1 of 15 in additional filter chain; firing Filter: 'ZkDesktopReuseFilter' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 2 of 15 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.context.HttpSessionSecurityContextRepository] Obtained a valid SecurityContext from SPRING_SECURITY_CONTEXT: 'org.springframework.security.core.context.SecurityContextImpl@1071009b: Authentication: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 3 of 15 in additional filter chain; firing Filter: 'LogoutFilter' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 4 of 15 in additional filter chain; firing Filter: 'ZkError403Filter' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 5 of 15 in additional filter chain; firing Filter: 'ZkDisableSessionInvalidateFilter' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 6 of 15 in additional filter chain; firing Filter: 'UsernamePasswordAuthenticationFilter' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 7 of 15 in additional filter chain; firing Filter: 'BasicAuthenticationFilter' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 8 of 15 in additional filter chain; firing Filter: 'RequestCacheAwareFilter' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 9 of 15 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 10 of 15 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.authentication.AnonymousAuthenticationFilter] SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 11 of 15 in additional filter chain; firing Filter: 'SessionManagementFilter' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 12 of 15 in additional filter chain; firing Filter: 'ExceptionTranslationFilter' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 13 of 15 in additional filter chain; firing Filter: 'ZkEnableSessionInvalidateFilter' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 14 of 15 in additional filter chain; firing Filter: 'FilterSecurityInterceptor' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Converted URL to lowercase, from: '/zkau'; to: '/zkau' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /login.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /index.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /incoming.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /incomingprint.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /outgoing.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /outgoingprint.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contacts.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contact.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /contact_win.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /documenttypes.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /documenttype.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /links.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /about.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /upload.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companies.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /company.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companytypes.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /companytype.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /distributionmethods.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /distributionmethod.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /parameters.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /parameter.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /users.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /user.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /protocolbook.zul; matched=false 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.DefaultFilterInvocationSecurityMetadataSource] Candidate is: '/zkau'; pattern is /**; matched=true 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Secure object: FilterInvocation: URL: /zkau; Attributes: [IS_AUTHENTICATED_ANONYMOUSLY] 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Previously Authenticated: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@1071009b: Principal: gr.scriptum.eprotocol.security.ScriptumUser@77291e95; Credentials: [PROTECTED]; Authenticated: true; Details: org.springframework.security.web.authentication.WebAuthenticationDetails@380f4: RemoteIpAddress: 127.0.0.1; SessionId: 41E95E62BD5F40108F49B65E95DD8559; Granted Authorities: ROLE_WRITER 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.RoleVoter@4bf329a4, returned: 0 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.access.vote.AffirmativeBased] Voter: org.springframework.security.access.vote.AuthenticatedVoter@4932f4f0, returned: 1 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] Authorization successful 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.intercept.FilterSecurityInterceptor] RunAsManager did not change Authentication object 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau at position 15 of 15 in additional filter chain; firing Filter: 'ZkLoginOKFilter' 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.FilterChainProxy] /zkau reached end of additional filter chain; proceeding with original chain 2011-11-03 15:57:46,051 INFO [gr.scriptum.eprotocol.util.JTAOpenSessionInViewListener] Started transaction: org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:46,051 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:46,051 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:46,051 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:46,051 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:46,051 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] Returning cached instance of singleton bean 'zkEventProcessInterceptor' 2011-11-03 15:57:46,051 DEBUG [org.zkoss.spring.security.intercept.zkevent.ZkEventProcessInterceptor] Public object - authentication not attempted 2011-11-03 15:57:46,051 INFO [gr.scriptum.eprotocol.util.JTAOpenSessionInViewListener] Committed transaction: org.jboss.tm.usertx.client.ServerVMClientUserTransaction@21606a56 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.access.ExceptionTranslationFilter] Chain processed normally 2011-11-03 15:57:46,051 DEBUG [org.springframework.security.web.context.SecurityContextPersistenceFilter] SecurityContextHolder now cleared, as request processing completed