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: #<URI::Generic:0x00000001ec7970 URL:http://localhost:3203/request?view=collection&states=declined&roles=creator&user=king> [[0;37mDEBUG[0m|# 7073] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=declined&roles=... [[0;37mDEBUG[0m|# 7073] Request took 0.065835324 seconds [[0;37mDEBUG[0m|# 7073] Fetching request list from api [[0;37mDEBUG[0m|# 7073] --> direct_http url: #<URI::Generic:0x00000001ef22b0 URL:http://localhost:3203/request?view=collection&states=review&roles=reviewer&reviewstates=new&user=king> [[0;37mDEBUG[0m|# 7073] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=review&roles=re... [[0;37mDEBUG[0m|# 7073] Request took 0.071313771 seconds [[0;37mDEBUG[0m|# 7073] Fetching request list from api [[0;37mDEBUG[0m|# 7073] --> direct_http url: #<URI::Generic:0x00000001f1dfa0 URL:http://localhost:3203/request?view=collection&states=new&roles=maintainer&user=king> [[0;37mDEBUG[0m|# 7073] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=new&roles=maint... [[0;37mDEBUG[0m|# 7073] Request took 0.07144054 seconds [[0;37mDEBUG[0m|# 7073] Cache write (will save 210.89ms): king_requests_that_need_work [[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:king) [[0;37mDEBUG[0m|# 7073] Cache miss: Project_a5d9fe90affee5686502abd47dc142fa ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|# 7073] [REST] find( Project, [{:name=>"home:king"}] ) [[0;37mDEBUG[0m|# 7073] http_do #1: method: get url: http://localhost:3203/source/home:king/_meta? [[0;37mDEBUG[0m|# 7073] Request took 0.049030628 seconds [[0;37mDEBUG[0m|# 7073] Project.find( {:name=>"home:king"} ) did not find anything, return nil [[0;37mDEBUG[0m|# 7073] method_missing -to_str- nil [[32mINFO [0m|# 7073] Redirected to http://www.example.com/configuration/connect_instance [[32mINFO [0m|# 7073] Completed in 282ms (DB: 3, API: 272, XML: 0) | 302 Found [http://www.example.com/] [[0;37mDEBUG[0m|# 7073] REQUESTING PAGE: GET http://www.example.com/configuration/connect_instance with {} and HTTP headers {"HTTP_REFERER"=>"http://www.example.com/"} [[32mINFO [0m|# 7073] Processing ConfigurationController#connect_instance (for 127.0.0.1 at 2012-04-12 11:13:59) [GET] [[0;37mDEBUG[0m|# 7073] Setting return_to: "/configuration/connect_instance" [[0;37mDEBUG[0m|# 7073] Authenticating with iChain mode: off [[32mINFO [0m|# 7073] Authenticated request to "/configuration/connect_instance" 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.050937497 seconds [[0;37mDEBUG[0m|# 7073] Cache write (will save 51.37ms): Person_31bc44caa5b35568da22b828c6853364 [[0;37mDEBUG[0m|# 7073] Cache hit: king_requests_that_need_work ({:expires_in=>600 seconds}) [[0;37mDEBUG[0m|# 7073] Cache hit: configuration ({:expires_in=>1800 seconds}) [[32mINFO [0m|# 7073] Rendering template within layouts/application [[32mINFO [0m|# 7073] Rendering configuration/connect_instance [[0;37mDEBUG[0m|# 7073] Rendered layouts/_watch_and_search (0.4ms) [[0;37mDEBUG[0m|# 7073] Rendered layouts/_header (1.0ms) [[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 65ms (View: 6, DB: 0, API: 51, XML: 0) | 200 OK [http://www.example.com/configuration/connect_instance] [[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 king [[0;37mDEBUG[0m|# 7073] Cache hit: configuration ({:expires_in=>1800 seconds}) [[32mINFO [0m|# 7073] Logging out: king [[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] + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/ + egrep 'perl|ruby' USER PID ACCESS COMMAND http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/: jenkins ..c.. sh jenkins ..c.. sh jenkins ..c.. egrep + echo 'Remove log/tmp files to save disc space' Remove log/tmp files to save disc space + rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets + exit 1 Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe@opensuse.org To contact the owner, e-mail: obs-tests+owner@opensuse.org