· 7 years ago · Dec 13, 2017, 02:44 PM
12017-12-13 13:52:53.679 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/api/v1/datacenters/'; against '/'
22017-12-13 13:52:53.680 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/oauth/token']
32017-12-13 13:52:53.680 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/api/v1/datacenters/'; against '/oauth/token'
42017-12-13 13:52:53.680 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/oauth/token_key']
52017-12-13 13:52:53.680 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/api/v1/datacenters/'; against '/oauth/token_key'
62017-12-13 13:52:53.680 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/oauth/check_token']
72017-12-13 13:52:53.680 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/api/v1/datacenters/'; against '/oauth/check_token'
82017-12-13 13:52:53.680 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.web.util.matcher.OrRequestMatcher : No matches found
92017-12-13 13:52:53.680 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.security.web.FilterChainProxy : /api/v1/datacenters/ at position 1 of 12 in additional filter chain; firing Filter: 'WebAsyncManagerIntegrationFilter'
102017-12-13 13:52:53.680 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.security.web.FilterChainProxy : /api/v1/datacenters/ at position 2 of 12 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter'
112017-12-13 13:52:53.680 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.security.web.FilterChainProxy : /api/v1/datacenters/ at position 3 of 12 in additional filter chain; firing Filter: 'HeaderWriterFilter'
122017-12-13 13:52:53.680 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.header.writers.HstsHeaderWriter : Not injecting HSTS header since it did not match the requestMatcher org.springframework.security.web.header.writers.HstsHeaderWriter$SecureRequestMatcher@6fd8bb29
132017-12-13 13:52:53.680 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.security.web.FilterChainProxy : /api/v1/datacenters/ at position 4 of 12 in additional filter chain; firing Filter: 'CorsFilter'
142017-12-13 13:52:53.681 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.web.cors.DefaultCorsProcessor : Skip CORS processing: request is from same origin
152017-12-13 13:52:53.681 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.security.web.FilterChainProxy : /api/v1/datacenters/ at position 5 of 12 in additional filter chain; firing Filter: 'LogoutFilter'
162017-12-13 13:52:53.681 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', GET]
172017-12-13 13:52:53.681 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'POST /api/v1/datacenters/' doesn't match 'GET /logout
182017-12-13 13:52:53.681 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', POST]
192017-12-13 13:52:53.681 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/api/v1/datacenters/'; against '/logout'
202017-12-13 13:52:53.681 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', PUT]
212017-12-13 13:52:53.681 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'POST /api/v1/datacenters/' doesn't match 'PUT /logout
222017-12-13 13:52:53.681 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/logout', DELETE]
232017-12-13 13:52:53.681 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'POST /api/v1/datacenters/' doesn't match 'DELETE /logout
242017-12-13 13:52:53.681 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.web.util.matcher.OrRequestMatcher : No matches found
252017-12-13 13:52:53.681 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.security.web.FilterChainProxy : /api/v1/datacenters/ at position 6 of 12 in additional filter chain; firing Filter: 'OAuth2AuthenticationProcessingFilter'
262017-12-13 13:52:53.681 DEBUG 21121 --- [http-nio-8080-exec-4] p.a.OAuth2AuthenticationProcessingFilter : Authentication success: org.springframework.security.oauth2.provider.OAuth2Authentication@79b775bc: Principal: org.springframework.security.core.userdetails.User@586034f: Username: admin; Password: [PROTECTED]; Enabled: true; AccountNonExpired: true; credentialsNonExpired: true; AccountNonLocked: true; Granted Authorities: ADMIN:*; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=10.128.255.254, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ADMIN:*
272017-12-13 13:52:53.682 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.security.web.FilterChainProxy : /api/v1/datacenters/ at position 7 of 12 in additional filter chain; firing Filter: 'RequestCacheAwareFilter'
282017-12-13 13:52:53.682 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.security.web.FilterChainProxy : /api/v1/datacenters/ at position 8 of 12 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter'
292017-12-13 13:52:53.682 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.security.web.FilterChainProxy : /api/v1/datacenters/ at position 9 of 12 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter'
302017-12-13 13:52:53.682 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.a.AnonymousAuthenticationFilter : SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.oauth2.provider.OAuth2Authentication@79b775bc: Principal: org.springframework.security.core.userdetails.User@586034f: Username: admin; Password: [PROTECTED]; Enabled: true; AccountNonExpired: true; credentialsNonExpired: true; AccountNonLocked: true; Granted Authorities: ADMIN:*; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=10.128.255.254, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ADMIN:*'
312017-12-13 13:52:53.682 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.security.web.FilterChainProxy : /api/v1/datacenters/ at position 10 of 12 in additional filter chain; firing Filter: 'SessionManagementFilter'
322017-12-13 13:52:53.682 DEBUG 21121 --- [http-nio-8080-exec-4] s.CompositeSessionAuthenticationStrategy : Delegating to org.springframework.security.web.authentication.session.ChangeSessionIdAuthenticationStrategy@4a3e1b5d
332017-12-13 13:52:53.682 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.security.web.FilterChainProxy : /api/v1/datacenters/ at position 11 of 12 in additional filter chain; firing Filter: 'ExceptionTranslationFilter'
342017-12-13 13:52:53.682 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.security.web.FilterChainProxy : /api/v1/datacenters/ at position 12 of 12 in additional filter chain; firing Filter: 'FilterSecurityInterceptor'
352017-12-13 13:52:53.682 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/api/v1/datacenters/'; against '/api/v1/components/services/register'
362017-12-13 13:52:53.682 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/api/v1/datacenters/'; against '/api/v1/security'
372017-12-13 13:52:53.682 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/api/v1/datacenters/'; against '/api/**'
382017-12-13 13:52:53.682 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.a.i.FilterSecurityInterceptor : Secure object: FilterInvocation: URL: /api/v1/datacenters/; Attributes: [#oauth2.throwOnError(#oauth2.hasScope('write'))]
392017-12-13 13:52:53.682 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.a.i.FilterSecurityInterceptor : Previously Authenticated: org.springframework.security.oauth2.provider.OAuth2Authentication@79b775bc: Principal: org.springframework.security.core.userdetails.User@586034f: Username: admin; Password: [PROTECTED]; Enabled: true; AccountNonExpired: true; credentialsNonExpired: true; AccountNonLocked: true; Granted Authorities: ADMIN:*; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=10.128.255.254, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ADMIN:*
402017-12-13 13:52:53.683 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.access.vote.AffirmativeBased : Voter: org.springframework.security.web.access.expression.WebExpressionVoter@780e312b, returned: 1
412017-12-13 13:52:53.683 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.a.i.FilterSecurityInterceptor : Authorization successful
422017-12-13 13:52:53.683 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.a.i.FilterSecurityInterceptor : RunAsManager did not change Authentication object
432017-12-13 13:52:53.683 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.security.web.FilterChainProxy : /api/v1/datacenters/ reached end of additional filter chain; proceeding with original chain
442017-12-13 13:52:53.684 DEBUG 21121 --- [http-nio-8080-exec-4] s.w.s.m.m.a.RequestMappingHandlerMapping : Looking up handler method for path /api/v1/datacenters/
452017-12-13 13:52:53.686 DEBUG 21121 --- [http-nio-8080-exec-4] s.w.s.m.m.a.RequestMappingHandlerMapping : Returning handler method [public org.openbaton.catalogue.nfvo.viminstances.BaseVimInstance org.openbaton.nfvo.api.admin.RestVimInstances.create(org.openbaton.catalogue.nfvo.viminstances.BaseVimInstance,java.lang.String) throws org.openbaton.exceptions.VimException,org.openbaton.exceptions.PluginException,org.openbaton.exceptions.EntityUnreachableException,java.io.IOException,org.openbaton.exceptions.BadRequestException,org.openbaton.exceptions.AlreadyExistingException,org.openbaton.exceptions.NotFoundException]
462017-12-13 13:52:53.692 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.web.cors.DefaultCorsProcessor : Skip CORS processing: request is from same origin
472017-12-13 13:52:53.693 DEBUG 21121 --- [http-nio-8080-exec-4] m.m.a.RequestResponseBodyMethodProcessor : Read [class org.openbaton.catalogue.nfvo.viminstances.BaseVimInstance] as "application/json;charset=UTF-8" with [org.springframework.http.converter.json.GsonHttpMessageConverter@7719b257]
482017-12-13 13:52:53.731 INFO 21121 --- [http-nio-8080-exec-4] o.openbaton.nfvo.core.api.VimManagement : Refreshing vim
492017-12-13 13:52:54.100 DEBUG 21121 --- [http-nio-8080-exec-4] .m.m.a.ExceptionHandlerExceptionResolver : Resolving exception from handler [public org.openbaton.catalogue.nfvo.viminstances.BaseVimInstance org.openbaton.nfvo.api.admin.RestVimInstances.create(org.openbaton.catalogue.nfvo.viminstances.BaseVimInstance,java.lang.String) throws org.openbaton.exceptions.VimException,org.openbaton.exceptions.PluginException,org.openbaton.exceptions.EntityUnreachableException,java.io.IOException,org.openbaton.exceptions.BadRequestException,org.openbaton.exceptions.AlreadyExistingException,org.openbaton.exceptions.NotFoundException]: org.openbaton.exceptions.VimException: Vim instance Not refreshed pop-3. Caused by: org.openbaton.exceptions.VimDriverException: controller
502017-12-13 13:52:54.100 DEBUG 21121 --- [http-nio-8080-exec-4] .m.m.a.ExceptionHandlerExceptionResolver : Invoking @ExceptionHandler method: protected org.springframework.http.ResponseEntity<java.lang.Object> org.openbaton.nfvo.api.exceptions.GlobalExceptionHandler.handleInvalidRequest(java.lang.Exception,org.springframework.web.context.request.WebRequest)
512017-12-13 13:52:54.101 ERROR 21121 --- [http-nio-8080-exec-4] o.o.n.a.e.GlobalExceptionHandler : Exception was thrown -> Return message: Vim instance Not refreshed pop-3. Caused by: org.openbaton.exceptions.VimDriverException: controller
522017-12-13 13:52:54.101 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.w.s.m.m.a.HttpEntityMethodProcessor : Written [org.openbaton.nfvo.api.exceptions.ExceptionResource@bcaddbd] as "application/json" using [org.springframework.http.converter.json.GsonHttpMessageConverter@7719b257]
532017-12-13 13:52:54.102 DEBUG 21121 --- [http-nio-8080-exec-4] o.s.s.w.a.ExceptionTranslationFilter : Chain processed normally
542017-12-13 13:52:54.102 DEBUG 21121 --- [http-nio-8080-exec-4] s.s.w.c.SecurityContextPersistenceFilter : SecurityContextHolder now cleared, as request processing completed