See <http://ci.opensuse.org/job/obs_master_testsuite_webui/872/changes> Changes: [mls] [backend] tweak metacache merging a bit ------------------------------------------ [...truncated 571229 lines...] [[32mINFO [0m|#12434] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#12434] Completed 200 OK in 181ms (Views: 16.4ms | ActiveRecord: 0.0ms | API: 160.2ms (XML: 0.0ms , View: 1.2ms , Backend: 0.0ms , DB: 7.6ms , HTTP: 85.4ms) | XML: 0.6ms) [[32mINFO [0m|#12434] Started GET "/project/buildresult?project=BaseDistro&index=" for 127.0.0.1 at 2013-03-25 17:16:18 +0000 [[32mINFO [0m|#12434] Processing by ProjectController#buildresult as */* [[32mINFO [0m|#12434] Parameters: {"project"=>"BaseDistro", "index"=>""} [[0;37mDEBUG[0m|#12434] Setting return_to: "/project/buildresult?project=BaseDistro&index=" [[0;37mDEBUG[0m|#12434] Authenticating with iChain mode: off [[32mINFO [0m|#12434] Authenticated request to "/project/buildresult?project=BaseDistro&index=" from tom [[0;37mDEBUG[0m|#12434] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#12434] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#12434] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#12434] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#12434] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#12434] RT http://localhost:3203/person/tom {"view"=>0.464947, "db"=>1.8205149999999999, "backend"=>0, "xml"=>0, :runtime=>12.616999999999999, :all=>15.840736000000001} [[0;37mDEBUG[0m|#12434] returning [{:name=>"BaseDistro"}] from rails cache Project_f726314c8d941efeb9d0b888fe97b37e [[0;37mDEBUG[0m|#12434] free_cache [{:project=>"BaseDistro", :view=>"summary"}] Buildresult_81f2400bb79dc620ddf8b0bf87e74935 [[0;37mDEBUG[0m|#12434] [REST] find( Buildresult, [{:project=>"BaseDistro", :view=>"summary"}] ) [[0;37mDEBUG[0m|#12434] params {:project=>"BaseDistro", :view=>"summary"} [[0;37mDEBUG[0m|#12434] uri is: http://localhost:3203/build/:project/_result?:view&:package&:code&:lastbuild... [[0;37mDEBUG[0m|#12434] http_do #1: method: get url: http://localhost:3203/build/BaseDistro/_result?view=summary [[0;37mDEBUG[0m|#12434] RT http://localhost:3203/build/BaseDistro/_result?view=summary {"view"=>nil, "db"=>0.7186450000000001, "backend"=>0, "xml"=>0, :runtime=>22.221, :all=>64.38240300000001} [[32mINFO [0m|#12434] Rendered project/_buildstatus.html.erb (0.1ms) [[32mINFO [0m|#12434] Completed 200 OK in 83ms (Views: 0.6ms | ActiveRecord: 0.0ms | API: 80.2ms (XML: 0.0ms , View: 0.5ms , Backend: 0.0ms , DB: 2.5ms , HTTP: 45.4ms) | XML: 0.2ms) [[32mINFO [0m|#12434] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2013-03-25 17:16:18 +0000 [[32mINFO [0m|#12434] Processing by ProjectController#show as HTML [[32mINFO [0m|#12434] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|#12434] Setting return_to: "/project/show?project=My%3AMaintenance" [[0;37mDEBUG[0m|#12434] Authenticating with iChain mode: off [[32mINFO [0m|#12434] Authenticated request to "/project/show?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|#12434] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#12434] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#12434] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#12434] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#12434] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#12434] RT http://localhost:3203/person/tom {"view"=>0.525118, "db"=>1.6442890000000001, "backend"=>0, "xml"=>0, :runtime=>63.957, :all=>103.37375999999999} [[0;37mDEBUG[0m|#12434] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#12434] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.26844999999999997, "db"=>4.259618, "backend"=>0, "xml"=>0, :runtime=>36.888, :all=>75.149785} [[0;37mDEBUG[0m|#12434] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#12434] http_do #1: method: get url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance [[0;37mDEBUG[0m|#12434] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.440004, "db"=>3.499005, "backend"=>0, "xml"=>0, :runtime=>34.675, :all=>75.41072799999999} [[0;37mDEBUG[0m|#12434] 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|#12434] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02 [[32mINFO [0m|#12434] Rendered project/_tabs.html.erb (3.1ms) [[32mINFO [0m|#12434] Rendered shared/_open_requests.html.erb (0.0ms) [[32mINFO [0m|#12434] Rendered layouts/_watch_and_search.html.erb (1.3ms) [[32mINFO [0m|#12434] Rendered layouts/_header.html.erb (2.0ms) [[32mINFO [0m|#12434] Rendered layouts/_personal_navigation.html.erb (1.0ms) [[32mINFO [0m|#12434] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#12434] Completed 200 OK in 272ms (Views: 13.3ms | ActiveRecord: 0.0ms | API: 253.9ms (XML: 0.0ms , View: 1.2ms , Backend: 0.0ms , DB: 9.4ms , HTTP: 118.4ms) | XML: 0.6ms) [[32mINFO [0m|#12434] Started GET "/project/toggle_watch?project=My%3AMaintenance" for 127.0.0.1 at 2013-03-25 17:16:18 +0000 [[32mINFO [0m|#12434] Processing by ProjectController#toggle_watch as HTML [[32mINFO [0m|#12434] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|#12434] Setting return_to: "/project/toggle_watch?project=My%3AMaintenance" [[0;37mDEBUG[0m|#12434] Authenticating with iChain mode: off [[32mINFO [0m|#12434] Authenticated request to "/project/toggle_watch?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|#12434] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#12434] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#12434] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#12434] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#12434] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#12434] RT http://localhost:3203/person/tom {"view"=>0.543498, "db"=>1.356373, "backend"=>0, "xml"=>0, :runtime=>12.888, :all=>16.196628} [[0;37mDEBUG[0m|#12434] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#12434] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.291386, "db"=>7.735046, "backend"=>0, "xml"=>0, :runtime=>103.80799999999999, :all=>143.415886} [[0;37mDEBUG[0m|#12434] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#12434] returning [{:name=>"My:Maintenance"}] from rails cache Project_ce8cfb5ef2a35543461dbbb6b22579cb [[0;37mDEBUG[0m|#12434] Remove My:Maintenance from watchlist for tom [[0;37mDEBUG[0m|#12434] user 'tom' removes project 'My:Maintenance' from watchlist [[0;37mDEBUG[0m|#12434] 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|#12434] http_do #1: method: put url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#12434] RT http://localhost:3203/person/tom {"view"=>1.3715789999999999, "db"=>2.7650999999999994, "backend"=>0, "xml"=>0, :runtime=>24.556, :all=>30.360088} [[0;37mDEBUG[0m|#12434] [REST] find( PersonGroup, [{:login=>"tom"}] ) [[0;37mDEBUG[0m|#12434] params {:login=>"tom"} [[0;37mDEBUG[0m|#12434] uri is: http://localhost:3203/person/:login/group [[0;37mDEBUG[0m|#12434] http_do #1: method: get url: http://localhost:3203/person/tom/group [[0;37mDEBUG[0m|#12434] RT http://localhost:3203/person/tom/group {"view"=>0.352743, "db"=>0.722151, "backend"=>0, "xml"=>0, :runtime=>6.394, :all=>10.675548} [[0;37mDEBUG[0m|#12434] free_cache [:id, {:what=>"project", :predicate=>"person/@userid='tom'"}] Collection_8c1f4f93580a7c15e1f5fefa4bf90a08 [[0;37mDEBUG[0m|#12434] free_cache [:id, {:what=>"package", :predicate=>"person/@userid='tom'"}] Collection_09c0d884aeab5856e9b6a54d49db8a54 [[32mINFO [0m|#12434] Redirected to http://127.0.0.1:52683/project/show?project=My%3AMaintenance [[32mINFO [0m|#12434] Completed 302 Found in 205ms (ActiveRecord: 0.0ms | API: 200.6ms (XML: 0.0ms , View: 2.6ms , Backend: 0.0ms , DB: 12.6ms , HTTP: 53.0ms) | XML: 0.3ms) [[32mINFO [0m|#12434] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2013-03-25 17:16:19 +0000 [[32mINFO [0m|#12434] Processing by ProjectController#show as HTML [[32mINFO [0m|#12434] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|#12434] Setting return_to: "/project/show?project=My%3AMaintenance" [[0;37mDEBUG[0m|#12434] Authenticating with iChain mode: off [[32mINFO [0m|#12434] Authenticated request to "/project/show?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|#12434] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#12434] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#12434] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#12434] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#12434] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#12434] RT http://localhost:3203/person/tom {"view"=>0.471787, "db"=>0.8232140000000001, "backend"=>0, "xml"=>0, :runtime=>8.071, :all=>49.58098} [[0;37mDEBUG[0m|#12434] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#12434] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.25766199999999995, "db"=>4.707140999999999, "backend"=>0, "xml"=>0, :runtime=>32.663, :all=>71.082737} [[0;37mDEBUG[0m|#12434] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#12434] http_do #1: method: get url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance [[0;37mDEBUG[0m|#12434] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.430932, "db"=>3.5102290000000007, "backend"=>0, "xml"=>0, :runtime=>36.191, :all=>75.59049599999999} [[0;37mDEBUG[0m|#12434] 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|#12434] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02 [[32mINFO [0m|#12434] Rendered project/_tabs.html.erb (3.0ms) [[32mINFO [0m|#12434] Rendered shared/_open_requests.html.erb (0.0ms) [[32mINFO [0m|#12434] Rendered layouts/_watch_and_search.html.erb (1.2ms) [[32mINFO [0m|#12434] Rendered layouts/_header.html.erb (2.2ms) [[32mINFO [0m|#12434] Rendered layouts/_personal_navigation.html.erb (1.5ms) [[32mINFO [0m|#12434] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#12434] Completed 200 OK in 216ms (Views: 15.0ms | ActiveRecord: 0.0ms | API: 196.3ms (XML: 0.0ms , View: 1.2ms , Backend: 0.0ms , DB: 9.0ms , HTTP: 119.3ms) | XML: 0.5ms) [[32mINFO [0m|#12434] Started POST "/user/logout" for 127.0.0.1 at 2013-03-25 17:16:19 +0000 [[32mINFO [0m|#12434] Processing by UserController#logout as HTML [[0;37mDEBUG[0m|#12434] Setting return_to: "/user/logout" [[0;37mDEBUG[0m|#12434] Authenticating with iChain mode: off [[32mINFO [0m|#12434] Authenticated request to "/user/logout" from tom [[32mINFO [0m|#12434] Logging out: tom [[32mINFO [0m|#12434] Redirected to http://127.0.0.1:52683/ [[0;37mDEBUG[0m|#12434] free_cache [{}] Person_f7754490a4ed0ddff3ff300384cc6faa [[32mINFO [0m|#12434] Completed 302 Found in 38ms (ActiveRecord: 36.1ms | XML: 0.0ms) [[32mINFO [0m|#12434] Started GET "/" for 127.0.0.1 at 2013-03-25 17:16:19 +0000 [[32mINFO [0m|#12434] Processing by MainController#index as HTML [[0;37mDEBUG[0m|#12434] Setting return_to: "/" [[0;37mDEBUG[0m|#12434] Authenticating with iChain mode: off [[32mINFO [0m|#12434] Anonymous request to / [[0;37mDEBUG[0m|#12434] free_cache [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"}] Statusmessage_753097322d9cc873836d791589547e90 [[0;37mDEBUG[0m|#12434] [REST] find( Statusmessage, [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"}] ) [[0;37mDEBUG[0m|#12434] params {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"} [[0;37mDEBUG[0m|#12434] uri is: http://localhost:3203/status/messages/:id/?:limit [[0;37mDEBUG[0m|#12434] http_do #1: method: get url: http://localhost:3203/status/messages/?limit=5 [[0;37mDEBUG[0m|#12434] RT http://localhost:3203/status/messages/?limit=5 {"view"=>0.725326, "db"=>0.442496, "backend"=>0, "xml"=>0, :runtime=>5.684, :all=>8.599905999999999} [[0;37mDEBUG[0m|#12434] free_cache [{:limit=>"6", :shared=>"true"}] LatestUpdated_ee34770dc62e51dca62baed2d5258b33 [[0;37mDEBUG[0m|#12434] [REST] find( LatestUpdated, [{:limit=>"6", :shared=>"true"}] ) [[0;37mDEBUG[0m|#12434] params {:limit=>"6", :shared=>"true"} [[0;37mDEBUG[0m|#12434] uri is: http://localhost:3203/statistics/latest_updated?:limit [[0;37mDEBUG[0m|#12434] http_do #1: method: get url: http://localhost:3203/statistics/latest_updated?limit=6 [[0;37mDEBUG[0m|#12434] RT http://localhost:3203/statistics/latest_updated?limit=6 {"view"=>1.120194, "db"=>2.107405, "backend"=>0, "xml"=>0, :runtime=>16.592, :all=>60.941233999999994} [[32mINFO [0m|#12434] Rendered shared/_sign_up.html.erb (1.2ms) [[32mINFO [0m|#12434] Rendered main/_news.html.erb (0.7ms) [[32mINFO [0m|#12434] Rendered main/_latest_updates.erb (5.6ms) [[32mINFO [0m|#12434] Rendered main/_countdown.html.erb (0.1ms) [[32mINFO [0m|#12434] Rendered main/_sponsors.html.erb (0.1ms) [[32mINFO [0m|#12434] Rendered main/_feeds.html.erb (0.1ms) [[32mINFO [0m|#12434] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#12434] Rendered layouts/_header.html.erb (1.0ms) [[32mINFO [0m|#12434] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|#12434] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#12434] Completed 200 OK in 90ms (Views: 16.7ms | ActiveRecord: 0.0ms | API: 69.5ms (XML: 0.0ms , View: 1.8ms , Backend: 0.0ms , DB: 2.5ms , HTTP: 47.3ms) | XML: 1.2ms) [[32mINFO [0m|#12434] Started GET "/main/systemstatus" for 127.0.0.1 at 2013-03-25 17:16:19 +0000 [[32mINFO [0m|#12434] Processing by MainController#systemstatus as */* [[0;37mDEBUG[0m|#12434] Setting return_to: "/main/systemstatus" [[0;37mDEBUG[0m|#12434] Authenticating with iChain mode: off [[32mINFO [0m|#12434] Anonymous request to /main/systemstatus [[0;37mDEBUG[0m|#12434] returning [{:shared=>"true"}] from rails cache GlobalCounters_2899b016f456189a42bc9c5c8fc7013a [[0;37mDEBUG[0m|#12434] returning [:available] from rails cache Architecture_82f3aea44c00f161487e3359eb33cff4 [[32mINFO [0m|#12434] Rendered main/_systemstatus.html.erb (1.2ms) [[32mINFO [0m|#12434] Completed 200 OK in 3ms (Views: 1.7ms | ActiveRecord: 0.0ms | XML: 0.3ms) [[0;37mDEBUG[0m|#12434] http_do #1: method: post url: http://localhost:3203/test/test_end [[0;37mDEBUG[0m|#12434] RT http://localhost:3203/test/test_end {"view"=>0.837112, "db"=>22.659475999999998, "backend"=>0, "xml"=>0, :runtime=>27.652, :all=>32.179761} + 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