November 2012 - Open Build Service Tests

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

16 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui/624/changes> Changes: [Stephan Kulow] [webui] fixing requests loading for projects ------------------------------------------ [...truncated 41180 lines...] [[0;37mDEBUG[0m|# 2161] http_do #1: method: get url: http://localhost:3203/person/unknown [[0;37mDEBUG[0m|# 2161] RT http://localhost:3203/person/unknown {"view"=>0.731048, "db"=>1.210372, "backend"=>0, "xml"=>0, :runtime=>7.796, :all=>16.255803999999998} [[0;37mDEBUG[0m|# 2161] Person.find( {:login=>"unknown"} ) did not find anything, return nil [[32mINFO [0m|# 2161] Rendered package/_commit_item.html.erb (212.0ms) [[32mINFO [0m|# 2161] Rendered package/_files_view.html.erb (216.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_watch_and_search.html.erb (1.1ms) [[32mINFO [0m|# 2161] Rendered layouts/_header.html.erb (1.8ms) [[32mINFO [0m|# 2161] Rendered layouts/_personal_navigation.html.erb (1.5ms) [[32mINFO [0m|# 2161] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 2161] Completed 200 OK in 604ms (Views: 285.6ms | ActiveRecord: 0.0ms | API: 577.5ms (XML: 0.0ms , View: 1.3ms , Backend: 0.0ms , DB: 26.4ms , HTTP: 309.1ms) | XML: 0.8ms) [[32mINFO [0m|# 2161] Started GET "/package/view_file?expand=0&file=_link&package=pack2_linked&project=BaseDistro2.0" for 127.0.0.1 at 2012-11-16 16:15:18 +0100 [[32mINFO [0m|# 2161] Processing by PackageController#view_file as HTML [[32mINFO [0m|# 2161] Parameters: {"expand"=>"0", "file"=>"_link", "package"=>"pack2_linked", "project"=>"BaseDistro2.0"} [[0;37mDEBUG[0m|# 2161] Setting return_to: "/package/view_file?expand=0&file=_link&package=pack2_linked&project=BaseDistro2.0" [[0;37mDEBUG[0m|# 2161] Authenticating with iChain mode: off [[32mINFO [0m|# 2161] Anonymous request to /package/view_file?expand=0&file=_link&package=pack2_linked&project=BaseDistro2.0 [[0;37mDEBUG[0m|# 2161] --> get_source: {:project=>"BaseDistro2.0", :package=>"pack2_linked", :filename=>"_link", :rev=>nil, :expand=>"0"} [[0;37mDEBUG[0m|# 2161] --> get_source path: /source/BaseDistro2.0/pack2_linked/_link?expand=0 [[0;37mDEBUG[0m|# 2161] --> direct_http url: #<URI::Generic:0x000000062f7708 URL:http://localhost:3203/source/BaseDistro2.0/pack2_linked/_link?expand=0> [[0;37mDEBUG[0m|# 2161] http_do #1: method: GET url: http://localhost:3203/source/BaseDistro2.0/pack2_linked/_link?expand=0 [[0;37mDEBUG[0m|# 2161] RT http://localhost:3203/source/BaseDistro2.0/pack2_linked/_link?expand=0 {"view"=>nil, "db"=>3.9460580000000003, "backend"=>0, "xml"=>0, :runtime=>40.004999999999995, :all=>80.68339900000001} [[32mINFO [0m|# 2161] Rendered package/_tabs.html.erb (4.0ms) [[32mINFO [0m|# 2161] Rendered shared/_editor.html.erb (8.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_watch_and_search.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_header.html.erb (2.2ms) [[32mINFO [0m|# 2161] Rendered layouts/_personal_navigation.html.erb (0.5ms) [[32mINFO [0m|# 2161] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 2161] Completed 200 OK in 116ms (Views: 22.4ms | ActiveRecord: 0.0ms | API: 80.7ms (XML: 0.0ms , Backend: 0.0ms , DB: 3.9ms , HTTP: 40.7ms) | XML: 0.8ms) [[32mINFO [0m|# 2161] Started GET "/package/view_file?file=my_file&package=bdpack&project=BinaryprotectedProject" for 127.0.0.1 at 2012-11-16 16:15:18 +0100 [[32mINFO [0m|# 2161] Processing by PackageController#view_file as HTML [[32mINFO [0m|# 2161] Parameters: {"file"=>"my_file", "package"=>"bdpack", "project"=>"BinaryprotectedProject"} [[0;37mDEBUG[0m|# 2161] Setting return_to: "/package/view_file?file=my_file&package=bdpack&project=BinaryprotectedProject" [[0;37mDEBUG[0m|# 2161] Authenticating with iChain mode: off [[32mINFO [0m|# 2161] Anonymous request to /package/view_file?file=my_file&package=bdpack&project=BinaryprotectedProject [[0;37mDEBUG[0m|# 2161] --> get_source: {:project=>"BinaryprotectedProject", :package=>"bdpack", :filename=>"my_file", :rev=>nil, :expand=>nil} [[0;37mDEBUG[0m|# 2161] --> get_source path: /source/BinaryprotectedProject/bdpack/my_file? [[0;37mDEBUG[0m|# 2161] --> direct_http url: #<URI::Generic:0x00000005b5b440 URL:http://localhost:3203/source/BinaryprotectedProject/bdpack/my_file?> [[0;37mDEBUG[0m|# 2161] http_do #1: method: GET url: http://localhost:3203/source/BinaryprotectedProject/bdpack/my_file? [[0;37mDEBUG[0m|# 2161] RT http://localhost:3203/source/BinaryprotectedProject/bdpack/my_file? {"view"=>nil, "db"=>8.499848, "backend"=>0, "xml"=>0, :runtime=>32.991, :all=>34.098286} [[32mINFO [0m|# 2161] Rendered package/_tabs.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered shared/_editor.html.erb (3.9ms) [[32mINFO [0m|# 2161] Rendered layouts/_watch_and_search.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_header.html.erb (14.1ms) [[32mINFO [0m|# 2161] Rendered layouts/_personal_navigation.html.erb (1.6ms) [[32mINFO [0m|# 2161] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 2161] Completed 200 OK in 61ms (Views: 23.9ms | ActiveRecord: 0.0ms | API: 34.1ms (XML: 0.0ms , Backend: 0.0ms , DB: 8.5ms , HTTP: 1.1ms) | XML: 0.0ms) [[32mINFO [0m|# 2161] Started GET "/package/binaries?arch=i586&package=pack&project=SourceprotectedProject&repository=repo" for 127.0.0.1 at 2012-11-16 16:15:18 +0100 [[32mINFO [0m|# 2161] Processing by PackageController#binaries as HTML [[32mINFO [0m|# 2161] Parameters: {"arch"=>"i586", "package"=>"pack", "project"=>"SourceprotectedProject", "repository"=>"repo"} [[0;37mDEBUG[0m|# 2161] Setting return_to: "/package/binaries?arch=i586&package=pack&project=SourceprotectedProject&repository=repo" [[0;37mDEBUG[0m|# 2161] Authenticating with iChain mode: off [[32mINFO [0m|# 2161] Anonymous request to /package/binaries?arch=i586&package=pack&project=SourceprotectedProject&repository=repo [[32mINFO [0m|# 2161] Rendered package/_tabs.html.erb (3.1ms) [[32mINFO [0m|# 2161] Rendered shared/_download_repository_link.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_watch_and_search.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_header.html.erb (3.6ms) [[32mINFO [0m|# 2161] Rendered layouts/_personal_navigation.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 2161] Completed 200 OK in 28ms (Views: 27.8ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 2161] Started GET "/package/binary?arch=i586&filename=delete_me-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" for 127.0.0.1 at 2012-11-16 16:15:18 +0100 [[32mINFO [0m|# 2161] Processing by PackageController#binary as HTML [[32mINFO [0m|# 2161] Parameters: {"arch"=>"i586", "filename"=>"delete_me-1.0-1.i586.rpm", "package"=>"aggregate", "project"=>"home:adrian:ProtectionTest", "repository"=>"repo"} [[0;37mDEBUG[0m|# 2161] Setting return_to: "/package/binary?arch=i586&filename=delete_me-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" [[0;37mDEBUG[0m|# 2161] Authenticating with iChain mode: off [[32mINFO [0m|# 2161] Anonymous request to /package/binary?arch=i586&filename=delete_me-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo [[0;37mDEBUG[0m|# 2161] [REST] find( Fileinfo, [{:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :repository=>"repo", :arch=>"i586", :filename=>"delete_me-1.0-1.i586.rpm", :view=>"fileinfo_ext"}] ) [[0;37mDEBUG[0m|# 2161] params {:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :repository=>"repo", :arch=>"i586", :filename=>"delete_me-1.0-1.i586.rpm", :view=>"fileinfo_ext"} [[0;37mDEBUG[0m|# 2161] uri is: http://localhost:3203/build/:project/:repository/:arch/:package/:filename?:… [[0;37mDEBUG[0m|# 2161] http_do #1: method: get url: http://localhost:3203/build/home:adrian:ProtectionTest/repo/i586/aggregate/… [[0;37mDEBUG[0m|# 2161] RT http://localhost:3203/build/home:adrian:ProtectionTest/repo/i586/aggregate/… {"view"=>nil, "db"=>9.828966, "backend"=>0, "xml"=>0, :runtime=>104.15299999999999, :all=>106.174605} [[0;37mDEBUG[0m|# 2161] Checking url: http://download.opensuse.org/repositories/home:/adrian:/ProtectionTest/repo… [[0;37mDEBUG[0m|# 2161] accepting format:text/html [[32mINFO [0m|# 2161] Rendered package/_tabs.html.erb (4.0ms) [[32mINFO [0m|# 2161] Rendered package/_deps.html.erb (0.5ms) [[32mINFO [0m|# 2161] Rendered layouts/_watch_and_search.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_header.html.erb (2.3ms) [[32mINFO [0m|# 2161] Rendered layouts/_personal_navigation.html.erb (0.9ms) [[32mINFO [0m|# 2161] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 2161] Completed 200 OK in 161ms (Views: 33.6ms | ActiveRecord: 0.0ms | API: 106.2ms (XML: 0.0ms , Backend: 0.0ms , DB: 9.8ms , HTTP: 2.0ms) | XML: 0.7ms) [[32mINFO [0m|# 2161] Started GET "/package/binary?arch=i586&filename=package-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" for 127.0.0.1 at 2012-11-16 16:15:18 +0100 [[32mINFO [0m|# 2161] Processing by PackageController#binary as HTML [[32mINFO [0m|# 2161] Parameters: {"arch"=>"i586", "filename"=>"package-1.0-1.i586.rpm", "package"=>"aggregate", "project"=>"home:adrian:ProtectionTest", "repository"=>"repo"} [[0;37mDEBUG[0m|# 2161] Setting return_to: "/package/binary?arch=i586&filename=package-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" [[0;37mDEBUG[0m|# 2161] Authenticating with iChain mode: off [[32mINFO [0m|# 2161] Anonymous request to /package/binary?arch=i586&filename=package-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo [[0;37mDEBUG[0m|# 2161] [REST] find( Fileinfo, [{:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :repository=>"repo", :arch=>"i586", :filename=>"package-1.0-1.i586.rpm", :view=>"fileinfo_ext"}] ) [[0;37mDEBUG[0m|# 2161] params {:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :repository=>"repo", :arch=>"i586", :filename=>"package-1.0-1.i586.rpm", :view=>"fileinfo_ext"} [[0;37mDEBUG[0m|# 2161] uri is: http://localhost:3203/build/:project/:repository/:arch/:package/:filename?:… [[0;37mDEBUG[0m|# 2161] http_do #1: method: get url: http://localhost:3203/build/home:adrian:ProtectionTest/repo/i586/aggregate/… [[0;37mDEBUG[0m|# 2161] RT http://localhost:3203/build/home:adrian:ProtectionTest/repo/i586/aggregate/… {"view"=>nil, "db"=>5.298034, "backend"=>0, "xml"=>0, :runtime=>221.395, :all=>223.64100200000001} [[0;37mDEBUG[0m|# 2161] Checking url: http://download.opensuse.org/repositories/home:/adrian:/ProtectionTest/repo… [[0;37mDEBUG[0m|# 2161] accepting format:text/html [[32mINFO [0m|# 2161] Rendered package/_tabs.html.erb (12.0ms) [[32mINFO [0m|# 2161] Rendered package/_deps.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_watch_and_search.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_header.html.erb (2.3ms) [[32mINFO [0m|# 2161] Rendered layouts/_personal_navigation.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 2161] Completed 200 OK in 267ms (Views: 24.4ms | ActiveRecord: 0.0ms | API: 223.6ms (XML: 0.0ms , Backend: 0.0ms , DB: 5.3ms , HTTP: 2.2ms) | XML: 0.0ms) [[32mINFO [0m|# 2161] Started GET "/package/view_file?file=_aggregate&package=aggregate&project=home%3Aadrian%3AProtectionTest" for 127.0.0.1 at 2012-11-16 16:15:19 +0100 [[32mINFO [0m|# 2161] Processing by PackageController#view_file as HTML [[32mINFO [0m|# 2161] Parameters: {"file"=>"_aggregate", "package"=>"aggregate", "project"=>"home:adrian:ProtectionTest"} [[0;37mDEBUG[0m|# 2161] Setting return_to: "/package/view_file?file=_aggregate&package=aggregate&project=home%3Aadrian%3AProtectionTest" [[0;37mDEBUG[0m|# 2161] Authenticating with iChain mode: off [[32mINFO [0m|# 2161] Anonymous request to /package/view_file?file=_aggregate&package=aggregate&project=home%3Aadrian%3AProtectionTest [[0;37mDEBUG[0m|# 2161] --> get_source: {:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :filename=>"_aggregate", :rev=>nil, :expand=>nil} [[0;37mDEBUG[0m|# 2161] --> get_source path: /source/home:adrian:ProtectionTest/aggregate/_aggregate? [[0;37mDEBUG[0m|# 2161] --> direct_http url: #<URI::Generic:0x00000007547408 URL:http://localhost:3203/source/home:adrian:ProtectionTest/aggregate/_aggregate?> [[0;37mDEBUG[0m|# 2161] http_do #1: method: GET url: http://localhost:3203/source/home:adrian:ProtectionTest/aggregate/_aggregat… [[0;37mDEBUG[0m|# 2161] RT http://localhost:3203/source/home:adrian:ProtectionTest/aggregate/_aggregat… {"view"=>nil, "db"=>11.43297, "backend"=>0, "xml"=>0, :runtime=>45.368, :all=>47.026678000000004} [[32mINFO [0m|# 2161] Rendered package/_tabs.html.erb (3.4ms) [[32mINFO [0m|# 2161] Rendered shared/_editor.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_watch_and_search.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_header.html.erb (1.5ms) [[32mINFO [0m|# 2161] Rendered layouts/_personal_navigation.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 2161] Completed 200 OK in 61ms (Views: 11.5ms | ActiveRecord: 0.0ms | API: 47.0ms (XML: 0.0ms , Backend: 0.0ms , DB: 11.4ms , HTTP: 1.7ms) | XML: 0.4ms) [[32mINFO [0m|# 2161] Started GET "/package/binaries?arch=i586&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" for 127.0.0.1 at 2012-11-16 16:15:19 +0100 [[32mINFO [0m|# 2161] Processing by PackageController#binaries as HTML [[32mINFO [0m|# 2161] Parameters: {"arch"=>"i586", "package"=>"aggregate", "project"=>"home:adrian:ProtectionTest", "repository"=>"repo"} [[0;37mDEBUG[0m|# 2161] Setting return_to: "/package/binaries?arch=i586&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" [[0;37mDEBUG[0m|# 2161] Authenticating with iChain mode: off [[32mINFO [0m|# 2161] Anonymous request to /package/binaries?arch=i586&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo [[32mINFO [0m|# 2161] Rendered package/_tabs.html.erb (12.0ms) [[32mINFO [0m|# 2161] Rendered shared/_download_repository_link.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_watch_and_search.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_header.html.erb (2.3ms) [[32mINFO [0m|# 2161] Rendered layouts/_personal_navigation.html.erb (1.5ms) [[32mINFO [0m|# 2161] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 2161] Completed 200 OK in 23ms (Views: 22.7ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 2161] Started POST "/user/logout" for 127.0.0.1 at 2012-11-16 16:15:19 +0100 [[32mINFO [0m|# 2161] Processing by UserController#logout as HTML [[0;37mDEBUG[0m|# 2161] Setting return_to: "/user/logout" [[0;37mDEBUG[0m|# 2161] Authenticating with iChain mode: off [[32mINFO [0m|# 2161] Anonymous request to /user/logout [[32mINFO [0m|# 2161] Logging out: [[32mINFO [0m|# 2161] Redirected to http://example.org/ [[32mINFO [0m|# 2161] Completed 302 Found in 12ms (ActiveRecord: 0.0ms | XML: 0.0ms) [[0;37mDEBUG[0m|# 2161] [1m[35m (2.3ms)[0m rollback transaction [[0;37mDEBUG[0m|# 2161] [1m[36m (0.0ms)[0m [1mbegin transaction[0m [[32mINFO [0m|# 2161] Started POST "/user/do_login" for 127.0.0.1 at 2012-11-16 16:15:19 +0100 [[32mINFO [0m|# 2161] Processing by UserController#do_login as HTML [[32mINFO [0m|# 2161] Parameters: {"username"=>"tom", "password"=>"[FILTERED]", "return_to_path"=>"/"} [[0;37mDEBUG[0m|# 2161] Setting return_to: "/" [[0;37mDEBUG[0m|# 2161] Authenticating with iChain mode: off [[32mINFO [0m|# 2161] Anonymous request to / [[0;37mDEBUG[0m|# 2161] Doing form authorization to login user tom [[0;37mDEBUG[0m|# 2161] [REST] find( Person, [{:login=>"tom"}] ) [[0;37mDEBUG[0m|# 2161] params {:login=>"tom"} [[0;37mDEBUG[0m|# 2161] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|# 2161] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 2161] RT http://localhost:3203/person/tom {"view"=>0.538462, "db"=>6.251407, "backend"=>0, "xml"=>0, :runtime=>34.863, :all=>39.533693} [[32mINFO [0m|# 2161] Redirected to http://example.org/ [[32mINFO [0m|# 2161] Completed 302 Found in 41ms (ActiveRecord: 0.0ms | API: 39.5ms (XML: 0.0ms , View: 0.5ms , Backend: 0.0ms , DB: 6.3ms , HTTP: 4.7ms) | XML: 0.0ms) [[32mINFO [0m|# 2161] Started GET "/" for 127.0.0.1 at 2012-11-16 16:15:19 +0100 [[32mINFO [0m|# 2161] Processing by MainController#index as HTML [[0;37mDEBUG[0m|# 2161] Setting return_to: "/" [[0;37mDEBUG[0m|# 2161] Authenticating with iChain mode: off [[32mINFO [0m|# 2161] Authenticated request to "/" from tom [[0;37mDEBUG[0m|# 2161] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|# 2161] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|# 2161] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|# 2161] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|# 2161] RT http://localhost:3203/person/tom {"view"=>0.5157780000000001, "db"=>0.914131, "backend"=>0, "xml"=>0, :runtime=>9.350000000000001, :all=>50.926308000000006} [[0;37mDEBUG[0m|# 2161] --> direct_http url: #<URI::Generic:0x00000005b3f100 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom> [[0;37mDEBUG[0m|# 2161] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|# 2161] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.245724, "db"=>3.2122309999999996, "backend"=>0, "xml"=>0, :runtime=>46.122, :all=>87.27391700000001} [[0;37mDEBUG[0m|# 2161] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[32mINFO [0m|# 2161] Rendered main/_news.html.erb (0.6ms) [[32mINFO [0m|# 2161] Rendered main/_contact.html.erb (0.1ms) [[32mINFO [0m|# 2161] Rendered main/_latest_updates.erb (15.3ms) [[32mINFO [0m|# 2161] Rendered main/_countdown.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered main/_sponsors.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered main/_feeds.html.erb (0.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_watch_and_search.html.erb (0.8ms) [[32mINFO [0m|# 2161] Rendered layouts/_header.html.erb (1.6ms) [[32mINFO [0m|# 2161] Rendered layouts/_personal_navigation.html.erb (1.0ms) [[32mINFO [0m|# 2161] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|# 2161] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 2161] Completed 200 OK in 178ms (Views: 34.9ms | ActiveRecord: 0.0ms | API: 138.2ms (XML: 0.0ms , View: 0.8ms , Backend: 0.0ms , DB: 4.1ms , HTTP: 82.7ms) | XML: 0.9ms) [[32mINFO [0m|# 2161] Started POST "/user/logout" for 127.0.0.1 at 2012-11-16 16:15:19 +0100 [[32mINFO [0m|# 2161] Processing by UserController#logout as HTML [[0;37mDEBUG[0m|# 2161] Setting return_to: "/user/logout" [[0;37mDEBUG[0m|# 2161] Authenticating with iChain mode: off [[32mINFO [0m|# 2161] Authenticated request to "/user/logout" from tom [[32mINFO [0m|# 2161] Logging out: tom [[32mINFO [0m|# 2161] Redirected to http://example.org/ [[32mINFO [0m|# 2161] Completed 302 Found in 2ms (ActiveRecord: 0.2ms | XML: 0.0ms) [[0;37mDEBUG[0m|# 2161] [1m[35m (0.6ms)[0m rollback transaction + 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/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_api #1184
by admin＠opensuse.org 16 Nov '12

16 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_api/1184/changes> Changes: [adrian] [api] add OBS:Maintainer attribute [adrian] [dist] Logrotate will pickup all Apache logfiles [adrian] [dist] Fix obsstoragesetup for use_obs_vg and empty VG OBS. Fixes openSUSE/open-build-service#85. [adrian] [dist] worker doesn't make use of swap if chroot is used ------------------------------------------ [...truncated 135152 lines...] [[0;37mDEBUG[0m|#29194] [1m[35mUser Load (0.0ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#29194] USER found: king [[0;37mDEBUG[0m|#29194] User king initialised [[0;37mDEBUG[0m|#29194] get_by_project_and_name {} [[0;37mDEBUG[0m|#29194] [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|#29194] [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|#29194] [1m[36mPackage Load (1.3ms)[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|#29194] [1m[35mProject Load (0.0ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#29194] Validate XML response: #<ActionDispatch::Response:0x000000065a87e0> took 1ms [[32mINFO [0m|#29194] Completed 200 OK in 13ms (Views: 0.5ms | ActiveRecord: 1.3ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#29194] Started PUT "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-11-16 14:57:57 +0100 [[32mINFO [0m|#29194] Processing by SourceController#package_meta as XML [[32mINFO [0m|#29194] Parameters: {"<package name"=>"\"kdebase\" project=\"kde4\">\n <title>blub</title>\n <description>blub</description>\n</package>\n", "project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|#29194] Validate XML request: #<ActionDispatch::Request:0x0000000680ef98> [[0;37mDEBUG[0m|#29194] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#29194] [1m[36mUser Load (0.6ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#29194] USER found: king [[0;37mDEBUG[0m|#29194] User king initialised [[0;37mDEBUG[0m|#29194] [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|#29194] [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|#29194] [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|#29194] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#29194] get_by_project_and_name {} [[0;37mDEBUG[0m|#29194] [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|#29194] [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|#29194] [1m[35mCACHE (0.0ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#29194] [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|#29194] [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|#29194] [1m[36mFlag Load (0.0ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position[0m [[0;37mDEBUG[0m|#29194] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|#29194] [1m[35mFlag Load (0.0ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 [[0;37mDEBUG[0m|#29194] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|#29194] xml_disabled? X({"name"=>"kdebase", "project"=>"kde4", "title"=>"blub", "description"=>"blub"}) [[0;37mDEBUG[0m|#29194] [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|#29194] [1m[35m (0.0ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#29194] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#29194] [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|#29194] [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|#29194] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#29194] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#29199] 2012-11-16 14:57:57 [2609]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#29194] request took 0.026481687 [[0;37mDEBUG[0m|#29194] [1m[36mPackage Load (0.6ms)[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|#29194] [1m[35m (0.3ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#29194] [1m[36mPackageGroupRoleRelationship Load (0.0ms)[0m [1mSELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|#29194] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#29194] [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#29194] [1m[35m (0.0ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#29194] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#29194] [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|#29194] [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|#29194] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#29194] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#29199] 2012-11-16 14:57:57 [2610]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#29194] request took 0.043629712 [[0;37mDEBUG[0m|#29194] [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|#29194] [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#29194] [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#29194] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#29194] [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|#29194] [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|#29194] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#29194] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#29199] 2012-11-16 14:57:57 [2611]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#29194] request took 0.039488705 [[0;37mDEBUG[0m|#29194] [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|#29194] [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#29194] Validate XML response: #<ActionDispatch::Response:0x0000000680ef20> took 0ms [[32mINFO [0m|#29194] Completed 200 OK in 171ms (Views: 0.0ms | ActiveRecord: 1.8ms | Backend: 109.6ms | XML: 0.0ms) [[32mINFO [0m|#29194] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-11-16 14:57:57 +0100 [[32mINFO [0m|#29194] Processing by SourceController#package_meta as XML [[32mINFO [0m|#29194] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|#29194] Validate XML request: #<ActionDispatch::Request:0x00000006e21c00> [[0;37mDEBUG[0m|#29194] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#29194] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#29194] [1m[35mUser Load (0.0ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#29194] USER found: king [[0;37mDEBUG[0m|#29194] User king initialised [[0;37mDEBUG[0m|#29194] get_by_project_and_name {} [[0;37mDEBUG[0m|#29194] [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|#29194] [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|#29194] [1m[36mPackage Load (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|#29194] [1m[35mProject Load (0.0ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#29194] Validate XML response: #<ActionDispatch::Response:0x00000006e21bd8> took 1ms [[32mINFO [0m|#29194] Completed 200 OK in 10ms (Views: 0.2ms | ActiveRecord: 0.0ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#29194] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-16 14:57:57 +0100 [[32mINFO [0m|#29194] Processing by SourceController#package_meta as XML [[32mINFO [0m|#29194] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#29194] Validate XML request: #<ActionDispatch::Request:0x00000006eb85d8> [[0;37mDEBUG[0m|#29194] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#29194] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#29194] [1m[36mUser Load (0.7ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#29194] USER found: king [[0;37mDEBUG[0m|#29194] User king initialised [[0;37mDEBUG[0m|#29194] get_by_project_and_name {} [[0;37mDEBUG[0m|#29194] [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|#29194] [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|#29194] [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|#29194] [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|#29194] Validate XML response: #<ActionDispatch::Response:0x00000006eb8588> took 24ms [[32mINFO [0m|#29194] Completed 200 OK in 30ms (Views: 0.0ms | ActiveRecord: 0.7ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#29194] Started PUT "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-16 14:57:57 +0100 [[32mINFO [0m|#29194] Processing by SourceController#package_meta as XML [[32mINFO [0m|#29194] Parameters: {"<package name"=>"\"kdelibs\" project=\"kde4\">\n <title>blub</title>\n <description>Changed description</description>\n <devel project=\"home:Iggy\" package=\"TestPack\"/>\n <person userid=\"fredlibs\" role=\"maintainer\"/>\n <person userid=\"adrian\" role=\"reviewer\"/>\n <person userid=\"Iggy\" role=\"maintainer\"/>\n <person userid=\"Iggy\" role=\"bugowner\"/>\n <group groupid=\"test_group\" role=\"maintainer\"/>\n <group groupid=\"test_group\" role=\"reader\"/>\n <build>\n <enable/>\n </build>\n</package>\n", "project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#29194] Validate XML request: #<ActionDispatch::Request:0x00000006c785c0> [[0;37mDEBUG[0m|#29194] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#29194] [1m[35mUser Load (0.0ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#29194] USER found: king [[0;37mDEBUG[0m|#29194] User king initialised [[0;37mDEBUG[0m|#29194] [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|#29194] [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|#29194] [1m[36mPackage Load (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|#29194] [1m[35mProject Load (0.0ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#29194] get_by_project_and_name {} [[0;37mDEBUG[0m|#29194] [1m[36mCACHE (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#29194] [1m[35mCACHE (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|#29194] [1m[36mCACHE (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#29194] [1m[35mFlag Load (0.0ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1 [[0;37mDEBUG[0m|#29194] [1m[36mFlag Load (0.0ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1[0m [[0;37mDEBUG[0m|#29194] [1m[35mFlag Load (0.0ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position [[0;37mDEBUG[0m|#29194] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|#29194] [1m[36mFlag Load (0.0ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2[0m [[0;37mDEBUG[0m|#29194] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|#29194] xml_disabled? X({"name"=>"kdelibs", "project"=>"kde4", "title"=>"blub", "description"=>"Changed description", "devel"=>X({"project"=>"home:Iggy", "package"=>"TestPack"}), "person"=>[X({"userid"=>"fredlibs", "role"=>"maintainer"}), X({"userid"=>"adrian", "role"=>"reviewer"}), X({"userid"=>"Iggy", "role"=>"maintainer"}), X({"userid"=>"Iggy", "role"=>"bugowner"})], "group"=>[X({"groupid"=>"test_group", "role"=>"maintainer"}), X({"groupid"=>"test_group", "role"=>"reader"})], "build"=>X({"enable"=>X({})})}) [[0;37mDEBUG[0m|#29194] [1m[35mProject Load (0.0ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'686f6d653a49676779' AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#29194] [1m[36mPackage Load (0.0ms)[0m [1mSELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 502 AND `packages`.`name` = x'546573745061636b' AND (packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#29194] [1m[35mProject Load (1.3ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 502 AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#29194] [1m[36mPackageUserRoleRelationship Load (0.0ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|#29194] [1m[35mUser Load (0.0ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`id` = 4 LIMIT 1 [[0;37mDEBUG[0m|#29194] [1m[36mRole Load (0.0ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1[0m [[0;37mDEBUG[0m|#29194] [1m[35mUser Load (0.0ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`id` = 42 LIMIT 1 [[0;37mDEBUG[0m|#29194] [1m[36mRole Load (0.0ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 6 LIMIT 1[0m [[0;37mDEBUG[0m|#29194] [1m[35m (0.0ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#29194] [1m[36m (0.0ms)[0m [1mUPDATE `packages` SET `description` = 'Changed description', `develpackage_id` = 10095, `updated_at` = '2012-11-16 13:57:57' WHERE `packages`.`id` = 1[0m [[0;37mDEBUG[0m|#29194] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#29194] [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|#29194] [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|#29194] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#29194] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#29199] 2012-11-16 14:57:57 [2612]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#29199] 2012-11-16 14:57:57 [2613]: POST /event?type=package&project=kde4&package=kdelibs [[0;37mDEBUG[0m|#29194] request took 0.080159109 [[0;37mDEBUG[0m|#29194] [1m[35mPackage Load (7.3ms)[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|#29194] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#29194] [1m[35mUser Load (0.3ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1 [[0;37mDEBUG[0m|#29194] [1m[36m (0.2ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#29194] [1m[35mPackageUserRoleRelationship Load (0.2ms)[0m SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 3 AND bs_user_id = 267) LIMIT 1 [[0;37mDEBUG[0m|#29194] [1m[36mSQL (0.2ms)[0m [1mINSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 3)[0m [[0;37mDEBUG[0m|#29194] [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#29194] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#29194] [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#29194] [1m[36mUser Load (0.1ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1[0m [[0;37mDEBUG[0m|#29194] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#29194] [1m[36mPackageUserRoleRelationship Load (0.1ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 4 AND bs_user_id = 267) LIMIT 1[0m [[0;37mDEBUG[0m|#29194] [1m[35mSQL (0.1ms)[0m INSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 4) [[0;37mDEBUG[0m|#29194] [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#29194] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#29194] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#29194] [1m[35mPackageGroupRoleRelationship Load (0.2ms)[0m SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|#29194] [1m[36mGroup Load (0.1ms)[0m [1mSELECT `groups`.* FROM `groups` WHERE `groups`.`id` = 99 LIMIT 1[0m [[0;37mDEBUG[0m|#29194] [1m[35mRole Load (0.1ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1 [[0;37mDEBUG[0m|#29194] [1m[36mGroup Load (0.2ms)[0m [1mSELECT `groups`.* FROM `groups` WHERE `groups`.`title` = 'test_group' LIMIT 1[0m [[0;37mDEBUG[0m|#29194] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#29194] [1m[36mPackageGroupRoleRelationship Load (0.1ms)[0m [1mSELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE (db_package_id = 1 AND role_id = 7 AND bs_group_id = 99) LIMIT 1[0m [[0;37mDEBUG[0m|#29194] [1m[35mSQL (0.1ms)[0m INSERT INTO `package_group_role_relationships` (`bs_group_id`, `db_package_id`, `role_id`) VALUES (99, 1, 7) [[0;37mDEBUG[0m|#29194] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#29194] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#29194] [1m[36m (7.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#29194] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#29194] [1m[36mPackage Load (0.3ms)[0m [1mSELECT `packages`.* FROM `packages` WHERE `packages`.`id` = 1 AND (packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#29194] [1m[35m (0.2ms)[0m SELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1 [[0;37mDEBUG[0m|#29194] [1m[36mCACHE (0.0ms)[0m [1mSELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|#29194] [1m[35mSQL (0.2ms)[0m INSERT INTO `flags` (`architecture_id`, `db_package_id`, `db_project_id`, `flag`, `position`, `repo`, `status`) VALUES (NULL, 1, NULL, 'build', 1, NULL, 'enable') [[0;37mDEBUG[0m|#29194] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#29194] [1m[36mPackageUserRoleRelationship Load (15.5ms)[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|#29194] [1m[35mPackageGroupRoleRelationship Load (0.4ms)[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|#29194] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#29194] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#29199] 2012-11-16 14:57:58 [2614]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#29199] 2012-11-16 14:57:58 [2615]: POST /event?type=package&project=kde4&package=kdelibs [[0;37mDEBUG[0m|#29194] request took 0.100180994 [[0;37mDEBUG[0m|#29194] [1m[36mPackage Load (0.5ms)[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|#29194] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#29194] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#29194] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#29194] [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|#29194] [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|#29194] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#29194] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#29199] 2012-11-16 14:57:58 [2616]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#29194] request took 0.041856917 [[0;37mDEBUG[0m|#29194] [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|#29194] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#29194] Validate XML response: #<ActionDispatch::Response:0x00000006c78598> took 1ms [[32mINFO [0m|#29194] Completed 200 OK in 365ms (Views: 0.9ms | ActiveRecord: 35.8ms | Backend: 222.2ms | XML: 0.0ms) [[32mINFO [0m|#29194] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-16 14:57:58 +0100 [[32mINFO [0m|#29194] Processing by SourceController#package_meta as XML [[32mINFO [0m|#29194] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#29194] Validate XML request: #<ActionDispatch::Request:0x000000075cf128> [[0;37mDEBUG[0m|#29194] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#29194] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#29194] [1m[35mUser Load (0.4ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#29194] USER found: king [[0;37mDEBUG[0m|#29194] User king initialised [[0;37mDEBUG[0m|#29194] get_by_project_and_name {} [[0;37mDEBUG[0m|#29194] [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|#29194] [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|#29194] [1m[36mPackage Load (4.6ms)[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|#29194] [1m[35mProject Load (0.3ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#29194] Validate XML response: #<ActionDispatch::Response:0x000000075cf100> took 2ms [[32mINFO [0m|#29194] Completed 200 OK in 37ms (Views: 0.5ms | ActiveRecord: 5.7ms | Backend: 0.0ms | XML: 0.0ms) [[0;37mDEBUG[0m|#29194] [1m[36m (75.0ms)[0m [1mROLLBACK[0m [[0;37mDEBUG[0m|#29194] kill 29199 + 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/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets 'src/webui/log/*' 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 #593
by admin＠opensuse.org 13 Nov '12

13 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui/593/changes> Changes: [Stephan Kulow] [api] move the parsing of the activexml exception into the exception itself ------------------------------------------ Started by an SCM change Building in workspace <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/> Checkout:workspace / <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/> - hudson.remoting.LocalChannel@74e474e4 Using strategy: Default Last Built Revision: Revision 1fad595269fc7b22da4b6ea518ad4624d6aa2a80 (origin/master) Checkout:workspace / <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/> - hudson.remoting.LocalChannel@74e474e4 Fetching changes from 1 remote Git repository Fetching upstream changes from git://github.com/openSUSE/open-build-service.git Commencing build of Revision 38f181dff738f2a780fc2f1ac6f1ef64bd583eb0 (origin/master) Checking out Revision 38f181dff738f2a780fc2f1ac6f1ef64bd583eb0 (origin/master) [locks-and-latches] Checking to see if we really have the locks [locks-and-latches] Have all the locks, build can start [workspace] $ /bin/sh -xe /tmp/hudson6615348054880337721.sh + sh dist/ci/obs_testsuite_webui.sh ++ dirname dist/ci/obs_testsuite_webui.sh + . dist/ci/obs_testsuite_common.sh + setup_git + echo 'Checking status' Checking status + git status # Not currently on any branch. # Untracked files: # (use "git add <file>..." to include in what will be committed) # # src/api/Gemfile.lock.orig # src/webui/Gemfile.lock.orig # src/webui/db/structure.sql # src/webui/public/themes/ # src/webui/results/ # src/webui/webrat.log nothing added to commit but untracked files present (use "git add" to track) + echo 'Setup git submodules' Setup git submodules + git submodule init + git submodule update + echo 'Setup backend configuration template' Setup backend configuration template + sed -i -e 's|my $hostname = .*$|my $hostname = '\''localhost'\'';|' -e 's|our $bsuser = '\''obsrun'\'';|our $bsuser = '\''jenkins'\'';|' -e 's|our $bsgroup = '\''obsrun'\'';|our $bsgroup = '\''jenkins'\'';|' src/backend/BSConfig.pm.template + cp src/backend/BSConfig.pm.template src/backend/BSConfig.pm + echo 'Set environment variables' Set environment variables + export RAILS_ENV=test + RAILS_ENV=test + ret=0 + setup_api + echo 'Enter API rails root' Enter API rails root + cd src/api + echo 'Setup database configuration' Setup database configuration + cp config/database.yml.example config/database.yml + sed -i 's|database: api|database: ci_api|' config/database.yml + echo 'Setup additional configuration' Setup additional configuration + cp config/options.yml.example config/options.yml + echo 'Install missing gems locally' Install missing gems locally + mv Gemfile.lock Gemfile.lock.orig + bundle list Gems included by the bundle: * actionmailer (3.2.8) * actionpack (3.2.8) * activemodel (3.2.8) * activerecord (3.2.8) * activesupport (3.2.8) * arel (3.0.2) * builder (3.0.4) * bundler (1.2.1) * ci_reporter (1.7.3) * daemons (1.1.9) * delayed_job (3.0.3) * delayed_job_active_record (0.3.3) * erubis (2.7.0) * eventmachine (1.0.0) * exception_notification (3.0.0) * faraday (0.8.4) * faraday_middleware (0.9.0) * fast_xs (0.8.0) * hashie (1.2.0) * hike (1.2.1) * http_parser.rb (0.5.3) * i18n (0.6.1) * journey (1.0.4) * json (1.7.5) * mail (2.4.4) * memcache-client (1.8.5) * mime-types (1.19) * minitest (4.2.0) * multi_json (1.3.7) * multipart-post (1.1.5) * mysql2 (0.3.11) * nokogiri (1.5.5) * pkg-config (1.1.4) * polyglot (0.3.3) * rack (1.4.1) * rack-cache (1.2) * rack-ssl (1.3.2) * rack-test (0.6.2) * rails-api (0.0.2) * railties (3.2.8) * rake (0.9.2.2) * rdoc (3.12) * simple_oauth (0.1.9) * simplecov (0.7.1) * simplecov-html (0.7.1) * simplecov-rcov (0.2.3) * sprockets (2.1.3) * thor (0.16.0) * tilt (1.3.3) * tinder (1.9.1) * treetop (1.4.12) * twitter-stream (0.1.16) * tzinfo (0.3.35) * xmlhash (1.3.3) * yajl-ruby (1.1.0) + diff -u Gemfile.lock.orig Gemfile.lock + chmod a+x script/start_test_backend + echo 'Initialize test database, run migrations, load seed data' Initialize test database, run migrations, load seed data + rake --trace db:drop db:create db:setup db:migrate ** Invoke db:drop (first_time) ** Invoke db:load_config (first_time) ** Execute db:load_config ** Invoke rails_env (first_time) ** Execute rails_env ** Execute db:drop ** Invoke db:create (first_time) ** Invoke db:load_config ** Invoke rails_env ** Execute db:create ** Invoke db:setup (first_time) ** Execute db:setup ** Invoke db:structure:load (first_time) ** Invoke environment (first_time) ** Execute environment rake aborted! <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/api/lib/active…>:409: syntax error, unexpected $end, expecting keyword_end /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `require' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `block in require' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:236:in `load_dependency' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `require' <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/api/lib/active…>:9:in `<top (required)>' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `require' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `block in require' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:236:in `load_dependency' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `require' <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/api/config/ini…>:1:in `<top (required)>' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:245:in `load' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:245:in `block in load' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:236:in `load_dependency' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:245:in `load' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/engine.rb:588:in `block (2 levels) in <class:Engine>' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/engine.rb:587:in `each' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/engine.rb:587:in `block in <class:Engine>' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/initializable.rb:30:in `instance_exec' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/initializable.rb:30:in `run' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/initializable.rb:55:in `block in run_initializers' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/initializable.rb:54:in `each' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/initializable.rb:54:in `run_initializers' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/application.rb:136:in `initialize!' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/railtie/configurable.rb:30:in `method_missing' <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/api/config/env…>:21:in `<top (required)>' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `require' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `block in require' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:236:in `load_dependency' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `require' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/application.rb:103:in `require_environment!' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/application.rb:295:in `block (2 levels) in initialize_tasks' /usr/lib64/ruby/1.9.1/rake/task.rb:205:in `call' /usr/lib64/ruby/1.9.1/rake/task.rb:205:in `block in execute' /usr/lib64/ruby/1.9.1/rake/task.rb:200:in `each' /usr/lib64/ruby/1.9.1/rake/task.rb:200:in `execute' /usr/lib64/ruby/1.9.1/rake/task.rb:158:in `block in invoke_with_call_chain' /usr/lib64/ruby/1.9.1/monitor.rb:211:in `mon_synchronize' /usr/lib64/ruby/1.9.1/rake/task.rb:151:in `invoke_with_call_chain' /usr/lib64/ruby/1.9.1/rake/task.rb:176:in `block in invoke_prerequisites' /usr/lib64/ruby/1.9.1/rake/task.rb:174:in `each' /usr/lib64/ruby/1.9.1/rake/task.rb:174:in `invoke_prerequisites' /usr/lib64/ruby/1.9.1/rake/task.rb:157:in `block in invoke_with_call_chain' /usr/lib64/ruby/1.9.1/monitor.rb:211:in `mon_synchronize' /usr/lib64/ruby/1.9.1/rake/task.rb:151:in `invoke_with_call_chain' /usr/lib64/ruby/1.9.1/rake/task.rb:144:in `invoke' <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/api/lib/tasks/…>:84:in `block (2 levels) in <top (required)>' /usr/lib64/ruby/1.9.1/rake/task.rb:205:in `call' /usr/lib64/ruby/1.9.1/rake/task.rb:205:in `block in execute' /usr/lib64/ruby/1.9.1/rake/task.rb:200:in `each' /usr/lib64/ruby/1.9.1/rake/task.rb:200:in `execute' /usr/lib64/ruby/1.9.1/rake/task.rb:158:in `block in invoke_with_call_chain' /usr/lib64/ruby/1.9.1/monitor.rb:211:in `mon_synchronize' /usr/lib64/ruby/1.9.1/rake/task.rb:151:in `invoke_with_call_chain' /usr/lib64/ruby/1.9.1/rake/task.rb:144:in `invoke' /usr/lib64/ruby/1.9.1/rake/application.rb:116:in `invoke_task' /usr/lib64/ruby/1.9.1/rake/application.rb:94:in `block (2 levels) in top_level' /usr/lib64/ruby/1.9.1/rake/application.rb:94:in `each' /usr/lib64/ruby/1.9.1/rake/application.rb:94:in `block in top_level' /usr/lib64/ruby/1.9.1/rake/application.rb:133:in `standard_exception_handling' /usr/lib64/ruby/1.9.1/rake/application.rb:88:in `top_level' /usr/lib64/ruby/1.9.1/rake/application.rb:66:in `block in run' /usr/lib64/ruby/1.9.1/rake/application.rb:133:in `standard_exception_handling' /usr/lib64/ruby/1.9.1/rake/application.rb:63:in `run' /usr/bin/rake:32:in `<main>' Tasks: TOP => db:structure:load => environment 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 #1162
by admin＠opensuse.org 13 Nov '12

13 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_api/1162/changes> Changes: [Stephan Kulow] [api] move the parsing of the activexml exception into the exception itself ------------------------------------------ Started by an SCM change Building in workspace <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/> Checkout:workspace / <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/> - hudson.remoting.LocalChannel@74e474e4 Using strategy: Default Last Built Revision: Revision 1fad595269fc7b22da4b6ea518ad4624d6aa2a80 (origin/master) Checkout:workspace / <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/> - hudson.remoting.LocalChannel@74e474e4 Fetching changes from 1 remote Git repository Fetching upstream changes from git://github.com/openSUSE/open-build-service.git Commencing build of Revision 38f181dff738f2a780fc2f1ac6f1ef64bd583eb0 (origin/master) Checking out Revision 38f181dff738f2a780fc2f1ac6f1ef64bd583eb0 (origin/master) [locks-and-latches] Checking to see if we really have the locks [locks-and-latches] Have all the locks, build can start [workspace] $ /bin/sh -xe /tmp/hudson3471522197365242293.sh + sh dist/ci/obs_testsuite_api.sh ++ dirname dist/ci/obs_testsuite_api.sh + . dist/ci/obs_testsuite_common.sh + setup_git + echo 'Checking status' Checking status + git status # Not currently on any branch. # Untracked files: # (use "git add <file>..." to include in what will be committed) # # src/api/Gemfile.lock.orig # src/api/results/ # src/webui/public/themes/ nothing added to commit but untracked files present (use "git add" to track) + echo 'Setup git submodules' Setup git submodules + git submodule init + git submodule update + echo 'Setup backend configuration template' Setup backend configuration template + sed -i -e 's|my $hostname = .*$|my $hostname = '\''localhost'\'';|' -e 's|our $bsuser = '\''obsrun'\'';|our $bsuser = '\''jenkins'\'';|' -e 's|our $bsgroup = '\''obsrun'\'';|our $bsgroup = '\''jenkins'\'';|' src/backend/BSConfig.pm.template + cp src/backend/BSConfig.pm.template src/backend/BSConfig.pm + echo 'Set environment variables' Set environment variables + export RAILS_ENV=test + RAILS_ENV=test + ret=0 + setup_api + echo 'Enter API rails root' Enter API rails root + cd src/api + echo 'Setup database configuration' Setup database configuration + cp config/database.yml.example config/database.yml + sed -i 's|database: api|database: ci_api|' config/database.yml + echo 'Setup additional configuration' Setup additional configuration + cp config/options.yml.example config/options.yml + echo 'Install missing gems locally' Install missing gems locally + mv Gemfile.lock Gemfile.lock.orig + bundle list Gems included by the bundle: * actionmailer (3.2.8) * actionpack (3.2.8) * activemodel (3.2.8) * activerecord (3.2.8) * activesupport (3.2.8) * arel (3.0.2) * builder (3.0.4) * bundler (1.2.1) * ci_reporter (1.7.3) * daemons (1.1.9) * delayed_job (3.0.3) * delayed_job_active_record (0.3.3) * erubis (2.7.0) * eventmachine (1.0.0) * exception_notification (3.0.0) * faraday (0.8.4) * faraday_middleware (0.9.0) * fast_xs (0.8.0) * hashie (1.2.0) * hike (1.2.1) * http_parser.rb (0.5.3) * i18n (0.6.1) * journey (1.0.4) * json (1.7.5) * mail (2.4.4) * memcache-client (1.8.5) * mime-types (1.19) * minitest (4.2.0) * multi_json (1.3.7) * multipart-post (1.1.5) * mysql2 (0.3.11) * nokogiri (1.5.5) * pkg-config (1.1.4) * polyglot (0.3.3) * rack (1.4.1) * rack-cache (1.2) * rack-ssl (1.3.2) * rack-test (0.6.2) * rails-api (0.0.2) * railties (3.2.8) * rake (0.9.2.2) * rdoc (3.12) * simple_oauth (0.1.9) * simplecov (0.7.1) * simplecov-html (0.7.1) * simplecov-rcov (0.2.3) * sprockets (2.1.3) * thor (0.16.0) * tilt (1.3.3) * tinder (1.9.1) * treetop (1.4.12) * twitter-stream (0.1.16) * tzinfo (0.3.35) * xmlhash (1.3.3) * yajl-ruby (1.1.0) + diff -u Gemfile.lock.orig Gemfile.lock + chmod a+x script/start_test_backend + echo 'Initialize test database, run migrations, load seed data' Initialize test database, run migrations, load seed data + rake --trace db:drop db:create db:setup db:migrate ** Invoke db:drop (first_time) ** Invoke db:load_config (first_time) ** Execute db:load_config ** Invoke rails_env (first_time) ** Execute rails_env ** Execute db:drop ** Invoke db:create (first_time) ** Invoke db:load_config ** Invoke rails_env ** Execute db:create ** Invoke db:setup (first_time) ** Execute db:setup ** Invoke db:structure:load (first_time) ** Invoke environment (first_time) ** Execute environment rake aborted! <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/src/api/lib/activexm…>:409: syntax error, unexpected $end, expecting keyword_end /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `require' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `block in require' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:236:in `load_dependency' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `require' <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/src/api/lib/activexm…>:9:in `<top (required)>' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `require' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `block in require' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:236:in `load_dependency' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `require' <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/src/api/config/initi…>:1:in `<top (required)>' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:245:in `load' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:245:in `block in load' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:236:in `load_dependency' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:245:in `load' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/engine.rb:588:in `block (2 levels) in <class:Engine>' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/engine.rb:587:in `each' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/engine.rb:587:in `block in <class:Engine>' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/initializable.rb:30:in `instance_exec' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/initializable.rb:30:in `run' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/initializable.rb:55:in `block in run_initializers' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/initializable.rb:54:in `each' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/initializable.rb:54:in `run_initializers' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/application.rb:136:in `initialize!' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/railtie/configurable.rb:30:in `method_missing' <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/src/api/config/envir…>:21:in `<top (required)>' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `require' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `block in require' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:236:in `load_dependency' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/dependencies.rb:251:in `require' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/application.rb:103:in `require_environment!' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/application.rb:295:in `block (2 levels) in initialize_tasks' /usr/lib64/ruby/1.9.1/rake/task.rb:205:in `call' /usr/lib64/ruby/1.9.1/rake/task.rb:205:in `block in execute' /usr/lib64/ruby/1.9.1/rake/task.rb:200:in `each' /usr/lib64/ruby/1.9.1/rake/task.rb:200:in `execute' /usr/lib64/ruby/1.9.1/rake/task.rb:158:in `block in invoke_with_call_chain' /usr/lib64/ruby/1.9.1/monitor.rb:211:in `mon_synchronize' /usr/lib64/ruby/1.9.1/rake/task.rb:151:in `invoke_with_call_chain' /usr/lib64/ruby/1.9.1/rake/task.rb:176:in `block in invoke_prerequisites' /usr/lib64/ruby/1.9.1/rake/task.rb:174:in `each' /usr/lib64/ruby/1.9.1/rake/task.rb:174:in `invoke_prerequisites' /usr/lib64/ruby/1.9.1/rake/task.rb:157:in `block in invoke_with_call_chain' /usr/lib64/ruby/1.9.1/monitor.rb:211:in `mon_synchronize' /usr/lib64/ruby/1.9.1/rake/task.rb:151:in `invoke_with_call_chain' /usr/lib64/ruby/1.9.1/rake/task.rb:144:in `invoke' <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/src/api/lib/tasks/da…>:84:in `block (2 levels) in <top (required)>' /usr/lib64/ruby/1.9.1/rake/task.rb:205:in `call' /usr/lib64/ruby/1.9.1/rake/task.rb:205:in `block in execute' /usr/lib64/ruby/1.9.1/rake/task.rb:200:in `each' /usr/lib64/ruby/1.9.1/rake/task.rb:200:in `execute' /usr/lib64/ruby/1.9.1/rake/task.rb:158:in `block in invoke_with_call_chain' /usr/lib64/ruby/1.9.1/monitor.rb:211:in `mon_synchronize' /usr/lib64/ruby/1.9.1/rake/task.rb:151:in `invoke_with_call_chain' /usr/lib64/ruby/1.9.1/rake/task.rb:144:in `invoke' /usr/lib64/ruby/1.9.1/rake/application.rb:116:in `invoke_task' /usr/lib64/ruby/1.9.1/rake/application.rb:94:in `block (2 levels) in top_level' /usr/lib64/ruby/1.9.1/rake/application.rb:94:in `each' /usr/lib64/ruby/1.9.1/rake/application.rb:94:in `block in top_level' /usr/lib64/ruby/1.9.1/rake/application.rb:133:in `standard_exception_handling' /usr/lib64/ruby/1.9.1/rake/application.rb:88:in `top_level' /usr/lib64/ruby/1.9.1/rake/application.rb:66:in `block in run' /usr/lib64/ruby/1.9.1/rake/application.rb:133:in `standard_exception_handling' /usr/lib64/ruby/1.9.1/rake/application.rb:63:in `run' /usr/bin/rake:32:in `<main>' Tasks: TOP => db:structure:load => environment 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 #453
by admin＠opensuse.org 12 Nov '12

12 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/453/changes> Changes: [Stephan Kulow] [api] fix parsing of the issues [Stephan Kulow] [webui] do not crash on issues [Stephan Kulow] [webui] reviews are still not having a comment, to be fixed ------------------------------------------ [...truncated 255360 lines...] [[32mINFO [0m|#12398] Started GET "/user/login?return_to_host=http%3A%2F%2Flocalhost&return_to_path=%2Fhome%2Frequests" for 127.0.0.1 at 2012-11-12 12:31:06 +0100 [[32mINFO [0m|#12398] Processing by UserController#login as HTML [[32mINFO [0m|#12398] Parameters: {"return_to_host"=>"http://localhost", "return_to_path"=>"/home/requests"} [[0;37mDEBUG[0m|#12398] Setting return_to: "/home/requests" [[0;37mDEBUG[0m|#12398] Authenticating with iChain mode: off [[32mINFO [0m|#12398] Anonymous request to /home/requests [[32mINFO [0m|#12398] Rendered user/login.html.erb within layouts/application (1.3ms) [[32mINFO [0m|#12398] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#12398] Rendered layouts/_header.html.erb (1.2ms) [[32mINFO [0m|#12398] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|#12398] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#12398] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#12398] Completed 200 OK in 10ms (Views: 7.9ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#12398] Started GET "/assets/application.js" for 127.0.0.1 at 2012-11-12 12:31:06 +0100 [[32mINFO [0m|#12398] Served asset /application.js - 304 Not Modified (1ms) [[32mINFO [0m|#12398] Started GET "/assets/icons-s30b5819a4d.png" for 127.0.0.1 at 2012-11-12 12:31:06 +0100 [[32mINFO [0m|#12398] Served asset /icons-s30b5819a4d.png - 304 Not Modified (0ms) [[32mINFO [0m|#12398] Started GET "/request/show/997" for 127.0.0.1 at 2012-11-12 12:31:07 +0100 [[32mINFO [0m|#12398] Processing by RequestController#show as HTML [[32mINFO [0m|#12398] Parameters: {"id"=>"997"} [[0;37mDEBUG[0m|#12398] Setting return_to: "/request/show/997" [[0;37mDEBUG[0m|#12398] Authenticating with iChain mode: off [[32mINFO [0m|#12398] Anonymous request to /request/show/997 [[0;37mDEBUG[0m|#12398] --> direct_http url: #<URI::Generic:0x0000000639aa48 URL:http://localhost:3203/webui/request_show?id=997> [[0;37mDEBUG[0m|#12398] http_do #1: method: GET url: http://localhost:3203/webui/request_show?id=997 [[0;37mDEBUG[0m|#12398] RT http://localhost:3203/webui/request_show?id=997 {"view"=>0.854433, "db"=>5.238644, "backend"=>0, "xml"=>0.11103, :runtime=>90.41000000000001, :all=>93.35078} [[0;37mDEBUG[0m|#12398] data { "id": 997, "description": "", "state": "new", "creator": "tom", "created_at": "2012-09-03T13:08:02Z", "is_target_maintainer": false, "my_open_reviews": [ ], "other_open_reviews": [ ], "events": [ { "who": "tom", "what": "", "when": "2012-09-03T13:08:02Z", "comment": null, "color": "" } ], "actions": [ { "type": "submit", "sprj": "NotExisiting", "spkg": "notthere", "srev": "1", "tprj": "kde4", "tpkg": "kdelibs", "name": "Submit notthere", "sourcediff": { } } ] } [[32mINFO [0m|#12398] Rendered shared/_user_with_realname_and_icon.html.erb (0.3ms) [[0;37mDEBUG[0m|#12398] [REST] find( Project, [{:name=>"NotExisiting"}] ) [[0;37mDEBUG[0m|#12398] params {:name=>"NotExisiting"} [[0;37mDEBUG[0m|#12398] uri is: http://localhost:3203/source/:name/_meta?:view [[0;37mDEBUG[0m|#12398] http_do #1: method: get url: http://localhost:3203/source/NotExisiting/_meta? [[0;37mDEBUG[0m|#12398] RT http://localhost:3203/source/NotExisiting/_meta? {"view"=>0.9801740000000001, "db"=>0.8111710000000001, "backend"=>0, "xml"=>0, :runtime=>9.495, :all=>53.162067} [[0;37mDEBUG[0m|#12398] Project.find( {:name=>"NotExisiting"} ) did not find anything, return nil [[0;37mDEBUG[0m|#12398] [REST] find( Package, [{:project=>"NotExisiting", :name=>"notthere"}] ) [[0;37mDEBUG[0m|#12398] params {:project=>"NotExisiting", :name=>"notthere"} [[0;37mDEBUG[0m|#12398] uri is: http://localhost:3203/source/:project/:name/_meta?:view [[0;37mDEBUG[0m|#12398] http_do #1: method: get url: http://localhost:3203/source/NotExisiting/notthere/_meta? [[0;37mDEBUG[0m|#12398] RT http://localhost:3203/source/NotExisiting/notthere/_meta? {"view"=>1.056422, "db"=>0.8251510000000001, "backend"=>0, "xml"=>0, :runtime=>10.644, :all=>51.826316} [[0;37mDEBUG[0m|#12398] Package.find( {:project=>"NotExisiting", :name=>"notthere"} ) did not find anything, return nil [[0;37mDEBUG[0m|#12398] [REST] find( Directory, [{:project=>"NotExisiting", :package=>"notthere"}] ) [[0;37mDEBUG[0m|#12398] params {:project=>"NotExisiting", :package=>"notthere"} [[0;37mDEBUG[0m|#12398] uri is: http://localhost:3203/source/:project/:package?:expand&:rev [[0;37mDEBUG[0m|#12398] http_do #1: method: get url: http://localhost:3203/source/NotExisiting/notthere? [[0;37mDEBUG[0m|#12398] RT http://localhost:3203/source/NotExisiting/notthere? {"view"=>0.7314809999999999, "db"=>0.5390219999999999, "backend"=>0, "xml"=>0, :runtime=>8.17, :all=>50.093858999999995} [[0;37mDEBUG[0m|#12398] Directory.find( {:project=>"NotExisiting", :package=>"notthere"} ) did not find anything, return nil [[32mINFO [0m|#12398] Rendered shared/_project_or_package_link.html.erb (158.0ms) [[0;37mDEBUG[0m|#12398] [REST] find( Project, [{:name=>"kde4"}] ) [[0;37mDEBUG[0m|#12398] params {:name=>"kde4"} [[0;37mDEBUG[0m|#12398] uri is: http://localhost:3203/source/:name/_meta?:view [[0;37mDEBUG[0m|#12398] http_do #1: method: get url: http://localhost:3203/source/kde4/_meta? [[0;37mDEBUG[0m|#12398] RT http://localhost:3203/source/kde4/_meta? {"view"=>0.705854, "db"=>0.7286969999999999, "backend"=>0, "xml"=>0, :runtime=>13.341000000000001, :all=>54.773767} [[32mINFO [0m|#12398] Rendered shared/_project_or_package_link.html.erb (56.8ms) [[0;37mDEBUG[0m|#12398] [{"who"=>"tom", "what"=>"", "when"=>"2012-09-03T13:08:02Z", "comment"=>nil, "color"=>""}] [[32mINFO [0m|#12398] Rendered shared/_user_with_realname_and_icon.html.erb (0.9ms) [[32mINFO [0m|#12398] Rendered request/_recent_events_table.html.erb (2.1ms) [[32mINFO [0m|#12398] Rendered request/show.html.erb within layouts/application (219.4ms) [[32mINFO [0m|#12398] Rendered layouts/_watch_and_search.html.erb (0.7ms) [[32mINFO [0m|#12398] Rendered layouts/_header.html.erb (1.5ms) [[32mINFO [0m|#12398] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|#12398] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#12398] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#12398] Completed 200 OK in 322ms (Views: 226.3ms | ActiveRecord: 0.0ms | API: 303.2ms (XML: 0.1ms , View: 4.3ms , Backend: 0.0ms , DB: 8.1ms , HTTP: 171.1ms) | XML: 0.3ms) [[32mINFO [0m|#12398] Started GET "/assets/application.js" for 127.0.0.1 at 2012-11-12 12:31:07 +0100 [[32mINFO [0m|#12398] Served asset /application.js - 304 Not Modified (0ms) [[32mINFO [0m|#12398] Started GET "/home/icon?id=tom&size=20" for 127.0.0.1 at 2012-11-12 12:31:07 +0100 [[32mINFO [0m|#12398] Processing by HomeController#icon as HTML [[32mINFO [0m|#12398] Parameters: {"id"=>"tom", "size"=>"20"} [[0;37mDEBUG[0m|#12398] Setting return_to: "/home/icon?id=tom&size=20" [[0;37mDEBUG[0m|#12398] Authenticating with iChain mode: off [[32mINFO [0m|#12398] Anonymous request to /home/icon?id=tom&size=20 [[32mINFO [0m|#12398] Rendered text template (0.0ms) [[32mINFO [0m|#12398] Completed 200 OK in 1ms (Views: 0.4ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#12398] Started GET "/assets/icons-s30b5819a4d.png" for 127.0.0.1 at 2012-11-12 12:31:07 +0100 [[32mINFO [0m|#12398] Served asset /icons-s30b5819a4d.png - 304 Not Modified (0ms) [[32mINFO [0m|#12398] Started GET "/request/show/998" for 127.0.0.1 at 2012-11-12 12:31:08 +0100 [[32mINFO [0m|#12398] Processing by RequestController#show as HTML [[32mINFO [0m|#12398] Parameters: {"id"=>"998"} [[0;37mDEBUG[0m|#12398] Setting return_to: "/request/show/998" [[0;37mDEBUG[0m|#12398] Authenticating with iChain mode: off [[32mINFO [0m|#12398] Anonymous request to /request/show/998 [[0;37mDEBUG[0m|#12398] --> direct_http url: #<URI::Generic:0x00000006ce1a98 URL:http://localhost:3203/webui/request_show?id=998> [[0;37mDEBUG[0m|#12398] http_do #1: method: GET url: http://localhost:3203/webui/request_show?id=998 [[0;37mDEBUG[0m|#12398] RT http://localhost:3203/webui/request_show?id=998 {"view"=>0.964625, "db"=>3.006371, "backend"=>5.46291, "xml"=>0, :runtime=>32.483, :all=>35.660195} [[0;37mDEBUG[0m|#12398] data { "id": 998, "description": "", "state": "new", "creator": "tom", "created_at": "2012-09-03T13:08:02Z", "is_target_maintainer": true, "my_open_reviews": [ ], "other_open_reviews": [ ], "events": [ { "who": "tom", "what": "", "when": "2012-09-03T13:08:02Z", "comment": null, "color": "" } ], "actions": [ { "type": "submit", "sprj": "kde4", "spkg": "kdelibs", "srev": "1", "tprj": "NeitherExisting", "tpkg": "unknown", "name": "Submit kdelibs", "sourcediff": [ { "error": "NeitherExisting" } ] }, { "type": "delete", "tprj": "NeitherExisting", "tpkg": "unknown2", "name": "Delete unknown2", "sourcediff": [ { "error": "project 'NeitherExisting' does not exist" } ] } ] } [[32mINFO [0m|#12398] Rendered shared/_user_with_realname_and_icon.html.erb (0.3ms) [[0;37mDEBUG[0m|#12398] [REST] find( Directory, [{:project=>"kde4", :package=>"kdelibs"}] ) [[0;37mDEBUG[0m|#12398] params {:project=>"kde4", :package=>"kdelibs"} [[0;37mDEBUG[0m|#12398] uri is: http://localhost:3203/source/:project/:package?:expand&:rev [[0;37mDEBUG[0m|#12398] http_do #1: method: get url: http://localhost:3203/source/kde4/kdelibs? [[0;37mDEBUG[0m|#12398] RT http://localhost:3203/source/kde4/kdelibs? {"view"=>nil, "db"=>1.555453, "backend"=>0, "xml"=>0, :runtime=>21.273, :all=>59.369258} [[32mINFO [0m|#12398] Rendered shared/_project_or_package_link.html.erb (61.0ms) [[0;37mDEBUG[0m|#12398] [REST] find( Project, [{:name=>"NeitherExisting"}] ) [[0;37mDEBUG[0m|#12398] params {:name=>"NeitherExisting"} [[0;37mDEBUG[0m|#12398] uri is: http://localhost:3203/source/:name/_meta?:view [[0;37mDEBUG[0m|#12398] http_do #1: method: get url: http://localhost:3203/source/NeitherExisting/_meta? [[0;37mDEBUG[0m|#12398] RT http://localhost:3203/source/NeitherExisting/_meta? {"view"=>0.9180879999999999, "db"=>0.753649, "backend"=>0, "xml"=>0, :runtime=>8.467, :all=>51.78074} [[0;37mDEBUG[0m|#12398] Project.find( {:name=>"NeitherExisting"} ) did not find anything, return nil [[0;37mDEBUG[0m|#12398] [REST] find( Package, [{:project=>"NeitherExisting", :name=>"unknown"}] ) [[0;37mDEBUG[0m|#12398] params {:project=>"NeitherExisting", :name=>"unknown"} [[0;37mDEBUG[0m|#12398] uri is: http://localhost:3203/source/:project/:name/_meta?:view [[0;37mDEBUG[0m|#12398] http_do #1: method: get url: http://localhost:3203/source/NeitherExisting/unknown/_meta? [[0;37mDEBUG[0m|#12398] RT http://localhost:3203/source/NeitherExisting/unknown/_meta? {"view"=>1.1260560000000002, "db"=>0.734119, "backend"=>0, "xml"=>0, :runtime=>10.669, :all=>50.118281} [[0;37mDEBUG[0m|#12398] Package.find( {:project=>"NeitherExisting", :name=>"unknown"} ) did not find anything, return nil [[32mINFO [0m|#12398] Rendered shared/_project_or_package_link.html.erb (103.6ms) [[32mINFO [0m|#12398] Rendered shared/_buildresult_box.html.erb (1.2ms) [[32mINFO [0m|#12398] Rendered request/show.html.erb within layouts/application (168.7ms) [[31mERROR[0m|#12398] rescue_action: caught ActionView::Template::Error: undefined method `length' for nil:NilClass [[0;37mDEBUG[0m|#12398] ERROR: 400; No message set [[0;37mDEBUG[0m|#12398] <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…>:156:in `block (2 levels) in _app_views_request_show_html_erb__2593194421368411520_60234140' <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…>:132:in `each' <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…>:132:in `each_with_index' <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…>:132:in `block in _app_views_request_show_html_erb__2593194421368411520_60234140' <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…>:64:in `each' <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…>:64:in `each_with_index' <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…>:64:in `_app_views_request_show_html_erb__2593194421368411520_60234140' [[32mINFO [0m|#12398] Rendered error.html.erb within layouts/application (1.6ms) [[32mINFO [0m|#12398] Rendered layouts/_watch_and_search.html.erb (0.7ms) [[32mINFO [0m|#12398] Rendered layouts/_header.html.erb (1.7ms) [[32mINFO [0m|#12398] Rendered layouts/_personal_navigation.html.erb (1.4ms) [[32mINFO [0m|#12398] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#12398] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#12398] Completed 400 Bad Request in 218ms (Views: 10.5ms | ActiveRecord: 0.0ms | API: 196.9ms (XML: 0.0ms , View: 3.0ms , Backend: 5.5ms , DB: 6.0ms , HTTP: 124.0ms) | XML: 0.2ms) [[32mINFO [0m|#12398] Started GET "/assets/application.js" for 127.0.0.1 at 2012-11-12 12:31:08 +0100 [[32mINFO [0m|#12398] Served asset /application.js - 304 Not Modified (1ms) [[32mINFO [0m|#12398] Started GET "/assets/icons-s30b5819a4d.png" for 127.0.0.1 at 2012-11-12 12:31:08 +0100 [[32mINFO [0m|#12398] Served asset /icons-s30b5819a4d.png - 304 Not Modified (0ms) + 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.. sh jenkins ..c.. sh jenkins ..c.. egrep + echo 'Remove log/tmp files to save disc space' Remove log/tmp files to save disc space + rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets + exit 1 Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe(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 #448
by admin＠opensuse.org 12 Nov '12

12 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/448/changes> Changes: [Stephan Kulow] [api] make request tests reproducible ------------------------------------------ [...truncated 6315 lines...] [[0;37mDEBUG[0m|#28805] http_do #1: method: get url: http://localhost:3203/attribute/NSTEST [[0;37mDEBUG[0m|#28805] RT http://localhost:3203/attribute/NSTEST {"view"=>0.39931, "db"=>0.6560140000000001, "backend"=>0, "xml"=>0, :runtime=>6.361000000000001, :all=>47.34636} [[0;37mDEBUG[0m|#28805] [REST] find( Attribute, [:attributes, {:namespace=>"OBS"}] ) [[0;37mDEBUG[0m|#28805] params {:namespace=>"OBS"} [[0;37mDEBUG[0m|#28805] uri is: http://localhost:3203/attribute/:namespace [[0;37mDEBUG[0m|#28805] http_do #1: method: get url: http://localhost:3203/attribute/OBS [[0;37mDEBUG[0m|#28805] RT http://localhost:3203/attribute/OBS {"view"=>0.500141, "db"=>0.719861, "backend"=>0, "xml"=>0, :runtime=>8.224, :all=>47.954217} [[0;37mDEBUG[0m|#28805] [REST] find( IssueTracker, [:all] ) [[0;37mDEBUG[0m|#28805] params {} [[0;37mDEBUG[0m|#28805] uri is: http://localhost:3203/issue_trackers/ [[0;37mDEBUG[0m|#28805] http_do #1: method: get url: http://localhost:3203/issue_trackers [[0;37mDEBUG[0m|#28805] RT http://localhost:3203/issue_trackers {"view"=>0.070839, "db"=>0.23394099999999998, "backend"=>0, "xml"=>0, :runtime=>25.529, :all=>66.12035800000001} [[0;37mDEBUG[0m|#28805] [REST] find( Collection, [{:what=>"package", :predicate=>"[contains(attribute/@name,'OBS:RequestCloned')]"}] ) [[0;37mDEBUG[0m|#28805] params {:what=>"package", :predicate=>"[contains(attribute/@name,'OBS:RequestCloned')]"} [[0;37mDEBUG[0m|#28805] uri is: http://localhost:3203/search/:what?match=:predicate [[0;37mDEBUG[0m|#28805] [REST] Transport.find using POST-method [[0;37mDEBUG[0m|#28805] http_do #1: method: post url: http://localhost:3203/search/package [[0;37mDEBUG[0m|#28805] RT http://localhost:3203/search/package {"view"=>0.564038, "db"=>1.6035270000000001, "backend"=>0, "xml"=>0.068346, :runtime=>16.698999999999998, :all=>22.393964} [[0;37mDEBUG[0m|#28805] [REST] find( Collection, [{:what=>"project", :predicate=>"[contains(attribute/@name,'OBS:RequestCloned')]"}] ) [[0;37mDEBUG[0m|#28805] params {:what=>"project", :predicate=>"[contains(attribute/@name,'OBS:RequestCloned')]"} [[0;37mDEBUG[0m|#28805] uri is: http://localhost:3203/search/:what?match=:predicate [[0;37mDEBUG[0m|#28805] [REST] Transport.find using POST-method [[0;37mDEBUG[0m|#28805] http_do #1: method: post url: http://localhost:3203/search/project [[0;37mDEBUG[0m|#28805] RT http://localhost:3203/search/project {"view"=>0.46719900000000003, "db"=>1.021163, "backend"=>0, "xml"=>0.046505000000000005, :runtime=>10.805, :all=>15.239895} [[32mINFO [0m|#28805] Rendered search/_search_result.html.erb (0.0ms) [[32mINFO [0m|#28805] Rendered search/search.html.erb within layouts/application (0.8ms) [[32mINFO [0m|#28805] Rendered layouts/_watch_and_search.html.erb (0.7ms) [[32mINFO [0m|#28805] Rendered layouts/_header.html.erb (1.3ms) [[32mINFO [0m|#28805] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|#28805] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#28805] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#28805] Completed 200 OK in 283ms (Views: 7.2ms | ActiveRecord: 0.0ms | API: 214.6ms (XML: 0.1ms , View: 2.7ms , Backend: 0.0ms , DB: 4.8ms , HTTP: 134.8ms) | XML: 52.6ms) [[32mINFO [0m|#28805] Started GET "/assets/application.css" for 127.0.0.1 at 2012-11-11 21:38:04 +0100 [[32mINFO [0m|#28805] Served asset /application.css - 200 OK (2ms) [[32mINFO [0m|#28805] Started GET "/assets/application.js" for 127.0.0.1 at 2012-11-11 21:38:04 +0100 [[32mINFO [0m|#28805] Served asset /application.js - 200 OK (2ms) [[32mINFO [0m|#28805] Started GET "/assets/obs-logo_small.png" for 127.0.0.1 at 2012-11-11 21:38:04 +0100 [[32mINFO [0m|#28805] Served asset /obs-logo_small.png - 200 OK (0ms) [[32mINFO [0m|#28805] Started GET "/assets/icons-s30b5819a4d.png" for 127.0.0.1 at 2012-11-11 21:38:04 +0100 [[32mINFO [0m|#28805] Served asset /icons-s30b5819a4d.png - 200 OK (0ms) [[32mINFO [0m|#28805] Started GET "/assets/images/gradient-dark-nav.png" for 127.0.0.1 at 2012-11-11 21:38:04 +0100 [[32mINFO [0m|#28805] Served asset /images/gradient-dark-nav.png - 200 OK (0ms) [[32mINFO [0m|#28805] Started GET "/search" for 127.0.0.1 at 2012-11-11 21:38:05 +0100 [[32mINFO [0m|#28805] Processing by SearchController#index as HTML [[0;37mDEBUG[0m|#28805] Setting return_to: "/search" [[0;37mDEBUG[0m|#28805] Authenticating with iChain mode: off [[32mINFO [0m|#28805] Anonymous request to /search [[32mINFO [0m|#28805] Rendered search/index.html.erb within layouts/application (2.1ms) [[32mINFO [0m|#28805] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#28805] Rendered layouts/_header.html.erb (1.1ms) [[32mINFO [0m|#28805] Rendered layouts/_personal_navigation.html.erb (1.3ms) [[32mINFO [0m|#28805] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#28805] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#28805] Completed 200 OK in 15ms (Views: 8.0ms | ActiveRecord: 0.0ms | XML: 2.8ms) [[32mINFO [0m|#28805] 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-11 21:38:05 +0100 [[32mINFO [0m|#28805] Processing by SearchController#search as HTML [[32mINFO [0m|#28805] 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|#28805] 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|#28805] Authenticating with iChain mode: off [[32mINFO [0m|#28805] 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= [[32mINFO [0m|#28805] Rendered search/_search_result.html.erb (0.0ms) [[32mINFO [0m|#28805] Rendered search/search.html.erb within layouts/application (0.7ms) [[32mINFO [0m|#28805] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#28805] Rendered layouts/_header.html.erb (1.1ms) [[32mINFO [0m|#28805] Rendered layouts/_personal_navigation.html.erb (1.3ms) [[32mINFO [0m|#28805] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#28805] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#28805] Completed 200 OK in 13ms (Views: 6.5ms | ActiveRecord: 0.0ms | XML: 2.4ms) [[32mINFO [0m|#28805] 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-11 21:38:06 +0100 [[32mINFO [0m|#28805] Processing by SearchController#search as HTML [[32mINFO [0m|#28805] 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|#28805] 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|#28805] Authenticating with iChain mode: off [[32mINFO [0m|#28805] 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|#28805] [REST] find( Attribute, [:namespaces] ) [[0;37mDEBUG[0m|#28805] params {} [[0;37mDEBUG[0m|#28805] uri is: http://localhost:3203/attribute [[0;37mDEBUG[0m|#28805] http_do #1: method: get url: http://localhost:3203/attribute [[0;37mDEBUG[0m|#28805] RT http://localhost:3203/attribute {"view"=>0.46731399999999995, "db"=>0.412891, "backend"=>0, "xml"=>0, :runtime=>5.554, :all=>8.325608} [[0;37mDEBUG[0m|#28805] [REST] find( Attribute, [:attributes, {:namespace=>"NSTEST"}] ) [[0;37mDEBUG[0m|#28805] params {:namespace=>"NSTEST"} [[0;37mDEBUG[0m|#28805] uri is: http://localhost:3203/attribute/:namespace [[0;37mDEBUG[0m|#28805] http_do #1: method: get url: http://localhost:3203/attribute/NSTEST [[0;37mDEBUG[0m|#28805] RT http://localhost:3203/attribute/NSTEST {"view"=>0.459847, "db"=>0.513977, "backend"=>0, "xml"=>0, :runtime=>6.467, :all=>47.295410000000004} [[0;37mDEBUG[0m|#28805] [REST] find( Attribute, [:attributes, {:namespace=>"OBS"}] ) [[0;37mDEBUG[0m|#28805] params {:namespace=>"OBS"} [[0;37mDEBUG[0m|#28805] uri is: http://localhost:3203/attribute/:namespace [[0;37mDEBUG[0m|#28805] http_do #1: method: get url: http://localhost:3203/attribute/OBS [[0;37mDEBUG[0m|#28805] RT http://localhost:3203/attribute/OBS {"view"=>0.47771800000000003, "db"=>0.5220640000000001, "backend"=>0, "xml"=>0, :runtime=>8.064, :all=>51.307584000000006} [[0;37mDEBUG[0m|#28805] [REST] find( IssueTracker, [:all] ) [[0;37mDEBUG[0m|#28805] params {} [[0;37mDEBUG[0m|#28805] uri is: http://localhost:3203/issue_trackers/ [[0;37mDEBUG[0m|#28805] http_do #1: method: get url: http://localhost:3203/issue_trackers [[0;37mDEBUG[0m|#28805] RT http://localhost:3203/issue_trackers {"view"=>0.07459, "db"=>0.289756, "backend"=>0, "xml"=>0, :runtime=>29.424, :all=>70.936991} [[32mINFO [0m|#28805] Rendered search/_search_result.html.erb (0.0ms) [[32mINFO [0m|#28805] Rendered search/search.html.erb within layouts/application (0.8ms) [[32mINFO [0m|#28805] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#28805] Rendered layouts/_header.html.erb (1.1ms) [[32mINFO [0m|#28805] Rendered layouts/_personal_navigation.html.erb (1.1ms) [[32mINFO [0m|#28805] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#28805] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#28805] Completed 200 OK in 245ms (Views: 6.9ms | ActiveRecord: 0.0ms | API: 177.9ms (XML: 0.0ms , View: 1.5ms , Backend: 0.0ms , DB: 1.7ms , HTTP: 128.4ms) | XML: 53.7ms) [[32mINFO [0m|#28805] Started GET "/assets/application.css" for 127.0.0.1 at 2012-11-11 21:38:06 +0100 [[32mINFO [0m|#28805] Served asset /application.css - 200 OK (1ms) [[32mINFO [0m|#28805] Started GET "/assets/application.js" for 127.0.0.1 at 2012-11-11 21:38:06 +0100 [[32mINFO [0m|#28805] Served asset /application.js - 200 OK (1ms) [[32mINFO [0m|#28805] Started GET "/assets/images/gradient-dark-nav.png" for 127.0.0.1 at 2012-11-11 21:38:06 +0100 [[32mINFO [0m|#28805] Served asset /images/gradient-dark-nav.png - 200 OK (0ms) [[32mINFO [0m|#28805] Started GET "/assets/icons-s30b5819a4d.png" for 127.0.0.1 at 2012-11-11 21:38:06 +0100 [[32mINFO [0m|#28805] Served asset /icons-s30b5819a4d.png - 200 OK (0ms) [[32mINFO [0m|#28805] Started GET "/assets/obs-logo_small.png" for 127.0.0.1 at 2012-11-11 21:38:06 +0100 [[32mINFO [0m|#28805] Served asset /obs-logo_small.png - 200 OK (0ms) [[32mINFO [0m|#28805] Started GET "/search" for 127.0.0.1 at 2012-11-11 21:38:06 +0100 [[32mINFO [0m|#28805] Processing by SearchController#index as HTML [[0;37mDEBUG[0m|#28805] Setting return_to: "/search" [[0;37mDEBUG[0m|#28805] Authenticating with iChain mode: off [[32mINFO [0m|#28805] Anonymous request to /search [[32mINFO [0m|#28805] Rendered search/index.html.erb within layouts/application (2.0ms) [[32mINFO [0m|#28805] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#28805] Rendered layouts/_header.html.erb (1.0ms) [[32mINFO [0m|#28805] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|#28805] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#28805] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#28805] Completed 200 OK in 14ms (Views: 7.5ms | ActiveRecord: 0.0ms | XML: 2.5ms) [[32mINFO [0m|#28805] 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-11 21:38:07 +0100 [[32mINFO [0m|#28805] Processing by SearchController#search as HTML [[32mINFO [0m|#28805] 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|#28805] 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|#28805] Authenticating with iChain mode: off [[32mINFO [0m|#28805] 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= [[32mINFO [0m|#28805] Redirected to http://localhost:3199/search [[32mINFO [0m|#28805] Completed 302 Found in 10ms (ActiveRecord: 0.0ms | XML: 4.5ms) [[32mINFO [0m|#28805] Started GET "/search" for 127.0.0.1 at 2012-11-11 21:38:07 +0100 [[32mINFO [0m|#28805] Processing by SearchController#index as HTML [[0;37mDEBUG[0m|#28805] Setting return_to: "/search" [[0;37mDEBUG[0m|#28805] Authenticating with iChain mode: off [[32mINFO [0m|#28805] Anonymous request to /search [[32mINFO [0m|#28805] Rendered search/index.html.erb within layouts/application (4.8ms) [[32mINFO [0m|#28805] Rendered layouts/_watch_and_search.html.erb (0.6ms) [[32mINFO [0m|#28805] Rendered layouts/_header.html.erb (1.2ms) [[32mINFO [0m|#28805] Rendered layouts/_personal_navigation.html.erb (1.3ms) [[32mINFO [0m|#28805] Rendered layouts/_flash.html.erb (56.7ms) [[32mINFO [0m|#28805] Rendered layouts/_sponsor.html.erb (0.1ms) [[32mINFO [0m|#28805] Completed 200 OK in 76ms (Views: 68.8ms | ActiveRecord: 0.0ms | XML: 3.0ms) [[32mINFO [0m|#28805] 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-11 21:38:08 +0100 [[32mINFO [0m|#28805] Processing by SearchController#search as HTML [[32mINFO [0m|#28805] 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|#28805] 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|#28805] Authenticating with iChain mode: off [[32mINFO [0m|#28805] 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= [[32mINFO [0m|#28805] Redirected to http://localhost:3199/search [[32mINFO [0m|#28805] Completed 302 Found in 6ms (ActiveRecord: 0.0ms | XML: 1.9ms) [[32mINFO [0m|#28805] Started GET "/search" for 127.0.0.1 at 2012-11-11 21:38:08 +0100 [[32mINFO [0m|#28805] Processing by SearchController#index as HTML [[0;37mDEBUG[0m|#28805] Setting return_to: "/search" [[0;37mDEBUG[0m|#28805] Authenticating with iChain mode: off [[32mINFO [0m|#28805] Anonymous request to /search [[32mINFO [0m|#28805] Rendered search/index.html.erb within layouts/application (2.2ms) [[32mINFO [0m|#28805] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#28805] Rendered layouts/_header.html.erb (1.2ms) [[32mINFO [0m|#28805] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|#28805] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#28805] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#28805] Completed 200 OK in 15ms (Views: 9.1ms | ActiveRecord: 0.0ms | XML: 2.2ms) [[32mINFO [0m|#28805] Started GET "/" for 127.0.0.1 at 2012-11-11 21:38:08 +0100 [[32mINFO [0m|#28805] Processing by MainController#index as HTML [[0;37mDEBUG[0m|#28805] Setting return_to: "/" [[0;37mDEBUG[0m|#28805] Authenticating with iChain mode: off [[32mINFO [0m|#28805] Anonymous request to / [[32mINFO [0m|#28805] Rendered main/_news.html.erb (0.5ms) [[32mINFO [0m|#28805] Rendered main/_contact.html.erb (0.1ms) [[32mINFO [0m|#28805] Rendered main/_latest_updates.erb (4.5ms) [[32mINFO [0m|#28805] Rendered main/_countdown.html.erb (0.0ms) [[32mINFO [0m|#28805] Rendered main/_sponsors.html.erb (0.0ms) [[32mINFO [0m|#28805] Rendered main/_feeds.html.erb (0.1ms) [[32mINFO [0m|#28805] Rendered main/index.html.erb within layouts/application (9.7ms) [[32mINFO [0m|#28805] Rendered layouts/_watch_and_search.html.erb (0.6ms) [[32mINFO [0m|#28805] Rendered layouts/_header.html.erb (1.2ms) [[32mINFO [0m|#28805] Rendered layouts/_personal_navigation.html.erb (1.3ms) [[32mINFO [0m|#28805] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#28805] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#28805] Completed 200 OK in 62ms (Views: 15.9ms | ActiveRecord: 0.0ms | XML: 0.7ms) [[32mINFO [0m|#28805] Started GET "/main/systemstatus" for 127.0.0.1 at 2012-11-11 21:38:09 +0100 [[32mINFO [0m|#28805] Processing by MainController#systemstatus as */* [[0;37mDEBUG[0m|#28805] Setting return_to: "/main/systemstatus" [[0;37mDEBUG[0m|#28805] Authenticating with iChain mode: off [[32mINFO [0m|#28805] Anonymous request to /main/systemstatus [[32mINFO [0m|#28805] Rendered main/_systemstatus.html.erb (1.3ms) [[32mINFO [0m|#28805] Completed 200 OK in 3ms (Views: 1.9ms | ActiveRecord: 0.0ms | XML: 0.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.. sh jenkins ..c.. sh jenkins ..c.. egrep + echo 'Remove log/tmp files to save disc space' Remove log/tmp files to save disc space + rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets + exit 1 Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe(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 #445
by admin＠opensuse.org 10 Nov '12

10 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/445/changes> Changes: [Stephan Kulow] [activexml] do not double escape text ------------------------------------------ [...truncated 246593 lines...] [[0;37mDEBUG[0m|# 9059] params {:project=>"home:Iggy:branches:kde4", :package=>"BranchPack", :view=>"status"} [[0;37mDEBUG[0m|# 9059] uri is: http://localhost:3203/build/:project/_result?:view&:package&:code&:lastbuil… [[0;37mDEBUG[0m|# 9059] http_do #1: method: get url: http://localhost:3203/build/home:Iggy:branches:kde4/_result?view=status&pac… [[0;37mDEBUG[0m|# 9059] RT http://localhost:3203/build/home:Iggy:branches:kde4/_result?view=status&pac… {"view"=>nil, "db"=>1.85761, "backend"=>0, "xml"=>0, :runtime=>29.406000000000002, :all=>72.252692} [[32mINFO [0m|# 9059] Rendered package/_no_repositories.html.erb (0.5ms) [[32mINFO [0m|# 9059] Rendered package/_buildstatus.html.erb (1.0ms) [[32mINFO [0m|# 9059] Completed 200 OK in 76ms (Views: 1.7ms | ActiveRecord: 0.0ms | API: 72.3ms (XML: 0.0ms , Backend: 0.0ms , DB: 1.9ms , HTTP: 42.8ms) | XML: 0.3ms) [[32mINFO [0m|# 9059] Started GET "/home/requests" for 127.0.0.1 at 2012-11-10 15:15:59 +0100 [[32mINFO [0m|# 9059] Processing by HomeController#requests as HTML [[0;37mDEBUG[0m|# 9059] Setting return_to: "/home/requests" [[0;37mDEBUG[0m|# 9059] Authenticating with iChain mode: off [[32mINFO [0m|# 9059] Anonymous request to /home/requests [[32mINFO [0m|# 9059] Redirected to http://localhost:3199/user/login?return_to_host=http%3A%2F%2Flocalhost&retu… [[32mINFO [0m|# 9059] Filter chain halted as :require_login rendered or redirected [[32mINFO [0m|# 9059] Completed 302 Found in 2ms (ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 9059] Started GET "/user/login?return_to_host=http%3A%2F%2Flocalhost&return_to_path=%2Fhome%2Frequests" for 127.0.0.1 at 2012-11-10 15:15:59 +0100 [[32mINFO [0m|# 9059] Processing by UserController#login as HTML [[32mINFO [0m|# 9059] Parameters: {"return_to_host"=>"http://localhost", "return_to_path"=>"/home/requests"} [[0;37mDEBUG[0m|# 9059] Setting return_to: "/home/requests" [[0;37mDEBUG[0m|# 9059] Authenticating with iChain mode: off [[32mINFO [0m|# 9059] Anonymous request to /home/requests [[32mINFO [0m|# 9059] Rendered user/login.html.erb within layouts/application (1.0ms) [[32mINFO [0m|# 9059] Rendered layouts/_watch_and_search.html.erb (0.9ms) [[32mINFO [0m|# 9059] Rendered layouts/_header.html.erb (1.4ms) [[32mINFO [0m|# 9059] Rendered layouts/_personal_navigation.html.erb (1.6ms) [[32mINFO [0m|# 9059] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|# 9059] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 9059] Completed 200 OK in 12ms (Views: 9.6ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 9059] Started GET "/assets/icons-s30b5819a4d.png" for 127.0.0.1 at 2012-11-10 15:15:59 +0100 [[32mINFO [0m|# 9059] Served asset /icons-s30b5819a4d.png - 304 Not Modified (0ms) [[32mINFO [0m|# 9059] Started GET "/request/show/997" for 127.0.0.1 at 2012-11-10 15:15:59 +0100 [[32mINFO [0m|# 9059] Processing by RequestController#show as HTML [[32mINFO [0m|# 9059] Parameters: {"id"=>"997"} [[0;37mDEBUG[0m|# 9059] Setting return_to: "/request/show/997" [[0;37mDEBUG[0m|# 9059] Authenticating with iChain mode: off [[32mINFO [0m|# 9059] Anonymous request to /request/show/997 [[0;37mDEBUG[0m|# 9059] --> direct_http url: #<URI::Generic:0x000000069a7490 URL:http://localhost:3203/webui/request_show?id=997> [[0;37mDEBUG[0m|# 9059] http_do #1: method: GET url: http://localhost:3203/webui/request_show?id=997 [[0;37mDEBUG[0m|# 9059] RT http://localhost:3203/webui/request_show?id=997 {"view"=>0.882685, "db"=>4.962375999999999, "backend"=>0, "xml"=>0.15277300000000002, :runtime=>51.352000000000004, :all=>54.908982} [[0;37mDEBUG[0m|# 9059] data { "id": 997, "description": "", "state": "new", "creator": "tom", "created_at": "2012-09-03T13:08:02Z", "is_target_maintainer": false, "my_open_reviews": [ ], "other_open_reviews": [ ], "events": [ { "who": "tom", "what": "", "when": "2012-09-03T13:08:02Z", "comment": null, "color": "" } ], "actions": [ { "type": "submit", "sprj": "NotExisiting", "spkg": "notthere", "srev": "1", "tprj": "kde4", "tpkg": "kdelibs", "name": "Submit notthere", "sourcediff": { } } ] } [[32mINFO [0m|# 9059] Rendered shared/_user_with_realname_and_icon.html.erb (0.4ms) [[0;37mDEBUG[0m|# 9059] [REST] find( Project, [{:name=>"NotExisiting"}] ) [[0;37mDEBUG[0m|# 9059] params {:name=>"NotExisiting"} [[0;37mDEBUG[0m|# 9059] uri is: http://localhost:3203/source/:name/_meta?:view [[0;37mDEBUG[0m|# 9059] http_do #1: method: get url: http://localhost:3203/source/NotExisiting/_meta? [[0;37mDEBUG[0m|# 9059] RT http://localhost:3203/source/NotExisiting/_meta? {"view"=>1.08432, "db"=>1.2892919999999999, "backend"=>0, "xml"=>0, :runtime=>10.299000000000001, :all=>49.946262999999995} [[0;37mDEBUG[0m|# 9059] Project.find( {:name=>"NotExisiting"} ) did not find anything, return nil [[0;37mDEBUG[0m|# 9059] [REST] find( Package, [{:project=>"NotExisiting", :name=>"notthere"}] ) [[0;37mDEBUG[0m|# 9059] params {:project=>"NotExisiting", :name=>"notthere"} [[0;37mDEBUG[0m|# 9059] uri is: http://localhost:3203/source/:project/:name/_meta?:view [[0;37mDEBUG[0m|# 9059] http_do #1: method: get url: http://localhost:3203/source/NotExisiting/notthere/_meta? [[0;37mDEBUG[0m|# 9059] RT http://localhost:3203/source/NotExisiting/notthere/_meta? {"view"=>0.949591, "db"=>0.8047120000000001, "backend"=>0, "xml"=>0, :runtime=>9.622, :all=>50.604487} [[0;37mDEBUG[0m|# 9059] Package.find( {:project=>"NotExisiting", :name=>"notthere"} ) did not find anything, return nil [[0;37mDEBUG[0m|# 9059] [REST] find( Directory, [{:project=>"NotExisiting", :package=>"notthere"}] ) [[0;37mDEBUG[0m|# 9059] params {:project=>"NotExisiting", :package=>"notthere"} [[0;37mDEBUG[0m|# 9059] uri is: http://localhost:3203/source/:project/:package?:expand&:rev [[0;37mDEBUG[0m|# 9059] http_do #1: method: get url: http://localhost:3203/source/NotExisiting/notthere? [[0;37mDEBUG[0m|# 9059] RT http://localhost:3203/source/NotExisiting/notthere? {"view"=>0.695971, "db"=>0.554049, "backend"=>0, "xml"=>0, :runtime=>7.3790000000000004, :all=>47.125358999999996} [[0;37mDEBUG[0m|# 9059] Directory.find( {:project=>"NotExisiting", :package=>"notthere"} ) did not find anything, return nil [[32mINFO [0m|# 9059] Rendered shared/_project_or_package_link.html.erb (150.6ms) [[0;37mDEBUG[0m|# 9059] [REST] find( Project, [{:name=>"kde4"}] ) [[0;37mDEBUG[0m|# 9059] params {:name=>"kde4"} [[0;37mDEBUG[0m|# 9059] uri is: http://localhost:3203/source/:name/_meta?:view [[0;37mDEBUG[0m|# 9059] http_do #1: method: get url: http://localhost:3203/source/kde4/_meta? [[0;37mDEBUG[0m|# 9059] RT http://localhost:3203/source/kde4/_meta? {"view"=>0.567825, "db"=>0.6670189999999999, "backend"=>0, "xml"=>0, :runtime=>10.011000000000001, :all=>50.786445} [[32mINFO [0m|# 9059] Rendered shared/_project_or_package_link.html.erb (53.0ms) [[0;37mDEBUG[0m|# 9059] [{"who"=>"tom", "what"=>"", "when"=>"2012-09-03T13:08:02Z", "comment"=>nil, "color"=>""}] [[32mINFO [0m|# 9059] Rendered shared/_user_with_realname_and_icon.html.erb (0.8ms) [[32mINFO [0m|# 9059] Rendered request/_recent_events_table.html.erb (2.3ms) [[32mINFO [0m|# 9059] Rendered request/show.html.erb within layouts/application (208.6ms) [[32mINFO [0m|# 9059] Rendered layouts/_watch_and_search.html.erb (0.9ms) [[32mINFO [0m|# 9059] Rendered layouts/_header.html.erb (1.7ms) [[32mINFO [0m|# 9059] Rendered layouts/_personal_navigation.html.erb (1.7ms) [[32mINFO [0m|# 9059] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 9059] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 9059] Completed 200 OK in 276ms (Views: 217.4ms | ActiveRecord: 0.0ms | API: 253.4ms (XML: 0.2ms , View: 4.2ms , Backend: 0.0ms , DB: 8.3ms , HTTP: 164.7ms) | XML: 0.3ms) [[32mINFO [0m|# 9059] Started GET "/home/icon?id=tom&size=20" for 127.0.0.1 at 2012-11-10 15:16:00 +0100 [[32mINFO [0m|# 9059] Processing by HomeController#icon as HTML [[32mINFO [0m|# 9059] Parameters: {"id"=>"tom", "size"=>"20"} [[0;37mDEBUG[0m|# 9059] Setting return_to: "/home/icon?id=tom&size=20" [[0;37mDEBUG[0m|# 9059] Authenticating with iChain mode: off [[32mINFO [0m|# 9059] Anonymous request to /home/icon?id=tom&size=20 [[32mINFO [0m|# 9059] Rendered text template (0.0ms) [[32mINFO [0m|# 9059] Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 9059] Started GET "/assets/icons-s30b5819a4d.png" for 127.0.0.1 at 2012-11-10 15:16:00 +0100 [[32mINFO [0m|# 9059] Served asset /icons-s30b5819a4d.png - 304 Not Modified (0ms) [[32mINFO [0m|# 9059] Started GET "/request/show/998" for 127.0.0.1 at 2012-11-10 15:16:00 +0100 [[32mINFO [0m|# 9059] Processing by RequestController#show as HTML [[32mINFO [0m|# 9059] Parameters: {"id"=>"998"} [[0;37mDEBUG[0m|# 9059] Setting return_to: "/request/show/998" [[0;37mDEBUG[0m|# 9059] Authenticating with iChain mode: off [[32mINFO [0m|# 9059] Anonymous request to /request/show/998 [[0;37mDEBUG[0m|# 9059] --> direct_http url: #<URI::Generic:0x00000006f99498 URL:http://localhost:3203/webui/request_show?id=998> [[0;37mDEBUG[0m|# 9059] http_do #1: method: GET url: http://localhost:3203/webui/request_show?id=998 [[0;37mDEBUG[0m|# 9059] RT http://localhost:3203/webui/request_show?id=998 {"view"=>1.008718, "db"=>3.293312, "backend"=>0, "xml"=>0, :runtime=>25.303, :all=>28.575821} [[32mINFO [0m|# 9059] Redirected to http://localhost:3199/home/requests [[32mINFO [0m|# 9059] Completed 302 Found in 32ms (ActiveRecord: 0.0ms | API: 28.6ms (XML: 0.0ms , View: 1.0ms , Backend: 0.0ms , DB: 3.3ms , HTTP: 3.3ms) | XML: 0.0ms) [[32mINFO [0m|# 9059] Started GET "/home/requests" for 127.0.0.1 at 2012-11-10 15:16:00 +0100 [[32mINFO [0m|# 9059] Processing by HomeController#requests as HTML [[0;37mDEBUG[0m|# 9059] Setting return_to: "/home/requests" [[0;37mDEBUG[0m|# 9059] Authenticating with iChain mode: off [[32mINFO [0m|# 9059] Anonymous request to /home/requests [[32mINFO [0m|# 9059] Redirected to http://localhost:3199/user/login?return_to_host=http%3A%2F%2Flocalhost&retu… [[32mINFO [0m|# 9059] Filter chain halted as :require_login rendered or redirected [[32mINFO [0m|# 9059] Completed 302 Found in 1ms (ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 9059] Started GET "/user/login?return_to_host=http%3A%2F%2Flocalhost&return_to_path=%2Fhome%2Frequests" for 127.0.0.1 at 2012-11-10 15:16:01 +0100 [[32mINFO [0m|# 9059] Processing by UserController#login as HTML [[32mINFO [0m|# 9059] Parameters: {"return_to_host"=>"http://localhost", "return_to_path"=>"/home/requests"} [[0;37mDEBUG[0m|# 9059] Setting return_to: "/home/requests" [[0;37mDEBUG[0m|# 9059] Authenticating with iChain mode: off [[32mINFO [0m|# 9059] Anonymous request to /home/requests [[32mINFO [0m|# 9059] Rendered user/login.html.erb within layouts/application (1.9ms) [[32mINFO [0m|# 9059] Rendered layouts/_watch_and_search.html.erb (0.9ms) [[32mINFO [0m|# 9059] Rendered layouts/_header.html.erb (1.6ms) [[32mINFO [0m|# 9059] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|# 9059] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 9059] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 9059] Completed 200 OK in 12ms (Views: 9.9ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 9059] Started GET "/assets/icons-s30b5819a4d.png" for 127.0.0.1 at 2012-11-10 15:16:01 +0100 [[32mINFO [0m|# 9059] Served asset /icons-s30b5819a4d.png - 304 Not Modified (0ms) [[32mINFO [0m|# 9059] Started GET "/search" for 127.0.0.1 at 2012-11-10 15:16:01 +0100 [[32mINFO [0m|# 9059] Processing by SearchController#index as HTML [[0;37mDEBUG[0m|# 9059] Setting return_to: "/search" [[0;37mDEBUG[0m|# 9059] Authenticating with iChain mode: off [[32mINFO [0m|# 9059] Anonymous request to /search [[32mINFO [0m|# 9059] Rendered search/index.html.erb within layouts/application (1.8ms) [[32mINFO [0m|# 9059] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|# 9059] Rendered layouts/_header.html.erb (1.1ms) [[32mINFO [0m|# 9059] Rendered layouts/_personal_navigation.html.erb (1.1ms) [[32mINFO [0m|# 9059] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 9059] Rendered layouts/_sponsor.html.erb (0.1ms) [[32mINFO [0m|# 9059] Completed 200 OK in 14ms (Views: 7.7ms | ActiveRecord: 0.0ms | XML: 2.2ms) [[32mINFO [0m|# 9059] Started GET "/assets/icons-s30b5819a4d.png" for 127.0.0.1 at 2012-11-10 15:16:01 +0100 [[32mINFO [0m|# 9059] Served asset /icons-s30b5819a4d.png - 304 Not Modified (0ms) [[32mINFO [0m|# 9059] Started GET "/user/login" for 127.0.0.1 at 2012-11-10 15:16:02 +0100 [[32mINFO [0m|# 9059] Processing by UserController#login as HTML [[0;37mDEBUG[0m|# 9059] Setting return_to: "/user/login" [[0;37mDEBUG[0m|# 9059] Authenticating with iChain mode: off [[32mINFO [0m|# 9059] Anonymous request to /user/login [[32mINFO [0m|# 9059] Rendered user/login.html.erb within layouts/application (1.0ms) [[32mINFO [0m|# 9059] Rendered layouts/_watch_and_search.html.erb (0.7ms) [[32mINFO [0m|# 9059] Rendered layouts/_header.html.erb (1.4ms) [[32mINFO [0m|# 9059] Rendered layouts/_personal_navigation.html.erb (1.5ms) [[32mINFO [0m|# 9059] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 9059] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 9059] Completed 200 OK in 10ms (Views: 8.4ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 9059] Started GET "/assets/icons-s30b5819a4d.png" for 127.0.0.1 at 2012-11-10 15:16:02 +0100 [[32mINFO [0m|# 9059] Served asset /icons-s30b5819a4d.png - 304 Not Modified (0ms) [[32mINFO [0m|# 9059] Started GET "/user/register_user" for 127.0.0.1 at 2012-11-10 15:16:02 +0100 [[32mINFO [0m|# 9059] Processing by UserController#register_user as HTML [[0;37mDEBUG[0m|# 9059] Setting return_to: "/user/register_user" [[0;37mDEBUG[0m|# 9059] Authenticating with iChain mode: off [[32mINFO [0m|# 9059] Anonymous request to /user/register_user [[32mINFO [0m|# 9059] Rendered user/register_user.erb within layouts/application (60.1ms) [[32mINFO [0m|# 9059] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|# 9059] Rendered layouts/_header.html.erb (1.2ms) [[32mINFO [0m|# 9059] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|# 9059] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 9059] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 9059] Completed 200 OK in 70ms (Views: 67.6ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 9059] Started GET "/assets/icons-s30b5819a4d.png" for 127.0.0.1 at 2012-11-10 15:16:03 +0100 [[32mINFO [0m|# 9059] Served asset /icons-s30b5819a4d.png - 304 Not Modified (0ms) + 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.. 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_api #1151
by admin＠opensuse.org 09 Nov '12

09 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_api/1151/changes> Changes: [Sascha Peilicke] Bring back me bug report icon [Stephan Kulow] [webui] give request_state_icon a more specific name [Stephan Kulow] [api] for project_infos, the request IDs are enough [Stephan Kulow] [api] fix return value for source diff errors [Stephan Kulow] [webui] show the error in case the sourcediff could not be generated [Stephan Kulow] [api] try to parse the backend error and return it in human readable form [Stephan Kulow] [webui] fix crash on locked maintenance projects [Stephan Kulow] [webui] avoid crash if bots follow ajax calls ------------------------------------------ [...truncated 133524 lines...] [[0;37mDEBUG[0m|#32135] [1m[35mUser Load (0.3ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#32135] USER found: king [[0;37mDEBUG[0m|#32135] User king initialised [[0;37mDEBUG[0m|#32135] get_by_project_and_name {} [[0;37mDEBUG[0m|#32135] [1m[36mRole Load (0.4ms)[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|#32135] [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|#32135] [1m[36mPackage Load (0.2ms)[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|#32135] [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|#32135] Validate XML response: #<ActionDispatch::Response:0x0000000704bee0> took 1ms [[32mINFO [0m|#32135] Completed 200 OK in 12ms (Views: 0.5ms | ActiveRecord: 1.1ms | Backend: 0.0ms | XML: 0.3ms) [[32mINFO [0m|#32135] Started PUT "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-11-09 16:04:21 +0100 [[32mINFO [0m|#32135] Processing by SourceController#package_meta as XML [[32mINFO [0m|#32135] Parameters: {"<package name"=>"\"kdebase\" project=\"kde4\">\n <title>blub</title>\n <description>blub</description>\n</package>\n", "project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|#32135] Validate XML request: #<ActionDispatch::Request:0x000000071b0c40> [[0;37mDEBUG[0m|#32135] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#32135] [1m[36mUser Load (0.2ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#32135] USER found: king [[0;37mDEBUG[0m|#32135] User king initialised [[0;37mDEBUG[0m|#32135] [1m[35mRole Load (0.5ms)[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|#32135] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#32135] [1m[35mPackage Load (0.1ms)[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|#32135] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#32135] get_by_project_and_name {} [[0;37mDEBUG[0m|#32135] [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|#32135] [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|#32135] [1m[35mCACHE (0.0ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#32135] [1m[36mFlag Load (0.2ms)[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|#32135] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1 [[0;37mDEBUG[0m|#32135] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position[0m [[0;37mDEBUG[0m|#32135] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|#32135] [1m[35mFlag Load (0.1ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 [[0;37mDEBUG[0m|#32135] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|#32135] xml_disabled? X({"name"=>"kdebase", "project"=>"kde4", "title"=>"blub", "description"=>"blub"}) [[0;37mDEBUG[0m|#32135] [1m[36mPackageUserRoleRelationship Load (0.2ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|#32135] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#32135] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#32135] [1m[36mPackageUserRoleRelationship Load (0.8ms)[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|#32135] [1m[35mPackageGroupRoleRelationship Load (0.8ms)[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|#32135] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#32135] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#32140] 2012-11-09 16:04:21 [5748]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#32135] request took 0.010717716 [[0;37mDEBUG[0m|#32135] [1m[36mPackage Load (0.5ms)[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|#32135] [1m[35m (0.2ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#32135] [1m[36mPackageGroupRoleRelationship Load (0.1ms)[0m [1mSELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|#32135] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#32135] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#32135] [1m[35m (0.0ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#32135] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#32135] [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|#32135] [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|#32135] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#32135] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#32140] 2012-11-09 16:04:21 [5749]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#32135] request took 0.020507252 [[0;37mDEBUG[0m|#32135] [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|#32135] [1m[35m (0.4ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#32135] [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#32135] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#32135] [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|#32135] [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|#32135] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#32135] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#32140] 2012-11-09 16:04:21 [5750]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#32135] request took 0.033065433 [[0;37mDEBUG[0m|#32135] [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|#32135] [1m[36m (0.2ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#32135] Validate XML response: #<ActionDispatch::Response:0x000000071b0c18> took 0ms [[32mINFO [0m|#32135] Completed 200 OK in 235ms (Views: 0.9ms | ActiveRecord: 5.4ms | Backend: 64.3ms | XML: 0.0ms) [[32mINFO [0m|#32135] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-11-09 16:04:22 +0100 [[32mINFO [0m|#32135] Processing by SourceController#package_meta as XML [[32mINFO [0m|#32135] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|#32135] Validate XML request: #<ActionDispatch::Request:0x00000004f3ebf8> [[0;37mDEBUG[0m|#32135] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#32135] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#32135] [1m[35mUser Load (0.3ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#32135] USER found: king [[0;37mDEBUG[0m|#32135] User king initialised [[0;37mDEBUG[0m|#32135] get_by_project_and_name {} [[0;37mDEBUG[0m|#32135] [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|#32135] [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|#32135] [1m[36mPackage Load (0.2ms)[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|#32135] [1m[35mProject Load (0.2ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#32135] Validate XML response: #<ActionDispatch::Response:0x00000004f3e810> took 2ms [[32mINFO [0m|#32135] Completed 200 OK in 13ms (Views: 0.4ms | ActiveRecord: 1.1ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#32135] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-09 16:04:22 +0100 [[32mINFO [0m|#32135] Processing by SourceController#package_meta as XML [[32mINFO [0m|#32135] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#32135] Validate XML request: #<ActionDispatch::Request:0x00000005b0bd78> [[0;37mDEBUG[0m|#32135] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#32135] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#32135] [1m[36mUser Load (0.3ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#32135] USER found: king [[0;37mDEBUG[0m|#32135] User king initialised [[0;37mDEBUG[0m|#32135] get_by_project_and_name {} [[0;37mDEBUG[0m|#32135] [1m[35mRole Load (0.2ms)[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|#32135] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#32135] [1m[35mPackage Load (0.2ms)[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|#32135] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#32135] Validate XML response: #<ActionDispatch::Response:0x00000005b0bd28> took 2ms [[32mINFO [0m|#32135] Completed 200 OK in 13ms (Views: 0.3ms | ActiveRecord: 1.0ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#32135] Started PUT "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-09 16:04:22 +0100 [[32mINFO [0m|#32135] Processing by SourceController#package_meta as XML [[32mINFO [0m|#32135] Parameters: {"<package name"=>"\"kdelibs\" project=\"kde4\">\n <title>blub</title>\n <description>Changed description</description>\n <devel project=\"home:Iggy\" package=\"TestPack\"/>\n <person userid=\"fredlibs\" role=\"maintainer\"/>\n <person userid=\"adrian\" role=\"reviewer\"/>\n <person userid=\"Iggy\" role=\"maintainer\"/>\n <person userid=\"Iggy\" role=\"bugowner\"/>\n <group groupid=\"test_group\" role=\"maintainer\"/>\n <group groupid=\"test_group\" role=\"reader\"/>\n <build>\n <enable/>\n </build>\n</package>\n", "project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#32135] Validate XML request: #<ActionDispatch::Request:0x000000060501d0> [[0;37mDEBUG[0m|#32135] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#32135] [1m[35mUser Load (0.3ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#32135] USER found: king [[0;37mDEBUG[0m|#32135] User king initialised [[0;37mDEBUG[0m|#32135] [1m[36mRole Load (0.2ms)[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|#32135] [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|#32135] [1m[36mPackage Load (0.2ms)[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|#32135] [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|#32135] get_by_project_and_name {} [[0;37mDEBUG[0m|#32135] [1m[36mCACHE (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#32135] [1m[35mCACHE (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|#32135] [1m[36mCACHE (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#32135] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1 [[0;37mDEBUG[0m|#32135] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1[0m [[0;37mDEBUG[0m|#32135] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position [[0;37mDEBUG[0m|#32135] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|#32135] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2[0m [[0;37mDEBUG[0m|#32135] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|#32135] xml_disabled? X({"name"=>"kdelibs", "project"=>"kde4", "title"=>"blub", "description"=>"Changed description", "devel"=>X({"project"=>"home:Iggy", "package"=>"TestPack"}), "person"=>[X({"userid"=>"fredlibs", "role"=>"maintainer"}), X({"userid"=>"adrian", "role"=>"reviewer"}), X({"userid"=>"Iggy", "role"=>"maintainer"}), X({"userid"=>"Iggy", "role"=>"bugowner"})], "group"=>[X({"groupid"=>"test_group", "role"=>"maintainer"}), X({"groupid"=>"test_group", "role"=>"reader"})], "build"=>X({"enable"=>X({})})}) [[0;37mDEBUG[0m|#32135] [1m[35mProject Load (0.2ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'686f6d653a49676779' AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#32135] [1m[36mPackage Load (0.2ms)[0m [1mSELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 502 AND `packages`.`name` = x'546573745061636b' AND (packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#32135] [1m[35mProject Load (0.2ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 502 AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#32135] [1m[36mPackageUserRoleRelationship Load (0.2ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|#32135] [1m[35mUser Load (0.1ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`id` = 4 LIMIT 1 [[0;37mDEBUG[0m|#32135] [1m[36mRole Load (0.1ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1[0m [[0;37mDEBUG[0m|#32135] [1m[35mUser Load (0.1ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`id` = 42 LIMIT 1 [[0;37mDEBUG[0m|#32135] [1m[36mRole Load (0.1ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 6 LIMIT 1[0m [[0;37mDEBUG[0m|#32135] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#32135] [1m[36m (0.2ms)[0m [1mUPDATE `packages` SET `description` = 'Changed description', `develpackage_id` = 10095, `updated_at` = '2012-11-09 15:04:22' WHERE `packages`.`id` = 1[0m [[0;37mDEBUG[0m|#32135] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#32135] [1m[35mPackageUserRoleRelationship Load (0.2ms)[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|#32135] [1m[36mPackageGroupRoleRelationship Load (0.2ms)[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|#32135] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#32135] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#32140] 2012-11-09 16:04:22 [5751]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#32140] 2012-11-09 16:04:22 [5752]: POST /event?type=package&project=kde4&package=kdelibs [[0;37mDEBUG[0m|#32135] request took 0.042134211 [[0;37mDEBUG[0m|#32135] [1m[35mPackage Load (0.8ms)[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|#32135] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#32135] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1 [[0;37mDEBUG[0m|#32135] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#32135] [1m[35mPackageUserRoleRelationship Load (0.2ms)[0m SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 3 AND bs_user_id = 267) LIMIT 1 [[0;37mDEBUG[0m|#32135] [1m[36mSQL (0.2ms)[0m [1mINSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 3)[0m [[0;37mDEBUG[0m|#32135] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#32135] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#32135] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#32135] [1m[36mUser Load (0.1ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1[0m [[0;37mDEBUG[0m|#32135] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#32135] [1m[36mPackageUserRoleRelationship Load (0.1ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 4 AND bs_user_id = 267) LIMIT 1[0m [[0;37mDEBUG[0m|#32135] [1m[35mSQL (22.3ms)[0m INSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 4) [[0;37mDEBUG[0m|#32135] [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#32135] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#32135] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#32135] [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|#32135] [1m[36mGroup Load (0.2ms)[0m [1mSELECT `groups`.* FROM `groups` WHERE `groups`.`id` = 99 LIMIT 1[0m [[0;37mDEBUG[0m|#32135] [1m[35mRole Load (0.2ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1 [[0;37mDEBUG[0m|#32135] [1m[36mGroup Load (0.1ms)[0m [1mSELECT `groups`.* FROM `groups` WHERE `groups`.`title` = 'test_group' LIMIT 1[0m [[0;37mDEBUG[0m|#32135] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#32135] [1m[36mPackageGroupRoleRelationship Load (0.2ms)[0m [1mSELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE (db_package_id = 1 AND role_id = 7 AND bs_group_id = 99) LIMIT 1[0m [[0;37mDEBUG[0m|#32135] [1m[35mSQL (0.1ms)[0m INSERT INTO `package_group_role_relationships` (`bs_group_id`, `db_package_id`, `role_id`) VALUES (99, 1, 7) [[0;37mDEBUG[0m|#32135] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#32135] [1m[35m (0.0ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#32135] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#32135] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#32135] [1m[36mPackage Load (0.2ms)[0m [1mSELECT `packages`.* FROM `packages` WHERE `packages`.`id` = 1 AND (packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#32135] [1m[35m (0.2ms)[0m SELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1 [[0;37mDEBUG[0m|#32135] [1m[36mCACHE (0.0ms)[0m [1mSELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|#32135] [1m[35mSQL (0.2ms)[0m INSERT INTO `flags` (`architecture_id`, `db_package_id`, `db_project_id`, `flag`, `position`, `repo`, `status`) VALUES (NULL, 1, NULL, 'build', 1, NULL, 'enable') [[0;37mDEBUG[0m|#32135] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#32135] [1m[36mPackageUserRoleRelationship Load (0.3ms)[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|#32135] [1m[35mPackageGroupRoleRelationship Load (0.2ms)[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|#32135] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#32135] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#32140] 2012-11-09 16:04:22 [5753]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#32140] 2012-11-09 16:04:22 [5754]: POST /event?type=package&project=kde4&package=kdelibs [[0;37mDEBUG[0m|#32135] request took 0.049598203 [[0;37mDEBUG[0m|#32135] [1m[36mPackage Load (0.5ms)[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|#32135] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#32135] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#32135] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#32135] [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|#32135] [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|#32135] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#32135] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#32140] 2012-11-09 16:04:22 [5755]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#32135] request took 0.019069288 [[0;37mDEBUG[0m|#32135] [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|#32135] [1m[36m (0.4ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#32135] Validate XML response: #<ActionDispatch::Response:0x000000060501a8> took 1ms [[32mINFO [0m|#32135] Completed 200 OK in 218ms (Views: 0.9ms | ActiveRecord: 32.2ms | Backend: 110.8ms | XML: 0.0ms) [[32mINFO [0m|#32135] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-09 16:04:22 +0100 [[32mINFO [0m|#32135] Processing by SourceController#package_meta as XML [[32mINFO [0m|#32135] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#32135] Validate XML request: #<ActionDispatch::Request:0x00000006cf3400> [[0;37mDEBUG[0m|#32135] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#32135] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#32135] [1m[35mUser Load (8.8ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#32135] USER found: king [[0;37mDEBUG[0m|#32135] User king initialised [[0;37mDEBUG[0m|#32135] get_by_project_and_name {} [[0;37mDEBUG[0m|#32135] [1m[36mRole Load (0.4ms)[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|#32135] [1m[35mProject Load (0.1ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#32135] [1m[36mPackage Load (0.2ms)[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|#32135] [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|#32135] Validate XML response: #<ActionDispatch::Response:0x00000006cf3360> took 2ms [[32mINFO [0m|#32135] Completed 200 OK in 23ms (Views: 0.5ms | ActiveRecord: 9.7ms | Backend: 0.0ms | XML: 0.0ms) [[0;37mDEBUG[0m|#32135] [1m[36m (4.9ms)[0m [1mROLLBACK[0m [[0;37mDEBUG[0m|#32135] kill 32140 + echo + cleanup + echo 'Killing backend processes' Killing backend processes + egrep 'perl|ruby' + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/> USER PID ACCESS COMMAND <http://ci.opensuse.org/job/obs_master_testsuite_api/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/*' 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 #573
by admin＠opensuse.org 09 Nov '12

09 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui/573/changes> Changes: [Stephan Kulow] [webui] do not crash on project/maintenance_incidents [Stephan Kulow] [api] fix call for maintenance subprojects [Stephan Kulow] [api] make the maintenance incidents uniq, so the webui can count [adrian] [api] fix crashes on invalid configured repositories [Stephan Kulow] [webui] also catch \r as not to replace - helps readability ------------------------------------------ [...truncated 41735 lines...] [[0;37mDEBUG[0m|#24080] http_do #1: method: get url: http://localhost:3203/person/unknown [[0;37mDEBUG[0m|#24080] RT http://localhost:3203/person/unknown {"view"=>1.2726879999999998, "db"=>0.498529, "backend"=>0, "xml"=>0, :runtime=>7.489, :all=>46.838285} [[0;37mDEBUG[0m|#24080] Person.find( {:login=>"unknown"} ) did not find anything, return nil [[32mINFO [0m|#24080] Rendered package/_commit_item.html.erb (120.5ms) [[32mINFO [0m|#24080] Rendered package/_files_view.html.erb (124.0ms) [[32mINFO [0m|#24080] Rendered layouts/_watch_and_search.html.erb (0.8ms) [[32mINFO [0m|#24080] Rendered layouts/_header.html.erb (1.4ms) [[32mINFO [0m|#24080] Rendered layouts/_personal_navigation.html.erb (1.7ms) [[32mINFO [0m|#24080] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#24080] Rendered layouts/_sponsor.html.erb (0.1ms) [[32mINFO [0m|#24080] Completed 200 OK in 457ms (Views: 191.5ms | ActiveRecord: 0.0ms | API: 425.5ms (XML: 0.0ms , View: 1.8ms , Backend: 0.0ms , DB: 10.6ms , HTTP: 245.3ms) | XML: 1.6ms) [[32mINFO [0m|#24080] Started GET "/package/view_file?expand=0&file=_link&package=pack2_linked&project=BaseDistro2.0" for 127.0.0.1 at 2012-11-09 11:51:34 +0100 [[32mINFO [0m|#24080] Processing by PackageController#view_file as HTML [[32mINFO [0m|#24080] Parameters: {"expand"=>"0", "file"=>"_link", "package"=>"pack2_linked", "project"=>"BaseDistro2.0"} [[0;37mDEBUG[0m|#24080] Setting return_to: "/package/view_file?expand=0&file=_link&package=pack2_linked&project=BaseDistro2.0" [[0;37mDEBUG[0m|#24080] Authenticating with iChain mode: off [[32mINFO [0m|#24080] Anonymous request to /package/view_file?expand=0&file=_link&package=pack2_linked&project=BaseDistro2.0 [[0;37mDEBUG[0m|#24080] --> get_source: {:project=>"BaseDistro2.0", :package=>"pack2_linked", :filename=>"_link", :rev=>nil, :expand=>"0"} [[0;37mDEBUG[0m|#24080] --> get_source path: /source/BaseDistro2.0/pack2_linked/_link?expand=0 [[0;37mDEBUG[0m|#24080] --> direct_http url: #<URI::Generic:0x00000006776dd8 URL:http://localhost:3203/source/BaseDistro2.0/pack2_linked/_link?expand=0> [[0;37mDEBUG[0m|#24080] http_do #1: method: GET url: http://localhost:3203/source/BaseDistro2.0/pack2_linked/_link?expand=0 [[0;37mDEBUG[0m|#24080] RT http://localhost:3203/source/BaseDistro2.0/pack2_linked/_link?expand=0 {"view"=>nil, "db"=>1.285157, "backend"=>0, "xml"=>0, :runtime=>18.424, :all=>58.294132} [[32mINFO [0m|#24080] Rendered package/_tabs.html.erb (3.0ms) [[32mINFO [0m|#24080] Rendered shared/_editor.html.erb (0.7ms) [[32mINFO [0m|#24080] Rendered layouts/_watch_and_search.html.erb (0.6ms) [[32mINFO [0m|#24080] Rendered layouts/_header.html.erb (1.2ms) [[32mINFO [0m|#24080] Rendered layouts/_personal_navigation.html.erb (1.3ms) [[32mINFO [0m|#24080] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#24080] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#24080] Completed 200 OK in 73ms (Views: 11.7ms | ActiveRecord: 0.0ms | API: 58.3ms (XML: 0.0ms , Backend: 0.0ms , DB: 1.3ms , HTTP: 39.9ms) | XML: 0.7ms) [[32mINFO [0m|#24080] Started GET "/package/view_file?file=my_file&package=bdpack&project=BinaryprotectedProject" for 127.0.0.1 at 2012-11-09 11:51:34 +0100 [[32mINFO [0m|#24080] Processing by PackageController#view_file as HTML [[32mINFO [0m|#24080] Parameters: {"file"=>"my_file", "package"=>"bdpack", "project"=>"BinaryprotectedProject"} [[0;37mDEBUG[0m|#24080] Setting return_to: "/package/view_file?file=my_file&package=bdpack&project=BinaryprotectedProject" [[0;37mDEBUG[0m|#24080] Authenticating with iChain mode: off [[32mINFO [0m|#24080] Anonymous request to /package/view_file?file=my_file&package=bdpack&project=BinaryprotectedProject [[0;37mDEBUG[0m|#24080] --> get_source: {:project=>"BinaryprotectedProject", :package=>"bdpack", :filename=>"my_file", :rev=>nil, :expand=>nil} [[0;37mDEBUG[0m|#24080] --> get_source path: /source/BinaryprotectedProject/bdpack/my_file? [[0;37mDEBUG[0m|#24080] --> direct_http url: #<URI::Generic:0x00000006fbfe68 URL:http://localhost:3203/source/BinaryprotectedProject/bdpack/my_file?> [[0;37mDEBUG[0m|#24080] http_do #1: method: GET url: http://localhost:3203/source/BinaryprotectedProject/bdpack/my_file? [[0;37mDEBUG[0m|#24080] RT http://localhost:3203/source/BinaryprotectedProject/bdpack/my_file? {"view"=>nil, "db"=>1.374665, "backend"=>0, "xml"=>0, :runtime=>19.7, :all=>61.581122} [[32mINFO [0m|#24080] Rendered package/_tabs.html.erb (2.6ms) [[32mINFO [0m|#24080] Rendered shared/_editor.html.erb (0.6ms) [[32mINFO [0m|#24080] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#24080] Rendered layouts/_header.html.erb (1.1ms) [[32mINFO [0m|#24080] Rendered layouts/_personal_navigation.html.erb (1.1ms) [[32mINFO [0m|#24080] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#24080] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#24080] Completed 200 OK in 76ms (Views: 11.0ms | ActiveRecord: 0.0ms | API: 61.6ms (XML: 0.0ms , Backend: 0.0ms , DB: 1.4ms , HTTP: 41.9ms) | XML: 0.4ms) [[32mINFO [0m|#24080] Started GET "/package/binaries?arch=i586&package=pack&project=SourceprotectedProject&repository=repo" for 127.0.0.1 at 2012-11-09 11:51:34 +0100 [[32mINFO [0m|#24080] Processing by PackageController#binaries as HTML [[32mINFO [0m|#24080] Parameters: {"arch"=>"i586", "package"=>"pack", "project"=>"SourceprotectedProject", "repository"=>"repo"} [[0;37mDEBUG[0m|#24080] Setting return_to: "/package/binaries?arch=i586&package=pack&project=SourceprotectedProject&repository=repo" [[0;37mDEBUG[0m|#24080] Authenticating with iChain mode: off [[32mINFO [0m|#24080] Anonymous request to /package/binaries?arch=i586&package=pack&project=SourceprotectedProject&repository=repo [[32mINFO [0m|#24080] Rendered package/_tabs.html.erb (2.6ms) [[32mINFO [0m|#24080] Rendered shared/_download_repository_link.html.erb (0.2ms) [[32mINFO [0m|#24080] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#24080] Rendered layouts/_header.html.erb (1.0ms) [[32mINFO [0m|#24080] Rendered layouts/_personal_navigation.html.erb (1.7ms) [[32mINFO [0m|#24080] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#24080] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#24080] Completed 200 OK in 14ms (Views: 12.1ms | ActiveRecord: 0.0ms | XML: 0.5ms) [[32mINFO [0m|#24080] Started GET "/package/binary?arch=i586&filename=delete_me-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" for 127.0.0.1 at 2012-11-09 11:51:34 +0100 [[32mINFO [0m|#24080] Processing by PackageController#binary as HTML [[32mINFO [0m|#24080] Parameters: {"arch"=>"i586", "filename"=>"delete_me-1.0-1.i586.rpm", "package"=>"aggregate", "project"=>"home:adrian:ProtectionTest", "repository"=>"repo"} [[0;37mDEBUG[0m|#24080] Setting return_to: "/package/binary?arch=i586&filename=delete_me-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" [[0;37mDEBUG[0m|#24080] Authenticating with iChain mode: off [[32mINFO [0m|#24080] Anonymous request to /package/binary?arch=i586&filename=delete_me-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo [[0;37mDEBUG[0m|#24080] [REST] find( Fileinfo, [{:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :repository=>"repo", :arch=>"i586", :filename=>"delete_me-1.0-1.i586.rpm", :view=>"fileinfo_ext"}] ) [[0;37mDEBUG[0m|#24080] params {:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :repository=>"repo", :arch=>"i586", :filename=>"delete_me-1.0-1.i586.rpm", :view=>"fileinfo_ext"} [[0;37mDEBUG[0m|#24080] uri is: http://localhost:3203/build/:project/:repository/:arch/:package/:filename?:… [[0;37mDEBUG[0m|#24080] http_do #1: method: get url: http://localhost:3203/build/home:adrian:ProtectionTest/repo/i586/aggregate/… [[0;37mDEBUG[0m|#24080] RT http://localhost:3203/build/home:adrian:ProtectionTest/repo/i586/aggregate/… {"view"=>nil, "db"=>1.4136389999999999, "backend"=>0, "xml"=>0, :runtime=>55.415, :all=>58.166912} [[0;37mDEBUG[0m|#24080] Checking url: http://download.opensuse.org/repositories/home:/adrian:/ProtectionTest/repo… [[0;37mDEBUG[0m|#24080] accepting format:text/html [[32mINFO [0m|#24080] Rendered package/_tabs.html.erb (2.9ms) [[32mINFO [0m|#24080] Rendered package/_deps.html.erb (0.9ms) [[32mINFO [0m|#24080] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#24080] Rendered layouts/_header.html.erb (1.4ms) [[32mINFO [0m|#24080] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|#24080] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#24080] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#24080] Completed 200 OK in 102ms (Views: 25.1ms | ActiveRecord: 0.0ms | API: 58.2ms (XML: 0.0ms , Backend: 0.0ms , DB: 1.4ms , HTTP: 2.8ms) | XML: 1.0ms) [[32mINFO [0m|#24080] Started GET "/package/binary?arch=i586&filename=package-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" for 127.0.0.1 at 2012-11-09 11:51:34 +0100 [[32mINFO [0m|#24080] Processing by PackageController#binary as HTML [[32mINFO [0m|#24080] Parameters: {"arch"=>"i586", "filename"=>"package-1.0-1.i586.rpm", "package"=>"aggregate", "project"=>"home:adrian:ProtectionTest", "repository"=>"repo"} [[0;37mDEBUG[0m|#24080] Setting return_to: "/package/binary?arch=i586&filename=package-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" [[0;37mDEBUG[0m|#24080] Authenticating with iChain mode: off [[32mINFO [0m|#24080] Anonymous request to /package/binary?arch=i586&filename=package-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo [[0;37mDEBUG[0m|#24080] [REST] find( Fileinfo, [{:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :repository=>"repo", :arch=>"i586", :filename=>"package-1.0-1.i586.rpm", :view=>"fileinfo_ext"}] ) [[0;37mDEBUG[0m|#24080] params {:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :repository=>"repo", :arch=>"i586", :filename=>"package-1.0-1.i586.rpm", :view=>"fileinfo_ext"} [[0;37mDEBUG[0m|#24080] uri is: http://localhost:3203/build/:project/:repository/:arch/:package/:filename?:… [[0;37mDEBUG[0m|#24080] http_do #1: method: get url: http://localhost:3203/build/home:adrian:ProtectionTest/repo/i586/aggregate/… [[0;37mDEBUG[0m|#24080] RT http://localhost:3203/build/home:adrian:ProtectionTest/repo/i586/aggregate/… {"view"=>nil, "db"=>1.571146, "backend"=>0, "xml"=>0, :runtime=>45.732, :all=>48.888066} [[0;37mDEBUG[0m|#24080] Checking url: http://download.opensuse.org/repositories/home:/adrian:/ProtectionTest/repo… [[0;37mDEBUG[0m|#24080] accepting format:text/html [[32mINFO [0m|#24080] Rendered package/_tabs.html.erb (10.8ms) [[32mINFO [0m|#24080] Rendered package/_deps.html.erb (78.0ms) [[32mINFO [0m|#24080] Rendered layouts/_watch_and_search.html.erb (0.6ms) [[32mINFO [0m|#24080] Rendered layouts/_header.html.erb (1.5ms) [[32mINFO [0m|#24080] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|#24080] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#24080] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#24080] Completed 200 OK in 172ms (Views: 99.7ms | ActiveRecord: 0.0ms | API: 48.9ms (XML: 0.0ms , Backend: 0.0ms , DB: 1.6ms , HTTP: 3.2ms) | XML: 1.0ms) [[32mINFO [0m|#24080] Started GET "/package/view_file?file=_aggregate&package=aggregate&project=home%3Aadrian%3AProtectionTest" for 127.0.0.1 at 2012-11-09 11:51:34 +0100 [[32mINFO [0m|#24080] Processing by PackageController#view_file as HTML [[32mINFO [0m|#24080] Parameters: {"file"=>"_aggregate", "package"=>"aggregate", "project"=>"home:adrian:ProtectionTest"} [[0;37mDEBUG[0m|#24080] Setting return_to: "/package/view_file?file=_aggregate&package=aggregate&project=home%3Aadrian%3AProtectionTest" [[0;37mDEBUG[0m|#24080] Authenticating with iChain mode: off [[32mINFO [0m|#24080] Anonymous request to /package/view_file?file=_aggregate&package=aggregate&project=home%3Aadrian%3AProtectionTest [[0;37mDEBUG[0m|#24080] --> get_source: {:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :filename=>"_aggregate", :rev=>nil, :expand=>nil} [[0;37mDEBUG[0m|#24080] --> get_source path: /source/home:adrian:ProtectionTest/aggregate/_aggregate? [[0;37mDEBUG[0m|#24080] --> direct_http url: #<URI::Generic:0x00000005b79d28 URL:http://localhost:3203/source/home:adrian:ProtectionTest/aggregate/_aggregate?> [[0;37mDEBUG[0m|#24080] http_do #1: method: GET url: http://localhost:3203/source/home:adrian:ProtectionTest/aggregate/_aggregat… [[0;37mDEBUG[0m|#24080] RT http://localhost:3203/source/home:adrian:ProtectionTest/aggregate/_aggregat… {"view"=>nil, "db"=>1.853863, "backend"=>0, "xml"=>0, :runtime=>23.262, :all=>26.471643999999998} [[32mINFO [0m|#24080] Rendered package/_tabs.html.erb (3.0ms) [[32mINFO [0m|#24080] Rendered shared/_editor.html.erb (0.6ms) [[32mINFO [0m|#24080] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#24080] Rendered layouts/_header.html.erb (1.4ms) [[32mINFO [0m|#24080] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|#24080] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#24080] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#24080] Completed 200 OK in 43ms (Views: 13.1ms | ActiveRecord: 0.0ms | API: 26.5ms (XML: 0.0ms , Backend: 0.0ms , DB: 1.9ms , HTTP: 3.2ms) | XML: 0.5ms) [[32mINFO [0m|#24080] Started GET "/package/binaries?arch=i586&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" for 127.0.0.1 at 2012-11-09 11:51:34 +0100 [[32mINFO [0m|#24080] Processing by PackageController#binaries as HTML [[32mINFO [0m|#24080] Parameters: {"arch"=>"i586", "package"=>"aggregate", "project"=>"home:adrian:ProtectionTest", "repository"=>"repo"} [[0;37mDEBUG[0m|#24080] Setting return_to: "/package/binaries?arch=i586&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" [[0;37mDEBUG[0m|#24080] Authenticating with iChain mode: off [[32mINFO [0m|#24080] Anonymous request to /package/binaries?arch=i586&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo [[32mINFO [0m|#24080] Rendered package/_tabs.html.erb (3.0ms) [[32mINFO [0m|#24080] Rendered shared/_download_repository_link.html.erb (0.1ms) [[32mINFO [0m|#24080] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#24080] Rendered layouts/_header.html.erb (1.1ms) [[32mINFO [0m|#24080] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|#24080] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#24080] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#24080] Completed 200 OK in 15ms (Views: 12.1ms | ActiveRecord: 0.0ms | XML: 0.6ms) [[32mINFO [0m|#24080] Started POST "/user/logout" for 127.0.0.1 at 2012-11-09 11:51:34 +0100 [[32mINFO [0m|#24080] Processing by UserController#logout as HTML [[0;37mDEBUG[0m|#24080] Setting return_to: "/user/logout" [[0;37mDEBUG[0m|#24080] Authenticating with iChain mode: off [[32mINFO [0m|#24080] Anonymous request to /user/logout [[32mINFO [0m|#24080] Logging out: [[32mINFO [0m|#24080] Redirected to http://example.org/ [[32mINFO [0m|#24080] Completed 302 Found in 2ms (ActiveRecord: 0.2ms | XML: 0.4ms) [[0;37mDEBUG[0m|#24080] [1m[35m (0.5ms)[0m rollback transaction [[0;37mDEBUG[0m|#24080] [1m[36m (0.0ms)[0m [1mbegin transaction[0m [[32mINFO [0m|#24080] Started POST "/user/do_login" for 127.0.0.1 at 2012-11-09 11:51:34 +0100 [[32mINFO [0m|#24080] Processing by UserController#do_login as HTML [[32mINFO [0m|#24080] Parameters: {"username"=>"tom", "password"=>"[FILTERED]", "return_to_path"=>"/"} [[0;37mDEBUG[0m|#24080] Setting return_to: "/" [[0;37mDEBUG[0m|#24080] Authenticating with iChain mode: off [[32mINFO [0m|#24080] Anonymous request to / [[0;37mDEBUG[0m|#24080] Doing form authorization to login user tom [[0;37mDEBUG[0m|#24080] [REST] find( Person, [{:login=>"tom"}] ) [[0;37mDEBUG[0m|#24080] params {:login=>"tom"} [[0;37mDEBUG[0m|#24080] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#24080] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#24080] RT http://localhost:3203/person/tom {"view"=>0.5337430000000001, "db"=>0.7573080000000001, "backend"=>0, "xml"=>0, :runtime=>8.398, :all=>11.858706} [[32mINFO [0m|#24080] Redirected to http://example.org/ [[32mINFO [0m|#24080] Completed 302 Found in 14ms (ActiveRecord: 0.0ms | API: 11.9ms (XML: 0.0ms , View: 0.5ms , Backend: 0.0ms , DB: 0.8ms , HTTP: 3.5ms) | XML: 0.1ms) [[32mINFO [0m|#24080] Started GET "/" for 127.0.0.1 at 2012-11-09 11:51:34 +0100 [[32mINFO [0m|#24080] Processing by MainController#index as HTML [[0;37mDEBUG[0m|#24080] Setting return_to: "/" [[0;37mDEBUG[0m|#24080] Authenticating with iChain mode: off [[32mINFO [0m|#24080] Authenticated request to "/" from tom [[0;37mDEBUG[0m|#24080] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#24080] params {:is_current=>"true", :login=>"tom"} [[0;37mDEBUG[0m|#24080] uri is: http://localhost:3203/person/:login [[0;37mDEBUG[0m|#24080] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#24080] RT http://localhost:3203/person/tom {"view"=>0.43342800000000004, "db"=>0.7663909999999999, "backend"=>0, "xml"=>0, :runtime=>7.726, :all=>47.338055} [[0;37mDEBUG[0m|#24080] --> direct_http url: #<URI::Generic:0x0000000701bdd0 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom> [[0;37mDEBUG[0m|#24080] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom [[0;37mDEBUG[0m|#24080] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.230459, "db"=>3.34681, "backend"=>0, "xml"=>0, :runtime=>33.131, :all=>75.271191} [[0;37mDEBUG[0m|#24080] data { "declined": [ ], "new": [ ], "reviews": [ 1000 ] } [[32mINFO [0m|#24080] Rendered main/_news.html.erb (0.8ms) [[32mINFO [0m|#24080] Rendered main/_contact.html.erb (0.1ms) [[32mINFO [0m|#24080] Rendered main/_latest_updates.erb (6.9ms) [[32mINFO [0m|#24080] Rendered main/_countdown.html.erb (0.0ms) [[32mINFO [0m|#24080] Rendered main/_sponsors.html.erb (0.0ms) [[32mINFO [0m|#24080] Rendered main/_feeds.html.erb (0.0ms) [[32mINFO [0m|#24080] Rendered layouts/_watch_and_search.html.erb (0.7ms) [[32mINFO [0m|#24080] Rendered layouts/_header.html.erb (1.5ms) [[32mINFO [0m|#24080] Rendered layouts/_personal_navigation.html.erb (1.0ms) [[32mINFO [0m|#24080] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#24080] Rendered layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#24080] Completed 200 OK in 146ms (Views: 19.5ms | ActiveRecord: 0.0ms | API: 122.6ms (XML: 0.0ms , View: 0.7ms , Backend: 0.0ms , DB: 4.1ms , HTTP: 81.8ms) | XML: 0.9ms) [[32mINFO [0m|#24080] Started POST "/user/logout" for 127.0.0.1 at 2012-11-09 11:51:35 +0100 [[32mINFO [0m|#24080] Processing by UserController#logout as HTML [[0;37mDEBUG[0m|#24080] Setting return_to: "/user/logout" [[0;37mDEBUG[0m|#24080] Authenticating with iChain mode: off [[32mINFO [0m|#24080] Authenticated request to "/user/logout" from tom [[32mINFO [0m|#24080] Logging out: tom [[32mINFO [0m|#24080] Redirected to http://example.org/ [[32mINFO [0m|#24080] Completed 302 Found in 2ms (ActiveRecord: 0.2ms | XML: 0.0ms) [[0;37mDEBUG[0m|#24080] [1m[35m (0.6ms)[0m rollback transaction + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/> + egrep 'perl|ruby' USER PID ACCESS COMMAND <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/>: jenkins ..c.. sh jenkins ..c.. sh jenkins ..c.. egrep + echo 'Remove log/tmp files to save disc space' Remove log/tmp files to save disc space + rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets + exit 1 Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe(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_api #1148
by admin＠opensuse.org 09 Nov '12

09 Nov '12

See <http://ci.opensuse.org/job/obs_master_testsuite_api/1148/changes> Changes: [Stephan Kulow] [api] packages not found can't have a diff ------------------------------------------ [...truncated 132704 lines...] [[0;37mDEBUG[0m|# 2998] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|# 2998] USER found: king [[0;37mDEBUG[0m|# 2998] User king initialised [[0;37mDEBUG[0m|# 2998] get_by_project_and_name {} [[0;37mDEBUG[0m|# 2998] [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|# 2998] [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|# 2998] [1m[36mPackage Load (0.2ms)[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|# 2998] [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|# 2998] Validate XML response: #<ActionDispatch::Response:0x000000063ffe48> took 2ms [[32mINFO [0m|# 2998] Completed 200 OK in 12ms (Views: 0.4ms | ActiveRecord: 0.9ms | Backend: 0.0ms | XML: 0.6ms) [[32mINFO [0m|# 2998] Started PUT "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-11-08 18:19:40 +0100 [[32mINFO [0m|# 2998] Processing by SourceController#package_meta as XML [[32mINFO [0m|# 2998] Parameters: {"<package name"=>"\"kdebase\" project=\"kde4\">\n <title>blub</title>\n <description>blub</description>\n</package>\n", "project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|# 2998] Validate XML request: #<ActionDispatch::Request:0x00000006766028> [[0;37mDEBUG[0m|# 2998] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|# 2998] [1m[36mUser Load (0.2ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] USER found: king [[0;37mDEBUG[0m|# 2998] User king initialised [[0;37mDEBUG[0m|# 2998] [1m[35mRole Load (0.2ms)[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|# 2998] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] [1m[35mPackage Load (0.2ms)[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|# 2998] [1m[36mProject Load (0.1ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] get_by_project_and_name {} [[0;37mDEBUG[0m|# 2998] [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|# 2998] [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|# 2998] [1m[35mCACHE (0.0ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 2998] [1m[36mFlag Load (0.2ms)[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|# 2998] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1 [[0;37mDEBUG[0m|# 2998] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position[0m [[0;37mDEBUG[0m|# 2998] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|# 2998] [1m[35mFlag Load (0.1ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 [[0;37mDEBUG[0m|# 2998] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|# 2998] xml_disabled? X({"name"=>"kdebase", "project"=>"kde4", "title"=>"blub", "description"=>"blub"}) [[0;37mDEBUG[0m|# 2998] [1m[36mPackageUserRoleRelationship Load (0.1ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|# 2998] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 2998] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|# 2998] [1m[36mPackageUserRoleRelationship Load (0.2ms)[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|# 2998] [1m[35mPackageGroupRoleRelationship Load (0.2ms)[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|# 2998] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|# 2998] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|# 3003] 2012-11-08 18:19:40 [8703]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|# 2998] request took 0.013404172 [[0;37mDEBUG[0m|# 2998] [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|# 2998] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 2998] [1m[36mPackageGroupRoleRelationship Load (0.2ms)[0m [1mSELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|# 2998] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 2998] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|# 2998] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 2998] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|# 2998] [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|# 2998] [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|# 2998] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|# 2998] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|# 3003] 2012-11-08 18:19:40 [8704]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|# 2998] request took 0.015427825 [[0;37mDEBUG[0m|# 2998] [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|# 2998] [1m[35m (0.4ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 2998] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|# 2998] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|# 2998] [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|# 2998] [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|# 2998] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|# 2998] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|# 3003] 2012-11-08 18:19:41 [8705]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|# 2998] request took 0.018906799 [[0;37mDEBUG[0m|# 2998] [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|# 2998] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|# 2998] Validate XML response: #<ActionDispatch::Response:0x00000006765fb0> took 1ms [[32mINFO [0m|# 2998] Completed 200 OK in 92ms (Views: 0.8ms | ActiveRecord: 3.6ms | Backend: 47.7ms | XML: 0.0ms) [[32mINFO [0m|# 2998] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-11-08 18:19:41 +0100 [[32mINFO [0m|# 2998] Processing by SourceController#package_meta as XML [[32mINFO [0m|# 2998] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|# 2998] Validate XML request: #<ActionDispatch::Request:0x00000006c2e8a8> [[0;37mDEBUG[0m|# 2998] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|# 2998] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|# 2998] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|# 2998] USER found: king [[0;37mDEBUG[0m|# 2998] User king initialised [[0;37mDEBUG[0m|# 2998] get_by_project_and_name {} [[0;37mDEBUG[0m|# 2998] [1m[36mRole Load (0.2ms)[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|# 2998] [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|# 2998] [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|# 2998] [1m[35mProject Load (0.2ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 2998] Validate XML response: #<ActionDispatch::Response:0x00000006c2e740> took 2ms [[32mINFO [0m|# 2998] Completed 200 OK in 25ms (Views: 0.4ms | ActiveRecord: 1.2ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 2998] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-08 18:19:41 +0100 [[32mINFO [0m|# 2998] Processing by SourceController#package_meta as XML [[32mINFO [0m|# 2998] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|# 2998] Validate XML request: #<ActionDispatch::Request:0x00000006cec358> [[0;37mDEBUG[0m|# 2998] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|# 2998] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|# 2998] [1m[36mUser Load (0.3ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] USER found: king [[0;37mDEBUG[0m|# 2998] User king initialised [[0;37mDEBUG[0m|# 2998] get_by_project_and_name {} [[0;37mDEBUG[0m|# 2998] [1m[35mRole Load (0.3ms)[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|# 2998] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] [1m[35mPackage Load (0.2ms)[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|# 2998] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] Validate XML response: #<ActionDispatch::Response:0x00000006cec308> took 2ms [[32mINFO [0m|# 2998] Completed 200 OK in 13ms (Views: 0.4ms | ActiveRecord: 1.1ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 2998] Started PUT "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-08 18:19:41 +0100 [[32mINFO [0m|# 2998] Processing by SourceController#package_meta as XML [[32mINFO [0m|# 2998] Parameters: {"<package name"=>"\"kdelibs\" project=\"kde4\">\n <title>blub</title>\n <description>Changed description</description>\n <devel project=\"home:Iggy\" package=\"TestPack\"/>\n <person userid=\"fredlibs\" role=\"maintainer\"/>\n <person userid=\"adrian\" role=\"reviewer\"/>\n <person userid=\"Iggy\" role=\"maintainer\"/>\n <person userid=\"Iggy\" role=\"bugowner\"/>\n <group groupid=\"test_group\" role=\"maintainer\"/>\n <group groupid=\"test_group\" role=\"reader\"/>\n <build>\n <enable/>\n </build>\n</package>\n", "project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|# 2998] Validate XML request: #<ActionDispatch::Request:0x00000006e68268> [[0;37mDEBUG[0m|# 2998] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|# 2998] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|# 2998] USER found: king [[0;37mDEBUG[0m|# 2998] User king initialised [[0;37mDEBUG[0m|# 2998] [1m[36mRole Load (0.2ms)[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|# 2998] [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|# 2998] [1m[36mPackage Load (0.2ms)[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|# 2998] [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|# 2998] get_by_project_and_name {} [[0;37mDEBUG[0m|# 2998] [1m[36mCACHE (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] [1m[35mCACHE (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|# 2998] [1m[36mCACHE (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] [1m[35mFlag Load (0.3ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1 [[0;37mDEBUG[0m|# 2998] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position [[0;37mDEBUG[0m|# 2998] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|# 2998] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2[0m [[0;37mDEBUG[0m|# 2998] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|# 2998] xml_disabled? X({"name"=>"kdelibs", "project"=>"kde4", "title"=>"blub", "description"=>"Changed description", "devel"=>X({"project"=>"home:Iggy", "package"=>"TestPack"}), "person"=>[X({"userid"=>"fredlibs", "role"=>"maintainer"}), X({"userid"=>"adrian", "role"=>"reviewer"}), X({"userid"=>"Iggy", "role"=>"maintainer"}), X({"userid"=>"Iggy", "role"=>"bugowner"})], "group"=>[X({"groupid"=>"test_group", "role"=>"maintainer"}), X({"groupid"=>"test_group", "role"=>"reader"})], "build"=>X({"enable"=>X({})})}) [[0;37mDEBUG[0m|# 2998] [1m[35mProject Load (0.2ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'686f6d653a49676779' AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 2998] [1m[36mPackage Load (0.2ms)[0m [1mSELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 502 AND `packages`.`name` = x'546573745061636b' AND (packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] [1m[35mProject Load (0.1ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 502 AND (projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 2998] [1m[36mPackageUserRoleRelationship Load (0.2ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|# 2998] [1m[35mUser Load (0.1ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`id` = 4 LIMIT 1 [[0;37mDEBUG[0m|# 2998] [1m[36mRole Load (0.1ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] [1m[35mUser Load (0.1ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`id` = 42 LIMIT 1 [[0;37mDEBUG[0m|# 2998] [1m[36mRole Load (0.1ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 6 LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 2998] [1m[36m (0.5ms)[0m [1mUPDATE `packages` SET `description` = 'Changed description', `develpackage_id` = 10095, `updated_at` = '2012-11-08 17:19:41' WHERE `packages`.`id` = 1[0m [[0;37mDEBUG[0m|# 2998] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|# 2998] [1m[35mPackageUserRoleRelationship Load (0.2ms)[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|# 2998] [1m[36mPackageGroupRoleRelationship Load (0.2ms)[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|# 2998] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|# 2998] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|# 3003] 2012-11-08 18:19:41 [8706]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|# 3003] 2012-11-08 18:19:41 [8707]: POST /event?type=package&project=kde4&package=kdelibs [[0;37mDEBUG[0m|# 2998] request took 0.054311892 [[0;37mDEBUG[0m|# 2998] [1m[35mPackage Load (0.5ms)[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|# 2998] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|# 2998] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1 [[0;37mDEBUG[0m|# 2998] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|# 2998] [1m[35mPackageUserRoleRelationship Load (6.8ms)[0m SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 3 AND bs_user_id = 267) LIMIT 1 [[0;37mDEBUG[0m|# 2998] [1m[36mSQL (0.3ms)[0m [1mINSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 3)[0m [[0;37mDEBUG[0m|# 2998] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 2998] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|# 2998] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 2998] [1m[36mUser Load (0.3ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 2998] [1m[36mPackageUserRoleRelationship Load (0.1ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 4 AND bs_user_id = 267) LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] [1m[35mSQL (0.1ms)[0m INSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 4) [[0;37mDEBUG[0m|# 2998] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|# 2998] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 2998] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|# 2998] [1m[35mPackageGroupRoleRelationship Load (0.2ms)[0m SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|# 2998] [1m[36mGroup Load (0.1ms)[0m [1mSELECT `groups`.* FROM `groups` WHERE `groups`.`id` = 99 LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] [1m[35mRole Load (0.2ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1 [[0;37mDEBUG[0m|# 2998] [1m[36mGroup Load (0.2ms)[0m [1mSELECT `groups`.* FROM `groups` WHERE `groups`.`title` = 'test_group' LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 2998] [1m[36mPackageGroupRoleRelationship Load (7.0ms)[0m [1mSELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE (db_package_id = 1 AND role_id = 7 AND bs_group_id = 99) LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] [1m[35mSQL (0.3ms)[0m INSERT INTO `package_group_role_relationships` (`bs_group_id`, `db_package_id`, `role_id`) VALUES (99, 1, 7) [[0;37mDEBUG[0m|# 2998] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|# 2998] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 2998] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|# 2998] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 2998] [1m[36mPackage Load (0.3ms)[0m [1mSELECT `packages`.* FROM `packages` WHERE `packages`.`id` = 1 AND (packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 2998] [1m[35m (0.2ms)[0m SELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1 [[0;37mDEBUG[0m|# 2998] [1m[36mCACHE (0.0ms)[0m [1mSELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|# 2998] [1m[35mSQL (0.2ms)[0m INSERT INTO `flags` (`architecture_id`, `db_package_id`, `db_project_id`, `flag`, `position`, `repo`, `status`) VALUES (NULL, 1, NULL, 'build', 1, NULL, 'enable') [[0;37mDEBUG[0m|# 2998] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|# 2998] [1m[36mPackageUserRoleRelationship Load (0.3ms)[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|# 2998] [1m[35mPackageGroupRoleRelationship Load (0.2ms)[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|# 2998] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|# 2998] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|# 3003] 2012-11-08 18:19:41 [8708]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|# 3003] 2012-11-08 18:19:41 [8709]: POST /event?type=package&project=kde4&package=kdelibs [[0;37mDEBUG[0m|# 2998] request took 0.033118768 [[0;37mDEBUG[0m|# 2998] [1m[36mPackage Load (0.5ms)[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|# 2998] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 2998] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|# 2998] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|# 2998] [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|# 2998] [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|# 2998] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|# 2998] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|# 3003] 2012-11-08 18:19:41 [8710]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|# 2998] request took 0.032222289 [[0;37mDEBUG[0m|# 2998] [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|# 2998] [1m[36m (0.9ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|# 2998] Validate XML response: #<ActionDispatch::Response:0x00000006e68240> took 1ms [[32mINFO [0m|# 2998] Completed 200 OK in 218ms (Views: 1.0ms | ActiveRecord: 23.5ms | Backend: 119.7ms | XML: 0.0ms) [[32mINFO [0m|# 2998] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-08 18:19:41 +0100 [[32mINFO [0m|# 2998] Processing by SourceController#package_meta as XML [[32mINFO [0m|# 2998] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|# 2998] Validate XML request: #<ActionDispatch::Request:0x00000007476060> [[0;37mDEBUG[0m|# 2998] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|# 2998] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|# 2998] [1m[35mUser Load (0.3ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|# 2998] USER found: king [[0;37mDEBUG[0m|# 2998] User king initialised [[0;37mDEBUG[0m|# 2998] get_by_project_and_name {} [[0;37mDEBUG[0m|# 2998] [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|# 2998] [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|# 2998] [1m[36mPackage Load (0.2ms)[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|# 2998] [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|# 2998] Validate XML response: #<ActionDispatch::Response:0x00000007476038> took 2ms [[32mINFO [0m|# 2998] Completed 200 OK in 12ms (Views: 0.3ms | ActiveRecord: 1.1ms | Backend: 0.0ms | XML: 0.0ms) [[0;37mDEBUG[0m|# 2998] [1m[36m (6.9ms)[0m [1mROLLBACK[0m [[0;37mDEBUG[0m|# 2998] kill 3003 + 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.. egrep + echo 'Remove log/tmp files to save disc space' Remove log/tmp files to save disc space + rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets 'src/webui/log/*' 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