[prev in list] [next in list] [prev in thread] [next in thread] 

List:       opensuse-buildservice
Subject:    [opensuse-buildservice] Invalid user / random logouts
From:       Matthew Drobnak <mdrobnak () appnexus ! com>
Date:       2012-10-22 20:25:29
Message-ID: 5085ABB9.1070801 () appnexus ! com
[Download RAW message or body]

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


[prev in list] [next in list] [prev in thread] [next in thread]