November 2012 - Open Build Service Tests

[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui #689
by admin＠opensuse.org 29 Nov '12

29 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui/689/changes> Changes: [Stephan Kulow] [webui] more work on the user vs login confusion [Stephan Kulow] [dist] cat the log for single failed tests ------------------------------------------ [...truncated 148623 lines...] [[0;37mDEBUG[0m|#23293] Authenticating with iChain mode: off [[32mINFO [0m|#23293] Authenticated request to "/project/buildresult?project=BaseDistro&index=" from tom [[0;37mDEBUG[0m|#23293] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#23293] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#23293] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#23293] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#23293] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#23293] RT http://localhost:3203/person/tom {"view"=>0.64644, "db"=>1.4889189999999999, "backend"=>0, "xml"=>0, :runtime=>16.279, :all=>19.854176000000002} [[0;37mDEBUG[0m|#23293] returning [{:name=>"BaseDistro"}] from rails cache Project_f726314c8d941efeb9d0b888fe97b37e [[0;37mDEBUG[0m|#23293] free_cache [{:project=>"BaseDistro", :view=>"summary"}] Buildresult_81f2400bb79dc620ddf8b0bf87e74935 [[0;37mDEBUG[0m|#23293] [REST] find( Buildresult, [{:project=>"BaseDistro", :view=>"summary"}] ) [[0;37mDEBUG[0m|#23293] params {:project=>"BaseDistro", :view=>"summary"} [[0;37mDEBUG[0m|#23293] uri is: http://localhost:3203/build/:project/_result?:view&:package&:code&:lastbuil… [[0;37mDEBUG[0m|#23293] http_do #1: method: get url: http://localhost:3203/build/BaseDistro/_result?view=summary [[0;37mDEBUG[0m|#23293] RT http://localhost:3203/build/BaseDistro/_result?view=summary {"view"=>nil, "db"=>0.574098, "backend"=>0, "xml"=>0, :runtime=>124.3, :all=>167.564516} [[32mINFO [0m|#23293] Rendered project/_buildstatus.html.erb (0.1ms) [[32mINFO [0m|#23293] Completed 200 OK in 191ms (Views: 0.8ms | ActiveRecord: 0.0ms | API: 187.4ms (XML: 0.0ms , View: 0.6ms , Backend: 0.0ms , DB: 2.1ms , HTTP: 46.8ms) | XML: 0.3ms) [[32mINFO [0m|#23293] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-29 11:41:23 +0100 [[32mINFO [0m|#23293] Processing by ProjectController#show as HTML [[32mINFO [0m|#23293] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|#23293] Setting return_to: "/project/show?project=My%3AMaintenance" [[0;37mDEBUG[0m|#23293] Authenticating with iChain mode: off [[32mINFO [0m|#23293] Authenticated request to "/project/show?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|#23293] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#23293] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#23293] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#23293] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#23293] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#23293] RT http://localhost:3203/person/tom {"view"=>0.993351, "db"=>1.5833999999999997, "backend"=>0, "xml"=>0, :runtime=>17.642000000000003, :all=>58.324925} [[0;37mDEBUG[0m|#23293] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#23293] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.30012, "db"=>3.40919, "backend"=>0, "xml"=>0, :runtime=>36.251, :all=>78.812065} [[0;37mDEBUG[0m|#23293] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#23293] http_do #1: method: get url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance [[0;37mDEBUG[0m|#23293] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.478115, "db"=>3.3001250000000004, "backend"=>0, "xml"=>0, :runtime=>36.093, :all=>79.584954} [[0;37mDEBUG[0m|#23293] 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|#23293] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02 [[32mINFO [0m|#23293] Rendered project/_tabs.html.erb (3.5ms) [[32mINFO [0m|#23293] Rendered shared/_open_requests.html.erb (0.1ms) [[32mINFO [0m|#23293] Rendered layouts/_watch_and_search.html.erb (1.5ms) [[32mINFO [0m|#23293] Rendered layouts/_header.html.erb (2.6ms) [[32mINFO [0m|#23293] Rendered layouts/_personal_navigation.html.erb (1.1ms) [[32mINFO [0m|#23293] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#23293] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#23293] Completed 200 OK in 240ms (Views: 17.7ms | ActiveRecord: 0.0ms | API: 216.7ms (XML: 0.0ms , View: 1.8ms , Backend: 0.0ms , DB: 8.3ms , HTTP: 126.7ms) | XML: 0.7ms) [[32mINFO [0m|#23293] Started GET "/project/toggle_watch?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-29 11:41:24 +0100 [[32mINFO [0m|#23293] Processing by ProjectController#toggle_watch as HTML [[32mINFO [0m|#23293] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|#23293] Setting return_to: "/project/toggle_watch?project=My%3AMaintenance" [[0;37mDEBUG[0m|#23293] Authenticating with iChain mode: off [[32mINFO [0m|#23293] Authenticated request to "/project/toggle_watch?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|#23293] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#23293] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#23293] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#23293] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#23293] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#23293] RT http://localhost:3203/person/tom {"view"=>0.836481, "db"=>1.94821, "backend"=>0, "xml"=>0, :runtime=>16.711, :all=>20.799475} [[0;37mDEBUG[0m|#23293] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#23293] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.285265, "db"=>3.553219, "backend"=>0, "xml"=>0, :runtime=>33.402, :all=>76.587538} [[0;37mDEBUG[0m|#23293] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#23293] returning [{:name=>"My:Maintenance"}] from rails cache Project_ce8cfb5ef2a35543461dbbb6b22579cb [[0;37mDEBUG[0m|#23293] Remove My:Maintenance from watchlist for tom [[0;37mDEBUG[0m|#23293] user 'tom' removes project 'My:Maintenance' from watchlist [[0;37mDEBUG[0m|#23293] saving object Person ({:is_current=>"true", :login=>"tom"}) to api: <person> <login>tom</login> <email>tschmidt(a)suse.de</email> <realname>Thor</realname> <watchlist> </watchlist> </person> [[0;37mDEBUG[0m|#23293] http_do #1: method: put url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#23293] RT http://localhost:3203/person/tom {"view"=>1.168358, "db"=>1.9056919999999995, "backend"=>0, "xml"=>0, :runtime=>21.402, :all=>25.781683} [[0;37mDEBUG[0m|#23293] [REST] find( PersonGroup, [{:login=>"tom"}] ) [[0;37mDEBUG[0m|#23293] params {:login=>"tom"} [[0;37mDEBUG[0m|#23293] uri is: http://localhost:3203/person/:login/group [[0;37mDEBUG[0m|#23293] http_do #1: method: get url: http://localhost:3203/person/tom/group [[0;37mDEBUG[0m|#23293] RT http://localhost:3203/person/tom/group {"view"=>0.667531, "db"=>0.626085, "backend"=>0, "xml"=>0, :runtime=>8.472, :all=>13.32783} [[0;37mDEBUG[0m|#23293] free_cache [:id, {:what=>"project", :predicate=>"person/@userid='tom'"}] Collection_8c1f4f93580a7c15e1f5fefa4bf90a08 [[0;37mDEBUG[0m|#23293] free_cache [:id, {:what=>"package", :predicate=>"person/@userid='tom'"}] Collection_09c0d884aeab5856e9b6a54d49db8a54 [[32mINFO [0m|#23293] Redirected to http://127.0.0.1:47409/project/show?project=My%3AMaintenance [[32mINFO [0m|#23293] Completed 302 Found in 142ms (ActiveRecord: 0.0ms | API: 136.5ms (XML: 0.0ms , View: 3.0ms , Backend: 0.0ms , DB: 8.0ms , HTTP: 56.5ms) | XML: 0.4ms) [[32mINFO [0m|#23293] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-29 11:41:24 +0100 [[32mINFO [0m|#23293] Processing by ProjectController#show as HTML [[32mINFO [0m|#23293] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|#23293] Setting return_to: "/project/show?project=My%3AMaintenance" [[0;37mDEBUG[0m|#23293] Authenticating with iChain mode: off [[32mINFO [0m|#23293] Authenticated request to "/project/show?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|#23293] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#23293] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#23293] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#23293] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#23293] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#23293] RT http://localhost:3203/person/tom {"view"=>0.528012, "db"=>0.815291, "backend"=>0, "xml"=>0, :runtime=>8.878, :all=>47.841046} [[0;37mDEBUG[0m|#23293] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#23293] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.27859199999999995, "db"=>5.9949330000000005, "backend"=>0, "xml"=>0, :runtime=>50.852000000000004, :all=>94.687052} [[0;37mDEBUG[0m|#23293] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#23293] http_do #1: method: get url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance [[0;37mDEBUG[0m|#23293] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.506504, "db"=>3.1577010000000008, "backend"=>0, "xml"=>0, :runtime=>33.766999999999996, :all=>76.229319} [[0;37mDEBUG[0m|#23293] 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|#23293] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02 [[32mINFO [0m|#23293] Rendered project/_tabs.html.erb (2.3ms) [[32mINFO [0m|#23293] Rendered shared/_open_requests.html.erb (0.0ms) [[32mINFO [0m|#23293] Rendered layouts/_watch_and_search.html.erb (1.0ms) [[32mINFO [0m|#23293] Rendered layouts/_header.html.erb (1.8ms) [[32mINFO [0m|#23293] Rendered layouts/_personal_navigation.html.erb (1.0ms) [[32mINFO [0m|#23293] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#23293] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#23293] Completed 200 OK in 237ms (Views: 12.6ms | ActiveRecord: 0.0ms | API: 218.8ms (XML: 0.0ms , View: 1.3ms , Backend: 0.0ms , DB: 10.0ms , HTTP: 125.3ms) | XML: 0.5ms) [[32mINFO [0m|#23293] Started POST "/user/logout" for 127.0.0.1 at 2012-11-29 11:41:25 +0100 [[32mINFO [0m|#23293] Processing by UserController#logout as HTML [[0;37mDEBUG[0m|#23293] Setting return_to: "/user/logout" [[0;37mDEBUG[0m|#23293] Authenticating with iChain mode: off [[32mINFO [0m|#23293] Authenticated request to "/user/logout" from tom [[32mINFO [0m|#23293] Logging out: tom [[32mINFO [0m|#23293] Redirected to http://127.0.0.1:47409/ [[0;37mDEBUG[0m|#23293] free_cache [{}] Person_f7754490a4ed0ddff3ff300384cc6faa [[32mINFO [0m|#23293] Completed 302 Found in 33ms (ActiveRecord: 29.0ms | XML: 0.0ms) [[32mINFO [0m|#23293] Started GET "/" for 127.0.0.1 at 2012-11-29 11:41:25 +0100 [[32mINFO [0m|#23293] Processing by MainController#index as HTML [[0;37mDEBUG[0m|#23293] Setting return_to: "/" [[0;37mDEBUG[0m|#23293] Authenticating with iChain mode: off [[32mINFO [0m|#23293] Anonymous request to / [[0;37mDEBUG[0m|#23293] returning [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"}] from rails cache Statusmessage_753097322d9cc873836d791589547e90 [[0;37mDEBUG[0m|#23293] returning [{:limit=>"6", :shared=>"true"}] from rails cache LatestUpdated_ee34770dc62e51dca62baed2d5258b33 [[0;37mDEBUG[0m|#23293] returning [{:login=>"king"}] from rails cache Person_d11b7899dedf6f054f648e67eed36b8c [[32mINFO [0m|#23293] Rendered shared/_user_with_realname_and_icon.html.erb (80.1ms) [[32mINFO [0m|#23293] Rendered main/_news.html.erb (82.5ms) [[32mINFO [0m|#23293] Rendered main/_contact.html.erb (4.2ms) [[32mINFO [0m|#23293] Rendered main/_latest_updates.erb (5.8ms) [[32mINFO [0m|#23293] Rendered main/_countdown.html.erb (0.0ms) [[32mINFO [0m|#23293] Rendered main/_sponsors.html.erb (0.0ms) [[32mINFO [0m|#23293] Rendered main/_feeds.html.erb (0.0ms) [[32mINFO [0m|#23293] Rendered layouts/_watch_and_search.html.erb (0.6ms) [[32mINFO [0m|#23293] Rendered layouts/_header.html.erb (1.3ms) [[32mINFO [0m|#23293] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|#23293] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#23293] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#23293] Completed 200 OK in 108ms (Views: 104.3ms | ActiveRecord: 0.0ms | XML: 1.1ms) [[32mINFO [0m|#23293] Started GET "/home/king/icon?size=20" for 127.0.0.1 at 2012-11-29 11:41:25 +0100 [[32mINFO [0m|#23293] Processing by HomeController#icon as HTML [[32mINFO [0m|#23293] Parameters: {"size"=>"20", "user"=>"king"} [[0;37mDEBUG[0m|#23293] Setting return_to: "/home/king/icon?size=20" [[0;37mDEBUG[0m|#23293] Authenticating with iChain mode: off [[32mINFO [0m|#23293] Anonymous request to /home/king/icon?size=20 [[32mINFO [0m|#23293] Completed 200 OK in 5ms (Views: 0.4ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#23293] Started GET "/main/systemstatus" for 127.0.0.1 at 2012-11-29 11:41:25 +0100 [[32mINFO [0m|#23293] Processing by MainController#systemstatus as */* [[0;37mDEBUG[0m|#23293] Setting return_to: "/main/systemstatus" [[0;37mDEBUG[0m|#23293] Authenticating with iChain mode: off [[32mINFO [0m|#23293] Anonymous request to /main/systemstatus [[0;37mDEBUG[0m|#23293] returning [{:shared=>"true"}] from rails cache GlobalCounters_2899b016f456189a42bc9c5c8fc7013a [[0;37mDEBUG[0m|#23293] returning [:available] from rails cache Architecture_82f3aea44c00f161487e3359eb33cff4 [[32mINFO [0m|#23293] Rendered main/_systemstatus.html.erb (1.5ms) [[32mINFO [0m|#23293] Completed 200 OK in 4ms (Views: 2.2ms | ActiveRecord: 0.0ms | XML: 0.5ms) [[0;37mDEBUG[0m|#23293] http_do #1: method: post url: http://localhost:3203/test/test_end [[0;37mDEBUG[0m|#23293] RT http://localhost:3203/test/test_end {"view"=>1.637947, "db"=>34.137701, "backend"=>0, "xml"=>0, :runtime=>41.583, :all=>47.27313100000001} + 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/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/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(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui #682
by admin＠opensuse.org 28 Nov '12

28 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui/682/changes> Changes: [Stephan Kulow] [webui] add a "spider as admin" test [Stephan Kulow] [webui] remove some caching for packages ------------------------------------------ [...truncated 85095 lines...] [[32mINFO [0m|#18700] Started GET "/home/tom/icon?size=60" for 127.0.0.1 at 2012-11-28 11:20:44 +0100 [[32mINFO [0m|#18700] Processing by HomeController#icon as HTML [[32mINFO [0m|#18700] Parameters: {"size"=>"60", "user"=>"tom"} [[0;37mDEBUG[0m|#18700] Setting return_to: "/home/tom/icon?size=60" [[0;37mDEBUG[0m|#18700] Authenticating with iChain mode: off [[32mINFO [0m|#18700] Authenticated request to "/home/tom/icon?size=60" from tom [[32mINFO [0m|#18700] Completed 200 OK in 2ms (Views: 0.7ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#18700] Started GET "/assets/user_edit.png" for 127.0.0.1 at 2012-11-28 11:20:44 +0100 [[32mINFO [0m|#18700] Served asset /user_edit.png - 200 OK (7ms) [[32mINFO [0m|#18700] Started POST "/user/logout" for 127.0.0.1 at 2012-11-28 11:20:45 +0100 [[32mINFO [0m|#18700] Processing by UserController#logout as HTML [[0;37mDEBUG[0m|#18700] Setting return_to: "/user/logout" [[0;37mDEBUG[0m|#18700] Authenticating with iChain mode: off [[32mINFO [0m|#18700] Authenticated request to "/user/logout" from tom [[32mINFO [0m|#18700] Logging out: tom [[32mINFO [0m|#18700] Redirected to http://127.0.0.1:34576/ [[0;37mDEBUG[0m|#18700] free_cache [{}] Person_f7754490a4ed0ddff3ff300384cc6faa [[32mINFO [0m|#18700] Completed 302 Found in 21ms (ActiveRecord: 20.0ms | XML: 0.0ms) [[32mINFO [0m|#18700] Started GET "/" for 127.0.0.1 at 2012-11-28 11:20:45 +0100 [[32mINFO [0m|#18700] Processing by MainController#index as HTML [[0;37mDEBUG[0m|#18700] Setting return_to: "/" [[0;37mDEBUG[0m|#18700] Authenticating with iChain mode: off [[32mINFO [0m|#18700] Anonymous request to / [[0;37mDEBUG[0m|#18700] returning [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"}] from rails cache Statusmessage_753097322d9cc873836d791589547e90 [[0;37mDEBUG[0m|#18700] returning [{:limit=>"6", :shared=>"true"}] from rails cache LatestUpdated_ee34770dc62e51dca62baed2d5258b33 [[0;37mDEBUG[0m|#18700] returning [{:login=>"king"}] from rails cache Person_d11b7899dedf6f054f648e67eed36b8c [[32mINFO [0m|#18700] Rendered shared/_user_with_realname_and_icon.html.erb (5.8ms) [[32mINFO [0m|#18700] Rendered main/_news.html.erb (10.9ms) [[32mINFO [0m|#18700] Rendered main/_contact.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered main/_latest_updates.erb (29.0ms) [[32mINFO [0m|#18700] Rendered main/_countdown.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered main/_sponsors.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered main/_feeds.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered layouts/_watch_and_search.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered layouts/_header.html.erb (1.5ms) [[32mINFO [0m|#18700] Rendered layouts/_personal_navigation.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#18700] Completed 200 OK in 50ms (Views: 48.0ms | ActiveRecord: 0.0ms | XML: 0.2ms) [[32mINFO [0m|#18700] Started GET "/home/king/icon?size=20" for 127.0.0.1 at 2012-11-28 11:20:45 +0100 [[32mINFO [0m|#18700] Processing by HomeController#icon as HTML [[32mINFO [0m|#18700] Parameters: {"size"=>"20", "user"=>"king"} [[0;37mDEBUG[0m|#18700] Setting return_to: "/home/king/icon?size=20" [[0;37mDEBUG[0m|#18700] Authenticating with iChain mode: off [[32mINFO [0m|#18700] Anonymous request to /home/king/icon?size=20 [[32mINFO [0m|#18700] Completed 200 OK in 1ms (Views: 0.4ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#18700] Started GET "/main/systemstatus" for 127.0.0.1 at 2012-11-28 11:20:45 +0100 [[32mINFO [0m|#18700] Processing by MainController#systemstatus as */* [[0;37mDEBUG[0m|#18700] Setting return_to: "/main/systemstatus" [[0;37mDEBUG[0m|#18700] Authenticating with iChain mode: off [[32mINFO [0m|#18700] Anonymous request to /main/systemstatus [[0;37mDEBUG[0m|#18700] returning [{:shared=>"true"}] from rails cache GlobalCounters_2899b016f456189a42bc9c5c8fc7013a [[0;37mDEBUG[0m|#18700] returning [:available] from rails cache Architecture_82f3aea44c00f161487e3359eb33cff4 [[32mINFO [0m|#18700] Rendered main/_systemstatus.html.erb (1.2ms) [[32mINFO [0m|#18700] Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.0ms | XML: 0.3ms) [[0;37mDEBUG[0m|#18700] http_do #1: method: post url: http://localhost:3203/test/test_end [[0;37mDEBUG[0m|#18700] RT http://localhost:3203/test/test_end {"view"=>0.901213, "db"=>16.706025000000004, "backend"=>0, "xml"=>0, :runtime=>21.188, :all=>34.492562} [[0;37mDEBUG[0m|#18700] http_do #1: method: post url: http://localhost:3203/test/test_start [[0;37mDEBUG[0m|#18700] RT http://localhost:3203/test/test_start {"view"=>2.589689, "db"=>0.061622, "backend"=>0, "xml"=>0, :runtime=>5.23, :all=>23.635043000000003} [[32mINFO [0m|#18700] Started GET "/" for 127.0.0.1 at 2012-11-28 11:20:46 +0100 [[32mINFO [0m|#18700] Processing by MainController#index as HTML [[0;37mDEBUG[0m|#18700] Setting return_to: "/" [[0;37mDEBUG[0m|#18700] Authenticating with iChain mode: off [[32mINFO [0m|#18700] Anonymous request to / [[0;37mDEBUG[0m|#18700] returning [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"}] from rails cache Statusmessage_753097322d9cc873836d791589547e90 [[0;37mDEBUG[0m|#18700] returning [{:limit=>"6", :shared=>"true"}] from rails cache LatestUpdated_ee34770dc62e51dca62baed2d5258b33 [[0;37mDEBUG[0m|#18700] returning [{:login=>"king"}] from rails cache Person_d11b7899dedf6f054f648e67eed36b8c [[32mINFO [0m|#18700] Rendered shared/_user_with_realname_and_icon.html.erb (0.4ms) [[32mINFO [0m|#18700] Rendered main/_news.html.erb (5.0ms) [[32mINFO [0m|#18700] Rendered main/_contact.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered main/_latest_updates.erb (6.3ms) [[32mINFO [0m|#18700] Rendered main/_countdown.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered main/_sponsors.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered main/_feeds.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered layouts/_watch_and_search.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered layouts/_header.html.erb (12.0ms) [[32mINFO [0m|#18700] Rendered layouts/_personal_navigation.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#18700] Completed 200 OK in 41ms (Views: 39.5ms | ActiveRecord: 0.0ms | API: 58.1ms (XML: 0.0ms , View: 3.5ms , Backend: 0.0ms , DB: 16.8ms , HTTP: 31.7ms) | XML: 0.4ms) [[32mINFO [0m|#18700] Started GET "/home/king/icon?size=20" for 127.0.0.1 at 2012-11-28 11:20:46 +0100 [[32mINFO [0m|#18700] Processing by HomeController#icon as HTML [[32mINFO [0m|#18700] Parameters: {"size"=>"20", "user"=>"king"} [[0;37mDEBUG[0m|#18700] Setting return_to: "/home/king/icon?size=20" [[0;37mDEBUG[0m|#18700] Authenticating with iChain mode: off [[32mINFO [0m|#18700] Anonymous request to /home/king/icon?size=20 [[32mINFO [0m|#18700] Completed 200 OK in 1ms (Views: 0.4ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#18700] Started GET "/main/systemstatus" for 127.0.0.1 at 2012-11-28 11:20:46 +0100 [[32mINFO [0m|#18700] Processing by MainController#systemstatus as */* [[0;37mDEBUG[0m|#18700] Setting return_to: "/main/systemstatus" [[0;37mDEBUG[0m|#18700] Authenticating with iChain mode: off [[32mINFO [0m|#18700] Anonymous request to /main/systemstatus [[0;37mDEBUG[0m|#18700] returning [{:shared=>"true"}] from rails cache GlobalCounters_2899b016f456189a42bc9c5c8fc7013a [[0;37mDEBUG[0m|#18700] returning [:available] from rails cache Architecture_82f3aea44c00f161487e3359eb33cff4 [[32mINFO [0m|#18700] Rendered main/_systemstatus.html.erb (1.2ms) [[32mINFO [0m|#18700] Completed 200 OK in 3ms (Views: 1.8ms | ActiveRecord: 0.0ms | XML: 0.3ms) [[32mINFO [0m|#18700] Started POST "/user/do_login" for 127.0.0.1 at 2012-11-28 11:20:46 +0100 [[32mINFO [0m|#18700] Processing by UserController#do_login as HTML [[32mINFO [0m|#18700] Parameters: {"utf8"=>"��", "return_to_path"=>"/", "username"=>"tom", "password"=>"[FILTERED]", "commit"=>"Login"} [[0;37mDEBUG[0m|#18700] Setting return_to: "/" [[0;37mDEBUG[0m|#18700] Authenticating with iChain mode: off [[32mINFO [0m|#18700] Anonymous request to / [[0;37mDEBUG[0m|#18700] Doing form authorization to login user tom [[0;37mDEBUG[0m|#18700] [REST] find( Person, [{:login=>"tom"}] ) [[0;37mDEBUG[0m|#18700] params {:login=>"tom"} [[0;37mDEBUG[0m|#18700] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#18700] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#18700] RT http://localhost:3203/person/tom {"view"=>0.620662, "db"=>14.420321999999999, "backend"=>0, "xml"=>0, :runtime=>23.839, :all=>34.879042999999996} [[32mINFO [0m|#18700] Redirected to http://127.0.0.1:34576/ [[32mINFO [0m|#18700] Completed 302 Found in 38ms (ActiveRecord: 0.0ms | API: 34.9ms (XML: 0.0ms , View: 0.6ms , Backend: 0.0ms , DB: 14.4ms , HTTP: 11.0ms) | XML: 0.1ms) [[32mINFO [0m|#18700] Started GET "/" for 127.0.0.1 at 2012-11-28 11:20:46 +0100 [[32mINFO [0m|#18700] Processing by MainController#index as HTML [[0;37mDEBUG[0m|#18700] Setting return_to: "/" [[0;37mDEBUG[0m|#18700] Authenticating with iChain mode: off [[32mINFO [0m|#18700] Authenticated request to "/" from tom [[0;37mDEBUG[0m|#18700] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#18700] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#18700] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#18700] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#18700] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#18700] RT http://localhost:3203/person/tom {"view"=>0.48961800000000005, "db"=>0.9842869999999999, "backend"=>0, "xml"=>0, :runtime=>9.248, :all=>54.15870099999999} [[0;37mDEBUG[0m|#18700] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#18700] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.231517, "db"=>18.39303, "backend"=>0, "xml"=>0, :runtime=>59.418, :all=>103.15279600000001} [[0;37mDEBUG[0m|#18700] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#18700] returning [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"}] from rails cache Statusmessage_753097322d9cc873836d791589547e90 [[0;37mDEBUG[0m|#18700] returning [{:limit=>"6", :shared=>"true"}] from rails cache LatestUpdated_ee34770dc62e51dca62baed2d5258b33 [[0;37mDEBUG[0m|#18700] returning [{:name=>"home:tom"}] from rails cache Project_aadf1ec049c50614647e4261c26bfa68 [[0;37mDEBUG[0m|#18700] returning [{:login=>"king"}] from rails cache Person_d11b7899dedf6f054f648e67eed36b8c [[32mINFO [0m|#18700] Rendered shared/_user_with_realname_and_icon.html.erb (1.0ms) [[32mINFO [0m|#18700] Rendered main/_news.html.erb (2.5ms) [[32mINFO [0m|#18700] Rendered main/_contact.html.erb (0.1ms) [[32mINFO [0m|#18700] Rendered main/_latest_updates.erb (15.9ms) [[32mINFO [0m|#18700] Rendered main/_countdown.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered main/_sponsors.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered main/_feeds.html.erb (0.0ms) [[32mINFO [0m|#18700] Rendered layouts/_watch_and_search.html.erb (0.7ms) [[32mINFO [0m|#18700] Rendered layouts/_header.html.erb (1.5ms) [[32mINFO [0m|#18700] Rendered layouts/_personal_navigation.html.erb (1.0ms) [[32mINFO [0m|#18700] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#18700] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#18700] Completed 200 OK in 191ms (Views: 29.4ms | ActiveRecord: 0.0ms | API: 157.3ms (XML: 0.0ms , View: 0.7ms , Backend: 0.0ms , DB: 19.4ms , HTTP: 88.6ms) | XML: 12.5ms) [[32mINFO [0m|#18700] Started GET "/home/king/icon?size=20" for 127.0.0.1 at 2012-11-28 11:20:47 +0100 [[32mINFO [0m|#18700] Processing by HomeController#icon as HTML [[32mINFO [0m|#18700] Parameters: {"size"=>"20", "user"=>"king"} [[0;37mDEBUG[0m|#18700] Setting return_to: "/home/king/icon?size=20" [[0;37mDEBUG[0m|#18700] Authenticating with iChain mode: off [[32mINFO [0m|#18700] Authenticated request to "/home/king/icon?size=20" from tom [[32mINFO [0m|#18700] Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#18700] Started GET "/main/systemstatus" for 127.0.0.1 at 2012-11-28 11:20:47 +0100 [[32mINFO [0m|#18700] Processing by MainController#systemstatus as */* [[0;37mDEBUG[0m|#18700] Setting return_to: "/main/systemstatus" [[0;37mDEBUG[0m|#18700] Authenticating with iChain mode: off [[32mINFO [0m|#18700] Authenticated request to "/main/systemstatus" from tom [[0;37mDEBUG[0m|#18700] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#18700] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#18700] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#18700] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#18700] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#18700] RT http://localhost:3203/person/tom {"view"=>0.523914, "db"=>0.9888669999999999, "backend"=>0, "xml"=>0, :runtime=>9.68, :all=>19.076791} [[0;37mDEBUG[0m|#18700] returning [{:shared=>"true"}] from rails cache GlobalCounters_2899b016f456189a42bc9c5c8fc7013a [[0;37mDEBUG[0m|#18700] returning [:available] from rails cache Architecture_82f3aea44c00f161487e3359eb33cff4 [[32mINFO [0m|#18700] Rendered main/_systemstatus.html.erb (158.8ms) [[32mINFO [0m|#18700] Completed 200 OK in 188ms (Views: 166.8ms | ActiveRecord: 0.0ms | API: 19.1ms (XML: 0.0ms , View: 0.5ms , Backend: 0.0ms , DB: 1.0ms , HTTP: 9.4ms) | XML: 149.4ms) [[32mINFO [0m|#18700] Started GET "/project/show?project=BaseDistro" for 127.0.0.1 at 2012-11-28 11:20:48 +0100 [[32mINFO [0m|#18700] Processing by ProjectController#show as HTML [[32mINFO [0m|#18700] Parameters: {"project"=>"BaseDistro"} [[0;37mDEBUG[0m|#18700] Setting return_to: "/project/show?project=BaseDistro" [[0;37mDEBUG[0m|#18700] Authenticating with iChain mode: off [[32mINFO [0m|#18700] Authenticated request to "/project/show?project=BaseDistro" from tom [[0;37mDEBUG[0m|#18700] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#18700] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#18700] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#18700] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#18700] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#18700] RT http://localhost:3203/person/tom {"view"=>0.5870759999999999, "db"=>0.8784019999999999, "backend"=>0, "xml"=>0, :runtime=>10.677000000000001, :all=>24.691018} [[0;37mDEBUG[0m|#18700] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#18700] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.27239, "db"=>3.431926, "backend"=>0, "xml"=>0, :runtime=>47.507, :all=>95.163998} [[0;37mDEBUG[0m|#18700] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#18700] http_do #1: method: get url: http://localhost:3203/webui/project_infos?project=BaseDistro [[0;37mDEBUG[0m|#18700] RT http://localhost:3203/webui/project_infos?project=BaseDistro {"view"=>0.49058, "db"=>18.005331999999996, "backend"=>0, "xml"=>0, :runtime=>91.128, :all=>135.454675} [[0;37mDEBUG[0m|#18700] data { "name": "BaseDistro", "packages": [ [ "pack1", null ], [ "pack2", null ], [ "pack3", null ] ], "xml": "<project name=\"BaseDistro\">\n <title>This is a base distro</title>\n <description>This could be openSUSE:11.2 project for example</description>\n <repository name=\"BaseDistro_repo\">\n <releasetarget project=\"BaseDistro:Update\" repository=\"BaseDistroUpdateProject_repo\"/>\n <arch>i586</arch>\n </repository>\n</project>\n", "maintenance_project": "My:Maintenance", "linking_projects": [ "BaseDistro:Update" ], "requests": [ ], "nr_of_problem_packages": 0 } [[32mINFO [0m|#18700] Rendered project/_tabs.html.erb (3.9ms) [[32mINFO [0m|#18700] Rendered shared/_open_requests.html.erb (0.0ms) [[32mINFO [0m|#18700] Completed 500 Internal Server Error in 278ms + 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/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(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui-testsuite #492
by admin＠opensuse.org 28 Nov '12

28 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/492/changes> Changes: [Stephan Kulow] [webui] add a "spider as admin" test [Stephan Kulow] [webui] remove some caching for packages ------------------------------------------ [...truncated 13594 lines...] [[0;37mDEBUG[0m|#17526] uri is: http://localhost:3203/search/:what?match=:predicate [[0;37mDEBUG[0m|#17526] [REST] Transport.find using POST-method [[0;37mDEBUG[0m|#17526] http_do #1: method: post url: http://localhost:3203/search/project [[0;37mDEBUG[0m|#17526] RT http://localhost:3203/search/project {"view"=>0.641912, "db"=>1.562543, "backend"=>0, "xml"=>0.110431, :runtime=>13.661, :all=>27.529103} [[32mINFO [0m|#17526] Rendered search/_search_result.html.erb (0.0ms) [[32mINFO [0m|#17526] Rendered search/search.html.erb within layouts/application (0.7ms) [[32mINFO [0m|#17526] Rendered layouts/_watch_and_search.html.erb (3.4ms) [[32mINFO [0m|#17526] Rendered layouts/_header.html.erb (4.1ms) [[32mINFO [0m|#17526] Rendered layouts/_personal_navigation.html.erb (1.3ms) [[32mINFO [0m|#17526] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#17526] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#17526] Completed 200 OK in 119ms (Views: 9.8ms | ActiveRecord: 0.0ms | API: 84.3ms (XML: 0.2ms , View: 1.3ms , Backend: 0.0ms , DB: 33.0ms , HTTP: 26.6ms) | XML: 18.5ms) [[32mINFO [0m|#17526] Started GET "/search/search?utf8=%E2%9C%93&search_text=&commit=Search&project=1&package=1&attribute=OBS%3ARequestCloned&advanced=on&issue_tracker=bnc+%28Novell+Bugzilla%29&issue_name=" for 127.0.0.1 at 2012-11-28 11:06:04 +0100 [[32mINFO [0m|#17526] Processing by SearchController#search as HTML [[32mINFO [0m|#17526] Parameters: {"utf8"=>"��", "search_text"=>"", "commit"=>"Search", "project"=>"1", "package"=>"1", "attribute"=>"OBS:RequestCloned", "advanced"=>"on", "issue_tracker"=>"bnc (Novell Bugzilla)", "issue_name"=>""} [[0;37mDEBUG[0m|#17526] Setting return_to: "/search/search?utf8=%E2%9C%93&search_text=&commit=Search&project=1&package=1&attribute=OBS%3ARequestCloned&advanced=on&issue_tracker=bnc+%28Novell+Bugzilla%29&issue_name=" [[0;37mDEBUG[0m|#17526] Authenticating with iChain mode: off [[32mINFO [0m|#17526] Anonymous request to /search/search?utf8=%E2%9C%93&search_text=&commit=Search&project=1&package=1&attribute=OBS%3ARequestCloned&advanced=on&issue_tracker=bnc+%28Novell+Bugzilla%29&issue_name= [[0;37mDEBUG[0m|#17526] free_cache [:namespaces] Attribute_abf073acf4f2b7187044a69dc776c36a [[0;37mDEBUG[0m|#17526] [REST] find( Attribute, [:namespaces] ) [[0;37mDEBUG[0m|#17526] params {} [[0;37mDEBUG[0m|#17526] uri is: http://localhost:3203/attribute [[0;37mDEBUG[0m|#17526] http_do #1: method: get url: http://localhost:3203/attribute [[0;37mDEBUG[0m|#17526] RT http://localhost:3203/attribute {"view"=>0.611209, "db"=>0.382417, "backend"=>0, "xml"=>0, :runtime=>6.388, :all=>20.284759} [[0;37mDEBUG[0m|#17526] free_cache [:attributes, {:namespace=>"NSTEST"}] Attribute_acf162ca35af0c0b6e81adfd382303e0 [[0;37mDEBUG[0m|#17526] [REST] find( Attribute, [:attributes, {:namespace=>"NSTEST"}] ) [[0;37mDEBUG[0m|#17526] params {:namespace=>"NSTEST"} [[0;37mDEBUG[0m|#17526] uri is: http://localhost:3203/attribute/:namespace [[0;37mDEBUG[0m|#17526] http_do #1: method: get url: http://localhost:3203/attribute/NSTEST [[0;37mDEBUG[0m|#17526] RT http://localhost:3203/attribute/NSTEST {"view"=>0.604496, "db"=>9.347454999999998, "backend"=>0, "xml"=>0, :runtime=>16.688000000000002, :all=>61.448321} [[0;37mDEBUG[0m|#17526] free_cache [:attributes, {:namespace=>"OBS"}] Attribute_8fb2549b00f45ceed5a606ef2fc64363 [[0;37mDEBUG[0m|#17526] [REST] find( Attribute, [:attributes, {:namespace=>"OBS"}] ) [[0;37mDEBUG[0m|#17526] params {:namespace=>"OBS"} [[0;37mDEBUG[0m|#17526] uri is: http://localhost:3203/attribute/:namespace [[0;37mDEBUG[0m|#17526] http_do #1: method: get url: http://localhost:3203/attribute/OBS [[0;37mDEBUG[0m|#17526] RT http://localhost:3203/attribute/OBS {"view"=>0.621007, "db"=>1.008696, "backend"=>0, "xml"=>0, :runtime=>9.609, :all=>55.121721} [[0;37mDEBUG[0m|#17526] free_cache [:all] IssueTracker_c9f2a41f57195c17eafd205f9fae8094 [[0;37mDEBUG[0m|#17526] [REST] find( IssueTracker, [:all] ) [[0;37mDEBUG[0m|#17526] params {} [[0;37mDEBUG[0m|#17526] uri is: http://localhost:3203/issue_trackers/ [[0;37mDEBUG[0m|#17526] http_do #1: method: get url: http://localhost:3203/issue_trackers [[0;37mDEBUG[0m|#17526] RT http://localhost:3203/issue_trackers {"view"=>0.093552, "db"=>0.345835, "backend"=>0, "xml"=>0, :runtime=>40.349999999999994, :all=>86.820109} [[0;37mDEBUG[0m|#17526] free_cache [{:what=>"package", :predicate=>"[contains(attribute/@name,'OBS:RequestCloned')]"}] Collection_b4a6251d984101111f1430f53048389b [[0;37mDEBUG[0m|#17526] [REST] find( Collection, [{:what=>"package", :predicate=>"[contains(attribute/@name,'OBS:RequestCloned')]"}] ) [[0;37mDEBUG[0m|#17526] params {:what=>"package", :predicate=>"[contains(attribute/@name,'OBS:RequestCloned')]"} [[0;37mDEBUG[0m|#17526] uri is: http://localhost:3203/search/:what?match=:predicate [[0;37mDEBUG[0m|#17526] [REST] Transport.find using POST-method [[0;37mDEBUG[0m|#17526] http_do #1: method: post url: http://localhost:3203/search/package [[0;37mDEBUG[0m|#17526] RT http://localhost:3203/search/package {"view"=>0.553326, "db"=>9.242348, "backend"=>0, "xml"=>0.065371, :runtime=>20.38, :all=>34.753082} [[0;37mDEBUG[0m|#17526] free_cache [{:what=>"project", :predicate=>"[contains(attribute/@name,'OBS:RequestCloned')]"}] Collection_9f6bdf722e5b54ceaf85083caf3fb0b3 [[0;37mDEBUG[0m|#17526] [REST] find( Collection, [{:what=>"project", :predicate=>"[contains(attribute/@name,'OBS:RequestCloned')]"}] ) [[0;37mDEBUG[0m|#17526] params {:what=>"project", :predicate=>"[contains(attribute/@name,'OBS:RequestCloned')]"} [[0;37mDEBUG[0m|#17526] uri is: http://localhost:3203/search/:what?match=:predicate [[0;37mDEBUG[0m|#17526] [REST] Transport.find using POST-method [[0;37mDEBUG[0m|#17526] http_do #1: method: post url: http://localhost:3203/search/project [[0;37mDEBUG[0m|#17526] RT http://localhost:3203/search/project {"view"=>0.5023770000000001, "db"=>1.183953, "backend"=>0, "xml"=>0.062973, :runtime=>21.304, :all=>35.774715} [[32mINFO [0m|#17526] Rendered search/_search_result.html.erb (0.0ms) [[32mINFO [0m|#17526] Rendered search/search.html.erb within layouts/application (0.6ms) [[32mINFO [0m|#17526] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#17526] Rendered layouts/_header.html.erb (1.0ms) [[32mINFO [0m|#17526] Rendered layouts/_personal_navigation.html.erb (1.1ms) [[32mINFO [0m|#17526] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#17526] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#17526] Completed 200 OK in 311ms (Views: 5.9ms | ActiveRecord: 0.0ms | API: 294.2ms (XML: 0.1ms , View: 3.0ms , Backend: 0.0ms , DB: 21.5ms , HTTP: 179.5ms) | XML: 3.3ms) [[32mINFO [0m|#17526] Started GET "/assets/application.css" for 127.0.0.1 at 2012-11-28 11:06:04 +0100 [[32mINFO [0m|#17526] Served asset /application.css - 200 OK (0ms) [[32mINFO [0m|#17526] Started GET "/assets/application.js" for 127.0.0.1 at 2012-11-28 11:06:04 +0100 [[32mINFO [0m|#17526] Served asset /application.js - 200 OK (1ms) [[32mINFO [0m|#17526] Started GET "/assets/obs-logo_small.png" for 127.0.0.1 at 2012-11-28 11:06:05 +0100 [[32mINFO [0m|#17526] Served asset /obs-logo_small.png - 200 OK (0ms) [[32mINFO [0m|#17526] Started GET "/assets/images/gradient-dark-nav.png" for 127.0.0.1 at 2012-11-28 11:06:05 +0100 [[32mINFO [0m|#17526] Served asset /images/gradient-dark-nav.png - 200 OK (0ms) [[32mINFO [0m|#17526] Started GET "/assets/icons-s30b5819a4d.png" for 127.0.0.1 at 2012-11-28 11:06:05 +0100 [[32mINFO [0m|#17526] Served asset /icons-s30b5819a4d.png - 200 OK (0ms) [[32mINFO [0m|#17526] Started GET "/search" for 127.0.0.1 at 2012-11-28 11:06:05 +0100 [[32mINFO [0m|#17526] Processing by SearchController#index as HTML [[0;37mDEBUG[0m|#17526] Setting return_to: "/search" [[0;37mDEBUG[0m|#17526] Authenticating with iChain mode: off [[32mINFO [0m|#17526] Anonymous request to /search [[0;37mDEBUG[0m|#17526] returning [:namespaces] from rails cache Attribute_abf073acf4f2b7187044a69dc776c36a [[0;37mDEBUG[0m|#17526] returning [:attributes, {:namespace=>"NSTEST"}] from rails cache Attribute_acf162ca35af0c0b6e81adfd382303e0 [[0;37mDEBUG[0m|#17526] returning [:attributes, {:namespace=>"OBS"}] from rails cache Attribute_8fb2549b00f45ceed5a606ef2fc64363 [[0;37mDEBUG[0m|#17526] returning [:all] from rails cache IssueTracker_c9f2a41f57195c17eafd205f9fae8094 [[32mINFO [0m|#17526] Rendered search/index.html.erb within layouts/application (1.8ms) [[32mINFO [0m|#17526] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#17526] Rendered layouts/_header.html.erb (1.9ms) [[32mINFO [0m|#17526] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|#17526] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#17526] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#17526] Completed 200 OK in 110ms (Views: 103.9ms | ActiveRecord: 0.0ms | XML: 2.0ms) [[32mINFO [0m|#17526] Started GET "/search/search?utf8=%E2%9C%93&search_text=Some+empty+search.&commit=Search&name=1&title=1&description=1&attribute=&advanced=on&issue_tracker=bnc+%28Novell+Bugzilla%29&issue_name=" for 127.0.0.1 at 2012-11-28 11:06:06 +0100 [[32mINFO [0m|#17526] Processing by SearchController#search as HTML [[32mINFO [0m|#17526] Parameters: {"utf8"=>"��", "search_text"=>"Some empty search.", "commit"=>"Search", "name"=>"1", "title"=>"1", "description"=>"1", "attribute"=>"", "advanced"=>"on", "issue_tracker"=>"bnc (Novell Bugzilla)", "issue_name"=>""} [[0;37mDEBUG[0m|#17526] Setting return_to: "/search/search?utf8=%E2%9C%93&search_text=Some+empty+search.&commit=Search&name=1&title=1&description=1&attribute=&advanced=on&issue_tracker=bnc+%28Novell+Bugzilla%29&issue_name=" [[0;37mDEBUG[0m|#17526] Authenticating with iChain mode: off [[32mINFO [0m|#17526] Anonymous request to /search/search?utf8=%E2%9C%93&search_text=Some+empty+search.&commit=Search&name=1&title=1&description=1&attribute=&advanced=on&issue_tracker=bnc+%28Novell+Bugzilla%29&issue_name= [[0;37mDEBUG[0m|#17526] returning [:namespaces] from rails cache Attribute_abf073acf4f2b7187044a69dc776c36a [[0;37mDEBUG[0m|#17526] returning [:attributes, {:namespace=>"NSTEST"}] from rails cache Attribute_acf162ca35af0c0b6e81adfd382303e0 [[0;37mDEBUG[0m|#17526] returning [:attributes, {:namespace=>"OBS"}] from rails cache Attribute_8fb2549b00f45ceed5a606ef2fc64363 [[0;37mDEBUG[0m|#17526] returning [:all] from rails cache IssueTracker_c9f2a41f57195c17eafd205f9fae8094 [[32mINFO [0m|#17526] Rendered search/_search_result.html.erb (0.0ms) [[32mINFO [0m|#17526] Rendered search/search.html.erb within layouts/application (0.8ms) [[32mINFO [0m|#17526] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#17526] Rendered layouts/_header.html.erb (1.2ms) [[32mINFO [0m|#17526] Rendered layouts/_personal_navigation.html.erb (1.3ms) [[32mINFO [0m|#17526] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#17526] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#17526] Completed 200 OK in 27ms (Views: 7.6ms | ActiveRecord: 0.0ms | XML: 2.8ms) [[32mINFO [0m|#17526] Started GET "/search/search?utf8=%E2%9C%93&search_text=Some+empty+search.&commit=Search&name=1&title=1&description=1&attribute=&advanced=on&issue_tracker=bnc+%28Novell+Bugzilla%29&issue_name=" for 127.0.0.1 at 2012-11-28 11:06:07 +0100 [[32mINFO [0m|#17526] Processing by SearchController#search as HTML [[32mINFO [0m|#17526] Parameters: {"utf8"=>"��", "search_text"=>"Some empty search.", "commit"=>"Search", "name"=>"1", "title"=>"1", "description"=>"1", "attribute"=>"", "advanced"=>"on", "issue_tracker"=>"bnc (Novell Bugzilla)", "issue_name"=>""} [[0;37mDEBUG[0m|#17526] Setting return_to: "/search/search?utf8=%E2%9C%93&search_text=Some+empty+search.&commit=Search&name=1&title=1&description=1&attribute=&advanced=on&issue_tracker=bnc+%28Novell+Bugzilla%29&issue_name=" [[0;37mDEBUG[0m|#17526] Authenticating with iChain mode: off [[32mINFO [0m|#17526] Anonymous request to /search/search?utf8=%E2%9C%93&search_text=Some+empty+search.&commit=Search&name=1&title=1&description=1&attribute=&advanced=on&issue_tracker=bnc+%28Novell+Bugzilla%29&issue_name= [[0;37mDEBUG[0m|#17526] free_cache [:namespaces] Attribute_abf073acf4f2b7187044a69dc776c36a [[0;37mDEBUG[0m|#17526] [REST] find( Attribute, [:namespaces] ) [[0;37mDEBUG[0m|#17526] params {} [[0;37mDEBUG[0m|#17526] uri is: http://localhost:3203/attribute [[0;37mDEBUG[0m|#17526] http_do #1: method: get url: http://localhost:3203/attribute [[0;37mDEBUG[0m|#17526] RT http://localhost:3203/attribute {"view"=>0.603223, "db"=>0.44438600000000006, "backend"=>0, "xml"=>0, :runtime=>7.29, :all=>21.341670999999998} [[0;37mDEBUG[0m|#17526] free_cache [:attributes, {:namespace=>"NSTEST"}] Attribute_acf162ca35af0c0b6e81adfd382303e0 [[0;37mDEBUG[0m|#17526] [REST] find( Attribute, [:attributes, {:namespace=>"NSTEST"}] ) [[0;37mDEBUG[0m|#17526] params {:namespace=>"NSTEST"} [[0;37mDEBUG[0m|#17526] uri is: http://localhost:3203/attribute/:namespace [[0;37mDEBUG[0m|#17526] http_do #1: method: get url: http://localhost:3203/attribute/NSTEST [[0;37mDEBUG[0m|#17526] RT http://localhost:3203/attribute/NSTEST {"view"=>0.552991, "db"=>0.650198, "backend"=>0, "xml"=>0, :runtime=>15.467, :all=>59.447744} [[0;37mDEBUG[0m|#17526] free_cache [:attributes, {:namespace=>"OBS"}] Attribute_8fb2549b00f45ceed5a606ef2fc64363 [[0;37mDEBUG[0m|#17526] [REST] find( Attribute, [:attributes, {:namespace=>"OBS"}] ) [[0;37mDEBUG[0m|#17526] params {:namespace=>"OBS"} [[0;37mDEBUG[0m|#17526] uri is: http://localhost:3203/attribute/:namespace [[0;37mDEBUG[0m|#17526] http_do #1: method: get url: http://localhost:3203/attribute/OBS [[0;37mDEBUG[0m|#17526] RT http://localhost:3203/attribute/OBS {"view"=>0.547556, "db"=>0.730198, "backend"=>0, "xml"=>0, :runtime=>8.87, :all=>55.116149} [[0;37mDEBUG[0m|#17526] free_cache [:all] IssueTracker_c9f2a41f57195c17eafd205f9fae8094 [[0;37mDEBUG[0m|#17526] [REST] find( IssueTracker, [:all] ) [[0;37mDEBUG[0m|#17526] params {} [[0;37mDEBUG[0m|#17526] uri is: http://localhost:3203/issue_trackers/ [[0;37mDEBUG[0m|#17526] http_do #1: method: get url: http://localhost:3203/issue_trackers [[0;37mDEBUG[0m|#17526] RT http://localhost:3203/issue_trackers {"view"=>0.095986, "db"=>0.349769, "backend"=>0, "xml"=>0, :runtime=>41.329, :all=>86.835991} [[32mINFO [0m|#17526] Rendered search/_search_result.html.erb (0.0ms) [[32mINFO [0m|#17526] Rendered search/search.html.erb within layouts/application (0.7ms) [[32mINFO [0m|#17526] Rendered layouts/_watch_and_search.html.erb (8.6ms) [[32mINFO [0m|#17526] Rendered layouts/_header.html.erb (9.2ms) [[32mINFO [0m|#17526] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|#17526] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#17526] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#17526] Completed 200 OK in 248ms (Views: 14.9ms | ActiveRecord: 0.0ms | API: 222.7ms (XML: 0.0ms , View: 1.8ms , Backend: 0.0ms , DB: 2.2ms , HTTP: 149.8ms) | XML: 3.5ms) [[32mINFO [0m|#17526] Started GET "/assets/application.css" for 127.0.0.1 at 2012-11-28 11:06:07 +0100 [[32mINFO [0m|#17526] Served asset /application.css - 200 OK (1ms) [[32mINFO [0m|#17526] Started GET "/assets/application.js" for 127.0.0.1 at 2012-11-28 11:06:07 +0100 [[32mINFO [0m|#17526] Served asset /application.js - 200 OK (1ms) [[32mINFO [0m|#17526] Started GET "/assets/obs-logo_small.png" for 127.0.0.1 at 2012-11-28 11:06:08 +0100 [[32mINFO [0m|#17526] Served asset /obs-logo_small.png - 200 OK (0ms) [[32mINFO [0m|#17526] Started GET "/assets/icons-s30b5819a4d.png" for 127.0.0.1 at 2012-11-28 11:06:08 +0100 [[32mINFO [0m|#17526] Served asset /icons-s30b5819a4d.png - 200 OK (0ms) [[32mINFO [0m|#17526] Started GET "/assets/images/gradient-dark-nav.png" for 127.0.0.1 at 2012-11-28 11:06:08 +0100 [[32mINFO [0m|#17526] Served asset /images/gradient-dark-nav.png - 200 OK (0ms) [[32mINFO [0m|#17526] Started GET "/search" for 127.0.0.1 at 2012-11-28 11:06:08 +0100 [[32mINFO [0m|#17526] Processing by SearchController#index as HTML [[0;37mDEBUG[0m|#17526] Setting return_to: "/search" [[0;37mDEBUG[0m|#17526] Authenticating with iChain mode: off [[32mINFO [0m|#17526] Anonymous request to /search [[0;37mDEBUG[0m|#17526] returning [:namespaces] from rails cache Attribute_abf073acf4f2b7187044a69dc776c36a [[0;37mDEBUG[0m|#17526] returning [:attributes, {:namespace=>"NSTEST"}] from rails cache Attribute_acf162ca35af0c0b6e81adfd382303e0 [[0;37mDEBUG[0m|#17526] returning [:attributes, {:namespace=>"OBS"}] from rails cache Attribute_8fb2549b00f45ceed5a606ef2fc64363 [[0;37mDEBUG[0m|#17526] returning [:all] from rails cache IssueTracker_c9f2a41f57195c17eafd205f9fae8094 [[32mINFO [0m|#17526] Rendered search/index.html.erb within layouts/application (16.4ms) [[32mINFO [0m|#17526] Rendered layouts/_watch_and_search.html.erb (0.9ms) [[32mINFO [0m|#17526] Rendered layouts/_header.html.erb (2.1ms) [[32mINFO [0m|#17526] Rendered layouts/_personal_navigation.html.erb (2.1ms) [[32mINFO [0m|#17526] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#17526] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#17526] Completed 200 OK in 40ms (Views: 33.6ms | ActiveRecord: 0.0ms | XML: 2.0ms) [[32mINFO [0m|#17526] Started GET "/search/search?utf8=%E2%9C%93&search_text=Some+empty+search+again.&commit=Search&project=1&package=1&attribute=&advanced=on&issue_tracker=bnc+%28Novell+Bugzilla%29&issue_name=" for 127.0.0.1 at 2012-11-28 11:06:09 +0100 [[32mINFO [0m|#17526] Processing by SearchController#search as HTML [[32mINFO [0m|#17526] Parameters: {"utf8"=>"��", "search_text"=>"Some empty search again.", "commit"=>"Search", "project"=>"1", "package"=>"1", "attribute"=>"", "advanced"=>"on", "issue_tracker"=>"bnc (Novell Bugzilla)", "issue_name"=>""} [[0;37mDEBUG[0m|#17526] Setting return_to: "/search/search?utf8=%E2%9C%93&search_text=Some+empty+search+again.&commit=Search&project=1&package=1&attribute=&advanced=on&issue_tracker=bnc+%28Novell+Bugzilla%29&issue_name=" [[0;37mDEBUG[0m|#17526] Authenticating with iChain mode: off [[32mINFO [0m|#17526] Anonymous request to /search/search?utf8=%E2%9C%93&search_text=Some+empty+search+again.&commit=Search&project=1&package=1&attribute=&advanced=on&issue_tracker=bnc+%28Novell+Bugzilla%29&issue_name= [[0;37mDEBUG[0m|#17526] returning [:namespaces] from rails cache Attribute_abf073acf4f2b7187044a69dc776c36a [[0;37mDEBUG[0m|#17526] returning [:attributes, {:namespace=>"NSTEST"}] from rails cache Attribute_acf162ca35af0c0b6e81adfd382303e0 [[0;37mDEBUG[0m|#17526] returning [:attributes, {:namespace=>"OBS"}] from rails cache Attribute_8fb2549b00f45ceed5a606ef2fc64363 [[0;37mDEBUG[0m|#17526] returning [:all] from rails cache IssueTracker_c9f2a41f57195c17eafd205f9fae8094 [[32mINFO [0m|#17526] Redirected to http://localhost:3199/search [[32mINFO [0m|#17526] Completed 302 Found in 13ms (ActiveRecord: 0.0ms | XML: 1.9ms) [[32mINFO [0m|#17526] Started GET "/search" for 127.0.0.1 at 2012-11-28 11:06:09 +0100 [[32mINFO [0m|#17526] Processing by SearchController#index as HTML [[0;37mDEBUG[0m|#17526] Setting return_to: "/search" [[0;37mDEBUG[0m|#17526] Authenticating with iChain mode: off [[32mINFO [0m|#17526] Anonymous request to /search [[0;37mDEBUG[0m|#17526] returning [:namespaces] from rails cache Attribute_abf073acf4f2b7187044a69dc776c36a [[0;37mDEBUG[0m|#17526] returning [:attributes, {:namespace=>"NSTEST"}] from rails cache Attribute_acf162ca35af0c0b6e81adfd382303e0 [[0;37mDEBUG[0m|#17526] returning [:attributes, {:namespace=>"OBS"}] from rails cache Attribute_8fb2549b00f45ceed5a606ef2fc64363 [[0;37mDEBUG[0m|#17526] returning [:all] from rails cache IssueTracker_c9f2a41f57195c17eafd205f9fae8094 [[32mINFO [0m|#17526] Rendered search/index.html.erb within layouts/application (1.8ms) [[32mINFO [0m|#17526] Rendered layouts/_watch_and_search.html.erb (1.0ms) [[32mINFO [0m|#17526] Rendered layouts/_header.html.erb (10.2ms) [[32mINFO [0m|#17526] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|#17526] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#17526] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#17526] Completed 200 OK in 164ms (Views: 158.1ms | ActiveRecord: 0.0ms | XML: 1.8ms) [[32mINFO [0m|#17526] Started GET "/search/search?utf8=%E2%9C%93&search_text=&commit=Search&project=1&package=1&name=1&attribute=&advanced=on&issue_tracker=bnc+%28Novell+Bugzilla%29&issue_name=" for 127.0.0.1 at 2012-11-28 11:06:10 +0100 [[32mINFO [0m|#17526] Processing by SearchController#search as HTML [[32mINFO [0m|#17526] Parameters: {"utf8"=>"��", "search_text"=>"", "commit"=>"Search", "project"=>"1", "package"=>"1", "name"=>"1", "attribute"=>"", "advanced"=>"on", "issue_tracker"=>"bnc (Novell Bugzilla)", "issue_name"=>""} [[0;37mDEBUG[0m|#17526] Setting return_to: "/search/search?utf8=%E2%9C%93&search_text=&commit=Search&project=1&package=1&name=1&attribute=&advanced=on&issue_tracker=bnc+%28Novell+Bugzilla%29&issue_name=" [[0;37mDEBUG[0m|#17526] Authenticating with iChain mode: off [[32mINFO [0m|#17526] Anonymous request to /search/search?utf8=%E2%9C%93&search_text=&commit=Search&project=1&package=1&name=1&attribute=&advanced=on&issue_tracker=bnc+%28Novell+Bugzilla%29&issue_name= [[0;37mDEBUG[0m|#17526] returning [:namespaces] from rails cache Attribute_abf073acf4f2b7187044a69dc776c36a [[0;37mDEBUG[0m|#17526] returning [:attributes, {:namespace=>"NSTEST"}] from rails cache Attribute_acf162ca35af0c0b6e81adfd382303e0 [[0;37mDEBUG[0m|#17526] returning [:attributes, {:namespace=>"OBS"}] from rails cache Attribute_8fb2549b00f45ceed5a606ef2fc64363 [[0;37mDEBUG[0m|#17526] returning [:all] from rails cache IssueTracker_c9f2a41f57195c17eafd205f9fae8094 [[32mINFO [0m|#17526] Redirected to http://localhost:3199/search [[32mINFO [0m|#17526] Completed 302 Found in 15ms (ActiveRecord: 0.0ms | XML: 2.5ms) [[32mINFO [0m|#17526] Started GET "/search" for 127.0.0.1 at 2012-11-28 11:06:10 +0100 [[32mINFO [0m|#17526] Processing by SearchController#index as HTML [[0;37mDEBUG[0m|#17526] Setting return_to: "/search" [[0;37mDEBUG[0m|#17526] Authenticating with iChain mode: off [[32mINFO [0m|#17526] Anonymous request to /search [[0;37mDEBUG[0m|#17526] returning [:namespaces] from rails cache Attribute_abf073acf4f2b7187044a69dc776c36a [[0;37mDEBUG[0m|#17526] returning [:attributes, {:namespace=>"NSTEST"}] from rails cache Attribute_acf162ca35af0c0b6e81adfd382303e0 [[0;37mDEBUG[0m|#17526] returning [:attributes, {:namespace=>"OBS"}] from rails cache Attribute_8fb2549b00f45ceed5a606ef2fc64363 [[0;37mDEBUG[0m|#17526] returning [:all] from rails cache IssueTracker_c9f2a41f57195c17eafd205f9fae8094 [[32mINFO [0m|#17526] Rendered search/index.html.erb within layouts/application (2.0ms) [[32mINFO [0m|#17526] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#17526] Rendered layouts/_header.html.erb (1.2ms) [[32mINFO [0m|#17526] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|#17526] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#17526] Rendered layouts/_sponsor.html.erb (0.1ms) [[32mINFO [0m|#17526] Completed 200 OK in 24ms (Views: 16.6ms | ActiveRecord: 0.0ms | XML: 2.4ms) + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/> + egrep 'perl|ruby' USER PID ACCESS COMMAND <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/>: jenkins ..c.. git 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/scheduler.done 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(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui #679
by admin＠opensuse.org 28 Nov '12

28 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui/679/changes> Changes: [Stephan Kulow] [api] make Basedistro very important ------------------------------------------ [...truncated 103364 lines...] [[0;37mDEBUG[0m|# 8016] Authenticating with iChain mode: off [[32mINFO [0m|# 8016] Authenticated request to "/project/buildresult?project=BaseDistro&index=" from tom [[0;37mDEBUG[0m|# 8016] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|# 8016] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|# 8016] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|# 8016] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|# 8016] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 8016] RT http://localhost:3203/person/tom {"view"=>0.562604, "db"=>7.257848999999999, "backend"=>0, "xml"=>0, :runtime=>27.931, :all=>173.738854} [[0;37mDEBUG[0m|# 8016] returning [{:name=>"BaseDistro"}] from rails cache Project_f726314c8d941efeb9d0b888fe97b37e [[0;37mDEBUG[0m|# 8016] free_cache [{:project=>"BaseDistro", :view=>"summary"}] Buildresult_81f2400bb79dc620ddf8b0bf87e74935 [[0;37mDEBUG[0m|# 8016] [REST] find( Buildresult, [{:project=>"BaseDistro", :view=>"summary"}] ) [[0;37mDEBUG[0m|# 8016] params {:project=>"BaseDistro", :view=>"summary"} [[0;37mDEBUG[0m|# 8016] uri is: http://localhost:3203/build/:project/_result?:view&:package&:code&:lastbuil… [[0;37mDEBUG[0m|# 8016] http_do #1: method: get url: http://localhost:3203/build/BaseDistro/_result?view=summary [[0;37mDEBUG[0m|# 8016] RT http://localhost:3203/build/BaseDistro/_result?view=summary {"view"=>nil, "db"=>1.3523070000000001, "backend"=>0, "xml"=>0, :runtime=>75.261, :all=>78.586313} [[32mINFO [0m|# 8016] Rendered project/_buildstatus.html.erb (0.1ms) [[32mINFO [0m|# 8016] Completed 200 OK in 257ms (Views: 1.0ms | ActiveRecord: 0.0ms | API: 252.3ms (XML: 0.0ms , View: 0.6ms , Backend: 0.0ms , DB: 8.6ms , HTTP: 149.1ms) | XML: 0.3ms) [[32mINFO [0m|# 8016] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-27 22:41:09 +0100 [[32mINFO [0m|# 8016] Processing by ProjectController#show as HTML [[32mINFO [0m|# 8016] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|# 8016] Setting return_to: "/project/show?project=My%3AMaintenance" [[0;37mDEBUG[0m|# 8016] Authenticating with iChain mode: off [[32mINFO [0m|# 8016] Authenticated request to "/project/show?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|# 8016] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|# 8016] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|# 8016] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|# 8016] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|# 8016] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 8016] RT http://localhost:3203/person/tom {"view"=>0.572616, "db"=>6.801786, "backend"=>0, "xml"=>0, :runtime=>19.158, :all=>22.128973} [[0;37mDEBUG[0m|# 8016] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|# 8016] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.308155, "db"=>4.323308000000001, "backend"=>0, "xml"=>0, :runtime=>57.659, :all=>109.259894} [[0;37mDEBUG[0m|# 8016] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|# 8016] http_do #1: method: get url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance [[0;37mDEBUG[0m|# 8016] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.46368400000000004, "db"=>10.403585999999995, "backend"=>0, "xml"=>0, :runtime=>71.947, :all=>111.279174} [[0;37mDEBUG[0m|# 8016] 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|# 8016] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02 [[32mINFO [0m|# 8016] Rendered project/_tabs.html.erb (2.5ms) [[32mINFO [0m|# 8016] Rendered shared/_open_requests.html.erb (0.1ms) [[32mINFO [0m|# 8016] Rendered layouts/_watch_and_search.html.erb (1.5ms) [[32mINFO [0m|# 8016] Rendered layouts/_header.html.erb (2.5ms) [[32mINFO [0m|# 8016] Rendered layouts/_personal_navigation.html.erb (1.1ms) [[32mINFO [0m|# 8016] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|# 8016] Rendered layouts/_sponsor.html.erb (0.1ms) [[32mINFO [0m|# 8016] Completed 200 OK in 285ms (Views: 23.5ms | ActiveRecord: 0.0ms | API: 242.7ms (XML: 0.0ms , View: 1.3ms , Backend: 0.0ms , DB: 21.5ms , HTTP: 93.9ms) | XML: 0.5ms) [[32mINFO [0m|# 8016] Started GET "/project/toggle_watch?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-27 22:41:10 +0100 [[32mINFO [0m|# 8016] Processing by ProjectController#toggle_watch as HTML [[32mINFO [0m|# 8016] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|# 8016] Setting return_to: "/project/toggle_watch?project=My%3AMaintenance" [[0;37mDEBUG[0m|# 8016] Authenticating with iChain mode: off [[32mINFO [0m|# 8016] Authenticated request to "/project/toggle_watch?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|# 8016] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|# 8016] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|# 8016] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|# 8016] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|# 8016] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 8016] RT http://localhost:3203/person/tom {"view"=>0.521803, "db"=>1.4538, "backend"=>0, "xml"=>0, :runtime=>13.268, :all=>24.048675} [[0;37mDEBUG[0m|# 8016] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|# 8016] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.269166, "db"=>10.902642000000004, "backend"=>0, "xml"=>0, :runtime=>52.075, :all=>91.45967300000001} [[0;37mDEBUG[0m|# 8016] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|# 8016] returning [{:name=>"My:Maintenance"}] from rails cache Project_ce8cfb5ef2a35543461dbbb6b22579cb [[0;37mDEBUG[0m|# 8016] Remove My:Maintenance from watchlist for tom [[0;37mDEBUG[0m|# 8016] user 'tom' removes project 'My:Maintenance' from watchlist [[0;37mDEBUG[0m|# 8016] saving object Person ({:is_current=>"true", :login=>"tom"}) to api: <person> <login>tom</login> <email>tschmidt(a)suse.de</email> <realname>Thor</realname> <watchlist> </watchlist> </person> [[0;37mDEBUG[0m|# 8016] http_do #1: method: put url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 8016] RT http://localhost:3203/person/tom {"view"=>1.04893, "db"=>13.748749999999998, "backend"=>0, "xml"=>0, :runtime=>42.981, :all=>56.574893} [[0;37mDEBUG[0m|# 8016] [REST] find( PersonGroup, [{:login=>"tom"}] ) [[0;37mDEBUG[0m|# 8016] params {:login=>"tom"} [[0;37mDEBUG[0m|# 8016] uri is: http://localhost:3203/person/:login/group [[0;37mDEBUG[0m|# 8016] http_do #1: method: get url: http://localhost:3203/person/tom/group [[0;37mDEBUG[0m|# 8016] RT http://localhost:3203/person/tom/group {"view"=>0.351972, "db"=>0.512308, "backend"=>0, "xml"=>0, :runtime=>6.999, :all=>22.862391} [[0;37mDEBUG[0m|# 8016] free_cache [:id, {:what=>"project", :predicate=>"person/@userid='tom'"}] Collection_8c1f4f93580a7c15e1f5fefa4bf90a08 [[0;37mDEBUG[0m|# 8016] free_cache [:id, {:what=>"package", :predicate=>"person/@userid='tom'"}] Collection_09c0d884aeab5856e9b6a54d49db8a54 [[32mINFO [0m|# 8016] Redirected to http://127.0.0.1:56519/project/show?project=My%3AMaintenance [[32mINFO [0m|# 8016] Completed 302 Found in 200ms (ActiveRecord: 0.0ms | API: 194.9ms (XML: 0.0ms , View: 2.2ms , Backend: 0.0ms , DB: 26.6ms , HTTP: 79.6ms) | XML: 0.4ms) [[32mINFO [0m|# 8016] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-27 22:41:10 +0100 [[32mINFO [0m|# 8016] Processing by ProjectController#show as HTML [[32mINFO [0m|# 8016] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|# 8016] Setting return_to: "/project/show?project=My%3AMaintenance" [[0;37mDEBUG[0m|# 8016] Authenticating with iChain mode: off [[32mINFO [0m|# 8016] Authenticated request to "/project/show?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|# 8016] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|# 8016] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|# 8016] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|# 8016] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|# 8016] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 8016] RT http://localhost:3203/person/tom {"view"=>10.554941999999999, "db"=>0.792017, "backend"=>0, "xml"=>0, :runtime=>19.335, :all=>61.594618} [[0;37mDEBUG[0m|# 8016] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|# 8016] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.374245, "db"=>16.511548, "backend"=>0, "xml"=>0, :runtime=>55.428, :all=>98.77659399999999} [[0;37mDEBUG[0m|# 8016] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|# 8016] http_do #1: method: get url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance [[0;37mDEBUG[0m|# 8016] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.403308, "db"=>19.519765000000007, "backend"=>0, "xml"=>0, :runtime=>57.936, :all=>99.57157099999999} [[0;37mDEBUG[0m|# 8016] 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|# 8016] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02 [[32mINFO [0m|# 8016] Rendered project/_tabs.html.erb (2.2ms) [[32mINFO [0m|# 8016] Rendered shared/_open_requests.html.erb (0.0ms) [[32mINFO [0m|# 8016] Rendered layouts/_watch_and_search.html.erb (0.9ms) [[32mINFO [0m|# 8016] Rendered layouts/_header.html.erb (9.5ms) [[32mINFO [0m|# 8016] Rendered layouts/_personal_navigation.html.erb (1.6ms) [[32mINFO [0m|# 8016] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|# 8016] Rendered layouts/_sponsor.html.erb (0.1ms) [[32mINFO [0m|# 8016] Completed 200 OK in 289ms (Views: 22.5ms | ActiveRecord: 0.0ms | API: 259.9ms (XML: 0.0ms , View: 11.3ms , Backend: 0.0ms , DB: 36.8ms , HTTP: 127.2ms) | XML: 0.5ms) [[32mINFO [0m|# 8016] Started POST "/user/logout" for 127.0.0.1 at 2012-11-27 22:41:11 +0100 [[32mINFO [0m|# 8016] Processing by UserController#logout as HTML [[0;37mDEBUG[0m|# 8016] Setting return_to: "/user/logout" [[0;37mDEBUG[0m|# 8016] Authenticating with iChain mode: off [[32mINFO [0m|# 8016] Authenticated request to "/user/logout" from tom [[32mINFO [0m|# 8016] Logging out: tom [[32mINFO [0m|# 8016] Redirected to http://127.0.0.1:56519/ [[0;37mDEBUG[0m|# 8016] free_cache [{}] Person_f7754490a4ed0ddff3ff300384cc6faa [[32mINFO [0m|# 8016] Completed 302 Found in 33ms (ActiveRecord: 30.5ms | XML: 0.0ms) [[32mINFO [0m|# 8016] Started GET "/" for 127.0.0.1 at 2012-11-27 22:41:11 +0100 [[32mINFO [0m|# 8016] Processing by MainController#index as HTML [[0;37mDEBUG[0m|# 8016] Setting return_to: "/" [[0;37mDEBUG[0m|# 8016] Authenticating with iChain mode: off [[32mINFO [0m|# 8016] Anonymous request to / [[0;37mDEBUG[0m|# 8016] returning [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"}] from rails cache Statusmessage_753097322d9cc873836d791589547e90 [[0;37mDEBUG[0m|# 8016] returning [{:limit=>"6", :shared=>"true"}] from rails cache LatestUpdated_ee34770dc62e51dca62baed2d5258b33 [[0;37mDEBUG[0m|# 8016] returning [{:login=>"king"}] from rails cache Person_d11b7899dedf6f054f648e67eed36b8c [[32mINFO [0m|# 8016] Rendered shared/_user_with_realname_and_icon.html.erb (1.1ms) [[32mINFO [0m|# 8016] Rendered main/_news.html.erb (2.8ms) [[32mINFO [0m|# 8016] Rendered main/_contact.html.erb (0.1ms) [[32mINFO [0m|# 8016] Rendered main/_latest_updates.erb (14.3ms) [[32mINFO [0m|# 8016] Rendered main/_countdown.html.erb (0.0ms) [[32mINFO [0m|# 8016] Rendered main/_sponsors.html.erb (0.0ms) [[32mINFO [0m|# 8016] Rendered main/_feeds.html.erb (0.0ms) [[32mINFO [0m|# 8016] Rendered layouts/_watch_and_search.html.erb (0.6ms) [[32mINFO [0m|# 8016] Rendered layouts/_header.html.erb (1.3ms) [[32mINFO [0m|# 8016] Rendered layouts/_personal_navigation.html.erb (1.3ms) [[32mINFO [0m|# 8016] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|# 8016] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 8016] Completed 200 OK in 40ms (Views: 37.4ms | ActiveRecord: 0.0ms | XML: 1.0ms) [[32mINFO [0m|# 8016] Started GET "/home/king/icon?size=20" for 127.0.0.1 at 2012-11-27 22:41:11 +0100 [[32mINFO [0m|# 8016] Processing by HomeController#icon as HTML [[32mINFO [0m|# 8016] Parameters: {"size"=>"20", "user"=>"king"} [[0;37mDEBUG[0m|# 8016] Setting return_to: "/home/king/icon?size=20" [[0;37mDEBUG[0m|# 8016] Authenticating with iChain mode: off [[32mINFO [0m|# 8016] Anonymous request to /home/king/icon?size=20 [[32mINFO [0m|# 8016] Completed 200 OK in 1ms (Views: 0.4ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 8016] Started GET "/main/systemstatus" for 127.0.0.1 at 2012-11-27 22:41:11 +0100 [[32mINFO [0m|# 8016] Processing by MainController#systemstatus as */* [[0;37mDEBUG[0m|# 8016] Setting return_to: "/main/systemstatus" [[0;37mDEBUG[0m|# 8016] Authenticating with iChain mode: off [[32mINFO [0m|# 8016] Anonymous request to /main/systemstatus [[0;37mDEBUG[0m|# 8016] returning [{:shared=>"true"}] from rails cache GlobalCounters_2899b016f456189a42bc9c5c8fc7013a [[0;37mDEBUG[0m|# 8016] returning [:available] from rails cache Architecture_82f3aea44c00f161487e3359eb33cff4 [[32mINFO [0m|# 8016] Rendered main/_systemstatus.html.erb (0.4ms) [[32mINFO [0m|# 8016] Completed 200 OK in 3ms (Views: 2.5ms | ActiveRecord: 0.0ms | XML: 0.2ms) [[0;37mDEBUG[0m|# 8016] http_do #1: method: post url: http://localhost:3203/test/test_end [[0;37mDEBUG[0m|# 8016] RT http://localhost:3203/test/test_end {"view"=>4.007791999999999, "db"=>50.427019, "backend"=>0, "xml"=>0, :runtime=>57.397999999999996, :all=>71.46548899999999} + echo + cleanup + echo 'Killing backend processes' Killing backend processes + egrep 'perl|ruby' + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/> 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/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(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui #671
by admin＠opensuse.org 24 Nov '12

24 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui/671/changes> Changes: [Stephan Kulow] [webui] if the project does not exist, there is no package either ------------------------------------------ [...truncated 66906 lines...] [[0;37mDEBUG[0m|#32020] Setting return_to: "/project/buildresult?project=BaseDistro&index=" [[0;37mDEBUG[0m|#32020] Authenticating with iChain mode: off [[32mINFO [0m|#32020] Authenticated request to "/project/buildresult?project=BaseDistro&index=" from tom [[0;37mDEBUG[0m|#32020] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#32020] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#32020] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#32020] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#32020] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#32020] RT http://localhost:3203/person/tom {"view"=>0.0, "db"=>5.248565999999999, "backend"=>0, "xml"=>0, :runtime=>13.25, :all=>16.003209000000002} [[0;37mDEBUG[0m|#32020] returning [{:name=>"BaseDistro"}] from rails cache Project_f726314c8d941efeb9d0b888fe97b37e [[0;37mDEBUG[0m|#32020] free_cache [{:project=>"BaseDistro", :view=>"summary"}] Buildresult_81f2400bb79dc620ddf8b0bf87e74935 [[0;37mDEBUG[0m|#32020] [REST] find( Buildresult, [{:project=>"BaseDistro", :view=>"summary"}] ) [[0;37mDEBUG[0m|#32020] params {:project=>"BaseDistro", :view=>"summary"} [[0;37mDEBUG[0m|#32020] uri is: http://localhost:3203/build/:project/_result?:view&:package&:code&:lastbuil… [[0;37mDEBUG[0m|#32020] http_do #1: method: get url: http://localhost:3203/build/BaseDistro/_result?view=summary [[0;37mDEBUG[0m|#32020] RT http://localhost:3203/build/BaseDistro/_result?view=summary {"view"=>nil, "db"=>0.0, "backend"=>0, "xml"=>0, :runtime=>59.31, :all=>116.007555} [[32mINFO [0m|#32020] Rendered project/_buildstatus.html.erb (0.0ms) [[32mINFO [0m|#32020] Completed 200 OK in 132ms (Views: 0.0ms | ActiveRecord: 0.0ms | API: 132.0ms (XML: 0.0ms , View: 0.0ms , Backend: 0.0ms , DB: 5.2ms , HTTP: 59.5ms) | XML: 0.0ms) [[32mINFO [0m|#32020] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-24 17:30:40 +0100 [[32mINFO [0m|#32020] Processing by ProjectController#show as HTML [[32mINFO [0m|#32020] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|#32020] Setting return_to: "/project/show?project=My%3AMaintenance" [[0;37mDEBUG[0m|#32020] Authenticating with iChain mode: off [[32mINFO [0m|#32020] Authenticated request to "/project/show?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|#32020] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#32020] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#32020] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#32020] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#32020] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#32020] RT http://localhost:3203/person/tom {"view"=>0.778695, "db"=>0.0, "backend"=>0, "xml"=>0, :runtime=>22.581, :all=>25.990492} [[0;37mDEBUG[0m|#32020] --> direct_http url: #<URI::Generic:0x0000000693b358 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom> [[0;37mDEBUG[0m|#32020] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#32020] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.0, "db"=>22.876614000000004, "backend"=>0, "xml"=>0, :runtime=>152.334, :all=>202.266828} [[0;37mDEBUG[0m|#32020] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#32020] --> direct_http url: #<URI::Generic:0x00000006bb6f88 URL:http://localhost:3203/webui/project_infos?project=My%3AMaintenance> [[0;37mDEBUG[0m|#32020] http_do #1: method: GET url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance [[0;37mDEBUG[0m|#32020] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.0, "db"=>2.466017, "backend"=>0, "xml"=>0, :runtime=>79.311, :all=>121.002951} [[0;37mDEBUG[0m|#32020] 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|#32020] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02 [[32mINFO [0m|#32020] Rendered project/_tabs.html.erb (4.0ms) [[32mINFO [0m|#32020] Rendered shared/_open_requests.html.erb (0.0ms) [[32mINFO [0m|#32020] Rendered layouts/_watch_and_search.html.erb (4.0ms) [[32mINFO [0m|#32020] Rendered layouts/_header.html.erb (4.0ms) [[32mINFO [0m|#32020] Rendered layouts/_personal_navigation.html.erb (0.0ms) [[32mINFO [0m|#32020] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#32020] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#32020] Completed 200 OK in 384ms (Views: 24.0ms | ActiveRecord: 0.0ms | API: 349.3ms (XML: 0.0ms , View: 0.8ms , Backend: 0.0ms , DB: 25.3ms , HTTP: 95.0ms) | XML: 0.1ms) [[32mINFO [0m|#32020] Started GET "/project/toggle_watch?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-24 17:30:41 +0100 [[32mINFO [0m|#32020] Processing by ProjectController#toggle_watch as HTML [[32mINFO [0m|#32020] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|#32020] Setting return_to: "/project/toggle_watch?project=My%3AMaintenance" [[0;37mDEBUG[0m|#32020] Authenticating with iChain mode: off [[32mINFO [0m|#32020] Authenticated request to "/project/toggle_watch?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|#32020] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#32020] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#32020] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#32020] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#32020] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#32020] RT http://localhost:3203/person/tom {"view"=>7.942473000000001, "db"=>0.746653, "backend"=>0, "xml"=>0, :runtime=>22.075999999999997, :all=>26.051278} [[0;37mDEBUG[0m|#32020] --> direct_http url: #<URI::Generic:0x00000007486dc0 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom> [[0;37mDEBUG[0m|#32020] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#32020] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.0, "db"=>1.154762, "backend"=>0, "xml"=>0, :runtime=>47.663, :all=>89.82749} [[0;37mDEBUG[0m|#32020] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#32020] returning [{:name=>"My:Maintenance"}] from rails cache Project_ce8cfb5ef2a35543461dbbb6b22579cb [[0;37mDEBUG[0m|#32020] Remove My:Maintenance from watchlist for tom [[0;37mDEBUG[0m|#32020] user 'tom' removes project 'My:Maintenance' from watchlist [[0;37mDEBUG[0m|#32020] saving object Person ({:is_current=>"true", :login=>"tom"}) to api: <person> <login>tom</login> <email>tschmidt(a)suse.de</email> <realname>Thor</realname> <watchlist> </watchlist> </person> [[0;37mDEBUG[0m|#32020] http_do #1: method: put url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#32020] RT http://localhost:3203/person/tom {"view"=>0.8463029999999999, "db"=>1.59613, "backend"=>0, "xml"=>0, :runtime=>15.908999999999999, :all=>34.227879} [[32mINFO [0m|#32020] Redirected to http://127.0.0.1:44844/project/show?project=My%3AMaintenance [[32mINFO [0m|#32020] Completed 302 Found in 151ms (ActiveRecord: 0.0ms | API: 150.1ms (XML: 0.0ms , View: 8.8ms , Backend: 0.0ms , DB: 3.5ms , HTTP: 64.5ms) | XML: 0.0ms) [[32mINFO [0m|#32020] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-24 17:30:41 +0100 [[32mINFO [0m|#32020] Processing by ProjectController#show as HTML [[32mINFO [0m|#32020] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|#32020] Setting return_to: "/project/show?project=My%3AMaintenance" [[0;37mDEBUG[0m|#32020] Authenticating with iChain mode: off [[32mINFO [0m|#32020] Authenticated request to "/project/show?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|#32020] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#32020] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#32020] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#32020] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#32020] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#32020] RT http://localhost:3203/person/tom {"view"=>17.873552, "db"=>0.7329289999999999, "backend"=>0, "xml"=>0, :runtime=>27.614, :all=>68.215561} [[0;37mDEBUG[0m|#32020] --> direct_http url: #<URI::Generic:0x000000075bbd80 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom> [[0;37mDEBUG[0m|#32020] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#32020] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.212024, "db"=>2.927679, "backend"=>0, "xml"=>0, :runtime=>51.568999999999996, :all=>95.147443} [[0;37mDEBUG[0m|#32020] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#32020] --> direct_http url: #<URI::Generic:0x000000075d6360 URL:http://localhost:3203/webui/project_infos?project=My%3AMaintenance> [[0;37mDEBUG[0m|#32020] http_do #1: method: GET url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance [[0;37mDEBUG[0m|#32020] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.432796, "db"=>16.591825999999998, "backend"=>0, "xml"=>0, :runtime=>50.604000000000006, :all=>91.61606499999999} [[0;37mDEBUG[0m|#32020] 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|#32020] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02 [[32mINFO [0m|#32020] Rendered project/_tabs.html.erb (1.9ms) [[32mINFO [0m|#32020] Rendered shared/_open_requests.html.erb (0.0ms) [[32mINFO [0m|#32020] Rendered layouts/_watch_and_search.html.erb (17.0ms) [[32mINFO [0m|#32020] Rendered layouts/_header.html.erb (17.9ms) [[32mINFO [0m|#32020] Rendered layouts/_personal_navigation.html.erb (1.1ms) [[32mINFO [0m|#32020] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#32020] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#32020] Completed 200 OK in 287ms (Views: 27.8ms | ActiveRecord: 0.0ms | API: 255.0ms (XML: 0.0ms , View: 18.5ms , Backend: 0.0ms , DB: 20.3ms , HTTP: 125.2ms) | XML: 0.4ms) [[32mINFO [0m|#32020] Started POST "/user/logout" for 127.0.0.1 at 2012-11-24 17:30:42 +0100 [[32mINFO [0m|#32020] Processing by UserController#logout as HTML [[0;37mDEBUG[0m|#32020] Setting return_to: "/user/logout" [[0;37mDEBUG[0m|#32020] Authenticating with iChain mode: off [[32mINFO [0m|#32020] Authenticated request to "/user/logout" from tom [[32mINFO [0m|#32020] Logging out: tom [[32mINFO [0m|#32020] Redirected to http://127.0.0.1:44844/ [[0;37mDEBUG[0m|#32020] free_cache [{}] Person_f7754490a4ed0ddff3ff300384cc6faa [[32mINFO [0m|#32020] Completed 302 Found in 31ms (ActiveRecord: 29.1ms | XML: 0.0ms) [[32mINFO [0m|#32020] Started GET "/" for 127.0.0.1 at 2012-11-24 17:30:42 +0100 [[32mINFO [0m|#32020] Processing by MainController#index as HTML [[0;37mDEBUG[0m|#32020] Setting return_to: "/" [[0;37mDEBUG[0m|#32020] Authenticating with iChain mode: off [[32mINFO [0m|#32020] Anonymous request to / [[0;37mDEBUG[0m|#32020] returning [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"}] from rails cache Statusmessage_753097322d9cc873836d791589547e90 [[0;37mDEBUG[0m|#32020] returning [{:limit=>"6", :shared=>"true"}] from rails cache LatestUpdated_ee34770dc62e51dca62baed2d5258b33 [[0;37mDEBUG[0m|#32020] returning [{:login=>"king"}] from rails cache Person_d11b7899dedf6f054f648e67eed36b8c [[32mINFO [0m|#32020] Rendered shared/_user_with_realname_and_icon.html.erb (0.8ms) [[32mINFO [0m|#32020] Rendered main/_news.html.erb (2.5ms) [[32mINFO [0m|#32020] Rendered main/_contact.html.erb (0.1ms) [[32mINFO [0m|#32020] Rendered main/_latest_updates.erb (13.9ms) [[32mINFO [0m|#32020] Rendered main/_countdown.html.erb (0.0ms) [[32mINFO [0m|#32020] Rendered main/_sponsors.html.erb (0.0ms) [[32mINFO [0m|#32020] Rendered main/_feeds.html.erb (0.1ms) [[32mINFO [0m|#32020] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#32020] Rendered layouts/_header.html.erb (1.2ms) [[32mINFO [0m|#32020] Rendered layouts/_personal_navigation.html.erb (1.1ms) [[32mINFO [0m|#32020] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#32020] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#32020] Completed 200 OK in 29ms (Views: 26.4ms | ActiveRecord: 0.0ms | XML: 0.9ms) [[32mINFO [0m|#32020] Started GET "/home/icon?id=king&size=20" for 127.0.0.1 at 2012-11-24 17:30:42 +0100 [[32mINFO [0m|#32020] Processing by HomeController#icon as HTML [[32mINFO [0m|#32020] Parameters: {"id"=>"king", "size"=>"20"} [[0;37mDEBUG[0m|#32020] Setting return_to: "/home/icon?id=king&size=20" [[0;37mDEBUG[0m|#32020] Authenticating with iChain mode: off [[32mINFO [0m|#32020] Anonymous request to /home/icon?id=king&size=20 [[32mINFO [0m|#32020] Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#32020] Started GET "/main/systemstatus" for 127.0.0.1 at 2012-11-24 17:30:42 +0100 [[32mINFO [0m|#32020] Processing by MainController#systemstatus as */* [[0;37mDEBUG[0m|#32020] Setting return_to: "/main/systemstatus" [[0;37mDEBUG[0m|#32020] Authenticating with iChain mode: off [[32mINFO [0m|#32020] Anonymous request to /main/systemstatus [[0;37mDEBUG[0m|#32020] returning [{:shared=>"true"}] from rails cache GlobalCounters_2899b016f456189a42bc9c5c8fc7013a [[0;37mDEBUG[0m|#32020] returning [:available] from rails cache Architecture_82f3aea44c00f161487e3359eb33cff4 [[32mINFO [0m|#32020] Rendered main/_systemstatus.html.erb (1.2ms) [[32mINFO [0m|#32020] Completed 200 OK in 13ms (Views: 1.9ms | ActiveRecord: 0.0ms | XML: 0.3ms) [[0;37mDEBUG[0m|#32020] --> direct_http url: #<URI::Generic:0x000000079e4308 URL:http://localhost:3203/test/test_end> [[0;37mDEBUG[0m|#32020] http_do #1: method: GET url: http://localhost:3203/test/test_end [[0;37mDEBUG[0m|#32020] RT http://localhost:3203/test/test_end {"view"=>0.0, "db"=>19.673803, "backend"=>0, "xml"=>0, :runtime=>27.650000000000002, :all=>28.598057999999998} + 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.. egrep jenkins ..c.. sh jenkins ..c.. sh + 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/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/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(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

[obs-tests] Build failed in Jenkins: obs_master_testsuite_api #1211
by admin＠opensuse.org 24 Nov '12

24 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_api/1211/changes> Changes: [Stephan Kulow] [ci] downgrade to firefox 11 on travis ------------------------------------------ [...truncated 134581 lines...] [[32mINFO [0m|#28457] Started GET "/source/kde4" for 127.0.0.1 at 2012-11-23 18:06:38 +0100 [[32mINFO [0m|#28457] Processing by SourceController#index_project as XML [[32mINFO [0m|#28457] Parameters: {"project"=>"kde4"} [[0;37mDEBUG[0m|#28457] Validate XML request: #<ActionDispatch::Request:0x00000006a020e8> [[0;37mDEBUG[0m|#28457] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#28457] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#28457] [1m[36mUser Load (0.0ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#28457] USER found: king [[0;37mDEBUG[0m|#28457] User king initialised [[0;37mDEBUG[0m|#28457] [1m[35mRole Load (0.0ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mProject Load (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mPackage Load (0.6ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND (packages.db_project_id not in (0)) [[0;37mDEBUG[0m|#28457] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"response"} [[0;37mDEBUG[0m|#28457] Validate XML response: #<ActionDispatch::Response:0x00000006a020c0> took 1ms [[32mINFO [0m|#28457] Completed 200 OK in 41ms (Views: 0.0ms | ActiveRecord: 0.6ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#28457] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-11-23 18:06:38 +0100 [[32mINFO [0m|#28457] Processing by SourceController#package_meta as XML [[32mINFO [0m|#28457] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|#28457] Validate XML request: #<ActionDispatch::Request:0x00000006b5aff8> [[0;37mDEBUG[0m|#28457] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#28457] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#28457] [1m[36mUser Load (0.0ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#28457] USER found: king [[0;37mDEBUG[0m|#28457] User king initialised [[0;37mDEBUG[0m|#28457] get_by_project_and_name {} [[0;37mDEBUG[0m|#28457] [1m[35mRole Load (0.0ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mProject Load (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mPackage Load (0.0ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b646562617365' AND (packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mProject Load (0.6ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#28457] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#28457] [1m[35mPackageUserRoleRelationship Load (0.0ms)[0m SELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 2 [[0;37mDEBUG[0m|#28457] [1m[36mPackageGroupRoleRelationship Load (0.0ms)[0m [1mSELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|#28457] [1m[35mFlag Load (0.0ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position [[0;37mDEBUG[0m|#28457] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#28457] Validate XML response: #<ActionDispatch::Response:0x00000006b5afa8> took 4ms [[32mINFO [0m|#28457] Completed 200 OK in 43ms (Views: 0.0ms | ActiveRecord: 0.6ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#28457] Started PUT "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-11-23 18:06:38 +0100 [[32mINFO [0m|#28457] Processing by SourceController#package_meta as XML [[32mINFO [0m|#28457] Parameters: {"<package name"=>"\"kdebase\" project=\"kde4\">\n <title>blub</title>\n <description>blub</description>\n</package>\n", "project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|#28457] Validate XML request: #<ActionDispatch::Request:0x00000006f80510> [[0;37mDEBUG[0m|#28457] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#28457] [1m[36mUser Load (0.0ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#28457] USER found: king [[0;37mDEBUG[0m|#28457] User king initialised [[0;37mDEBUG[0m|#28457] [1m[35mRole Load (0.0ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mProject Load (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mPackage Load (0.0ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b646562617365' AND (packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mProject Load (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#28457] get_by_project_and_name {} [[0;37mDEBUG[0m|#28457] [1m[35mCACHE (0.0ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mCACHE (0.0ms)[0m [1mSELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b646562617365' AND (packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mCACHE (0.0ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mFlag Load (0.0ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mFlag Load (0.0ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mFlag Load (4.0ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position[0m [[0;37mDEBUG[0m|#28457] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|#28457] [1m[35mFlag Load (0.0ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 [[0;37mDEBUG[0m|#28457] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|#28457] xml_disabled? X({"name"=>"kdebase", "project"=>"kde4", "title"=>"blub", "description"=>"blub"}) [[0;37mDEBUG[0m|#28457] [1m[36mPackageUserRoleRelationship Load (0.0ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|#28457] [1m[35m (0.0ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#28457] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#28457] [1m[36mPackageUserRoleRelationship Load (0.0ms)[0m [1mSELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|#28457] [1m[35mPackageGroupRoleRelationship Load (0.0ms)[0m SELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 2 [[0;37mDEBUG[0m|#28457] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#28457] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#28462] 2012-11-23 18:06:38 [2229]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#28457] request took 0.053855053 [[0;37mDEBUG[0m|#28457] [1m[36mPackage Load (0.4ms)[0m [1mSELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp, IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 2 LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35m (4.6ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#28457] [1m[36mPackageGroupRoleRelationship Load (0.3ms)[0m [1mSELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|#28457] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#28457] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#28457] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#28457] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#28457] [1m[36mCACHE (0.0ms)[0m [1mSELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|#28457] [1m[35mCACHE (0.0ms)[0m SELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 2 [[0;37mDEBUG[0m|#28457] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#28457] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#28462] 2012-11-23 18:06:38 [2230]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#28457] request took 0.022277507 [[0;37mDEBUG[0m|#28457] [1m[36mCACHE (0.0ms)[0m [1mSELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp, IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 2 LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35m (0.2ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#28457] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#28457] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#28457] [1m[35mCACHE (0.0ms)[0m SELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 2 [[0;37mDEBUG[0m|#28457] [1m[36mCACHE (0.0ms)[0m [1mSELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|#28457] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#28457] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#28462] 2012-11-23 18:06:38 [2231]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#28457] request took 0.039574866 [[0;37mDEBUG[0m|#28457] [1m[35mCACHE (0.0ms)[0m SELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp, IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 2 LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36m (0.3ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#28457] Validate XML response: #<ActionDispatch::Response:0x00000006f804e8> took 0ms [[32mINFO [0m|#28457] Completed 200 OK in 162ms (Views: 1.0ms | ActiveRecord: 10.3ms | Backend: 115.7ms | XML: 0.0ms) [[32mINFO [0m|#28457] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-11-23 18:06:38 +0100 [[32mINFO [0m|#28457] Processing by SourceController#package_meta as XML [[32mINFO [0m|#28457] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|#28457] Validate XML request: #<ActionDispatch::Request:0x00000007360478> [[0;37mDEBUG[0m|#28457] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#28457] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#28457] [1m[35mUser Load (0.3ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#28457] USER found: king [[0;37mDEBUG[0m|#28457] User king initialised [[0;37mDEBUG[0m|#28457] get_by_project_and_name {} [[0;37mDEBUG[0m|#28457] [1m[36mRole Load (0.3ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mProject Load (0.2ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mPackage Load (0.4ms)[0m [1mSELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b646562617365' AND (packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mProject Load (0.1ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#28457] Validate XML response: #<ActionDispatch::Response:0x00000007360400> took 35ms [[32mINFO [0m|#28457] Completed 200 OK in 70ms (Views: 0.5ms | ActiveRecord: 1.3ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#28457] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-23 18:06:39 +0100 [[32mINFO [0m|#28457] Processing by SourceController#package_meta as XML [[32mINFO [0m|#28457] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#28457] Validate XML request: #<ActionDispatch::Request:0x000000073d4bc0> [[0;37mDEBUG[0m|#28457] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#28457] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#28457] [1m[36mUser Load (0.4ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#28457] USER found: king [[0;37mDEBUG[0m|#28457] User king initialised [[0;37mDEBUG[0m|#28457] get_by_project_and_name {} [[0;37mDEBUG[0m|#28457] [1m[35mRole Load (1.8ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mProject Load (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mPackage Load (0.0ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b64656c696273' AND (packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mProject Load (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#28457] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#28457] [1m[35mPackage Load (1.3ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`id` = 1234 AND (packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mProject Load (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 601 AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mPackageUserRoleRelationship Load (0.0ms)[0m SELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|#28457] [1m[36mPackageGroupRoleRelationship Load (4.0ms)[0m [1mSELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mFlag Load (0.0ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position [[0;37mDEBUG[0m|#28457] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#28457] Validate XML response: #<ActionDispatch::Response:0x000000073d4b70> took 16ms [[32mINFO [0m|#28457] Completed 200 OK in 60ms (Views: 0.0ms | ActiveRecord: 7.5ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#28457] Started PUT "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-23 18:06:39 +0100 [[32mINFO [0m|#28457] Processing by SourceController#package_meta as XML [[32mINFO [0m|#28457] Parameters: {"<package name"=>"\"kdelibs\" project=\"kde4\">\n <title>blub</title>\n <description>blub</description>\n <devel project=\"home:coolo:test\" package=\"kdelibs_DEVEL_package\"/>\n <person userid=\"fredlibs\" role=\"maintainer\"/>\n <person userid=\"adrian\" role=\"reviewer\"/>\n <group groupid=\"test_group\" role=\"maintainer\"/>\n</package>\n", "project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#28457] Validate XML request: #<ActionDispatch::Request:0x00000007757ba8> [[0;37mDEBUG[0m|#28457] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#28457] [1m[36mUser Load (0.5ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#28457] USER found: king [[0;37mDEBUG[0m|#28457] User king initialised [[0;37mDEBUG[0m|#28457] [1m[35mRole Load (0.0ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mProject Load (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mPackage Load (0.0ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b64656c696273' AND (packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mProject Load (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#28457] get_by_project_and_name {} [[0;37mDEBUG[0m|#28457] [1m[35mCACHE (0.0ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mCACHE (0.0ms)[0m [1mSELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b64656c696273' AND (packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mCACHE (0.0ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mFlag Load (0.0ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mFlag Load (0.0ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mFlag Load (0.0ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position[0m [[0;37mDEBUG[0m|#28457] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|#28457] [1m[35mFlag Load (0.0ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 [[0;37mDEBUG[0m|#28457] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|#28457] xml_disabled? X({"name"=>"kdelibs", "project"=>"kde4", "title"=>"blub", "description"=>"blub", "devel"=>X({"project"=>"home:coolo:test", "package"=>"kdelibs_DEVEL_package"}), "person"=>[X({"userid"=>"fredlibs", "role"=>"maintainer"}), X({"userid"=>"adrian", "role"=>"reviewer"})], "group"=>X({"groupid"=>"test_group", "role"=>"maintainer"})}) [[0;37mDEBUG[0m|#28457] [1m[36mProject Load (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'686f6d653a636f6f6c6f3a74657374' AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mPackage Load (0.0ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 601 AND `packages`.`name` = x'6b64656c6962735f444556454c5f7061636b616765' AND (packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mProject Load (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 601 AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mPackageUserRoleRelationship Load (0.0ms)[0m SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|#28457] [1m[36mUser Load (0.0ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`id` = 4 LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mRole Load (0.0ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mUser Load (0.0ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`id` = 42 LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mRole Load (0.0ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 6 LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#28457] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#28457] [1m[35mPackageUserRoleRelationship Load (0.0ms)[0m SELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|#28457] [1m[36mPackageGroupRoleRelationship Load (0.0ms)[0m [1mSELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|#28457] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#28457] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#28462] 2012-11-23 18:06:39 [2232]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#28457] request took 0.031752776 [[0;37mDEBUG[0m|#28457] [1m[35mPackage Load (0.9ms)[0m SELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp, IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 1 LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36m (0.7ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#28457] [1m[35mUser Load (0.5ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`login` = x'667265646c696273' LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mUser Load (0.8ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`login` = x'61647269616e' LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mPackageGroupRoleRelationship Load (0.8ms)[0m SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|#28457] [1m[36mGroup Load (1.0ms)[0m [1mSELECT `groups`.* FROM `groups` WHERE `groups`.`id` = 99 LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mCACHE (0.0ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mGroup Load (0.4ms)[0m [1mSELECT `groups`.* FROM `groups` WHERE `groups`.`title` = 'test_group' LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35m (0.5ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#28457] [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#28457] [1m[35m (0.0ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#28457] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#28457] [1m[36mCACHE (0.0ms)[0m [1mSELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mCACHE (0.0ms)[0m SELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|#28457] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#28457] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#28462] 2012-11-23 18:06:39 [2233]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#28457] request took 0.037993964 [[0;37mDEBUG[0m|#28457] [1m[36mCACHE (0.0ms)[0m [1mSELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp, IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 1 LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35m (0.9ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#28457] [1m[36m (0.3ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#28457] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#28457] [1m[35mCACHE (0.0ms)[0m SELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|#28457] [1m[36mCACHE (0.0ms)[0m [1mSELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|#28457] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#28457] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#28462] 2012-11-23 18:06:39 [2234]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#28457] request took 0.024158687 [[0;37mDEBUG[0m|#28457] [1m[35mCACHE (0.0ms)[0m SELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp, IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 1 LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36m (1.2ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#28457] Validate XML response: #<ActionDispatch::Response:0x00000007757b80> took 0ms [[32mINFO [0m|#28457] Completed 200 OK in 172ms (Views: 0.0ms | ActiveRecord: 8.4ms | Backend: 93.9ms | XML: 0.0ms) [[32mINFO [0m|#28457] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-23 18:06:39 +0100 [[32mINFO [0m|#28457] Processing by SourceController#package_meta as XML [[32mINFO [0m|#28457] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#28457] Validate XML request: #<ActionDispatch::Request:0x0000000793abc8> [[0;37mDEBUG[0m|#28457] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#28457] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#28457] [1m[35mUser Load (0.0ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#28457] USER found: king [[0;37mDEBUG[0m|#28457] User king initialised [[0;37mDEBUG[0m|#28457] get_by_project_and_name {} [[0;37mDEBUG[0m|#28457] [1m[36mRole Load (0.0ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mProject Load (0.0ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#28457] [1m[36mPackage Load (8.0ms)[0m [1mSELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b64656c696273' AND (packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#28457] [1m[35mProject Load (1.2ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#28457] Validate XML response: #<ActionDispatch::Response:0x0000000793aba0> took 232ms [[32mINFO [0m|#28457] Completed 200 OK in 258ms (Views: 0.0ms | ActiveRecord: 9.2ms | Backend: 0.0ms | XML: 0.0ms) [[0;37mDEBUG[0m|#28457] [1m[36m (5.6ms)[0m [1mROLLBACK[0m [[0;37mDEBUG[0m|#28457] kill 28462 + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/> + egrep 'perl|ruby' USER PID ACCESS COMMAND <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/>: jenkins ..c.. sh jenkins ..c.. sh jenkins ..c.. sh + 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/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets 'src/webui/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(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 2

[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui #667
by admin＠opensuse.org 24 Nov '12

24 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui/667/changes> Changes: [adrian] [backend] fix build job constraints oracle for size limits [adrian] [backend] fixing wrong reported cpu flags ------------------------------------------ [...truncated 60693 lines...] [[0;37mDEBUG[0m|# 6529] Setting return_to: "/project/buildresult?project=BaseDistro&index=" [[0;37mDEBUG[0m|# 6529] Authenticating with iChain mode: off [[32mINFO [0m|# 6529] Authenticated request to "/project/buildresult?project=BaseDistro&index=" from tom [[0;37mDEBUG[0m|# 6529] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|# 6529] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|# 6529] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|# 6529] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|# 6529] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 6529] RT http://localhost:3203/person/tom {"view"=>0.6015820000000001, "db"=>1.410663, "backend"=>0, "xml"=>0, :runtime=>24.101999999999997, :all=>28.240261999999998} [[0;37mDEBUG[0m|# 6529] returning [{:name=>"BaseDistro"}] from rails cache Project_f726314c8d941efeb9d0b888fe97b37e [[0;37mDEBUG[0m|# 6529] free_cache [{:project=>"BaseDistro", :view=>"summary"}] Buildresult_81f2400bb79dc620ddf8b0bf87e74935 [[0;37mDEBUG[0m|# 6529] [REST] find( Buildresult, [{:project=>"BaseDistro", :view=>"summary"}] ) [[0;37mDEBUG[0m|# 6529] params {:project=>"BaseDistro", :view=>"summary"} [[0;37mDEBUG[0m|# 6529] uri is: http://localhost:3203/build/:project/_result?:view&:package&:code&:lastbuil… [[0;37mDEBUG[0m|# 6529] http_do #1: method: get url: http://localhost:3203/build/BaseDistro/_result?view=summary [[0;37mDEBUG[0m|# 6529] RT http://localhost:3203/build/BaseDistro/_result?view=summary {"view"=>nil, "db"=>8.72516, "backend"=>0, "xml"=>0, :runtime=>59.677, :all=>105.4641} [[32mINFO [0m|# 6529] Rendered project/_buildstatus.html.erb (0.1ms) [[32mINFO [0m|# 6529] Completed 200 OK in 146ms (Views: 1.0ms | ActiveRecord: 0.0ms | API: 133.7ms (XML: 0.0ms , View: 0.6ms , Backend: 0.0ms , DB: 10.1ms , HTTP: 49.9ms) | XML: 0.3ms) [[32mINFO [0m|# 6529] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-23 15:43:18 +0100 [[32mINFO [0m|# 6529] Processing by ProjectController#show as HTML [[32mINFO [0m|# 6529] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|# 6529] Setting return_to: "/project/show?project=My%3AMaintenance" [[0;37mDEBUG[0m|# 6529] Authenticating with iChain mode: off [[32mINFO [0m|# 6529] Authenticated request to "/project/show?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|# 6529] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|# 6529] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|# 6529] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|# 6529] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|# 6529] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 6529] RT http://localhost:3203/person/tom {"view"=>0.692343, "db"=>11.913637000000001, "backend"=>0, "xml"=>0, :runtime=>25.796, :all=>29.519575} [[0;37mDEBUG[0m|# 6529] --> direct_http url: #<URI::Generic:0x000000074f4a50 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom> [[0;37mDEBUG[0m|# 6529] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|# 6529] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.343212, "db"=>3.3512779999999998, "backend"=>0, "xml"=>0, :runtime=>38.548, :all=>80.961272} [[0;37mDEBUG[0m|# 6529] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|# 6529] --> direct_http url: #<URI::Generic:0x000000074ff630 URL:http://localhost:3203/webui/project_infos?project=My%3AMaintenance> [[0;37mDEBUG[0m|# 6529] http_do #1: method: GET url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance [[0;37mDEBUG[0m|# 6529] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.435171, "db"=>18.217565, "backend"=>0, "xml"=>0, :runtime=>48.754, :all=>91.52398000000001} [[0;37mDEBUG[0m|# 6529] 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|# 6529] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02 [[32mINFO [0m|# 6529] Rendered project/_tabs.html.erb (3.5ms) [[32mINFO [0m|# 6529] Rendered shared/_open_requests.html.erb (0.1ms) [[32mINFO [0m|# 6529] Rendered layouts/_watch_and_search.html.erb (2.3ms) [[32mINFO [0m|# 6529] Rendered layouts/_header.html.erb (3.7ms) [[32mINFO [0m|# 6529] Rendered layouts/_personal_navigation.html.erb (3.8ms) [[32mINFO [0m|# 6529] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|# 6529] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 6529] Completed 200 OK in 235ms (Views: 26.8ms | ActiveRecord: 0.0ms | API: 202.0ms (XML: 0.0ms , View: 1.5ms , Backend: 0.0ms , DB: 33.5ms , HTTP: 88.9ms) | XML: 0.8ms) [[32mINFO [0m|# 6529] Started GET "/project/toggle_watch?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-23 15:43:19 +0100 [[32mINFO [0m|# 6529] Processing by ProjectController#toggle_watch as HTML [[32mINFO [0m|# 6529] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|# 6529] Setting return_to: "/project/toggle_watch?project=My%3AMaintenance" [[0;37mDEBUG[0m|# 6529] Authenticating with iChain mode: off [[32mINFO [0m|# 6529] Authenticated request to "/project/toggle_watch?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|# 6529] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|# 6529] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|# 6529] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|# 6529] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|# 6529] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 6529] RT http://localhost:3203/person/tom {"view"=>0.7693319999999999, "db"=>8.691322999999999, "backend"=>0, "xml"=>0, :runtime=>36.13, :all=>41.041513} [[0;37mDEBUG[0m|# 6529] --> direct_http url: #<URI::Generic:0x000000076342a8 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom> [[0;37mDEBUG[0m|# 6529] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|# 6529] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.232307, "db"=>26.636430999999998, "backend"=>0, "xml"=>0, :runtime=>60.668, :all=>103.185772} [[0;37mDEBUG[0m|# 6529] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|# 6529] returning [{:name=>"My:Maintenance"}] from rails cache Project_ce8cfb5ef2a35543461dbbb6b22579cb [[0;37mDEBUG[0m|# 6529] Remove My:Maintenance from watchlist for tom [[0;37mDEBUG[0m|# 6529] user 'tom' removes project 'My:Maintenance' from watchlist [[0;37mDEBUG[0m|# 6529] saving object Person ({:is_current=>"true", :login=>"tom"}) to api: <person> <login>tom</login> <email>tschmidt(a)suse.de</email> <realname>Thor</realname> <watchlist> </watchlist> </person> [[0;37mDEBUG[0m|# 6529] http_do #1: method: put url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 6529] RT http://localhost:3203/person/tom {"view"=>0.97594, "db"=>15.033448, "backend"=>0, "xml"=>0, :runtime=>39.381, :all=>56.716007999999995} [[32mINFO [0m|# 6529] Redirected to http://127.0.0.1:51251/project/show?project=My%3AMaintenance [[32mINFO [0m|# 6529] Completed 302 Found in 207ms (ActiveRecord: 0.0ms | API: 200.9ms (XML: 0.0ms , View: 2.0ms , Backend: 0.0ms , DB: 50.4ms , HTTP: 64.8ms) | XML: 0.3ms) [[32mINFO [0m|# 6529] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-23 15:43:19 +0100 [[32mINFO [0m|# 6529] Processing by ProjectController#show as HTML [[32mINFO [0m|# 6529] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|# 6529] Setting return_to: "/project/show?project=My%3AMaintenance" [[0;37mDEBUG[0m|# 6529] Authenticating with iChain mode: off [[32mINFO [0m|# 6529] Authenticated request to "/project/show?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|# 6529] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|# 6529] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|# 6529] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|# 6529] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|# 6529] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 6529] RT http://localhost:3203/person/tom {"view"=>0.61558, "db"=>1.164026, "backend"=>0, "xml"=>0, :runtime=>8.669, :all=>51.016628000000004} [[0;37mDEBUG[0m|# 6529] --> direct_http url: #<URI::Generic:0x000000076b5f10 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom> [[0;37mDEBUG[0m|# 6529] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|# 6529] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.536904, "db"=>22.456642000000002, "backend"=>0, "xml"=>0, :runtime=>44.775000000000006, :all=>87.701579} [[0;37mDEBUG[0m|# 6529] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|# 6529] --> direct_http url: #<URI::Generic:0x000000076ccc38 URL:http://localhost:3203/webui/project_infos?project=My%3AMaintenance> [[0;37mDEBUG[0m|# 6529] http_do #1: method: GET url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance [[0;37mDEBUG[0m|# 6529] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.0, "db"=>0.48478, "backend"=>0, "xml"=>0, :runtime=>78.05199999999999, :all=>123.033378} [[0;37mDEBUG[0m|# 6529] 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|# 6529] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02 [[32mINFO [0m|# 6529] Rendered project/_tabs.html.erb (3.1ms) [[32mINFO [0m|# 6529] Rendered shared/_open_requests.html.erb (0.0ms) [[32mINFO [0m|# 6529] Rendered layouts/_watch_and_search.html.erb (1.1ms) [[32mINFO [0m|# 6529] Rendered layouts/_header.html.erb (14.2ms) [[32mINFO [0m|# 6529] Rendered layouts/_personal_navigation.html.erb (0.0ms) [[32mINFO [0m|# 6529] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|# 6529] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 6529] Completed 200 OK in 316ms (Views: 49.1ms | ActiveRecord: 0.0ms | API: 261.8ms (XML: 0.0ms , View: 1.2ms , Backend: 0.0ms , DB: 24.1ms , HTTP: 130.3ms) | XML: 0.4ms) [[32mINFO [0m|# 6529] Started POST "/user/logout" for 127.0.0.1 at 2012-11-23 15:43:20 +0100 [[32mINFO [0m|# 6529] Processing by UserController#logout as HTML [[0;37mDEBUG[0m|# 6529] Setting return_to: "/user/logout" [[0;37mDEBUG[0m|# 6529] Authenticating with iChain mode: off [[32mINFO [0m|# 6529] Authenticated request to "/user/logout" from tom [[32mINFO [0m|# 6529] Logging out: tom [[32mINFO [0m|# 6529] Redirected to http://127.0.0.1:51251/ [[0;37mDEBUG[0m|# 6529] free_cache [{}] Person_f7754490a4ed0ddff3ff300384cc6faa [[32mINFO [0m|# 6529] Completed 302 Found in 33ms (ActiveRecord: 33.3ms | XML: 0.0ms) [[32mINFO [0m|# 6529] Started GET "/" for 127.0.0.1 at 2012-11-23 15:43:20 +0100 [[32mINFO [0m|# 6529] Processing by MainController#index as HTML [[0;37mDEBUG[0m|# 6529] Setting return_to: "/" [[0;37mDEBUG[0m|# 6529] Authenticating with iChain mode: off [[32mINFO [0m|# 6529] Anonymous request to / [[0;37mDEBUG[0m|# 6529] returning [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"}] from rails cache Statusmessage_753097322d9cc873836d791589547e90 [[0;37mDEBUG[0m|# 6529] returning [{:limit=>"6", :shared=>"true"}] from rails cache LatestUpdated_ee34770dc62e51dca62baed2d5258b33 [[0;37mDEBUG[0m|# 6529] returning [{:login=>"king"}] from rails cache Person_d11b7899dedf6f054f648e67eed36b8c [[32mINFO [0m|# 6529] Rendered shared/_user_with_realname_and_icon.html.erb (0.9ms) [[32mINFO [0m|# 6529] Rendered main/_news.html.erb (2.7ms) [[32mINFO [0m|# 6529] Rendered main/_contact.html.erb (0.0ms) [[32mINFO [0m|# 6529] Rendered main/_latest_updates.erb (135.3ms) [[32mINFO [0m|# 6529] Rendered main/_countdown.html.erb (0.0ms) [[32mINFO [0m|# 6529] Rendered main/_sponsors.html.erb (0.0ms) [[32mINFO [0m|# 6529] Rendered main/_feeds.html.erb (0.0ms) [[32mINFO [0m|# 6529] Rendered layouts/_watch_and_search.html.erb (0.3ms) [[32mINFO [0m|# 6529] Rendered layouts/_header.html.erb (2.4ms) [[32mINFO [0m|# 6529] Rendered layouts/_personal_navigation.html.erb (2.0ms) [[32mINFO [0m|# 6529] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 6529] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 6529] Completed 200 OK in 161ms (Views: 159.3ms | ActiveRecord: 0.0ms | XML: 23.5ms) [[32mINFO [0m|# 6529] Started GET "/home/icon?id=king&size=20" for 127.0.0.1 at 2012-11-23 15:43:21 +0100 [[32mINFO [0m|# 6529] Processing by HomeController#icon as HTML [[32mINFO [0m|# 6529] Parameters: {"id"=>"king", "size"=>"20"} [[0;37mDEBUG[0m|# 6529] Setting return_to: "/home/icon?id=king&size=20" [[0;37mDEBUG[0m|# 6529] Authenticating with iChain mode: off [[32mINFO [0m|# 6529] Anonymous request to /home/icon?id=king&size=20 [[32mINFO [0m|# 6529] Completed 200 OK in 1ms (Views: 0.4ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 6529] Started GET "/main/systemstatus" for 127.0.0.1 at 2012-11-23 15:43:21 +0100 [[32mINFO [0m|# 6529] Processing by MainController#systemstatus as */* [[0;37mDEBUG[0m|# 6529] Setting return_to: "/main/systemstatus" [[0;37mDEBUG[0m|# 6529] Authenticating with iChain mode: off [[32mINFO [0m|# 6529] Anonymous request to /main/systemstatus [[0;37mDEBUG[0m|# 6529] returning [{:shared=>"true"}] from rails cache GlobalCounters_2899b016f456189a42bc9c5c8fc7013a [[0;37mDEBUG[0m|# 6529] returning [:available] from rails cache Architecture_82f3aea44c00f161487e3359eb33cff4 [[32mINFO [0m|# 6529] Rendered main/_systemstatus.html.erb (1.3ms) [[32mINFO [0m|# 6529] Completed 200 OK in 4ms (Views: 2.0ms | ActiveRecord: 0.0ms | XML: 0.3ms) [[0;37mDEBUG[0m|# 6529] --> direct_http url: #<URI::Generic:0x000000068c4708 URL:http://localhost:3203/test/test_end> [[0;37mDEBUG[0m|# 6529] http_do #1: method: GET url: http://localhost:3203/test/test_end [[0;37mDEBUG[0m|# 6529] RT http://localhost:3203/test/test_end {"view"=>0.571063, "db"=>20.490128, "backend"=>0, "xml"=>0, :runtime=>23.85, :all=>27.063585} + 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/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(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 3

[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui #661
by admin＠opensuse.org 23 Nov '12

23 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui/661/changes> Changes: [adrian] [api] fix a crash in owner search, when an a role for a user or group does not exist [adrian] [api] fix permission on project copy into existing project [mls] [backend] bs_dispatch: deal with jobs that don't contain the prppgk triplet [mls] [backend] mangle overlong prps in job names, limit packid to 170 chars [Stephan Kulow] [webui] remove Transport::extract_error_message [Stephan Kulow] [webui] saving a file can cause a backend exception [Stephan Kulow] [webui] work on addition of repos [Stephan Kulow] [backend] fix perl syntax ------------------------------------------ [...truncated 69224 lines...] [[0;37mDEBUG[0m|# 7628] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|# 7628] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 7628] RT http://localhost:3203/person/tom {"view"=>7.951287, "db"=>1.458104, "backend"=>0, "xml"=>0, :runtime=>21.37, :all=>24.944452} [[0;37mDEBUG[0m|# 7628] returning [{:name=>"BaseDistro"}] from rails cache Project_f726314c8d941efeb9d0b888fe97b37e [[0;37mDEBUG[0m|# 7628] free_cache [{:project=>"BaseDistro", :view=>"summary"}] Buildresult_81f2400bb79dc620ddf8b0bf87e74935 [[0;37mDEBUG[0m|# 7628] [REST] find( Buildresult, [{:project=>"BaseDistro", :view=>"summary"}] ) [[0;37mDEBUG[0m|# 7628] params {:project=>"BaseDistro", :view=>"summary"} [[0;37mDEBUG[0m|# 7628] uri is: http://localhost:3203/build/:project/_result?:view&:package&:code&:lastbuil… [[0;37mDEBUG[0m|# 7628] http_do #1: method: get url: http://localhost:3203/build/BaseDistro/_result?view=summary [[0;37mDEBUG[0m|# 7628] RT http://localhost:3203/build/BaseDistro/_result?view=summary {"view"=>nil, "db"=>8.704271, "backend"=>0, "xml"=>0, :runtime=>59.405, :all=>100.46854900000001} [[32mINFO [0m|# 7628] Rendered project/_buildstatus.html.erb (0.1ms) [[32mINFO [0m|# 7628] Completed 200 OK in 129ms (Views: 0.8ms | ActiveRecord: 0.0ms | API: 125.4ms (XML: 0.0ms , View: 8.0ms , Backend: 0.0ms , DB: 10.2ms , HTTP: 44.6ms) | XML: 0.2ms) [[32mINFO [0m|# 7628] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-22 20:53:36 +0100 [[32mINFO [0m|# 7628] Processing by ProjectController#show as HTML [[32mINFO [0m|# 7628] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|# 7628] Setting return_to: "/project/show?project=My%3AMaintenance" [[0;37mDEBUG[0m|# 7628] Authenticating with iChain mode: off [[32mINFO [0m|# 7628] Authenticated request to "/project/show?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|# 7628] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|# 7628] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|# 7628] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|# 7628] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|# 7628] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 7628] RT http://localhost:3203/person/tom {"view"=>0.5673549999999999, "db"=>1.424312, "backend"=>0, "xml"=>0, :runtime=>14.243, :all=>17.593924} [[0;37mDEBUG[0m|# 7628] --> direct_http url: #<URI::Generic:0x007fa3782373f0 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom> [[0;37mDEBUG[0m|# 7628] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|# 7628] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.32272399999999996, "db"=>27.013009999999998, "backend"=>0, "xml"=>0, :runtime=>200.844, :all=>251.56737099999998} [[0;37mDEBUG[0m|# 7628] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|# 7628] --> direct_http url: #<URI::Generic:0x007fa378018bc8 URL:http://localhost:3203/webui/project_infos?project=My%3AMaintenance> [[0;37mDEBUG[0m|# 7628] http_do #1: method: GET url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance [[0;37mDEBUG[0m|# 7628] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.508716, "db"=>15.556285000000004, "backend"=>0, "xml"=>0, :runtime=>91.462, :all=>131.322391} [[0;37mDEBUG[0m|# 7628] 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|# 7628] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02 [[32mINFO [0m|# 7628] Rendered project/_tabs.html.erb (2.4ms) [[32mINFO [0m|# 7628] Rendered shared/_open_requests.html.erb (0.0ms) [[32mINFO [0m|# 7628] Rendered layouts/_watch_and_search.html.erb (1.3ms) [[32mINFO [0m|# 7628] Rendered layouts/_header.html.erb (2.4ms) [[32mINFO [0m|# 7628] Rendered layouts/_personal_navigation.html.erb (1.1ms) [[32mINFO [0m|# 7628] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|# 7628] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 7628] Completed 200 OK in 449ms (Views: 42.7ms | ActiveRecord: 0.0ms | API: 400.5ms (XML: 0.0ms , View: 1.4ms , Backend: 0.0ms , DB: 44.0ms , HTTP: 93.9ms) | XML: 0.5ms) [[32mINFO [0m|# 7628] Started GET "/assets/application.js" for 127.0.0.1 at 2012-11-22 20:53:36 +0100 [[32mINFO [0m|# 7628] Served asset /application.js - 304 Not Modified (1ms) [[32mINFO [0m|# 7628] Started GET "/project/toggle_watch?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-22 20:53:37 +0100 [[32mINFO [0m|# 7628] Processing by ProjectController#toggle_watch as HTML [[32mINFO [0m|# 7628] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|# 7628] Setting return_to: "/project/toggle_watch?project=My%3AMaintenance" [[0;37mDEBUG[0m|# 7628] Authenticating with iChain mode: off [[32mINFO [0m|# 7628] Authenticated request to "/project/toggle_watch?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|# 7628] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|# 7628] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|# 7628] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|# 7628] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|# 7628] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 7628] RT http://localhost:3203/person/tom {"view"=>0.6367849999999999, "db"=>10.443608000000001, "backend"=>0, "xml"=>0, :runtime=>24.563000000000002, :all=>30.056031} [[0;37mDEBUG[0m|# 7628] --> direct_http url: #<URI::Generic:0x00000006fa7430 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom> [[0;37mDEBUG[0m|# 7628] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|# 7628] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.24537899999999999, "db"=>3.2879330000000007, "backend"=>0, "xml"=>0, :runtime=>51.913000000000004, :all=>102.69568699999999} [[0;37mDEBUG[0m|# 7628] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|# 7628] returning [{:name=>"My:Maintenance"}] from rails cache Project_ce8cfb5ef2a35543461dbbb6b22579cb [[0;37mDEBUG[0m|# 7628] Remove My:Maintenance from watchlist for tom [[0;37mDEBUG[0m|# 7628] user 'tom' removes project 'My:Maintenance' from watchlist [[0;37mDEBUG[0m|# 7628] saving object Person ({:is_current=>"true", :login=>"tom"}) to api: <person> <login>tom</login> <email>tschmidt(a)suse.de</email> <realname>Thor</realname> <watchlist> </watchlist> </person> [[0;37mDEBUG[0m|# 7628] http_do #1: method: put url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 7628] RT http://localhost:3203/person/tom {"view"=>0.9923930000000001, "db"=>1.673069, "backend"=>0, "xml"=>0, :runtime=>26.199, :all=>39.440994} [[32mINFO [0m|# 7628] Redirected to http://127.0.0.1:35563/project/show?project=My%3AMaintenance [[32mINFO [0m|# 7628] Completed 302 Found in 187ms (ActiveRecord: 0.0ms | API: 172.2ms (XML: 0.0ms , View: 1.9ms , Backend: 0.0ms , DB: 15.4ms , HTTP: 69.5ms) | XML: 0.2ms) [[32mINFO [0m|# 7628] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-22 20:53:37 +0100 [[32mINFO [0m|# 7628] Processing by ProjectController#show as HTML [[32mINFO [0m|# 7628] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|# 7628] Setting return_to: "/project/show?project=My%3AMaintenance" [[0;37mDEBUG[0m|# 7628] Authenticating with iChain mode: off [[32mINFO [0m|# 7628] Authenticated request to "/project/show?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|# 7628] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|# 7628] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|# 7628] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|# 7628] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|# 7628] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 7628] RT http://localhost:3203/person/tom {"view"=>0.66906, "db"=>7.245102, "backend"=>0, "xml"=>0, :runtime=>16.026, :all=>55.05634} [[0;37mDEBUG[0m|# 7628] --> direct_http url: #<URI::Generic:0x00000006c98b18 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom> [[0;37mDEBUG[0m|# 7628] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|# 7628] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.24946000000000002, "db"=>13.242058000000002, "backend"=>0, "xml"=>0, :runtime=>48.328, :all=>87.14309800000001} [[0;37mDEBUG[0m|# 7628] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|# 7628] --> direct_http url: #<URI::Generic:0x0000000715ac50 URL:http://localhost:3203/webui/project_infos?project=My%3AMaintenance> [[0;37mDEBUG[0m|# 7628] http_do #1: method: GET url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance [[0;37mDEBUG[0m|# 7628] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.40945800000000004, "db"=>10.535893, "backend"=>0, "xml"=>0, :runtime=>51.846000000000004, :all=>91.680644} [[0;37mDEBUG[0m|# 7628] 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|# 7628] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02 [[32mINFO [0m|# 7628] Rendered project/_tabs.html.erb (2.2ms) [[32mINFO [0m|# 7628] Rendered shared/_open_requests.html.erb (0.0ms) [[32mINFO [0m|# 7628] Rendered layouts/_watch_and_search.html.erb (1.4ms) [[32mINFO [0m|# 7628] Rendered layouts/_header.html.erb (2.6ms) [[32mINFO [0m|# 7628] Rendered layouts/_personal_navigation.html.erb (1.4ms) [[32mINFO [0m|# 7628] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|# 7628] Rendered layouts/_sponsor.html.erb (0.1ms) [[32mINFO [0m|# 7628] Completed 200 OK in 271ms (Views: 31.1ms | ActiveRecord: 0.0ms | API: 233.9ms (XML: 0.0ms , View: 1.3ms , Backend: 0.0ms , DB: 31.0ms , HTTP: 117.7ms) | XML: 0.5ms) [[32mINFO [0m|# 7628] Started GET "/assets/application.js" for 127.0.0.1 at 2012-11-22 20:53:37 +0100 [[32mINFO [0m|# 7628] Served asset /application.js - 304 Not Modified (1ms) [[32mINFO [0m|# 7628] Started POST "/user/logout" for 127.0.0.1 at 2012-11-22 20:53:38 +0100 [[32mINFO [0m|# 7628] Processing by UserController#logout as HTML [[0;37mDEBUG[0m|# 7628] Setting return_to: "/user/logout" [[0;37mDEBUG[0m|# 7628] Authenticating with iChain mode: off [[32mINFO [0m|# 7628] Authenticated request to "/user/logout" from tom [[32mINFO [0m|# 7628] Logging out: tom [[32mINFO [0m|# 7628] Redirected to http://127.0.0.1:35563/ [[0;37mDEBUG[0m|# 7628] free_cache [{}] Person_f7754490a4ed0ddff3ff300384cc6faa [[32mINFO [0m|# 7628] Completed 302 Found in 42ms (ActiveRecord: 30.4ms | XML: 0.0ms) [[32mINFO [0m|# 7628] Started GET "/" for 127.0.0.1 at 2012-11-22 20:53:38 +0100 [[32mINFO [0m|# 7628] Processing by MainController#index as HTML [[0;37mDEBUG[0m|# 7628] Setting return_to: "/" [[0;37mDEBUG[0m|# 7628] Authenticating with iChain mode: off [[32mINFO [0m|# 7628] Anonymous request to / [[0;37mDEBUG[0m|# 7628] returning [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"}] from rails cache Statusmessage_753097322d9cc873836d791589547e90 [[0;37mDEBUG[0m|# 7628] returning [{:limit=>"6", :shared=>"true"}] from rails cache LatestUpdated_ee34770dc62e51dca62baed2d5258b33 [[0;37mDEBUG[0m|# 7628] returning [{:login=>"king"}] from rails cache Person_d11b7899dedf6f054f648e67eed36b8c [[32mINFO [0m|# 7628] Rendered shared/_user_with_realname_and_icon.html.erb (1.1ms) [[32mINFO [0m|# 7628] Rendered main/_news.html.erb (3.2ms) [[32mINFO [0m|# 7628] Rendered main/_contact.html.erb (0.1ms) [[32mINFO [0m|# 7628] Rendered main/_latest_updates.erb (23.1ms) [[32mINFO [0m|# 7628] Rendered main/_countdown.html.erb (0.0ms) [[32mINFO [0m|# 7628] Rendered main/_sponsors.html.erb (0.0ms) [[32mINFO [0m|# 7628] Rendered main/_feeds.html.erb (0.0ms) [[32mINFO [0m|# 7628] Rendered layouts/_watch_and_search.html.erb (0.6ms) [[32mINFO [0m|# 7628] Rendered layouts/_header.html.erb (1.3ms) [[32mINFO [0m|# 7628] Rendered layouts/_personal_navigation.html.erb (1.3ms) [[32mINFO [0m|# 7628] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|# 7628] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 7628] Completed 200 OK in 53ms (Views: 40.1ms | ActiveRecord: 0.0ms | XML: 1.1ms) [[32mINFO [0m|# 7628] Started GET "/assets/application.js" for 127.0.0.1 at 2012-11-22 20:53:38 +0100 [[32mINFO [0m|# 7628] Served asset /application.js - 304 Not Modified (1ms) [[32mINFO [0m|# 7628] Started GET "/home/icon?id=king&size=20" for 127.0.0.1 at 2012-11-22 20:53:38 +0100 [[32mINFO [0m|# 7628] Processing by HomeController#icon as HTML [[32mINFO [0m|# 7628] Parameters: {"id"=>"king", "size"=>"20"} [[0;37mDEBUG[0m|# 7628] Setting return_to: "/home/icon?id=king&size=20" [[0;37mDEBUG[0m|# 7628] Authenticating with iChain mode: off [[32mINFO [0m|# 7628] Anonymous request to /home/icon?id=king&size=20 [[32mINFO [0m|# 7628] Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 7628] Started GET "/main/systemstatus" for 127.0.0.1 at 2012-11-22 20:53:38 +0100 [[32mINFO [0m|# 7628] Processing by MainController#systemstatus as */* [[0;37mDEBUG[0m|# 7628] Setting return_to: "/main/systemstatus" [[0;37mDEBUG[0m|# 7628] Authenticating with iChain mode: off [[32mINFO [0m|# 7628] Anonymous request to /main/systemstatus [[0;37mDEBUG[0m|# 7628] returning [{:shared=>"true"}] from rails cache GlobalCounters_2899b016f456189a42bc9c5c8fc7013a [[0;37mDEBUG[0m|# 7628] returning [:available] from rails cache Architecture_82f3aea44c00f161487e3359eb33cff4 [[32mINFO [0m|# 7628] Rendered main/_systemstatus.html.erb (1.3ms) [[32mINFO [0m|# 7628] Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.0ms | XML: 0.3ms) [[0;37mDEBUG[0m|# 7628] --> direct_http url: #<URI::Generic:0x007fa3786031a0 URL:http://localhost:3203/test/test_end> [[0;37mDEBUG[0m|# 7628] http_do #1: method: GET url: http://localhost:3203/test/test_end [[0;37mDEBUG[0m|# 7628] RT http://localhost:3203/test/test_end {"view"=>1.0928330000000002, "db"=>20.52499, "backend"=>0, "xml"=>0, :runtime=>25.865, :all=>28.998953} + 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/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(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui-testsuite #471
by admin＠opensuse.org 22 Nov '12

22 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/471/changes> Changes: [mls] [backend] enforce a couple of length restrictions ------------------------------------------ [...truncated 154375 lines...] [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[32mINFO [0m|# 2410] Rendered shared/_user_with_realname_and_icon.html.erb (1.2ms) [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[32mINFO [0m|# 2410] Rendered shared/_user_with_realname_and_icon.html.erb (0.8ms) [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[32mINFO [0m|# 2410] Rendered shared/_user_with_realname_and_icon.html.erb (0.9ms) [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[32mINFO [0m|# 2410] Rendered shared/_user_with_realname_and_icon.html.erb (0.8ms) [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[32mINFO [0m|# 2410] Rendered shared/_user_with_realname_and_icon.html.erb (0.8ms) [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[32mINFO [0m|# 2410] Rendered shared/_involved_users.html.erb (282.7ms) [[32mINFO [0m|# 2410] Rendered package/users.html.erb within layouts/application (296.9ms) [[32mINFO [0m|# 2410] Rendered layouts/_watch_and_search.html.erb (0.0ms) [[32mINFO [0m|# 2410] Rendered layouts/_header.html.erb (2.1ms) [[32mINFO [0m|# 2410] Rendered layouts/_personal_navigation.html.erb (1.1ms) [[32mINFO [0m|# 2410] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|# 2410] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 2410] Completed 200 OK in 470ms (Views: 316.3ms | ActiveRecord: 0.0ms | API: 145.9ms (XML: 0.0ms , View: 0.7ms , Backend: 0.0ms , DB: 13.3ms , HTTP: 83.9ms) | XML: 137.4ms) [[32mINFO [0m|# 2410] Started GET "/package/users?package=HomePackage1&project=home%3Auser1" for 127.0.0.1 at 2012-11-22 18:14:23 +0100 [[32mINFO [0m|# 2410] Processing by PackageController#users as */* [[32mINFO [0m|# 2410] Parameters: {"package"=>"HomePackage1", "project"=>"home:user1"} [[0;37mDEBUG[0m|# 2410] Setting return_to: "/package/users?package=HomePackage1&project=home%3Auser1" [[0;37mDEBUG[0m|# 2410] Authenticating with iChain mode: off [[32mINFO [0m|# 2410] Authenticated request to "/package/users?package=HomePackage1&project=home%3Auser1" from user1 [[0;37mDEBUG[0m|# 2410] free_cache [{:is_current=>"true", :login=>"user1"}] Person_4e3d97134319e03d92019e157f7750a6 [[0;37mDEBUG[0m|# 2410] [REST] find( Person, [{:is_current=>"true", :login=>"user1"}] ) [[0;37mDEBUG[0m|# 2410] params {:is_current=>"true", :login=>"user1"} [[0;37mDEBUG[0m|# 2410] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|# 2410] http_do #1: method: get url: http://localhost:3203/person/user1 [[0;37mDEBUG[0m|# 2410] RT http://localhost:3203/person/user1 {"view"=>0.732742, "db"=>1.0128240000000002, "backend"=>0, "xml"=>0, :runtime=>42.649, :all=>46.856628} [[0;37mDEBUG[0m|# 2410] returning [{:name=>"home:user1"}] from rails cache Project_da56571d55feb22b74045d1d30c18905 [[0;37mDEBUG[0m|# 2410] returning [{:project=>"home:user1", :name=>"HomePackage1"}] from rails cache Package_27fba4179644d17d461ff22bc38e2bf9 [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from rails cache Person_d11b7899dedf6f054f648e67eed36b8c [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from rails cache Person_22fcce58e218d814ddc40dcec4621fb3 [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from rails cache Person_c0c18cc80c023eacb36358b39896704d [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from rails cache Person_7d7a59413c784b4fd8c61d8ea3ba6eed [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from rails cache Person_e811407b5c119fdc159c6d5df24845a2 [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from rails cache Person_00e5d8987db894c32ec54cb575fc38cf [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from rails cache Person_e2896a0f0a7cec5f7ca6e2ba7090b36f [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[32mINFO [0m|# 2410] Rendered package/_tabs.html.erb (4.0ms) [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[32mINFO [0m|# 2410] Rendered shared/_user_with_realname_and_icon.html.erb (1.0ms) [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"king"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[32mINFO [0m|# 2410] Rendered shared/_user_with_realname_and_icon.html.erb (0.9ms) [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user1"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[32mINFO [0m|# 2410] Rendered shared/_user_with_realname_and_icon.html.erb (0.9ms) [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user2"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[32mINFO [0m|# 2410] Rendered shared/_user_with_realname_and_icon.html.erb (1.0ms) [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user3"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[32mINFO [0m|# 2410] Rendered shared/_user_with_realname_and_icon.html.erb (1.3ms) [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user4"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[32mINFO [0m|# 2410] Rendered shared/_user_with_realname_and_icon.html.erb (0.2ms) [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user5"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[32mINFO [0m|# 2410] Rendered shared/_user_with_realname_and_icon.html.erb (3.4ms) [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[0;37mDEBUG[0m|# 2410] returning [{:login=>"user6"}] from object_cache [[32mINFO [0m|# 2410] Rendered shared/_involved_users.html.erb (266.9ms) [[32mINFO [0m|# 2410] Rendered package/users.html.erb within layouts/application (274.1ms) [[32mINFO [0m|# 2410] Rendered layouts/_watch_and_search.html.erb (0.0ms) [[32mINFO [0m|# 2410] Rendered layouts/_header.html.erb (2.3ms) [[32mINFO [0m|# 2410] Rendered layouts/_personal_navigation.html.erb (1.1ms) [[32mINFO [0m|# 2410] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|# 2410] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 2410] Completed 200 OK in 460ms (Views: 295.8ms | ActiveRecord: 0.0ms | API: 46.9ms (XML: 0.0ms , View: 0.7ms , Backend: 0.0ms , DB: 1.0ms , HTTP: 4.2ms) | XML: 227.8ms) + echo + cleanup + echo 'Killing backend processes' Killing backend processes + egrep 'perl|ruby' + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/> USER PID ACCESS COMMAND <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/>: jenkins ..c.. sh jenkins ..c.. sh jenkins ..c.. egrep jenkins ..c.. git + 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/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(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui #658
by admin＠opensuse.org 22 Nov '12

22 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui/658/changes> Changes: [Sascha Peilicke] [webui] Don't check groups in Project#user_has_role? [Sascha Peilicke] [webui] Drop request link id attribute. [Stephan Kulow] [webui] return moved_permanently for package/files ------------------------------------------ [...truncated 66335 lines...] [[0;37mDEBUG[0m|#13074] Setting return_to: "/project/buildresult?project=BaseDistro&index=" [[0;37mDEBUG[0m|#13074] Authenticating with iChain mode: off [[32mINFO [0m|#13074] Authenticated request to "/project/buildresult?project=BaseDistro&index=" from tom [[0;37mDEBUG[0m|#13074] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#13074] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#13074] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#13074] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#13074] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#13074] RT http://localhost:3203/person/tom {"view"=>0.850803, "db"=>14.791786, "backend"=>0, "xml"=>0, :runtime=>29.329, :all=>32.561692} [[0;37mDEBUG[0m|#13074] returning [{:name=>"BaseDistro"}] from rails cache Project_f726314c8d941efeb9d0b888fe97b37e [[0;37mDEBUG[0m|#13074] free_cache [{:project=>"BaseDistro", :view=>"summary"}] Buildresult_81f2400bb79dc620ddf8b0bf87e74935 [[0;37mDEBUG[0m|#13074] [REST] find( Buildresult, [{:project=>"BaseDistro", :view=>"summary"}] ) [[0;37mDEBUG[0m|#13074] params {:project=>"BaseDistro", :view=>"summary"} [[0;37mDEBUG[0m|#13074] uri is: http://localhost:3203/build/:project/_result?:view&:package&:code&:lastbuil… [[0;37mDEBUG[0m|#13074] http_do #1: method: get url: http://localhost:3203/build/BaseDistro/_result?view=summary [[0;37mDEBUG[0m|#13074] RT http://localhost:3203/build/BaseDistro/_result?view=summary {"view"=>nil, "db"=>0.0, "backend"=>0, "xml"=>0, :runtime=>56.171, :all=>113.829662} [[32mINFO [0m|#13074] Rendered project/_buildstatus.html.erb (0.0ms) [[32mINFO [0m|#13074] Completed 200 OK in 152ms (Views: 0.4ms | ActiveRecord: 0.0ms | API: 146.4ms (XML: 0.0ms , View: 0.9ms , Backend: 0.0ms , DB: 14.8ms , HTTP: 60.9ms) | XML: 0.9ms) [[32mINFO [0m|#13074] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-22 14:12:36 +0100 [[32mINFO [0m|#13074] Processing by ProjectController#show as HTML [[32mINFO [0m|#13074] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|#13074] Setting return_to: "/project/show?project=My%3AMaintenance" [[0;37mDEBUG[0m|#13074] Authenticating with iChain mode: off [[32mINFO [0m|#13074] Authenticated request to "/project/show?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|#13074] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#13074] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#13074] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#13074] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#13074] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#13074] RT http://localhost:3203/person/tom {"view"=>0.5683109999999999, "db"=>1.750643, "backend"=>0, "xml"=>0, :runtime=>13.952000000000002, :all=>17.158628} [[0;37mDEBUG[0m|#13074] --> direct_http url: #<URI::Generic:0x0000000782d4b0 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom> [[0;37mDEBUG[0m|#13074] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#13074] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.290953, "db"=>31.788738000000002, "backend"=>0, "xml"=>0, :runtime=>76.87299999999999, :all=>127.614059} [[0;37mDEBUG[0m|#13074] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#13074] --> direct_http url: #<URI::Generic:0x0000000783bc40 URL:http://localhost:3203/webui/project_infos?project=My%3AMaintenance> [[0;37mDEBUG[0m|#13074] http_do #1: method: GET url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance [[0;37mDEBUG[0m|#13074] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.432269, "db"=>17.708008000000003, "backend"=>0, "xml"=>0, :runtime=>64.103, :all=>103.291363} [[0;37mDEBUG[0m|#13074] 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|#13074] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02 [[32mINFO [0m|#13074] Rendered project/_tabs.html.erb (2.1ms) [[32mINFO [0m|#13074] Rendered shared/_open_requests.html.erb (0.0ms) [[32mINFO [0m|#13074] Rendered layouts/_watch_and_search.html.erb (1.2ms) [[32mINFO [0m|#13074] Rendered layouts/_header.html.erb (4.7ms) [[32mINFO [0m|#13074] Rendered layouts/_personal_navigation.html.erb (1.0ms) [[32mINFO [0m|#13074] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#13074] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#13074] Completed 200 OK in 268ms (Views: 15.3ms | ActiveRecord: 0.0ms | API: 248.1ms (XML: 0.0ms , View: 1.3ms , Backend: 0.0ms , DB: 51.2ms , HTTP: 93.1ms) | XML: 0.5ms) [[32mINFO [0m|#13074] Started GET "/project/toggle_watch?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-22 14:12:37 +0100 [[32mINFO [0m|#13074] Processing by ProjectController#toggle_watch as HTML [[32mINFO [0m|#13074] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|#13074] Setting return_to: "/project/toggle_watch?project=My%3AMaintenance" [[0;37mDEBUG[0m|#13074] Authenticating with iChain mode: off [[32mINFO [0m|#13074] Authenticated request to "/project/toggle_watch?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|#13074] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#13074] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#13074] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#13074] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#13074] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#13074] RT http://localhost:3203/person/tom {"view"=>0.80072, "db"=>10.585251, "backend"=>0, "xml"=>0, :runtime=>26.38, :all=>30.014617} [[0;37mDEBUG[0m|#13074] --> direct_http url: #<URI::Generic:0x0000000799ac58 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom> [[0;37mDEBUG[0m|#13074] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#13074] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.322506, "db"=>11.956762999999997, "backend"=>0, "xml"=>0, :runtime=>47.172, :all=>95.72390999999999} [[0;37mDEBUG[0m|#13074] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#13074] returning [{:name=>"My:Maintenance"}] from rails cache Project_ce8cfb5ef2a35543461dbbb6b22579cb [[0;37mDEBUG[0m|#13074] Remove My:Maintenance from watchlist for tom [[0;37mDEBUG[0m|#13074] user 'tom' removes project 'My:Maintenance' from watchlist [[0;37mDEBUG[0m|#13074] saving object Person ({:is_current=>"true", :login=>"tom"}) to api: <person> <login>tom</login> <email>tschmidt(a)suse.de</email> <realname>Thor</realname> <watchlist> </watchlist> </person> [[0;37mDEBUG[0m|#13074] http_do #1: method: put url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#13074] RT http://localhost:3203/person/tom {"view"=>1.048676, "db"=>12.106427, "backend"=>0, "xml"=>0, :runtime=>130.07399999999998, :all=>155.488404} [[32mINFO [0m|#13074] Redirected to http://127.0.0.1:52745/project/show?project=My%3AMaintenance [[32mINFO [0m|#13074] Completed 302 Found in 285ms (ActiveRecord: 0.0ms | API: 281.2ms (XML: 0.0ms , View: 2.2ms , Backend: 0.0ms , DB: 34.6ms , HTTP: 77.6ms) | XML: 0.3ms) [[32mINFO [0m|#13074] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-22 14:12:37 +0100 [[32mINFO [0m|#13074] Processing by ProjectController#show as HTML [[32mINFO [0m|#13074] Parameters: {"project"=>"My:Maintenance"} [[0;37mDEBUG[0m|#13074] Setting return_to: "/project/show?project=My%3AMaintenance" [[0;37mDEBUG[0m|#13074] Authenticating with iChain mode: off [[32mINFO [0m|#13074] Authenticated request to "/project/show?project=My%3AMaintenance" from tom [[0;37mDEBUG[0m|#13074] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83 [[0;37mDEBUG[0m|#13074] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#13074] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#13074] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#13074] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#13074] RT http://localhost:3203/person/tom {"view"=>0.580804, "db"=>5.131492, "backend"=>0, "xml"=>0, :runtime=>9.975, :all=>191.872234} [[0;37mDEBUG[0m|#13074] --> direct_http url: #<URI::Generic:0x000000079165e8 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom> [[0;37mDEBUG[0m|#13074] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#13074] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.0, "db"=>2.545848, "backend"=>0, "xml"=>0, :runtime=>42.061, :all=>47.473488} [[0;37mDEBUG[0m|#13074] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[0;37mDEBUG[0m|#13074] --> direct_http url: #<URI::Generic:0x0000000714dfa0 URL:http://localhost:3203/webui/project_infos?project=My%3AMaintenance> [[0;37mDEBUG[0m|#13074] http_do #1: method: GET url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance [[0;37mDEBUG[0m|#13074] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.0, "db"=>0.993292, "backend"=>0, "xml"=>0, :runtime=>47.993, :all=>103.70124799999999} [[0;37mDEBUG[0m|#13074] 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|#13074] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02 [[32mINFO [0m|#13074] Rendered project/_tabs.html.erb (12.5ms) [[32mINFO [0m|#13074] Rendered shared/_open_requests.html.erb (0.1ms) [[32mINFO [0m|#13074] Rendered layouts/_watch_and_search.html.erb (1.3ms) [[32mINFO [0m|#13074] Rendered layouts/_header.html.erb (2.3ms) [[32mINFO [0m|#13074] Rendered layouts/_personal_navigation.html.erb (1.4ms) [[32mINFO [0m|#13074] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#13074] Rendered layouts/_sponsor.html.erb (0.1ms) [[32mINFO [0m|#13074] Completed 200 OK in 376ms (Views: 25.9ms | ActiveRecord: 0.0ms | API: 343.0ms (XML: 0.0ms , View: 0.6ms , Backend: 0.0ms , DB: 8.7ms , HTTP: 243.0ms) | XML: 0.9ms) [[32mINFO [0m|#13074] Started POST "/user/logout" for 127.0.0.1 at 2012-11-22 14:12:38 +0100 [[32mINFO [0m|#13074] Processing by UserController#logout as HTML [[0;37mDEBUG[0m|#13074] Setting return_to: "/user/logout" [[0;37mDEBUG[0m|#13074] Authenticating with iChain mode: off [[32mINFO [0m|#13074] Authenticated request to "/user/logout" from tom [[32mINFO [0m|#13074] Logging out: tom [[32mINFO [0m|#13074] Redirected to http://127.0.0.1:52745/ [[0;37mDEBUG[0m|#13074] free_cache [{}] Person_f7754490a4ed0ddff3ff300384cc6faa [[32mINFO [0m|#13074] Completed 302 Found in 34ms (ActiveRecord: 32.4ms | XML: 0.0ms) [[32mINFO [0m|#13074] Started GET "/" for 127.0.0.1 at 2012-11-22 14:12:38 +0100 [[32mINFO [0m|#13074] Processing by MainController#index as HTML [[0;37mDEBUG[0m|#13074] Setting return_to: "/" [[0;37mDEBUG[0m|#13074] Authenticating with iChain mode: off [[32mINFO [0m|#13074] Anonymous request to / [[0;37mDEBUG[0m|#13074] returning [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"}] from rails cache Statusmessage_753097322d9cc873836d791589547e90 [[0;37mDEBUG[0m|#13074] returning [{:limit=>"6", :shared=>"true"}] from rails cache LatestUpdated_ee34770dc62e51dca62baed2d5258b33 [[0;37mDEBUG[0m|#13074] returning [{:login=>"king"}] from rails cache Person_d11b7899dedf6f054f648e67eed36b8c [[32mINFO [0m|#13074] Rendered shared/_user_with_realname_and_icon.html.erb (13.1ms) [[32mINFO [0m|#13074] Rendered main/_news.html.erb (13.3ms) [[32mINFO [0m|#13074] Rendered main/_contact.html.erb (0.0ms) [[32mINFO [0m|#13074] Rendered main/_latest_updates.erb (5.4ms) [[32mINFO [0m|#13074] Rendered main/_countdown.html.erb (0.0ms) [[32mINFO [0m|#13074] Rendered main/_sponsors.html.erb (0.1ms) [[32mINFO [0m|#13074] Rendered main/_feeds.html.erb (0.0ms) [[32mINFO [0m|#13074] Rendered layouts/_watch_and_search.html.erb (0.0ms) [[32mINFO [0m|#13074] Rendered layouts/_header.html.erb (1.2ms) [[32mINFO [0m|#13074] Rendered layouts/_personal_navigation.html.erb (1.5ms) [[32mINFO [0m|#13074] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#13074] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#13074] Completed 200 OK in 30ms (Views: 29.6ms | ActiveRecord: 0.0ms | XML: 0.1ms) [[32mINFO [0m|#13074] Started GET "/home/icon?id=king&size=20" for 127.0.0.1 at 2012-11-22 14:12:39 +0100 [[32mINFO [0m|#13074] Processing by HomeController#icon as HTML [[32mINFO [0m|#13074] Parameters: {"id"=>"king", "size"=>"20"} [[0;37mDEBUG[0m|#13074] Setting return_to: "/home/icon?id=king&size=20" [[0;37mDEBUG[0m|#13074] Authenticating with iChain mode: off [[32mINFO [0m|#13074] Anonymous request to /home/icon?id=king&size=20 [[32mINFO [0m|#13074] Completed 200 OK in 0ms (Views: 0.0ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#13074] Started GET "/main/systemstatus" for 127.0.0.1 at 2012-11-22 14:12:39 +0100 [[32mINFO [0m|#13074] Processing by MainController#systemstatus as */* [[0;37mDEBUG[0m|#13074] Setting return_to: "/main/systemstatus" [[0;37mDEBUG[0m|#13074] Authenticating with iChain mode: off [[32mINFO [0m|#13074] Anonymous request to /main/systemstatus [[0;37mDEBUG[0m|#13074] returning [{:shared=>"true"}] from rails cache GlobalCounters_2899b016f456189a42bc9c5c8fc7013a [[0;37mDEBUG[0m|#13074] returning [:available] from rails cache Architecture_82f3aea44c00f161487e3359eb33cff4 [[32mINFO [0m|#13074] Rendered main/_systemstatus.html.erb (1.4ms) [[32mINFO [0m|#13074] Completed 200 OK in 4ms (Views: 2.1ms | ActiveRecord: 0.0ms | XML: 0.4ms) [[0;37mDEBUG[0m|#13074] --> direct_http url: #<URI::Generic:0x00000006b504b8 URL:http://localhost:3203/test/test_end> [[0;37mDEBUG[0m|#13074] http_do #1: method: GET url: http://localhost:3203/test/test_end [[0;37mDEBUG[0m|#13074] RT http://localhost:3203/test/test_end {"view"=>0.0, "db"=>10.456374, "backend"=>0, "xml"=>0, :runtime=>11.251000000000001, :all=>17.068421} + 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.. sh + 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(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1