bwNUMjWQ

1
2
32019-08-08 12:13:15,994 INFO  [stdout] (default task-44) 12:13:15.993 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/token']
42019-08-08 12:13:15,994 INFO  [stdout] (default task-44) 12:13:15.994 [default task-44] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/1'; against '/oauth/token'
52019-08-08 12:13:15,995 INFO  [stdout] (default task-44) 12:13:15.995 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/token_key']
62019-08-08 12:13:15,995 INFO  [stdout] (default task-44) 12:13:15.995 [default task-44] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/1'; against '/oauth/token_key'
72019-08-08 12:13:15,996 INFO  [stdout] (default task-44) 12:13:15.995 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/check_token']
82019-08-08 12:13:15,996 INFO  [stdout] (default task-44) 12:13:15.996 [default task-44] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/1'; against '/oauth/check_token'
92019-08-08 12:13:15,996 INFO  [stdout] (default task-44) 12:13:15.996 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - No matches found
102019-08-08 12:13:15,997 INFO  [stdout] (default task-44) 12:13:15.996 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/1 at position 1 of 11 in additional filter chain; firing Filter: 'WebAsyncManagerIntegrationFilter'
112019-08-08 12:13:15,997 INFO  [stdout] (default task-44) 12:13:15.997 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/1 at position 2 of 11 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter'
122019-08-08 12:13:15,997 INFO  [stdout] (default task-44) 12:13:15.997 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/1 at position 3 of 11 in additional filter chain; firing Filter: 'HeaderWriterFilter'
132019-08-08 12:13:15,998 INFO  [stdout] (default task-44) 12:13:15.998 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/1 at position 4 of 11 in additional filter chain; firing Filter: 'LogoutFilter'
142019-08-08 12:13:15,998 INFO  [stdout] (default task-44) 12:13:15.998 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', GET]
152019-08-08 12:13:15,998 INFO  [stdout] (default task-44) 12:13:15.998 [default task-44] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/1'; against '/logout'
162019-08-08 12:13:15,999 INFO  [stdout] (default task-44) 12:13:15.999 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', POST]
172019-08-08 12:13:15,999 INFO  [stdout] (default task-44) 12:13:15.999 [default task-44] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/1' doesn't match 'POST /logout'
182019-08-08 12:13:16,000 INFO  [stdout] (default task-44) 12:13:15.999 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', PUT]
192019-08-08 12:13:16,000 INFO  [stdout] (default task-44) 12:13:16.000 [default task-44] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/1' doesn't match 'PUT /logout'
202019-08-08 12:13:16,000 INFO  [stdout] (default task-44) 12:13:16.000 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', DELETE]
212019-08-08 12:13:16,001 INFO  [stdout] (default task-44) 12:13:16.001 [default task-44] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/1' doesn't match 'DELETE /logout'
222019-08-08 12:13:16,001 INFO  [stdout] (default task-44) 12:13:16.001 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - No matches found
232019-08-08 12:13:16,001 INFO  [stdout] (default task-44) 12:13:16.001 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/1 at position 5 of 11 in additional filter chain; firing Filter: 'OAuth2AuthenticationProcessingFilter'
242019-08-08 12:13:16,003 INFO  [stdout] (default task-44) 12:13:16.002 [default task-44] DEBUG o.s.s.o.p.a.OAuth2AuthenticationProcessingFilter - Authentication success: org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN
252019-08-08 12:13:16,003 INFO  [stdout] (default task-44) 12:13:16.003 [default task-44] DEBUG o.s.b.a.audit.listener.AuditListener - AuditEvent [timestamp=2019-08-08T12:13:16.003373Z, principal=admin, type=AUTHENTICATION_SUCCESS, data={details=remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>}]
262019-08-08 12:13:16,004 INFO  [stdout] (default task-44) 12:13:16.003 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/1 at position 6 of 11 in additional filter chain; firing Filter: 'RequestCacheAwareFilter'
272019-08-08 12:13:16,004 INFO  [stdout] (default task-44) 12:13:16.004 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/1 at position 7 of 11 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter'
282019-08-08 12:13:16,004 INFO  [stdout] (default task-44) 12:13:16.004 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/1 at position 8 of 11 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter'
292019-08-08 12:13:16,005 INFO  [stdout] (default task-44) 12:13:16.005 [default task-44] DEBUG o.s.s.w.a.AnonymousAuthenticationFilter - SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN'
302019-08-08 12:13:16,005 INFO  [stdout] (default task-44) 12:13:16.005 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/1 at position 9 of 11 in additional filter chain; firing Filter: 'SessionManagementFilter'
312019-08-08 12:13:16,006 INFO  [stdout] (default task-44) 12:13:16.005 [default task-44] DEBUG o.s.s.w.a.s.CompositeSessionAuthenticationStrategy - Delegating to org.springframework.security.web.authentication.session.ChangeSessionIdAuthenticationStrategy@7237efab
322019-08-08 12:13:16,006 INFO  [stdout] (default task-44) 12:13:16.006 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/1 at position 10 of 11 in additional filter chain; firing Filter: 'ExceptionTranslationFilter'
332019-08-08 12:13:16,006 INFO  [stdout] (default task-44) 12:13:16.006 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/1 at position 11 of 11 in additional filter chain; firing Filter: 'FilterSecurityInterceptor'
342019-08-08 12:13:16,007 INFO  [stdout] (default task-44) 12:13:16.007 [default task-44] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/1'; against '/users/**'
352019-08-08 12:13:16,007 INFO  [stdout] (default task-44) 12:13:16.007 [default task-44] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Secure object: FilterInvocation: URL: /merchants/1; Attributes: [#oauth2.throwOnError(authenticated)]
362019-08-08 12:13:16,008 INFO  [stdout] (default task-44) 12:13:16.007 [default task-44] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Previously Authenticated: org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN
372019-08-08 12:13:16,008 INFO  [stdout] (default task-44) 12:13:16.008 [default task-44] DEBUG o.s.s.access.vote.AffirmativeBased - Voter: org.springframework.security.web.access.expression.WebExpressionVoter@73b2a9d9, returned: 1
382019-08-08 12:13:16,009 INFO  [stdout] (default task-44) 12:13:16.009 [default task-44] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Authorization successful
392019-08-08 12:13:16,009 INFO  [stdout] (default task-44) 12:13:16.009 [default task-44] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - RunAsManager did not change Authentication object
402019-08-08 12:13:16,009 INFO  [stdout] (default task-44) 12:13:16.009 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/1 reached end of additional filter chain; proceeding with original chain
412019-08-08 12:13:16,010 INFO  [stdout] (default task-44) 12:13:16.010 [default task-44] DEBUG o.s.web.servlet.DispatcherServlet - GET "/api_admin/merchants/1", parameters={}
422019-08-08 12:13:16,013 INFO  [stdout] (default task-44) 12:13:16.013 [default task-44] DEBUG o.s.w.s.m.m.a.RequestMappingHandlerMapping - Mapped to public org.springframework.http.ResponseEntity<?> org.datalis.admin.backend.restapi.MerchantController.get(java.lang.Integer)
432019-08-08 12:13:16,014 INFO  [stdout] (default task-44) 12:13:16.014 [default task-44] DEBUG o.s.o.j.s.OpenEntityManagerInViewInterceptor - Opening JPA EntityManager in OpenEntityManagerInViewInterceptor
442019-08-08 12:13:16,015 INFO  [stdout] (default task-44) 12:13:16.015 [default task-44] DEBUG o.s.orm.jpa.JpaTransactionManager - Found thread-bound EntityManager [SessionImpl(2118720687<open>)] for JPA transaction
452019-08-08 12:13:16,015 INFO  [stdout] (default task-44) 12:13:16.015 [default task-44] DEBUG o.s.orm.jpa.JpaTransactionManager - Creating new transaction with name [org.datalis.plugin.service.MerchantsServiceImpl.findById]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
462019-08-08 12:13:16,016 INFO  [stdout] (default task-44) 12:13:16.016 [default task-44] DEBUG o.h.e.t.internal.TransactionImpl - On TransactionImpl creation, JpaCompliance#isJpaTransactionComplianceEnabled == false
472019-08-08 12:13:16,017 INFO  [stdout] (default task-44) 12:13:16.016 [default task-44] DEBUG o.h.e.t.internal.TransactionImpl - begin
482019-08-08 12:13:16,018 INFO  [stdout] (default task-44) 12:13:16.018 [default task-44] DEBUG o.s.orm.jpa.JpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@69c679d1]
492019-08-08 12:13:16,019 INFO  [stdout] (default task-44) 12:13:16.018 [default task-44] DEBUG org.hibernate.SQL - select merchants0_.id as id1_22_, merchants0_.address_state as address_2_22_, merchants0_.admin_id as admin_id3_22_, merchants0_.admin_type as admin_ty4_22_, merchants0_.allowed_domains as allowed_5_22_, merchants0_.allowed_ip_address as allowed_6_22_, merchants0_.created_at as created_7_22_, merchants0_.global_key_id as global_k8_22_, merchants0_.login as login9_22_, merchants0_.merchant_owner_id as merchan10_22_, merchants0_.merchant_owner_type as merchan11_22_, merchants0_.name as name12_22_, merchants0_.pwd as pwd13_22_, merchants0_.state_raw as state_r14_22_, merchants0_.street_address as street_15_22_, merchants0_.supported_features as support16_22_, merchants0_.terminated_at as termina17_22_, merchants0_.updated_at as updated18_22_, merchants0_.zip_code as zip_cod19_22_ from merchants merchants0_ where merchants0_.id=?
502019-08-08 12:13:16,019 INFO  [stdout] (default task-44) Hibernate: select merchants0_.id as id1_22_, merchants0_.address_state as address_2_22_, merchants0_.admin_id as admin_id3_22_, merchants0_.admin_type as admin_ty4_22_, merchants0_.allowed_domains as allowed_5_22_, merchants0_.allowed_ip_address as allowed_6_22_, merchants0_.created_at as created_7_22_, merchants0_.global_key_id as global_k8_22_, merchants0_.login as login9_22_, merchants0_.merchant_owner_id as merchan10_22_, merchants0_.merchant_owner_type as merchan11_22_, merchants0_.name as name12_22_, merchants0_.pwd as pwd13_22_, merchants0_.state_raw as state_r14_22_, merchants0_.street_address as street_15_22_, merchants0_.supported_features as support16_22_, merchants0_.terminated_at as termina17_22_, merchants0_.updated_at as updated18_22_, merchants0_.zip_code as zip_cod19_22_ from merchants merchants0_ where merchants0_.id=?
512019-08-08 12:13:16,020 INFO  [stdout] (default task-44) 12:13:16.020 [default task-44] DEBUG org.hibernate.loader.Loader - Result set row: 0
522019-08-08 12:13:16,021 INFO  [stdout] (default task-44) 12:13:16.021 [default task-44] DEBUG org.hibernate.loader.Loader - Result row: EntityKey[org.datalis.plugin.entity.Merchants#1]
532019-08-08 12:13:16,022 INFO  [stdout] (default task-44) 12:13:16.022 [default task-44] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: 2018-01-11 00:00:00.0 -> 2018-01-11T00:00
542019-08-08 12:13:16,022 INFO  [stdout] (default task-44) 12:13:16.022 [default task-44] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: null -> null
552019-08-08 12:13:16,023 INFO  [stdout] (default task-44) 12:13:16.023 [default task-44] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: 2019-07-02 19:02:38.0 -> 2019-07-02T19:02:38
562019-08-08 12:13:16,024 INFO  [stdout] (default task-44) 12:13:16.024 [default task-44] DEBUG o.h.engine.internal.TwoPhaseLoad - Resolving associations for [org.datalis.plugin.entity.Merchants#1]
572019-08-08 12:13:16,024 INFO  [stdout] (default task-44) 12:13:16.024 [default task-44] DEBUG o.h.engine.internal.TwoPhaseLoad - Done materializing entity [org.datalis.plugin.entity.Merchants#1]
582019-08-08 12:13:16,025 INFO  [stdout] (default task-44) 12:13:16.025 [default task-44] DEBUG o.s.orm.jpa.JpaTransactionManager - Initiating transaction commit
592019-08-08 12:13:16,025 INFO  [stdout] (default task-44) 12:13:16.025 [default task-44] DEBUG o.s.orm.jpa.JpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(2118720687<open>)]
602019-08-08 12:13:16,026 INFO  [stdout] (default task-44) 12:13:16.026 [default task-44] DEBUG o.h.e.t.internal.TransactionImpl - committing
612019-08-08 12:13:16,027 INFO  [stdout] (default task-44) 12:13:16.026 [default task-44] DEBUG o.h.e.i.AbstractFlushingEventListener - Processing flush-time cascades
622019-08-08 12:13:16,027 INFO  [stdout] (default task-44) 12:13:16.027 [default task-44] DEBUG o.h.e.i.AbstractFlushingEventListener - Dirty checking collections
632019-08-08 12:13:16,028 INFO  [stdout] (default task-44) 12:13:16.028 [default task-44] DEBUG o.h.e.i.AbstractFlushingEventListener - Flushed: 0 insertions, 0 updates, 0 deletions to 1 objects
642019-08-08 12:13:16,028 INFO  [stdout] (default task-44) 12:13:16.028 [default task-44] DEBUG o.h.e.i.AbstractFlushingEventListener - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
652019-08-08 12:13:16,029 INFO  [stdout] (default task-44) 12:13:16.029 [default task-44] DEBUG o.h.internal.util.EntityPrinter - Listing entities:
662019-08-08 12:13:16,029 INFO  [stdout] (default task-44) 12:13:16.029 [default task-44] DEBUG o.h.internal.util.EntityPrinter - org.datalis.plugin.entity.Merchants{street_address=rthrth, allowed_domains=trhrth, address_state=rthrth, admin_type=null, created_at=2018-01-11T00:00, merchant_owner_id=null, state_raw=2, merchant_owner_type=null, login=84cc52828d3b6dd14992a0721060a22e1b245f83, zip_code=thrthrth, allowed_ip_address=orange, updated_at=2019-07-02T19:02:38, admin_id=null, supported_features=1, name=Test_Merchant_one, id=1, global_key_id=null, pwd=d40b6dba88b0e4fd876ae5463de05ed2fa053193, terminated_at=null}
672019-08-08 12:13:16,030 INFO  [stdout] (default task-44) 12:13:16.030 [default task-44] DEBUG o.s.orm.jpa.JpaTransactionManager - Not closing pre-bound JPA EntityManager after transaction
682019-08-08 12:13:16,031 INFO  [stdout] (default task-44) 12:13:16.031 [default task-44] DEBUG o.s.w.s.m.m.a.HttpEntityMethodProcessor - Using 'application/json', given [application/json, text/plain, */*] and supported [application/json, application/*+json, application/json, application/*+json, application/xml, text/xml, application/*+xml, application/xml, text/xml, application/*+xml]
692019-08-08 12:13:16,032 INFO  [stdout] (default task-44) 12:13:16.032 [default task-44] DEBUG o.s.w.s.m.m.a.HttpEntityMethodProcessor - Writing [org.datalis.admin.backend.restapi.dto.MerchantNewDTO@1ad08602]
702019-08-08 12:13:16,033 INFO  [stdout] (default task-44) 12:13:16.032 [default task-44] DEBUG o.s.s.w.h.writers.HstsHeaderWriter - Not injecting HSTS header since it did not match the requestMatcher org.springframework.security.web.header.writers.HstsHeaderWriter$SecureRequestMatcher@e398f1b
712019-08-08 12:13:16,033 INFO  [stdout] (default task-44) 12:13:16.033 [default task-44] DEBUG o.s.o.j.s.OpenEntityManagerInViewInterceptor - Closing JPA EntityManager in OpenEntityManagerInViewInterceptor
722019-08-08 12:13:16,034 INFO  [stdout] (default task-44) 12:13:16.034 [default task-44] DEBUG o.s.web.servlet.DispatcherServlet - Completed 200 OK
732019-08-08 12:13:16,035 INFO  [stdout] (default task-44) 12:13:16.035 [default task-44] DEBUG o.s.s.w.a.ExceptionTranslationFilter - Chain processed normally
742019-08-08 12:13:16,035 INFO  [stdout] (default task-44) 12:13:16.035 [default task-44] DEBUG o.s.s.w.c.SecurityContextPersistenceFilter - SecurityContextHolder now cleared, as request processing completed
75
76
77
78
792019-08-08 12:13:16,692 INFO  [stdout] (default task-44) 12:13:16.691 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/token']
802019-08-08 12:13:16,692 INFO  [stdout] (default task-44) 12:13:16.692 [default task-44] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_company_list'; against '/oauth/token'
812019-08-08 12:13:16,693 INFO  [stdout] (default task-44) 12:13:16.693 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/token_key']
822019-08-08 12:13:16,693 INFO  [stdout] (default task-44) 12:13:16.693 [default task-44] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_company_list'; against '/oauth/token_key'
832019-08-08 12:13:16,693 INFO  [stdout] (default task-44) 12:13:16.693 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/check_token']
842019-08-08 12:13:16,694 INFO  [stdout] (default task-44) 12:13:16.694 [default task-44] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_company_list'; against '/oauth/check_token'
852019-08-08 12:13:16,694 INFO  [stdout] (default task-44) 12:13:16.694 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - No matches found
862019-08-08 12:13:16,695 INFO  [stdout] (default task-44) 12:13:16.694 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list at position 1 of 11 in additional filter chain; firing Filter: 'WebAsyncManagerIntegrationFilter'
872019-08-08 12:13:16,695 INFO  [stdout] (default task-44) 12:13:16.695 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list at position 2 of 11 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter'
882019-08-08 12:13:16,695 INFO  [stdout] (default task-44) 12:13:16.695 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list at position 3 of 11 in additional filter chain; firing Filter: 'HeaderWriterFilter'
892019-08-08 12:13:16,696 INFO  [stdout] (default task-44) 12:13:16.696 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list at position 4 of 11 in additional filter chain; firing Filter: 'LogoutFilter'
902019-08-08 12:13:16,696 INFO  [stdout] (default task-44) 12:13:16.696 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', GET]
912019-08-08 12:13:16,697 INFO  [stdout] (default task-44) 12:13:16.696 [default task-44] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_company_list'; against '/logout'
922019-08-08 12:13:16,697 INFO  [stdout] (default task-44) 12:13:16.697 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', POST]
932019-08-08 12:13:16,697 INFO  [stdout] (default task-44) 12:13:16.697 [default task-44] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/merchant_company_list' doesn't match 'POST /logout'
942019-08-08 12:13:16,698 INFO  [stdout] (default task-44) 12:13:16.698 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', PUT]
952019-08-08 12:13:16,698 INFO  [stdout] (default task-44) 12:13:16.698 [default task-44] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/merchant_company_list' doesn't match 'PUT /logout'
962019-08-08 12:13:16,699 INFO  [stdout] (default task-44) 12:13:16.698 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', DELETE]
972019-08-08 12:13:16,699 INFO  [stdout] (default task-44) 12:13:16.699 [default task-44] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/merchant_company_list' doesn't match 'DELETE /logout'
982019-08-08 12:13:16,699 INFO  [stdout] (default task-44) 12:13:16.699 [default task-44] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - No matches found
992019-08-08 12:13:16,700 INFO  [stdout] (default task-44) 12:13:16.700 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list at position 5 of 11 in additional filter chain; firing Filter: 'OAuth2AuthenticationProcessingFilter'
1002019-08-08 12:13:16,701 INFO  [stdout] (default task-44) 12:13:16.701 [default task-44] DEBUG o.s.s.o.p.a.OAuth2AuthenticationProcessingFilter - Authentication success: org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN
1012019-08-08 12:13:16,701 INFO  [stdout] (default task-44) 12:13:16.701 [default task-44] DEBUG o.s.b.a.audit.listener.AuditListener - AuditEvent [timestamp=2019-08-08T12:13:16.701688Z, principal=admin, type=AUTHENTICATION_SUCCESS, data={details=remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>}]
1022019-08-08 12:13:16,702 INFO  [stdout] (default task-44) 12:13:16.702 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list at position 6 of 11 in additional filter chain; firing Filter: 'RequestCacheAwareFilter'
1032019-08-08 12:13:16,702 INFO  [stdout] (default task-44) 12:13:16.702 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list at position 7 of 11 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter'
1042019-08-08 12:13:16,703 INFO  [stdout] (default task-44) 12:13:16.702 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list at position 8 of 11 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter'
1052019-08-08 12:13:16,703 INFO  [stdout] (default task-44) 12:13:16.703 [default task-44] DEBUG o.s.s.w.a.AnonymousAuthenticationFilter - SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN'
1062019-08-08 12:13:16,703 INFO  [stdout] (default task-44) 12:13:16.703 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list at position 9 of 11 in additional filter chain; firing Filter: 'SessionManagementFilter'
1072019-08-08 12:13:16,704 INFO  [stdout] (default task-44) 12:13:16.704 [default task-44] DEBUG o.s.s.w.a.s.CompositeSessionAuthenticationStrategy - Delegating to org.springframework.security.web.authentication.session.ChangeSessionIdAuthenticationStrategy@7237efab
1082019-08-08 12:13:16,704 INFO  [stdout] (default task-44) 12:13:16.704 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list at position 10 of 11 in additional filter chain; firing Filter: 'ExceptionTranslationFilter'
1092019-08-08 12:13:16,705 INFO  [stdout] (default task-44) 12:13:16.705 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list at position 11 of 11 in additional filter chain; firing Filter: 'FilterSecurityInterceptor'
1102019-08-08 12:13:16,706 INFO  [stdout] (default task-44) 12:13:16.705 [default task-44] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_company_list'; against '/users/**'
1112019-08-08 12:13:16,706 INFO  [stdout] (default task-44) 12:13:16.706 [default task-44] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Secure object: FilterInvocation: URL: /merchants/merchant_company_list; Attributes: [#oauth2.throwOnError(authenticated)]
1122019-08-08 12:13:16,707 INFO  [stdout] (default task-44) 12:13:16.706 [default task-44] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Previously Authenticated: org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN
1132019-08-08 12:13:16,707 INFO  [stdout] (default task-44) 12:13:16.707 [default task-44] DEBUG o.s.s.access.vote.AffirmativeBased - Voter: org.springframework.security.web.access.expression.WebExpressionVoter@73b2a9d9, returned: 1
1142019-08-08 12:13:16,708 INFO  [stdout] (default task-44) 12:13:16.708 [default task-44] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Authorization successful
1152019-08-08 12:13:16,709 INFO  [stdout] (default task-46) 12:13:16.709 [default task-46] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/token']
1162019-08-08 12:13:16,709 INFO  [stdout] (default task-44) 12:13:16.709 [default task-44] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - RunAsManager did not change Authentication object
1172019-08-08 12:13:16,709 INFO  [stdout] (default task-46) 12:13:16.709 [default task-46] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_company_list/1'; against '/oauth/token'
1182019-08-08 12:13:16,710 INFO  [stdout] (default task-44) 12:13:16.709 [default task-44] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list reached end of additional filter chain; proceeding with original chain
1192019-08-08 12:13:16,710 INFO  [stdout] (default task-46) 12:13:16.710 [default task-46] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/token_key']
1202019-08-08 12:13:16,711 INFO  [stdout] (default task-44) 12:13:16.711 [default task-44] DEBUG o.s.web.servlet.DispatcherServlet - GET "/api_admin/merchants/merchant_company_list", parameters={}
1212019-08-08 12:13:16,712 INFO  [stdout] (default task-46) 12:13:16.711 [default task-46] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_company_list/1'; against '/oauth/token_key'
1222019-08-08 12:13:16,712 INFO  [stdout] (default task-47) 12:13:16.712 [default task-47] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/token']
1232019-08-08 12:13:16,713 INFO  [stdout] (default task-47) 12:13:16.713 [default task-47] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_user_list'; against '/oauth/token'
1242019-08-08 12:13:16,714 INFO  [stdout] (default task-46) 12:13:16.712 [default task-46] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/check_token']
1252019-08-08 12:13:16,714 INFO  [stdout] (default task-44) 12:13:16.713 [default task-44] DEBUG o.s.w.s.m.m.a.RequestMappingHandlerMapping - Mapped to public org.springframework.http.ResponseEntity<java.util.List<org.datalis.plugin.dto.CompanyDTO>> org.datalis.admin.backend.restapi.MerchantController.getBusinessesList() throws java.lang.NumberFormatException,java.lang.Exception
1262019-08-08 12:13:16,715 INFO  [stdout] (default task-47) 12:13:16.714 [default task-47] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/token_key']
1272019-08-08 12:13:16,715 INFO  [stdout] (default task-48) 12:13:16.714 [default task-48] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/token']
1282019-08-08 12:13:16,716 INFO  [stdout] (default task-46) 12:13:16.714 [default task-46] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_company_list/1'; against '/oauth/check_token'
1292019-08-08 12:13:16,717 INFO  [stdout] (default task-44) 12:13:16.715 [default task-44] DEBUG o.s.o.j.s.OpenEntityManagerInViewInterceptor - Opening JPA EntityManager in OpenEntityManagerInViewInterceptor
1302019-08-08 12:13:16,717 INFO  [stdout] (default task-47) 12:13:16.715 [default task-47] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_user_list'; against '/oauth/token_key'
1312019-08-08 12:13:16,718 INFO  [stdout] (default task-47) 12:13:16.718 [default task-47] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/check_token']
1322019-08-08 12:13:16,718 INFO  [stdout] (default task-49) 12:13:16.715 [default task-49] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/token']
1332019-08-08 12:13:16,719 INFO  [stdout] (default task-49) 12:13:16.718 [default task-49] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/notification_urls/1'; against '/oauth/token'
1342019-08-08 12:13:16,719 INFO  [stdout] (default task-50) 12:13:16.716 [default task-50] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/token']
1352019-08-08 12:13:16,719 INFO  [stdout] (default task-48) 12:13:16.716 [default task-48] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_user_list/1'; against '/oauth/token'
1362019-08-08 12:13:16,720 INFO  [stdout] (default task-46) 12:13:16.717 [default task-46] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - No matches found
1372019-08-08 12:13:16,720 INFO  [stdout] (default task-44) 12:13:16.718 [default task-44] DEBUG o.s.orm.jpa.JpaTransactionManager - Found thread-bound EntityManager [SessionImpl(228965287<open>)] for JPA transaction
1382019-08-08 12:13:16,721 INFO  [stdout] (default task-47) 12:13:16.718 [default task-47] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_user_list'; against '/oauth/check_token'
1392019-08-08 12:13:16,721 INFO  [stdout] (default task-49) 12:13:16.719 [default task-49] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/token_key']
1402019-08-08 12:13:16,722 INFO  [stdout] (default task-50) 12:13:16.719 [default task-50] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/notification_types'; against '/oauth/token'
1412019-08-08 12:13:16,722 INFO  [stdout] (default task-48) 12:13:16.720 [default task-48] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/token_key']
1422019-08-08 12:13:16,722 INFO  [stdout] (default task-46) 12:13:16.720 [default task-46] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list/1 at position 1 of 11 in additional filter chain; firing Filter: 'WebAsyncManagerIntegrationFilter'
1432019-08-08 12:13:16,723 INFO  [stdout] (default task-44) 12:13:16.721 [default task-44] DEBUG o.s.orm.jpa.JpaTransactionManager - Creating new transaction with name [org.datalis.plugin.service.CompanyServiceImpl.findAll]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
1442019-08-08 12:13:16,723 INFO  [stdout] (default task-47) 12:13:16.721 [default task-47] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - No matches found
1452019-08-08 12:13:16,724 INFO  [stdout] (default task-49) 12:13:16.722 [default task-49] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/notification_urls/1'; against '/oauth/token_key'
1462019-08-08 12:13:16,724 INFO  [stdout] (default task-50) 12:13:16.722 [default task-50] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/token_key']
1472019-08-08 12:13:16,725 INFO  [stdout] (default task-48) 12:13:16.722 [default task-48] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_user_list/1'; against '/oauth/token_key'
1482019-08-08 12:13:16,725 INFO  [stdout] (default task-46) 12:13:16.723 [default task-46] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list/1 at position 2 of 11 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter'
1492019-08-08 12:13:16,725 INFO  [stdout] (default task-44) 12:13:16.723 [default task-44] DEBUG o.h.e.t.internal.TransactionImpl - On TransactionImpl creation, JpaCompliance#isJpaTransactionComplianceEnabled == false
1502019-08-08 12:13:16,726 INFO  [stdout] (default task-47) 12:13:16.724 [default task-47] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list at position 1 of 11 in additional filter chain; firing Filter: 'WebAsyncManagerIntegrationFilter'
1512019-08-08 12:13:16,726 INFO  [stdout] (default task-49) 12:13:16.724 [default task-49] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/check_token']
1522019-08-08 12:13:16,727 INFO  [stdout] (default task-50) 12:13:16.724 [default task-50] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/notification_types'; against '/oauth/token_key'
1532019-08-08 12:13:16,727 INFO  [stdout] (default task-48) 12:13:16.725 [default task-48] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/check_token']
1542019-08-08 12:13:16,728 INFO  [stdout] (default task-46) 12:13:16.725 [default task-46] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list/1 at position 3 of 11 in additional filter chain; firing Filter: 'HeaderWriterFilter'
1552019-08-08 12:13:16,728 INFO  [stdout] (default task-44) 12:13:16.726 [default task-44] DEBUG o.h.e.t.internal.TransactionImpl - begin
1562019-08-08 12:13:16,729 INFO  [stdout] (default task-47) 12:13:16.726 [default task-47] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list at position 2 of 11 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter'
1572019-08-08 12:13:16,729 INFO  [stdout] (default task-49) 12:13:16.727 [default task-49] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/notification_urls/1'; against '/oauth/check_token'
1582019-08-08 12:13:16,729 INFO  [stdout] (default task-50) 12:13:16.727 [default task-50] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/oauth/check_token']
1592019-08-08 12:13:16,730 INFO  [stdout] (default task-48) 12:13:16.727 [default task-48] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_user_list/1'; against '/oauth/check_token'
1602019-08-08 12:13:16,730 INFO  [stdout] (default task-46) 12:13:16.728 [default task-46] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list/1 at position 4 of 11 in additional filter chain; firing Filter: 'LogoutFilter'
1612019-08-08 12:13:16,731 INFO  [stdout] (default task-46) 12:13:16.731 [default task-46] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', GET]
1622019-08-08 12:13:16,731 INFO  [stdout] (default task-47) 12:13:16.729 [default task-47] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list at position 3 of 11 in additional filter chain; firing Filter: 'HeaderWriterFilter'
1632019-08-08 12:13:16,732 INFO  [stdout] (default task-44) 12:13:16.729 [default task-44] DEBUG o.s.orm.jpa.JpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@298dd347]
1642019-08-08 12:13:16,732 INFO  [stdout] (default task-49) 12:13:16.729 [default task-49] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - No matches found
1652019-08-08 12:13:16,732 INFO  [stdout] (default task-50) 12:13:16.730 [default task-50] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/notification_types'; against '/oauth/check_token'
1662019-08-08 12:13:16,733 INFO  [stdout] (default task-48) 12:13:16.730 [default task-48] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - No matches found
1672019-08-08 12:13:16,733 INFO  [stdout] (default task-46) 12:13:16.731 [default task-46] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_company_list/1'; against '/logout'
1682019-08-08 12:13:16,734 INFO  [stdout] (default task-46) 12:13:16.734 [default task-46] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', POST]
1692019-08-08 12:13:16,734 INFO  [stdout] (default task-47) 12:13:16.731 [default task-47] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list at position 4 of 11 in additional filter chain; firing Filter: 'LogoutFilter'
1702019-08-08 12:13:16,735 INFO  [stdout] (default task-44) 12:13:16.732 [default task-44] DEBUG org.hibernate.SQL - select companies0_.id as id1_8_, companies0_.created_at as created_2_8_, companies0_.name as name3_8_, companies0_.updated_at as updated_4_8_ from companies companies0_
1712019-08-08 12:13:16,735 INFO  [stdout] (default task-49) 12:13:16.732 [default task-49] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_urls/1 at position 1 of 11 in additional filter chain; firing Filter: 'WebAsyncManagerIntegrationFilter'
1722019-08-08 12:13:16,735 INFO  [stdout] (default task-44) Hibernate: select companies0_.id as id1_8_, companies0_.created_at as created_2_8_, companies0_.name as name3_8_, companies0_.updated_at as updated_4_8_ from companies companies0_
1732019-08-08 12:13:16,736 INFO  [stdout] (default task-50) 12:13:16.733 [default task-50] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - No matches found
1742019-08-08 12:13:16,736 INFO  [stdout] (default task-50) 12:13:16.736 [default task-50] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_types at position 1 of 11 in additional filter chain; firing Filter: 'WebAsyncManagerIntegrationFilter'
1752019-08-08 12:13:16,737 INFO  [stdout] (default task-48) 12:13:16.733 [default task-48] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list/1 at position 1 of 11 in additional filter chain; firing Filter: 'WebAsyncManagerIntegrationFilter'
1762019-08-08 12:13:16,737 INFO  [stdout] (default task-46) 12:13:16.734 [default task-46] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/merchant_company_list/1' doesn't match 'POST /logout'
1772019-08-08 12:13:16,738 INFO  [stdout] (default task-47) 12:13:16.734 [default task-47] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', GET]
1782019-08-08 12:13:16,738 INFO  [stdout] (default task-49) 12:13:16.736 [default task-49] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_urls/1 at position 2 of 11 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter'
1792019-08-08 12:13:16,739 INFO  [stdout] (default task-44) 12:13:16.737 [default task-44] DEBUG org.hibernate.loader.Loader - Result set row: 0
1802019-08-08 12:13:16,739 INFO  [stdout] (default task-50) 12:13:16.737 [default task-50] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_types at position 2 of 11 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter'
1812019-08-08 12:13:16,740 INFO  [stdout] (default task-48) 12:13:16.737 [default task-48] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list/1 at position 2 of 11 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter'
1822019-08-08 12:13:16,740 INFO  [stdout] (default task-46) 12:13:16.737 [default task-46] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', PUT]
1832019-08-08 12:13:16,741 INFO  [stdout] (default task-46) 12:13:16.740 [default task-46] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/merchant_company_list/1' doesn't match 'PUT /logout'
1842019-08-08 12:13:16,741 INFO  [stdout] (default task-49) 12:13:16.739 [default task-49] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_urls/1 at position 3 of 11 in additional filter chain; firing Filter: 'HeaderWriterFilter'
1852019-08-08 12:13:16,741 INFO  [stdout] (default task-49) 12:13:16.741 [default task-49] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_urls/1 at position 4 of 11 in additional filter chain; firing Filter: 'LogoutFilter'
1862019-08-08 12:13:16,742 INFO  [stdout] (default task-49) 12:13:16.742 [default task-49] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', GET]
1872019-08-08 12:13:16,742 INFO  [stdout] (default task-49) 12:13:16.742 [default task-49] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/notification_urls/1'; against '/logout'
1882019-08-08 12:13:16,743 INFO  [stdout] (default task-49) 12:13:16.742 [default task-49] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', POST]
1892019-08-08 12:13:16,743 INFO  [stdout] (default task-49) 12:13:16.743 [default task-49] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/notification_urls/1' doesn't match 'POST /logout'
1902019-08-08 12:13:16,743 INFO  [stdout] (default task-49) 12:13:16.743 [default task-49] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', PUT]
1912019-08-08 12:13:16,744 INFO  [stdout] (default task-49) 12:13:16.744 [default task-49] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/notification_urls/1' doesn't match 'PUT /logout'
1922019-08-08 12:13:16,744 INFO  [stdout] (default task-49) 12:13:16.744 [default task-49] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', DELETE]
1932019-08-08 12:13:16,745 INFO  [stdout] (default task-49) 12:13:16.744 [default task-49] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/notification_urls/1' doesn't match 'DELETE /logout'
1942019-08-08 12:13:16,745 INFO  [stdout] (default task-47) 12:13:16.739 [default task-47] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_user_list'; against '/logout'
1952019-08-08 12:13:16,745 INFO  [stdout] (default task-44) 12:13:16.739 [default task-44] DEBUG org.hibernate.loader.Loader - Result row: EntityKey[org.datalis.plugin.entity.Companies#4]
1962019-08-08 12:13:16,746 INFO  [stdout] (default task-50) 12:13:16.740 [default task-50] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_types at position 3 of 11 in additional filter chain; firing Filter: 'HeaderWriterFilter'
1972019-08-08 12:13:16,747 INFO  [stdout] (default task-46) 12:13:16.741 [default task-46] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', DELETE]
1982019-08-08 12:13:16,747 INFO  [stdout] (default task-46) 12:13:16.747 [default task-46] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/merchant_company_list/1' doesn't match 'DELETE /logout'
1992019-08-08 12:13:16,747 INFO  [stdout] (default task-46) 12:13:16.747 [default task-46] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - No matches found
2002019-08-08 12:13:16,748 INFO  [stdout] (default task-46) 12:13:16.748 [default task-46] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list/1 at position 5 of 11 in additional filter chain; firing Filter: 'OAuth2AuthenticationProcessingFilter'
2012019-08-08 12:13:16,748 INFO  [stdout] (default task-48) 12:13:16.741 [default task-48] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list/1 at position 3 of 11 in additional filter chain; firing Filter: 'HeaderWriterFilter'
2022019-08-08 12:13:16,749 INFO  [stdout] (default task-48) 12:13:16.749 [default task-48] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list/1 at position 4 of 11 in additional filter chain; firing Filter: 'LogoutFilter'
2032019-08-08 12:13:16,750 INFO  [stdout] (default task-48) 12:13:16.749 [default task-48] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', GET]
2042019-08-08 12:13:16,750 INFO  [stdout] (default task-48) 12:13:16.750 [default task-48] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_user_list/1'; against '/logout'
2052019-08-08 12:13:16,751 INFO  [stdout] (default task-48) 12:13:16.751 [default task-48] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', POST]
2062019-08-08 12:13:16,751 INFO  [stdout] (default task-48) 12:13:16.751 [default task-48] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/merchant_user_list/1' doesn't match 'POST /logout'
2072019-08-08 12:13:16,752 INFO  [stdout] (default task-48) 12:13:16.752 [default task-48] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', PUT]
2082019-08-08 12:13:16,752 INFO  [stdout] (default task-48) 12:13:16.752 [default task-48] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/merchant_user_list/1' doesn't match 'PUT /logout'
2092019-08-08 12:13:16,753 INFO  [stdout] (default task-48) 12:13:16.752 [default task-48] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', DELETE]
2102019-08-08 12:13:16,753 INFO  [stdout] (default task-48) 12:13:16.753 [default task-48] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/merchant_user_list/1' doesn't match 'DELETE /logout'
2112019-08-08 12:13:16,753 INFO  [stdout] (default task-48) 12:13:16.753 [default task-48] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - No matches found
2122019-08-08 12:13:16,754 INFO  [stdout] (default task-48) 12:13:16.754 [default task-48] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list/1 at position 5 of 11 in additional filter chain; firing Filter: 'OAuth2AuthenticationProcessingFilter'
2132019-08-08 12:13:16,755 INFO  [stdout] (default task-49) 12:13:16.745 [default task-49] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - No matches found
2142019-08-08 12:13:16,755 INFO  [stdout] (default task-49) 12:13:16.755 [default task-49] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_urls/1 at position 5 of 11 in additional filter chain; firing Filter: 'OAuth2AuthenticationProcessingFilter'
2152019-08-08 12:13:16,756 INFO  [stdout] (default task-49) 12:13:16.756 [default task-49] DEBUG o.s.s.o.p.a.OAuth2AuthenticationProcessingFilter - Authentication success: org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN
2162019-08-08 12:13:16,756 INFO  [stdout] (default task-47) 12:13:16.745 [default task-47] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', POST]
2172019-08-08 12:13:16,757 INFO  [stdout] (default task-47) 12:13:16.757 [default task-47] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/merchant_user_list' doesn't match 'POST /logout'
2182019-08-08 12:13:16,757 INFO  [stdout] (default task-44) 12:13:16.746 [default task-44] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: 2019-05-27 14:47:38.0 -> 2019-05-27T14:47:38
2192019-08-08 12:13:16,758 INFO  [stdout] (default task-50) 12:13:16.746 [default task-50] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_types at position 4 of 11 in additional filter chain; firing Filter: 'LogoutFilter'
2202019-08-08 12:13:16,759 INFO  [stdout] (default task-46) 12:13:16.749 [default task-46] DEBUG o.s.s.o.p.a.OAuth2AuthenticationProcessingFilter - Authentication success: org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN
2212019-08-08 12:13:16,759 INFO  [stdout] (default task-48) 12:13:16.755 [default task-48] DEBUG o.s.s.o.p.a.OAuth2AuthenticationProcessingFilter - Authentication success: org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN
2222019-08-08 12:13:16,760 INFO  [stdout] (default task-47) 12:13:16.757 [default task-47] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', PUT]
2232019-08-08 12:13:16,760 INFO  [stdout] (default task-47) 12:13:16.760 [default task-47] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/merchant_user_list' doesn't match 'PUT /logout'
2242019-08-08 12:13:16,760 INFO  [stdout] (default task-49) 12:13:16.757 [default task-49] DEBUG o.s.b.a.audit.listener.AuditListener - AuditEvent [timestamp=2019-08-08T12:13:16.757527Z, principal=admin, type=AUTHENTICATION_SUCCESS, data={details=remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>}]
2252019-08-08 12:13:16,761 INFO  [stdout] (default task-44) 12:13:16.758 [default task-44] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: 2019-07-20 12:49:09.0 -> 2019-07-20T12:49:09
2262019-08-08 12:13:16,761 INFO  [stdout] (default task-50) 12:13:16.758 [default task-50] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', GET]
2272019-08-08 12:13:16,762 INFO  [stdout] (default task-46) 12:13:16.759 [default task-46] DEBUG o.s.b.a.audit.listener.AuditListener - AuditEvent [timestamp=2019-08-08T12:13:16.759437Z, principal=admin, type=AUTHENTICATION_SUCCESS, data={details=remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>}]
2282019-08-08 12:13:16,762 INFO  [stdout] (default task-48) 12:13:16.759 [default task-48] DEBUG o.s.b.a.audit.listener.AuditListener - AuditEvent [timestamp=2019-08-08T12:13:16.759876Z, principal=admin, type=AUTHENTICATION_SUCCESS, data={details=remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>}]
2292019-08-08 12:13:16,763 INFO  [stdout] (default task-47) 12:13:16.760 [default task-47] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', DELETE]
2302019-08-08 12:13:16,763 INFO  [stdout] (default task-49) 12:13:16.761 [default task-49] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_urls/1 at position 6 of 11 in additional filter chain; firing Filter: 'RequestCacheAwareFilter'
2312019-08-08 12:13:16,764 INFO  [stdout] (default task-49) 12:13:16.763 [default task-49] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_urls/1 at position 7 of 11 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter'
2322019-08-08 12:13:16,764 INFO  [stdout] (default task-44) 12:13:16.761 [default task-44] DEBUG org.hibernate.loader.Loader - Result set row: 1
2332019-08-08 12:13:16,764 INFO  [stdout] (default task-50) 12:13:16.762 [default task-50] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/notification_types'; against '/logout'
2342019-08-08 12:13:16,765 INFO  [stdout] (default task-46) 12:13:16.762 [default task-46] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list/1 at position 6 of 11 in additional filter chain; firing Filter: 'RequestCacheAwareFilter'
2352019-08-08 12:13:16,765 INFO  [stdout] (default task-48) 12:13:16.763 [default task-48] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list/1 at position 6 of 11 in additional filter chain; firing Filter: 'RequestCacheAwareFilter'
2362019-08-08 12:13:16,766 INFO  [stdout] (default task-47) 12:13:16.763 [default task-47] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/merchant_user_list' doesn't match 'DELETE /logout'
2372019-08-08 12:13:16,766 INFO  [stdout] (default task-49) 12:13:16.764 [default task-49] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_urls/1 at position 8 of 11 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter'
2382019-08-08 12:13:16,767 INFO  [stdout] (default task-44) 12:13:16.764 [default task-44] DEBUG org.hibernate.loader.Loader - Result row: EntityKey[org.datalis.plugin.entity.Companies#5]
2392019-08-08 12:13:16,768 INFO  [stdout] (default task-50) 12:13:16.765 [default task-50] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', POST]
2402019-08-08 12:13:16,769 INFO  [stdout] (default task-46) 12:13:16.765 [default task-46] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list/1 at position 7 of 11 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter'
2412019-08-08 12:13:16,769 INFO  [stdout] (default task-48) 12:13:16.766 [default task-48] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list/1 at position 7 of 11 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter'
2422019-08-08 12:13:16,770 INFO  [stdout] (default task-47) 12:13:16.766 [default task-47] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - No matches found
2432019-08-08 12:13:16,770 INFO  [stdout] (default task-49) 12:13:16.766 [default task-49] DEBUG o.s.s.w.a.AnonymousAuthenticationFilter - SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN'
2442019-08-08 12:13:16,770 INFO  [stdout] (default task-49) 12:13:16.770 [default task-49] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_urls/1 at position 9 of 11 in additional filter chain; firing Filter: 'SessionManagementFilter'
2452019-08-08 12:13:16,771 INFO  [stdout] (default task-44) 12:13:16.768 [default task-44] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: 2019-05-27 21:34:57.0 -> 2019-05-27T21:34:57
2462019-08-08 12:13:16,771 INFO  [stdout] (default task-50) 12:13:16.769 [default task-50] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/notification_types' doesn't match 'POST /logout'
2472019-08-08 12:13:16,772 INFO  [stdout] (default task-46) 12:13:16.769 [default task-46] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list/1 at position 8 of 11 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter'
2482019-08-08 12:13:16,772 INFO  [stdout] (default task-48) 12:13:16.770 [default task-48] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list/1 at position 8 of 11 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter'
2492019-08-08 12:13:16,773 INFO  [stdout] (default task-47) 12:13:16.770 [default task-47] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list at position 5 of 11 in additional filter chain; firing Filter: 'OAuth2AuthenticationProcessingFilter'
2502019-08-08 12:13:16,773 INFO  [stdout] (default task-49) 12:13:16.771 [default task-49] DEBUG o.s.s.w.a.s.CompositeSessionAuthenticationStrategy - Delegating to org.springframework.security.web.authentication.session.ChangeSessionIdAuthenticationStrategy@7237efab
2512019-08-08 12:13:16,774 INFO  [stdout] (default task-49) 12:13:16.773 [default task-49] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_urls/1 at position 10 of 11 in additional filter chain; firing Filter: 'ExceptionTranslationFilter'
2522019-08-08 12:13:16,774 INFO  [stdout] (default task-44) 12:13:16.771 [default task-44] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: null -> null
2532019-08-08 12:13:16,774 INFO  [stdout] (default task-50) 12:13:16.772 [default task-50] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', PUT]
2542019-08-08 12:13:16,775 INFO  [stdout] (default task-50) 12:13:16.775 [default task-50] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/notification_types' doesn't match 'PUT /logout'
2552019-08-08 12:13:16,775 INFO  [stdout] (default task-46) 12:13:16.772 [default task-46] DEBUG o.s.s.w.a.AnonymousAuthenticationFilter - SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN'
2562019-08-08 12:13:16,776 INFO  [stdout] (default task-48) 12:13:16.773 [default task-48] DEBUG o.s.s.w.a.AnonymousAuthenticationFilter - SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN'
2572019-08-08 12:13:16,777 INFO  [stdout] (default task-47) 12:13:16.774 [default task-47] DEBUG o.s.s.o.p.a.OAuth2AuthenticationProcessingFilter - Authentication success: org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN
2582019-08-08 12:13:16,777 INFO  [stdout] (default task-49) 12:13:16.774 [default task-49] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_urls/1 at position 11 of 11 in additional filter chain; firing Filter: 'FilterSecurityInterceptor'
2592019-08-08 12:13:16,778 INFO  [stdout] (default task-44) 12:13:16.774 [default task-44] DEBUG org.hibernate.loader.Loader - Result set row: 2
2602019-08-08 12:13:16,778 INFO  [stdout] (default task-50) 12:13:16.775 [default task-50] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - Trying to match using Ant [pattern='/logout', DELETE]
2612019-08-08 12:13:16,779 INFO  [stdout] (default task-46) 12:13:16.776 [default task-46] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list/1 at position 9 of 11 in additional filter chain; firing Filter: 'SessionManagementFilter'
2622019-08-08 12:13:16,779 INFO  [stdout] (default task-48) 12:13:16.777 [default task-48] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list/1 at position 9 of 11 in additional filter chain; firing Filter: 'SessionManagementFilter'
2632019-08-08 12:13:16,780 INFO  [stdout] (default task-47) 12:13:16.777 [default task-47] DEBUG o.s.b.a.audit.listener.AuditListener - AuditEvent [timestamp=2019-08-08T12:13:16.777823Z, principal=admin, type=AUTHENTICATION_SUCCESS, data={details=remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>}]
2642019-08-08 12:13:16,780 INFO  [stdout] (default task-49) 12:13:16.778 [default task-49] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/notification_urls/1'; against '/users/**'
2652019-08-08 12:13:16,781 INFO  [stdout] (default task-44) 12:13:16.778 [default task-44] DEBUG org.hibernate.loader.Loader - Result row: EntityKey[org.datalis.plugin.entity.Companies#6]
2662019-08-08 12:13:16,781 INFO  [stdout] (default task-50) 12:13:16.779 [default task-50] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Request 'GET /merchants/notification_types' doesn't match 'DELETE /logout'
2672019-08-08 12:13:16,782 INFO  [stdout] (default task-46) 12:13:16.779 [default task-46] DEBUG o.s.s.w.a.s.CompositeSessionAuthenticationStrategy - Delegating to org.springframework.security.web.authentication.session.ChangeSessionIdAuthenticationStrategy@7237efab
2682019-08-08 12:13:16,782 INFO  [stdout] (default task-48) 12:13:16.780 [default task-48] DEBUG o.s.s.w.a.s.CompositeSessionAuthenticationStrategy - Delegating to org.springframework.security.web.authentication.session.ChangeSessionIdAuthenticationStrategy@7237efab
2692019-08-08 12:13:16,783 INFO  [stdout] (default task-47) 12:13:16.780 [default task-47] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list at position 6 of 11 in additional filter chain; firing Filter: 'RequestCacheAwareFilter'
2702019-08-08 12:13:16,783 INFO  [stdout] (default task-49) 12:13:16.780 [default task-49] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Secure object: FilterInvocation: URL: /merchants/notification_urls/1; Attributes: [#oauth2.throwOnError(authenticated)]
2712019-08-08 12:13:16,783 INFO  [stdout] (default task-44) 12:13:16.781 [default task-44] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: 2019-07-12 22:47:17.0 -> 2019-07-12T22:47:17
2722019-08-08 12:13:16,784 INFO  [stdout] (default task-50) 12:13:16.782 [default task-50] DEBUG o.s.s.w.u.matcher.OrRequestMatcher - No matches found
2732019-08-08 12:13:16,784 INFO  [stdout] (default task-46) 12:13:16.782 [default task-46] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list/1 at position 10 of 11 in additional filter chain; firing Filter: 'ExceptionTranslationFilter'
2742019-08-08 12:13:16,785 INFO  [stdout] (default task-48) 12:13:16.782 [default task-48] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list/1 at position 10 of 11 in additional filter chain; firing Filter: 'ExceptionTranslationFilter'
2752019-08-08 12:13:16,785 INFO  [stdout] (default task-47) 12:13:16.783 [default task-47] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list at position 7 of 11 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter'
2762019-08-08 12:13:16,786 INFO  [stdout] (default task-49) 12:13:16.783 [default task-49] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Previously Authenticated: org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN
2772019-08-08 12:13:16,786 INFO  [stdout] (default task-44) 12:13:16.784 [default task-44] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: 2019-07-20 17:05:42.0 -> 2019-07-20T17:05:42
2782019-08-08 12:13:16,787 INFO  [stdout] (default task-50) 12:13:16.784 [default task-50] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_types at position 5 of 11 in additional filter chain; firing Filter: 'OAuth2AuthenticationProcessingFilter'
2792019-08-08 12:13:16,787 INFO  [stdout] (default task-46) 12:13:16.785 [default task-46] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list/1 at position 11 of 11 in additional filter chain; firing Filter: 'FilterSecurityInterceptor'
2802019-08-08 12:13:16,788 INFO  [stdout] (default task-48) 12:13:16.785 [default task-48] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list/1 at position 11 of 11 in additional filter chain; firing Filter: 'FilterSecurityInterceptor'
2812019-08-08 12:13:16,788 INFO  [stdout] (default task-47) 12:13:16.786 [default task-47] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list at position 8 of 11 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter'
2822019-08-08 12:13:16,788 INFO  [stdout] (default task-49) 12:13:16.786 [default task-49] DEBUG o.s.s.access.vote.AffirmativeBased - Voter: org.springframework.security.web.access.expression.WebExpressionVoter@73b2a9d9, returned: 1
2832019-08-08 12:13:16,789 INFO  [stdout] (default task-49) 12:13:16.789 [default task-49] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Authorization successful
2842019-08-08 12:13:16,789 INFO  [stdout] (default task-49) 12:13:16.789 [default task-49] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - RunAsManager did not change Authentication object
2852019-08-08 12:13:16,790 INFO  [stdout] (default task-49) 12:13:16.790 [default task-49] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_urls/1 reached end of additional filter chain; proceeding with original chain
2862019-08-08 12:13:16,790 INFO  [stdout] (default task-44) 12:13:16.787 [default task-44] DEBUG org.hibernate.loader.Loader - Result set row: 3
2872019-08-08 12:13:16,791 INFO  [stdout] (default task-46) 12:13:16.787 [default task-46] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_company_list/1'; against '/users/**'
2882019-08-08 12:13:16,791 INFO  [stdout] (default task-50) 12:13:16.788 [default task-50] DEBUG o.s.s.o.p.a.OAuth2AuthenticationProcessingFilter - Authentication success: org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN
2892019-08-08 12:13:16,792 INFO  [stdout] (default task-48) 12:13:16.788 [default task-48] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_user_list/1'; against '/users/**'
2902019-08-08 12:13:16,792 INFO  [stdout] (default task-47) 12:13:16.788 [default task-47] DEBUG o.s.s.w.a.AnonymousAuthenticationFilter - SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN'
2912019-08-08 12:13:16,792 INFO  [stdout] (default task-49) 12:13:16.790 [default task-49] DEBUG o.s.web.servlet.DispatcherServlet - GET "/api_admin/merchants/notification_urls/1", parameters={}
2922019-08-08 12:13:16,793 INFO  [stdout] (default task-44) 12:13:16.791 [default task-44] DEBUG org.hibernate.loader.Loader - Result row: EntityKey[org.datalis.plugin.entity.Companies#7]
2932019-08-08 12:13:16,793 INFO  [stdout] (default task-46) 12:13:16.791 [default task-46] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Secure object: FilterInvocation: URL: /merchants/merchant_company_list/1; Attributes: [#oauth2.throwOnError(authenticated)]
2942019-08-08 12:13:16,794 INFO  [stdout] (default task-50) 12:13:16.792 [default task-50] DEBUG o.s.b.a.audit.listener.AuditListener - AuditEvent [timestamp=2019-08-08T12:13:16.791960Z, principal=admin, type=AUTHENTICATION_SUCCESS, data={details=remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>}]
2952019-08-08 12:13:16,797 INFO  [stdout] (default task-48) 12:13:16.792 [default task-48] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Secure object: FilterInvocation: URL: /merchants/merchant_user_list/1; Attributes: [#oauth2.throwOnError(authenticated)]
2962019-08-08 12:13:16,798 INFO  [stdout] (default task-47) 12:13:16.792 [default task-47] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list at position 9 of 11 in additional filter chain; firing Filter: 'SessionManagementFilter'
2972019-08-08 12:13:16,798 INFO  [stdout] (default task-47) 12:13:16.798 [default task-47] DEBUG o.s.s.w.a.s.CompositeSessionAuthenticationStrategy - Delegating to org.springframework.security.web.authentication.session.ChangeSessionIdAuthenticationStrategy@7237efab
2982019-08-08 12:13:16,799 INFO  [stdout] (default task-44) 12:13:16.793 [default task-44] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: 2019-07-20 17:05:38.0 -> 2019-07-20T17:05:38
2992019-08-08 12:13:16,799 INFO  [stdout] (default task-44) 12:13:16.799 [default task-44] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: null -> null
3002019-08-08 12:13:16,800 INFO  [stdout] (default task-44) 12:13:16.800 [default task-44] DEBUG o.h.engine.internal.TwoPhaseLoad - Resolving associations for [org.datalis.plugin.entity.Companies#4]
3012019-08-08 12:13:16,800 INFO  [stdout] (default task-46) 12:13:16.794 [default task-46] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Previously Authenticated: org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN
3022019-08-08 12:13:16,801 INFO  [stdout] (default task-49) 12:13:16.795 [default task-49] DEBUG o.s.w.s.m.m.a.RequestMappingHandlerMapping - Mapped to public org.springframework.http.ResponseEntity<?> org.datalis.admin.backend.restapi.MerchantController.getNotification_urls(java.lang.Integer) throws java.lang.Exception
3032019-08-08 12:13:16,801 INFO  [stdout] (default task-50) 12:13:16.797 [default task-50] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_types at position 6 of 11 in additional filter chain; firing Filter: 'RequestCacheAwareFilter'
3042019-08-08 12:13:16,802 INFO  [stdout] (default task-48) 12:13:16.798 [default task-48] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Previously Authenticated: org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN
3052019-08-08 12:13:16,802 INFO  [stdout] (default task-47) 12:13:16.799 [default task-47] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list at position 10 of 11 in additional filter chain; firing Filter: 'ExceptionTranslationFilter'
3062019-08-08 12:13:16,803 INFO  [stdout] (default task-44) 12:13:16.800 [default task-44] DEBUG o.h.engine.internal.TwoPhaseLoad - Done materializing entity [org.datalis.plugin.entity.Companies#4]
3072019-08-08 12:13:16,803 INFO  [stdout] (default task-46) 12:13:16.801 [default task-46] DEBUG o.s.s.access.vote.AffirmativeBased - Voter: org.springframework.security.web.access.expression.WebExpressionVoter@73b2a9d9, returned: 1
3082019-08-08 12:13:16,804 INFO  [stdout] (default task-46) 12:13:16.803 [default task-46] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Authorization successful
3092019-08-08 12:13:16,804 INFO  [stdout] (default task-49) 12:13:16.801 [default task-49] DEBUG o.s.o.j.s.OpenEntityManagerInViewInterceptor - Opening JPA EntityManager in OpenEntityManagerInViewInterceptor
3102019-08-08 12:13:16,805 INFO  [stdout] (default task-50) 12:13:16.802 [default task-50] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_types at position 7 of 11 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter'
3112019-08-08 12:13:16,805 INFO  [stdout] (default task-48) 12:13:16.802 [default task-48] DEBUG o.s.s.access.vote.AffirmativeBased - Voter: org.springframework.security.web.access.expression.WebExpressionVoter@73b2a9d9, returned: 1
3122019-08-08 12:13:16,805 INFO  [stdout] (default task-47) 12:13:16.802 [default task-47] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list at position 11 of 11 in additional filter chain; firing Filter: 'FilterSecurityInterceptor'
3132019-08-08 12:13:16,806 INFO  [stdout] (default task-44) 12:13:16.803 [default task-44] DEBUG o.h.engine.internal.TwoPhaseLoad - Resolving associations for [org.datalis.plugin.entity.Companies#5]
3142019-08-08 12:13:16,806 INFO  [stdout] (default task-46) 12:13:16.804 [default task-46] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - RunAsManager did not change Authentication object
3152019-08-08 12:13:16,807 INFO  [stdout] (default task-46) 12:13:16.807 [default task-46] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_company_list/1 reached end of additional filter chain; proceeding with original chain
3162019-08-08 12:13:16,808 INFO  [stdout] (default task-46) 12:13:16.808 [default task-46] DEBUG o.s.web.servlet.DispatcherServlet - GET "/api_admin/merchants/merchant_company_list/1", parameters={}
3172019-08-08 12:13:16,809 INFO  [stdout] (default task-50) 12:13:16.805 [default task-50] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_types at position 8 of 11 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter'
3182019-08-08 12:13:16,809 INFO  [stdout] (default task-48) 12:13:16.805 [default task-48] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Authorization successful
3192019-08-08 12:13:16,810 INFO  [stdout] (default task-48) 12:13:16.810 [default task-48] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - RunAsManager did not change Authentication object
3202019-08-08 12:13:16,810 INFO  [stdout] (default task-47) 12:13:16.806 [default task-47] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/merchant_user_list'; against '/users/**'
3212019-08-08 12:13:16,811 INFO  [stdout] (default task-44) 12:13:16.806 [default task-44] DEBUG o.h.engine.internal.TwoPhaseLoad - Done materializing entity [org.datalis.plugin.entity.Companies#5]
3222019-08-08 12:13:16,812 INFO  [stdout] (default task-49) 12:13:16.807 [default task-49] DEBUG o.s.orm.jpa.JpaTransactionManager - Found thread-bound EntityManager [SessionImpl(1308633567<open>)] for JPA transaction
3232019-08-08 12:13:16,812 INFO  [stdout] (default task-50) 12:13:16.809 [default task-50] DEBUG o.s.s.w.a.AnonymousAuthenticationFilter - SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN'
3242019-08-08 12:13:16,813 INFO  [stdout] (default task-48) 12:13:16.810 [default task-48] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list/1 reached end of additional filter chain; proceeding with original chain
3252019-08-08 12:13:16,813 INFO  [stdout] (default task-47) 12:13:16.811 [default task-47] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Secure object: FilterInvocation: URL: /merchants/merchant_user_list; Attributes: [#oauth2.throwOnError(authenticated)]
3262019-08-08 12:13:16,814 INFO  [stdout] (default task-44) 12:13:16.812 [default task-44] DEBUG o.h.engine.internal.TwoPhaseLoad - Resolving associations for [org.datalis.plugin.entity.Companies#6]
3272019-08-08 12:13:16,814 INFO  [stdout] (default task-46) 12:13:16.812 [default task-46] DEBUG o.s.w.s.m.m.a.RequestMappingHandlerMapping - Mapped to public org.springframework.http.ResponseEntity<java.util.List<org.datalis.plugin.entity.Businesses>> org.datalis.admin.backend.restapi.MerchantController.getBusinessesList(java.lang.Integer) throws java.lang.NumberFormatException,java.lang.Exception
3282019-08-08 12:13:16,815 INFO  [stdout] (default task-49) 12:13:16.812 [default task-49] DEBUG o.s.orm.jpa.JpaTransactionManager - Creating new transaction with name [org.datalis.plugin.service.NotificationUrlServiceImpl.getNotificationUrlByOwnerId]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
3292019-08-08 12:13:16,815 INFO  [stdout] (default task-50) 12:13:16.813 [default task-50] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_types at position 9 of 11 in additional filter chain; firing Filter: 'SessionManagementFilter'
3302019-08-08 12:13:16,816 INFO  [stdout] (default task-48) 12:13:16.813 [default task-48] DEBUG o.s.web.servlet.DispatcherServlet - GET "/api_admin/merchants/merchant_user_list/1", parameters={}
3312019-08-08 12:13:16,816 INFO  [stdout] (default task-47) 12:13:16.813 [default task-47] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Previously Authenticated: org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN
3322019-08-08 12:13:16,817 INFO  [stdout] (default task-47) 12:13:16.816 [default task-47] DEBUG o.s.s.access.vote.AffirmativeBased - Voter: org.springframework.security.web.access.expression.WebExpressionVoter@73b2a9d9, returned: 1
3332019-08-08 12:13:16,817 INFO  [stdout] (default task-47) 12:13:16.817 [default task-47] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Authorization successful
3342019-08-08 12:13:16,817 INFO  [stdout] (default task-47) 12:13:16.817 [default task-47] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - RunAsManager did not change Authentication object
3352019-08-08 12:13:16,818 INFO  [stdout] (default task-47) 12:13:16.818 [default task-47] DEBUG o.s.security.web.FilterChainProxy - /merchants/merchant_user_list reached end of additional filter chain; proceeding with original chain
3362019-08-08 12:13:16,818 INFO  [stdout] (default task-47) 12:13:16.818 [default task-47] DEBUG o.s.web.servlet.DispatcherServlet - GET "/api_admin/merchants/merchant_user_list", parameters={}
3372019-08-08 12:13:16,819 INFO  [stdout] (default task-44) 12:13:16.814 [default task-44] DEBUG o.h.engine.internal.TwoPhaseLoad - Done materializing entity [org.datalis.plugin.entity.Companies#6]
3382019-08-08 12:13:16,820 INFO  [stdout] (default task-44) 12:13:16.819 [default task-44] DEBUG o.h.engine.internal.TwoPhaseLoad - Resolving associations for [org.datalis.plugin.entity.Companies#7]
3392019-08-08 12:13:16,820 INFO  [stdout] (default task-46) 12:13:16.815 [default task-46] DEBUG o.s.o.j.s.OpenEntityManagerInViewInterceptor - Opening JPA EntityManager in OpenEntityManagerInViewInterceptor
3402019-08-08 12:13:16,821 INFO  [stdout] (default task-49) 12:13:16.815 [default task-49] DEBUG o.h.e.t.internal.TransactionImpl - On TransactionImpl creation, JpaCompliance#isJpaTransactionComplianceEnabled == false
3412019-08-08 12:13:16,821 INFO  [stdout] (default task-50) 12:13:16.815 [default task-50] DEBUG o.s.s.w.a.s.CompositeSessionAuthenticationStrategy - Delegating to org.springframework.security.web.authentication.session.ChangeSessionIdAuthenticationStrategy@7237efab
3422019-08-08 12:13:16,821 INFO  [stdout] (default task-48) 12:13:16.818 [default task-48] DEBUG o.s.w.s.m.m.a.RequestMappingHandlerMapping - Mapped to public org.springframework.http.ResponseEntity<java.util.List<org.datalis.plugin.entity.Users>> org.datalis.admin.backend.restapi.MerchantController.getMerchantUsersList(java.lang.Integer) throws java.lang.NumberFormatException,java.lang.Exception
3432019-08-08 12:13:16,822 INFO  [stdout] (default task-48) 12:13:16.822 [default task-48] DEBUG o.s.o.j.s.OpenEntityManagerInViewInterceptor - Opening JPA EntityManager in OpenEntityManagerInViewInterceptor
3442019-08-08 12:13:16,823 INFO  [stdout] (default task-46) 12:13:16.822 [default task-46] DEBUG o.s.orm.jpa.JpaTransactionManager - Found thread-bound EntityManager [SessionImpl(1614920391<open>)] for JPA transaction
3452019-08-08 12:13:16,823 INFO  [stdout] (default task-47) 12:13:16.819 [default task-47] DEBUG o.s.w.s.m.m.a.RequestMappingHandlerMapping - Mapped to public org.springframework.http.ResponseEntity<java.util.List<org.datalis.plugin.dto.MerchantUserDTO>> org.datalis.admin.backend.restapi.MerchantController.getMerchantUserList() throws java.lang.NumberFormatException,java.lang.Exception
3462019-08-08 12:13:16,824 INFO  [stdout] (default task-44) 12:13:16.820 [default task-44] DEBUG o.h.engine.internal.TwoPhaseLoad - Done materializing entity [org.datalis.plugin.entity.Companies#7]
3472019-08-08 12:13:16,824 INFO  [stdout] (default task-49) 12:13:16.821 [default task-49] DEBUG o.h.e.t.internal.TransactionImpl - begin
3482019-08-08 12:13:16,825 INFO  [stdout] (default task-50) 12:13:16.821 [default task-50] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_types at position 10 of 11 in additional filter chain; firing Filter: 'ExceptionTranslationFilter'
3492019-08-08 12:13:16,825 INFO  [stdout] (default task-48) 12:13:16.823 [default task-48] DEBUG o.s.orm.jpa.JpaTransactionManager - Found thread-bound EntityManager [SessionImpl(1986214625<open>)] for JPA transaction
3502019-08-08 12:13:16,826 INFO  [stdout] (default task-47) 12:13:16.823 [default task-47] DEBUG o.s.o.j.s.OpenEntityManagerInViewInterceptor - Opening JPA EntityManager in OpenEntityManagerInViewInterceptor
3512019-08-08 12:13:16,826 INFO  [stdout] (default task-47) 12:13:16.826 [default task-47] DEBUG o.s.orm.jpa.JpaTransactionManager - Found thread-bound EntityManager [SessionImpl(831417649<open>)] for JPA transaction
3522019-08-08 12:13:16,827 INFO  [stdout] (default task-47) 12:13:16.827 [default task-47] DEBUG o.s.orm.jpa.JpaTransactionManager - Creating new transaction with name [org.datalis.plugin.service.MerchantsServiceImpl.findAllMerchantUsersList]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
3532019-08-08 12:13:16,828 INFO  [stdout] (default task-47) 12:13:16.827 [default task-47] DEBUG o.h.e.t.internal.TransactionImpl - On TransactionImpl creation, JpaCompliance#isJpaTransactionComplianceEnabled == false
3542019-08-08 12:13:16,828 INFO  [stdout] (default task-47) 12:13:16.828 [default task-47] DEBUG o.h.e.t.internal.TransactionImpl - begin
3552019-08-08 12:13:16,829 INFO  [stdout] (default task-46) 12:13:16.824 [default task-46] DEBUG o.s.orm.jpa.JpaTransactionManager - Creating new transaction with name [org.datalis.plugin.service.MerchantsServiceImpl.findCompaniesBusinessesById]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
3562019-08-08 12:13:16,829 INFO  [stdout] (default task-44) 12:13:16.824 [default task-44] DEBUG o.s.orm.jpa.JpaTransactionManager - Initiating transaction commit
3572019-08-08 12:13:16,830 INFO  [stdout] (default task-48) 12:13:16.826 [default task-48] DEBUG o.s.orm.jpa.JpaTransactionManager - Creating new transaction with name [org.datalis.plugin.service.MerchantsServiceImpl.findMerchantUsersByMerchantIdList]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
3582019-08-08 12:13:16,830 INFO  [stdout] (default task-48) 12:13:16.830 [default task-48] DEBUG o.h.e.t.internal.TransactionImpl - On TransactionImpl creation, JpaCompliance#isJpaTransactionComplianceEnabled == false
3592019-08-08 12:13:16,831 INFO  [stdout] (default task-48) 12:13:16.831 [default task-48] DEBUG o.h.e.t.internal.TransactionImpl - begin
3602019-08-08 12:13:16,832 INFO  [stdout] (default task-49) 12:13:16.825 [default task-49] DEBUG o.s.orm.jpa.JpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@f3d23e4]
3612019-08-08 12:13:16,835 INFO  [stdout] (default task-50) 12:13:16.825 [default task-50] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_types at position 11 of 11 in additional filter chain; firing Filter: 'FilterSecurityInterceptor'
3622019-08-08 12:13:16,836 INFO  [stdout] (default task-47) 12:13:16.829 [default task-47] DEBUG o.s.orm.jpa.JpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@70b3670a]
3632019-08-08 12:13:16,837 INFO  [stdout] (default task-46) 12:13:16.829 [default task-46] DEBUG o.h.e.t.internal.TransactionImpl - On TransactionImpl creation, JpaCompliance#isJpaTransactionComplianceEnabled == false
3642019-08-08 12:13:16,837 INFO  [stdout] (default task-46) 12:13:16.837 [default task-46] DEBUG o.h.e.t.internal.TransactionImpl - begin
3652019-08-08 12:13:16,839 INFO  [stdout] (default task-44) 12:13:16.830 [default task-44] DEBUG o.s.orm.jpa.JpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(228965287<open>)]
3662019-08-08 12:13:16,843 INFO  [stdout] (default task-48) 12:13:16.835 [default task-48] DEBUG o.s.orm.jpa.JpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@375e30da]
3672019-08-08 12:13:16,844 INFO  [stdout] (default task-48) 12:13:16.844 [default task-48] DEBUG org.hibernate.SQL - select users0_.id as id1_42_, users0_.client_cert as client_c2_42_, users0_.confirmation_sent_at as confirma3_42_, users0_.confirmation_token as confirma4_42_, users0_.confirmed_at as confirme5_42_, users0_.created_at as created_6_42_, users0_.created_by as created_7_42_, users0_.email as email8_42_, users0_.enabled as enabled9_42_, users0_.enabled_terminals as enabled10_42_, users0_.encrypted_password as encrypt11_42_, users0_.entity_privileges as entity_12_42_, users0_.expired_at as expired13_42_, users0_.failed_attempts as failed_14_42_, users0_.first_name as first_n15_42_, users0_.key_custodian_id as key_cus16_42_, users0_.last_activity_at as last_ac17_42_, users0_.last_name as last_na18_42_, users0_.locked_at as locked_19_42_, users0_.login as login20_42_, users0_.owner_id as owner_i21_42_, users0_.owner_type as owner_t22_42_, users0_.password_changed_at as passwor23_42_, users0_.reset_password_sent_at as reset_p24_42_, users0_.reset_password_token as reset_p25_42_, users0_.reset_password_token_sent_at as reset_p26_42_, users0_.role as role27_42_, users0_.salt as salt28_42_, users0_.type as type29_42_, users0_.unique_session_id as unique_30_42_, users0_.updated_at as updated31_42_ from users users0_ where users0_.owner_id=? and users0_.owner_type='Merchant'
3682019-08-08 12:13:16,844 INFO  [stdout] (default task-48) Hibernate: select users0_.id as id1_42_, users0_.client_cert as client_c2_42_, users0_.confirmation_sent_at as confirma3_42_, users0_.confirmation_token as confirma4_42_, users0_.confirmed_at as confirme5_42_, users0_.created_at as created_6_42_, users0_.created_by as created_7_42_, users0_.email as email8_42_, users0_.enabled as enabled9_42_, users0_.enabled_terminals as enabled10_42_, users0_.encrypted_password as encrypt11_42_, users0_.entity_privileges as entity_12_42_, users0_.expired_at as expired13_42_, users0_.failed_attempts as failed_14_42_, users0_.first_name as first_n15_42_, users0_.key_custodian_id as key_cus16_42_, users0_.last_activity_at as last_ac17_42_, users0_.last_name as last_na18_42_, users0_.locked_at as locked_19_42_, users0_.login as login20_42_, users0_.owner_id as owner_i21_42_, users0_.owner_type as owner_t22_42_, users0_.password_changed_at as passwor23_42_, users0_.reset_password_sent_at as reset_p24_42_, users0_.reset_password_token as reset_p25_42_, users0_.reset_password_token_sent_at as reset_p26_42_, users0_.role as role27_42_, users0_.salt as salt28_42_, users0_.type as type29_42_, users0_.unique_session_id as unique_30_42_, users0_.updated_at as updated31_42_ from users users0_ where users0_.owner_id=? and users0_.owner_type='Merchant'
3692019-08-08 12:13:16,845 INFO  [stdout] (default task-49) 12:13:16.835 [default task-49] DEBUG org.hibernate.SQL - select notificati0_.id as id1_24_, notificati0_.created_at as created_2_24_, notificati0_.enabled as enabled3_24_, notificati0_.notification_url_owner_id as notifica4_24_, notificati0_.notification_url_owner_type as notifica5_24_, notificati0_.updated_at as updated_6_24_, notificati0_.url as url7_24_, notificati0_.url_type as url_type8_24_ from notification_urls notificati0_ where notificati0_.notification_url_owner_id=?
3702019-08-08 12:13:16,845 INFO  [stdout] (default task-50) 12:13:16.836 [default task-50] DEBUG o.s.s.w.u.m.AntPathRequestMatcher - Checking match of request : '/merchants/notification_types'; against '/users/**'
3712019-08-08 12:13:16,846 INFO  [stdout] (default task-49) Hibernate: select notificati0_.id as id1_24_, notificati0_.created_at as created_2_24_, notificati0_.enabled as enabled3_24_, notificati0_.notification_url_owner_id as notifica4_24_, notificati0_.notification_url_owner_type as notifica5_24_, notificati0_.updated_at as updated_6_24_, notificati0_.url as url7_24_, notificati0_.url_type as url_type8_24_ from notification_urls notificati0_ where notificati0_.notification_url_owner_id=?
3722019-08-08 12:13:16,846 INFO  [stdout] (default task-47) 12:13:16.837 [default task-47] DEBUG org.hibernate.SQL - select users0_.id as id1_42_, users0_.client_cert as client_c2_42_, users0_.confirmation_sent_at as confirma3_42_, users0_.confirmation_token as confirma4_42_, users0_.confirmed_at as confirme5_42_, users0_.created_at as created_6_42_, users0_.created_by as created_7_42_, users0_.email as email8_42_, users0_.enabled as enabled9_42_, users0_.enabled_terminals as enabled10_42_, users0_.encrypted_password as encrypt11_42_, users0_.entity_privileges as entity_12_42_, users0_.expired_at as expired13_42_, users0_.failed_attempts as failed_14_42_, users0_.first_name as first_n15_42_, users0_.key_custodian_id as key_cus16_42_, users0_.last_activity_at as last_ac17_42_, users0_.last_name as last_na18_42_, users0_.locked_at as locked_19_42_, users0_.login as login20_42_, users0_.owner_id as owner_i21_42_, users0_.owner_type as owner_t22_42_, users0_.password_changed_at as passwor23_42_, users0_.reset_password_sent_at as reset_p24_42_, users0_.reset_password_token as reset_p25_42_, users0_.reset_password_token_sent_at as reset_p26_42_, users0_.role as role27_42_, users0_.salt as salt28_42_, users0_.type as type29_42_, users0_.unique_session_id as unique_30_42_, users0_.updated_at as updated31_42_ from users users0_ where users0_.type='MerchantUser'
3732019-08-08 12:13:16,847 INFO  [stdout] (default task-47) Hibernate: select users0_.id as id1_42_, users0_.client_cert as client_c2_42_, users0_.confirmation_sent_at as confirma3_42_, users0_.confirmation_token as confirma4_42_, users0_.confirmed_at as confirme5_42_, users0_.created_at as created_6_42_, users0_.created_by as created_7_42_, users0_.email as email8_42_, users0_.enabled as enabled9_42_, users0_.enabled_terminals as enabled10_42_, users0_.encrypted_password as encrypt11_42_, users0_.entity_privileges as entity_12_42_, users0_.expired_at as expired13_42_, users0_.failed_attempts as failed_14_42_, users0_.first_name as first_n15_42_, users0_.key_custodian_id as key_cus16_42_, users0_.last_activity_at as last_ac17_42_, users0_.last_name as last_na18_42_, users0_.locked_at as locked_19_42_, users0_.login as login20_42_, users0_.owner_id as owner_i21_42_, users0_.owner_type as owner_t22_42_, users0_.password_changed_at as passwor23_42_, users0_.reset_password_sent_at as reset_p24_42_, users0_.reset_password_token as reset_p25_42_, users0_.reset_password_token_sent_at as reset_p26_42_, users0_.role as role27_42_, users0_.salt as salt28_42_, users0_.type as type29_42_, users0_.unique_session_id as unique_30_42_, users0_.updated_at as updated31_42_ from users users0_ where users0_.type='MerchantUser'
3742019-08-08 12:13:16,847 INFO  [stdout] (default task-46) 12:13:16.842 [default task-46] DEBUG o.s.orm.jpa.JpaTransactionManager - Exposing JPA transaction as JDBC [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@42778f9b]
3752019-08-08 12:13:16,848 INFO  [stdout] (default task-44) 12:13:16.843 [default task-44] DEBUG o.h.e.t.internal.TransactionImpl - committing
3762019-08-08 12:13:16,848 INFO  [stdout] (default task-48) 12:13:16.846 [default task-48] DEBUG o.s.orm.jpa.JpaTransactionManager - Initiating transaction commit
3772019-08-08 12:13:16,849 INFO  [stdout] (default task-50) 12:13:16.846 [default task-50] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Secure object: FilterInvocation: URL: /merchants/notification_types; Attributes: [#oauth2.throwOnError(authenticated)]
3782019-08-08 12:13:16,849 INFO  [stdout] (default task-49) 12:13:16.847 [default task-49] DEBUG org.hibernate.loader.Loader - Result set row: 0
3792019-08-08 12:13:16,850 INFO  [stdout] (default task-46) 12:13:16.848 [default task-46] DEBUG org.hibernate.SQL - select businesses0_.id as id1_5_, businesses0_.business_owner_id as business2_5_, businesses0_.business_owner_type as business3_5_, businesses0_.company_id as company_4_5_, businesses0_.created_at as created_5_5_, businesses0_.updated_at as updated_6_5_ from businesses businesses0_ inner join companies companies1_ on (companies1_.id=businesses0_.company_id) where businesses0_.business_owner_id=? and businesses0_.business_owner_type='Merchant' order by businesses0_.id ASC
3802019-08-08 12:13:16,850 INFO  [stdout] (default task-46) Hibernate: select businesses0_.id as id1_5_, businesses0_.business_owner_id as business2_5_, businesses0_.business_owner_type as business3_5_, businesses0_.company_id as company_4_5_, businesses0_.created_at as created_5_5_, businesses0_.updated_at as updated_6_5_ from businesses businesses0_ inner join companies companies1_ on (companies1_.id=businesses0_.company_id) where businesses0_.business_owner_id=? and businesses0_.business_owner_type='Merchant' order by businesses0_.id ASC
3812019-08-08 12:13:16,850 INFO  [stdout] (default task-44) 12:13:16.848 [default task-44] DEBUG o.h.e.i.AbstractFlushingEventListener - Processing flush-time cascades
3822019-08-08 12:13:16,851 INFO  [stdout] (default task-44) 12:13:16.851 [default task-44] DEBUG o.h.e.i.AbstractFlushingEventListener - Dirty checking collections
3832019-08-08 12:13:16,851 INFO  [stdout] (default task-48) 12:13:16.848 [default task-48] DEBUG o.s.orm.jpa.JpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(1986214625<open>)]
3842019-08-08 12:13:16,852 INFO  [stdout] (default task-50) 12:13:16.849 [default task-50] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Previously Authenticated: org.springframework.security.oauth2.provider.OAuth2Authentication@c0efadcb: Principal: admin; Credentials: [PROTECTED]; Authenticated: true; Details: remoteAddress=199.38.247.130, tokenType=BearertokenValue=<TOKEN>; Granted Authorities: ROLE_ADMIN
3852019-08-08 12:13:16,852 INFO  [stdout] (default task-47) 12:13:16.849 [default task-47] DEBUG org.hibernate.loader.Loader - Result set row: 0
3862019-08-08 12:13:16,853 INFO  [stdout] (default task-49) 12:13:16.849 [default task-49] DEBUG org.hibernate.loader.Loader - Result row: EntityKey[org.datalis.plugin.entity.NotificationUrl#13]
3872019-08-08 12:13:16,854 INFO  [stdout] (default task-46) 12:13:16.851 [default task-46] DEBUG org.hibernate.loader.Loader - Result set row: 0
3882019-08-08 12:13:16,854 INFO  [stdout] (default task-44) 12:13:16.852 [default task-44] DEBUG o.h.e.i.AbstractFlushingEventListener - Flushed: 0 insertions, 0 updates, 0 deletions to 4 objects
3892019-08-08 12:13:16,855 INFO  [stdout] (default task-48) 12:13:16.852 [default task-48] DEBUG o.h.e.t.internal.TransactionImpl - committing
3902019-08-08 12:13:16,855 INFO  [stdout] (default task-50) 12:13:16.853 [default task-50] DEBUG o.s.s.access.vote.AffirmativeBased - Voter: org.springframework.security.web.access.expression.WebExpressionVoter@73b2a9d9, returned: 1
3912019-08-08 12:13:16,856 INFO  [stdout] (default task-47) 12:13:16.853 [default task-47] DEBUG org.hibernate.loader.Loader - Result row: EntityKey[org.datalis.plugin.entity.Users#362]
3922019-08-08 12:13:16,856 INFO  [stdout] (default task-49) 12:13:16.854 [default task-49] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: 2019-05-15 04:53:11.0 -> 2019-05-15T04:53:11
3932019-08-08 12:13:16,857 INFO  [stdout] (default task-46) 12:13:16.854 [default task-46] DEBUG org.hibernate.loader.Loader - Result row: EntityKey[org.datalis.plugin.entity.Businesses#34]
3942019-08-08 12:13:16,857 INFO  [stdout] (default task-44) 12:13:16.855 [default task-44] DEBUG o.h.e.i.AbstractFlushingEventListener - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
3952019-08-08 12:13:16,858 INFO  [stdout] (default task-48) 12:13:16.855 [default task-48] DEBUG o.s.orm.jpa.JpaTransactionManager - Not closing pre-bound JPA EntityManager after transaction
3962019-08-08 12:13:16,858 INFO  [stdout] (default task-50) 12:13:16.855 [default task-50] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - Authorization successful
3972019-08-08 12:13:16,859 INFO  [stdout] (default task-50) 12:13:16.859 [default task-50] DEBUG o.s.s.w.a.i.FilterSecurityInterceptor - RunAsManager did not change Authentication object
3982019-08-08 12:13:16,859 INFO  [stdout] (default task-47) 12:13:16.856 [default task-47] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: null -> null
3992019-08-08 12:13:16,860 INFO  [stdout] (default task-49) 12:13:16.857 [default task-49] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: 2019-07-20 19:03:58.0 -> 2019-07-20T19:03:58
4002019-08-08 12:13:16,860 INFO  [stdout] (default task-46) 12:13:16.857 [default task-46] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: null -> null
4012019-08-08 12:13:16,861 INFO  [stdout] (default task-44) 12:13:16.858 [default task-44] DEBUG o.h.internal.util.EntityPrinter - Listing entities:
4022019-08-08 12:13:16,862 INFO  [stdout] (default task-48) 12:13:16.859 [default task-48] DEBUG o.s.w.s.m.m.a.HttpEntityMethodProcessor - Using 'application/json', given [application/json, text/plain, */*] and supported [application/json, application/*+json, application/json, application/*+json, application/xml, text/xml, application/*+xml, application/xml, text/xml, application/*+xml]
4032019-08-08 12:13:16,862 INFO  [stdout] (default task-50) 12:13:16.859 [default task-50] DEBUG o.s.security.web.FilterChainProxy - /merchants/notification_types reached end of additional filter chain; proceeding with original chain
4042019-08-08 12:13:16,863 INFO  [stdout] (default task-47) 12:13:16.860 [default task-47] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: 2019-08-03 18:24:58.0 -> 2019-08-03T18:24:58
4052019-08-08 12:13:16,863 INFO  [stdout] (default task-49) 12:13:16.860 [default task-49] DEBUG org.hibernate.loader.Loader - Result set row: 1
4062019-08-08 12:13:16,864 INFO  [stdout] (default task-46) 12:13:16.861 [default task-46] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: null -> null
4072019-08-08 12:13:16,864 INFO  [stdout] (default task-44) 12:13:16.861 [default task-44] DEBUG o.h.internal.util.EntityPrinter - org.datalis.plugin.entity.Companies{updated_at=null, name=thrthrth, created_at=2019-07-20T17:05:38, id=7}
4082019-08-08 12:13:16,865 INFO  [stdout] (default task-48) 12:13:16.862 [default task-48] DEBUG o.s.w.s.m.m.a.HttpEntityMethodProcessor - Writing [[]]
4092019-08-08 12:13:16,865 INFO  [stdout] (default task-50) 12:13:16.863 [default task-50] DEBUG o.s.web.servlet.DispatcherServlet - GET "/api_admin/merchants/notification_types", parameters={}
4102019-08-08 12:13:16,866 INFO  [stdout] (default task-47) 12:13:16.863 [default task-47] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: 2019-08-03 18:24:37.0 -> 2019-08-03T18:24:37
4112019-08-08 12:13:16,866 INFO  [stdout] (default task-49) 12:13:16.864 [default task-49] DEBUG org.hibernate.loader.Loader - Result row: EntityKey[org.datalis.plugin.entity.NotificationUrl#22]
4122019-08-08 12:13:16,867 INFO  [stdout] (default task-46) 12:13:16.864 [default task-46] DEBUG org.hibernate.loader.Loader - Result set row: 1
4132019-08-08 12:13:16,867 INFO  [stdout] (default task-44) 12:13:16.865 [default task-44] DEBUG o.h.internal.util.EntityPrinter - org.datalis.plugin.entity.Companies{updated_at=2019-07-20T17:05:42, name=ggn, created_at=2019-07-12T22:47:17, id=6}
4142019-08-08 12:13:16,868 INFO  [stdout] (default task-48) 12:13:16.866 [default task-48] DEBUG o.s.s.w.h.writers.HstsHeaderWriter - Not injecting HSTS header since it did not match the requestMatcher org.springframework.security.web.header.writers.HstsHeaderWriter$SecureRequestMatcher@e398f1b
4152019-08-08 12:13:16,869 INFO  [stdout] (default task-48) 12:13:16.868 [default task-48] DEBUG o.s.o.j.s.OpenEntityManagerInViewInterceptor - Closing JPA EntityManager in OpenEntityManagerInViewInterceptor
4162019-08-08 12:13:16,869 INFO  [stdout] (default task-47) 12:13:16.866 [default task-47] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: null -> null
4172019-08-08 12:13:16,870 INFO  [stdout] (default task-50) 12:13:16.866 [default task-50] DEBUG o.s.w.s.m.m.a.RequestMappingHandlerMapping - Mapped to public org.springframework.http.ResponseEntity<?> org.datalis.admin.backend.restapi.MerchantController.getTransactionTypes()
4182019-08-08 12:13:16,870 INFO  [stdout] (default task-49) 12:13:16.867 [default task-49] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: 2019-05-17 21:11:53.0 -> 2019-05-17T21:11:53
4192019-08-08 12:13:16,871 INFO  [stdout] (default task-46) 12:13:16.867 [default task-46] DEBUG org.hibernate.loader.Loader - Result row: EntityKey[org.datalis.plugin.entity.Businesses#35]
4202019-08-08 12:13:16,871 INFO  [stdout] (default task-44) 12:13:16.868 [default task-44] DEBUG o.h.internal.util.EntityPrinter - org.datalis.plugin.entity.Companies{updated_at=null, name=tyjttyj, created_at=2019-05-27T21:34:57, id=5}
4212019-08-08 12:13:16,872 INFO  [stdout] (default task-44) 12:13:16.872 [default task-44] DEBUG o.h.internal.util.EntityPrinter - org.datalis.plugin.entity.Companies{updated_at=2019-07-20T12:49:09, name=rhrth, created_at=2019-05-27T14:47:38, id=4}
4222019-08-08 12:13:16,873 INFO  [stdout] (default task-48) 12:13:16.870 [default task-48] DEBUG o.s.web.servlet.DispatcherServlet - Completed 200 OK
4232019-08-08 12:13:16,873 INFO  [stdout] (default task-50) 12:13:16.870 [default task-50] DEBUG o.s.o.j.s.OpenEntityManagerInViewInterceptor - Opening JPA EntityManager in OpenEntityManagerInViewInterceptor
4242019-08-08 12:13:16,876 INFO  [stdout] (default task-47) 12:13:16.870 [default task-47] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: null -> null
4252019-08-08 12:13:16,876 INFO  [stdout] (default task-47) 12:13:16.876 [default task-47] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: null -> null
4262019-08-08 12:13:16,877 INFO  [stdout] (default task-49) 12:13:16.871 [default task-49] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: null -> null
4272019-08-08 12:13:16,877 INFO  [stdout] (default task-49) 12:13:16.877 [default task-49] DEBUG o.h.engine.internal.TwoPhaseLoad - Resolving associations for [org.datalis.plugin.entity.NotificationUrl#13]
4282019-08-08 12:13:16,878 INFO  [stdout] (default task-46) 12:13:16.871 [default task-46] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: null -> null
4292019-08-08 12:13:16,879 INFO  [stdout] (default task-48) 12:13:16.873 [default task-48] DEBUG o.s.s.w.a.ExceptionTranslationFilter - Chain processed normally
4302019-08-08 12:13:16,879 INFO  [stdout] (default task-48) 12:13:16.879 [default task-48] DEBUG o.s.s.w.c.SecurityContextPersistenceFilter - SecurityContextHolder now cleared, as request processing completed
4312019-08-08 12:13:16,880 INFO  [stdout] (default task-44) 12:13:16.873 [default task-44] DEBUG o.s.orm.jpa.JpaTransactionManager - Not closing pre-bound JPA EntityManager after transaction
4322019-08-08 12:13:16,881 INFO  [stdout] (default task-50) 12:13:16.876 [default task-50] DEBUG o.s.w.s.m.m.a.HttpEntityMethodProcessor - Using 'application/json', given [application/json, text/plain, */*] and supported [application/json, application/*+json, application/json, application/*+json, application/xml, text/xml, application/*+xml, application/xml, text/xml, application/*+xml]
4332019-08-08 12:13:16,881 INFO  [stdout] (default task-47) 12:13:16.877 [default task-47] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: 2019-08-03 18:25:02.0 -> 2019-08-03T18:25:02
4342019-08-08 12:13:16,882 INFO  [stdout] (default task-49) 12:13:16.878 [default task-49] DEBUG o.h.engine.internal.TwoPhaseLoad - Done materializing entity [org.datalis.plugin.entity.NotificationUrl#13]
4352019-08-08 12:13:16,882 INFO  [stdout] (default task-46) 12:13:16.878 [default task-46] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: null -> null
4362019-08-08 12:13:16,883 INFO  [stdout] (default task-44) 12:13:16.881 [default task-44] DEBUG o.s.w.s.m.m.a.HttpEntityMethodProcessor - Using 'application/json', given [application/json, text/plain, */*] and supported [application/json, application/*+json, application/json, application/*+json, application/xml, text/xml, application/*+xml, application/xml, text/xml, application/*+xml]
4372019-08-08 12:13:16,883 INFO  [stdout] (default task-50) 12:13:16.881 [default task-50] DEBUG o.s.w.s.m.m.a.HttpEntityMethodProcessor - Writing [[org.datalis.plugin.dto.NotificationTypesDTO@774209e4, org.datalis.plugin.dto.NotificationTypesDTO@7 (truncated)...]
4382019-08-08 12:13:16,884 INFO  [stdout] (default task-47) 12:13:16.882 [default task-47] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: null -> null
4392019-08-08 12:13:16,884 INFO  [stdout] (default task-47) 12:13:16.884 [default task-47] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: null -> null
4402019-08-08 12:13:16,885 INFO  [stdout] (default task-49) 12:13:16.882 [default task-49] DEBUG o.h.engine.internal.TwoPhaseLoad - Resolving associations for [org.datalis.plugin.entity.NotificationUrl#22]
4412019-08-08 12:13:16,886 INFO  [stdout] (default task-46) 12:13:16.883 [default task-46] DEBUG o.h.engine.internal.TwoPhaseLoad - Resolving associations for [org.datalis.plugin.entity.Businesses#34]
4422019-08-08 12:13:16,886 INFO  [stdout] (default task-44) 12:13:16.883 [default task-44] DEBUG o.s.w.s.m.m.a.HttpEntityMethodProcessor - Writing [[org.datalis.plugin.dto.CompanyDTO@7255b3c0, org.datalis.plugin.dto.CompanyDTO@21af583a, org.datalis (truncated)...]
4432019-08-08 12:13:16,887 INFO  [stdout] (default task-50) 12:13:16.885 [default task-50] DEBUG o.s.s.w.h.writers.HstsHeaderWriter - Not injecting HSTS header since it did not match the requestMatcher org.springframework.security.web.header.writers.HstsHeaderWriter$SecureRequestMatcher@e398f1b
4442019-08-08 12:13:16,887 INFO  [stdout] (default task-47) 12:13:16.885 [default task-47] DEBUG o.h.t.d.c.AttributeConverterSqlTypeDescriptorAdapter - Converted value on extraction: null -> null
4452019-08-08 12:13:16,888 INFO  [stdout] (default task-49) 12:13:16.885 [default task-49] DEBUG o.h.engine.internal.TwoPhaseLoad - Done materializing entity [org.datalis.plugin.entity.NotificationUrl#22]
4462019-08-08 12:13:16,888 INFO  [stdout] (default task-46) 12:13:16.886 [default task-46] DEBUG o.h.engine.internal.TwoPhaseLoad - Done materializing entity [org.datalis.plugin.entity.Businesses#34]
4472019-08-08 12:13:16,889 INFO  [stdout] (default task-44) 12:13:16.887 [default task-44] DEBUG o.s.s.w.h.writers.HstsHeaderWriter - Not injecting HSTS header since it did not match the requestMatcher org.springframework.security.web.header.writers.HstsHeaderWriter$SecureRequestMatcher@e398f1b
4482019-08-08 12:13:16,889 INFO  [stdout] (default task-50) 12:13:16.887 [default task-50] DEBUG o.s.o.j.s.OpenEntityManagerInViewInterceptor - Closing JPA EntityManager in OpenEntityManagerInViewInterceptor
4492019-08-08 12:13:16,890 INFO  [stdout] (default task-47) 12:13:16.888 [default task-47] DEBUG o.h.engine.internal.TwoPhaseLoad - Resolving associations for [org.datalis.plugin.entity.Users#362]
4502019-08-08 12:13:16,890 INFO  [stdout] (default task-49) 12:13:16.888 [default task-49] DEBUG o.s.orm.jpa.JpaTransactionManager - Initiating transaction commit
4512019-08-08 12:13:16,891 INFO  [stdout] (default task-46) 12:13:16.889 [default task-46] DEBUG o.h.engine.internal.TwoPhaseLoad - Resolving associations for [org.datalis.plugin.entity.Businesses#35]
4522019-08-08 12:13:16,892 INFO  [stdout] (default task-44) 12:13:16.889 [default task-44] DEBUG o.s.o.j.s.OpenEntityManagerInViewInterceptor - Closing JPA EntityManager in OpenEntityManagerInViewInterceptor
4532019-08-08 12:13:16,892 INFO  [stdout] (default task-50) 12:13:16.890 [default task-50] DEBUG o.s.web.servlet.DispatcherServlet - Completed 200 OK
4542019-08-08 12:13:16,893 INFO  [stdout] (default task-47) 12:13:16.890 [default task-47] DEBUG o.h.engine.internal.TwoPhaseLoad - Done materializing entity [org.datalis.plugin.entity.Users#362]
4552019-08-08 12:13:16,893 INFO  [stdout] (default task-49) 12:13:16.891 [default task-49] DEBUG o.s.orm.jpa.JpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(1308633567<open>)]
4562019-08-08 12:13:16,894 INFO  [stdout] (default task-46) 12:13:16.891 [default task-46] DEBUG o.h.engine.internal.TwoPhaseLoad - Done materializing entity [org.datalis.plugin.entity.Businesses#35]
4572019-08-08 12:13:16,894 INFO  [stdout] (default task-44) 12:13:16.892 [default task-44] DEBUG o.s.web.servlet.DispatcherServlet - Completed 200 OK
4582019-08-08 12:13:16,894 INFO  [stdout] (default task-50) 12:13:16.892 [default task-50] DEBUG o.s.s.w.a.ExceptionTranslationFilter - Chain processed normally
4592019-08-08 12:13:16,895 INFO  [stdout] (default task-50) 12:13:16.895 [default task-50] DEBUG o.s.s.w.c.SecurityContextPersistenceFilter - SecurityContextHolder now cleared, as request processing completed
4602019-08-08 12:13:16,895 INFO  [stdout] (default task-47) 12:13:16.893 [default task-47] DEBUG o.s.orm.jpa.JpaTransactionManager - Initiating transaction commit
4612019-08-08 12:13:16,896 INFO  [stdout] (default task-47) 12:13:16.896 [default task-47] DEBUG o.s.orm.jpa.JpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(831417649<open>)]
4622019-08-08 12:13:16,896 INFO  [stdout] (default task-47) 12:13:16.896 [default task-47] DEBUG o.h.e.t.internal.TransactionImpl - committing
4632019-08-08 12:13:16,897 INFO  [stdout] (default task-49) 12:13:16.893 [default task-49] DEBUG o.h.e.t.internal.TransactionImpl - committing
4642019-08-08 12:13:16,897 INFO  [stdout] (default task-49) 12:13:16.897 [default task-49] DEBUG o.h.e.i.AbstractFlushingEventListener - Processing flush-time cascades
4652019-08-08 12:13:16,898 INFO  [stdout] (default task-49) 12:13:16.898 [default task-49] DEBUG o.h.e.i.AbstractFlushingEventListener - Dirty checking collections
4662019-08-08 12:13:16,899 INFO  [stdout] (default task-49) 12:13:16.898 [default task-49] DEBUG o.h.e.i.AbstractFlushingEventListener - Flushed: 0 insertions, 0 updates, 0 deletions to 2 objects
4672019-08-08 12:13:16,899 INFO  [stdout] (default task-49) 12:13:16.899 [default task-49] DEBUG o.h.e.i.AbstractFlushingEventListener - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
4682019-08-08 12:13:16,900 INFO  [stdout] (default task-49) 12:13:16.899 [default task-49] DEBUG o.h.internal.util.EntityPrinter - Listing entities:
4692019-08-08 12:13:16,900 INFO  [stdout] (default task-49) 12:13:16.900 [default task-49] DEBUG o.h.internal.util.EntityPrinter - org.datalis.plugin.entity.NotificationUrl{updated_at=null, url_type=retrieval_request, created_at=2019-05-17T21:11:53, id=22, enabled=true, url=http://vmvelev.pro/genesis.php, notification_url_owner_id=1, notification_url_owner_type=terminal}
4702019-08-08 12:13:16,901 INFO  [stdout] (default task-49) 12:13:16.901 [default task-49] DEBUG o.h.internal.util.EntityPrinter - org.datalis.plugin.entity.NotificationUrl{updated_at=2019-07-20T19:03:58, url_type=chargeback_reversal, created_at=2019-05-15T04:53:11, id=13, enabled=true, url=http://vmvelev.pro/genesis.php, notification_url_owner_id=1, notification_url_owner_type=merchant}
4712019-08-08 12:13:16,901 INFO  [stdout] (default task-46) 12:13:16.894 [default task-46] DEBUG o.s.orm.jpa.JpaTransactionManager - Initiating transaction commit
4722019-08-08 12:13:16,902 INFO  [stdout] (default task-44) 12:13:16.895 [default task-44] DEBUG o.s.s.w.a.ExceptionTranslationFilter - Chain processed normally
4732019-08-08 12:13:16,903 INFO  [stdout] (default task-47) 12:13:16.897 [default task-47] DEBUG o.h.e.i.AbstractFlushingEventListener - Processing flush-time cascades
4742019-08-08 12:13:16,903 INFO  [stdout] (default task-47) 12:13:16.903 [default task-47] DEBUG o.h.e.i.AbstractFlushingEventListener - Dirty checking collections
4752019-08-08 12:13:16,904 INFO  [stdout] (default task-47) 12:13:16.903 [default task-47] DEBUG o.h.e.i.AbstractFlushingEventListener - Flushed: 0 insertions, 0 updates, 0 deletions to 1 objects
4762019-08-08 12:13:16,904 INFO  [stdout] (default task-47) 12:13:16.904 [default task-47] DEBUG o.h.e.i.AbstractFlushingEventListener - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
4772019-08-08 12:13:16,905 INFO  [stdout] (default task-47) 12:13:16.904 [default task-47] DEBUG o.h.internal.util.EntityPrinter - Listing entities:
4782019-08-08 12:13:16,905 INFO  [stdout] (default task-47) 12:13:16.905 [default task-47] DEBUG o.h.internal.util.EntityPrinter - org.datalis.plugin.entity.Users{role=ROLE_ADMIN, client_cert=null, failed_attempts=null, owner_id=24, created_at=2019-08-03T18:24:37, unique_session_id=null, expired_at=null, login=test, type=MerchantUser, confirmation_token=null, enabled=true, reset_password_token=null, entity_privileges=null, key_custodian_id=null, updated_at=null, locked_at=null, last_activity_at=null, id=362, confirmed_at=2019-08-03T18:24:58, first_name=wefwef, email=peter.penzov@gmail.com, encrypted_password={bcrypt}$2a$10$VpRI19bmuEmwqnDZGb5IB.3cTNUpIvoNGqf.8dqjA4uPuioWlImRK, password_changed_at=2019-08-03T18:25:02, salt=null, confirmation_sent_at=null, reset_password_sent_at=null, last_name=wefwefwef, created_by=null, enabled_terminals=111100000000, reset_password_token_sent_at=null, owner_type=Merchant}
4792019-08-08 12:13:16,906 INFO  [stdout] (default task-49) 12:13:16.901 [default task-49] DEBUG o.s.orm.jpa.JpaTransactionManager - Not closing pre-bound JPA EntityManager after transaction
4802019-08-08 12:13:16,906 INFO  [stdout] (default task-46) 12:13:16.902 [default task-46] DEBUG o.s.orm.jpa.JpaTransactionManager - Committing JPA transaction on EntityManager [SessionImpl(1614920391<open>)]
4812019-08-08 12:13:16,907 INFO  [stdout] (default task-46) 12:13:16.907 [default task-46] DEBUG o.h.e.t.internal.TransactionImpl - committing
4822019-08-08 12:13:16,907 INFO  [stdout] (default task-44) 12:13:16.902 [default task-44] DEBUG o.s.s.w.c.SecurityContextPersistenceFilter - SecurityContextHolder now cleared, as request processing completed
4832019-08-08 12:13:16,908 INFO  [stdout] (default task-47) 12:13:16.906 [default task-47] DEBUG o.s.orm.jpa.JpaTransactionManager - Not closing pre-bound JPA EntityManager after transaction
4842019-08-08 12:13:16,909 INFO  [stdout] (default task-49) 12:13:16.907 [default task-49] DEBUG o.s.w.s.m.m.a.HttpEntityMethodProcessor - Using 'application/json', given [application/json, text/plain, */*] and supported [application/json, application/*+json, application/json, application/*+json, application/xml, text/xml, application/*+xml, application/xml, text/xml, application/*+xml]
4852019-08-08 12:13:16,909 INFO  [stdout] (default task-46) 12:13:16.907 [default task-46] DEBUG o.h.e.i.AbstractFlushingEventListener - Processing flush-time cascades
4862019-08-08 12:13:16,910 INFO  [stdout] (default task-49) 12:13:16.909 [default task-49] DEBUG o.s.w.s.m.m.a.HttpEntityMethodProcessor - Writing [[org.datalis.plugin.dto.NotificationUrlsNewDTO@512b317d, org.datalis.plugin.dto.NotificationUrlsNewD (truncated)...]
4872019-08-08 12:13:16,910 INFO  [stdout] (default task-47) 12:13:16.909 [default task-47] DEBUG o.s.w.s.m.m.a.HttpEntityMethodProcessor - Using 'application/json', given [application/json, text/plain, */*] and supported [application/json, application/*+json, application/json, application/*+json, application/xml, text/xml, application/*+xml, application/xml, text/xml, application/*+xml]
4882019-08-08 12:13:16,911 INFO  [stdout] (default task-49) 12:13:16.910 [default task-49] DEBUG o.s.s.w.h.writers.HstsHeaderWriter - Not injecting HSTS header since it did not match the requestMatcher org.springframework.security.web.header.writers.HstsHeaderWriter$SecureRequestMatcher@e398f1b
4892019-08-08 12:13:16,911 INFO  [stdout] (default task-46) 12:13:16.910 [default task-46] DEBUG o.h.e.i.AbstractFlushingEventListener - Dirty checking collections
4902019-08-08 12:13:16,912 INFO  [stdout] (default task-47) 12:13:16.911 [default task-47] DEBUG o.s.w.s.m.m.a.HttpEntityMethodProcessor - Writing [[org.datalis.plugin.dto.MerchantUserDTO@109c9845]]
4912019-08-08 12:13:16,912 INFO  [stdout] (default task-49) 12:13:16.911 [default task-49] DEBUG o.s.o.j.s.OpenEntityManagerInViewInterceptor - Closing JPA EntityManager in OpenEntityManagerInViewInterceptor
4922019-08-08 12:13:16,913 INFO  [stdout] (default task-46) 12:13:16.912 [default task-46] DEBUG o.h.e.i.AbstractFlushingEventListener - Flushed: 0 insertions, 0 updates, 0 deletions to 2 objects
4932019-08-08 12:13:16,913 INFO  [stdout] (default task-47) 12:13:16.913 [default task-47] DEBUG o.s.s.w.h.writers.HstsHeaderWriter - Not injecting HSTS header since it did not match the requestMatcher org.springframework.security.web.header.writers.HstsHeaderWriter$SecureRequestMatcher@e398f1b
4942019-08-08 12:13:16,914 INFO  [stdout] (default task-49) 12:13:16.913 [default task-49] DEBUG o.s.web.servlet.DispatcherServlet - Completed 200 OK
4952019-08-08 12:13:16,914 INFO  [stdout] (default task-46) 12:13:16.913 [default task-46] DEBUG o.h.e.i.AbstractFlushingEventListener - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
4962019-08-08 12:13:16,915 INFO  [stdout] (default task-46) 12:13:16.914 [default task-46] DEBUG o.h.internal.util.EntityPrinter - Listing entities:
4972019-08-08 12:13:16,915 INFO  [stdout] (default task-47) 12:13:16.914 [default task-47] DEBUG o.s.o.j.s.OpenEntityManagerInViewInterceptor - Closing JPA EntityManager in OpenEntityManagerInViewInterceptor
4982019-08-08 12:13:16,916 INFO  [stdout] (default task-49) 12:13:16.914 [default task-49] DEBUG o.s.s.w.a.ExceptionTranslationFilter - Chain processed normally
4992019-08-08 12:13:16,916 INFO  [stdout] (default task-46) 12:13:16.915 [default task-46] DEBUG o.h.internal.util.EntityPrinter - org.datalis.plugin.entity.Businesses{company_id=4, updated_at=null, business_owner_type=Merchant, created_at=null, id=34, business_owner_id=1}
5002019-08-08 12:13:16,917 INFO  [stdout] (default task-47) 12:13:16.916 [default task-47] DEBUG o.s.web.servlet.DispatcherServlet - Completed 200 OK
5012019-08-08 12:13:16,917 INFO  [stdout] (default task-49) 12:13:16.916 [default task-49] DEBUG o.s.s.w.c.SecurityContextPersistenceFilter - SecurityContextHolder now cleared, as request processing completed
5022019-08-08 12:13:16,918 INFO  [stdout] (default task-46) 12:13:16.917 [default task-46] DEBUG o.h.internal.util.EntityPrinter - org.datalis.plugin.entity.Businesses{company_id=5, updated_at=null, business_owner_type=Merchant, created_at=null, id=35, business_owner_id=1}
5032019-08-08 12:13:16,918 INFO  [stdout] (default task-47) 12:13:16.917 [default task-47] DEBUG o.s.s.w.a.ExceptionTranslationFilter - Chain processed normally
5042019-08-08 12:13:16,918 INFO  [stdout] (default task-46) 12:13:16.918 [default task-46] DEBUG o.s.orm.jpa.JpaTransactionManager - Not closing pre-bound JPA EntityManager after transaction
5052019-08-08 12:13:16,919 INFO  [stdout] (default task-47) 12:13:16.918 [default task-47] DEBUG o.s.s.w.c.SecurityContextPersistenceFilter - SecurityContextHolder now cleared, as request processing completed
5062019-08-08 12:13:16,919 INFO  [stdout] (default task-46) 12:13:16.919 [default task-46] DEBUG o.s.w.s.m.m.a.HttpEntityMethodProcessor - Using 'application/json', given [application/json, text/plain, */*] and supported [application/json, application/*+json, application/json, application/*+json, application/xml, text/xml, application/*+xml, application/xml, text/xml, application/*+xml]
5072019-08-08 12:13:16,920 INFO  [stdout] (default task-46) 12:13:16.920 [default task-46] DEBUG o.s.w.s.m.m.a.HttpEntityMethodProcessor - Writing [[org.datalis.plugin.entity.Businesses@5f7f011e, org.datalis.plugin.entity.Businesses@218a563d]]
5082019-08-08 12:13:16,921 INFO  [stdout] (default task-46) 12:13:16.921 [default task-46] DEBUG o.s.s.w.h.writers.HstsHeaderWriter - Not injecting HSTS header since it did not match the requestMatcher org.springframework.security.web.header.writers.HstsHeaderWriter$SecureRequestMatcher@e398f1b
5092019-08-08 12:13:16,922 INFO  [stdout] (default task-46) 12:13:16.922 [default task-46] DEBUG o.s.o.j.s.OpenEntityManagerInViewInterceptor - Closing JPA EntityManager in OpenEntityManagerInViewInterceptor
5102019-08-08 12:13:16,922 INFO  [stdout] (default task-46) 12:13:16.922 [default task-46] DEBUG o.s.web.servlet.DispatcherServlet - Completed 200 OK
5112019-08-08 12:13:16,923 INFO  [stdout] (default task-46) 12:13:16.923 [default task-46] DEBUG o.s.s.w.a.ExceptionTranslationFilter - Chain processed normally
5122019-08-08 12:13:16,923 INFO  [stdout] (default task-46) 12:13:16.923 [default task-46] DEBUG o.s.s.w.c.SecurityContextPersistenceFilter - SecurityContextHolder now cleared, as request processing completed
513^C
514root@datalis:/opt/wildfly-16.0.0.Final/standalone/log#