[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui #256
See http://ci.opensuse.org/job/obs_master_testsuite_webui/256/changes
Changes:
[Sascha Peilicke] [webui] New layout
[Sascha Peilicke] [webui] Add 'branch other package' link to project show view
[Sascha Peilicke] [webui] Move package list to project frontpage.
[Sascha Peilicke] [webui] First step on package view
[Sascha Peilicke] [webui] Lighter request view
[Sascha Peilicke] [webui] Avoid empty <ul> in project show view...
[Sascha Peilicke] [webui] Use dataTables for subprojects lists
[Sascha Peilicke] [webui] Move jquery.dataTables setup to application.js
[Sascha Peilicke] [webui] Use datatables for project/package users view
------------------------------------------
[...truncated 38632 lines...]
[[0;37mDEBUG[0m|#18460] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|#18460] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#18460] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#18460] Completed in 256ms (View: 6, DB: 0, API: 245, XML: 0) | 200 OK [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#18460] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#18460]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-10 15:10:09) [POST]
[[32mINFO [0m|#18460] Parameters: {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"}
[[0;37mDEBUG[0m|#18460] Setting return_to: "/"
[[0;37mDEBUG[0m|#18460] Authenticating with iChain mode: off
[[32mINFO [0m|#18460] Anonymous request to /
[[0;37mDEBUG[0m|#18460] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#18460] Doing form authorization to login user tom
[[0;37mDEBUG[0m|#18460] Person.find( nil, tom)
[[0;37mDEBUG[0m|#18460] [REST] find( Person, [{:login=>"tom"}] )
[[0;37mDEBUG[0m|#18460] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#18460] Request took 0.056565233 seconds
[[32mINFO [0m|#18460] Redirected to http://www.example.com/
[[32mINFO [0m|#18460] Completed in 61ms (DB: 1, API: 57, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#18460] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#18460]
Processing MainController#index (for 127.0.0.1 at 2012-04-10 15:10:09) [GET]
[[0;37mDEBUG[0m|#18460] Setting return_to: "/"
[[0;37mDEBUG[0m|#18460] Authenticating with iChain mode: off
[[32mINFO [0m|#18460] Authenticated request to "/" from tom
[[0;37mDEBUG[0m|#18460] Cache delete: Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|#18460] method_missing -to_str- nil
[[0;37mDEBUG[0m|#18460] method_missing -to_str- nil
[[0;37mDEBUG[0m|#18460] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#18460] Person.find( 1800 seconds, tom, {:is_current=>true})
[[0;37mDEBUG[0m|#18460] Cache miss: Person_bcccc817286c9705b67feb0c7cc953c3 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#18460] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#18460] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#18460] Request took 0.051038393 seconds
[[0;37mDEBUG[0m|#18460] Cache write (will save 51.51ms): Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|#18460] Cache hit: tom_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#18460] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#18460] method_missing -to_str- nil
[[0;37mDEBUG[0m|#18460] method_missing -to_str- nil
[[0;37mDEBUG[0m|#18460] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#18460] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#18460] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#18460] method_missing -to_str- nil
[[0;37mDEBUG[0m|#18460] method_missing -to_str- nil
[[0;37mDEBUG[0m|#18460] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#18460] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#18460] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[0;37mDEBUG[0m|#18460] Project.find( 1800 seconds, home:tom)
[[0;37mDEBUG[0m|#18460] Cache hit: Project_adb9094ae2c389898fafee54514195c4 ({:expires_in=>1800 seconds})
[[32mINFO [0m|#18460] Rendering template within layouts/application
[[32mINFO [0m|#18460] Rendering main/index
[[0;37mDEBUG[0m|#18460] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|#18460] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#18460] Rendered main/_latest_updates (3.6ms)
[[0;37mDEBUG[0m|#18460] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#18460] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|#18460] Rendered main/_feeds (0.0ms)
[[0;37mDEBUG[0m|#18460] Rendered layouts/_watch_and_search (0.4ms)
[[0;37mDEBUG[0m|#18460] Rendered layouts/_header (0.9ms)
[[0;37mDEBUG[0m|#18460] Rendered layouts/_personal_navigation (0.4ms)
[[0;37mDEBUG[0m|#18460] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#18460] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#18460] Completed in 70ms (View: 11, DB: 0, API: 51, XML: 1) | 200 OK [http://www.example.com/]
[[32mINFO [0m|#18460]
Processing UserController#logout (for 127.0.0.1 at 2012-04-10 15:10:09) [POST]
[[0;37mDEBUG[0m|#18460] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|#18460] Authenticating with iChain mode: off
[[32mINFO [0m|#18460] Authenticated request to "/user/logout" from tom
[[0;37mDEBUG[0m|#18460] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#18460] Logging out: tom
[[32mINFO [0m|#18460] Redirected to http://www.example.com/
[[0;37mDEBUG[0m|#18460] Cache delete: Person_f7754490a4ed0ddff3ff300384cc6faa
[[32mINFO [0m|#18460] Completed in 4ms (DB: 1, API: 0, XML: 0) | 302 Found [http://www.example.com/user/logout]
[[0;37mDEBUG[0m|#18460] REQUESTING PAGE: GET / with {} and HTTP headers {}
[[32mINFO [0m|#18460]
Processing MainController#index (for 127.0.0.1 at 2012-04-10 15:10:09) [GET]
[[0;37mDEBUG[0m|#18460] Setting return_to: "/"
[[0;37mDEBUG[0m|#18460] Authenticating with iChain mode: off
[[32mINFO [0m|#18460] Anonymous request to /
[[0;37mDEBUG[0m|#18460] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#18460] method_missing -to_str- nil
[[0;37mDEBUG[0m|#18460] method_missing -to_str- nil
[[0;37mDEBUG[0m|#18460] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#18460] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#18460] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#18460] method_missing -to_str- nil
[[0;37mDEBUG[0m|#18460] method_missing -to_str- nil
[[0;37mDEBUG[0m|#18460] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#18460] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#18460] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[32mINFO [0m|#18460] Rendering template within layouts/application
[[32mINFO [0m|#18460] Rendering main/index
[[0;37mDEBUG[0m|#18460] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|#18460] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#18460] Rendered main/_latest_updates (3.7ms)
[[0;37mDEBUG[0m|#18460] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#18460] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|#18460] Rendered main/_feeds (0.0ms)
[[0;37mDEBUG[0m|#18460] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#18460] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|#18460] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|#18460] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#18460] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#18460] Completed in 16ms (View: 10, DB: 0, API: 0, XML: 1) | 200 OK [http://www.example.com/]
[[0;37mDEBUG[0m|#18460] REQUESTING PAGE: GET /user/login with {} and HTTP headers {"HTTP_REFERER"=>"/"}
[[32mINFO [0m|#18460]
Processing UserController#login (for 127.0.0.1 at 2012-04-10 15:10:09) [GET]
[[0;37mDEBUG[0m|#18460] Setting return_to: "/user/login"
[[0;37mDEBUG[0m|#18460] Authenticating with iChain mode: off
[[32mINFO [0m|#18460] Anonymous request to /user/login
[[0;37mDEBUG[0m|#18460] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#18460] Rendering template within layouts/application
[[32mINFO [0m|#18460] Rendering user/login
[[0;37mDEBUG[0m|#18460] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#18460] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|#18460] Rendered layouts/_personal_navigation (0.6ms)
[[0;37mDEBUG[0m|#18460] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#18460] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#18460] Completed in 8ms (View: 4, DB: 1, API: 0, XML: 0) | 200 OK [http://www.example.com/user/login]
[[0;37mDEBUG[0m|#18460] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/login"}
[[32mINFO [0m|#18460]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-10 15:10:09) [POST]
[[32mINFO [0m|#18460] Parameters: {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"}
[[0;37mDEBUG[0m|#18460] Setting return_to: "/"
[[0;37mDEBUG[0m|#18460] Authenticating with iChain mode: off
[[32mINFO [0m|#18460] Anonymous request to /
[[0;37mDEBUG[0m|#18460] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#18460] Doing form authorization to login user king
[[0;37mDEBUG[0m|#18460] Person.find( nil, king)
[[0;37mDEBUG[0m|#18460] [REST] find( Person, [{:login=>"king"}] )
[[0;37mDEBUG[0m|#18460] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#18460] Request took 0.013237189 seconds
[[32mINFO [0m|#18460] Redirected to http://www.example.com/
[[32mINFO [0m|#18460] Completed in 17ms (DB: 0, API: 13, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#18460] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#18460]
Processing MainController#index (for 127.0.0.1 at 2012-04-10 15:10:09) [GET]
[[0;37mDEBUG[0m|#18460] Setting return_to: "/"
[[0;37mDEBUG[0m|#18460] Authenticating with iChain mode: off
[[32mINFO [0m|#18460] Authenticated request to "/" from king
[[0;37mDEBUG[0m|#18460] Cache delete: Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#18460] method_missing -to_str- nil
[[0;37mDEBUG[0m|#18460] method_missing -to_str- nil
[[0;37mDEBUG[0m|#18460] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#18460] Person.find( 1800 seconds, king, {:is_current=>true})
[[0;37mDEBUG[0m|#18460] Cache miss: Person_31bc44caa5b35568da22b828c6853364 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#18460] [REST] find( Person, [{:is_current=>"true", :login=>"king"}] )
[[0;37mDEBUG[0m|#18460] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#18460] Request took 0.050795329 seconds
[[0;37mDEBUG[0m|#18460] Cache write (will save 51.21ms): Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#18460] Cache miss: king_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#18460] Fetching request list from api
[[0;37mDEBUG[0m|#18460] --> direct_http url: #
See http://ci.opensuse.org/job/obs_master_testsuite_webui/257/changes
Changes:
[Sascha Peilicke] [webui] Adjust redirects for the PackageController's 'packages' action to 'show' action
[Sascha Peilicke] [webui] Adjust testsuite to wording and route changes
------------------------------------------
[...truncated 76556 lines...]
[[0;37mDEBUG[0m|#13520] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|#13520] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#13520] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#13520] Completed in 365ms (View: 6, DB: 0, API: 354, XML: 0) | 200 OK [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#13520] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#13520]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-11 12:06:00) [POST]
[[32mINFO [0m|#13520] Parameters: {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"}
[[0;37mDEBUG[0m|#13520] Setting return_to: "/"
[[0;37mDEBUG[0m|#13520] Authenticating with iChain mode: off
[[32mINFO [0m|#13520] Anonymous request to /
[[0;37mDEBUG[0m|#13520] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#13520] Doing form authorization to login user tom
[[0;37mDEBUG[0m|#13520] Person.find( nil, tom)
[[0;37mDEBUG[0m|#13520] [REST] find( Person, [{:login=>"tom"}] )
[[0;37mDEBUG[0m|#13520] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#13520] Request took 0.056104995 seconds
[[32mINFO [0m|#13520] Redirected to http://www.example.com/
[[32mINFO [0m|#13520] Completed in 60ms (DB: 1, API: 56, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#13520] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#13520]
Processing MainController#index (for 127.0.0.1 at 2012-04-11 12:06:00) [GET]
[[0;37mDEBUG[0m|#13520] Setting return_to: "/"
[[0;37mDEBUG[0m|#13520] Authenticating with iChain mode: off
[[32mINFO [0m|#13520] Authenticated request to "/" from tom
[[0;37mDEBUG[0m|#13520] Cache delete: Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|#13520] method_missing -to_str- nil
[[0;37mDEBUG[0m|#13520] method_missing -to_str- nil
[[0;37mDEBUG[0m|#13520] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#13520] Person.find( 1800 seconds, tom, {:is_current=>true})
[[0;37mDEBUG[0m|#13520] Cache miss: Person_bcccc817286c9705b67feb0c7cc953c3 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#13520] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#13520] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#13520] Request took 0.05101106 seconds
[[0;37mDEBUG[0m|#13520] Cache write (will save 51.49ms): Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|#13520] Cache hit: tom_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#13520] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#13520] method_missing -to_str- nil
[[0;37mDEBUG[0m|#13520] method_missing -to_str- nil
[[0;37mDEBUG[0m|#13520] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#13520] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#13520] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#13520] method_missing -to_str- nil
[[0;37mDEBUG[0m|#13520] method_missing -to_str- nil
[[0;37mDEBUG[0m|#13520] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#13520] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#13520] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[0;37mDEBUG[0m|#13520] Project.find( 1800 seconds, home:tom)
[[0;37mDEBUG[0m|#13520] Cache hit: Project_adb9094ae2c389898fafee54514195c4 ({:expires_in=>1800 seconds})
[[32mINFO [0m|#13520] Rendering template within layouts/application
[[32mINFO [0m|#13520] Rendering main/index
[[0;37mDEBUG[0m|#13520] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|#13520] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#13520] Rendered main/_latest_updates (3.6ms)
[[0;37mDEBUG[0m|#13520] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#13520] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|#13520] Rendered main/_feeds (0.1ms)
[[0;37mDEBUG[0m|#13520] Rendered layouts/_watch_and_search (0.4ms)
[[0;37mDEBUG[0m|#13520] Rendered layouts/_header (0.9ms)
[[0;37mDEBUG[0m|#13520] Rendered layouts/_personal_navigation (0.4ms)
[[0;37mDEBUG[0m|#13520] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#13520] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#13520] Completed in 70ms (View: 11, DB: 0, API: 51, XML: 1) | 200 OK [http://www.example.com/]
[[32mINFO [0m|#13520]
Processing UserController#logout (for 127.0.0.1 at 2012-04-11 12:06:00) [POST]
[[0;37mDEBUG[0m|#13520] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|#13520] Authenticating with iChain mode: off
[[32mINFO [0m|#13520] Authenticated request to "/user/logout" from tom
[[0;37mDEBUG[0m|#13520] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#13520] Logging out: tom
[[32mINFO [0m|#13520] Redirected to http://www.example.com/
[[0;37mDEBUG[0m|#13520] Cache delete: Person_f7754490a4ed0ddff3ff300384cc6faa
[[32mINFO [0m|#13520] Completed in 4ms (DB: 1, API: 0, XML: 0) | 302 Found [http://www.example.com/user/logout]
[[0;37mDEBUG[0m|#13520] REQUESTING PAGE: GET / with {} and HTTP headers {}
[[32mINFO [0m|#13520]
Processing MainController#index (for 127.0.0.1 at 2012-04-11 12:06:00) [GET]
[[0;37mDEBUG[0m|#13520] Setting return_to: "/"
[[0;37mDEBUG[0m|#13520] Authenticating with iChain mode: off
[[32mINFO [0m|#13520] Anonymous request to /
[[0;37mDEBUG[0m|#13520] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#13520] method_missing -to_str- nil
[[0;37mDEBUG[0m|#13520] method_missing -to_str- nil
[[0;37mDEBUG[0m|#13520] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#13520] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#13520] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#13520] method_missing -to_str- nil
[[0;37mDEBUG[0m|#13520] method_missing -to_str- nil
[[0;37mDEBUG[0m|#13520] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#13520] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#13520] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[32mINFO [0m|#13520] Rendering template within layouts/application
[[32mINFO [0m|#13520] Rendering main/index
[[0;37mDEBUG[0m|#13520] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|#13520] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#13520] Rendered main/_latest_updates (3.7ms)
[[0;37mDEBUG[0m|#13520] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#13520] Rendered main/_sponsors (0.0ms)
[[0;37mDEBUG[0m|#13520] Rendered main/_feeds (0.0ms)
[[0;37mDEBUG[0m|#13520] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#13520] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|#13520] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|#13520] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#13520] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#13520] Completed in 16ms (View: 10, DB: 0, API: 0, XML: 1) | 200 OK [http://www.example.com/]
[[0;37mDEBUG[0m|#13520] REQUESTING PAGE: GET /user/login with {} and HTTP headers {"HTTP_REFERER"=>"/"}
[[32mINFO [0m|#13520]
Processing UserController#login (for 127.0.0.1 at 2012-04-11 12:06:00) [GET]
[[0;37mDEBUG[0m|#13520] Setting return_to: "/user/login"
[[0;37mDEBUG[0m|#13520] Authenticating with iChain mode: off
[[32mINFO [0m|#13520] Anonymous request to /user/login
[[0;37mDEBUG[0m|#13520] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#13520] Rendering template within layouts/application
[[32mINFO [0m|#13520] Rendering user/login
[[0;37mDEBUG[0m|#13520] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#13520] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|#13520] Rendered layouts/_personal_navigation (0.6ms)
[[0;37mDEBUG[0m|#13520] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#13520] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#13520] Completed in 8ms (View: 4, DB: 1, API: 0, XML: 0) | 200 OK [http://www.example.com/user/login]
[[0;37mDEBUG[0m|#13520] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/login"}
[[32mINFO [0m|#13520]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-11 12:06:00) [POST]
[[32mINFO [0m|#13520] Parameters: {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"}
[[0;37mDEBUG[0m|#13520] Setting return_to: "/"
[[0;37mDEBUG[0m|#13520] Authenticating with iChain mode: off
[[32mINFO [0m|#13520] Anonymous request to /
[[0;37mDEBUG[0m|#13520] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#13520] Doing form authorization to login user king
[[0;37mDEBUG[0m|#13520] Person.find( nil, king)
[[0;37mDEBUG[0m|#13520] [REST] find( Person, [{:login=>"king"}] )
[[0;37mDEBUG[0m|#13520] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#13520] Request took 0.013123834 seconds
[[32mINFO [0m|#13520] Redirected to http://www.example.com/
[[32mINFO [0m|#13520] Completed in 17ms (DB: 0, API: 13, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#13520] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#13520]
Processing MainController#index (for 127.0.0.1 at 2012-04-11 12:06:00) [GET]
[[0;37mDEBUG[0m|#13520] Setting return_to: "/"
[[0;37mDEBUG[0m|#13520] Authenticating with iChain mode: off
[[32mINFO [0m|#13520] Authenticated request to "/" from king
[[0;37mDEBUG[0m|#13520] Cache delete: Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#13520] method_missing -to_str- nil
[[0;37mDEBUG[0m|#13520] method_missing -to_str- nil
[[0;37mDEBUG[0m|#13520] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#13520] Person.find( 1800 seconds, king, {:is_current=>true})
[[0;37mDEBUG[0m|#13520] Cache miss: Person_31bc44caa5b35568da22b828c6853364 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#13520] [REST] find( Person, [{:is_current=>"true", :login=>"king"}] )
[[0;37mDEBUG[0m|#13520] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#13520] Request took 0.049987566 seconds
[[0;37mDEBUG[0m|#13520] Cache write (will save 50.39ms): Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#13520] Cache miss: king_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#13520] Fetching request list from api
[[0;37mDEBUG[0m|#13520] --> direct_http url: #
See http://ci.opensuse.org/job/obs_master_testsuite_webui/258/changes
Changes:
[Sascha Peilicke] [webui] Remove some more traces of delayed_job (following 8cb76d68)
------------------------------------------
[...truncated 76553 lines...]
[[0;37mDEBUG[0m|#20690] Rendered layouts/_personal_navigation (0.9ms)
[[0;37mDEBUG[0m|#20690] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#20690] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#20690] Completed in 334ms (View: 54, DB: 0, API: 275, XML: 0) | 200 OK [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#20690] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#20690]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-11 13:25:28) [POST]
[[32mINFO [0m|#20690] Parameters: {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"}
[[0;37mDEBUG[0m|#20690] Setting return_to: "/"
[[0;37mDEBUG[0m|#20690] Authenticating with iChain mode: off
[[32mINFO [0m|#20690] Anonymous request to /
[[0;37mDEBUG[0m|#20690] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#20690] Doing form authorization to login user tom
[[0;37mDEBUG[0m|#20690] Person.find( nil, tom)
[[0;37mDEBUG[0m|#20690] [REST] find( Person, [{:login=>"tom"}] )
[[0;37mDEBUG[0m|#20690] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#20690] Request took 0.023267919 seconds
[[32mINFO [0m|#20690] Redirected to http://www.example.com/
[[32mINFO [0m|#20690] Completed in 27ms (DB: 1, API: 23, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#20690] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#20690]
Processing MainController#index (for 127.0.0.1 at 2012-04-11 13:25:28) [GET]
[[0;37mDEBUG[0m|#20690] Setting return_to: "/"
[[0;37mDEBUG[0m|#20690] Authenticating with iChain mode: off
[[32mINFO [0m|#20690] Authenticated request to "/" from tom
[[0;37mDEBUG[0m|#20690] Cache delete: Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|#20690] method_missing -to_str- nil
[[0;37mDEBUG[0m|#20690] method_missing -to_str- nil
[[0;37mDEBUG[0m|#20690] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#20690] Person.find( 1800 seconds, tom, {:is_current=>true})
[[0;37mDEBUG[0m|#20690] Cache miss: Person_bcccc817286c9705b67feb0c7cc953c3 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#20690] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#20690] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#20690] Request took 0.054061636 seconds
[[0;37mDEBUG[0m|#20690] Cache write (will save 54.51ms): Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|#20690] Cache hit: tom_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#20690] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#20690] method_missing -to_str- nil
[[0;37mDEBUG[0m|#20690] method_missing -to_str- nil
[[0;37mDEBUG[0m|#20690] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#20690] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#20690] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#20690] method_missing -to_str- nil
[[0;37mDEBUG[0m|#20690] method_missing -to_str- nil
[[0;37mDEBUG[0m|#20690] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#20690] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#20690] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[0;37mDEBUG[0m|#20690] Project.find( 1800 seconds, home:tom)
[[0;37mDEBUG[0m|#20690] Cache hit: Project_adb9094ae2c389898fafee54514195c4 ({:expires_in=>1800 seconds})
[[32mINFO [0m|#20690] Rendering template within layouts/application
[[32mINFO [0m|#20690] Rendering main/index
[[0;37mDEBUG[0m|#20690] Rendered main/_news (0.6ms)
[[0;37mDEBUG[0m|#20690] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#20690] Rendered main/_latest_updates (3.6ms)
[[0;37mDEBUG[0m|#20690] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#20690] Rendered main/_sponsors (0.0ms)
[[0;37mDEBUG[0m|#20690] Rendered main/_feeds (0.0ms)
[[0;37mDEBUG[0m|#20690] Rendered layouts/_watch_and_search (0.4ms)
[[0;37mDEBUG[0m|#20690] Rendered layouts/_header (0.9ms)
[[0;37mDEBUG[0m|#20690] Rendered layouts/_personal_navigation (0.4ms)
[[0;37mDEBUG[0m|#20690] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#20690] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#20690] Completed in 73ms (View: 11, DB: 0, API: 54, XML: 1) | 200 OK [http://www.example.com/]
[[32mINFO [0m|#20690]
Processing UserController#logout (for 127.0.0.1 at 2012-04-11 13:25:28) [POST]
[[0;37mDEBUG[0m|#20690] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|#20690] Authenticating with iChain mode: off
[[32mINFO [0m|#20690] Authenticated request to "/user/logout" from tom
[[0;37mDEBUG[0m|#20690] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#20690] Logging out: tom
[[32mINFO [0m|#20690] Redirected to http://www.example.com/
[[0;37mDEBUG[0m|#20690] Cache delete: Person_f7754490a4ed0ddff3ff300384cc6faa
[[32mINFO [0m|#20690] Completed in 4ms (DB: 1, API: 0, XML: 0) | 302 Found [http://www.example.com/user/logout]
[[0;37mDEBUG[0m|#20690] REQUESTING PAGE: GET / with {} and HTTP headers {}
[[32mINFO [0m|#20690]
Processing MainController#index (for 127.0.0.1 at 2012-04-11 13:25:28) [GET]
[[0;37mDEBUG[0m|#20690] Setting return_to: "/"
[[0;37mDEBUG[0m|#20690] Authenticating with iChain mode: off
[[32mINFO [0m|#20690] Anonymous request to /
[[0;37mDEBUG[0m|#20690] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#20690] method_missing -to_str- nil
[[0;37mDEBUG[0m|#20690] method_missing -to_str- nil
[[0;37mDEBUG[0m|#20690] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#20690] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#20690] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#20690] method_missing -to_str- nil
[[0;37mDEBUG[0m|#20690] method_missing -to_str- nil
[[0;37mDEBUG[0m|#20690] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#20690] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#20690] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[32mINFO [0m|#20690] Rendering template within layouts/application
[[32mINFO [0m|#20690] Rendering main/index
[[0;37mDEBUG[0m|#20690] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|#20690] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#20690] Rendered main/_latest_updates (3.6ms)
[[0;37mDEBUG[0m|#20690] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#20690] Rendered main/_sponsors (0.0ms)
[[0;37mDEBUG[0m|#20690] Rendered main/_feeds (0.0ms)
[[0;37mDEBUG[0m|#20690] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#20690] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|#20690] Rendered layouts/_personal_navigation (0.6ms)
[[0;37mDEBUG[0m|#20690] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#20690] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#20690] Completed in 15ms (View: 10, DB: 0, API: 0, XML: 1) | 200 OK [http://www.example.com/]
[[0;37mDEBUG[0m|#20690] REQUESTING PAGE: GET /user/login with {} and HTTP headers {"HTTP_REFERER"=>"/"}
[[32mINFO [0m|#20690]
Processing UserController#login (for 127.0.0.1 at 2012-04-11 13:25:28) [GET]
[[0;37mDEBUG[0m|#20690] Setting return_to: "/user/login"
[[0;37mDEBUG[0m|#20690] Authenticating with iChain mode: off
[[32mINFO [0m|#20690] Anonymous request to /user/login
[[0;37mDEBUG[0m|#20690] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#20690] Rendering template within layouts/application
[[32mINFO [0m|#20690] Rendering user/login
[[0;37mDEBUG[0m|#20690] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#20690] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|#20690] Rendered layouts/_personal_navigation (0.6ms)
[[0;37mDEBUG[0m|#20690] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#20690] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#20690] Completed in 8ms (View: 4, DB: 1, API: 0, XML: 0) | 200 OK [http://www.example.com/user/login]
[[0;37mDEBUG[0m|#20690] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/login"}
[[32mINFO [0m|#20690]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-11 13:25:28) [POST]
[[32mINFO [0m|#20690] Parameters: {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"}
[[0;37mDEBUG[0m|#20690] Setting return_to: "/"
[[0;37mDEBUG[0m|#20690] Authenticating with iChain mode: off
[[32mINFO [0m|#20690] Anonymous request to /
[[0;37mDEBUG[0m|#20690] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#20690] Doing form authorization to login user king
[[0;37mDEBUG[0m|#20690] Person.find( nil, king)
[[0;37mDEBUG[0m|#20690] [REST] find( Person, [{:login=>"king"}] )
[[0;37mDEBUG[0m|#20690] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#20690] Request took 0.013286334 seconds
[[32mINFO [0m|#20690] Redirected to http://www.example.com/
[[32mINFO [0m|#20690] Completed in 21ms (DB: 3, API: 13, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#20690] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#20690]
Processing MainController#index (for 127.0.0.1 at 2012-04-11 13:25:28) [GET]
[[0;37mDEBUG[0m|#20690] Setting return_to: "/"
[[0;37mDEBUG[0m|#20690] Authenticating with iChain mode: off
[[32mINFO [0m|#20690] Authenticated request to "/" from king
[[0;37mDEBUG[0m|#20690] Cache delete: Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#20690] method_missing -to_str- nil
[[0;37mDEBUG[0m|#20690] method_missing -to_str- nil
[[0;37mDEBUG[0m|#20690] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#20690] Person.find( 1800 seconds, king, {:is_current=>true})
[[0;37mDEBUG[0m|#20690] Cache miss: Person_31bc44caa5b35568da22b828c6853364 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#20690] [REST] find( Person, [{:is_current=>"true", :login=>"king"}] )
[[0;37mDEBUG[0m|#20690] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#20690] Request took 0.051829891 seconds
[[0;37mDEBUG[0m|#20690] Cache write (will save 52.27ms): Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#20690] Cache miss: king_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#20690] Fetching request list from api
[[0;37mDEBUG[0m|#20690] --> direct_http url: #
See http://ci.opensuse.org/job/obs_master_testsuite_webui/259/changes
Changes:
[adrian] [dist] fix browsing of published repositories
------------------------------------------
[...truncated 76552 lines...]
[[0;37mDEBUG[0m|#25635] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|#25635] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#25635] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#25635] Completed in 221ms (View: 5, DB: 0, API: 211, XML: 0) | 200 OK [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#25635] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#25635]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-11 14:39:50) [POST]
[[32mINFO [0m|#25635] Parameters: {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"}
[[0;37mDEBUG[0m|#25635] Setting return_to: "/"
[[0;37mDEBUG[0m|#25635] Authenticating with iChain mode: off
[[32mINFO [0m|#25635] Anonymous request to /
[[0;37mDEBUG[0m|#25635] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#25635] Doing form authorization to login user tom
[[0;37mDEBUG[0m|#25635] Person.find( nil, tom)
[[0;37mDEBUG[0m|#25635] [REST] find( Person, [{:login=>"tom"}] )
[[0;37mDEBUG[0m|#25635] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#25635] Request took 0.017877906 seconds
[[32mINFO [0m|#25635] Redirected to http://www.example.com/
[[32mINFO [0m|#25635] Completed in 70ms (DB: 1, API: 18, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#25635] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#25635]
Processing MainController#index (for 127.0.0.1 at 2012-04-11 14:39:50) [GET]
[[0;37mDEBUG[0m|#25635] Setting return_to: "/"
[[0;37mDEBUG[0m|#25635] Authenticating with iChain mode: off
[[32mINFO [0m|#25635] Authenticated request to "/" from tom
[[0;37mDEBUG[0m|#25635] Cache delete: Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|#25635] method_missing -to_str- nil
[[0;37mDEBUG[0m|#25635] method_missing -to_str- nil
[[0;37mDEBUG[0m|#25635] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#25635] Person.find( 1800 seconds, tom, {:is_current=>true})
[[0;37mDEBUG[0m|#25635] Cache miss: Person_bcccc817286c9705b67feb0c7cc953c3 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#25635] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#25635] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#25635] Request took 0.051273701 seconds
[[0;37mDEBUG[0m|#25635] Cache write (will save 51.69ms): Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|#25635] Cache hit: tom_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#25635] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#25635] method_missing -to_str- nil
[[0;37mDEBUG[0m|#25635] method_missing -to_str- nil
[[0;37mDEBUG[0m|#25635] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#25635] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#25635] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#25635] method_missing -to_str- nil
[[0;37mDEBUG[0m|#25635] method_missing -to_str- nil
[[0;37mDEBUG[0m|#25635] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#25635] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#25635] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[0;37mDEBUG[0m|#25635] Project.find( 1800 seconds, home:tom)
[[0;37mDEBUG[0m|#25635] Cache hit: Project_adb9094ae2c389898fafee54514195c4 ({:expires_in=>1800 seconds})
[[32mINFO [0m|#25635] Rendering template within layouts/application
[[32mINFO [0m|#25635] Rendering main/index
[[0;37mDEBUG[0m|#25635] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|#25635] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#25635] Rendered main/_latest_updates (3.7ms)
[[0;37mDEBUG[0m|#25635] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#25635] Rendered main/_sponsors (0.0ms)
[[0;37mDEBUG[0m|#25635] Rendered main/_feeds (0.1ms)
[[0;37mDEBUG[0m|#25635] Rendered layouts/_watch_and_search (0.4ms)
[[0;37mDEBUG[0m|#25635] Rendered layouts/_header (0.9ms)
[[0;37mDEBUG[0m|#25635] Rendered layouts/_personal_navigation (0.5ms)
[[0;37mDEBUG[0m|#25635] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#25635] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#25635] Completed in 70ms (View: 11, DB: 0, API: 51, XML: 1) | 200 OK [http://www.example.com/]
[[32mINFO [0m|#25635]
Processing UserController#logout (for 127.0.0.1 at 2012-04-11 14:39:50) [POST]
[[0;37mDEBUG[0m|#25635] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|#25635] Authenticating with iChain mode: off
[[32mINFO [0m|#25635] Authenticated request to "/user/logout" from tom
[[0;37mDEBUG[0m|#25635] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#25635] Logging out: tom
[[32mINFO [0m|#25635] Redirected to http://www.example.com/
[[0;37mDEBUG[0m|#25635] Cache delete: Person_f7754490a4ed0ddff3ff300384cc6faa
[[32mINFO [0m|#25635] Completed in 4ms (DB: 1, API: 0, XML: 0) | 302 Found [http://www.example.com/user/logout]
[[0;37mDEBUG[0m|#25635] REQUESTING PAGE: GET / with {} and HTTP headers {}
[[32mINFO [0m|#25635]
Processing MainController#index (for 127.0.0.1 at 2012-04-11 14:39:50) [GET]
[[0;37mDEBUG[0m|#25635] Setting return_to: "/"
[[0;37mDEBUG[0m|#25635] Authenticating with iChain mode: off
[[32mINFO [0m|#25635] Anonymous request to /
[[0;37mDEBUG[0m|#25635] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#25635] method_missing -to_str- nil
[[0;37mDEBUG[0m|#25635] method_missing -to_str- nil
[[0;37mDEBUG[0m|#25635] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#25635] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#25635] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#25635] method_missing -to_str- nil
[[0;37mDEBUG[0m|#25635] method_missing -to_str- nil
[[0;37mDEBUG[0m|#25635] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#25635] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#25635] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[32mINFO [0m|#25635] Rendering template within layouts/application
[[32mINFO [0m|#25635] Rendering main/index
[[0;37mDEBUG[0m|#25635] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|#25635] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#25635] Rendered main/_latest_updates (3.6ms)
[[0;37mDEBUG[0m|#25635] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#25635] Rendered main/_sponsors (0.0ms)
[[0;37mDEBUG[0m|#25635] Rendered main/_feeds (0.0ms)
[[0;37mDEBUG[0m|#25635] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#25635] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|#25635] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|#25635] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#25635] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#25635] Completed in 15ms (View: 10, DB: 0, API: 0, XML: 1) | 200 OK [http://www.example.com/]
[[0;37mDEBUG[0m|#25635] REQUESTING PAGE: GET /user/login with {} and HTTP headers {"HTTP_REFERER"=>"/"}
[[32mINFO [0m|#25635]
Processing UserController#login (for 127.0.0.1 at 2012-04-11 14:39:50) [GET]
[[0;37mDEBUG[0m|#25635] Setting return_to: "/user/login"
[[0;37mDEBUG[0m|#25635] Authenticating with iChain mode: off
[[32mINFO [0m|#25635] Anonymous request to /user/login
[[0;37mDEBUG[0m|#25635] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#25635] Rendering template within layouts/application
[[32mINFO [0m|#25635] Rendering user/login
[[0;37mDEBUG[0m|#25635] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#25635] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|#25635] Rendered layouts/_personal_navigation (0.6ms)
[[0;37mDEBUG[0m|#25635] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#25635] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#25635] Completed in 8ms (View: 4, DB: 1, API: 0, XML: 0) | 200 OK [http://www.example.com/user/login]
[[0;37mDEBUG[0m|#25635] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/login"}
[[32mINFO [0m|#25635]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-11 14:39:50) [POST]
[[32mINFO [0m|#25635] Parameters: {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"}
[[0;37mDEBUG[0m|#25635] Setting return_to: "/"
[[0;37mDEBUG[0m|#25635] Authenticating with iChain mode: off
[[32mINFO [0m|#25635] Anonymous request to /
[[0;37mDEBUG[0m|#25635] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#25635] Doing form authorization to login user king
[[0;37mDEBUG[0m|#25635] Person.find( nil, king)
[[0;37mDEBUG[0m|#25635] [REST] find( Person, [{:login=>"king"}] )
[[0;37mDEBUG[0m|#25635] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#25635] Request took 0.01317955 seconds
[[32mINFO [0m|#25635] Redirected to http://www.example.com/
[[32mINFO [0m|#25635] Completed in 17ms (DB: 0, API: 13, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#25635] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#25635]
Processing MainController#index (for 127.0.0.1 at 2012-04-11 14:39:50) [GET]
[[0;37mDEBUG[0m|#25635] Setting return_to: "/"
[[0;37mDEBUG[0m|#25635] Authenticating with iChain mode: off
[[32mINFO [0m|#25635] Authenticated request to "/" from king
[[0;37mDEBUG[0m|#25635] Cache delete: Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#25635] method_missing -to_str- nil
[[0;37mDEBUG[0m|#25635] method_missing -to_str- nil
[[0;37mDEBUG[0m|#25635] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#25635] Person.find( 1800 seconds, king, {:is_current=>true})
[[0;37mDEBUG[0m|#25635] Cache miss: Person_31bc44caa5b35568da22b828c6853364 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#25635] [REST] find( Person, [{:is_current=>"true", :login=>"king"}] )
[[0;37mDEBUG[0m|#25635] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#25635] Request took 0.04925419 seconds
[[0;37mDEBUG[0m|#25635] Cache write (will save 49.65ms): Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#25635] Cache miss: king_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#25635] Fetching request list from api
[[0;37mDEBUG[0m|#25635] --> direct_http url: #
See http://ci.opensuse.org/job/obs_master_testsuite_webui/260/changes
Changes:
[Sascha Peilicke] [webui] Fix some more testsuite issues...
------------------------------------------
[...truncated 78416 lines...]
[[0;37mDEBUG[0m|# 676] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|# 676] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|# 676] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|# 676] Completed in 950ms (View: 6, DB: 0, API: 939, XML: 0) | 200 OK [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|# 676] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|# 676]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-11 16:10:02) [POST]
[[32mINFO [0m|# 676] Parameters: {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"}
[[0;37mDEBUG[0m|# 676] Setting return_to: "/"
[[0;37mDEBUG[0m|# 676] Authenticating with iChain mode: off
[[32mINFO [0m|# 676] Anonymous request to /
[[0;37mDEBUG[0m|# 676] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 676] Doing form authorization to login user tom
[[0;37mDEBUG[0m|# 676] Person.find( nil, tom)
[[0;37mDEBUG[0m|# 676] [REST] find( Person, [{:login=>"tom"}] )
[[0;37mDEBUG[0m|# 676] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|# 676] Request took 0.358485362 seconds
[[32mINFO [0m|# 676] Redirected to http://www.example.com/
[[32mINFO [0m|# 676] Completed in 363ms (DB: 1, API: 359, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|# 676] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|# 676]
Processing MainController#index (for 127.0.0.1 at 2012-04-11 16:10:03) [GET]
[[0;37mDEBUG[0m|# 676] Setting return_to: "/"
[[0;37mDEBUG[0m|# 676] Authenticating with iChain mode: off
[[32mINFO [0m|# 676] Authenticated request to "/" from tom
[[0;37mDEBUG[0m|# 676] Cache delete: Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|# 676] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 676] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 676] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 676] Person.find( 1800 seconds, tom, {:is_current=>true})
[[0;37mDEBUG[0m|# 676] Cache miss: Person_bcccc817286c9705b67feb0c7cc953c3 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 676] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|# 676] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|# 676] Request took 0.0619841 seconds
[[0;37mDEBUG[0m|# 676] Cache write (will save 62.47ms): Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|# 676] Cache hit: tom_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|# 676] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 676] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 676] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 676] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 676] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|# 676] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|# 676] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 676] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 676] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 676] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|# 676] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[0;37mDEBUG[0m|# 676] Project.find( 1800 seconds, home:tom)
[[0;37mDEBUG[0m|# 676] Cache hit: Project_adb9094ae2c389898fafee54514195c4 ({:expires_in=>1800 seconds})
[[32mINFO [0m|# 676] Rendering template within layouts/application
[[32mINFO [0m|# 676] Rendering main/index
[[0;37mDEBUG[0m|# 676] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|# 676] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|# 676] Rendered main/_latest_updates (3.8ms)
[[0;37mDEBUG[0m|# 676] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|# 676] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|# 676] Rendered main/_feeds (0.1ms)
[[0;37mDEBUG[0m|# 676] Rendered layouts/_watch_and_search (0.4ms)
[[0;37mDEBUG[0m|# 676] Rendered layouts/_header (0.9ms)
[[0;37mDEBUG[0m|# 676] Rendered layouts/_personal_navigation (0.5ms)
[[0;37mDEBUG[0m|# 676] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|# 676] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|# 676] Completed in 81ms (View: 12, DB: 0, API: 62, XML: 1) | 200 OK [http://www.example.com/]
[[32mINFO [0m|# 676]
Processing UserController#logout (for 127.0.0.1 at 2012-04-11 16:10:03) [POST]
[[0;37mDEBUG[0m|# 676] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|# 676] Authenticating with iChain mode: off
[[32mINFO [0m|# 676] Authenticated request to "/user/logout" from tom
[[0;37mDEBUG[0m|# 676] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|# 676] Logging out: tom
[[32mINFO [0m|# 676] Redirected to http://www.example.com/
[[0;37mDEBUG[0m|# 676] Cache delete: Person_f7754490a4ed0ddff3ff300384cc6faa
[[32mINFO [0m|# 676] Completed in 4ms (DB: 1, API: 0, XML: 0) | 302 Found [http://www.example.com/user/logout]
[[0;37mDEBUG[0m|# 676] REQUESTING PAGE: GET / with {} and HTTP headers {}
[[32mINFO [0m|# 676]
Processing MainController#index (for 127.0.0.1 at 2012-04-11 16:10:03) [GET]
[[0;37mDEBUG[0m|# 676] Setting return_to: "/"
[[0;37mDEBUG[0m|# 676] Authenticating with iChain mode: off
[[32mINFO [0m|# 676] Anonymous request to /
[[0;37mDEBUG[0m|# 676] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 676] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 676] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 676] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 676] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|# 676] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|# 676] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 676] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 676] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 676] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|# 676] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[32mINFO [0m|# 676] Rendering template within layouts/application
[[32mINFO [0m|# 676] Rendering main/index
[[0;37mDEBUG[0m|# 676] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|# 676] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|# 676] Rendered main/_latest_updates (3.6ms)
[[0;37mDEBUG[0m|# 676] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|# 676] Rendered main/_sponsors (0.0ms)
[[0;37mDEBUG[0m|# 676] Rendered main/_feeds (0.0ms)
[[0;37mDEBUG[0m|# 676] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|# 676] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|# 676] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|# 676] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|# 676] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|# 676] Completed in 15ms (View: 10, DB: 0, API: 0, XML: 1) | 200 OK [http://www.example.com/]
[[0;37mDEBUG[0m|# 676] REQUESTING PAGE: GET /user/login with {} and HTTP headers {"HTTP_REFERER"=>"/"}
[[32mINFO [0m|# 676]
Processing UserController#login (for 127.0.0.1 at 2012-04-11 16:10:03) [GET]
[[0;37mDEBUG[0m|# 676] Setting return_to: "/user/login"
[[0;37mDEBUG[0m|# 676] Authenticating with iChain mode: off
[[32mINFO [0m|# 676] Anonymous request to /user/login
[[0;37mDEBUG[0m|# 676] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|# 676] Rendering template within layouts/application
[[32mINFO [0m|# 676] Rendering user/login
[[0;37mDEBUG[0m|# 676] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|# 676] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|# 676] Rendered layouts/_personal_navigation (0.6ms)
[[0;37mDEBUG[0m|# 676] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|# 676] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|# 676] Completed in 9ms (View: 4, DB: 1, API: 0, XML: 0) | 200 OK [http://www.example.com/user/login]
[[0;37mDEBUG[0m|# 676] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/login"}
[[32mINFO [0m|# 676]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-11 16:10:03) [POST]
[[32mINFO [0m|# 676] Parameters: {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"}
[[0;37mDEBUG[0m|# 676] Setting return_to: "/"
[[0;37mDEBUG[0m|# 676] Authenticating with iChain mode: off
[[32mINFO [0m|# 676] Anonymous request to /
[[0;37mDEBUG[0m|# 676] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 676] Doing form authorization to login user king
[[0;37mDEBUG[0m|# 676] Person.find( nil, king)
[[0;37mDEBUG[0m|# 676] [REST] find( Person, [{:login=>"king"}] )
[[0;37mDEBUG[0m|# 676] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|# 676] Request took 0.013157634 seconds
[[32mINFO [0m|# 676] Redirected to http://www.example.com/
[[32mINFO [0m|# 676] Completed in 17ms (DB: 0, API: 13, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|# 676] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|# 676]
Processing MainController#index (for 127.0.0.1 at 2012-04-11 16:10:03) [GET]
[[0;37mDEBUG[0m|# 676] Setting return_to: "/"
[[0;37mDEBUG[0m|# 676] Authenticating with iChain mode: off
[[32mINFO [0m|# 676] Authenticated request to "/" from king
[[0;37mDEBUG[0m|# 676] Cache delete: Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|# 676] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 676] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 676] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 676] Person.find( 1800 seconds, king, {:is_current=>true})
[[0;37mDEBUG[0m|# 676] Cache miss: Person_31bc44caa5b35568da22b828c6853364 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 676] [REST] find( Person, [{:is_current=>"true", :login=>"king"}] )
[[0;37mDEBUG[0m|# 676] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|# 676] Request took 0.05112036 seconds
[[0;37mDEBUG[0m|# 676] Cache write (will save 51.53ms): Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|# 676] Cache miss: king_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|# 676] Fetching request list from api
[[0;37mDEBUG[0m|# 676] --> direct_http url: #
See http://ci.opensuse.org/job/obs_master_testsuite_webui/261/changes
Changes:
[Sascha Peilicke] [api,webui] Drop code to handle www-form-encoded data in configuration
------------------------------------------
[...truncated 78425 lines...]
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|# 7910] Completed in 219ms (View: 6, DB: 0, API: 207, XML: 0) | 200 OK [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|# 7910] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|# 7910]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-11 16:50:53) [POST]
[[32mINFO [0m|# 7910] Parameters: {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"}
[[0;37mDEBUG[0m|# 7910] Setting return_to: "/"
[[0;37mDEBUG[0m|# 7910] Authenticating with iChain mode: off
[[32mINFO [0m|# 7910] Anonymous request to /
[[0;37mDEBUG[0m|# 7910] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7910] Doing form authorization to login user tom
[[0;37mDEBUG[0m|# 7910] Person.find( nil, tom)
[[0;37mDEBUG[0m|# 7910] [REST] find( Person, [{:login=>"tom"}] )
[[0;37mDEBUG[0m|# 7910] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|# 7910] Request took 0.065218303 seconds
[[32mINFO [0m|# 7910] Redirected to http://www.example.com/
[[32mINFO [0m|# 7910] Completed in 69ms (DB: 0, API: 65, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|# 7910] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|# 7910]
Processing MainController#index (for 127.0.0.1 at 2012-04-11 16:50:53) [GET]
[[0;37mDEBUG[0m|# 7910] Setting return_to: "/"
[[0;37mDEBUG[0m|# 7910] Authenticating with iChain mode: off
[[32mINFO [0m|# 7910] Authenticated request to "/" from tom
[[0;37mDEBUG[0m|# 7910] Cache delete: Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|# 7910] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7910] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7910] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7910] Person.find( 1800 seconds, tom, {:is_current=>true})
[[0;37mDEBUG[0m|# 7910] Cache miss: Person_bcccc817286c9705b67feb0c7cc953c3 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7910] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|# 7910] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|# 7910] Request took 0.051139314 seconds
[[0;37mDEBUG[0m|# 7910] Cache write (will save 51.54ms): Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|# 7910] Cache hit: tom_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|# 7910] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7910] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7910] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7910] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7910] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|# 7910] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|# 7910] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7910] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7910] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7910] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|# 7910] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[0;37mDEBUG[0m|# 7910] Project.find( 1800 seconds, home:tom)
[[0;37mDEBUG[0m|# 7910] Cache hit: Project_adb9094ae2c389898fafee54514195c4 ({:expires_in=>1800 seconds})
[[32mINFO [0m|# 7910] Rendering template within layouts/application
[[32mINFO [0m|# 7910] Rendering main/index
[[0;37mDEBUG[0m|# 7910] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|# 7910] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|# 7910] Rendered main/_latest_updates (3.5ms)
[[0;37mDEBUG[0m|# 7910] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|# 7910] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|# 7910] Rendered main/_feeds (0.0ms)
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_watch_and_search (0.4ms)
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_header (0.9ms)
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_personal_navigation (0.4ms)
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|# 7910] Completed in 70ms (View: 11, DB: 0, API: 51, XML: 1) | 200 OK [http://www.example.com/]
[[32mINFO [0m|# 7910]
Processing UserController#logout (for 127.0.0.1 at 2012-04-11 16:50:53) [POST]
[[0;37mDEBUG[0m|# 7910] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|# 7910] Authenticating with iChain mode: off
[[32mINFO [0m|# 7910] Authenticated request to "/user/logout" from tom
[[0;37mDEBUG[0m|# 7910] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|# 7910] Logging out: tom
[[32mINFO [0m|# 7910] Redirected to http://www.example.com/
[[0;37mDEBUG[0m|# 7910] Cache delete: Person_f7754490a4ed0ddff3ff300384cc6faa
[[32mINFO [0m|# 7910] Completed in 4ms (DB: 1, API: 0, XML: 0) | 302 Found [http://www.example.com/user/logout]
[[0;37mDEBUG[0m|# 7910] REQUESTING PAGE: GET / with {} and HTTP headers {}
[[32mINFO [0m|# 7910]
Processing MainController#index (for 127.0.0.1 at 2012-04-11 16:50:53) [GET]
[[0;37mDEBUG[0m|# 7910] Setting return_to: "/"
[[0;37mDEBUG[0m|# 7910] Authenticating with iChain mode: off
[[32mINFO [0m|# 7910] Anonymous request to /
[[0;37mDEBUG[0m|# 7910] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7910] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7910] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7910] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7910] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|# 7910] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|# 7910] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7910] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7910] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7910] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|# 7910] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[32mINFO [0m|# 7910] Rendering template within layouts/application
[[32mINFO [0m|# 7910] Rendering main/index
[[0;37mDEBUG[0m|# 7910] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|# 7910] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|# 7910] Rendered main/_latest_updates (3.6ms)
[[0;37mDEBUG[0m|# 7910] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|# 7910] Rendered main/_sponsors (0.0ms)
[[0;37mDEBUG[0m|# 7910] Rendered main/_feeds (0.0ms)
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|# 7910] Completed in 15ms (View: 10, DB: 0, API: 0, XML: 1) | 200 OK [http://www.example.com/]
[[0;37mDEBUG[0m|# 7910] REQUESTING PAGE: GET /user/login with {} and HTTP headers {"HTTP_REFERER"=>"/"}
[[32mINFO [0m|# 7910]
Processing UserController#login (for 127.0.0.1 at 2012-04-11 16:50:53) [GET]
[[0;37mDEBUG[0m|# 7910] Setting return_to: "/user/login"
[[0;37mDEBUG[0m|# 7910] Authenticating with iChain mode: off
[[32mINFO [0m|# 7910] Anonymous request to /user/login
[[0;37mDEBUG[0m|# 7910] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|# 7910] Rendering template within layouts/application
[[32mINFO [0m|# 7910] Rendering user/login
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_watch_and_search (0.5ms)
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_header (1.1ms)
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_personal_navigation (0.8ms)
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|# 7910] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|# 7910] Completed in 59ms (View: 55, DB: 1, API: 0, XML: 0) | 200 OK [http://www.example.com/user/login]
[[0;37mDEBUG[0m|# 7910] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/login"}
[[32mINFO [0m|# 7910]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-11 16:50:53) [POST]
[[32mINFO [0m|# 7910] Parameters: {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"}
[[0;37mDEBUG[0m|# 7910] Setting return_to: "/"
[[0;37mDEBUG[0m|# 7910] Authenticating with iChain mode: off
[[32mINFO [0m|# 7910] Anonymous request to /
[[0;37mDEBUG[0m|# 7910] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7910] Doing form authorization to login user king
[[0;37mDEBUG[0m|# 7910] Person.find( nil, king)
[[0;37mDEBUG[0m|# 7910] [REST] find( Person, [{:login=>"king"}] )
[[0;37mDEBUG[0m|# 7910] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|# 7910] Request took 0.013350196 seconds
[[32mINFO [0m|# 7910] Redirected to http://www.example.com/
[[32mINFO [0m|# 7910] Completed in 18ms (DB: 0, API: 13, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|# 7910] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|# 7910]
Processing MainController#index (for 127.0.0.1 at 2012-04-11 16:50:53) [GET]
[[0;37mDEBUG[0m|# 7910] Setting return_to: "/"
[[0;37mDEBUG[0m|# 7910] Authenticating with iChain mode: off
[[32mINFO [0m|# 7910] Authenticated request to "/" from king
[[0;37mDEBUG[0m|# 7910] Cache delete: Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|# 7910] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7910] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7910] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7910] Person.find( 1800 seconds, king, {:is_current=>true})
[[0;37mDEBUG[0m|# 7910] Cache miss: Person_31bc44caa5b35568da22b828c6853364 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7910] [REST] find( Person, [{:is_current=>"true", :login=>"king"}] )
[[0;37mDEBUG[0m|# 7910] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|# 7910] Request took 0.048640201 seconds
[[0;37mDEBUG[0m|# 7910] Cache write (will save 49.06ms): Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|# 7910] Cache miss: king_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|# 7910] Fetching request list from api
[[0;37mDEBUG[0m|# 7910] --> direct_http url: #
See http://ci.opensuse.org/job/obs_master_testsuite_webui/262/changes
Changes:
[adrian] [api] do not crash on set_flag and unlock operations on remote packages
[Sascha Peilicke] [webui] Updated sponsorship
------------------------------------------
[...truncated 78421 lines...]
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|# 7073] Completed in 315ms (View: 6, DB: 0, API: 304, XML: 0) | 200 OK [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|# 7073] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|# 7073]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-12 11:13:59) [POST]
[[32mINFO [0m|# 7073] Parameters: {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"}
[[0;37mDEBUG[0m|# 7073] Setting return_to: "/"
[[0;37mDEBUG[0m|# 7073] Authenticating with iChain mode: off
[[32mINFO [0m|# 7073] Anonymous request to /
[[0;37mDEBUG[0m|# 7073] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7073] Doing form authorization to login user tom
[[0;37mDEBUG[0m|# 7073] Person.find( nil, tom)
[[0;37mDEBUG[0m|# 7073] [REST] find( Person, [{:login=>"tom"}] )
[[0;37mDEBUG[0m|# 7073] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|# 7073] Request took 0.030772572 seconds
[[32mINFO [0m|# 7073] Redirected to http://www.example.com/
[[32mINFO [0m|# 7073] Completed in 35ms (DB: 1, API: 31, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|# 7073] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|# 7073]
Processing MainController#index (for 127.0.0.1 at 2012-04-12 11:13:59) [GET]
[[0;37mDEBUG[0m|# 7073] Setting return_to: "/"
[[0;37mDEBUG[0m|# 7073] Authenticating with iChain mode: off
[[32mINFO [0m|# 7073] Authenticated request to "/" from tom
[[0;37mDEBUG[0m|# 7073] Cache delete: Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|# 7073] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7073] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7073] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7073] Person.find( 1800 seconds, tom, {:is_current=>true})
[[0;37mDEBUG[0m|# 7073] Cache miss: Person_bcccc817286c9705b67feb0c7cc953c3 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7073] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|# 7073] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|# 7073] Request took 0.050856039 seconds
[[0;37mDEBUG[0m|# 7073] Cache write (will save 51.28ms): Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|# 7073] Cache hit: tom_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|# 7073] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7073] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7073] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7073] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7073] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|# 7073] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|# 7073] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7073] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7073] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7073] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|# 7073] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[0;37mDEBUG[0m|# 7073] Project.find( 1800 seconds, home:tom)
[[0;37mDEBUG[0m|# 7073] Cache hit: Project_adb9094ae2c389898fafee54514195c4 ({:expires_in=>1800 seconds})
[[32mINFO [0m|# 7073] Rendering template within layouts/application
[[32mINFO [0m|# 7073] Rendering main/index
[[0;37mDEBUG[0m|# 7073] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|# 7073] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|# 7073] Rendered main/_latest_updates (3.6ms)
[[0;37mDEBUG[0m|# 7073] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|# 7073] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|# 7073] Rendered main/_feeds (0.0ms)
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_watch_and_search (0.4ms)
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_header (0.9ms)
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_personal_navigation (0.4ms)
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|# 7073] Completed in 70ms (View: 11, DB: 0, API: 51, XML: 1) | 200 OK [http://www.example.com/]
[[32mINFO [0m|# 7073]
Processing UserController#logout (for 127.0.0.1 at 2012-04-12 11:13:59) [POST]
[[0;37mDEBUG[0m|# 7073] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|# 7073] Authenticating with iChain mode: off
[[32mINFO [0m|# 7073] Authenticated request to "/user/logout" from tom
[[0;37mDEBUG[0m|# 7073] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|# 7073] Logging out: tom
[[32mINFO [0m|# 7073] Redirected to http://www.example.com/
[[0;37mDEBUG[0m|# 7073] Cache delete: Person_f7754490a4ed0ddff3ff300384cc6faa
[[32mINFO [0m|# 7073] Completed in 4ms (DB: 1, API: 0, XML: 0) | 302 Found [http://www.example.com/user/logout]
[[0;37mDEBUG[0m|# 7073] REQUESTING PAGE: GET / with {} and HTTP headers {}
[[32mINFO [0m|# 7073]
Processing MainController#index (for 127.0.0.1 at 2012-04-12 11:13:59) [GET]
[[0;37mDEBUG[0m|# 7073] Setting return_to: "/"
[[0;37mDEBUG[0m|# 7073] Authenticating with iChain mode: off
[[32mINFO [0m|# 7073] Anonymous request to /
[[0;37mDEBUG[0m|# 7073] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7073] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7073] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7073] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7073] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|# 7073] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|# 7073] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7073] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7073] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7073] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|# 7073] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[32mINFO [0m|# 7073] Rendering template within layouts/application
[[32mINFO [0m|# 7073] Rendering main/index
[[0;37mDEBUG[0m|# 7073] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|# 7073] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|# 7073] Rendered main/_latest_updates (3.5ms)
[[0;37mDEBUG[0m|# 7073] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|# 7073] Rendered main/_sponsors (0.0ms)
[[0;37mDEBUG[0m|# 7073] Rendered main/_feeds (0.0ms)
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|# 7073] Completed in 15ms (View: 10, DB: 0, API: 0, XML: 1) | 200 OK [http://www.example.com/]
[[0;37mDEBUG[0m|# 7073] REQUESTING PAGE: GET /user/login with {} and HTTP headers {"HTTP_REFERER"=>"/"}
[[32mINFO [0m|# 7073]
Processing UserController#login (for 127.0.0.1 at 2012-04-12 11:13:59) [GET]
[[0;37mDEBUG[0m|# 7073] Setting return_to: "/user/login"
[[0;37mDEBUG[0m|# 7073] Authenticating with iChain mode: off
[[32mINFO [0m|# 7073] Anonymous request to /user/login
[[0;37mDEBUG[0m|# 7073] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|# 7073] Rendering template within layouts/application
[[32mINFO [0m|# 7073] Rendering user/login
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_personal_navigation (0.6ms)
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|# 7073] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|# 7073] Completed in 8ms (View: 4, DB: 1, API: 0, XML: 0) | 200 OK [http://www.example.com/user/login]
[[0;37mDEBUG[0m|# 7073] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/login"}
[[32mINFO [0m|# 7073]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-12 11:13:59) [POST]
[[32mINFO [0m|# 7073] Parameters: {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"}
[[0;37mDEBUG[0m|# 7073] Setting return_to: "/"
[[0;37mDEBUG[0m|# 7073] Authenticating with iChain mode: off
[[32mINFO [0m|# 7073] Anonymous request to /
[[0;37mDEBUG[0m|# 7073] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7073] Doing form authorization to login user king
[[0;37mDEBUG[0m|# 7073] Person.find( nil, king)
[[0;37mDEBUG[0m|# 7073] [REST] find( Person, [{:login=>"king"}] )
[[0;37mDEBUG[0m|# 7073] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|# 7073] Request took 0.013091201 seconds
[[32mINFO [0m|# 7073] Redirected to http://www.example.com/
[[32mINFO [0m|# 7073] Completed in 17ms (DB: 0, API: 13, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|# 7073] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|# 7073]
Processing MainController#index (for 127.0.0.1 at 2012-04-12 11:13:59) [GET]
[[0;37mDEBUG[0m|# 7073] Setting return_to: "/"
[[0;37mDEBUG[0m|# 7073] Authenticating with iChain mode: off
[[32mINFO [0m|# 7073] Authenticated request to "/" from king
[[0;37mDEBUG[0m|# 7073] Cache delete: Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|# 7073] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7073] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7073] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7073] Person.find( 1800 seconds, king, {:is_current=>true})
[[0;37mDEBUG[0m|# 7073] Cache miss: Person_31bc44caa5b35568da22b828c6853364 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7073] [REST] find( Person, [{:is_current=>"true", :login=>"king"}] )
[[0;37mDEBUG[0m|# 7073] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|# 7073] Request took 0.013757298 seconds
[[0;37mDEBUG[0m|# 7073] Cache write (will save 14.20ms): Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|# 7073] Cache miss: king_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|# 7073] Fetching request list from api
[[0;37mDEBUG[0m|# 7073] --> direct_http url: #
See http://ci.opensuse.org/job/obs_master_testsuite_webui/263/changes
Changes:
[Sascha Peilicke] [webui] Don't bail out in Person#running_patchinfos if a project was
------------------------------------------
[...truncated 78424 lines...]
[[0;37mDEBUG[0m|#19670] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|#19670] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#19670] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#19670] Completed in 187ms (View: 5, DB: 0, API: 176, XML: 0) | 200 OK [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#19670] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#19670]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-12 14:55:37) [POST]
[[32mINFO [0m|#19670] Parameters: {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"}
[[0;37mDEBUG[0m|#19670] Setting return_to: "/"
[[0;37mDEBUG[0m|#19670] Authenticating with iChain mode: off
[[32mINFO [0m|#19670] Anonymous request to /
[[0;37mDEBUG[0m|#19670] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#19670] Doing form authorization to login user tom
[[0;37mDEBUG[0m|#19670] Person.find( nil, tom)
[[0;37mDEBUG[0m|#19670] [REST] find( Person, [{:login=>"tom"}] )
[[0;37mDEBUG[0m|#19670] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#19670] Request took 0.03736735 seconds
[[32mINFO [0m|#19670] Redirected to http://www.example.com/
[[32mINFO [0m|#19670] Completed in 42ms (DB: 1, API: 37, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#19670] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#19670]
Processing MainController#index (for 127.0.0.1 at 2012-04-12 14:55:37) [GET]
[[0;37mDEBUG[0m|#19670] Setting return_to: "/"
[[0;37mDEBUG[0m|#19670] Authenticating with iChain mode: off
[[32mINFO [0m|#19670] Authenticated request to "/" from tom
[[0;37mDEBUG[0m|#19670] Cache delete: Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|#19670] method_missing -to_str- nil
[[0;37mDEBUG[0m|#19670] method_missing -to_str- nil
[[0;37mDEBUG[0m|#19670] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#19670] Person.find( 1800 seconds, tom, {:is_current=>true})
[[0;37mDEBUG[0m|#19670] Cache miss: Person_bcccc817286c9705b67feb0c7cc953c3 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#19670] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#19670] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#19670] Request took 0.050596937 seconds
[[0;37mDEBUG[0m|#19670] Cache write (will save 51.00ms): Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|#19670] Cache hit: tom_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#19670] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#19670] method_missing -to_str- nil
[[0;37mDEBUG[0m|#19670] method_missing -to_str- nil
[[0;37mDEBUG[0m|#19670] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#19670] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#19670] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#19670] method_missing -to_str- nil
[[0;37mDEBUG[0m|#19670] method_missing -to_str- nil
[[0;37mDEBUG[0m|#19670] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#19670] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#19670] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[0;37mDEBUG[0m|#19670] Project.find( 1800 seconds, home:tom)
[[0;37mDEBUG[0m|#19670] Cache hit: Project_adb9094ae2c389898fafee54514195c4 ({:expires_in=>1800 seconds})
[[32mINFO [0m|#19670] Rendering template within layouts/application
[[32mINFO [0m|#19670] Rendering main/index
[[0;37mDEBUG[0m|#19670] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|#19670] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#19670] Rendered main/_latest_updates (3.5ms)
[[0;37mDEBUG[0m|#19670] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#19670] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|#19670] Rendered main/_feeds (0.1ms)
[[0;37mDEBUG[0m|#19670] Rendered layouts/_watch_and_search (0.4ms)
[[0;37mDEBUG[0m|#19670] Rendered layouts/_header (0.9ms)
[[0;37mDEBUG[0m|#19670] Rendered layouts/_personal_navigation (0.4ms)
[[0;37mDEBUG[0m|#19670] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#19670] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#19670] Completed in 69ms (View: 11, DB: 0, API: 51, XML: 1) | 200 OK [http://www.example.com/]
[[32mINFO [0m|#19670]
Processing UserController#logout (for 127.0.0.1 at 2012-04-12 14:55:37) [POST]
[[0;37mDEBUG[0m|#19670] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|#19670] Authenticating with iChain mode: off
[[32mINFO [0m|#19670] Authenticated request to "/user/logout" from tom
[[0;37mDEBUG[0m|#19670] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#19670] Logging out: tom
[[32mINFO [0m|#19670] Redirected to http://www.example.com/
[[0;37mDEBUG[0m|#19670] Cache delete: Person_f7754490a4ed0ddff3ff300384cc6faa
[[32mINFO [0m|#19670] Completed in 4ms (DB: 1, API: 0, XML: 0) | 302 Found [http://www.example.com/user/logout]
[[0;37mDEBUG[0m|#19670] REQUESTING PAGE: GET / with {} and HTTP headers {}
[[32mINFO [0m|#19670]
Processing MainController#index (for 127.0.0.1 at 2012-04-12 14:55:37) [GET]
[[0;37mDEBUG[0m|#19670] Setting return_to: "/"
[[0;37mDEBUG[0m|#19670] Authenticating with iChain mode: off
[[32mINFO [0m|#19670] Anonymous request to /
[[0;37mDEBUG[0m|#19670] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#19670] method_missing -to_str- nil
[[0;37mDEBUG[0m|#19670] method_missing -to_str- nil
[[0;37mDEBUG[0m|#19670] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#19670] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#19670] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#19670] method_missing -to_str- nil
[[0;37mDEBUG[0m|#19670] method_missing -to_str- nil
[[0;37mDEBUG[0m|#19670] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#19670] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#19670] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[32mINFO [0m|#19670] Rendering template within layouts/application
[[32mINFO [0m|#19670] Rendering main/index
[[0;37mDEBUG[0m|#19670] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|#19670] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#19670] Rendered main/_latest_updates (3.5ms)
[[0;37mDEBUG[0m|#19670] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#19670] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|#19670] Rendered main/_feeds (0.1ms)
[[0;37mDEBUG[0m|#19670] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#19670] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|#19670] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|#19670] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#19670] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#19670] Completed in 15ms (View: 10, DB: 0, API: 0, XML: 1) | 200 OK [http://www.example.com/]
[[0;37mDEBUG[0m|#19670] REQUESTING PAGE: GET /user/login with {} and HTTP headers {"HTTP_REFERER"=>"/"}
[[32mINFO [0m|#19670]
Processing UserController#login (for 127.0.0.1 at 2012-04-12 14:55:37) [GET]
[[0;37mDEBUG[0m|#19670] Setting return_to: "/user/login"
[[0;37mDEBUG[0m|#19670] Authenticating with iChain mode: off
[[32mINFO [0m|#19670] Anonymous request to /user/login
[[0;37mDEBUG[0m|#19670] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#19670] Rendering template within layouts/application
[[32mINFO [0m|#19670] Rendering user/login
[[0;37mDEBUG[0m|#19670] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#19670] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|#19670] Rendered layouts/_personal_navigation (0.6ms)
[[0;37mDEBUG[0m|#19670] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#19670] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#19670] Completed in 8ms (View: 4, DB: 1, API: 0, XML: 0) | 200 OK [http://www.example.com/user/login]
[[0;37mDEBUG[0m|#19670] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/login"}
[[32mINFO [0m|#19670]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-12 14:55:37) [POST]
[[32mINFO [0m|#19670] Parameters: {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"}
[[0;37mDEBUG[0m|#19670] Setting return_to: "/"
[[0;37mDEBUG[0m|#19670] Authenticating with iChain mode: off
[[32mINFO [0m|#19670] Anonymous request to /
[[0;37mDEBUG[0m|#19670] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#19670] Doing form authorization to login user king
[[0;37mDEBUG[0m|#19670] Person.find( nil, king)
[[0;37mDEBUG[0m|#19670] [REST] find( Person, [{:login=>"king"}] )
[[0;37mDEBUG[0m|#19670] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#19670] Request took 0.013104602 seconds
[[32mINFO [0m|#19670] Redirected to http://www.example.com/
[[32mINFO [0m|#19670] Completed in 62ms (DB: 0, API: 13, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#19670] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#19670]
Processing MainController#index (for 127.0.0.1 at 2012-04-12 14:55:37) [GET]
[[0;37mDEBUG[0m|#19670] Setting return_to: "/"
[[0;37mDEBUG[0m|#19670] Authenticating with iChain mode: off
[[32mINFO [0m|#19670] Authenticated request to "/" from king
[[0;37mDEBUG[0m|#19670] Cache delete: Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#19670] method_missing -to_str- nil
[[0;37mDEBUG[0m|#19670] method_missing -to_str- nil
[[0;37mDEBUG[0m|#19670] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#19670] Person.find( 1800 seconds, king, {:is_current=>true})
[[0;37mDEBUG[0m|#19670] Cache miss: Person_31bc44caa5b35568da22b828c6853364 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#19670] [REST] find( Person, [{:is_current=>"true", :login=>"king"}] )
[[0;37mDEBUG[0m|#19670] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#19670] Request took 0.013534053 seconds
[[0;37mDEBUG[0m|#19670] Cache write (will save 13.99ms): Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#19670] Cache miss: king_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#19670] Fetching request list from api
[[0;37mDEBUG[0m|#19670] --> direct_http url: #
See http://ci.opensuse.org/job/obs_master_testsuite_webui/264/changes
Changes:
[Sascha Peilicke] [webui] Don't show table header for package list (currently only one
[Sascha Peilicke] [webui] Don't show empty <ul> in PackageController#show
[Sascha Peilicke] [webui] Fix for empty buildresult in PackageController#rpmlint_result
------------------------------------------
[...truncated 93485 lines...]
[[0;37mDEBUG[0m|#14385] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|#14385] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#14385] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#14385] Completed in 101ms (View: 6, DB: 1, API: 89, XML: 0) | 200 OK [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#14385] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#14385]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-13 10:17:34) [POST]
[[32mINFO [0m|#14385] Parameters: {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"}
[[0;37mDEBUG[0m|#14385] Setting return_to: "/"
[[0;37mDEBUG[0m|#14385] Authenticating with iChain mode: off
[[32mINFO [0m|#14385] Anonymous request to /
[[0;37mDEBUG[0m|#14385] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#14385] Doing form authorization to login user tom
[[0;37mDEBUG[0m|#14385] Person.find( nil, tom)
[[0;37mDEBUG[0m|#14385] [REST] find( Person, [{:login=>"tom"}] )
[[0;37mDEBUG[0m|#14385] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#14385] Request took 0.059591135 seconds
[[32mINFO [0m|#14385] Redirected to http://www.example.com/
[[32mINFO [0m|#14385] Completed in 65ms (DB: 1, API: 60, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#14385] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#14385]
Processing MainController#index (for 127.0.0.1 at 2012-04-13 10:17:34) [GET]
[[0;37mDEBUG[0m|#14385] Setting return_to: "/"
[[0;37mDEBUG[0m|#14385] Authenticating with iChain mode: off
[[32mINFO [0m|#14385] Authenticated request to "/" from tom
[[0;37mDEBUG[0m|#14385] Cache delete: Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|#14385] method_missing -to_str- nil
[[0;37mDEBUG[0m|#14385] method_missing -to_str- nil
[[0;37mDEBUG[0m|#14385] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#14385] Person.find( 1800 seconds, tom, {:is_current=>true})
[[0;37mDEBUG[0m|#14385] Cache miss: Person_bcccc817286c9705b67feb0c7cc953c3 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#14385] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#14385] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#14385] Request took 0.053439318 seconds
[[0;37mDEBUG[0m|#14385] Cache write (will save 53.98ms): Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|#14385] Cache hit: tom_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#14385] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#14385] method_missing -to_str- nil
[[0;37mDEBUG[0m|#14385] method_missing -to_str- nil
[[0;37mDEBUG[0m|#14385] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#14385] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#14385] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#14385] method_missing -to_str- nil
[[0;37mDEBUG[0m|#14385] method_missing -to_str- nil
[[0;37mDEBUG[0m|#14385] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#14385] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#14385] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[0;37mDEBUG[0m|#14385] Project.find( 1800 seconds, home:tom)
[[0;37mDEBUG[0m|#14385] Cache hit: Project_adb9094ae2c389898fafee54514195c4 ({:expires_in=>1800 seconds})
[[32mINFO [0m|#14385] Rendering template within layouts/application
[[32mINFO [0m|#14385] Rendering main/index
[[0;37mDEBUG[0m|#14385] Rendered main/_news (0.6ms)
[[0;37mDEBUG[0m|#14385] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#14385] Rendered main/_latest_updates (3.8ms)
[[0;37mDEBUG[0m|#14385] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#14385] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|#14385] Rendered main/_feeds (0.1ms)
[[0;37mDEBUG[0m|#14385] Rendered layouts/_watch_and_search (0.4ms)
[[0;37mDEBUG[0m|#14385] Rendered layouts/_header (1.0ms)
[[0;37mDEBUG[0m|#14385] Rendered layouts/_personal_navigation (0.4ms)
[[0;37mDEBUG[0m|#14385] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#14385] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#14385] Completed in 74ms (View: 12, DB: 1, API: 54, XML: 1) | 200 OK [http://www.example.com/]
[[32mINFO [0m|#14385]
Processing UserController#logout (for 127.0.0.1 at 2012-04-13 10:17:34) [POST]
[[0;37mDEBUG[0m|#14385] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|#14385] Authenticating with iChain mode: off
[[32mINFO [0m|#14385] Authenticated request to "/user/logout" from tom
[[0;37mDEBUG[0m|#14385] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#14385] Logging out: tom
[[32mINFO [0m|#14385] Redirected to http://www.example.com/
[[0;37mDEBUG[0m|#14385] Cache delete: Person_f7754490a4ed0ddff3ff300384cc6faa
[[32mINFO [0m|#14385] Completed in 4ms (DB: 1, API: 0, XML: 0) | 302 Found [http://www.example.com/user/logout]
[[0;37mDEBUG[0m|#14385] REQUESTING PAGE: GET / with {} and HTTP headers {}
[[32mINFO [0m|#14385]
Processing MainController#index (for 127.0.0.1 at 2012-04-13 10:17:34) [GET]
[[0;37mDEBUG[0m|#14385] Setting return_to: "/"
[[0;37mDEBUG[0m|#14385] Authenticating with iChain mode: off
[[32mINFO [0m|#14385] Anonymous request to /
[[0;37mDEBUG[0m|#14385] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#14385] method_missing -to_str- nil
[[0;37mDEBUG[0m|#14385] method_missing -to_str- nil
[[0;37mDEBUG[0m|#14385] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#14385] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#14385] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#14385] method_missing -to_str- nil
[[0;37mDEBUG[0m|#14385] method_missing -to_str- nil
[[0;37mDEBUG[0m|#14385] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#14385] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#14385] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[32mINFO [0m|#14385] Rendering template within layouts/application
[[32mINFO [0m|#14385] Rendering main/index
[[0;37mDEBUG[0m|#14385] Rendered main/_news (0.7ms)
[[0;37mDEBUG[0m|#14385] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#14385] Rendered main/_latest_updates (4.4ms)
[[0;37mDEBUG[0m|#14385] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#14385] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|#14385] Rendered main/_feeds (0.1ms)
[[0;37mDEBUG[0m|#14385] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#14385] Rendered layouts/_header (0.8ms)
[[0;37mDEBUG[0m|#14385] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|#14385] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#14385] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#14385] Completed in 92ms (View: 12, DB: 1, API: 0, XML: 1) | 200 OK [http://www.example.com/]
[[0;37mDEBUG[0m|#14385] REQUESTING PAGE: GET /user/login with {} and HTTP headers {"HTTP_REFERER"=>"/"}
[[32mINFO [0m|#14385]
Processing UserController#login (for 127.0.0.1 at 2012-04-13 10:17:35) [GET]
[[0;37mDEBUG[0m|#14385] Setting return_to: "/user/login"
[[0;37mDEBUG[0m|#14385] Authenticating with iChain mode: off
[[32mINFO [0m|#14385] Anonymous request to /user/login
[[0;37mDEBUG[0m|#14385] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#14385] Rendering template within layouts/application
[[32mINFO [0m|#14385] Rendering user/login
[[0;37mDEBUG[0m|#14385] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#14385] Rendered layouts/_header (0.8ms)
[[0;37mDEBUG[0m|#14385] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|#14385] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#14385] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#14385] Completed in 10ms (View: 5, DB: 1, API: 0, XML: 0) | 200 OK [http://www.example.com/user/login]
[[0;37mDEBUG[0m|#14385] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/login"}
[[32mINFO [0m|#14385]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-13 10:17:35) [POST]
[[32mINFO [0m|#14385] Parameters: {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"}
[[0;37mDEBUG[0m|#14385] Setting return_to: "/"
[[0;37mDEBUG[0m|#14385] Authenticating with iChain mode: off
[[32mINFO [0m|#14385] Anonymous request to /
[[0;37mDEBUG[0m|#14385] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#14385] Doing form authorization to login user king
[[0;37mDEBUG[0m|#14385] Person.find( nil, king)
[[0;37mDEBUG[0m|#14385] [REST] find( Person, [{:login=>"king"}] )
[[0;37mDEBUG[0m|#14385] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#14385] Request took 0.01599596 seconds
[[32mINFO [0m|#14385] Redirected to http://www.example.com/
[[32mINFO [0m|#14385] Completed in 21ms (DB: 0, API: 16, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#14385] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#14385]
Processing MainController#index (for 127.0.0.1 at 2012-04-13 10:17:35) [GET]
[[0;37mDEBUG[0m|#14385] Setting return_to: "/"
[[0;37mDEBUG[0m|#14385] Authenticating with iChain mode: off
[[32mINFO [0m|#14385] Authenticated request to "/" from king
[[0;37mDEBUG[0m|#14385] Cache delete: Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#14385] method_missing -to_str- nil
[[0;37mDEBUG[0m|#14385] method_missing -to_str- nil
[[0;37mDEBUG[0m|#14385] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#14385] Person.find( 1800 seconds, king, {:is_current=>true})
[[0;37mDEBUG[0m|#14385] Cache miss: Person_31bc44caa5b35568da22b828c6853364 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#14385] [REST] find( Person, [{:is_current=>"true", :login=>"king"}] )
[[0;37mDEBUG[0m|#14385] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#14385] Request took 0.051813495 seconds
[[0;37mDEBUG[0m|#14385] Cache write (will save 52.40ms): Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#14385] Cache miss: king_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#14385] Fetching request list from api
[[0;37mDEBUG[0m|#14385] --> direct_http url: #
See http://ci.opensuse.org/job/obs_master_testsuite_webui/265/changes
Changes:
[Sascha Peilicke] [webui] Fix wrong repo names in PackageController#rpmlint_log...
------------------------------------------
[...truncated 93482 lines...]
[[0;37mDEBUG[0m|#21516] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|#21516] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#21516] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#21516] Completed in 29ms (View: 6, DB: 1, API: 16, XML: 0) | 200 OK [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#21516] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#21516]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-13 11:38:21) [POST]
[[32mINFO [0m|#21516] Parameters: {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"}
[[0;37mDEBUG[0m|#21516] Setting return_to: "/"
[[0;37mDEBUG[0m|#21516] Authenticating with iChain mode: off
[[32mINFO [0m|#21516] Anonymous request to /
[[0;37mDEBUG[0m|#21516] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#21516] Doing form authorization to login user tom
[[0;37mDEBUG[0m|#21516] Person.find( nil, tom)
[[0;37mDEBUG[0m|#21516] [REST] find( Person, [{:login=>"tom"}] )
[[0;37mDEBUG[0m|#21516] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#21516] Request took 0.05956973 seconds
[[32mINFO [0m|#21516] Redirected to http://www.example.com/
[[32mINFO [0m|#21516] Completed in 136ms (DB: 1, API: 60, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#21516] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#21516]
Processing MainController#index (for 127.0.0.1 at 2012-04-13 11:38:21) [GET]
[[0;37mDEBUG[0m|#21516] Setting return_to: "/"
[[0;37mDEBUG[0m|#21516] Authenticating with iChain mode: off
[[32mINFO [0m|#21516] Authenticated request to "/" from tom
[[0;37mDEBUG[0m|#21516] Cache delete: Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|#21516] method_missing -to_str- nil
[[0;37mDEBUG[0m|#21516] method_missing -to_str- nil
[[0;37mDEBUG[0m|#21516] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#21516] Person.find( 1800 seconds, tom, {:is_current=>true})
[[0;37mDEBUG[0m|#21516] Cache miss: Person_bcccc817286c9705b67feb0c7cc953c3 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#21516] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#21516] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#21516] Request took 0.016781085 seconds
[[0;37mDEBUG[0m|#21516] Cache write (will save 17.35ms): Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|#21516] Cache hit: tom_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#21516] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#21516] method_missing -to_str- nil
[[0;37mDEBUG[0m|#21516] method_missing -to_str- nil
[[0;37mDEBUG[0m|#21516] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#21516] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#21516] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#21516] method_missing -to_str- nil
[[0;37mDEBUG[0m|#21516] method_missing -to_str- nil
[[0;37mDEBUG[0m|#21516] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#21516] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#21516] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[0;37mDEBUG[0m|#21516] Project.find( 1800 seconds, home:tom)
[[0;37mDEBUG[0m|#21516] Cache hit: Project_adb9094ae2c389898fafee54514195c4 ({:expires_in=>1800 seconds})
[[32mINFO [0m|#21516] Rendering template within layouts/application
[[32mINFO [0m|#21516] Rendering main/index
[[0;37mDEBUG[0m|#21516] Rendered main/_news (0.6ms)
[[0;37mDEBUG[0m|#21516] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#21516] Rendered main/_latest_updates (4.6ms)
[[0;37mDEBUG[0m|#21516] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#21516] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|#21516] Rendered main/_feeds (0.1ms)
[[0;37mDEBUG[0m|#21516] Rendered layouts/_watch_and_search (0.5ms)
[[0;37mDEBUG[0m|#21516] Rendered layouts/_header (1.0ms)
[[0;37mDEBUG[0m|#21516] Rendered layouts/_personal_navigation (0.5ms)
[[0;37mDEBUG[0m|#21516] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#21516] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#21516] Completed in 40ms (View: 14, DB: 1, API: 17, XML: 1) | 200 OK [http://www.example.com/]
[[32mINFO [0m|#21516]
Processing UserController#logout (for 127.0.0.1 at 2012-04-13 11:38:21) [POST]
[[0;37mDEBUG[0m|#21516] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|#21516] Authenticating with iChain mode: off
[[32mINFO [0m|#21516] Authenticated request to "/user/logout" from tom
[[0;37mDEBUG[0m|#21516] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#21516] Logging out: tom
[[32mINFO [0m|#21516] Redirected to http://www.example.com/
[[0;37mDEBUG[0m|#21516] Cache delete: Person_f7754490a4ed0ddff3ff300384cc6faa
[[32mINFO [0m|#21516] Completed in 4ms (DB: 1, API: 0, XML: 0) | 302 Found [http://www.example.com/user/logout]
[[0;37mDEBUG[0m|#21516] REQUESTING PAGE: GET / with {} and HTTP headers {}
[[32mINFO [0m|#21516]
Processing MainController#index (for 127.0.0.1 at 2012-04-13 11:38:21) [GET]
[[0;37mDEBUG[0m|#21516] Setting return_to: "/"
[[0;37mDEBUG[0m|#21516] Authenticating with iChain mode: off
[[32mINFO [0m|#21516] Anonymous request to /
[[0;37mDEBUG[0m|#21516] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#21516] method_missing -to_str- nil
[[0;37mDEBUG[0m|#21516] method_missing -to_str- nil
[[0;37mDEBUG[0m|#21516] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#21516] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#21516] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#21516] method_missing -to_str- nil
[[0;37mDEBUG[0m|#21516] method_missing -to_str- nil
[[0;37mDEBUG[0m|#21516] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#21516] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#21516] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[32mINFO [0m|#21516] Rendering template within layouts/application
[[32mINFO [0m|#21516] Rendering main/index
[[0;37mDEBUG[0m|#21516] Rendered main/_news (0.7ms)
[[0;37mDEBUG[0m|#21516] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#21516] Rendered main/_latest_updates (4.0ms)
[[0;37mDEBUG[0m|#21516] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#21516] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|#21516] Rendered main/_feeds (0.1ms)
[[0;37mDEBUG[0m|#21516] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#21516] Rendered layouts/_header (0.8ms)
[[0;37mDEBUG[0m|#21516] Rendered layouts/_personal_navigation (0.8ms)
[[0;37mDEBUG[0m|#21516] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#21516] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#21516] Completed in 18ms (View: 11, DB: 0, API: 0, XML: 1) | 200 OK [http://www.example.com/]
[[0;37mDEBUG[0m|#21516] REQUESTING PAGE: GET /user/login with {} and HTTP headers {"HTTP_REFERER"=>"/"}
[[32mINFO [0m|#21516]
Processing UserController#login (for 127.0.0.1 at 2012-04-13 11:38:21) [GET]
[[0;37mDEBUG[0m|#21516] Setting return_to: "/user/login"
[[0;37mDEBUG[0m|#21516] Authenticating with iChain mode: off
[[32mINFO [0m|#21516] Anonymous request to /user/login
[[0;37mDEBUG[0m|#21516] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#21516] Rendering template within layouts/application
[[32mINFO [0m|#21516] Rendering user/login
[[0;37mDEBUG[0m|#21516] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#21516] Rendered layouts/_header (0.8ms)
[[0;37mDEBUG[0m|#21516] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|#21516] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#21516] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#21516] Completed in 10ms (View: 5, DB: 1, API: 0, XML: 0) | 200 OK [http://www.example.com/user/login]
[[0;37mDEBUG[0m|#21516] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/login"}
[[32mINFO [0m|#21516]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-13 11:38:21) [POST]
[[32mINFO [0m|#21516] Parameters: {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"}
[[0;37mDEBUG[0m|#21516] Setting return_to: "/"
[[0;37mDEBUG[0m|#21516] Authenticating with iChain mode: off
[[32mINFO [0m|#21516] Anonymous request to /
[[0;37mDEBUG[0m|#21516] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#21516] Doing form authorization to login user king
[[0;37mDEBUG[0m|#21516] Person.find( nil, king)
[[0;37mDEBUG[0m|#21516] [REST] find( Person, [{:login=>"king"}] )
[[0;37mDEBUG[0m|#21516] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#21516] Request took 0.01629131 seconds
[[32mINFO [0m|#21516] Redirected to http://www.example.com/
[[32mINFO [0m|#21516] Completed in 21ms (DB: 0, API: 16, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#21516] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#21516]
Processing MainController#index (for 127.0.0.1 at 2012-04-13 11:38:21) [GET]
[[0;37mDEBUG[0m|#21516] Setting return_to: "/"
[[0;37mDEBUG[0m|#21516] Authenticating with iChain mode: off
[[32mINFO [0m|#21516] Authenticated request to "/" from king
[[0;37mDEBUG[0m|#21516] Cache delete: Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#21516] method_missing -to_str- nil
[[0;37mDEBUG[0m|#21516] method_missing -to_str- nil
[[0;37mDEBUG[0m|#21516] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#21516] Person.find( 1800 seconds, king, {:is_current=>true})
[[0;37mDEBUG[0m|#21516] Cache miss: Person_31bc44caa5b35568da22b828c6853364 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#21516] [REST] find( Person, [{:is_current=>"true", :login=>"king"}] )
[[0;37mDEBUG[0m|#21516] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#21516] Request took 0.054628679 seconds
[[0;37mDEBUG[0m|#21516] Cache write (will save 55.25ms): Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#21516] Cache miss: king_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#21516] Fetching request list from api
[[0;37mDEBUG[0m|#21516] --> direct_http url: #
See http://ci.opensuse.org/job/obs_master_testsuite_webui/266/changes
Changes:
[Sascha Peilicke] [webui] Fix doubled breadcrumbs for remote projects
------------------------------------------
[...truncated 93467 lines...]
[[0;37mDEBUG[0m|#28561] Rendered layouts/_personal_navigation (0.8ms)
[[0;37mDEBUG[0m|#28561] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#28561] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#28561] Completed in 227ms (View: 6, DB: 0, API: 166, XML: 0) | 200 OK [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#28561] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#28561]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-13 12:57:16) [POST]
[[32mINFO [0m|#28561] Parameters: {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"}
[[0;37mDEBUG[0m|#28561] Setting return_to: "/"
[[0;37mDEBUG[0m|#28561] Authenticating with iChain mode: off
[[32mINFO [0m|#28561] Anonymous request to /
[[0;37mDEBUG[0m|#28561] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#28561] Doing form authorization to login user tom
[[0;37mDEBUG[0m|#28561] Person.find( nil, tom)
[[0;37mDEBUG[0m|#28561] [REST] find( Person, [{:login=>"tom"}] )
[[0;37mDEBUG[0m|#28561] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#28561] Request took 0.05536717 seconds
[[32mINFO [0m|#28561] Redirected to http://www.example.com/
[[32mINFO [0m|#28561] Completed in 60ms (DB: 1, API: 55, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#28561] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#28561]
Processing MainController#index (for 127.0.0.1 at 2012-04-13 12:57:16) [GET]
[[0;37mDEBUG[0m|#28561] Setting return_to: "/"
[[0;37mDEBUG[0m|#28561] Authenticating with iChain mode: off
[[32mINFO [0m|#28561] Authenticated request to "/" from tom
[[0;37mDEBUG[0m|#28561] Cache delete: Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|#28561] method_missing -to_str- nil
[[0;37mDEBUG[0m|#28561] method_missing -to_str- nil
[[0;37mDEBUG[0m|#28561] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#28561] Person.find( 1800 seconds, tom, {:is_current=>true})
[[0;37mDEBUG[0m|#28561] Cache miss: Person_bcccc817286c9705b67feb0c7cc953c3 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#28561] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#28561] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#28561] Request took 0.050941281 seconds
[[0;37mDEBUG[0m|#28561] Cache write (will save 51.35ms): Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|#28561] Cache hit: tom_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#28561] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#28561] method_missing -to_str- nil
[[0;37mDEBUG[0m|#28561] method_missing -to_str- nil
[[0;37mDEBUG[0m|#28561] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#28561] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#28561] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#28561] method_missing -to_str- nil
[[0;37mDEBUG[0m|#28561] method_missing -to_str- nil
[[0;37mDEBUG[0m|#28561] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#28561] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#28561] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[0;37mDEBUG[0m|#28561] Project.find( 1800 seconds, home:tom)
[[0;37mDEBUG[0m|#28561] Cache hit: Project_adb9094ae2c389898fafee54514195c4 ({:expires_in=>1800 seconds})
[[32mINFO [0m|#28561] Rendering template within layouts/application
[[32mINFO [0m|#28561] Rendering main/index
[[0;37mDEBUG[0m|#28561] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|#28561] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#28561] Rendered main/_latest_updates (3.7ms)
[[0;37mDEBUG[0m|#28561] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#28561] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|#28561] Rendered main/_feeds (0.0ms)
[[0;37mDEBUG[0m|#28561] Rendered layouts/_watch_and_search (0.4ms)
[[0;37mDEBUG[0m|#28561] Rendered layouts/_header (0.9ms)
[[0;37mDEBUG[0m|#28561] Rendered layouts/_personal_navigation (0.4ms)
[[0;37mDEBUG[0m|#28561] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#28561] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#28561] Completed in 70ms (View: 11, DB: 0, API: 51, XML: 1) | 200 OK [http://www.example.com/]
[[32mINFO [0m|#28561]
Processing UserController#logout (for 127.0.0.1 at 2012-04-13 12:57:16) [POST]
[[0;37mDEBUG[0m|#28561] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|#28561] Authenticating with iChain mode: off
[[32mINFO [0m|#28561] Authenticated request to "/user/logout" from tom
[[0;37mDEBUG[0m|#28561] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#28561] Logging out: tom
[[32mINFO [0m|#28561] Redirected to http://www.example.com/
[[0;37mDEBUG[0m|#28561] Cache delete: Person_f7754490a4ed0ddff3ff300384cc6faa
[[32mINFO [0m|#28561] Completed in 4ms (DB: 1, API: 0, XML: 0) | 302 Found [http://www.example.com/user/logout]
[[0;37mDEBUG[0m|#28561] REQUESTING PAGE: GET / with {} and HTTP headers {}
[[32mINFO [0m|#28561]
Processing MainController#index (for 127.0.0.1 at 2012-04-13 12:57:16) [GET]
[[0;37mDEBUG[0m|#28561] Setting return_to: "/"
[[0;37mDEBUG[0m|#28561] Authenticating with iChain mode: off
[[32mINFO [0m|#28561] Anonymous request to /
[[0;37mDEBUG[0m|#28561] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#28561] method_missing -to_str- nil
[[0;37mDEBUG[0m|#28561] method_missing -to_str- nil
[[0;37mDEBUG[0m|#28561] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#28561] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#28561] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#28561] method_missing -to_str- nil
[[0;37mDEBUG[0m|#28561] method_missing -to_str- nil
[[0;37mDEBUG[0m|#28561] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#28561] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#28561] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[32mINFO [0m|#28561] Rendering template within layouts/application
[[32mINFO [0m|#28561] Rendering main/index
[[0;37mDEBUG[0m|#28561] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|#28561] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#28561] Rendered main/_latest_updates (3.6ms)
[[0;37mDEBUG[0m|#28561] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#28561] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|#28561] Rendered main/_feeds (0.0ms)
[[0;37mDEBUG[0m|#28561] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#28561] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|#28561] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|#28561] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#28561] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#28561] Completed in 15ms (View: 10, DB: 0, API: 0, XML: 1) | 200 OK [http://www.example.com/]
[[0;37mDEBUG[0m|#28561] REQUESTING PAGE: GET /user/login with {} and HTTP headers {"HTTP_REFERER"=>"/"}
[[32mINFO [0m|#28561]
Processing UserController#login (for 127.0.0.1 at 2012-04-13 12:57:16) [GET]
[[0;37mDEBUG[0m|#28561] Setting return_to: "/user/login"
[[0;37mDEBUG[0m|#28561] Authenticating with iChain mode: off
[[32mINFO [0m|#28561] Anonymous request to /user/login
[[0;37mDEBUG[0m|#28561] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#28561] Rendering template within layouts/application
[[32mINFO [0m|#28561] Rendering user/login
[[0;37mDEBUG[0m|#28561] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#28561] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|#28561] Rendered layouts/_personal_navigation (0.6ms)
[[0;37mDEBUG[0m|#28561] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#28561] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#28561] Completed in 8ms (View: 4, DB: 1, API: 0, XML: 0) | 200 OK [http://www.example.com/user/login]
[[0;37mDEBUG[0m|#28561] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/login"}
[[32mINFO [0m|#28561]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-13 12:57:16) [POST]
[[32mINFO [0m|#28561] Parameters: {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"}
[[0;37mDEBUG[0m|#28561] Setting return_to: "/"
[[0;37mDEBUG[0m|#28561] Authenticating with iChain mode: off
[[32mINFO [0m|#28561] Anonymous request to /
[[0;37mDEBUG[0m|#28561] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#28561] Doing form authorization to login user king
[[0;37mDEBUG[0m|#28561] Person.find( nil, king)
[[0;37mDEBUG[0m|#28561] [REST] find( Person, [{:login=>"king"}] )
[[0;37mDEBUG[0m|#28561] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#28561] Request took 0.013616039 seconds
[[32mINFO [0m|#28561] Redirected to http://www.example.com/
[[32mINFO [0m|#28561] Completed in 18ms (DB: 0, API: 14, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#28561] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#28561]
Processing MainController#index (for 127.0.0.1 at 2012-04-13 12:57:16) [GET]
[[0;37mDEBUG[0m|#28561] Setting return_to: "/"
[[0;37mDEBUG[0m|#28561] Authenticating with iChain mode: off
[[32mINFO [0m|#28561] Authenticated request to "/" from king
[[0;37mDEBUG[0m|#28561] Cache delete: Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#28561] method_missing -to_str- nil
[[0;37mDEBUG[0m|#28561] method_missing -to_str- nil
[[0;37mDEBUG[0m|#28561] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#28561] Person.find( 1800 seconds, king, {:is_current=>true})
[[0;37mDEBUG[0m|#28561] Cache miss: Person_31bc44caa5b35568da22b828c6853364 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#28561] [REST] find( Person, [{:is_current=>"true", :login=>"king"}] )
[[0;37mDEBUG[0m|#28561] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#28561] Request took 0.04929432 seconds
[[0;37mDEBUG[0m|#28561] Cache write (will save 49.75ms): Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#28561] Cache miss: king_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#28561] Fetching request list from api
[[0;37mDEBUG[0m|#28561] --> direct_http url: #
See http://ci.opensuse.org/job/obs_master_testsuite_webui/267/changes
Changes:
[Sascha Peilicke] [webui] Use link_to for remote instance link
[Sascha Peilicke] [webui] Catch missing parameter 'id' in RequestController#show
[adrian] [api] add hostsystem structure for cross builds, no functionality behind it yet (for B1-Systems)
------------------------------------------
Started by an SCM change
Started by an SCM change
Building in workspace http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/
Checkout:workspace / http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/ - hudson.remoting.LocalChannel@202e202e
Using strategy: Default
Last Built Revision: Revision c20ad95cb101a4fd5471dd3bffe5cd590e7b3470 (origin/master)
Checkout:workspace / http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/ - hudson.remoting.LocalChannel@202e202e
Fetching changes from 1 remote Git repository
Fetching upstream changes from git://github.com/openSUSE/open-build-service.git
Commencing build of Revision 3b425607396e56fb453a59cc37f55c5a1dee9ae7 (origin/master)
Checking out Revision 3b425607396e56fb453a59cc37f55c5a1dee9ae7 (origin/master)
[locks-and-latches] Checking to see if we really have the locks
[locks-and-latches] Have all the locks, build can start
[workspace] $ /bin/sh -xe /tmp/hudson6144772600608963891.sh
+ sh dist/ci/obs_testsuite_webui.sh
++ dirname dist/ci/obs_testsuite_webui.sh
+ . dist/ci/obs_testsuite_common.sh
+ setup_git
+ echo 'Checking status'
Checking status
+ git status
# Not currently on any branch.
# Untracked files:
# (use "git add <file>..." to include in what will be committed)
#
# src/webui/results/
nothing added to commit but untracked files present (use "git add" to track)
+ echo 'Setup git submodules'
Setup git submodules
+ git submodule init
+ git submodule update
+ echo 'Setup backend configuration template'
Setup backend configuration template
+ sed -i -e 's|my $hostname = .*$|my $hostname = '\''localhost'\'';|' -e 's|our $bsuser = '\''obsrun'\'';|our $bsuser = '\''jenkins'\'';|' -e 's|our $bsgroup = '\''obsrun'\'';|our $bsgroup = '\''jenkins'\'';|' src/backend/BSConfig.pm.template
+ cp src/backend/BSConfig.pm.template src/backend/BSConfig.pm
+ echo 'Set environment variables'
Set environment variables
+ export RAILS_ENV=test
+ RAILS_ENV=test
+ ret=0
+ setup_api
+ echo 'Enter API rails root'
Enter API rails root
+ cd src/api
+ echo 'Setup database configuration'
Setup database configuration
+ cp config/database.yml.example config/database.yml
+ sed -i 's|database: api|database: ci_api|' config/database.yml
+ echo 'Setup additional configuration'
Setup additional configuration
+ cp config/options.yml.example config/options.yml
+ echo 'Install missing gems locally'
Install missing gems locally
+ rm Gemfile.lock
+ bundle list
Gems included by the bundle:
* actionmailer (3.2.3)
* actionpack (3.2.3)
* activemodel (3.2.3)
* activerecord (3.2.3)
* activeresource (3.2.3)
* activesupport (3.2.3)
* arel (3.0.2)
* builder (3.0.0)
* bundler (1.1.3)
* ci_reporter (1.7.0)
* delayed_job (3.0.2)
* delayed_job_active_record (0.3.2)
* erubis (2.7.0)
* exception_notification (2.5.2)
* fast_xs (0.8.0)
* hike (1.2.1)
* i18n (0.6.0)
* journey (1.0.3)
* json (1.6.6)
* mail (2.4.4)
* memcache-client (1.8.5)
* mime-types (1.18)
* multi_json (1.2.0)
* mysql2 (0.3.11)
* nokogiri (1.5.2)
* polyglot (0.3.3)
* rack (1.4.1)
* rack-cache (1.2)
* rack-ssl (1.3.2)
* rack-test (0.6.1)
* rails (3.2.3)
* railties (3.2.3)
* rake (0.9.2.2)
* rdoc (3.12)
* simplecov (0.6.1)
* simplecov-html (0.5.3)
* simplecov-rcov (0.2.3)
* sprockets (2.1.2)
* test-unit (2.4.8)
* thor (0.14.6)
* tilt (1.3.3)
* treetop (1.4.10)
* tzinfo (0.3.32)
* xmlhash (1.2.1)
* yajl-ruby (1.1.0)
+ git diff Gemfile.lock
diff --git a/src/api/Gemfile.lock b/src/api/Gemfile.lock
index e09ea7f..77013d5 100644
--- a/src/api/Gemfile.lock
+++ b/src/api/Gemfile.lock
@@ -96,7 +96,7 @@ GEM
treetop (1.4.10)
polyglot
polyglot (>= 0.3.1)
- tzinfo (0.3.33)
+ tzinfo (0.3.32)
xmlhash (1.2.1)
yajl-ruby (1.1.0)
+ chmod a+x script/start_test_backend
+ echo 'Initialize test database, run migrations, load seed data'
Initialize test database, run migrations, load seed data
+ rake --trace db:drop db:create db:setup db:migrate
** Invoke db:drop (first_time)
** Invoke db:load_config (first_time)
** Invoke rails_env (first_time)
** Execute rails_env
** Execute db:load_config
** Execute db:drop
** Invoke db:create (first_time)
** Invoke db:load_config
** Execute db:create
** Invoke db:setup (first_time)
** Execute db:setup
** Invoke db:structure:load (first_time)
** Invoke environment (first_time)
** Execute environment
** Invoke db:load_config
** Execute db:structure:load
rake aborted!
Mysql2::Error: Key column 'hostsystem' doesn't exist in table: CREATE TABLE `repositories` (
`id` int(11) NOT NULL AUTO_INCREMENT,
`db_project_id` int(11) NOT NULL,
`name` varchar(255) DEFAULT NULL,
`remote_project_name` varchar(255) DEFAULT NULL,
`rebuild` enum('transitive','direct','local') DEFAULT NULL,
`block` enum('all','local','never') DEFAULT NULL,
`linkedbuild` enum('off','localdep','all') DEFAULT NULL,
`hostsystem_id` int(11) DEFAULT NULL,
PRIMARY KEY (`id`),
UNIQUE KEY `projects_name_index` (`db_project_id`,`name`,`remote_project_name`),
KEY `remote_project_name_index` (`remote_project_name`),
KEY `hostsystem` (`hostsystem`),
KEY `hostsystem_id` (`hostsystem_id`),
CONSTRAINT `repositories_ibfk_1` FOREIGN KEY (`db_project_id`) REFERENCES `db_projects` (`id`),
CONSTRAINT `repositories_ibfk_2` FOREIGN KEY (`hostsystem_id`) REFERENCES `repositories` (`id`)
) ENGINE=InnoDB DEFAULT CHARSET=utf8;
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:243:in `query'
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:243:in `block in execute'
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/connection_adapters/abstract_adapter.rb:280:in `block in log'
/usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.3/lib/active_support/notifications/instrumenter.rb:20:in `instrument'
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/connection_adapters/abstract_adapter.rb:275:in `log'
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:243:in `execute'
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/connection_adapters/mysql2_adapter.rb:211:in `execute'
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/railties/databases.rake:424:in `block (4 levels) in
See http://ci.opensuse.org/job/obs_master_testsuite_webui/268/changes
Changes:
[adrian] [api] validate access to host project
------------------------------------------
Started by an SCM change
Building in workspace http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/
Checkout:workspace / http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/ - hudson.remoting.LocalChannel@202e202e
Using strategy: Default
Last Built Revision: Revision 3b425607396e56fb453a59cc37f55c5a1dee9ae7 (origin/master)
Checkout:workspace / http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/ - hudson.remoting.LocalChannel@202e202e
Fetching changes from 1 remote Git repository
Fetching upstream changes from git://github.com/openSUSE/open-build-service.git
Commencing build of Revision e43cf8c50bd0e8610f4c41ddcf92574974d3e823 (origin/master)
Checking out Revision e43cf8c50bd0e8610f4c41ddcf92574974d3e823 (origin/master)
[locks-and-latches] Checking to see if we really have the locks
[locks-and-latches] Have all the locks, build can start
[workspace] $ /bin/sh -xe /tmp/hudson2898041676722889646.sh
+ sh dist/ci/obs_testsuite_webui.sh
++ dirname dist/ci/obs_testsuite_webui.sh
+ . dist/ci/obs_testsuite_common.sh
+ setup_git
+ echo 'Checking status'
Checking status
+ git status
# Not currently on any branch.
# Untracked files:
# (use "git add <file>..." to include in what will be committed)
#
# src/webui/results/
nothing added to commit but untracked files present (use "git add" to track)
+ echo 'Setup git submodules'
Setup git submodules
+ git submodule init
+ git submodule update
+ echo 'Setup backend configuration template'
Setup backend configuration template
+ sed -i -e 's|my $hostname = .*$|my $hostname = '\''localhost'\'';|' -e 's|our $bsuser = '\''obsrun'\'';|our $bsuser = '\''jenkins'\'';|' -e 's|our $bsgroup = '\''obsrun'\'';|our $bsgroup = '\''jenkins'\'';|' src/backend/BSConfig.pm.template
+ cp src/backend/BSConfig.pm.template src/backend/BSConfig.pm
+ echo 'Set environment variables'
Set environment variables
+ export RAILS_ENV=test
+ RAILS_ENV=test
+ ret=0
+ setup_api
+ echo 'Enter API rails root'
Enter API rails root
+ cd src/api
+ echo 'Setup database configuration'
Setup database configuration
+ cp config/database.yml.example config/database.yml
+ sed -i 's|database: api|database: ci_api|' config/database.yml
+ echo 'Setup additional configuration'
Setup additional configuration
+ cp config/options.yml.example config/options.yml
+ echo 'Install missing gems locally'
Install missing gems locally
+ rm Gemfile.lock
+ bundle list
Gems included by the bundle:
* actionmailer (3.2.3)
* actionpack (3.2.3)
* activemodel (3.2.3)
* activerecord (3.2.3)
* activeresource (3.2.3)
* activesupport (3.2.3)
* arel (3.0.2)
* builder (3.0.0)
* bundler (1.1.3)
* ci_reporter (1.7.0)
* delayed_job (3.0.2)
* delayed_job_active_record (0.3.2)
* erubis (2.7.0)
* exception_notification (2.5.2)
* fast_xs (0.8.0)
* hike (1.2.1)
* i18n (0.6.0)
* journey (1.0.3)
* json (1.6.6)
* mail (2.4.4)
* memcache-client (1.8.5)
* mime-types (1.18)
* multi_json (1.2.0)
* mysql2 (0.3.11)
* nokogiri (1.5.2)
* polyglot (0.3.3)
* rack (1.4.1)
* rack-cache (1.2)
* rack-ssl (1.3.2)
* rack-test (0.6.1)
* rails (3.2.3)
* railties (3.2.3)
* rake (0.9.2.2)
* rdoc (3.12)
* simplecov (0.6.1)
* simplecov-html (0.5.3)
* simplecov-rcov (0.2.3)
* sprockets (2.1.2)
* test-unit (2.4.8)
* thor (0.14.6)
* tilt (1.3.3)
* treetop (1.4.10)
* tzinfo (0.3.32)
* xmlhash (1.2.1)
* yajl-ruby (1.1.0)
+ git diff Gemfile.lock
diff --git a/src/api/Gemfile.lock b/src/api/Gemfile.lock
index e09ea7f..77013d5 100644
--- a/src/api/Gemfile.lock
+++ b/src/api/Gemfile.lock
@@ -96,7 +96,7 @@ GEM
treetop (1.4.10)
polyglot
polyglot (>= 0.3.1)
- tzinfo (0.3.33)
+ tzinfo (0.3.32)
xmlhash (1.2.1)
yajl-ruby (1.1.0)
+ chmod a+x script/start_test_backend
+ echo 'Initialize test database, run migrations, load seed data'
Initialize test database, run migrations, load seed data
+ rake --trace db:drop db:create db:setup db:migrate
** Invoke db:drop (first_time)
** Invoke db:load_config (first_time)
** Invoke rails_env (first_time)
** Execute rails_env
** Execute db:load_config
** Execute db:drop
** Invoke db:create (first_time)
** Invoke db:load_config
** Execute db:create
** Invoke db:setup (first_time)
** Execute db:setup
** Invoke db:structure:load (first_time)
** Invoke environment (first_time)
** Execute environment
** Invoke db:load_config
** Execute db:structure:load
rake aborted!
Mysql2::Error: Key column 'hostsystem' doesn't exist in table: CREATE TABLE `repositories` (
`id` int(11) NOT NULL AUTO_INCREMENT,
`db_project_id` int(11) NOT NULL,
`name` varchar(255) DEFAULT NULL,
`remote_project_name` varchar(255) DEFAULT NULL,
`rebuild` enum('transitive','direct','local') DEFAULT NULL,
`block` enum('all','local','never') DEFAULT NULL,
`linkedbuild` enum('off','localdep','all') DEFAULT NULL,
`hostsystem_id` int(11) DEFAULT NULL,
PRIMARY KEY (`id`),
UNIQUE KEY `projects_name_index` (`db_project_id`,`name`,`remote_project_name`),
KEY `remote_project_name_index` (`remote_project_name`),
KEY `hostsystem` (`hostsystem`),
KEY `hostsystem_id` (`hostsystem_id`),
CONSTRAINT `repositories_ibfk_1` FOREIGN KEY (`db_project_id`) REFERENCES `db_projects` (`id`),
CONSTRAINT `repositories_ibfk_2` FOREIGN KEY (`hostsystem_id`) REFERENCES `repositories` (`id`)
) ENGINE=InnoDB DEFAULT CHARSET=utf8;
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:243:in `query'
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:243:in `block in execute'
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/connection_adapters/abstract_adapter.rb:280:in `block in log'
/usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.3/lib/active_support/notifications/instrumenter.rb:20:in `instrument'
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/connection_adapters/abstract_adapter.rb:275:in `log'
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:243:in `execute'
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/connection_adapters/mysql2_adapter.rb:211:in `execute'
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/railties/databases.rake:424:in `block (4 levels) in
See http://ci.opensuse.org/job/obs_master_testsuite_webui/269/changes
Changes:
[Stephan Kulow] [api] fix structure
------------------------------------------
[...truncated 35151 lines...]
/usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.3/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'
/usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.3/lib/active_support/callbacks.rb:81:in `run_callbacks'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/abstract_controller/callbacks.rb:17:in `process_action'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_controller/metal/rescue.rb:29:in `process_action'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'
/usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.3/lib/active_support/notifications.rb:123:in `block in instrument'
/usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.3/lib/active_support/notifications/instrumenter.rb:20:in `instrument'
/usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.3/lib/active_support/notifications.rb:123:in `instrument'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_controller/metal/instrumentation.rb:29:in `process_action'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_controller/metal/params_wrapper.rb:205:in `process_action'
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/railties/controller_runtime.rb:18:in `process_action'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/abstract_controller/base.rb:121:in `process'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/abstract_controller/rendering.rb:45:in `process'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_controller/metal.rb:203:in `dispatch'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_controller/metal.rb:246:in `block in action'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_dispatch/routing/route_set.rb:73:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_dispatch/routing/route_set.rb:36:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.3/lib/journey/router.rb:68:in `block in call'
/usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.3/lib/journey/router.rb:56:in `each'
/usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.3/lib/journey/router.rb:56:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_dispatch/routing/route_set.rb:600:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/etag.rb:23:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/conditionalget.rb:25:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_dispatch/middleware/head.rb:14:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_dispatch/middleware/params_parser.rb:21:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_dispatch/middleware/flash.rb:242:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:205:in `context'
/usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:200:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_dispatch/middleware/cookies.rb:338:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/query_cache.rb:64:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.3/lib/active_record/connection_adapters/abstract/connection_pool.rb:467:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
/usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.3/lib/active_support/callbacks.rb:405:in `_run__1738935445817261028__call__4403300948153389398__callbacks'
/usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.3/lib/active_support/callbacks.rb:405:in `__run_callback'
/usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.3/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'
/usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.3/lib/active_support/callbacks.rb:81:in `run_callbacks'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_dispatch/middleware/callbacks.rb:27:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.3/lib/rails/rack/logger.rb:26:in `call_app'
/usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.3/lib/rails/rack/logger.rb:16:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_dispatch/middleware/request_id.rb:22:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/methodoverride.rb:21:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/runtime.rb:17:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/lock.rb:15:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.3/lib/action_dispatch/middleware/static.rb:62:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.3/lib/rails/engine.rb:479:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.3/lib/rails/application.rb:220:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/content_length.rb:14:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.3/lib/rails/rack/log_tailer.rb:14:in `call'
/usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/handler/webrick.rb:59:in `service'
/usr/lib64/ruby/1.9.1/webrick/httpserver.rb:138:in `service'
/usr/lib64/ruby/1.9.1/webrick/httpserver.rb:94:in `run'
/usr/lib64/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread'
[[32mINFO [0m|#32393] Rendering template within layouts/application
[[32mINFO [0m|#32393] Rendering error (400)
[[0;37mDEBUG[0m|#32393] Rendered layouts/_watch_and_search (0.4ms)
[[0;37mDEBUG[0m|#32393] Rendered layouts/_header (1.1ms)
[[0;37mDEBUG[0m|#32393] Rendered layouts/_personal_navigation (0.5ms)
[[0;37mDEBUG[0m|#32393] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#32393] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#32393]
Processing UserController#logout (for 127.0.0.1 at 2012-04-16 07:45:42) [POST]
[[0;37mDEBUG[0m|#32393] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|#32393] Authenticating with iChain mode: off
[[32mINFO [0m|#32393] Authenticated request to "/user/logout" from tom
[[0;37mDEBUG[0m|#32393] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#32393] Logging out: tom
[[32mINFO [0m|#32393] Redirected to http://www.example.com/
[[0;37mDEBUG[0m|#32393] Cache delete: Person_f7754490a4ed0ddff3ff300384cc6faa
[[32mINFO [0m|#32393] Completed in 4ms (DB: 1, API: 116, XML: 0) | 302 Found [http://www.example.com/user/logout]
[[0;37mDEBUG[0m|#32393] REQUESTING PAGE: GET / with {} and HTTP headers {}
[[32mINFO [0m|#32393]
Processing MainController#index (for 127.0.0.1 at 2012-04-16 07:45:42) [GET]
[[0;37mDEBUG[0m|#32393] Setting return_to: "/"
[[0;37mDEBUG[0m|#32393] Authenticating with iChain mode: off
[[32mINFO [0m|#32393] Anonymous request to /
[[0;37mDEBUG[0m|#32393] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#32393] method_missing -to_str- nil
[[0;37mDEBUG[0m|#32393] method_missing -to_str- nil
[[0;37mDEBUG[0m|#32393] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#32393] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|#32393] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|#32393] method_missing -to_str- nil
[[0;37mDEBUG[0m|#32393] method_missing -to_str- nil
[[0;37mDEBUG[0m|#32393] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#32393] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|#32393] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[32mINFO [0m|#32393] Rendering template within layouts/application
[[32mINFO [0m|#32393] Rendering main/index
[[0;37mDEBUG[0m|#32393] Rendered main/_news (0.6ms)
[[0;37mDEBUG[0m|#32393] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|#32393] Rendered main/_latest_updates (3.7ms)
[[0;37mDEBUG[0m|#32393] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|#32393] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|#32393] Rendered main/_feeds (0.0ms)
[[0;37mDEBUG[0m|#32393] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#32393] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|#32393] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|#32393] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#32393] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#32393] Completed in 61ms (View: 10, DB: 0, API: 0, XML: 1) | 200 OK [http://www.example.com/]
[[0;37mDEBUG[0m|#32393] REQUESTING PAGE: GET /user/login with {} and HTTP headers {"HTTP_REFERER"=>"/"}
[[32mINFO [0m|#32393]
Processing UserController#login (for 127.0.0.1 at 2012-04-16 07:45:42) [GET]
[[0;37mDEBUG[0m|#32393] Setting return_to: "/user/login"
[[0;37mDEBUG[0m|#32393] Authenticating with iChain mode: off
[[32mINFO [0m|#32393] Anonymous request to /user/login
[[0;37mDEBUG[0m|#32393] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|#32393] Rendering template within layouts/application
[[32mINFO [0m|#32393] Rendering user/login
[[0;37mDEBUG[0m|#32393] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|#32393] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|#32393] Rendered layouts/_personal_navigation (0.6ms)
[[0;37mDEBUG[0m|#32393] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#32393] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#32393] Completed in 9ms (View: 5, DB: 1, API: 0, XML: 0) | 200 OK [http://www.example.com/user/login]
[[0;37mDEBUG[0m|#32393] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/login"}
[[32mINFO [0m|#32393]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-16 07:45:42) [POST]
[[32mINFO [0m|#32393] Parameters: {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"}
[[0;37mDEBUG[0m|#32393] Setting return_to: "/"
[[0;37mDEBUG[0m|#32393] Authenticating with iChain mode: off
[[32mINFO [0m|#32393] Anonymous request to /
[[0;37mDEBUG[0m|#32393] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#32393] Doing form authorization to login user king
[[0;37mDEBUG[0m|#32393] Person.find( nil, king)
[[0;37mDEBUG[0m|#32393] [REST] find( Person, [{:login=>"king"}] )
[[0;37mDEBUG[0m|#32393] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#32393] Request took 0.014446805 seconds
[[32mINFO [0m|#32393] Redirected to http://www.example.com/
[[32mINFO [0m|#32393] Completed in 19ms (DB: 0, API: 15, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|#32393] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|#32393]
Processing MainController#index (for 127.0.0.1 at 2012-04-16 07:45:42) [GET]
[[0;37mDEBUG[0m|#32393] Setting return_to: "/"
[[0;37mDEBUG[0m|#32393] Authenticating with iChain mode: off
[[32mINFO [0m|#32393] Authenticated request to "/" from king
[[0;37mDEBUG[0m|#32393] Cache delete: Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#32393] method_missing -to_str- nil
[[0;37mDEBUG[0m|#32393] method_missing -to_str- nil
[[0;37mDEBUG[0m|#32393] method_missing -to_ary- nil
[[0;37mDEBUG[0m|#32393] Person.find( 1800 seconds, king, {:is_current=>true})
[[0;37mDEBUG[0m|#32393] Cache miss: Person_31bc44caa5b35568da22b828c6853364 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#32393] [REST] find( Person, [{:is_current=>"true", :login=>"king"}] )
[[0;37mDEBUG[0m|#32393] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|#32393] Request took 0.053738365 seconds
[[0;37mDEBUG[0m|#32393] Cache write (will save 54.29ms): Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|#32393] Cache miss: king_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#32393] Fetching request list from api
[[0;37mDEBUG[0m|#32393] --> direct_http url: #
See http://ci.opensuse.org/job/obs_master_testsuite_webui/270/changes
Changes:
[Stephan Kulow] [api] fix the hostsystem test case
------------------------------------------
[...truncated 93534 lines...]
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|# 7342] Completed in 126ms (View: 6, DB: 0, API: 115, XML: 0) | 200 OK [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|# 7342] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|# 7342]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-16 09:43:28) [POST]
[[32mINFO [0m|# 7342] Parameters: {"return_to_path"=>"/", "username"=>"tom", "password"=>"thunder", "commit"=>"Login"}
[[0;37mDEBUG[0m|# 7342] Setting return_to: "/"
[[0;37mDEBUG[0m|# 7342] Authenticating with iChain mode: off
[[32mINFO [0m|# 7342] Anonymous request to /
[[0;37mDEBUG[0m|# 7342] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7342] Doing form authorization to login user tom
[[0;37mDEBUG[0m|# 7342] Person.find( nil, tom)
[[0;37mDEBUG[0m|# 7342] [REST] find( Person, [{:login=>"tom"}] )
[[0;37mDEBUG[0m|# 7342] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|# 7342] Request took 0.047220251 seconds
[[32mINFO [0m|# 7342] Redirected to http://www.example.com/
[[32mINFO [0m|# 7342] Completed in 52ms (DB: 1, API: 47, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|# 7342] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|# 7342]
Processing MainController#index (for 127.0.0.1 at 2012-04-16 09:43:28) [GET]
[[0;37mDEBUG[0m|# 7342] Setting return_to: "/"
[[0;37mDEBUG[0m|# 7342] Authenticating with iChain mode: off
[[32mINFO [0m|# 7342] Authenticated request to "/" from tom
[[0;37mDEBUG[0m|# 7342] Cache delete: Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|# 7342] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7342] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7342] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7342] Person.find( 1800 seconds, tom, {:is_current=>true})
[[0;37mDEBUG[0m|# 7342] Cache miss: Person_bcccc817286c9705b67feb0c7cc953c3 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7342] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|# 7342] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|# 7342] Request took 0.052737156 seconds
[[0;37mDEBUG[0m|# 7342] Cache write (will save 53.24ms): Person_bcccc817286c9705b67feb0c7cc953c3
[[0;37mDEBUG[0m|# 7342] Cache hit: tom_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|# 7342] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7342] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7342] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7342] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7342] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|# 7342] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|# 7342] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7342] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7342] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7342] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|# 7342] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[0;37mDEBUG[0m|# 7342] Project.find( 1800 seconds, home:tom)
[[0;37mDEBUG[0m|# 7342] Cache hit: Project_adb9094ae2c389898fafee54514195c4 ({:expires_in=>1800 seconds})
[[32mINFO [0m|# 7342] Rendering template within layouts/application
[[32mINFO [0m|# 7342] Rendering main/index
[[0;37mDEBUG[0m|# 7342] Rendered main/_news (0.6ms)
[[0;37mDEBUG[0m|# 7342] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|# 7342] Rendered main/_latest_updates (3.8ms)
[[0;37mDEBUG[0m|# 7342] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|# 7342] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|# 7342] Rendered main/_feeds (0.1ms)
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_watch_and_search (0.4ms)
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_header (0.9ms)
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_personal_navigation (0.4ms)
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|# 7342] Completed in 73ms (View: 12, DB: 0, API: 53, XML: 1) | 200 OK [http://www.example.com/]
[[32mINFO [0m|# 7342]
Processing UserController#logout (for 127.0.0.1 at 2012-04-16 09:43:28) [POST]
[[0;37mDEBUG[0m|# 7342] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|# 7342] Authenticating with iChain mode: off
[[32mINFO [0m|# 7342] Authenticated request to "/user/logout" from tom
[[0;37mDEBUG[0m|# 7342] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|# 7342] Logging out: tom
[[32mINFO [0m|# 7342] Redirected to http://www.example.com/
[[0;37mDEBUG[0m|# 7342] Cache delete: Person_f7754490a4ed0ddff3ff300384cc6faa
[[32mINFO [0m|# 7342] Completed in 4ms (DB: 1, API: 0, XML: 0) | 302 Found [http://www.example.com/user/logout]
[[0;37mDEBUG[0m|# 7342] REQUESTING PAGE: GET / with {} and HTTP headers {}
[[32mINFO [0m|# 7342]
Processing MainController#index (for 127.0.0.1 at 2012-04-16 09:43:28) [GET]
[[0;37mDEBUG[0m|# 7342] Setting return_to: "/"
[[0;37mDEBUG[0m|# 7342] Authenticating with iChain mode: off
[[32mINFO [0m|# 7342] Anonymous request to /
[[0;37mDEBUG[0m|# 7342] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7342] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7342] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7342] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7342] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5})
[[0;37mDEBUG[0m|# 7342] Cache hit: Statusmessage_5b45803e997cc5915704d09b14da5b4f ({:expires_in=>900 seconds})
[[0;37mDEBUG[0m|# 7342] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7342] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7342] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7342] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true})
[[0;37mDEBUG[0m|# 7342] Cache hit: LatestUpdated_3af9e60d376285bdcc1ba8f465ef1f8e ({:expires_in=>300 seconds})
[[32mINFO [0m|# 7342] Rendering template within layouts/application
[[32mINFO [0m|# 7342] Rendering main/index
[[0;37mDEBUG[0m|# 7342] Rendered main/_news (0.5ms)
[[0;37mDEBUG[0m|# 7342] Rendered main/_contact (0.1ms)
[[0;37mDEBUG[0m|# 7342] Rendered main/_latest_updates (3.7ms)
[[0;37mDEBUG[0m|# 7342] Rendered main/_countdown (0.1ms)
[[0;37mDEBUG[0m|# 7342] Rendered main/_sponsors (0.1ms)
[[0;37mDEBUG[0m|# 7342] Rendered main/_feeds (0.1ms)
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_personal_navigation (0.7ms)
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|# 7342] Completed in 16ms (View: 10, DB: 1, API: 0, XML: 1) | 200 OK [http://www.example.com/]
[[0;37mDEBUG[0m|# 7342] REQUESTING PAGE: GET /user/login with {} and HTTP headers {"HTTP_REFERER"=>"/"}
[[32mINFO [0m|# 7342]
Processing UserController#login (for 127.0.0.1 at 2012-04-16 09:43:28) [GET]
[[0;37mDEBUG[0m|# 7342] Setting return_to: "/user/login"
[[0;37mDEBUG[0m|# 7342] Authenticating with iChain mode: off
[[32mINFO [0m|# 7342] Anonymous request to /user/login
[[0;37mDEBUG[0m|# 7342] Cache hit: configuration ({:expires_in=>1800 seconds})
[[32mINFO [0m|# 7342] Rendering template within layouts/application
[[32mINFO [0m|# 7342] Rendering user/login
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_watch_and_search (0.3ms)
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_header (0.7ms)
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_personal_navigation (0.6ms)
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|# 7342] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|# 7342] Completed in 8ms (View: 4, DB: 1, API: 0, XML: 0) | 200 OK [http://www.example.com/user/login]
[[0;37mDEBUG[0m|# 7342] REQUESTING PAGE: POST /user/do_login with {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"} and HTTP headers {"HTTP_REFERER"=>"/user/login"}
[[32mINFO [0m|# 7342]
Processing UserController#do_login (for 127.0.0.1 at 2012-04-16 09:43:28) [POST]
[[32mINFO [0m|# 7342] Parameters: {"return_to_path"=>"/", "username"=>"king", "password"=>"sunflower", "commit"=>"Login"}
[[0;37mDEBUG[0m|# 7342] Setting return_to: "/"
[[0;37mDEBUG[0m|# 7342] Authenticating with iChain mode: off
[[32mINFO [0m|# 7342] Anonymous request to /
[[0;37mDEBUG[0m|# 7342] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7342] Doing form authorization to login user king
[[0;37mDEBUG[0m|# 7342] Person.find( nil, king)
[[0;37mDEBUG[0m|# 7342] [REST] find( Person, [{:login=>"king"}] )
[[0;37mDEBUG[0m|# 7342] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|# 7342] Request took 0.01364797 seconds
[[32mINFO [0m|# 7342] Redirected to http://www.example.com/
[[32mINFO [0m|# 7342] Completed in 18ms (DB: 0, API: 14, XML: 0) | 302 Found [http://www.example.com/user/do_login]
[[0;37mDEBUG[0m|# 7342] REQUESTING PAGE: GET http://www.example.com/ with {} and HTTP headers {"HTTP_REFERER"=>"/user/do_login"}
[[32mINFO [0m|# 7342]
Processing MainController#index (for 127.0.0.1 at 2012-04-16 09:43:28) [GET]
[[0;37mDEBUG[0m|# 7342] Setting return_to: "/"
[[0;37mDEBUG[0m|# 7342] Authenticating with iChain mode: off
[[32mINFO [0m|# 7342] Authenticated request to "/" from king
[[0;37mDEBUG[0m|# 7342] Cache delete: Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|# 7342] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7342] method_missing -to_str- nil
[[0;37mDEBUG[0m|# 7342] method_missing -to_ary- nil
[[0;37mDEBUG[0m|# 7342] Person.find( 1800 seconds, king, {:is_current=>true})
[[0;37mDEBUG[0m|# 7342] Cache miss: Person_31bc44caa5b35568da22b828c6853364 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|# 7342] [REST] find( Person, [{:is_current=>"true", :login=>"king"}] )
[[0;37mDEBUG[0m|# 7342] http_do #1: method: get url: http://localhost:3203/person/king
[[0;37mDEBUG[0m|# 7342] Request took 0.014500436 seconds
[[0;37mDEBUG[0m|# 7342] Cache write (will save 15.04ms): Person_31bc44caa5b35568da22b828c6853364
[[0;37mDEBUG[0m|# 7342] Cache miss: king_requests_that_need_work ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|# 7342] Fetching request list from api
[[0;37mDEBUG[0m|# 7342] --> direct_http url: #
See http://ci.opensuse.org/job/obs_master_testsuite_webui/271/changes -- To unsubscribe, e-mail: obs-tests+unsubscribe@opensuse.org To contact the owner, e-mail: obs-tests+owner@opensuse.org
participants (1)
-
admin＠opensuse.org