· 5 years ago · Dec 02, 2019, 10:16 PM
12019-12-02 23:08:11.775 INFO 10666 --- [ Test worker] o.s.t.c.transaction.TransactionContext : Began transaction (1) for test context [DefaultTestContext@6ed0f64d testClass = ClanControllerTest, testInstance = com.faforever.api.clan.ClanControllerTest@65a7ce62, testMethod = createClanWithExistingName@ClanControllerTest, testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@5621b252 testClass = ClanControllerTest, locations = '{}', classes = '{class com.faforever.api.FafApiApplication}', contextInitializerClasses = '[]', activeProfiles = '{int}', propertySourceLocations = '{}', propertySourceProperties = '{org.springframework.boot.test.context.SpringBootTestContextBootstrapper=true, server.port=0}', contextCustomizers = set[org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@75a0a5d2, [ImportsContextCustomizer@2219c183 key = [com.faforever.api.utils.OAuthHelper]], org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@f8d19d1, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@2cc411ad, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.web.client.TestRestTemplateContextCustomizer@1372fa09], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.boot.test.context.SpringBootContextLoader', parent = [null]], attributes = map['org.springframework.test.context.web.ServletTestExecutionListener.activateListener' -> false]]; transaction manager [org.springframework.orm.jpa.JpaTransactionManager@4b8fd631]; rollback [true]
22019-12-02 23:08:11.776 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:0, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["SET FOREIGN_KEY_CHECKS = 0"], Params:[]
32019-12-02 23:08:11.777 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM reported_user"], Params:[]
42019-12-02 23:08:11.778 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM ban"], Params:[]
52019-12-02 23:08:11.778 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:0, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM moderation_report"], Params:[]
62019-12-02 23:08:11.779 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:0, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM teamkills"], Params:[]
72019-12-02 23:08:11.780 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM unique_id_users"], Params:[]
82019-12-02 23:08:11.780 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:0, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM uniqueid"], Params:[]
92019-12-02 23:08:11.781 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM global_rating"], Params:[]
102019-12-02 23:08:11.782 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM ladder1v1_rating"], Params:[]
112019-12-02 23:08:11.782 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:0, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM uniqueid_exempt"], Params:[]
122019-12-02 23:08:11.784 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:2, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM version_lobby"], Params:[]
132019-12-02 23:08:11.784 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:0, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM friends_and_foes"], Params:[]
142019-12-02 23:08:11.785 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM ladder_map"], Params:[]
152019-12-02 23:08:11.786 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM tutorial"], Params:[]
162019-12-02 23:08:11.786 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:0, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM map_version_review"], Params:[]
172019-12-02 23:08:11.787 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM map_version_reviews_summary"], Params:[]
182019-12-02 23:08:11.788 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM map_version"], Params:[]
192019-12-02 23:08:11.789 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM `map`"], Params:[]
202019-12-02 23:08:11.789 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:0, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM mod_version_review"], Params:[]
212019-12-02 23:08:11.790 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM mod_version_reviews_summary"], Params:[]
222019-12-02 23:08:11.791 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM mod_version"], Params:[]
232019-12-02 23:08:11.791 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:0, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM `mod`"], Params:[]
242019-12-02 23:08:11.792 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM mod_stats"], Params:[]
252019-12-02 23:08:11.793 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM oauth_clients"], Params:[]
262019-12-02 23:08:11.794 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM updates_faf"], Params:[]
272019-12-02 23:08:11.795 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM updates_faf_files"], Params:[]
282019-12-02 23:08:11.795 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:0, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM avatars"], Params:[]
292019-12-02 23:08:11.796 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM avatars_list"], Params:[]
302019-12-02 23:08:11.797 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM ban"], Params:[]
312019-12-02 23:08:11.798 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM clan_membership"], Params:[]
322019-12-02 23:08:11.799 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM clan"], Params:[]
332019-12-02 23:08:11.800 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM game_player_stats"], Params:[]
342019-12-02 23:08:11.800 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:0, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM game_review"], Params:[]
352019-12-02 23:08:11.801 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:0, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM game_reviews_summary"], Params:[]
362019-12-02 23:08:11.802 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM game_stats"], Params:[]
372019-12-02 23:08:11.802 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:0, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM game_featuredMods"], Params:[]
382019-12-02 23:08:11.803 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM ladder_division_score"], Params:[]
392019-12-02 23:08:11.804 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM ladder_division"], Params:[]
402019-12-02 23:08:11.805 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM lobby_admin"], Params:[]
412019-12-02 23:08:11.806 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM name_history"], Params:[]
422019-12-02 23:08:11.806 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:0, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM group_permission_assignment"], Params:[]
432019-12-02 23:08:11.808 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:2, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM group_permission"], Params:[]
442019-12-02 23:08:11.810 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:2, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM user_group_assignment"], Params:[]
452019-12-02 23:08:11.811 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM user_group"], Params:[]
462019-12-02 23:08:11.813 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:2, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM login"], Params:[]
472019-12-02 23:08:11.814 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM email_domain_blacklist"], Params:[]
482019-12-02 23:08:11.814 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:0, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["SET FOREIGN_KEY_CHECKS = 1"], Params:[]
492019-12-02 23:08:11.816 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["INSERT INTO oauth_clients (id, name, client_secret, client_type, redirect_uris, default_redirect_uri, default_scope) VALUES ('test', 'test', '{noop}test', 'public', 'http://localhost https://www.getpostman.com/oauth2/callback ', 'http://localhost', 'read_events read_achievements upload_map upload_mod upload_avatar write_account_data vote')"], Params:[]
502019-12-02 23:08:11.818 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:2, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["INSERT INTO login (id, login, email, password, steamid) VALUES (1, 'USER', 'user@faforever.com', '92b7b421992ef490f3b75898ec0e511f1a5c02422819d89719b20362b023ee4f', NULL), (2, 'MODERATOR', 'moderator@faforever.com', '778ac5b81fa251b450f827846378739caee510c31b01cfa9d31822b88bed8441', 1234), (3, 'ADMIN', 'admin@faforever.com', '835d6dc88b708bc646d6db82c853ef4182fabbd4a8de59c213f2b5ab3ae7d9be', NULL), (4, 'BANNED', 'banned@faforever.com', '', NULL), (5, 'ACTIVE_USER', 'active-user@faforever.com', '', true)"], Params:[]
512019-12-02 23:08:11.819 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["INSERT INTO user_group (id, technical_name, name_key, parent_group_id, public) VALUES (1, 'ADMINISTRATOR', 'administrator', null, true), (2, 'MODERATOR', 'moderator', null, true)"], Params:[]
522019-12-02 23:08:11.820 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["INSERT INTO user_group_assignment (user_id, group_id) VALUES (2, 2), (3, 1)"], Params:[]
532019-12-02 23:08:11.821 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["INSERT INTO name_history (change_time, user_id, previous_name) VALUES (NOW(), 2, 'OLD_MODERATOR')"], Params:[]
542019-12-02 23:08:11.823 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM clan_membership"], Params:[]
552019-12-02 23:08:11.824 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["DELETE FROM clan"], Params:[]
562019-12-02 23:08:11.825 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["INSERT INTO login (id, login, email, password) VALUES (10, 'CLAN_FOUNDER', 'clan_founder@faforever.com', 'not used'), (11, 'CLAN_LEADER', 'clan_leader@faforever.com', 'not used'), (12, 'CLAN_MEMBER', 'clan_member@faforever.com', 'not used'), (13, 'CLAN_MEMBER_B', 'clan_member_b@faforever.com', 'not used')"], Params:[]
572019-12-02 23:08:11.830 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:5, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["INSERT INTO clan (id, name, tag, founder_id, leader_id, description) VALUES (1, 'Alpha Clan', '123', 10, 11, 'Lorem ipsum dolor sit amet, consetetur sadipscing elitr'), (2, 'Beta Clan', '345', 1, 1, 'Sed diam nonumy eirmod tempor invidunt ut labore')"], Params:[]
582019-12-02 23:08:11.833 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:3, Success:True, Type:Statement, Batch:False, QuerySize:1, BatchSize:0, Query:["INSERT INTO clan_membership (id, clan_id, player_id) VALUES (1, 1, 11), (2, 1, 12), (3, 1, 13), (4, 2, 10)"], Params:[]
59Hibernate: select user0_.id as id1_25_, user0_.create_time as create_t2_25_, user0_.update_time as update_t3_25_, user0_.email as email4_25_, user0_.last_login as last_log5_25_, user0_.login as login6_25_, user0_.ip as ip7_25_, user0_.steamid as steamid8_25_, user0_.user_agent as user_age9_25_, user0_.password as passwor10_25_ from login user0_ where user0_.login=? or user0_.email=?
602019-12-02 23:08:11.836 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Prepared, Batch:False, QuerySize:1, BatchSize:0, Query:["select user0_.id as id1_25_, user0_.create_time as create_t2_25_, user0_.update_time as update_t3_25_, user0_.email as email4_25_, user0_.last_login as last_log5_25_, user0_.login as login6_25_, user0_.ip as ip7_25_, user0_.steamid as steamid8_25_, user0_.user_agent as user_age9_25_, user0_.password as passwor10_25_ from login user0_ where user0_.login=? or user0_.email=?"], Params:[(USER,USER)]
61Hibernate: select usernotes0_.user_id as user_id7_49_0_, usernotes0_.id as id1_49_0_, usernotes0_.id as id1_49_1_, usernotes0_.create_time as create_t2_49_1_, usernotes0_.update_time as update_t3_49_1_, usernotes0_.author as author6_49_1_, usernotes0_.note as note4_49_1_, usernotes0_.user_id as user_id7_49_1_, usernotes0_.watched as watched5_49_1_ from user_notes usernotes0_ where usernotes0_.user_id=?
622019-12-02 23:08:11.841 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Prepared, Batch:False, QuerySize:1, BatchSize:0, Query:["select usernotes0_.user_id as user_id7_49_0_, usernotes0_.id as id1_49_0_, usernotes0_.id as id1_49_1_, usernotes0_.create_time as create_t2_49_1_, usernotes0_.update_time as update_t3_49_1_, usernotes0_.author as author6_49_1_, usernotes0_.note as note4_49_1_, usernotes0_.user_id as user_id7_49_1_, usernotes0_.watched as watched5_49_1_ from user_notes usernotes0_ where usernotes0_.user_id=?"], Params:[(1)]
63Hibernate: select bans0_.player_id as player_11_4_0_, bans0_.id as id1_4_0_, bans0_.id as id1_4_1_, bans0_.create_time as create_t2_4_1_, bans0_.update_time as update_t3_4_1_, bans0_.author_id as author_i9_4_1_, bans0_.expires_at as expires_4_4_1_, bans0_.level as level5_4_1_, bans0_.report_id as report_10_4_1_, bans0_.player_id as player_11_4_1_, bans0_.reason as reason6_4_1_, bans0_.revoke_author_id as revoke_12_4_1_, bans0_.revoke_reason as revoke_r7_4_1_, bans0_.revoke_time as revoke_t8_4_1_, player1_.id as id1_25_2_, player1_.create_time as create_t2_25_2_, player1_.update_time as update_t3_25_2_, player1_.email as email4_25_2_, player1_.last_login as last_log5_25_2_, player1_.login as login6_25_2_, player1_.ip as ip7_25_2_, player1_.steamid as steamid8_25_2_, player1_.user_agent as user_age9_25_2_, clanmember2_.id as id1_6_3_, clanmember2_.create_time as create_t2_6_3_, clanmember2_.update_time as update_t3_6_3_, clanmember2_.clan_id as clan_id4_6_3_, clanmember2_.player_id as player_i5_6_3_, moderation3_.id as id1_37_4_, moderation3_.create_time as create_t2_37_4_, moderation3_.update_time as update_t3_37_4_, moderation3_.game_id as game_id9_37_4_, moderation3_.game_incident_timecode as game_inc4_37_4_, moderation3_.last_moderator as last_mo10_37_4_, moderation3_.moderator_notice as moderato5_37_4_, moderation3_.moderator_private_note as moderato6_37_4_, moderation3_.report_description as report_d7_37_4_, moderation3_.report_status as report_s8_37_4_, moderation3_.reporter_id as reporte11_37_4_, player4_.id as id1_25_5_, player4_.create_time as create_t2_25_5_, player4_.update_time as update_t3_25_5_, player4_.email as email4_25_5_, player4_.last_login as last_log5_25_5_, player4_.login as login6_25_5_, player4_.ip as ip7_25_5_, player4_.steamid as steamid8_25_5_, player4_.user_agent as user_age9_25_5_ from ban bans0_ inner join login player1_ on bans0_.author_id=player1_.id left outer join clan_membership clanmember2_ on player1_.id=clanmember2_.player_id left outer join moderation_report moderation3_ on bans0_.report_id=moderation3_.id left outer join login player4_ on bans0_.revoke_author_id=player4_.id where bans0_.player_id=?
642019-12-02 23:08:11.843 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Prepared, Batch:False, QuerySize:1, BatchSize:0, Query:["select bans0_.player_id as player_11_4_0_, bans0_.id as id1_4_0_, bans0_.id as id1_4_1_, bans0_.create_time as create_t2_4_1_, bans0_.update_time as update_t3_4_1_, bans0_.author_id as author_i9_4_1_, bans0_.expires_at as expires_4_4_1_, bans0_.level as level5_4_1_, bans0_.report_id as report_10_4_1_, bans0_.player_id as player_11_4_1_, bans0_.reason as reason6_4_1_, bans0_.revoke_author_id as revoke_12_4_1_, bans0_.revoke_reason as revoke_r7_4_1_, bans0_.revoke_time as revoke_t8_4_1_, player1_.id as id1_25_2_, player1_.create_time as create_t2_25_2_, player1_.update_time as update_t3_25_2_, player1_.email as email4_25_2_, player1_.last_login as last_log5_25_2_, player1_.login as login6_25_2_, player1_.ip as ip7_25_2_, player1_.steamid as steamid8_25_2_, player1_.user_agent as user_age9_25_2_, clanmember2_.id as id1_6_3_, clanmember2_.create_time as create_t2_6_3_, clanmember2_.update_time as update_t3_6_3_, clanmember2_.clan_id as clan_id4_6_3_, clanmember2_.player_id as player_i5_6_3_, moderation3_.id as id1_37_4_, moderation3_.create_time as create_t2_37_4_, moderation3_.update_time as update_t3_37_4_, moderation3_.game_id as game_id9_37_4_, moderation3_.game_incident_timecode as game_inc4_37_4_, moderation3_.last_moderator as last_mo10_37_4_, moderation3_.moderator_notice as moderato5_37_4_, moderation3_.moderator_private_note as moderato6_37_4_, moderation3_.report_description as report_d7_37_4_, moderation3_.report_status as report_s8_37_4_, moderation3_.reporter_id as reporte11_37_4_, player4_.id as id1_25_5_, player4_.create_time as create_t2_25_5_, player4_.update_time as update_t3_25_5_, player4_.email as email4_25_5_, player4_.last_login as last_log5_25_5_, player4_.login as login6_25_5_, player4_.ip as ip7_25_5_, player4_.steamid as steamid8_25_5_, player4_.user_agent as user_age9_25_5_ from ban bans0_ inner join login player1_ on bans0_.author_id=player1_.id left outer join clan_membership clanmember2_ on player1_.id=clanmember2_.player_id left outer join moderation_report moderation3_ on bans0_.report_id=moderation3_.id left outer join login player4_ on bans0_.revoke_author_id=player4_.id where bans0_.player_id=?"], Params:[(1)]
65Hibernate: select usergroups0_.user_id as user_id2_48_0_, usergroups0_.group_id as group_id1_48_0_, usergroup1_.id as id1_47_1_, usergroup1_.create_time as create_t2_47_1_, usergroup1_.update_time as update_t3_47_1_, usergroup1_.name_key as name_key4_47_1_, usergroup1_.parent_group_id as parent_g7_47_1_, usergroup1_.public as public5_47_1_, usergroup1_.technical_name as technica6_47_1_, usergroup2_.id as id1_47_2_, usergroup2_.create_time as create_t2_47_2_, usergroup2_.update_time as update_t3_47_2_, usergroup2_.name_key as name_key4_47_2_, usergroup2_.parent_group_id as parent_g7_47_2_, usergroup2_.public as public5_47_2_, usergroup2_.technical_name as technica6_47_2_ from user_group_assignment usergroups0_ inner join user_group usergroup1_ on usergroups0_.group_id=usergroup1_.id left outer join user_group usergroup2_ on usergroup1_.parent_group_id=usergroup2_.id where usergroups0_.user_id=?
662019-12-02 23:08:11.845 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Prepared, Batch:False, QuerySize:1, BatchSize:0, Query:["select usergroups0_.user_id as user_id2_48_0_, usergroups0_.group_id as group_id1_48_0_, usergroup1_.id as id1_47_1_, usergroup1_.create_time as create_t2_47_1_, usergroup1_.update_time as update_t3_47_1_, usergroup1_.name_key as name_key4_47_1_, usergroup1_.parent_group_id as parent_g7_47_1_, usergroup1_.public as public5_47_1_, usergroup1_.technical_name as technica6_47_1_, usergroup2_.id as id1_47_2_, usergroup2_.create_time as create_t2_47_2_, usergroup2_.update_time as update_t3_47_2_, usergroup2_.name_key as name_key4_47_2_, usergroup2_.parent_group_id as parent_g7_47_2_, usergroup2_.public as public5_47_2_, usergroup2_.technical_name as technica6_47_2_ from user_group_assignment usergroups0_ inner join user_group usergroup1_ on usergroups0_.group_id=usergroup1_.id left outer join user_group usergroup2_ on usergroup1_.parent_group_id=usergroup2_.id where usergroups0_.user_id=?"], Params:[(1)]
672019-12-02 23:08:11.848 INFO 10666 --- [ Test worker] o.e.j.s.h.ContextHandler.application : Initializing Spring TestDispatcherServlet ''
682019-12-02 23:08:11.848 INFO 10666 --- [ Test worker] o.s.t.web.servlet.TestDispatcherServlet : Initializing Servlet ''
692019-12-02 23:08:11.852 INFO 10666 --- [ Test worker] o.s.t.web.servlet.TestDispatcherServlet : Completed initialization in 4 ms
70Hibernate: select player0_.id as id1_25_0_, player0_.create_time as create_t2_25_0_, player0_.update_time as update_t3_25_0_, player0_.email as email4_25_0_, player0_.last_login as last_log5_25_0_, player0_.login as login6_25_0_, player0_.ip as ip7_25_0_, player0_.steamid as steamid8_25_0_, player0_.user_agent as user_age9_25_0_, bans1_.player_id as player_11_4_1_, bans1_.id as id1_4_1_, bans1_.id as id1_4_2_, bans1_.create_time as create_t2_4_2_, bans1_.update_time as update_t3_4_2_, bans1_.author_id as author_i9_4_2_, bans1_.expires_at as expires_4_4_2_, bans1_.level as level5_4_2_, bans1_.report_id as report_10_4_2_, bans1_.player_id as player_11_4_2_, bans1_.reason as reason6_4_2_, bans1_.revoke_author_id as revoke_12_4_2_, bans1_.revoke_reason as revoke_r7_4_2_, bans1_.revoke_time as revoke_t8_4_2_, player2_.id as id1_25_3_, player2_.create_time as create_t2_25_3_, player2_.update_time as update_t3_25_3_, player2_.email as email4_25_3_, player2_.last_login as last_log5_25_3_, player2_.login as login6_25_3_, player2_.ip as ip7_25_3_, player2_.steamid as steamid8_25_3_, player2_.user_agent as user_age9_25_3_, moderation3_.id as id1_37_4_, moderation3_.create_time as create_t2_37_4_, moderation3_.update_time as update_t3_37_4_, moderation3_.game_id as game_id9_37_4_, moderation3_.game_incident_timecode as game_inc4_37_4_, moderation3_.last_moderator as last_mo10_37_4_, moderation3_.moderator_notice as moderato5_37_4_, moderation3_.moderator_private_note as moderato6_37_4_, moderation3_.report_description as report_d7_37_4_, moderation3_.report_status as report_s8_37_4_, moderation3_.reporter_id as reporte11_37_4_, player4_.id as id1_25_5_, player4_.create_time as create_t2_25_5_, player4_.update_time as update_t3_25_5_, player4_.email as email4_25_5_, player4_.last_login as last_log5_25_5_, player4_.login as login6_25_5_, player4_.ip as ip7_25_5_, player4_.steamid as steamid8_25_5_, player4_.user_agent as user_age9_25_5_, usernotes5_.user_id as user_id7_49_6_, usernotes5_.id as id1_49_6_, usernotes5_.id as id1_49_7_, usernotes5_.create_time as create_t2_49_7_, usernotes5_.update_time as update_t3_49_7_, usernotes5_.author as author6_49_7_, usernotes5_.note as note4_49_7_, usernotes5_.user_id as user_id7_49_7_, usernotes5_.watched as watched5_49_7_, clanmember6_.id as id1_6_8_, clanmember6_.create_time as create_t2_6_8_, clanmember6_.update_time as update_t3_6_8_, clanmember6_.clan_id as clan_id4_6_8_, clanmember6_.player_id as player_i5_6_8_ from login player0_ left outer join ban bans1_ on player0_.id=bans1_.player_id left outer join login player2_ on bans1_.author_id=player2_.id left outer join moderation_report moderation3_ on bans1_.report_id=moderation3_.id left outer join login player4_ on bans1_.revoke_author_id=player4_.id left outer join user_notes usernotes5_ on player0_.id=usernotes5_.user_id left outer join clan_membership clanmember6_ on player0_.id=clanmember6_.player_id where player0_.id=?
712019-12-02 23:08:11.856 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:2, Success:True, Type:Prepared, Batch:False, QuerySize:1, BatchSize:0, Query:["select player0_.id as id1_25_0_, player0_.create_time as create_t2_25_0_, player0_.update_time as update_t3_25_0_, player0_.email as email4_25_0_, player0_.last_login as last_log5_25_0_, player0_.login as login6_25_0_, player0_.ip as ip7_25_0_, player0_.steamid as steamid8_25_0_, player0_.user_agent as user_age9_25_0_, bans1_.player_id as player_11_4_1_, bans1_.id as id1_4_1_, bans1_.id as id1_4_2_, bans1_.create_time as create_t2_4_2_, bans1_.update_time as update_t3_4_2_, bans1_.author_id as author_i9_4_2_, bans1_.expires_at as expires_4_4_2_, bans1_.level as level5_4_2_, bans1_.report_id as report_10_4_2_, bans1_.player_id as player_11_4_2_, bans1_.reason as reason6_4_2_, bans1_.revoke_author_id as revoke_12_4_2_, bans1_.revoke_reason as revoke_r7_4_2_, bans1_.revoke_time as revoke_t8_4_2_, player2_.id as id1_25_3_, player2_.create_time as create_t2_25_3_, player2_.update_time as update_t3_25_3_, player2_.email as email4_25_3_, player2_.last_login as last_log5_25_3_, player2_.login as login6_25_3_, player2_.ip as ip7_25_3_, player2_.steamid as steamid8_25_3_, player2_.user_agent as user_age9_25_3_, moderation3_.id as id1_37_4_, moderation3_.create_time as create_t2_37_4_, moderation3_.update_time as update_t3_37_4_, moderation3_.game_id as game_id9_37_4_, moderation3_.game_incident_timecode as game_inc4_37_4_, moderation3_.last_moderator as last_mo10_37_4_, moderation3_.moderator_notice as moderato5_37_4_, moderation3_.moderator_private_note as moderato6_37_4_, moderation3_.report_description as report_d7_37_4_, moderation3_.report_status as report_s8_37_4_, moderation3_.reporter_id as reporte11_37_4_, player4_.id as id1_25_5_, player4_.create_time as create_t2_25_5_, player4_.update_time as update_t3_25_5_, player4_.email as email4_25_5_, player4_.last_login as last_log5_25_5_, player4_.login as login6_25_5_, player4_.ip as ip7_25_5_, player4_.steamid as steamid8_25_5_, player4_.user_agent as user_age9_25_5_, usernotes5_.user_id as user_id7_49_6_, usernotes5_.id as id1_49_6_, usernotes5_.id as id1_49_7_, usernotes5_.create_time as create_t2_49_7_, usernotes5_.update_time as update_t3_49_7_, usernotes5_.author as author6_49_7_, usernotes5_.note as note4_49_7_, usernotes5_.user_id as user_id7_49_7_, usernotes5_.watched as watched5_49_7_, clanmember6_.id as id1_6_8_, clanmember6_.create_time as create_t2_6_8_, clanmember6_.update_time as update_t3_6_8_, clanmember6_.clan_id as clan_id4_6_8_, clanmember6_.player_id as player_i5_6_8_ from login player0_ left outer join ban bans1_ on player0_.id=bans1_.player_id left outer join login player2_ on bans1_.author_id=player2_.id left outer join moderation_report moderation3_ on bans1_.report_id=moderation3_.id left outer join login player4_ on bans1_.revoke_author_id=player4_.id left outer join user_notes usernotes5_ on player0_.id=usernotes5_.user_id left outer join clan_membership clanmember6_ on player0_.id=clanmember6_.player_id where player0_.id=?"], Params:[(1)]
72Hibernate: select globalrati0_.id as id1_17_0_, globalrati0_.deviation as deviatio2_17_0_, globalrati0_.mean as mean3_17_0_, globalrati0_.rating as rating4_17_0_ from global_rating globalrati0_ where globalrati0_.id=?
732019-12-02 23:08:11.858 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Prepared, Batch:False, QuerySize:1, BatchSize:0, Query:["select globalrati0_.id as id1_17_0_, globalrati0_.deviation as deviatio2_17_0_, globalrati0_.mean as mean3_17_0_, globalrati0_.rating as rating4_17_0_ from global_rating globalrati0_ where globalrati0_.id=?"], Params:[(1)]
74Hibernate: select ladder1v1r0_.id as id1_20_0_, ladder1v1r0_.deviation as deviatio2_20_0_, ladder1v1r0_.mean as mean3_20_0_, ladder1v1r0_.rating as rating4_20_0_ from ladder1v1_rating ladder1v1r0_ where ladder1v1r0_.id=?
752019-12-02 23:08:11.864 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:4, Success:True, Type:Prepared, Batch:False, QuerySize:1, BatchSize:0, Query:["select ladder1v1r0_.id as id1_20_0_, ladder1v1r0_.deviation as deviatio2_20_0_, ladder1v1r0_.mean as mean3_20_0_, ladder1v1r0_.rating as rating4_20_0_ from ladder1v1_rating ladder1v1r0_ where ladder1v1r0_.id=?"], Params:[(1)]
76Hibernate: select clan0_.id as id1_5_, clan0_.create_time as create_t2_5_, clan0_.update_time as update_t3_5_, clan0_.description as descript4_5_, clan0_.founder_id as founder_9_5_, clan0_.leader_id as leader_10_5_, clan0_.name as name5_5_, clan0_.requires_invitation as requires6_5_, clan0_.tag as tag7_5_, clan0_.tag_color as tag_colo8_5_ from clan clan0_ where clan0_.name=?
772019-12-02 23:08:11.868 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:1, Success:True, Type:Prepared, Batch:False, QuerySize:1, BatchSize:0, Query:["select clan0_.id as id1_5_, clan0_.create_time as create_t2_5_, clan0_.update_time as update_t3_5_, clan0_.description as descript4_5_, clan0_.founder_id as founder_9_5_, clan0_.leader_id as leader_10_5_, clan0_.name as name5_5_, clan0_.requires_invitation as requires6_5_, clan0_.tag as tag7_5_, clan0_.tag_color as tag_colo8_5_ from clan clan0_ where clan0_.name=?"], Params:[(Alpha Clan)]
782019-12-02 23:08:11.870 DEBUG 10666 --- [ Test worker] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/oauth/token']
792019-12-02 23:08:11.870 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/oauth/token'
802019-12-02 23:08:11.870 DEBUG 10666 --- [ Test worker] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/oauth/token_key']
812019-12-02 23:08:11.870 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/oauth/token_key'
822019-12-02 23:08:11.870 DEBUG 10666 --- [ Test worker] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/oauth/check_token']
832019-12-02 23:08:11.870 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/oauth/check_token'
842019-12-02 23:08:11.870 DEBUG 10666 --- [ Test worker] o.s.s.web.util.matcher.OrRequestMatcher : No matches found
852019-12-02 23:08:11.871 DEBUG 10666 --- [ Test worker] w.c.HttpSessionSecurityContextRepository : No HttpSession currently exists
862019-12-02 23:08:11.871 DEBUG 10666 --- [ Test worker] w.c.HttpSessionSecurityContextRepository : No SecurityContext was available from the HttpSession: null. A new one will be created.
872019-12-02 23:08:11.871 DEBUG 10666 --- [ Test worker] w.c.HttpSessionSecurityContextRepository : HttpSession being created as SecurityContext is non-default
882019-12-02 23:08:11.871 DEBUG 10666 --- [ Test worker] w.c.HttpSessionSecurityContextRepository : SecurityContext 'org.springframework.security.core.context.SecurityContextImpl@9e0c626e: Authentication: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@9e0c626e: Principal: com.faforever.api.security.FafUserDetails@27e3cb: Username: USER; Password: [PROTECTED]; Enabled: true; AccountNonExpired: true; credentialsNonExpired: true; AccountNonLocked: true; Granted Authorities: ROLE_USER; Credentials: [PROTECTED]; Authenticated: true; Details: null; Granted Authorities: ROLE_USER' stored to HttpSession: 'org.springframework.mock.web.MockHttpSession@651a549f
892019-12-02 23:08:11.871 DEBUG 10666 --- [ Test worker] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/oauth/token']
902019-12-02 23:08:11.871 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/oauth/token'
912019-12-02 23:08:11.871 DEBUG 10666 --- [ Test worker] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/oauth/token_key']
922019-12-02 23:08:11.871 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/oauth/token_key'
932019-12-02 23:08:11.871 DEBUG 10666 --- [ Test worker] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/oauth/check_token']
942019-12-02 23:08:11.871 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/oauth/check_token'
952019-12-02 23:08:11.871 DEBUG 10666 --- [ Test worker] o.s.s.web.util.matcher.OrRequestMatcher : No matches found
962019-12-02 23:08:11.871 DEBUG 10666 --- [ Test worker] o.s.security.web.FilterChainProxy : /clans/create at position 1 of 12 in additional filter chain; firing Filter: 'WebAsyncManagerIntegrationFilter'
972019-12-02 23:08:11.871 DEBUG 10666 --- [ Test worker] o.s.security.web.FilterChainProxy : /clans/create at position 2 of 12 in additional filter chain; firing Filter: 'SecurityContextPersistenceFilter'
982019-12-02 23:08:11.871 DEBUG 10666 --- [ Test worker] w.c.HttpSessionSecurityContextRepository : Obtained a valid SecurityContext from SPRING_SECURITY_CONTEXT: 'org.springframework.security.core.context.SecurityContextImpl@9e0c626e: Authentication: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@9e0c626e: Principal: com.faforever.api.security.FafUserDetails@27e3cb: Username: USER; Password: [PROTECTED]; Enabled: true; AccountNonExpired: true; credentialsNonExpired: true; AccountNonLocked: true; Granted Authorities: ROLE_USER; Credentials: [PROTECTED]; Authenticated: true; Details: null; Granted Authorities: ROLE_USER'
992019-12-02 23:08:11.871 DEBUG 10666 --- [ Test worker] o.s.security.web.FilterChainProxy : /clans/create at position 3 of 12 in additional filter chain; firing Filter: 'HeaderWriterFilter'
1002019-12-02 23:08:11.871 DEBUG 10666 --- [ Test worker] o.s.security.web.FilterChainProxy : /clans/create at position 4 of 12 in additional filter chain; firing Filter: 'CsrfFilter'
1012019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/oauth/authorize']
1022019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/oauth/authorize'
1032019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.web.util.matcher.OrRequestMatcher : Trying to match using Ant [pattern='/login']
1042019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/login'
1052019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.web.util.matcher.OrRequestMatcher : No matches found
1062019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.security.web.FilterChainProxy : /clans/create at position 5 of 12 in additional filter chain; firing Filter: 'LogoutFilter'
1072019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/logout'
1082019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.security.web.FilterChainProxy : /clans/create at position 6 of 12 in additional filter chain; firing Filter: 'UsernamePasswordAuthenticationFilter'
1092019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/login'
1102019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.security.web.FilterChainProxy : /clans/create at position 7 of 12 in additional filter chain; firing Filter: 'RequestCacheAwareFilter'
1112019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.w.s.HttpSessionRequestCache : saved request doesn't match
1122019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.security.web.FilterChainProxy : /clans/create at position 8 of 12 in additional filter chain; firing Filter: 'SecurityContextHolderAwareRequestFilter'
1132019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.security.web.FilterChainProxy : /clans/create at position 9 of 12 in additional filter chain; firing Filter: 'AnonymousAuthenticationFilter'
1142019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.w.a.AnonymousAuthenticationFilter : SecurityContextHolder not populated with anonymous token, as it already contained: 'org.springframework.security.authentication.UsernamePasswordAuthenticationToken@9e0c626e: Principal: com.faforever.api.security.FafUserDetails@27e3cb: Username: USER; Password: [PROTECTED]; Enabled: true; AccountNonExpired: true; credentialsNonExpired: true; AccountNonLocked: true; Granted Authorities: ROLE_USER; Credentials: [PROTECTED]; Authenticated: true; Details: null; Granted Authorities: ROLE_USER'
1152019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.security.web.FilterChainProxy : /clans/create at position 10 of 12 in additional filter chain; firing Filter: 'SessionManagementFilter'
1162019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.security.web.FilterChainProxy : /clans/create at position 11 of 12 in additional filter chain; firing Filter: 'ExceptionTranslationFilter'
1172019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.security.web.FilterChainProxy : /clans/create at position 12 of 12 in additional filter chain; firing Filter: 'FilterSecurityInterceptor'
1182019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Request 'POST /clans/create' doesn't match 'OPTIONS /**'
1192019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/swagger-ui.html'
1202019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/swagger-resources/**'
1212019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/v2/api-docs/**'
1222019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/'
1232019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/css/*'
1242019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/favicon.ico'
1252019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.w.u.matcher.AntPathRequestMatcher : Checking match of request : '/clans/create'; against '/robots.txt'
1262019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.s.w.a.i.FilterSecurityInterceptor : Public object - authentication not attempted
1272019-12-02 23:08:11.872 DEBUG 10666 --- [ Test worker] o.s.security.web.FilterChainProxy : /clans/create reached end of additional filter chain; proceeding with original chain
1282019-12-02 23:08:11.874 DEBUG 10666 --- [ Test worker] o.s.s.a.i.a.MethodSecurityInterceptor : Secure object: ReflectiveMethodInvocation: public java.util.Map com.faforever.api.clan.ClansController.createClan(java.lang.String,java.lang.String,java.lang.String); target is of class [com.faforever.api.clan.ClansController]; Attributes: [[authorize: 'hasRole('ROLE_USER')', filter: 'null', filterTarget: 'null']]
1292019-12-02 23:08:11.874 DEBUG 10666 --- [ Test worker] o.s.s.a.i.a.MethodSecurityInterceptor : Previously Authenticated: org.springframework.security.authentication.UsernamePasswordAuthenticationToken@9e0c626e: Principal: com.faforever.api.security.FafUserDetails@27e3cb: Username: USER; Password: [PROTECTED]; Enabled: true; AccountNonExpired: true; credentialsNonExpired: true; AccountNonLocked: true; Granted Authorities: ROLE_USER; Credentials: [PROTECTED]; Authenticated: true; Details: null; Granted Authorities: ROLE_USER
1302019-12-02 23:08:11.875 DEBUG 10666 --- [ Test worker] o.s.s.access.vote.AffirmativeBased : Voter: org.springframework.security.access.prepost.PreInvocationAuthorizationAdviceVoter@56ec32db, returned: 1
1312019-12-02 23:08:11.875 DEBUG 10666 --- [ Test worker] o.s.s.a.i.a.MethodSecurityInterceptor : Authorization successful
1322019-12-02 23:08:11.875 DEBUG 10666 --- [ Test worker] o.s.s.a.i.a.MethodSecurityInterceptor : RunAsManager did not change Authentication object
133Hibernate: select player0_.id as id1_25_0_, player0_.create_time as create_t2_25_0_, player0_.update_time as update_t3_25_0_, player0_.email as email4_25_0_, player0_.last_login as last_log5_25_0_, player0_.login as login6_25_0_, player0_.ip as ip7_25_0_, player0_.steamid as steamid8_25_0_, player0_.user_agent as user_age9_25_0_, bans1_.player_id as player_11_4_1_, bans1_.id as id1_4_1_, bans1_.id as id1_4_2_, bans1_.create_time as create_t2_4_2_, bans1_.update_time as update_t3_4_2_, bans1_.author_id as author_i9_4_2_, bans1_.expires_at as expires_4_4_2_, bans1_.level as level5_4_2_, bans1_.report_id as report_10_4_2_, bans1_.player_id as player_11_4_2_, bans1_.reason as reason6_4_2_, bans1_.revoke_author_id as revoke_12_4_2_, bans1_.revoke_reason as revoke_r7_4_2_, bans1_.revoke_time as revoke_t8_4_2_, player2_.id as id1_25_3_, player2_.create_time as create_t2_25_3_, player2_.update_time as update_t3_25_3_, player2_.email as email4_25_3_, player2_.last_login as last_log5_25_3_, player2_.login as login6_25_3_, player2_.ip as ip7_25_3_, player2_.steamid as steamid8_25_3_, player2_.user_agent as user_age9_25_3_, moderation3_.id as id1_37_4_, moderation3_.create_time as create_t2_37_4_, moderation3_.update_time as update_t3_37_4_, moderation3_.game_id as game_id9_37_4_, moderation3_.game_incident_timecode as game_inc4_37_4_, moderation3_.last_moderator as last_mo10_37_4_, moderation3_.moderator_notice as moderato5_37_4_, moderation3_.moderator_private_note as moderato6_37_4_, moderation3_.report_description as report_d7_37_4_, moderation3_.report_status as report_s8_37_4_, moderation3_.reporter_id as reporte11_37_4_, player4_.id as id1_25_5_, player4_.create_time as create_t2_25_5_, player4_.update_time as update_t3_25_5_, player4_.email as email4_25_5_, player4_.last_login as last_log5_25_5_, player4_.login as login6_25_5_, player4_.ip as ip7_25_5_, player4_.steamid as steamid8_25_5_, player4_.user_agent as user_age9_25_5_, usernotes5_.user_id as user_id7_49_6_, usernotes5_.id as id1_49_6_, usernotes5_.id as id1_49_7_, usernotes5_.create_time as create_t2_49_7_, usernotes5_.update_time as update_t3_49_7_, usernotes5_.author as author6_49_7_, usernotes5_.note as note4_49_7_, usernotes5_.user_id as user_id7_49_7_, usernotes5_.watched as watched5_49_7_, clanmember6_.id as id1_6_8_, clanmember6_.create_time as create_t2_6_8_, clanmember6_.update_time as update_t3_6_8_, clanmember6_.clan_id as clan_id4_6_8_, clanmember6_.player_id as player_i5_6_8_ from login player0_ left outer join ban bans1_ on player0_.id=bans1_.player_id left outer join login player2_ on bans1_.author_id=player2_.id left outer join moderation_report moderation3_ on bans1_.report_id=moderation3_.id left outer join login player4_ on bans1_.revoke_author_id=player4_.id left outer join user_notes usernotes5_ on player0_.id=usernotes5_.user_id left outer join clan_membership clanmember6_ on player0_.id=clanmember6_.player_id where player0_.id=?
1342019-12-02 23:08:25.083 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:31, Time:96, Success:True, Type:Prepared, Batch:False, QuerySize:1, BatchSize:0, Query:["select player0_.id as id1_25_0_, player0_.create_time as create_t2_25_0_, player0_.update_time as update_t3_25_0_, player0_.email as email4_25_0_, player0_.last_login as last_log5_25_0_, player0_.login as login6_25_0_, player0_.ip as ip7_25_0_, player0_.steamid as steamid8_25_0_, player0_.user_agent as user_age9_25_0_, bans1_.player_id as player_11_4_1_, bans1_.id as id1_4_1_, bans1_.id as id1_4_2_, bans1_.create_time as create_t2_4_2_, bans1_.update_time as update_t3_4_2_, bans1_.author_id as author_i9_4_2_, bans1_.expires_at as expires_4_4_2_, bans1_.level as level5_4_2_, bans1_.report_id as report_10_4_2_, bans1_.player_id as player_11_4_2_, bans1_.reason as reason6_4_2_, bans1_.revoke_author_id as revoke_12_4_2_, bans1_.revoke_reason as revoke_r7_4_2_, bans1_.revoke_time as revoke_t8_4_2_, player2_.id as id1_25_3_, player2_.create_time as create_t2_25_3_, player2_.update_time as update_t3_25_3_, player2_.email as email4_25_3_, player2_.last_login as last_log5_25_3_, player2_.login as login6_25_3_, player2_.ip as ip7_25_3_, player2_.steamid as steamid8_25_3_, player2_.user_agent as user_age9_25_3_, moderation3_.id as id1_37_4_, moderation3_.create_time as create_t2_37_4_, moderation3_.update_time as update_t3_37_4_, moderation3_.game_id as game_id9_37_4_, moderation3_.game_incident_timecode as game_inc4_37_4_, moderation3_.last_moderator as last_mo10_37_4_, moderation3_.moderator_notice as moderato5_37_4_, moderation3_.moderator_private_note as moderato6_37_4_, moderation3_.report_description as report_d7_37_4_, moderation3_.report_status as report_s8_37_4_, moderation3_.reporter_id as reporte11_37_4_, player4_.id as id1_25_5_, player4_.create_time as create_t2_25_5_, player4_.update_time as update_t3_25_5_, player4_.email as email4_25_5_, player4_.last_login as last_log5_25_5_, player4_.login as login6_25_5_, player4_.ip as ip7_25_5_, player4_.steamid as steamid8_25_5_, player4_.user_agent as user_age9_25_5_, usernotes5_.user_id as user_id7_49_6_, usernotes5_.id as id1_49_6_, usernotes5_.id as id1_49_7_, usernotes5_.create_time as create_t2_49_7_, usernotes5_.update_time as update_t3_49_7_, usernotes5_.author as author6_49_7_, usernotes5_.note as note4_49_7_, usernotes5_.user_id as user_id7_49_7_, usernotes5_.watched as watched5_49_7_, clanmember6_.id as id1_6_8_, clanmember6_.create_time as create_t2_6_8_, clanmember6_.update_time as update_t3_6_8_, clanmember6_.clan_id as clan_id4_6_8_, clanmember6_.player_id as player_i5_6_8_ from login player0_ left outer join ban bans1_ on player0_.id=bans1_.player_id left outer join login player2_ on bans1_.author_id=player2_.id left outer join moderation_report moderation3_ on bans1_.report_id=moderation3_.id left outer join login player4_ on bans1_.revoke_author_id=player4_.id left outer join user_notes usernotes5_ on player0_.id=usernotes5_.user_id left outer join clan_membership clanmember6_ on player0_.id=clanmember6_.player_id where player0_.id=?"], Params:[(1)]
135Hibernate: select globalrati0_.id as id1_17_0_, globalrati0_.deviation as deviatio2_17_0_, globalrati0_.mean as mean3_17_0_, globalrati0_.rating as rating4_17_0_ from global_rating globalrati0_ where globalrati0_.id=?
1362019-12-02 23:08:25.358 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:31, Time:10, Success:True, Type:Prepared, Batch:False, QuerySize:1, BatchSize:0, Query:["select globalrati0_.id as id1_17_0_, globalrati0_.deviation as deviatio2_17_0_, globalrati0_.mean as mean3_17_0_, globalrati0_.rating as rating4_17_0_ from global_rating globalrati0_ where globalrati0_.id=?"], Params:[(1)]
137Hibernate: select ladder1v1r0_.id as id1_20_0_, ladder1v1r0_.deviation as deviatio2_20_0_, ladder1v1r0_.mean as mean3_20_0_, ladder1v1r0_.rating as rating4_20_0_ from ladder1v1_rating ladder1v1r0_ where ladder1v1r0_.id=?
1382019-12-02 23:08:25.418 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:31, Time:9, Success:True, Type:Prepared, Batch:False, QuerySize:1, BatchSize:0, Query:["select ladder1v1r0_.id as id1_20_0_, ladder1v1r0_.deviation as deviatio2_20_0_, ladder1v1r0_.mean as mean3_20_0_, ladder1v1r0_.rating as rating4_20_0_ from ladder1v1_rating ladder1v1r0_ where ladder1v1r0_.id=?"], Params:[(1)]
139Hibernate: select clan0_.id as id1_5_, clan0_.create_time as create_t2_5_, clan0_.update_time as update_t3_5_, clan0_.description as descript4_5_, clan0_.founder_id as founder_9_5_, clan0_.leader_id as leader_10_5_, clan0_.name as name5_5_, clan0_.requires_invitation as requires6_5_, clan0_.tag as tag7_5_, clan0_.tag_color as tag_colo8_5_ from clan clan0_ where clan0_.name=?
1402019-12-02 23:08:31.370 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:31, Time:15, Success:True, Type:Prepared, Batch:False, QuerySize:1, BatchSize:0, Query:["select clan0_.id as id1_5_, clan0_.create_time as create_t2_5_, clan0_.update_time as update_t3_5_, clan0_.description as descript4_5_, clan0_.founder_id as founder_9_5_, clan0_.leader_id as leader_10_5_, clan0_.name as name5_5_, clan0_.requires_invitation as requires6_5_, clan0_.tag as tag7_5_, clan0_.tag_color as tag_colo8_5_ from clan clan0_ where clan0_.name=?"], Params:[(Alpha Clan)]
1412019-12-02 23:09:38.365 WARN 10666 --- [faf housekeeper] com.zaxxer.hikari.pool.HikariPool : faf - Thread starvation or clock leap detected (housekeeper delta=1m26s658ms270µs456ns).
1422019-12-02 23:09:38.365 WARN 10666 --- [faf housekeeper] com.zaxxer.hikari.pool.HikariPool : faf - Thread starvation or clock leap detected (housekeeper delta=1m30s713ms424µs640ns).
143Hibernate: select clan0_.id as id1_5_, clan0_.create_time as create_t2_5_, clan0_.update_time as update_t3_5_, clan0_.description as descript4_5_, clan0_.founder_id as founder_9_5_, clan0_.leader_id as leader_10_5_, clan0_.name as name5_5_, clan0_.requires_invitation as requires6_5_, clan0_.tag as tag7_5_, clan0_.tag_color as tag_colo8_5_ from clan clan0_ where clan0_.tag=?
1442019-12-02 23:09:38.434 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:31, Time:4, Success:True, Type:Prepared, Batch:False, QuerySize:1, BatchSize:0, Query:["select clan0_.id as id1_5_, clan0_.create_time as create_t2_5_, clan0_.update_time as update_t3_5_, clan0_.description as descript4_5_, clan0_.founder_id as founder_9_5_, clan0_.leader_id as leader_10_5_, clan0_.name as name5_5_, clan0_.requires_invitation as requires6_5_, clan0_.tag as tag7_5_, clan0_.tag_color as tag_colo8_5_ from clan clan0_ where clan0_.tag=?"], Params:[(new)]
145Hibernate: insert into clan (create_time, update_time, description, founder_id, leader_id, name, requires_invitation, tag, tag_color) values (?, ?, ?, ?, ?, ?, ?, ?, ?)
1462019-12-02 23:09:38.466 INFO 10666 --- [ Test worker] n.t.d.l.l.CommonsQueryLoggingListener : Name:, Connection:163, Time:13, Success:False, Type:Prepared, Batch:False, QuerySize:1, BatchSize:0, Query:["insert into clan (create_time, update_time, description, founder_id, leader_id, name, requires_invitation, tag, tag_color) values (?, ?, ?, ?, ?, ?, ?, ?, ?)"], Params:[(NULL(TIMESTAMP),NULL(TIMESTAMP),spaces Must Be Encoded,1,1,Alpha Clan,true,new,NULL(VARCHAR))]
1472019-12-02 23:09:38.468 WARN 10666 --- [ Test worker] o.h.engine.jdbc.spi.SqlExceptionHelper : SQL Error: 1062, SQLState: 23000
1482019-12-02 23:09:38.469 ERROR 10666 --- [ Test worker] o.h.engine.jdbc.spi.SqlExceptionHelper : Duplicate entry 'Alpha Clan' for key 'clan_name_uindex'
1492019-12-02 23:09:38.481 WARN 10666 --- [ Test worker] c.f.a.e.GlobalControllerExceptionHandler : Internal server error
150
151org.springframework.dao.DataIntegrityViolationException: could not execute statement; SQL [n/a]; constraint [clan_name_uindex]; nested exception is org.hibernate.exception.ConstraintViolationException: could not execute statement