See http://ci.opensuse.org/job/obs_2.3_testsuite_webui/21/changes
Changes:
[Sascha Peilicke] [webui] Finding out which review was accepted doesn't work reliably
[Sascha Peilicke] [webui] This one's from 2006 ;-)
------------------------------------------
[...truncated 267382 lines...]
[[0;37mDEBUG[0m|#18932] Request took 0.012958 seconds
[[32mINFO [0m|#18932] Redirected to http://www.example.com/
[[32mINFO [0m|#18932] Completed in 16ms (DB: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#18932] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#18932] Processing MainController#index (for 127.0.0.1 at 2012-03-30 13:53:38) [GET]
[[0;37mDEBUG[0m|#18932] Setting return_to: "/"
[[0;37mDEBUG[0m|#18932] Authenticating with iChain mode: off
[[32mINFO [0m|#18932] Authenticated request to "/" from king
[[0;37mDEBUG[0m|#18932] Person.find( nil, king)
[[0;37mDEBUG[0m|#18932] [REST] find( Person, [{:login=>"king"}] )
[[0;37mDEBUG[0m|#18932] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#18932] Request took 0.050842 seconds
[[0;37mDEBUG[0m|#18932] Cache miss: king_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#18932] Fetching request list from api
[[0;37mDEBUG[0m|#18932] --> direct_http url: #http://localhost:3203/request?view=collection&states=declined&roles=creator&user=king>
[[0;37mDEBUG[0m|#18932] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=declined&roles=creator&user=king
[[0;37mDEBUG[0m|#18932] Request took 0.062897 seconds
[[0;37mDEBUG[0m|#18932] Fetching request list from api
[[0;37mDEBUG[0m|#18932] --> direct_http url: #http://localhost:3203/request?view=collection&states=review&roles=reviewer&reviewstates=new&user=king>
[[0;37mDEBUG[0m|#18932] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=review&roles=reviewer&reviewstates=new&user=king
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Processing PersonController#userinfo (for 127.0.0.1 at 2012-03-30 13:53:38) [GET]
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Parameters: {"login"=>"tom"}
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Validate XML request: #ActionController::Request:0x7f83eab69868
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] no schema found, skipping validation for methodgetactionuserinfotyperequestlogintomcontrollerperson
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] AUTH: ["Basic", "dG9tOnRodW5kZXJ6"]
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mUser Load (0.3ms)[0m [0;1mSELECT * FROM `users` WHERE (login = 'tom') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mSQL (0.1ms)[0m [0mBEGIN[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mUser Load (0.2ms)[0m [0;1mSELECT `users`.id FROM `users` WHERE (`users`.`login` = 'tom' AND `users`.id <> 3) LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Error - skipping to create user
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mUser Update (0.2ms)[0m [0mUPDATE `users` SET `updated_at` = '2012-03-30 13:53:38', `login_failure_count` = 1 WHERE `id` = 3[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mSQL (104.4ms)[0m [0;1mCOMMIT[0m
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Rendering status
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] errorcode 'unknown' - Unknown user 'tom' or invalid password
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Rendering status (401)
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Filter chain halted as [:extract_user] rendered_or_redirected.
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Completed in 111ms (View: 0, DB: 0) | 401 Unauthorized [http://localhost/person/tom]
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Processing PersonController#userinfo (for 127.0.0.1 at 2012-03-30 13:53:38) [GET]
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Parameters: {"login"=>"tom"}
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Validate XML request: #ActionController::Request:0x7f83eab2cda0
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] no schema found, skipping validation for methodgetactionuserinfotyperequestlogintomcontrollerperson
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] AUTH: ["Basic", "dG9tOnRodW5kZXI="]
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mUser Load (0.3ms)[0m [0mSELECT * FROM `users` WHERE (login = 'tom') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mSQL (0.1ms)[0m [0;1mBEGIN[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mUser Load (0.2ms)[0m [0mSELECT `users`.id FROM `users` WHERE (`users`.`login` = 'tom' AND `users`.id <> 3) LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Error - skipping to create user
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mUser Update (0.2ms)[0m [0;1mUPDATE `users` SET `updated_at` = '2012-03-30 13:53:38', `login_failure_count` = 0 WHERE `id` = 3[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mSQL (0.9ms)[0m [0mCOMMIT[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] USER found: tom
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] User tom initialised
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mRole Load (0.2ms)[0m [0;1mSELECT * FROM `roles` WHERE (`roles`.`title` = 'Admin') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mRole Load (0.2ms)[0m [0mSELECT `roles`.id FROM `roles` INNER JOIN `roles_users` ON `roles`.id = `roles_users`.role_id WHERE (`roles`.`id` = 1) AND (`roles_users`.user_id = 3 ) LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mUser Load (0.2ms)[0m [0;1mSELECT * FROM `users` WHERE (`users`.`login` = 'tom') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Generating user info for logged in user tom
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] ----------------- rendering person tom ------------------------
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mRole Load (0.2ms)[0m [0mSELECT * FROM `roles` INNER JOIN `roles_users` ON `roles`.id = `roles_users`.role_id WHERE (`roles_users`.user_id = 3 AND (global = true)) [0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mWatchedProject Load (0.3ms)[0m [0;1mSELECT * FROM `watched_projects` WHERE (`watched_projects`.bs_user_id = 3) [0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Validate XML response: #ActionController::Response:0x7f83eab2cad0
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Completed in 14ms (View: 0, DB: 3) | 200 OK [http://localhost/person/tom]
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Processing PersonController#userinfo (for 127.0.0.1 at 2012-03-30 13:53:38) [GET]
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Parameters: {"login"=>"tom"}
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Validate XML request: #ActionController::Request:0x7f83eaadac08
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] no schema found, skipping validation for methodgetactionuserinfotyperequestlogintomcontrollerperson
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] AUTH: ["Basic", "dG9tOnRodW5kZXI="]
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mUser Load (0.3ms)[0m [0mSELECT * FROM `users` WHERE (login = 'tom') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] USER found: tom
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] User tom initialised
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mRole Load (0.2ms)[0m [0;1mSELECT * FROM `roles` WHERE (`roles`.`title` = 'Admin') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mRole Load (0.2ms)[0m [0mSELECT `roles`.id FROM `roles` INNER JOIN `roles_users` ON `roles`.id = `roles_users`.role_id WHERE (`roles`.`id` = 1) AND (`roles_users`.user_id = 3 ) LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mUser Load (0.2ms)[0m [0;1mSELECT * FROM `users` WHERE (`users`.`login` = 'tom') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Generating user info for logged in user tom
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] ----------------- rendering person tom ------------------------
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mRole Load (0.2ms)[0m [0mSELECT * FROM `roles` INNER JOIN `roles_users` ON `roles`.id = `roles_users`.role_id WHERE (`roles_users`.user_id = 3 AND (global = true)) [0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mWatchedProject Load (0.3ms)[0m [0;1mSELECT * FROM `watched_projects` WHERE (`watched_projects`.bs_user_id = 3) [0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Validate XML response: #ActionController::Response:0x7f83eaada938
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Completed in 9ms (View: 0, DB: 1) | 200 OK [http://localhost/person/tom]
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Processing PersonController#userinfo (for 127.0.0.1 at 2012-03-30 13:53:38) [GET]
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Parameters: {"login"=>"king"}
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Validate XML request: #ActionController::Request:0x7f83eaaa1b88
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] no schema found, skipping validation for methodgetactionuserinfotyperequestloginkingcontrollerperson
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mUser Load (0.3ms)[0m [0mSELECT * FROM `users` WHERE (login = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] USER found: king
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] User king initialised
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mRole Load (0.2ms)[0m [0;1mSELECT * FROM `roles` WHERE (`roles`.`title` = 'Admin') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mRole Load (0.2ms)[0m [0mSELECT `roles`.id FROM `roles` INNER JOIN `roles_users` ON `roles`.id = `roles_users`.role_id WHERE (`roles`.`id` = 1) AND (`roles_users`.user_id = 2 ) LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mUser Load (0.2ms)[0m [0;1mSELECT * FROM `users` WHERE (`users`.`login` = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Generating user info for logged in user king
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] ----------------- rendering person king ------------------------
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mRole Load (0.2ms)[0m [0mSELECT * FROM `roles` INNER JOIN `roles_users` ON `roles`.id = `roles_users`.role_id WHERE (`roles_users`.user_id = 2 AND (global = true)) [0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mWatchedProject Load (0.2ms)[0m [0;1mSELECT * FROM `watched_projects` WHERE (`watched_projects`.bs_user_id = 2) [0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Validate XML response: #ActionController::Response:0x7f83eaaa18b8
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Completed in 7ms (View: 0, DB: 1) | 200 OK [http://localhost/person/king]
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Processing PersonController#userinfo (for 127.0.0.1 at 2012-03-30 13:53:38) [GET]
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Parameters: {"login"=>"king"}
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Validate XML request: #ActionController::Request:0x7f83eaa7e688
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] no schema found, skipping validation for methodgetactionuserinfotyperequestloginkingcontrollerperson
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mUser Load (0.3ms)[0m [0mSELECT * FROM `users` WHERE (login = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] USER found: king
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] User king initialised
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mRole Load (0.2ms)[0m [0;1mSELECT * FROM `roles` WHERE (`roles`.`title` = 'Admin') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mRole Load (0.2ms)[0m [0mSELECT `roles`.id FROM `roles` INNER JOIN `roles_users` ON `roles`.id = `roles_users`.role_id WHERE (`roles`.`id` = 1) AND (`roles_users`.user_id = 2 ) LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mUser Load (0.2ms)[0m [0;1mSELECT * FROM `users` WHERE (`users`.`login` = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Generating user info for logged in user king
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] ----------------- rendering person king ------------------------
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mRole Load (0.2ms)[0m [0mSELECT * FROM `roles` INNER JOIN `roles_users` ON `roles`.id = `roles_users`.role_id WHERE (`roles_users`.user_id = 2 AND (global = true)) [0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mWatchedProject Load (0.2ms)[0m [0;1mSELECT * FROM `watched_projects` WHERE (`watched_projects`.bs_user_id = 2) [0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Validate XML response: #ActionController::Response:0x7f83eaa7e3b8
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Completed in 7ms (View: 0, DB: 1) | 200 OK [http://localhost/person/king]
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Processing RequestController#index (for 127.0.0.1 at 2012-03-30 13:53:38) [GET]
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Parameters: {"view"=>"collection", "roles"=>"creator", "user"=>"king", "states"=>"declined"}
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Validate XML request: #ActionController::Request:0x7f83eaa58fc8
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] no schema found, skipping validation for viewcollectionuserkingrolescreatormethodgetactionindextyperequestcontrollerrequeststatesdeclined
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mUser Load (0.3ms)[0m [0mSELECT * FROM `users` WHERE (login = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] USER found: king
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] User king initialised
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mRole Load (0.2ms)[0m [0;1mSELECT * FROM `roles` WHERE (`roles`.`title` = 'Admin') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mRole Load (0.2ms)[0m [0mSELECT `roles`.id FROM `roles` INNER JOIN `roles_users` ON `roles`.id = `roles_users`.role_id WHERE (`roles`.`id` = 1) AND (`roles_users`.user_id = 2 ) LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mUser Load (0.2ms)[0m [0;1mSELECT * FROM `users` WHERE (login = BINARY 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] running backend query at Fri Mar 30 13:53:38 +0200 2012
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [backend] POST: /search/request?match=%28state%2F%40name%3D%27declined%27%29+and+%28state%2F%40who%3D%27king%27+and+not+%28history%29+or+history%5B%40who%3D%27king%27+and+position%28%29%3D1%5D%29
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18938] 2012-03-30 13:53:38 [21774]: POST /search/request?match=%28state%2F%40name%3D%27declined%27%29+and+%28state%2F%40who%3D%27king%27+and+not+%28history%29+or+history%5B%40who%3D%27king%27+and+position%28%29%3D1%5D%29
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Validate XML response: #ActionController::Response:0x7f83eaa58cf8
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] no schema found, skipping validation for viewcollectionuserkingrolescreatormethodgetactionindextyperesponsecontrollerrequeststatesdeclined
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Completed in 18ms (View: 0, DB: 1) | 200 OK [http://localhost/request?view=collection&states=declined&roles=creator&user=king]
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Processing RequestController#index (for 127.0.0.1 at 2012-03-30 13:53:38) [GET]
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Parameters: {"view"=>"collection", "roles"=>"reviewer", "user"=>"king", "states"=>"review", "reviewstates"=>"new"}
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Validate XML request: #ActionController::Request:0x7f83eaa34060
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] no schema found, skipping validation for viewcollectionuserkingrolesreviewermethodgetactionindextyperequestcontrollerrequestreviewstatesnewstatesreview
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mUser Load (0.4ms)[0m [0mSELECT * FROM `users` WHERE (login = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] USER found: king
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] User king initialised
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mRole Load (0.2ms)[0m [0;1mSELECT * FROM `roles` WHERE (`roles`.`title` = 'Admin') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mRole Load (0.2ms)[0m [0mSELECT `roles`.id FROM `roles` INNER JOIN `roles_users` ON `roles`.id = `roles_users`.role_id WHERE (`roles`.`id` = 1) AND (`roles_users`.user_id = 2 ) LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mUser Load (12.0ms)[0m [0;1mSELECT * FROM `users` WHERE (login = BINARY 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mGroup Load (0.4ms)[0m [0mSELECT * FROM `groups` INNER JOIN `groups_users` ON `groups`.id = `groups_users`.group_id WHERE (`groups_users`.user_id = 2 ) [0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mRole Load (0.2ms)[0m [0;1mSELECT * FROM `roles` WHERE (`roles`.`title` = 'maintainer') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mSQL (0.3ms)[0m [0mSELECT prj.id
[[0;37mDEBUG[0m|#18935] FROM db_projects prj
[[0;37mDEBUG[0m|#18935] LEFT JOIN project_user_role_relationships ur ON prj.id = ur.db_project_id
[[0;37mDEBUG[0m|#18935] WHERE ur.bs_user_id = 2 and ur.role_id = 3[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mSQL (0.2ms)[0m [0;1mSELECT prj.id
[[0;37mDEBUG[0m|#18935] FROM db_projects prj
[[0;37mDEBUG[0m|#18935] LEFT JOIN project_group_role_relationships gr ON prj.id = gr.db_project_id
[[0;37mDEBUG[0m|#18935] LEFT JOIN groups_users ug ON ug.group_id = gr.bs_group_id
[[0;37mDEBUG[0m|#18935] WHERE ug.user_id = 2 and gr.role_id = 3[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mDbProject Load (0.3ms)[0m [0mSELECT distinct prj.* FROM db_projects prj
[[0;37mDEBUG[0m|#18935] LEFT JOIN flags f on f.db_project_id = prj.id
[[0;37mDEBUG[0m|#18935] LEFT JOIN project_user_role_relationships aur ON aur.db_project_id = prj.id
[[0;37mDEBUG[0m|#18935] where prj.id in (2)
[[0;37mDEBUG[0m|#18935] and (f.flag is null or f.flag != 'access' or aur.id = 2)[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mCACHE (0.0ms)[0m [0;1mSELECT * FROM `roles` WHERE (`roles`.`title` = 'maintainer') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mCACHE (0.0ms)[0m [0mSELECT * FROM `roles` WHERE (`roles`.`title` = 'maintainer') LIMIT 1[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mSQL (0.2ms)[0m [0;1mSELECT prj.id
[[0;37mDEBUG[0m|#18935] FROM db_projects prj
[[0;37mDEBUG[0m|#18935] LEFT JOIN project_user_role_relationships ur ON prj.id = ur.db_project_id
[[0;37mDEBUG[0m|#18935] WHERE ur.bs_user_id = 2 and ur.role_id = 3[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mSQL (0.2ms)[0m [0mSELECT prj.id
[[0;37mDEBUG[0m|#18935] FROM db_projects prj
[[0;37mDEBUG[0m|#18935] LEFT JOIN project_group_role_relationships gr ON prj.id = gr.db_project_id
[[0;37mDEBUG[0m|#18935] LEFT JOIN groups_users ug ON ug.group_id = gr.bs_group_id
[[0;37mDEBUG[0m|#18935] WHERE ug.user_id = 2 and gr.role_id = 3[0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;36;1mSQL (0.3ms)[0m [0;1m SELECT pkg.id
[[0;37mDEBUG[0m|#18935] FROM db_packages pkg
[[0;37mDEBUG[0m|#18935] LEFT JOIN db_projects prj ON prj.id = pkg.db_project_id
[[0;37mDEBUG[0m|#18935] LEFT JOIN package_user_role_relationships ur ON pkg.id = ur.db_package_id
[[0;37mDEBUG[0m|#18935] WHERE ur.bs_user_id = 2 and ur.role_id = 3 and
[[0;37mDEBUG[0m|#18935] prj.id not in (2)
[[0;37mDEBUG[0m|#18935] [0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [4;35;1mSQL (0.3ms)[0m [0m SELECT pkg.id
[[0;37mDEBUG[0m|#18935] FROM db_packages pkg
[[0;37mDEBUG[0m|#18935] LEFT JOIN db_projects prj ON prj.id = pkg.db_project_id
[[0;37mDEBUG[0m|#18935] LEFT JOIN package_group_role_relationships gr ON pkg.id = gr.db_package_id
[[0;37mDEBUG[0m|#18935] LEFT JOIN groups_users ug ON ug.group_id = gr.bs_group_id
[[0;37mDEBUG[0m|#18935] WHERE ug.user_id = 2 and gr.role_id = 3 and
[[0;37mDEBUG[0m|#18935] prj.id not in (2)
[[0;37mDEBUG[0m|#18935] [0m
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] running backend query at Fri Mar 30 13:53:38 +0200 2012
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] [backend] POST: /search/request?match=%28state%2F%40name%3D%27review%27%29+and+%28review%5B%40by_user%3D%27king%27+and+%40state%3D%27new%27%5D+or+%28review%5B%40state%3D%27new%27+and+%40by_project%3D%27kde4%27%5D+and+state%2F%40name%3D%27review%27%29%29
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18938] 2012-03-30 13:53:38 [21775]: POST /search/request?match=%28state%2F%40name%3D%27review%27%29+and+%28review%5B%40by_user%3D%27king%27+and+%40state%3D%27new%27%5D+or+%28review%5B%40state%3D%27new%27+and+%40by_project%3D%27kde4%27%5D+and+state%2F%40name%3D%27review%27%29%29
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] Validate XML response: #ActionController::Response:0x7f83eaa33cc8
[[0;37mDEBUG[0m|#18935] [[0;37mDEBUG[0m|#18936] no schema found, skipping validation for viewcollectionuserkingrolesreviewermethodgetactionindextyperesponsecontrollerrequestreviewstatesnewstatesreview
[[0;37mDEBUG[0m|#18935] [[32mINFO [0m|#18936] Completed in 108ms (View: 1, DB: 15) | 200 OK [http://localhost/request?view=collection&states=review&roles=reviewer&reviewstates=new&user=king]
[[0;37mDEBUG[0m|#18932] Request took 0.151202 seconds
[[0;37mDEBUG[0m|#18932] Fetching request list from api
[[0;37mDEBUG[0m|#18932] --> direct_http url: #http://localhost:3203/request?view=collection&states=new&roles=maintainer&user=king>
[[0;37mDEBUG[0m|#18932] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=new&roles=maintainer&user=king
[[0;37mDEBUG[0m|#18932] Request took 0.079232 seconds
[[0;37mDEBUG[0m|#18932] Cache write (will save 295.54ms): king_requests_that_need_work
[[0;37mDEBUG[0m|#18932] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#18932] Statusmessage.find( 900 seconds, conditionsdeleted_at IS NULLlimit5ordercreate_at DESC)
[[0;37mDEBUG[0m|#18932] Cache hit: Statusmessage_fdd1fdac72c7daa6dfbc3fd9ffe7e27d ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#18932] LatestUpdated.find( 300 seconds, sharedtruelimit6)
[[0;37mDEBUG[0m|#18932] Cache hit: LatestUpdated_5207f540a9cfb116e5e9ea305dae5178 ({:expires_in=>300 seconds})
[[0;37mDEBUG[0m|#18932] Project.find( 1800 seconds, home:king)
[[0;37mDEBUG[0m|#18932] Cache miss: Project_574594158e160d6fce01e8b54fe13086 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#18932] [REST] find( Project, [{:name=>"home:king"}] )
[[0;37mDEBUG[0m|#18932] http_do #1: method: get url: http://localhost:3203/source/home:king/_meta?
[[0;37mDEBUG[0m|#18932] Request took 0.065312 seconds
[[0;37mDEBUG[0m|#18932] Project.find( {:name=>"home:king"} ) did not find anything, return nil
[[32mINFO [0m|#18932] Redirected to http://www.example.com/configuration/connect_instance
[[32mINFO [0m|#18932] Completed in 418ms (DB: 1) | 302 Found [http://www.example.com/]
[[0;37mDEBUG[0m|#18932] REQUESTING PAGE: GET http://www.example.com/configuration/connect_instance with {} and HTTP headers {"HTTP_REFERER"=>"http://www.example.com/"}
[[32mINFO [0m|#18932] Processing ConfigurationController#connect_instance (for 127.0.0.1 at 2012-03-30 13:53:39) [GET]
[[0;37mDEBUG[0m|#18932] Setting return_to: "/configuration/connect_instance"
[[0;37mDEBUG[0m|#18932] Authenticating with iChain mode: off
[[32mINFO [0m|#18932] Authenticated request to "/configuration/connect_instance" from king
[[0;37mDEBUG[0m|#18932] Person.find( nil, king)
[[0;37mDEBUG[0m|#18932] [REST] find( Person, [{:login=>"king"}] )
[[0;37mDEBUG[0m|#18932] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#18932] Request took 0.014032 seconds
[[0;37mDEBUG[0m|#18932] Cache hit: king_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#18932] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#18932] Rendering template within layouts/application
[[32mINFO [0m|#18932] Rendering configuration/connect_instance
[[0;37mDEBUG[0m|#18932] Rendered layouts/_watch_and_search (1.2ms)
[[0;37mDEBUG[0m|#18932] Rendered layouts/_header (2.3ms)
[[0;37mDEBUG[0m|#18932] Rendered layouts/_personal_navigation (1.2ms)
[[0;37mDEBUG[0m|#18932] Rendered layouts/_flash (0.2ms)
[[0;37mDEBUG[0m|#18932] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#18932] Completed in 34ms (View: 13, DB: 1) | 200 OK [http://www.example.com/configuration/connect_instance]
[[32mINFO [0m|#18932] Processing UserController#logout (for 127.0.0.1 at 2012-03-30 13:53:39) [POST]
[[0;37mDEBUG[0m|#18932] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|#18932] Authenticating with iChain mode: off
[[32mINFO [0m|#18932] Authenticated request to "/user/logout" from king
[[0;37mDEBUG[0m|#18932] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#18932] Logging out: king
[[32mINFO [0m|#18932] Redirected to http://www.example.com/
[[0;37mDEBUG[0m|#18932] Cache delete: Person_d41d8cd98f00b204e9800998ecf8427e
[[32mINFO [0m|#18932] Completed in 3ms (DB: 1) | 302 Found [http://www.example.com/user/logout]
+ echo
+ echo 'Remove log/tmp files to save disc space'
Remove log/tmp files to save disc space
+ rm -rf src/api/log/backend_access.log src/api/log/development.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets
rm: cannot remove directory `src/api/tmp/backend_data/trees/UseRemoteInstance/pack3': No such file or directory
rm: cannot remove directory `src/api/tmp/backend_data/trees/BaseDistro:Update/pack1': No such file or directory
Build step 'Execute shell' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
--
To unsubscribe, e-mail: obs-tests+unsubscribe@opensuse.org
To contact the owner, e-mail: obs-tests+owner@opensuse.org