[opensuse-buildservice] Invalid user / random logouts
I'm seeing this on my OBS server: [INFO |#23792] Processing PersonController#userinfo (for 1.2.3.4 at 2012-10-22 19:37:04) [GET] [INFO |#23792] Parameters: {"login"=>"mdrobnak"} [INFO |#23792] Completed in 7ms (View: 0, DB: 1) | 200 OK [https://obs01.nym1.appnexus.net/person/mdrobnak] [INFO |#23792] Processing BuildController#logfile (for 1.2.3.4 at 2012-10-22 19:37:04) [GET] [INFO |#23792] Parameters: {"repository"=>"ProductA_ScientificLinux_6", "project"=>"ProductA:widgetb", "arch"=>"x86_64", "end"=>"182868", "nostream"=>"1", "package"=>"widgetb", "start"=>"117332"} [INFO |#23792] Completed in 9ms (View: 0, DB: 2) | 200 OK [https://obs01.nym1.appnexus.net/build/ProductA:widgetb/ProductA_ScientificLinux_6/x86_64/widgetb/_log?nostream=1&start=117332&end=182868] [INFO |#23792] Processing PersonController#userinfo (for 1.2.3.4 at 2012-10-22 19:37:06) [GET] [INFO |#23792] Parameters: {"login"=>"mdrobnak"} [INFO |#23792] Completed in 7ms (View: 0, DB: 1) | 200 OK [https://obs01.nym1.appnexus.net/person/mdrobnak] [INFO |#23792] Processing BuildController#logfile (for 1.2.3.4 at 2012-10-22 19:37:06) [GET] [INFO |#23792] Parameters: {"repository"=>"ProductA_ScientificLinux_6", "project"=>"ProductA:widgetb", "arch"=>"x86_64", "end"=>"187241", "nostream"=>"1", "package"=>"widgetb", "start"=>"121705"} [INFO |#23792] Completed in 9ms (View: 0, DB: 2) | 200 OK [https://obs01.nym1.appnexus.net/build/ProductA:widgetb/ProductA_ScientificLinux_6/x86_64/widgetb/_log?nostream=1&start=121705&end=187241] [INFO |#23792] Processing PersonController#userinfo (for 1.2.3.4 at 2012-10-22 19:37:08) [GET] [INFO |#23792] Parameters: {"login"=>"mdrobnak"} [INFO |#23792] Completed in 7ms (View: 0, DB: 1) | 200 OK [https://obs01.nym1.appnexus.net/person/mdrobnak] [INFO |#23792] Processing BuildController#logfile (for 1.2.3.4 at 2012-10-22 19:37:08) [GET] [INFO |#23792] Parameters: {"repository"=>"ProductA_ScientificLinux_6", "project"=>"ProductA:widgetb", "arch"=>"x86_64", "end"=>"200953", "nostream"=>"1", "package"=>"widgetb", "start"=>"135417"} [INFO |#23792] Completed in 9ms (View: 0, DB: 2) | 200 OK [https://obs01.nym1.appnexus.net/build/ProductA:widgetb/ProductA_ScientificLinux_6/x86_64/widgetb/_log?nostream=1&start=135417&end=200953] [INFO |#23792] Processing PersonController#userinfo (for 1.2.3.4 at 2012-10-22 19:37:11) [GET] [INFO |#23792] Parameters: {"login"=>"mdrobnak"} [INFO |#23792] Rendering status [INFO |#23792] errorcode 'unknown' - Unknown user 'mdrobnak' or invalid password [INFO |#23792] Rendering status (401) [INFO |#23792] Filter chain halted as [:extract_user] rendered_or_redirected. [INFO |#23792] Completed in 10ms (View: 1, DB: 0) | 401 Unauthorized [https://obs01.nym1.appnexus.net/person/mdrobnak] This is using the stock LDAP implementation. Only happens so often, but if working with it 30+ minutes, can happen 5-10 times, which can be irritating. Any ideas, or do I need to up the logging as a first step? ...and this is what happens: [INFO |# 1328] Processing PersonController#userinfo (for 68.67.165.34 at 2012-10-22 20:24:22) [GET] [INFO |# 1328] Parameters: {"login"=>"mdrobnak"} [DEBUG|# 1328] Validate XML request: #<ActionController::Request:0x7f5d190f34d0> [DEBUG|# 1328] no schema found, skipping validation for controllerpersonactionuserinfotyperequestmethodgetloginmdrobnak [DEBUG|# 1328] AUTH: ["Basic", "REDACTED"] [DEBUG|# 1328] Using LDAP to find mdrobnak [DEBUG|# 1328] Looking for mdrobnak using ldap [DEBUG|# 1328] Cache read: ldap_cache_userpasswd:mdrobnak [DEBUG|# 1328] Search for (uid=mdrobnak) [DEBUG|# 1328] Search failed: error -1 [DEBUG|# 1328] User not found with LDAP, falling back to database [DEBUG|# 1328] User Load (0.4ms) SELECT * FROM `users` WHERE (login = 'mdrobnak') LIMIT 1 [DEBUG|# 1328] SQL (0.1ms) BEGIN [DEBUG|# 1328] User Load (0.2ms) SELECT `users`.id FROM `users` WHERE (`users`.`login` = 'mdrobnak' AND `users`.id <> 3) LIMIT 1 [DEBUG|# 1328] Error - skipping to create user [DEBUG|# 1328] User Update (0.3ms) UPDATE `users` SET `login_failure_count` = 147, `updated_at` = '2012-10-22 20:24:22' WHERE `id` = 3 [DEBUG|# 1328] SQL (2.3ms) COMMIT [INFO |# 1328] Rendering status [INFO |# 1328] errorcode 'unknown' - Unknown user 'mdrobnak' or invalid password [INFO |# 1328] Rendering status (401) [INFO |# 1328] Filter chain halted as [:extract_user] rendered_or_redirected. [INFO |# 1328] Completed in 11ms (View: 1, DB: 0) | 401 Unauthorized [https://obs01.nym1.appnexus.net/person/mdrobnak] -Matt -- To unsubscribe, e-mail: opensuse-buildservice+unsubscribe@opensuse.org To contact the owner, e-mail: opensuse-buildservice+owner@opensuse.org
participants (1)
-
Matthew Drobnak