See <http://ci.opensuse.org/job/obs_master_testsuite_webui/869/changes> Changes: [mls] [backend] do not look at jobs already in progress, add support for $BSConfig::signer_maxchild_flavor ------------------------------------------ [...truncated 571388 lines...] [[32mINFO [0m|#23878] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#23878] Completed 200 OK in 206ms (Views: 15.4ms | ActiveRecord: 0.0ms | API: 184.6ms (XML: 0.0ms , View: 1.9ms , Backend: 0.0ms , DB: 10.7ms , HTTP: 84.7ms) | XML: 0.7ms) [[32mINFO [0m|#23878] Started GET "/project/buildresult?project=BaseDistro&index=" for 127.0.0.1 at 2013-03-21 16:20:19 +0000 [[32mINFO [0m|#23878] Processing by ProjectController#buildresult as */* [[32mINFO [0m|#23878] Parameters: {"project"=>"BaseDistro", "index"=>""} [[0;37mDEBUG[0m|#23878] Setting return_to: "/project/buildresult?project=BaseDistro&index=" [[0;37mDEBUG[0m|#23878] Authenticating with iChain mode: off [[32mINFO [0m|#23878] Authenticated request to "/project/buildresult?project=BaseDistro&index=" from tom [[0;37mDEBUG[0m|#23878] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#23878] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#23878] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#23878] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#23878] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#23878] RT http://localhost:3203/person/tom {"view"=>0.487458, "db"=>1.627763, "backend"=>0, "xml"=>0, :runtime=>12.853, :all=>16.65597} [[0;37mDEBUG[0m|#23878] returning [{:name=>"BaseDistro"}] from rails cache Project_f726314c8d941efeb9d0b888fe97b37e [[0;37mDEBUG[0m|#23878] free_cache [{:project=>"BaseDistro", :view=>"summary"}] Buildresult_81f2400bb79dc620ddf8b0bf87e74935 [[0;37mDEBUG[0m|#23878] [REST] find( Buildresult, [{:project=>"BaseDistro", :view=>"summary"}] ) [[0;37mDEBUG[0m|#23878] params {:project=>"BaseDistro", :view=>"summary"} [[0;37mDEBUG[0m|#23878] uri is: http://localhost:3203/build/:project/_result?:view&:package&:code&:lastbuild... [[0;37mDEBUG[0m|#23878] http_do #1: method: get url: http://localhost:3203/build/BaseDistro/_result?view=summary [[0;37mDEBUG[0m|#23878] RT http://localhost:3203/build/BaseDistro/_result?view=summary {"view"=>nil, "db"=>0.5935590000000001, "backend"=>0, "xml"=>0, :runtime=>24.595, :all=>63.607776} [[32mINFO [0m|#23878] Rendered project/_buildstatus.html.erb (0.1ms) [[32mINFO [0m|#23878] Completed 200 OK in 84ms (Views: 0.8ms | ActiveRecord: 0.0ms | API: 80.3ms (XML: 0.0ms , View: 0.5ms , Backend: 0.0ms , DB: 2.2ms , HTTP: 42.8ms) | XML: 0.2ms) [[32mINFO [0m|#23878] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2013-03-21 16:20:19 +0000 [[32mINFO [0m|#23878] Processing by ProjectController#show as HTML [[32mINFO [0m|#23878] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|#23878] Setting return_to: "/project/show?project=My%3AMaintenance" [[0;37mDEBUG[0m|#23878] Authenticating with iChain mode: off [[32mINFO [0m|#23878] Authenticated request to "/project/show?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|#23878] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#23878] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#23878] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#23878] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#23878] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#23878] RT http://localhost:3203/person/tom {"view"=>0.437301, "db"=>1.2925570000000002, "backend"=>0, "xml"=>0, :runtime=>11.793, :all=>53.273854} [[0;37mDEBUG[0m|#23878] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#23878] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.239321, "db"=>3.5360990000000005, "backend"=>0, "xml"=>0, :runtime=>26.693, :all=>67.265332} [[0;37mDEBUG[0m|#23878] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#23878] http_do #1: method: get url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance [[0;37mDEBUG[0m|#23878] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.45186899999999997, "db"=>3.5222720000000005, "backend"=>0, "xml"=>0, :runtime=>36.613, :all=>79.468615} [[0;37mDEBUG[0m|#23878] data { "name": "My:Maintenance", "packages": [ ], "xml": "<project name=\"My:Maintenance\" kind=\"maintenance\">\n <title>official maintenance space</title>\n <description>official maintenance space, maintained by maintenance ;)</description>\n <person userid=\"maintenance_coord\" role=\"maintainer\"/>\n <maintenance>\n <maintains project=\"BaseDistro\"/>\n <maintains project=\"BaseDistro2.0:LinkedUpdateProject\"/>\n <maintains project=\"BaseDistro3\"/>\n </maintenance>\n</project>\n", "incidents": [ ], "maintained_projects": [ "BaseDistro", "BaseDistro2.0:LinkedUpdateProject", "BaseDistro3" ], "linking_projects": [ ], "requests": [ ], "nr_of_problem_packages": 0 } [[0;37mDEBUG[0m|#23878] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02 [[32mINFO [0m|#23878] Rendered project/_tabs.html.erb (2.9ms) [[32mINFO [0m|#23878] Rendered shared/_open_requests.html.erb (0.1ms) [[32mINFO [0m|#23878] Rendered layouts/_watch_and_search.html.erb (1.7ms) [[32mINFO [0m|#23878] Rendered layouts/_header.html.erb (2.8ms) [[32mINFO [0m|#23878] Rendered layouts/_personal_navigation.html.erb (1.0ms) [[32mINFO [0m|#23878] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#23878] Completed 200 OK in 220ms (Views: 15.6ms | ActiveRecord: 0.0ms | API: 200.0ms (XML: 0.0ms , View: 1.1ms , Backend: 0.0ms , DB: 8.4ms , HTTP: 124.9ms) | XML: 0.6ms) [[32mINFO [0m|#23878] Started GET "/project/toggle_watch?project=My%3AMaintenance" for 127.0.0.1 at 2013-03-21 16:20:20 +0000 [[32mINFO [0m|#23878] Processing by ProjectController#toggle_watch as HTML [[32mINFO [0m|#23878] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|#23878] Setting return_to: "/project/toggle_watch?project=My%3AMaintenance" [[0;37mDEBUG[0m|#23878] Authenticating with iChain mode: off [[32mINFO [0m|#23878] Authenticated request to "/project/toggle_watch?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|#23878] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#23878] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#23878] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#23878] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#23878] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#23878] RT http://localhost:3203/person/tom {"view"=>0.657556, "db"=>1.403236, "backend"=>0, "xml"=>0, :runtime=>13.056, :all=>16.42176} [[0;37mDEBUG[0m|#23878] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#23878] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.271752, "db"=>4.551113, "backend"=>0, "xml"=>0, :runtime=>36.161, :all=>75.61182199999999} [[0;37mDEBUG[0m|#23878] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#23878] returning [{:name=>"My:Maintenance"}] from rails cache Project_ce8cfb5ef2a35543461dbbb6b22579cb [[0;37mDEBUG[0m|#23878] Remove My:Maintenance from watchlist for tom [[0;37mDEBUG[0m|#23878] user 'tom' removes project 'My:Maintenance' from watchlist [[0;37mDEBUG[0m|#23878] saving object Person ({:is_current=>"true", :login=>"tom"}) to api: <person> <login>tom</login> <email>tschmidt@suse.de</email> <realname>Thor</realname> <state>confirmed</state> <watchlist> </watchlist> </person> [[0;37mDEBUG[0m|#23878] http_do #1: method: put url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#23878] RT http://localhost:3203/person/tom {"view"=>0.981099, "db"=>2.148857, "backend"=>0, "xml"=>0, :runtime=>17.182, :all=>106.075722} [[0;37mDEBUG[0m|#23878] [REST] find( PersonGroup, [{:login=>"tom"}] ) [[0;37mDEBUG[0m|#23878] params {:login=>"tom"} [[0;37mDEBUG[0m|#23878] uri is: http://localhost:3203/person/:login/group [[0;37mDEBUG[0m|#23878] http_do #1: method: get url: http://localhost:3203/person/tom/group [[0;37mDEBUG[0m|#23878] RT http://localhost:3203/person/tom/group {"view"=>0.429692, "db"=>0.767435, "backend"=>0, "xml"=>0, :runtime=>7.749, :all=>16.304223} [[0;37mDEBUG[0m|#23878] free_cache [:id, {:what=>"project", :predicate=>"person/@userid='tom'"}] Collection_8c1f4f93580a7c15e1f5fefa4bf90a08 [[0;37mDEBUG[0m|#23878] free_cache [:id, {:what=>"package", :predicate=>"person/@userid='tom'"}] Collection_09c0d884aeab5856e9b6a54d49db8a54 [[32mINFO [0m|#23878] Redirected to http://127.0.0.1:60782/project/show?project=My%3AMaintenance [[32mINFO [0m|#23878] Completed 302 Found in 219ms (ActiveRecord: 0.0ms | API: 214.4ms (XML: 0.0ms , View: 2.3ms , Backend: 0.0ms , DB: 8.9ms , HTTP: 140.3ms) | XML: 0.3ms) [[32mINFO [0m|#23878] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2013-03-21 16:20:20 +0000 [[32mINFO [0m|#23878] Processing by ProjectController#show as HTML [[32mINFO [0m|#23878] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|#23878] Setting return_to: "/project/show?project=My%3AMaintenance" [[0;37mDEBUG[0m|#23878] Authenticating with iChain mode: off [[32mINFO [0m|#23878] Authenticated request to "/project/show?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|#23878] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#23878] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#23878] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#23878] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#23878] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#23878] RT http://localhost:3203/person/tom {"view"=>0.508947, "db"=>1.021976, "backend"=>0, "xml"=>0, :runtime=>8.782, :all=>50.603131000000005} [[0;37mDEBUG[0m|#23878] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#23878] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.288428, "db"=>4.386502, "backend"=>0, "xml"=>0, :runtime=>31.995, :all=>71.080591} [[0;37mDEBUG[0m|#23878] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#23878] http_do #1: method: get url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance [[0;37mDEBUG[0m|#23878] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.435045, "db"=>3.8064199999999997, "backend"=>0, "xml"=>0, :runtime=>39.561, :all=>79.32193} [[0;37mDEBUG[0m|#23878] data { "name": "My:Maintenance", "packages": [ ], "xml": "<project name=\"My:Maintenance\" kind=\"maintenance\">\n <title>official maintenance space</title>\n <description>official maintenance space, maintained by maintenance ;)</description>\n <person userid=\"maintenance_coord\" role=\"maintainer\"/>\n <maintenance>\n <maintains project=\"BaseDistro\"/>\n <maintains project=\"BaseDistro2.0:LinkedUpdateProject\"/>\n <maintains project=\"BaseDistro3\"/>\n </maintenance>\n</project>\n", "incidents": [ ], "maintained_projects": [ "BaseDistro", "BaseDistro2.0:LinkedUpdateProject", "BaseDistro3" ], "linking_projects": [ ], "requests": [ ], "nr_of_problem_packages": 0 } [[0;37mDEBUG[0m|#23878] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02 [[32mINFO [0m|#23878] Rendered project/_tabs.html.erb (2.2ms) [[32mINFO [0m|#23878] Rendered shared/_open_requests.html.erb (0.0ms) [[32mINFO [0m|#23878] Rendered layouts/_watch_and_search.html.erb (0.9ms) [[32mINFO [0m|#23878] Rendered layouts/_header.html.erb (1.7ms) [[32mINFO [0m|#23878] Rendered layouts/_personal_navigation.html.erb (1.1ms) [[32mINFO [0m|#23878] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#23878] Completed 200 OK in 271ms (Views: 11.9ms | ActiveRecord: 0.0ms | API: 201.0ms (XML: 0.0ms , View: 1.2ms , Backend: 0.0ms , DB: 9.2ms , HTTP: 120.7ms) | XML: 0.5ms) [[32mINFO [0m|#23878] Started POST "/user/logout" for 127.0.0.1 at 2013-03-21 16:20:20 +0000 [[32mINFO [0m|#23878] Processing by UserController#logout as HTML [[0;37mDEBUG[0m|#23878] Setting return_to: "/user/logout" [[0;37mDEBUG[0m|#23878] Authenticating with iChain mode: off [[32mINFO [0m|#23878] Authenticated request to "/user/logout" from tom [[32mINFO [0m|#23878] Logging out: tom [[32mINFO [0m|#23878] Redirected to http://127.0.0.1:60782/ [[0;37mDEBUG[0m|#23878] free_cache [{}] Person_f7754490a4ed0ddff3ff300384cc6faa [[32mINFO [0m|#23878] Completed 302 Found in 10ms (ActiveRecord: 8.3ms | XML: 0.0ms) [[32mINFO [0m|#23878] Started GET "/" for 127.0.0.1 at 2013-03-21 16:20:20 +0000 [[32mINFO [0m|#23878] Processing by MainController#index as HTML [[0;37mDEBUG[0m|#23878] Setting return_to: "/" [[0;37mDEBUG[0m|#23878] Authenticating with iChain mode: off [[32mINFO [0m|#23878] Anonymous request to / [[0;37mDEBUG[0m|#23878] free_cache [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"}] Statusmessage_753097322d9cc873836d791589547e90 [[0;37mDEBUG[0m|#23878] [REST] find( Statusmessage, [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"}] ) [[0;37mDEBUG[0m|#23878] params {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"} [[0;37mDEBUG[0m|#23878] uri is: http://localhost:3203/status/messages/:id/?:limit [[0;37mDEBUG[0m|#23878] http_do #1: method: get url: http://localhost:3203/status/messages/?limit=5 [[0;37mDEBUG[0m|#23878] RT http://localhost:3203/status/messages/?limit=5 {"view"=>0.792212, "db"=>0.546617, "backend"=>0, "xml"=>0, :runtime=>6.207, :all=>9.978299} [[0;37mDEBUG[0m|#23878] free_cache [{:limit=>"6", :shared=>"true"}] LatestUpdated_ee34770dc62e51dca62baed2d5258b33 [[0;37mDEBUG[0m|#23878] [REST] find( LatestUpdated, [{:limit=>"6", :shared=>"true"}] ) [[0;37mDEBUG[0m|#23878] params {:limit=>"6", :shared=>"true"} [[0;37mDEBUG[0m|#23878] uri is: http://localhost:3203/statistics/latest_updated?:limit [[0;37mDEBUG[0m|#23878] http_do #1: method: get url: http://localhost:3203/statistics/latest_updated?limit=6 [[0;37mDEBUG[0m|#23878] RT http://localhost:3203/statistics/latest_updated?limit=6 {"view"=>1.216925, "db"=>2.006536, "backend"=>0, "xml"=>0, :runtime=>16.66, :all=>57.767906} [[32mINFO [0m|#23878] Rendered shared/_sign_up.html.erb (1.2ms) [[32mINFO [0m|#23878] Rendered main/_news.html.erb (0.5ms) [[32mINFO [0m|#23878] Rendered main/_latest_updates.erb (4.1ms) [[32mINFO [0m|#23878] Rendered main/_countdown.html.erb (0.0ms) [[32mINFO [0m|#23878] Rendered main/_sponsors.html.erb (0.0ms) [[32mINFO [0m|#23878] Rendered main/_feeds.html.erb (0.1ms) [[32mINFO [0m|#23878] Rendered layouts/_watch_and_search.html.erb (0.6ms) [[32mINFO [0m|#23878] Rendered layouts/_header.html.erb (1.1ms) [[32mINFO [0m|#23878] Rendered layouts/_personal_navigation.html.erb (1.3ms) [[32mINFO [0m|#23878] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#23878] Completed 200 OK in 88ms (Views: 16.3ms | ActiveRecord: 0.0ms | API: 67.7ms (XML: 0.0ms , View: 2.0ms , Backend: 0.0ms , DB: 2.6ms , HTTP: 44.9ms) | XML: 1.0ms) [[32mINFO [0m|#23878] Started GET "/main/systemstatus" for 127.0.0.1 at 2013-03-21 16:20:21 +0000 [[32mINFO [0m|#23878] Processing by MainController#systemstatus as */* [[0;37mDEBUG[0m|#23878] Setting return_to: "/main/systemstatus" [[0;37mDEBUG[0m|#23878] Authenticating with iChain mode: off [[32mINFO [0m|#23878] Anonymous request to /main/systemstatus [[0;37mDEBUG[0m|#23878] returning [{:shared=>"true"}] from rails cache GlobalCounters_2899b016f456189a42bc9c5c8fc7013a [[0;37mDEBUG[0m|#23878] returning [:available] from rails cache Architecture_82f3aea44c00f161487e3359eb33cff4 [[32mINFO [0m|#23878] Rendered main/_systemstatus.html.erb (1.2ms) [[32mINFO [0m|#23878] Completed 200 OK in 3ms (Views: 1.7ms | ActiveRecord: 0.0ms | XML: 0.3ms) [[0;37mDEBUG[0m|#23878] http_do #1: method: post url: http://localhost:3203/test/test_end [[0;37mDEBUG[0m|#23878] RT http://localhost:3203/test/test_end {"view"=>1.02176, "db"=>23.410975, "backend"=>0, "xml"=>0, :runtime=>28.951999999999998, :all=>32.559174000000006} + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/> + egrep 'perl|ruby' + 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/scheduler.done src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/capybara 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