From admin@opensuse.org Tue Oct 2 09:41:58 2012 From: admin@opensuse.org To: obs-tests@lists.opensuse.org Subject: [obs-tests] Build failed in Jenkins: obs_master_testsuite_webui-testsuite #334 Date: Tue, 02 Oct 2012 11:41:38 +0200 Message-ID: <2110291400.191349170898618.JavaMail.jenkins@ci-opensuse> MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="===============5351466308462473896==" --===============5351466308462473896== Content-Type: text/plain; charset="utf-8" Content-Transfer-Encoding: quoted-printable See Changes: [Stephan Kulow] [webui] fixes for the overview page for packages ------------------------------------------ [...truncated 242467 lines...] [=1B[32mINFO =1B[0m|#28705] Served asset /icons-sf0a0401b17.png - 304 Not Mod= ified (0ms) [=1B[32mINFO =1B[0m|#28705]=20 Started GET "/package/buildresult?project=3Dhome%3Auser2&package=3DHomePackag= e1-Duplicate&index=3D" for 127.0.0.1 at 2012-10-02 11:36:31 +0200 [=1B[32mINFO =1B[0m|#28705] Processing by PackageController#buildresult as */* [=1B[32mINFO =1B[0m|#28705] Parameters: {"project"=3D>"home:user2", "packag= e"=3D>"HomePackage1-Duplicate", "index"=3D>""} [=1B[0;37mDEBUG=1B[0m|#28705] Setting return_to: "/package/buildresult?projec= t=3Dhome%3Auser2&package=3DHomePackage1-Duplicate&index=3D" [=1B[0;37mDEBUG=1B[0m|#28705] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#28705] Anonymous request to /package/buildresult?project= =3Dhome%3Auser2&package=3DHomePackage1-Duplicate&index=3D [=1B[0;37mDEBUG=1B[0m|#28705] Project.find( 300 seconds, home:user2, {}) [=1B[0;37mDEBUG=1B[0m|#28705] Package.find( 1800 seconds, HomePackage1-Duplic= ate, {:project=3D> HomeProject Title Test generated empty home project for second user. }) [=1B[0;37mDEBUG=1B[0m|#28705] Buildresult.find( 300 seconds, {:project=3D> HomeProject Title Test generated empty home project for second user. , :package=3D> Title for HomePackage1 Empty home project package created by user1. , :view=3D>"status"}) [=1B[0;37mDEBUG=1B[0m|#28705] [REST] find( Buildresult, [{:project=3D>"home:u= ser2", :package=3D>"HomePackage1-Duplicate", :view=3D>"status"}] ) [=1B[0;37mDEBUG=1B[0m|#28705] http_do #1: method: get url: http://localhost:3= 203/build/home:user2/_result?view=3Dstatus&package=3DHomePackage1-Duplicate [=1B[0;37mDEBUG=1B[0m|#28705] RT {"view"=3D>nil, "db"=3D>0.809048, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>77.142, :all=3D>82.560637} [=1B[32mINFO =1B[0m|#28705] Rendered package/_no_repositories.html.erb (0.4= ms) [=1B[32mINFO =1B[0m|#28705] Rendered package/_buildstatus.html.erb (0.7ms) [=1B[32mINFO =1B[0m|#28705] Completed 200 OK in 88ms (Views: 1.2ms | ActiveRe= cord: 0.0ms | API: 82.6ms (XML: 0.0ms , Backend: 0.0ms , DB: 0.8ms , HTTP: 5.= 4ms) | XML: 0.2ms) [=1B[32mINFO =1B[0m|#28705]=20 Started GET "/package/rpmlint_result?project=3Dhome%3Auser2&package=3DHomePac= kage1-Duplicate&index=3D" for 127.0.0.1 at 2012-10-02 11:36:31 +0200 [=1B[32mINFO =1B[0m|#28705] Processing by PackageController#rpmlint_result as= */* [=1B[32mINFO =1B[0m|#28705] Parameters: {"project"=3D>"home:user2", "packag= e"=3D>"HomePackage1-Duplicate", "index"=3D>""} [=1B[0;37mDEBUG=1B[0m|#28705] Setting return_to: "/package/rpmlint_result?pro= ject=3Dhome%3Auser2&package=3DHomePackage1-Duplicate&index=3D" [=1B[0;37mDEBUG=1B[0m|#28705] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#28705] Anonymous request to /package/rpmlint_result?proj= ect=3Dhome%3Auser2&package=3DHomePackage1-Duplicate&index=3D [=1B[0;37mDEBUG=1B[0m|#28705] Project.find( 300 seconds, home:user2, {}) [=1B[0;37mDEBUG=1B[0m|#28705] Package.find( 1800 seconds, HomePackage1-Duplic= ate, {:project=3D> HomeProject Title Test generated empty home project for second user. }) [=1B[0;37mDEBUG=1B[0m|#28705] Buildresult.find( 300 seconds, {:project=3D> HomeProject Title Test generated empty home project for second user. , :package=3D> Title for HomePackage1 Empty home project package created by user1. , :view=3D>"status"}) [=1B[32mINFO =1B[0m|#28705] Rendered package/_no_repositories.html.erb (0.4= ms) [=1B[32mINFO =1B[0m|#28705] Completed 200 OK in 6ms (Views: 4.6ms | ActiveRec= ord: 0.0ms | XML: 0.0ms) [=1B[32mINFO =1B[0m|#28705]=20 Started GET "/home?user=3Duser2" for 127.0.0.1 at 2012-10-02 11:36:32 +0200 [=1B[32mINFO =1B[0m|#28705] Processing by HomeController#index as HTML [=1B[32mINFO =1B[0m|#28705] Parameters: {"user"=3D>"user2"} [=1B[0;37mDEBUG=1B[0m|#28705] Setting return_to: "/home?user=3Duser2" [=1B[0;37mDEBUG=1B[0m|#28705] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#28705] Anonymous request to /home?user=3Duser2 [=1B[32mINFO =1B[0m|#28705] Redirected to http://localhost:3199/user/login?re= turn_to_host=3Dhttp%3A%2F%2Flocalhost&return_to_path=3D%2Fhome%3Fuser%3Duser2 [=1B[32mINFO =1B[0m|#28705] Filter chain halted as :require_login rendered or= redirected [=1B[32mINFO =1B[0m|#28705] Completed 302 Found in 2ms (ActiveRecord: 0.0ms |= XML: 0.0ms) [=1B[32mINFO =1B[0m|#28705]=20 Started GET "/user/login?return_to_host=3Dhttp%3A%2F%2Flocalhost&return_to_pa= th=3D%2Fhome%3Fuser%3Duser2" for 127.0.0.1 at 2012-10-02 11:36:32 +0200 [=1B[32mINFO =1B[0m|#28705] Processing by UserController#login as HTML [=1B[32mINFO =1B[0m|#28705] Parameters: {"return_to_host"=3D>"http://localh= ost", "return_to_path"=3D>"/home?user=3Duser2"} [=1B[0;37mDEBUG=1B[0m|#28705] Setting return_to: "/home?user=3Duser2" [=1B[0;37mDEBUG=1B[0m|#28705] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#28705] Anonymous request to /home?user=3Duser2 [=1B[32mINFO =1B[0m|#28705] Rendered user/login.html.erb within layouts/app= lication (0.7ms) [=1B[32mINFO =1B[0m|#28705] Rendered layouts/_watch_and_search.html.erb (0.= 4ms) [=1B[32mINFO =1B[0m|#28705] Rendered vendor/neutral/layouts/_header.html.er= b (1.0ms) [=1B[32mINFO =1B[0m|#28705] Rendered layouts/_personal_navigation.html.erb = (1.1ms) [=1B[32mINFO =1B[0m|#28705] Rendered layouts/_flash.html.erb (0.1ms) [=1B[32mINFO =1B[0m|#28705] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#28705] Completed 200 OK in 7ms (Views: 5.9ms | ActiveRec= ord: 0.0ms | XML: 0.0ms) [=1B[32mINFO =1B[0m|#28705]=20 Started GET "/package/files?package=3DHomePackage1-Duplicate&project=3Dhome%3= Auser2" for 127.0.0.1 at 2012-10-02 11:36:32 +0200 [=1B[32mINFO =1B[0m|#28705] Processing by PackageController#files as HTML [=1B[32mINFO =1B[0m|#28705] Parameters: {"package"=3D>"HomePackage1-Duplica= te", "project"=3D>"home:user2"} [=1B[0;37mDEBUG=1B[0m|#28705] Setting return_to: "/package/files?package=3DHo= mePackage1-Duplicate&project=3Dhome%3Auser2" [=1B[0;37mDEBUG=1B[0m|#28705] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#28705] Anonymous request to /package/files?package=3DHom= ePackage1-Duplicate&project=3Dhome%3Auser2 [=1B[0;37mDEBUG=1B[0m|#28705] Project.find( 300 seconds, home:user2, {}) [=1B[0;37mDEBUG=1B[0m|#28705] Package.find( 1800 seconds, HomePackage1-Duplic= ate, {:project=3D> HomeProject Title Test generated empty home project for second user. }) [=1B[0;37mDEBUG=1B[0m|#28705] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#28705] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#28705] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3Dhome%3Auser2&package=3DHomePackage1-Duplicate [=1B[0;37mDEBUG=1B[0m|#28705] RT {"view"=3D>0.5025109999999999, "db"=3D>0.735= 209, "backend"=3D>0, "xml"=3D>0.053294, :runtime=3D>9.573, :all=3D>12.43686} [=1B[0;37mDEBUG=1B[0m|#28705] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#28705] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#28705] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3Dhome%3Aus= er2&package=3DHomePackage1-Duplicate [=1B[0;37mDEBUG=1B[0m|#28705] RT {"view"=3D>0.34943, "db"=3D>0.71622000000000= 01, "backend"=3D>0, "xml"=3D>0.044919, :runtime=3D>6.919, :all=3D>53.718327} [=1B[0;37mDEBUG=1B[0m|#28705] Directory.find( 1800 seconds, {:project=3D>"hom= e:user2", :package=3D>"HomePackage1-Duplicate"}) [=1B[0;37mDEBUG=1B[0m|#28705] [REST] find( Directory, [{:project=3D>"home:use= r2", :package=3D>"HomePackage1-Duplicate"}] ) [=1B[0;37mDEBUG=1B[0m|#28705] http_do #1: method: get url: http://localhost:3= 203/source/home:user2/HomePackage1-Duplicate? [=1B[0;37mDEBUG=1B[0m|#28705] RT {"view"=3D>nil, "db"=3D>1.7212429999999999, = "backend"=3D>0, "xml"=3D>0, :runtime=3D>28.675, :all=3D>71.129945} [=1B[0;37mDEBUG=1B[0m|#28705] Directory.find( 1800 seconds, {:project=3D>"hom= e:user2", :package=3D>"HomePackage1-Duplicate"}) [=1B[0;37mDEBUG=1B[0m|#28705] [REST] find( Directory, [{:project=3D>"home:use= r2", :package=3D>"HomePackage1-Duplicate"}] ) [=1B[0;37mDEBUG=1B[0m|#28705] http_do #1: method: get url: http://localhost:3= 203/source/home:user2/HomePackage1-Duplicate? [=1B[0;37mDEBUG=1B[0m|#28705] RT {"view"=3D>nil, "db"=3D>1.809103, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>27.825, :all=3D>70.53166399999999} [=1B[0;37mDEBUG=1B[0m|#28705] Directory.find( nil, {:project=3D>"home:user2",= :package=3D>"HomePackage1-Duplicate", :expand=3D>1, :rev=3D>"1"}) [=1B[0;37mDEBUG=1B[0m|#28705] [REST] find( Directory, [{:project=3D>"home:use= r2", :package=3D>"HomePackage1-Duplicate", :expand=3D>"1", :rev=3D>"1"}] ) [=1B[0;37mDEBUG=1B[0m|#28705] http_do #1: method: get url: http://localhost:3= 203/source/home:user2/HomePackage1-Duplicate?expand=3D1&rev=3D1 [=1B[0;37mDEBUG=1B[0m|#28705] RT {"view"=3D>nil, "db"=3D>1.9306930000000002, = "backend"=3D>0, "xml"=3D>0, :runtime=3D>28.253, :all=3D>71.920923} [=1B[0;37mDEBUG=1B[0m|#28705] Directory.find( nil, {:project=3D>"home:user2",= :package=3D>"HomePackage1-Duplicate", :expand=3D>0, :rev=3D>"1"}) [=1B[0;37mDEBUG=1B[0m|#28705] [REST] find( Directory, [{:project=3D>"home:use= r2", :package=3D>"HomePackage1-Duplicate", :expand=3D>"0", :rev=3D>"1"}] ) [=1B[0;37mDEBUG=1B[0m|#28705] http_do #1: method: get url: http://localhost:3= 203/source/home:user2/HomePackage1-Duplicate?expand=3D0&rev=3D1 [=1B[0;37mDEBUG=1B[0m|#28705] RT {"view"=3D>nil, "db"=3D>1.884612, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>28.646, :all=3D>68.61569200000001} [=1B[0;37mDEBUG=1B[0m|#28705] Link.find( 1800 seconds, {:project=3D> HomeProject Title Test generated empty home project for second user. , :package=3D> Title for HomePackage1 Empty home project package created by user1. , :rev=3D>"1"}) [=1B[0;37mDEBUG=1B[0m|#28705] Service.find( 1800 seconds, {:project=3D> HomeProject Title Test generated empty home project for second user. , :package=3D> Title for HomePackage1 Empty home project package created by user1. }) [=1B[0;37mDEBUG=1B[0m|#28705] [REST] find( Service, [{:project=3D>"home:user2= ", :package=3D>"HomePackage1-Duplicate"}] ) [=1B[0;37mDEBUG=1B[0m|#28705] http_do #1: method: get url: http://localhost:3= 203/source/home:user2/HomePackage1-Duplicate/_service [=1B[0;37mDEBUG=1B[0m|#28705] RT {"view"=3D>nil, "db"=3D>1.573187, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>25.04, :all=3D>65.14563} [=1B[0;37mDEBUG=1B[0m|#28705] Service.find( {:project=3D>"home:user2", :packa= ge=3D>"HomePackage1-Duplicate"} ) did not find anything, return nil [=1B[31mERROR=1B[0m|#28705] rescue_action: caught NameError: undefined local = variable or method `forced_unexpand' for # [=1B[31mERROR=1B[0m|#28705] Couldn't parse error xml: # /usr/lib64/ruby/1.9.1/rexml/parsers/treepa [=1B[0;37mDEBUG=1B[0m|#28705] ERROR: unknown; undefined local variable or met= hod `forced_unexpand' for # [=1B[0;37mDEBUG=1B[0m|#28705] :165:in = `files' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/= implicit_render.rb:4:in `send_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/base= .rb:167:in `process_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/= rendering.rb:10:in `process_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/call= backs.rb:18:in `block in process_action' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callba= cks.rb:524:in `_run__2120106953382052421__process_action__589064157985815815_= _callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callba= cks.rb:405:in `__run_callback' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callba= cks.rb:385:in `_run_process_action_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callba= cks.rb:81:in `run_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/call= backs.rb:17:in `process_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/= rescue.rb:29:in `process_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/= instrumentation.rb:30:in `block in process_action' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifi= cations.rb:123:in `block in instrument' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifi= cations/instrumenter.rb:20:in `instrument' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifi= cations.rb:123:in `instrument' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/= instrumentation.rb:29:in `process_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/= params_wrapper.rb:207:in `process_action' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/railties= /controller_runtime.rb:18:in `process_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/base= .rb:121:in `process' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/rend= ering.rb:45:in `process' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal.= rb:203:in `dispatch' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/= rack_delegation.rb:14:in `dispatch' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal.= rb:246:in `block in action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/routing/= route_set.rb:73:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/routing/= route_set.rb:73:in `dispatch' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/routing/= route_set.rb:36:in `call' /usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:68:in `bl= ock in call' /usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `ea= ch' /usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `ca= ll' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/routing/= route_set.rb:600:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/best_standards_support.rb:17:in `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/etag.rb:23:in `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/conditionalget.rb:25:in `= call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/head.rb:14:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/params_parser.rb:21:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/flash.rb:242:in `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:20= 5:in `context' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:20= 0:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/cookies.rb:339:in `call' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/query_ca= che.rb:64:in `call' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connecti= on_adapters/abstract/connection_pool.rb:473:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/callbacks.rb:28:in `block in call' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callba= cks.rb:405:in `_run__93265294073113600__call__1677348900581626612__callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callba= cks.rb:405:in `__run_callback' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callba= cks.rb:385:in `_run_call_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callba= cks.rb:81:in `run_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/callbacks.rb:27:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/remote_ip.rb:31:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/debug_exceptions.rb:16:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/show_exceptions.rb:56:in `call' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/rack/logger.rb:26:in= `call_app' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/rack/logger.rb:16:in= `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/request_id.rb:22:in `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/methodoverride.rb:21:in `= call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/runtime.rb:17:in `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/lock.rb:15:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/static.rb:62:in `call' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/engine.rb:479:in `ca= ll' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/application.rb:223:i= n `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/content_length.rb:14:in `= call' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/rack/log_tailer.rb:1= 7:in `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/handler/webrick.rb:59:in = `service' /usr/lib64/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /usr/lib64/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /usr/lib64/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' [=1B[32mINFO =1B[0m|#28705] Rendered error.html.erb within layouts/applicat= ion (1.1ms) [=1B[32mINFO =1B[0m|#28705] Rendered layouts/_watch_and_search.html.erb (0.= 5ms) [=1B[32mINFO =1B[0m|#28705] Rendered vendor/neutral/layouts/_header.html.er= b (1.7ms) [=1B[32mINFO =1B[0m|#28705] Rendered layouts/_personal_navigation.html.erb = (51.1ms) [=1B[32mINFO =1B[0m|#28705] Rendered layouts/_flash.html.erb (0.3ms) [=1B[32mINFO =1B[0m|#28705] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#28705] Completed 400 Bad Request in 490ms (Views: 59.6ms= | ActiveRecord: 0.0ms | API: 413.5ms (XML: 0.1ms , View: 0.9ms , Backend: 0.= 0ms , DB: 10.4ms , HTTP: 258.6ms) | XML: 1.1ms) + echo + cleanup + echo 'Killing backend processes' Killing backend processes + egrep 'perl|ruby' + fuser -v USER PID ACCESS COMMAND : 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/cach= e src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/tes= t.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 --=20 To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org --===============5351466308462473896==-- From admin@opensuse.org Tue Oct 2 10:05:14 2012 From: admin@opensuse.org To: obs-tests@lists.opensuse.org Subject: [obs-tests] Build failed in Jenkins: obs_master_testsuite_webui-testsuite #335 Date: Tue, 02 Oct 2012 12:04:57 +0200 Message-ID: <1702061427.201349172298187.JavaMail.jenkins@ci-opensuse> In-Reply-To: <2110291400.191349170898618.JavaMail.jenkins@ci-opensuse> MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="===============5841344573455251709==" --===============5841344573455251709== Content-Type: text/plain; charset="utf-8" Content-Transfer-Encoding: quoted-printable See Changes: [Stephan Kulow] [webui] include the icons needed for the codemirror ui ------------------------------------------ [...truncated 242732 lines...] [=1B[32mINFO =1B[0m|# 1780] Served asset /icons-s9df5a73f38.png - 304 Not Mod= ified (0ms) [=1B[32mINFO =1B[0m|# 1780]=20 Started GET "/package/buildresult?project=3Dhome%3Auser2&package=3DHomePackag= e1-Duplicate&index=3D" for 127.0.0.1 at 2012-10-02 11:59:53 +0200 [=1B[32mINFO =1B[0m|# 1780] Processing by PackageController#buildresult as */* [=1B[32mINFO =1B[0m|# 1780] Parameters: {"project"=3D>"home:user2", "packag= e"=3D>"HomePackage1-Duplicate", "index"=3D>""} [=1B[0;37mDEBUG=1B[0m|# 1780] Setting return_to: "/package/buildresult?projec= t=3Dhome%3Auser2&package=3DHomePackage1-Duplicate&index=3D" [=1B[0;37mDEBUG=1B[0m|# 1780] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 1780] Anonymous request to /package/buildresult?project= =3Dhome%3Auser2&package=3DHomePackage1-Duplicate&index=3D [=1B[0;37mDEBUG=1B[0m|# 1780] Project.find( 300 seconds, home:user2, {}) [=1B[0;37mDEBUG=1B[0m|# 1780] Package.find( 1800 seconds, HomePackage1-Duplic= ate, {:project=3D> HomeProject Title Test generated empty home project for second user. }) [=1B[0;37mDEBUG=1B[0m|# 1780] Buildresult.find( 300 seconds, {:project=3D> HomeProject Title Test generated empty home project for second user. , :package=3D> Title for HomePackage1 Empty home project package created by user1. , :view=3D>"status"}) [=1B[0;37mDEBUG=1B[0m|# 1780] [REST] find( Buildresult, [{:project=3D>"home:u= ser2", :package=3D>"HomePackage1-Duplicate", :view=3D>"status"}] ) [=1B[0;37mDEBUG=1B[0m|# 1780] http_do #1: method: get url: http://localhost:3= 203/build/home:user2/_result?view=3Dstatus&package=3DHomePackage1-Duplicate [=1B[0;37mDEBUG=1B[0m|# 1780] RT {"view"=3D>nil, "db"=3D>0.900868, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>86.43299999999999, :all=3D>89.642933} [=1B[32mINFO =1B[0m|# 1780] Rendered package/_no_repositories.html.erb (0.5= ms) [=1B[32mINFO =1B[0m|# 1780] Rendered package/_buildstatus.html.erb (1.1ms) [=1B[32mINFO =1B[0m|# 1780] Completed 200 OK in 95ms (Views: 1.7ms | ActiveRe= cord: 0.0ms | API: 89.6ms (XML: 0.0ms , Backend: 0.0ms , DB: 0.9ms , HTTP: 3.= 2ms) | XML: 0.1ms) [=1B[32mINFO =1B[0m|# 1780]=20 Started GET "/package/rpmlint_result?project=3Dhome%3Auser2&package=3DHomePac= kage1-Duplicate&index=3D" for 127.0.0.1 at 2012-10-02 11:59:53 +0200 [=1B[32mINFO =1B[0m|# 1780] Processing by PackageController#rpmlint_result as= */* [=1B[32mINFO =1B[0m|# 1780] Parameters: {"project"=3D>"home:user2", "packag= e"=3D>"HomePackage1-Duplicate", "index"=3D>""} [=1B[0;37mDEBUG=1B[0m|# 1780] Setting return_to: "/package/rpmlint_result?pro= ject=3Dhome%3Auser2&package=3DHomePackage1-Duplicate&index=3D" [=1B[0;37mDEBUG=1B[0m|# 1780] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 1780] Anonymous request to /package/rpmlint_result?proj= ect=3Dhome%3Auser2&package=3DHomePackage1-Duplicate&index=3D [=1B[0;37mDEBUG=1B[0m|# 1780] Project.find( 300 seconds, home:user2, {}) [=1B[0;37mDEBUG=1B[0m|# 1780] Package.find( 1800 seconds, HomePackage1-Duplic= ate, {:project=3D> HomeProject Title Test generated empty home project for second user. }) [=1B[0;37mDEBUG=1B[0m|# 1780] Buildresult.find( 300 seconds, {:project=3D> HomeProject Title Test generated empty home project for second user. , :package=3D> Title for HomePackage1 Empty home project package created by user1. , :view=3D>"status"}) [=1B[32mINFO =1B[0m|# 1780] Rendered package/_no_repositories.html.erb (0.5= ms) [=1B[32mINFO =1B[0m|# 1780] Completed 200 OK in 4ms (Views: 2.1ms | ActiveRec= ord: 0.0ms | XML: 0.0ms) [=1B[32mINFO =1B[0m|# 1780]=20 Started GET "/home?user=3Duser2" for 127.0.0.1 at 2012-10-02 11:59:54 +0200 [=1B[32mINFO =1B[0m|# 1780] Processing by HomeController#index as HTML [=1B[32mINFO =1B[0m|# 1780] Parameters: {"user"=3D>"user2"} [=1B[0;37mDEBUG=1B[0m|# 1780] Setting return_to: "/home?user=3Duser2" [=1B[0;37mDEBUG=1B[0m|# 1780] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 1780] Anonymous request to /home?user=3Duser2 [=1B[32mINFO =1B[0m|# 1780] Redirected to http://localhost:3199/user/login?re= turn_to_host=3Dhttp%3A%2F%2Flocalhost&return_to_path=3D%2Fhome%3Fuser%3Duser2 [=1B[32mINFO =1B[0m|# 1780] Filter chain halted as :require_login rendered or= redirected [=1B[32mINFO =1B[0m|# 1780] Completed 302 Found in 1ms (ActiveRecord: 0.0ms |= XML: 0.0ms) [=1B[32mINFO =1B[0m|# 1780]=20 Started GET "/user/login?return_to_host=3Dhttp%3A%2F%2Flocalhost&return_to_pa= th=3D%2Fhome%3Fuser%3Duser2" for 127.0.0.1 at 2012-10-02 11:59:54 +0200 [=1B[32mINFO =1B[0m|# 1780] Processing by UserController#login as HTML [=1B[32mINFO =1B[0m|# 1780] Parameters: {"return_to_host"=3D>"http://localh= ost", "return_to_path"=3D>"/home?user=3Duser2"} [=1B[0;37mDEBUG=1B[0m|# 1780] Setting return_to: "/home?user=3Duser2" [=1B[0;37mDEBUG=1B[0m|# 1780] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 1780] Anonymous request to /home?user=3Duser2 [=1B[32mINFO =1B[0m|# 1780] Rendered user/login.html.erb within layouts/app= lication (0.7ms) [=1B[32mINFO =1B[0m|# 1780] Rendered layouts/_watch_and_search.html.erb (0.= 5ms) [=1B[32mINFO =1B[0m|# 1780] Rendered vendor/neutral/layouts/_header.html.er= b (1.2ms) [=1B[32mINFO =1B[0m|# 1780] Rendered layouts/_personal_navigation.html.erb = (1.2ms) [=1B[32mINFO =1B[0m|# 1780] Rendered layouts/_flash.html.erb (0.1ms) [=1B[32mINFO =1B[0m|# 1780] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|# 1780] Completed 200 OK in 9ms (Views: 6.8ms | ActiveRec= ord: 0.0ms | XML: 0.0ms) [=1B[32mINFO =1B[0m|# 1780]=20 Started GET "/package/files?package=3DHomePackage1-Duplicate&project=3Dhome%3= Auser2" for 127.0.0.1 at 2012-10-02 11:59:54 +0200 [=1B[32mINFO =1B[0m|# 1780] Processing by PackageController#files as HTML [=1B[32mINFO =1B[0m|# 1780] Parameters: {"package"=3D>"HomePackage1-Duplica= te", "project"=3D>"home:user2"} [=1B[0;37mDEBUG=1B[0m|# 1780] Setting return_to: "/package/files?package=3DHo= mePackage1-Duplicate&project=3Dhome%3Auser2" [=1B[0;37mDEBUG=1B[0m|# 1780] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 1780] Anonymous request to /package/files?package=3DHom= ePackage1-Duplicate&project=3Dhome%3Auser2 [=1B[0;37mDEBUG=1B[0m|# 1780] Project.find( 300 seconds, home:user2, {}) [=1B[0;37mDEBUG=1B[0m|# 1780] Package.find( 1800 seconds, HomePackage1-Duplic= ate, {:project=3D> HomeProject Title Test generated empty home project for second user. }) [=1B[0;37mDEBUG=1B[0m|# 1780] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 1780] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 1780] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3Dhome%3Auser2&package=3DHomePackage1-Duplicate [=1B[0;37mDEBUG=1B[0m|# 1780] RT {"view"=3D>0.209982, "db"=3D>0.471181, "back= end"=3D>0, "xml"=3D>0.089307, :runtime=3D>9.086, :all=3D>12.555999} [=1B[0;37mDEBUG=1B[0m|# 1780] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 1780] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 1780] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3Dhome%3Aus= er2&package=3DHomePackage1-Duplicate [=1B[0;37mDEBUG=1B[0m|# 1780] RT {"view"=3D>0.324384, "db"=3D>0.55797, "backe= nd"=3D>0, "xml"=3D>0.0, :runtime=3D>4.928, :all=3D>46.743971} [=1B[0;37mDEBUG=1B[0m|# 1780] Directory.find( 1800 seconds, {:project=3D>"hom= e:user2", :package=3D>"HomePackage1-Duplicate"}) [=1B[0;37mDEBUG=1B[0m|# 1780] [REST] find( Directory, [{:project=3D>"home:use= r2", :package=3D>"HomePackage1-Duplicate"}] ) [=1B[0;37mDEBUG=1B[0m|# 1780] http_do #1: method: get url: http://localhost:3= 203/source/home:user2/HomePackage1-Duplicate? [=1B[0;37mDEBUG=1B[0m|# 1780] RT {"view"=3D>nil, "db"=3D>1.249224, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>23.206999999999997, :all=3D>62.90509399999999} [=1B[0;37mDEBUG=1B[0m|# 1780] Directory.find( 1800 seconds, {:project=3D>"hom= e:user2", :package=3D>"HomePackage1-Duplicate"}) [=1B[0;37mDEBUG=1B[0m|# 1780] [REST] find( Directory, [{:project=3D>"home:use= r2", :package=3D>"HomePackage1-Duplicate"}] ) [=1B[0;37mDEBUG=1B[0m|# 1780] http_do #1: method: get url: http://localhost:3= 203/source/home:user2/HomePackage1-Duplicate? [=1B[0;37mDEBUG=1B[0m|# 1780] RT {"view"=3D>nil, "db"=3D>0.8678959999999999, = "backend"=3D>0, "xml"=3D>0, :runtime=3D>22.25, :all=3D>63.891582} [=1B[0;37mDEBUG=1B[0m|# 1780] Directory.find( nil, {:project=3D>"home:user2",= :package=3D>"HomePackage1-Duplicate", :expand=3D>1, :rev=3D>"1"}) [=1B[0;37mDEBUG=1B[0m|# 1780] [REST] find( Directory, [{:project=3D>"home:use= r2", :package=3D>"HomePackage1-Duplicate", :expand=3D>"1", :rev=3D>"1"}] ) [=1B[0;37mDEBUG=1B[0m|# 1780] http_do #1: method: get url: http://localhost:3= 203/source/home:user2/HomePackage1-Duplicate?expand=3D1&rev=3D1 [=1B[0;37mDEBUG=1B[0m|# 1780] RT {"view"=3D>nil, "db"=3D>1.4097399999999998, = "backend"=3D>0, "xml"=3D>0, :runtime=3D>22.986, :all=3D>62.93135399999999} [=1B[0;37mDEBUG=1B[0m|# 1780] Directory.find( nil, {:project=3D>"home:user2",= :package=3D>"HomePackage1-Duplicate", :expand=3D>0, :rev=3D>"1"}) [=1B[0;37mDEBUG=1B[0m|# 1780] [REST] find( Directory, [{:project=3D>"home:use= r2", :package=3D>"HomePackage1-Duplicate", :expand=3D>"0", :rev=3D>"1"}] ) [=1B[0;37mDEBUG=1B[0m|# 1780] http_do #1: method: get url: http://localhost:3= 203/source/home:user2/HomePackage1-Duplicate?expand=3D0&rev=3D1 [=1B[0;37mDEBUG=1B[0m|# 1780] RT {"view"=3D>nil, "db"=3D>1.02366, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>25.209, :all=3D>65.781149} [=1B[0;37mDEBUG=1B[0m|# 1780] Link.find( 1800 seconds, {:project=3D> HomeProject Title Test generated empty home project for second user. , :package=3D> Title for HomePackage1 Empty home project package created by user1. , :rev=3D>"1"}) [=1B[0;37mDEBUG=1B[0m|# 1780] Service.find( 1800 seconds, {:project=3D> HomeProject Title Test generated empty home project for second user. , :package=3D> Title for HomePackage1 Empty home project package created by user1. }) [=1B[0;37mDEBUG=1B[0m|# 1780] [REST] find( Service, [{:project=3D>"home:user2= ", :package=3D>"HomePackage1-Duplicate"}] ) [=1B[0;37mDEBUG=1B[0m|# 1780] http_do #1: method: get url: http://localhost:3= 203/source/home:user2/HomePackage1-Duplicate/_service [=1B[0;37mDEBUG=1B[0m|# 1780] RT {"view"=3D>nil, "db"=3D>1.133499, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>25.28, :all=3D>68.016664} [=1B[0;37mDEBUG=1B[0m|# 1780] Service.find( {:project=3D>"home:user2", :packa= ge=3D>"HomePackage1-Duplicate"} ) did not find anything, return nil [=1B[31mERROR=1B[0m|# 1780] rescue_action: caught NameError: undefined local = variable or method `forced_unexpand' for # [=1B[31mERROR=1B[0m|# 1780] Couldn't parse error xml: # /usr/lib64/ruby/1.9.1/rexml/parsers/treepa [=1B[0;37mDEBUG=1B[0m|# 1780] ERROR: unknown; undefined local variable or met= hod `forced_unexpand' for # [=1B[0;37mDEBUG=1B[0m|# 1780] :165:in = `files' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/= implicit_render.rb:4:in `send_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/base= .rb:167:in `process_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/= rendering.rb:10:in `process_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/call= backs.rb:18:in `block in process_action' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callba= cks.rb:524:in `_run__2556887229979692744__process_action__17908057915397612__= callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callba= cks.rb:405:in `__run_callback' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callba= cks.rb:385:in `_run_process_action_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callba= cks.rb:81:in `run_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/call= backs.rb:17:in `process_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/= rescue.rb:29:in `process_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/= instrumentation.rb:30:in `block in process_action' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifi= cations.rb:123:in `block in instrument' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifi= cations/instrumenter.rb:20:in `instrument' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifi= cations.rb:123:in `instrument' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/= instrumentation.rb:29:in `process_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/= params_wrapper.rb:207:in `process_action' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/railties= /controller_runtime.rb:18:in `process_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/base= .rb:121:in `process' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/rend= ering.rb:45:in `process' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal.= rb:203:in `dispatch' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/= rack_delegation.rb:14:in `dispatch' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal.= rb:246:in `block in action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/routing/= route_set.rb:73:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/routing/= route_set.rb:73:in `dispatch' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/routing/= route_set.rb:36:in `call' /usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:68:in `bl= ock in call' /usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `ea= ch' /usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `ca= ll' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/routing/= route_set.rb:600:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/best_standards_support.rb:17:in `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/etag.rb:23:in `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/conditionalget.rb:25:in `= call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/head.rb:14:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/params_parser.rb:21:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/flash.rb:242:in `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:20= 5:in `context' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:20= 0:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/cookies.rb:339:in `call' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/query_ca= che.rb:64:in `call' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connecti= on_adapters/abstract/connection_pool.rb:473:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/callbacks.rb:28:in `block in call' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callba= cks.rb:405:in `_run__3600464292568714688__call__4017857882405206259__callback= s' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callba= cks.rb:405:in `__run_callback' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callba= cks.rb:385:in `_run_call_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callba= cks.rb:81:in `run_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/callbacks.rb:27:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/remote_ip.rb:31:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/debug_exceptions.rb:16:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/show_exceptions.rb:56:in `call' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/rack/logger.rb:26:in= `call_app' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/rack/logger.rb:16:in= `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/request_id.rb:22:in `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/methodoverride.rb:21:in `= call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/runtime.rb:17:in `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/lock.rb:15:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middlewa= re/static.rb:62:in `call' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/engine.rb:479:in `ca= ll' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/application.rb:223:i= n `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/content_length.rb:14:in `= call' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/rack/log_tailer.rb:1= 7:in `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/handler/webrick.rb:59:in = `service' /usr/lib64/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /usr/lib64/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /usr/lib64/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' [=1B[32mINFO =1B[0m|# 1780] Rendered error.html.erb within layouts/applicat= ion (1.7ms) [=1B[32mINFO =1B[0m|# 1780] Rendered layouts/_watch_and_search.html.erb (0.= 5ms) [=1B[32mINFO =1B[0m|# 1780] Rendered vendor/neutral/layouts/_header.html.er= b (1.5ms) [=1B[32mINFO =1B[0m|# 1780] Rendered layouts/_personal_navigation.html.erb = (1.4ms) [=1B[32mINFO =1B[0m|# 1780] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 1780] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|# 1780] Completed 400 Bad Request in 411ms (Views: 11.4ms= | ActiveRecord: 0.0ms | API: 382.8ms (XML: 0.1ms , View: 0.5ms , Backend: 0.= 0ms , DB: 6.7ms , HTTP: 249.9ms) | XML: 1.3ms) + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v + egrep 'perl|ruby' USER PID ACCESS COMMAND : 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/back= end_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/ap= i/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 --=20 To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org --===============5841344573455251709==-- From admin@opensuse.org Tue Oct 2 10:44:12 2012 From: admin@opensuse.org To: obs-tests@lists.opensuse.org Subject: [obs-tests] Build failed in Jenkins: obs_master_testsuite_webui-testsuite #336 Date: Tue, 02 Oct 2012 12:43:55 +0200 Message-ID: <270667810.211349174636052.JavaMail.jenkins@ci-opensuse> In-Reply-To: <1702061427.201349172298187.JavaMail.jenkins@ci-opensuse> MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="===============2960641431053331063==" --===============2960641431053331063== Content-Type: text/plain; charset="utf-8" Content-Transfer-Encoding: quoted-printable See Changes: [mls] [backend] do not create downgrade-deltas [Stephan Kulow] [webui] sql structure is overkill for the webui [Stephan Kulow] [webui] restore basic functionality for services [adrian] [backend] publish first the binaries and update afterwards the meta = data. Add support for arch packages. ------------------------------------------ [...truncated 302503 lines...] [=1B[0;37mDEBUG=1B[0m|#14592] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#14592] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.463652, "db"=3D>0.63686, "backe= nd"=3D>0, "xml"=3D>0.038597000000000006, :runtime=3D>8.93, :all=3D>50.2877860= 00000004} [=1B[0;37mDEBUG=1B[0m|#14592] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#14592] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DRemoteIns= tance [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.322502, "db"=3D>0.421003, "back= end"=3D>0, "xml"=3D>0.037315, :runtime=3D>5.849, :all=3D>47.33232099999999} [=1B[0;37mDEBUG=1B[0m|#14592] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#14592] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&roles=3Dsource&types=3Dma= intenance_incident&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.47656, "db"=3D>0.43136, "backen= d"=3D>0, "xml"=3D>0.033729999999999996, :runtime=3D>7.021, :all=3D>47.543014} [=1B[0;37mDEBUG=1B[0m|#14592] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: GET url: http://localhost:3= 203/search/project/?match=3Dstarts-with%28%40name%2C%27RemoteInstance%3A%27%2= 9+and+%40kind%3D%27maintenance_incident%27+and+repository%2Freleasetarget%2F%= 40trigger%3D%27maintenance%27 [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.579647, "db"=3D>1.058156, "back= end"=3D>0, "xml"=3D>0.050781, :runtime=3D>11.363, :all=3D>51.553034} [=1B[0;37mDEBUG=1B[0m|#14592] [REST] find( Package, [:all, {:project=3D>"Remo= teInstance"}] ) [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: get url: http://localhost:3= 203/source/RemoteInstance [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.49118800000000007, "db"=3D>0.73= 4308, "backend"=3D>0, "xml"=3D>0, :runtime=3D>10.344000000000001, :all=3D>51.= 260559} [=1B[0;37mDEBUG=1B[0m|#14592] [REST] find( Buildresult, [{:project=3D>"Remote= Instance", :view=3D>"status", :code=3D>["failed", "broken", "unresolvable"]}]= ) [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: get url: http://localhost:3= 203/build/RemoteInstance/_result?view=3Dstatus&code=3Dfailed&code=3Dbroken&co= de=3Dunresolvable [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>nil, "db"=3D>0.623485, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>17.302999999999997, :all=3D>59.189291} [=1B[0;37mDEBUG=1B[0m|#14592] [REST] find( Buildresult, [{:project=3D>"Remote= Instance", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: get url: http://localhost:3= 203/build/RemoteInstance/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>nil, "db"=3D>0.637615, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>16.094, :all=3D>55.267368999999995} [=1B[0;37mDEBUG=1B[0m|#14592] [REST] find( Collection, [:id, {:what=3D>"proje= ct", :predicate=3D>"maintenance/maintains/@project=3D'RemoteInstance'"}] ) [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: get url: http://localhost:3= 203/search/project/id?match=3Dmaintenance%2Fmaintains%2F%40project%3D%27Remot= eInstance%27 [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.6436000000000001, "db"=3D>0.430= 32499999999996, "backend"=3D>0, "xml"=3D>0.07271999999999999, :runtime=3D>8.6= 82, :all=3D>50.994273} [=1B[32mINFO =1B[0m|#14592] Rendered project/_tabs.html.erb (1.6ms) [=1B[32mINFO =1B[0m|#14592] Rendered shared/_open_requests.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#14592] Rendered shared/_buildresult_box.html.erb (0.6m= s) [=1B[32mINFO =1B[0m|#14592] Rendered project/show.html.erb within layouts/a= pplication (4.8ms) [=1B[32mINFO =1B[0m|#14592] Rendered layouts/_watch_and_search.html.erb (0.= 6ms) [=1B[32mINFO =1B[0m|#14592] Rendered vendor/neutral/layouts/_header.html.er= b (1.4ms) [=1B[32mINFO =1B[0m|#14592] Rendered layouts/_personal_navigation.html.erb = (1.5ms) [=1B[32mINFO =1B[0m|#14592] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#14592] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#14592] Completed 200 OK in 459ms (Views: 12.2ms | Active= Record: 0.0ms | API: 437.5ms (XML: 0.2ms , View: 3.6ms , Backend: 0.0ms , DB:= 7.0ms , HTTP: 331.5ms) | XML: 0.9ms) [=1B[32mINFO =1B[0m|#14592]=20 Started GET "/project/buildresult?project=3DRemoteInstance&index=3D" for 127.= 0.0.1 at 2012-10-02 12:38:47 +0200 [=1B[32mINFO =1B[0m|#14592] Processing by ProjectController#buildresult as */* [=1B[32mINFO =1B[0m|#14592] Parameters: {"project"=3D>"RemoteInstance", "in= dex"=3D>""} [=1B[0;37mDEBUG=1B[0m|#14592] Setting return_to: "/project/buildresult?projec= t=3DRemoteInstance&index=3D" [=1B[0;37mDEBUG=1B[0m|#14592] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#14592] Anonymous request to /project/buildresult?project= =3DRemoteInstance&index=3D [=1B[0;37mDEBUG=1B[0m|#14592] [REST] find( Buildresult, [{:project=3D>"Remote= Instance", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: get url: http://localhost:3= 203/build/RemoteInstance/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>nil, "db"=3D>1.15037, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>58.662, :all=3D>61.488333} [=1B[32mINFO =1B[0m|#14592] Rendered project/_buildstatus.html.erb (0.2ms) [=1B[32mINFO =1B[0m|#14592] Completed 200 OK in 64ms (Views: 0.5ms | ActiveRe= cord: 0.0ms | API: 61.5ms (XML: 0.0ms , Backend: 0.0ms , DB: 1.2ms , HTTP: 2.= 8ms) | XML: 0.1ms) [=1B[32mINFO =1B[0m|#14592]=20 Started GET "/project/requests?project=3DRemoteInstance" for 127.0.0.1 at 201= 2-10-02 12:38:47 +0200 [=1B[32mINFO =1B[0m|#14592] Processing by ProjectController#requests as HTML [=1B[32mINFO =1B[0m|#14592] Parameters: {"project"=3D>"RemoteInstance"} [=1B[0;37mDEBUG=1B[0m|#14592] Setting return_to: "/project/requests?project= =3DRemoteInstance" [=1B[0;37mDEBUG=1B[0m|#14592] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#14592] Anonymous request to /project/requests?project=3D= RemoteInstance [=1B[0;37mDEBUG=1B[0m|#14592] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#14592] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.57423, "db"=3D>1.193286, "backe= nd"=3D>0, "xml"=3D>0.091863, :runtime=3D>11.447000000000001, :all=3D>15.25610= 1} [=1B[0;37mDEBUG=1B[0m|#14592] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#14592] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DRemoteIns= tance [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.236865, "db"=3D>0.3071779999999= 9995, "backend"=3D>0, "xml"=3D>0.0, :runtime=3D>5.215, :all=3D>47.59148099999= 9995} [=1B[0;37mDEBUG=1B[0m|#14592] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#14592] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&roles=3Dsource&types=3Dma= intenance_incident&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.419155, "db"=3D>0.428126, "back= end"=3D>0, "xml"=3D>0.033952, :runtime=3D>6.113, :all=3D>47.203121} [=1B[32mINFO =1B[0m|#14592] Rendered project/_tabs.html.erb (1.6ms) [=1B[32mINFO =1B[0m|#14592] Rendered shared/_select_requests.html.erb (1.0m= s) [=1B[32mINFO =1B[0m|#14592] Rendered project/requests.html.erb within layou= ts/application (4.1ms) [=1B[32mINFO =1B[0m|#14592] Rendered layouts/_watch_and_search.html.erb (0.= 4ms) [=1B[32mINFO =1B[0m|#14592] Rendered vendor/neutral/layouts/_header.html.er= b (1.2ms) [=1B[32mINFO =1B[0m|#14592] Rendered layouts/_personal_navigation.html.erb = (1.0ms) [=1B[32mINFO =1B[0m|#14592] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#14592] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#14592] Completed 200 OK in 124ms (Views: 9.5ms | ActiveR= ecord: 0.0ms | API: 110.1ms (XML: 0.1ms , View: 1.2ms , Backend: 0.0ms , DB: = 1.9ms , HTTP: 87.3ms) | XML: 0.3ms) [=1B[32mINFO =1B[0m|#14592]=20 Started GET "/request/list?states=3Dnew%2Creview&types=3Dall&project=3DRemote= Instance&no_target=3Dtrue" for 127.0.0.1 at 2012-10-02 12:38:47 +0200 [=1B[32mINFO =1B[0m|#14592] Processing by RequestController#list as */* [=1B[32mINFO =1B[0m|#14592] Parameters: {"states"=3D>"new,review", "types"= =3D>"all", "project"=3D>"RemoteInstance", "no_target"=3D>"true"} [=1B[0;37mDEBUG=1B[0m|#14592] Setting return_to: "/request/list?states=3Dnew%= 2Creview&types=3Dall&project=3DRemoteInstance&no_target=3Dtrue" [=1B[0;37mDEBUG=1B[0m|#14592] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#14592] Anonymous request to /request/list?states=3Dnew%2= Creview&types=3Dall&project=3DRemoteInstance&no_target=3Dtrue [=1B[0;37mDEBUG=1B[0m|#14592] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#14592] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.372352, "db"=3D>3.465769, "back= end"=3D>0, "xml"=3D>0.145377, :runtime=3D>12.027, :all=3D>63.71144199999999} [=1B[32mINFO =1B[0m|#14592] Rendered shared/_requests.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#14592] Completed 200 OK in 66ms (Views: 0.9ms | ActiveRe= cord: 0.0ms | API: 63.7ms (XML: 0.1ms , View: 0.4ms , Backend: 0.0ms , DB: 3.= 5ms , HTTP: 51.7ms) | XML: 0.4ms) [=1B[32mINFO =1B[0m|#14592]=20 Started GET "/project/show" for 127.0.0.1 at 2012-10-02 12:38:48 +0200 [=1B[32mINFO =1B[0m|#14592] Processing by ProjectController#show as HTML [=1B[0;37mDEBUG=1B[0m|#14592] Setting return_to: "/project/show" [=1B[0;37mDEBUG=1B[0m|#14592] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#14592] Anonymous request to /project/show [=1B[32mINFO =1B[0m|#14592] Redirected to http://localhost:3199/project/list_= public?nextstatus=3D404 [=1B[32mINFO =1B[0m|#14592] Filter chain halted as :require_project rendered = or redirected [=1B[32mINFO =1B[0m|#14592] Completed 302 Found in 1ms (ActiveRecord: 0.0ms |= XML: 0.0ms) [=1B[32mINFO =1B[0m|#14592]=20 Started GET "/project/list_public?nextstatus=3D404" for 127.0.0.1 at 2012-10-= 02 12:38:48 +0200 [=1B[32mINFO =1B[0m|#14592] Processing by ProjectController#list_public as HT= ML [=1B[32mINFO =1B[0m|#14592] Parameters: {"nextstatus"=3D>"404"} [=1B[0;37mDEBUG=1B[0m|#14592] Setting return_to: "/project/list_public?nextst= atus=3D404" [=1B[0;37mDEBUG=1B[0m|#14592] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#14592] Anonymous request to /project/list_public?nextsta= tus=3D404 [=1B[32mINFO =1B[0m|#14592] Rendered project/_filter_projects.html.erb (1.0= ms) [=1B[32mINFO =1B[0m|#14592] Rendered project/_search_project.html.erb (5.7m= s) [=1B[32mINFO =1B[0m|#14592] Rendered project/list.html.erb within layouts/a= pplication (7.5ms) [=1B[32mINFO =1B[0m|#14592] Rendered layouts/_watch_and_search.html.erb (0.= 5ms) [=1B[32mINFO =1B[0m|#14592] Rendered vendor/neutral/layouts/_header.html.er= b (1.5ms) [=1B[32mINFO =1B[0m|#14592] Rendered layouts/_personal_navigation.html.erb = (1.3ms) [=1B[32mINFO =1B[0m|#14592] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#14592] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#14592] Completed 404 Not Found in 17ms (Views: 14.0ms | = ActiveRecord: 0.0ms | XML: 2.0ms) [=1B[32mINFO =1B[0m|#14592]=20 Started GET "/project/show?project=3DSourceprotectedProject" for 127.0.0.1 at= 2012-10-02 12:38:49 +0200 [=1B[32mINFO =1B[0m|#14592] Processing by ProjectController#show as HTML [=1B[32mINFO =1B[0m|#14592] Parameters: {"project"=3D>"SourceprotectedProje= ct"} [=1B[0;37mDEBUG=1B[0m|#14592] Setting return_to: "/project/show?project=3DSou= rceprotectedProject" [=1B[0;37mDEBUG=1B[0m|#14592] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#14592] Anonymous request to /project/show?project=3DSour= ceprotectedProject [=1B[0;37mDEBUG=1B[0m|#14592] [REST] find( Project, [{:name=3D>"Sourceprotect= edProject"}] ) [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/_meta? [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.745588, "db"=3D>3.1195430000000= 006, "backend"=3D>0, "xml"=3D>0, :runtime=3D>27.642, :all=3D>30.820069} [=1B[0;37mDEBUG=1B[0m|#14592] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#14592] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DSourceprotectedProject [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.49814200000000003, "db"=3D>0.76= 8958, "backend"=3D>0, "xml"=3D>0.044206, :runtime=3D>9.972999999999999, :all= =3D>50.403781} [=1B[0;37mDEBUG=1B[0m|#14592] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#14592] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DSourcepro= tectedProject [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.37238899999999997, "db"=3D>0.41= 4399, "backend"=3D>0, "xml"=3D>0.035441999999999994, :runtime=3D>6.711, :all= =3D>47.422056} [=1B[0;37mDEBUG=1B[0m|#14592] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#14592] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&roles=3Dsource&types=3Dma= intenance_incident&project=3DSourceprotectedProject [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.389976, "db"=3D>0.417674, "back= end"=3D>0, "xml"=3D>0.037075, :runtime=3D>7.045, :all=3D>47.512502} [=1B[0;37mDEBUG=1B[0m|#14592] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: GET url: http://localhost:3= 203/search/project/?match=3Dstarts-with%28%40name%2C%27SourceprotectedProject= %3A%27%29+and+%40kind%3D%27maintenance_incident%27+and+repository%2Freleaseta= rget%2F%40trigger%3D%27maintenance%27 [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.6115349999999999, "db"=3D>0.960= 653, "backend"=3D>0, "xml"=3D>0.053282, :runtime=3D>11.841000000000001, :all= =3D>51.673772} [=1B[0;37mDEBUG=1B[0m|#14592] [REST] find( Package, [:all, {:project=3D>"Sour= ceprotectedProject"}] ) [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.681731, "db"=3D>1.083994, "back= end"=3D>0, "xml"=3D>0, :runtime=3D>14.36, :all=3D>54.50389} [=1B[0;37mDEBUG=1B[0m|#14592] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :view=3D>"status", :code=3D>["failed", "broken", "unresolv= able"]}] ) [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dstatus&code=3Dfailed&code=3Db= roken&code=3Dunresolvable [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>nil, "db"=3D>0.480576, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>23.917, :all=3D>67.616053} [=1B[0;37mDEBUG=1B[0m|#14592] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: POST url: http://localhost:= 3203/source/SourceprotectedProject?cmd=3Dshowlinked [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.616115, "db"=3D>1.062282, "back= end"=3D>0, "xml"=3D>0, :runtime=3D>12.158000000000001, :all=3D>16.89723099999= 9998} [=1B[0;37mDEBUG=1B[0m|#14592] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>nil, "db"=3D>0.5107299999999999, = "backend"=3D>0, "xml"=3D>0, :runtime=3D>24.489, :all=3D>65.574439} [=1B[0;37mDEBUG=1B[0m|#14592] [REST] find( Collection, [:id, {:what=3D>"proje= ct", :predicate=3D>"maintenance/maintains/@project=3D'SourceprotectedProject'= "}] ) [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: get url: http://localhost:3= 203/search/project/id?match=3Dmaintenance%2Fmaintains%2F%40project%3D%27Sourc= eprotectedProject%27 [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.31105700000000003, "db"=3D>0.66= 33209999999999, "backend"=3D>0, "xml"=3D>0.306392, :runtime=3D>9.395999999999= 999, :all=3D>51.164226} [=1B[0;37mDEBUG=1B[0m|#14592] [REST] find( Project, [{:name=3D>"Sourceprotect= edProject"}] ) [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/_meta? [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.779264, "db"=3D>0.597759, "back= end"=3D>0, "xml"=3D>0, :runtime=3D>9.566, :all=3D>49.194186} [=1B[32mINFO =1B[0m|#14592] Rendered project/_tabs.html.erb (2.8ms) [=1B[32mINFO =1B[0m|#14592] Rendered shared/_open_requests.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#14592] Rendered shared/_buildresult_box.html.erb (0.7m= s) [=1B[32mINFO =1B[0m|#14592] Rendered project/show.html.erb within layouts/a= pplication (56.5ms) [=1B[32mINFO =1B[0m|#14592] Rendered layouts/_watch_and_search.html.erb (0.= 3ms) [=1B[32mINFO =1B[0m|#14592] Rendered vendor/neutral/layouts/_header.html.er= b (1.0ms) [=1B[32mINFO =1B[0m|#14592] Rendered layouts/_personal_navigation.html.erb = (0.8ms) [=1B[32mINFO =1B[0m|#14592] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#14592] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#14592] Completed 200 OK in 557ms (Views: 62.2ms | Active= Record: 0.0ms | API: 532.8ms (XML: 0.5ms , View: 5.0ms , Backend: 0.0ms , DB:= 10.1ms , HTTP: 375.7ms) | XML: 1.8ms) [=1B[32mINFO =1B[0m|#14592]=20 Started GET "/project/buildresult?project=3DSourceprotectedProject&index=3D" = for 127.0.0.1 at 2012-10-02 12:38:49 +0200 [=1B[32mINFO =1B[0m|#14592] Processing by ProjectController#buildresult as */* [=1B[32mINFO =1B[0m|#14592] Parameters: {"project"=3D>"SourceprotectedProje= ct", "index"=3D>""} [=1B[0;37mDEBUG=1B[0m|#14592] Setting return_to: "/project/buildresult?projec= t=3DSourceprotectedProject&index=3D" [=1B[0;37mDEBUG=1B[0m|#14592] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#14592] Anonymous request to /project/buildresult?project= =3DSourceprotectedProject&index=3D [=1B[0;37mDEBUG=1B[0m|#14592] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>nil, "db"=3D>0.9636549999999999, = "backend"=3D>0, "xml"=3D>0, :runtime=3D>24.139, :all=3D>26.882752} [=1B[32mINFO =1B[0m|#14592] Rendered project/_buildstatus.html.erb (1.5ms) [=1B[32mINFO =1B[0m|#14592] Completed 200 OK in 31ms (Views: 2.1ms | ActiveRe= cord: 0.0ms | API: 26.9ms (XML: 0.0ms , Backend: 0.0ms , DB: 1.0ms , HTTP: 2.= 7ms) | XML: 0.1ms) [=1B[32mINFO =1B[0m|#14592]=20 Started GET "/package/show?package=3Dpack&project=3DSourceprotectedProject" f= or 127.0.0.1 at 2012-10-02 12:38:50 +0200 [=1B[32mINFO =1B[0m|#14592] Processing by PackageController#show as HTML [=1B[32mINFO =1B[0m|#14592] Parameters: {"package"=3D>"pack", "project"=3D>= "SourceprotectedProject"} [=1B[0;37mDEBUG=1B[0m|#14592] Setting return_to: "/package/show?package=3Dpac= k&project=3DSourceprotectedProject" [=1B[0;37mDEBUG=1B[0m|#14592] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#14592] Anonymous request to /package/show?package=3Dpack= &project=3DSourceprotectedProject [=1B[0;37mDEBUG=1B[0m|#14592] [REST] find( Package, [{:project=3D>"Sourceprot= ectedProject", :name=3D>"pack"}] ) [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/pack/_meta? [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.44671700000000003, "db"=3D>1.80= 4755, "backend"=3D>0, "xml"=3D>0, :runtime=3D>17.423000000000002, :all=3D>20.= 869835} [=1B[0;37mDEBUG=1B[0m|#14592] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#14592] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DSourceprotectedProject&package=3Dpack [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.477388, "db"=3D>0.409594, "back= end"=3D>0, "xml"=3D>0.028956, :runtime=3D>7.991, :all=3D>46.316815999999996} [=1B[0;37mDEBUG=1B[0m|#14592] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#14592] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DSourcepro= tectedProject&package=3Dpack [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.619427, "db"=3D>0.5173129999999= 999, "backend"=3D>0, "xml"=3D>0.0, :runtime=3D>6.619, :all=3D>48.306221} [=1B[0;37mDEBUG=1B[0m|#14592] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :package=3D>"pack", :view=3D>"status"}] ) [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dstatus&package=3Dpack [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>nil, "db"=3D>0.68193, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>22.942, :all=3D>65.936192} [=1B[0;37mDEBUG=1B[0m|#14592] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: POST url: http://localhost:= 3203/source/SourceprotectedProject/pack?cmd=3Dshowlinked [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.933866, "db"=3D>0.9818890000000= 001, "backend"=3D>5.585132, "xml"=3D>0, :runtime=3D>69.05799999999999, :all= =3D>73.986204} [=1B[0;37mDEBUG=1B[0m|#14592] [REST] find( Directory, [{:project=3D>"Sourcepr= otectedProject", :package=3D>"pack"}] ) [=1B[0;37mDEBUG=1B[0m|#14592] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/pack? [=1B[0;37mDEBUG=1B[0m|#14592] RT {"view"=3D>0.956081, "db"=3D>2.1352650000000= 004, "backend"=3D>0, "xml"=3D>0, :runtime=3D>24.067, :all=3D>64.176851} [=1B[31mERROR=1B[0m|#14592] rescue_action: caught ActiveXML::Transport::Forbi= ddenError: SourceprotectedProject/pack
[=1B[0;37mDEBUG=1B[0m|#14592] ERROR: 401; SourceprotectedProject/pack [=1B[32mINFO =1B[0m|#14592] Rendered error.html.erb within layouts/applicat= ion (1.3ms) [=1B[32mINFO =1B[0m|#14592] Rendered layouts/_watch_and_search.html.erb (0.= 7ms) [=1B[32mINFO =1B[0m|#14592] Rendered vendor/neutral/layouts/_header.html.er= b (2.1ms) [=1B[32mINFO =1B[0m|#14592] Rendered layouts/_personal_navigation.html.erb = (1.4ms) [=1B[32mINFO =1B[0m|#14592] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#14592] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#14592] Completed 401 Unauthorized in 340ms (Views: 11.6m= s | ActiveRecord: 0.0ms | API: 319.6ms (XML: 0.0ms , View: 3.4ms , Backend: 5= .6ms , DB: 6.5ms , HTTP: 171.5ms) | XML: 0.5ms) + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v + egrep 'perl|ruby' USER PID ACCESS COMMAND : 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/back= end_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/ap= i/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 --=20 To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org --===============2960641431053331063==-- From admin@opensuse.org Tue Oct 2 11:20:48 2012 From: admin@opensuse.org To: obs-tests@lists.opensuse.org Subject: [obs-tests] Build failed in Jenkins: obs_master_testsuite_webui-testsuite #337 Date: Tue, 02 Oct 2012 13:20:30 +0200 Message-ID: <1133331341.221349176830762.JavaMail.jenkins@ci-opensuse> In-Reply-To: <270667810.211349174636052.JavaMail.jenkins@ci-opensuse> MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="===============7824075868521905189==" --===============7824075868521905189== Content-Type: text/plain; charset="utf-8" Content-Transfer-Encoding: quoted-printable See Changes: [adrian] [appliance] remove dangerous code which may wipe data on appliance. = Just use /srv/obs (we package no files inside anymore) ------------------------------------------ [...truncated 302142 lines...] [=1B[0;37mDEBUG=1B[0m|#27946] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#27946] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.42645500000000003, "db"=3D>0.69= 0818, "backend"=3D>0, "xml"=3D>0.075164, :runtime=3D>9.385, :all=3D>50.789472} [=1B[0;37mDEBUG=1B[0m|#27946] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#27946] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DRemoteIns= tance [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.39256399999999997, "db"=3D>0.53= 53209999999999, "backend"=3D>0, "xml"=3D>0.034117, :runtime=3D>49.456, :all= =3D>95.504283} [=1B[0;37mDEBUG=1B[0m|#27946] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#27946] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&roles=3Dsource&types=3Dma= intenance_incident&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.460705, "db"=3D>0.508497, "back= end"=3D>0, "xml"=3D>0.054313, :runtime=3D>8.487, :all=3D>51.496077} [=1B[0;37mDEBUG=1B[0m|#27946] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: GET url: http://localhost:3= 203/search/project/?match=3Dstarts-with%28%40name%2C%27RemoteInstance%3A%27%2= 9+and+%40kind%3D%27maintenance_incident%27+and+repository%2Freleasetarget%2F%= 40trigger%3D%27maintenance%27 [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.5444910000000001, "db"=3D>1.099= 9970000000001, "backend"=3D>0, "xml"=3D>0.0, :runtime=3D>13.946, :all=3D>56.4= 08819} [=1B[0;37mDEBUG=1B[0m|#27946] [REST] find( Package, [:all, {:project=3D>"Remo= teInstance"}] ) [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: get url: http://localhost:3= 203/source/RemoteInstance [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.717782, "db"=3D>0.944527, "back= end"=3D>0, "xml"=3D>0, :runtime=3D>10.734, :all=3D>53.903892} [=1B[0;37mDEBUG=1B[0m|#27946] [REST] find( Buildresult, [{:project=3D>"Remote= Instance", :view=3D>"status", :code=3D>["failed", "broken", "unresolvable"]}]= ) [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: get url: http://localhost:3= 203/build/RemoteInstance/_result?view=3Dstatus&code=3Dfailed&code=3Dbroken&co= de=3Dunresolvable [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>nil, "db"=3D>0.522651, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>17.326999999999998, :all=3D>59.736771000000005} [=1B[0;37mDEBUG=1B[0m|#27946] [REST] find( Buildresult, [{:project=3D>"Remote= Instance", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: get url: http://localhost:3= 203/build/RemoteInstance/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>nil, "db"=3D>7.629471000000001, "= backend"=3D>0, "xml"=3D>0, :runtime=3D>23.078000000000003, :all=3D>66.346793} [=1B[0;37mDEBUG=1B[0m|#27946] [REST] find( Collection, [:id, {:what=3D>"proje= ct", :predicate=3D>"maintenance/maintains/@project=3D'RemoteInstance'"}] ) [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: get url: http://localhost:3= 203/search/project/id?match=3Dmaintenance%2Fmaintains%2F%40project%3D%27Remot= eInstance%27 [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.584021, "db"=3D>0.6604990000000= 001, "backend"=3D>0, "xml"=3D>0.064273, :runtime=3D>10.768, :all=3D>51.187536} [=1B[32mINFO =1B[0m|#27946] Rendered project/_tabs.html.erb (1.6ms) [=1B[32mINFO =1B[0m|#27946] Rendered shared/_open_requests.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#27946] Rendered shared/_buildresult_box.html.erb (0.7m= s) [=1B[32mINFO =1B[0m|#27946] Rendered project/show.html.erb within layouts/a= pplication (4.7ms) [=1B[32mINFO =1B[0m|#27946] Rendered layouts/_watch_and_search.html.erb (0.= 6ms) [=1B[32mINFO =1B[0m|#27946] Rendered vendor/neutral/layouts/_header.html.er= b (1.4ms) [=1B[32mINFO =1B[0m|#27946] Rendered layouts/_personal_navigation.html.erb = (1.5ms) [=1B[32mINFO =1B[0m|#27946] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#27946] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#27946] Completed 200 OK in 532ms (Views: 12.2ms | Active= Record: 0.0ms | API: 509.1ms (XML: 0.2ms , View: 3.9ms , Backend: 0.0ms , DB:= 14.6ms , HTTP: 346.5ms) | XML: 1.0ms) [=1B[32mINFO =1B[0m|#27946]=20 Started GET "/project/buildresult?project=3DRemoteInstance&index=3D" for 127.= 0.0.1 at 2012-10-02 13:15:21 +0200 [=1B[32mINFO =1B[0m|#27946] Processing by ProjectController#buildresult as */* [=1B[32mINFO =1B[0m|#27946] Parameters: {"project"=3D>"RemoteInstance", "in= dex"=3D>""} [=1B[0;37mDEBUG=1B[0m|#27946] Setting return_to: "/project/buildresult?projec= t=3DRemoteInstance&index=3D" [=1B[0;37mDEBUG=1B[0m|#27946] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#27946] Anonymous request to /project/buildresult?project= =3DRemoteInstance&index=3D [=1B[0;37mDEBUG=1B[0m|#27946] [REST] find( Buildresult, [{:project=3D>"Remote= Instance", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: get url: http://localhost:3= 203/build/RemoteInstance/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>nil, "db"=3D>1.0041889999999998, = "backend"=3D>0, "xml"=3D>0, :runtime=3D>13.869, :all=3D>16.630760000000002} [=1B[32mINFO =1B[0m|#27946] Rendered project/_buildstatus.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#27946] Completed 200 OK in 19ms (Views: 0.5ms | ActiveRe= cord: 0.0ms | API: 16.6ms (XML: 0.0ms , Backend: 0.0ms , DB: 1.0ms , HTTP: 2.= 8ms) | XML: 0.1ms) [=1B[32mINFO =1B[0m|#27946]=20 Started GET "/project/requests?project=3DRemoteInstance" for 127.0.0.1 at 201= 2-10-02 13:15:21 +0200 [=1B[32mINFO =1B[0m|#27946] Processing by ProjectController#requests as HTML [=1B[32mINFO =1B[0m|#27946] Parameters: {"project"=3D>"RemoteInstance"} [=1B[0;37mDEBUG=1B[0m|#27946] Setting return_to: "/project/requests?project= =3DRemoteInstance" [=1B[0;37mDEBUG=1B[0m|#27946] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#27946] Anonymous request to /project/requests?project=3D= RemoteInstance [=1B[0;37mDEBUG=1B[0m|#27946] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#27946] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.450173, "db"=3D>0.691154, "back= end"=3D>0, "xml"=3D>0.094887, :runtime=3D>8.973, :all=3D>12.351512999999999} [=1B[0;37mDEBUG=1B[0m|#27946] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#27946] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DRemoteIns= tance [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.336183, "db"=3D>0.365279, "back= end"=3D>0, "xml"=3D>0.033365000000000006, :runtime=3D>5.585, :all=3D>45.80504= 9000000004} [=1B[0;37mDEBUG=1B[0m|#27946] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#27946] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&roles=3Dsource&types=3Dma= intenance_incident&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.423141, "db"=3D>0.511895, "back= end"=3D>0, "xml"=3D>0.043949, :runtime=3D>7.66, :all=3D>51.215999} [=1B[32mINFO =1B[0m|#27946] Rendered project/_tabs.html.erb (1.6ms) [=1B[32mINFO =1B[0m|#27946] Rendered shared/_select_requests.html.erb (1.2m= s) [=1B[32mINFO =1B[0m|#27946] Rendered project/requests.html.erb within layou= ts/application (4.1ms) [=1B[32mINFO =1B[0m|#27946] Rendered layouts/_watch_and_search.html.erb (0.= 6ms) [=1B[32mINFO =1B[0m|#27946] Rendered vendor/neutral/layouts/_header.html.er= b (1.4ms) [=1B[32mINFO =1B[0m|#27946] Rendered layouts/_personal_navigation.html.erb = (1.3ms) [=1B[32mINFO =1B[0m|#27946] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#27946] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#27946] Completed 200 OK in 126ms (Views: 11.1ms | Active= Record: 0.0ms | API: 109.4ms (XML: 0.2ms , View: 1.2ms , Backend: 0.0ms , DB:= 1.6ms , HTTP: 87.2ms) | XML: 0.2ms) [=1B[32mINFO =1B[0m|#27946]=20 Started GET "/request/list?states=3Dnew%2Creview&types=3Dall&project=3DRemote= Instance&no_target=3Dtrue" for 127.0.0.1 at 2012-10-02 13:15:22 +0200 [=1B[32mINFO =1B[0m|#27946] Processing by RequestController#list as */* [=1B[32mINFO =1B[0m|#27946] Parameters: {"states"=3D>"new,review", "types"= =3D>"all", "project"=3D>"RemoteInstance", "no_target"=3D>"true"} [=1B[0;37mDEBUG=1B[0m|#27946] Setting return_to: "/request/list?states=3Dnew%= 2Creview&types=3Dall&project=3DRemoteInstance&no_target=3Dtrue" [=1B[0;37mDEBUG=1B[0m|#27946] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#27946] Anonymous request to /request/list?states=3Dnew%2= Creview&types=3Dall&project=3DRemoteInstance&no_target=3Dtrue [=1B[0;37mDEBUG=1B[0m|#27946] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#27946] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.342675, "db"=3D>1.057196, "back= end"=3D>0, "xml"=3D>0.054357, :runtime=3D>7.686, :all=3D>10.372042} [=1B[32mINFO =1B[0m|#27946] Rendered shared/_requests.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#27946] Completed 200 OK in 13ms (Views: 0.6ms | ActiveRe= cord: 0.0ms | API: 10.4ms (XML: 0.1ms , View: 0.3ms , Backend: 0.0ms , DB: 1.= 1ms , HTTP: 2.7ms) | XML: 0.0ms) [=1B[32mINFO =1B[0m|#27946]=20 Started GET "/project/show" for 127.0.0.1 at 2012-10-02 13:15:22 +0200 [=1B[32mINFO =1B[0m|#27946] Processing by ProjectController#show as HTML [=1B[0;37mDEBUG=1B[0m|#27946] Setting return_to: "/project/show" [=1B[0;37mDEBUG=1B[0m|#27946] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#27946] Anonymous request to /project/show [=1B[32mINFO =1B[0m|#27946] Redirected to http://localhost:3199/project/list_= public?nextstatus=3D404 [=1B[32mINFO =1B[0m|#27946] Filter chain halted as :require_project rendered = or redirected [=1B[32mINFO =1B[0m|#27946] Completed 302 Found in 1ms (ActiveRecord: 0.0ms |= XML: 0.0ms) [=1B[32mINFO =1B[0m|#27946]=20 Started GET "/project/list_public?nextstatus=3D404" for 127.0.0.1 at 2012-10-= 02 13:15:22 +0200 [=1B[32mINFO =1B[0m|#27946] Processing by ProjectController#list_public as HT= ML [=1B[32mINFO =1B[0m|#27946] Parameters: {"nextstatus"=3D>"404"} [=1B[0;37mDEBUG=1B[0m|#27946] Setting return_to: "/project/list_public?nextst= atus=3D404" [=1B[0;37mDEBUG=1B[0m|#27946] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#27946] Anonymous request to /project/list_public?nextsta= tus=3D404 [=1B[32mINFO =1B[0m|#27946] Rendered project/_filter_projects.html.erb (1.0= ms) [=1B[32mINFO =1B[0m|#27946] Rendered project/_search_project.html.erb (4.9m= s) [=1B[32mINFO =1B[0m|#27946] Rendered project/list.html.erb within layouts/a= pplication (6.4ms) [=1B[32mINFO =1B[0m|#27946] Rendered layouts/_watch_and_search.html.erb (0.= 4ms) [=1B[32mINFO =1B[0m|#27946] Rendered vendor/neutral/layouts/_header.html.er= b (1.0ms) [=1B[32mINFO =1B[0m|#27946] Rendered layouts/_personal_navigation.html.erb = (1.1ms) [=1B[32mINFO =1B[0m|#27946] Rendered layouts/_flash.html.erb (0.1ms) [=1B[32mINFO =1B[0m|#27946] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#27946] Completed 404 Not Found in 15ms (Views: 11.7ms | = ActiveRecord: 0.0ms | XML: 1.6ms) [=1B[32mINFO =1B[0m|#27946]=20 Started GET "/project/show?project=3DSourceprotectedProject" for 127.0.0.1 at= 2012-10-02 13:15:23 +0200 [=1B[32mINFO =1B[0m|#27946] Processing by ProjectController#show as HTML [=1B[32mINFO =1B[0m|#27946] Parameters: {"project"=3D>"SourceprotectedProje= ct"} [=1B[0;37mDEBUG=1B[0m|#27946] Setting return_to: "/project/show?project=3DSou= rceprotectedProject" [=1B[0;37mDEBUG=1B[0m|#27946] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#27946] Anonymous request to /project/show?project=3DSour= ceprotectedProject [=1B[0;37mDEBUG=1B[0m|#27946] [REST] find( Project, [{:name=3D>"Sourceprotect= edProject"}] ) [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/_meta? [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.6007480000000001, "db"=3D>2.968= 4150000000007, "backend"=3D>0, "xml"=3D>0, :runtime=3D>24.381, :all=3D>27.773= 808} [=1B[0;37mDEBUG=1B[0m|#27946] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#27946] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DSourceprotectedProject [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.46059999999999995, "db"=3D>0.61= 8954, "backend"=3D>0, "xml"=3D>0.040426000000000004, :runtime=3D>8.386, :all= =3D>48.57049} [=1B[0;37mDEBUG=1B[0m|#27946] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#27946] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DSourcepro= tectedProject [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.498897, "db"=3D>0.568789, "back= end"=3D>0, "xml"=3D>0.051646, :runtime=3D>7.617, :all=3D>48.679623} [=1B[0;37mDEBUG=1B[0m|#27946] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#27946] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&roles=3Dsource&types=3Dma= intenance_incident&project=3DSourceprotectedProject [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.351377, "db"=3D>0.5118849999999= 999, "backend"=3D>0, "xml"=3D>0.164172, :runtime=3D>7.5, :all=3D>50.304203} [=1B[0;37mDEBUG=1B[0m|#27946] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: GET url: http://localhost:3= 203/search/project/?match=3Dstarts-with%28%40name%2C%27SourceprotectedProject= %3A%27%29+and+%40kind%3D%27maintenance_incident%27+and+repository%2Freleaseta= rget%2F%40trigger%3D%27maintenance%27 [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.6081, "db"=3D>0.924156, "backen= d"=3D>0, "xml"=3D>0.047945999999999996, :runtime=3D>12.785, :all=3D>55.639506= 999999995} [=1B[0;37mDEBUG=1B[0m|#27946] [REST] find( Package, [:all, {:project=3D>"Sour= ceprotectedProject"}] ) [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.6496540000000001, "db"=3D>1.167= 5740000000001, "backend"=3D>0, "xml"=3D>0, :runtime=3D>14.05, :all=3D>55.0792= 67} [=1B[0;37mDEBUG=1B[0m|#27946] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :view=3D>"status", :code=3D>["failed", "broken", "unresolv= able"]}] ) [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dstatus&code=3Dfailed&code=3Db= roken&code=3Dunresolvable [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>nil, "db"=3D>0.766618, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>27.14, :all=3D>70.712398} [=1B[0;37mDEBUG=1B[0m|#27946] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: POST url: http://localhost:= 3203/source/SourceprotectedProject?cmd=3Dshowlinked [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.621266, "db"=3D>1.1004580000000= 002, "backend"=3D>0, "xml"=3D>0, :runtime=3D>12.36, :all=3D>15.83724} [=1B[0;37mDEBUG=1B[0m|#27946] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>nil, "db"=3D>0.334141, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>21.651, :all=3D>66.845714} [=1B[0;37mDEBUG=1B[0m|#27946] [REST] find( Collection, [:id, {:what=3D>"proje= ct", :predicate=3D>"maintenance/maintains/@project=3D'SourceprotectedProject'= "}] ) [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: get url: http://localhost:3= 203/search/project/id?match=3Dmaintenance%2Fmaintains%2F%40project%3D%27Sourc= eprotectedProject%27 [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.409324, "db"=3D>0.659824, "back= end"=3D>0, "xml"=3D>0.0, :runtime=3D>12.196, :all=3D>51.241299} [=1B[0;37mDEBUG=1B[0m|#27946] [REST] find( Project, [{:name=3D>"Sourceprotect= edProject"}] ) [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/_meta? [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.919971, "db"=3D>0.5898490000000= 001, "backend"=3D>0, "xml"=3D>0, :runtime=3D>10.331, :all=3D>54.811638} [=1B[32mINFO =1B[0m|#27946] Rendered project/_tabs.html.erb (3.7ms) [=1B[32mINFO =1B[0m|#27946] Rendered shared/_open_requests.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#27946] Rendered shared/_buildresult_box.html.erb (0.6m= s) [=1B[32mINFO =1B[0m|#27946] Rendered project/show.html.erb within layouts/a= pplication (63.2ms) [=1B[32mINFO =1B[0m|#27946] Rendered layouts/_watch_and_search.html.erb (0.= 8ms) [=1B[32mINFO =1B[0m|#27946] Rendered vendor/neutral/layouts/_header.html.er= b (0.9ms) [=1B[32mINFO =1B[0m|#27946] Rendered layouts/_personal_navigation.html.erb = (0.7ms) [=1B[32mINFO =1B[0m|#27946] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#27946] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#27946] Completed 200 OK in 569ms (Views: 69.3ms | Active= Record: 0.0ms | API: 545.5ms (XML: 0.3ms , View: 5.1ms , Backend: 0.0ms , DB:= 10.2ms , HTTP: 387.1ms) | XML: 1.6ms) [=1B[32mINFO =1B[0m|#27946]=20 Started GET "/project/buildresult?project=3DSourceprotectedProject&index=3D" = for 127.0.0.1 at 2012-10-02 13:15:24 +0200 [=1B[32mINFO =1B[0m|#27946] Processing by ProjectController#buildresult as */* [=1B[32mINFO =1B[0m|#27946] Parameters: {"project"=3D>"SourceprotectedProje= ct", "index"=3D>""} [=1B[0;37mDEBUG=1B[0m|#27946] Setting return_to: "/project/buildresult?projec= t=3DSourceprotectedProject&index=3D" [=1B[0;37mDEBUG=1B[0m|#27946] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#27946] Anonymous request to /project/buildresult?project= =3DSourceprotectedProject&index=3D [=1B[0;37mDEBUG=1B[0m|#27946] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>nil, "db"=3D>1.553523, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>38.709, :all=3D>41.726095} [=1B[32mINFO =1B[0m|#27946] Rendered project/_buildstatus.html.erb (1.6ms) [=1B[32mINFO =1B[0m|#27946] Completed 200 OK in 46ms (Views: 2.2ms | ActiveRe= cord: 0.0ms | API: 41.7ms (XML: 0.0ms , Backend: 0.0ms , DB: 1.6ms , HTTP: 3.= 0ms) | XML: 0.0ms) [=1B[32mINFO =1B[0m|#27946]=20 Started GET "/package/show?package=3Dpack&project=3DSourceprotectedProject" f= or 127.0.0.1 at 2012-10-02 13:15:24 +0200 [=1B[32mINFO =1B[0m|#27946] Processing by PackageController#show as HTML [=1B[32mINFO =1B[0m|#27946] Parameters: {"package"=3D>"pack", "project"=3D>= "SourceprotectedProject"} [=1B[0;37mDEBUG=1B[0m|#27946] Setting return_to: "/package/show?package=3Dpac= k&project=3DSourceprotectedProject" [=1B[0;37mDEBUG=1B[0m|#27946] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#27946] Anonymous request to /package/show?package=3Dpack= &project=3DSourceprotectedProject [=1B[0;37mDEBUG=1B[0m|#27946] [REST] find( Package, [{:project=3D>"Sourceprot= ectedProject", :name=3D>"pack"}] ) [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/pack/_meta? [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.5992109999999999, "db"=3D>2.998= 596, "backend"=3D>0, "xml"=3D>0, :runtime=3D>63.431000000000004, :all=3D>66.7= 76897} [=1B[0;37mDEBUG=1B[0m|#27946] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#27946] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DSourceprotectedProject&package=3Dpack [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.434778, "db"=3D>0.628591, "back= end"=3D>0, "xml"=3D>0.037717, :runtime=3D>9.076, :all=3D>52.203156} [=1B[0;37mDEBUG=1B[0m|#27946] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#27946] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DSourcepro= tectedProject&package=3Dpack [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.361579, "db"=3D>0.38466, "backe= nd"=3D>0, "xml"=3D>0.033516000000000004, :runtime=3D>6.787, :all=3D>47.497209} [=1B[0;37mDEBUG=1B[0m|#27946] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :package=3D>"pack", :view=3D>"status"}] ) [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dstatus&package=3Dpack [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>nil, "db"=3D>0.680844, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>32.062, :all=3D>71.441969} [=1B[0;37mDEBUG=1B[0m|#27946] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: POST url: http://localhost:= 3203/source/SourceprotectedProject/pack?cmd=3Dshowlinked [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>0.904645, "db"=3D>1.466336, "back= end"=3D>6.85089, "xml"=3D>0, :runtime=3D>25.919, :all=3D>32.043439} [=1B[0;37mDEBUG=1B[0m|#27946] [REST] find( Directory, [{:project=3D>"Sourcepr= otectedProject", :package=3D>"pack"}] ) [=1B[0;37mDEBUG=1B[0m|#27946] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/pack? [=1B[0;37mDEBUG=1B[0m|#27946] RT {"view"=3D>1.000228, "db"=3D>20.126385999999= 997, "backend"=3D>0, "xml"=3D>0, :runtime=3D>43.09, :all=3D>85.343353} [=1B[31mERROR=1B[0m|#27946] rescue_action: caught ActiveXML::Transport::Forbi= ddenError: SourceprotectedProject/pack
[=1B[0;37mDEBUG=1B[0m|#27946] ERROR: 401; SourceprotectedProject/pack [=1B[32mINFO =1B[0m|#27946] Rendered error.html.erb within layouts/applicat= ion (1.0ms) [=1B[32mINFO =1B[0m|#27946] Rendered layouts/_watch_and_search.html.erb (0.= 5ms) [=1B[32mINFO =1B[0m|#27946] Rendered vendor/neutral/layouts/_header.html.er= b (1.2ms) [=1B[32mINFO =1B[0m|#27946] Rendered layouts/_personal_navigation.html.erb = (1.2ms) [=1B[32mINFO =1B[0m|#27946] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#27946] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#27946] Completed 401 Unauthorized in 437ms (Views: 8.6ms= | ActiveRecord: 0.0ms | API: 355.3ms (XML: 0.1ms , View: 3.3ms , Backend: 6.= 9ms , DB: 26.3ms , HTTP: 174.9ms) | XML: 0.9ms) + echo + cleanup + echo 'Killing backend processes' Killing backend processes + egrep 'perl|ruby' + fuser -v USER PID ACCESS COMMAND : 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/cach= e src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/tes= t.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 --=20 To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org --===============7824075868521905189==-- From admin@opensuse.org Tue Oct 2 12:30:56 2012 From: admin@opensuse.org To: obs-tests@lists.opensuse.org Subject: [obs-tests] Build failed in Jenkins: obs_master_testsuite_webui-testsuite #338 Date: Tue, 02 Oct 2012 14:30:39 +0200 Message-ID: <537141252.231349181039452.JavaMail.jenkins@ci-opensuse> In-Reply-To: <1133331341.221349176830762.JavaMail.jenkins@ci-opensuse> MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="===============3155222674997902497==" --===============3155222674997902497== Content-Type: text/plain; charset="utf-8" Content-Transfer-Encoding: quoted-printable See Changes: [mls] [backend] dispatch: start implementing an oracle function ------------------------------------------ [...truncated 302337 lines...] [=1B[0;37mDEBUG=1B[0m|# 4309] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 4309] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.6158300000000001, "db"=3D>0.616= 518, "backend"=3D>0, "xml"=3D>0.010842000000000001, :runtime=3D>16.159, :all= =3D>53.86389} [=1B[0;37mDEBUG=1B[0m|# 4309] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 4309] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DRemoteIns= tance [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.543766, "db"=3D>0.367699, "back= end"=3D>0, "xml"=3D>0.0, :runtime=3D>6.911, :all=3D>47.443185} [=1B[0;37mDEBUG=1B[0m|# 4309] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 4309] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&roles=3Dsource&types=3Dma= intenance_incident&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.09569, "db"=3D>1.106267, "backe= nd"=3D>0, "xml"=3D>0.0, :runtime=3D>7.004, :all=3D>47.477142} [=1B[0;37mDEBUG=1B[0m|# 4309] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: GET url: http://localhost:3= 203/search/project/?match=3Dstarts-with%28%40name%2C%27RemoteInstance%3A%27%2= 9+and+%40kind%3D%27maintenance_incident%27+and+repository%2Freleasetarget%2F%= 40trigger%3D%27maintenance%27 [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.473734, "db"=3D>1.0362489999999= 998, "backend"=3D>0, "xml"=3D>0.061593999999999996, :runtime=3D>10.825, :all= =3D>51.472480000000004} [=1B[0;37mDEBUG=1B[0m|# 4309] [REST] find( Package, [:all, {:project=3D>"Remo= teInstance"}] ) [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: get url: http://localhost:3= 203/source/RemoteInstance [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.678219, "db"=3D>0.715209, "back= end"=3D>0, "xml"=3D>0, :runtime=3D>11.538, :all=3D>55.660841000000005} [=1B[0;37mDEBUG=1B[0m|# 4309] [REST] find( Buildresult, [{:project=3D>"Remote= Instance", :view=3D>"status", :code=3D>["failed", "broken", "unresolvable"]}]= ) [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: get url: http://localhost:3= 203/build/RemoteInstance/_result?view=3Dstatus&code=3Dfailed&code=3Dbroken&co= de=3Dunresolvable [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>nil, "db"=3D>0.615603, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>13.904, :all=3D>54.416224} [=1B[0;37mDEBUG=1B[0m|# 4309] [REST] find( Buildresult, [{:project=3D>"Remote= Instance", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: get url: http://localhost:3= 203/build/RemoteInstance/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>nil, "db"=3D>3.6499560000000004, = "backend"=3D>0, "xml"=3D>0, :runtime=3D>64.226, :all=3D>107.25491400000001} [=1B[0;37mDEBUG=1B[0m|# 4309] [REST] find( Collection, [:id, {:what=3D>"proje= ct", :predicate=3D>"maintenance/maintains/@project=3D'RemoteInstance'"}] ) [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: get url: http://localhost:3= 203/search/project/id?match=3Dmaintenance%2Fmaintains%2F%40project%3D%27Remot= eInstance%27 [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.574374, "db"=3D>0.9955379999999= 999, "backend"=3D>0, "xml"=3D>0.060801999999999995, :runtime=3D>11.988, :all= =3D>55.317705000000004} [=1B[32mINFO =1B[0m|# 4309] Rendered project/_tabs.html.erb (1.6ms) [=1B[32mINFO =1B[0m|# 4309] Rendered shared/_open_requests.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 4309] Rendered shared/_buildresult_box.html.erb (0.8m= s) [=1B[32mINFO =1B[0m|# 4309] Rendered project/show.html.erb within layouts/a= pplication (4.4ms) [=1B[32mINFO =1B[0m|# 4309] Rendered layouts/_watch_and_search.html.erb (0.= 4ms) [=1B[32mINFO =1B[0m|# 4309] Rendered vendor/neutral/layouts/_header.html.er= b (0.9ms) [=1B[32mINFO =1B[0m|# 4309] Rendered layouts/_personal_navigation.html.erb = (1.2ms) [=1B[32mINFO =1B[0m|# 4309] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 4309] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|# 4309] Completed 200 OK in 512ms (Views: 9.7ms | ActiveR= ecord: 0.0ms | API: 494.6ms (XML: 0.1ms , View: 3.3ms , Backend: 0.0ms , DB: = 11.5ms , HTTP: 333.6ms) | XML: 0.7ms) [=1B[32mINFO =1B[0m|# 4309]=20 Started GET "/project/buildresult?project=3DRemoteInstance&index=3D" for 127.= 0.0.1 at 2012-10-02 14:25:30 +0200 [=1B[32mINFO =1B[0m|# 4309] Processing by ProjectController#buildresult as */* [=1B[32mINFO =1B[0m|# 4309] Parameters: {"project"=3D>"RemoteInstance", "in= dex"=3D>""} [=1B[0;37mDEBUG=1B[0m|# 4309] Setting return_to: "/project/buildresult?projec= t=3DRemoteInstance&index=3D" [=1B[0;37mDEBUG=1B[0m|# 4309] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 4309] Anonymous request to /project/buildresult?project= =3DRemoteInstance&index=3D [=1B[0;37mDEBUG=1B[0m|# 4309] [REST] find( Buildresult, [{:project=3D>"Remote= Instance", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: get url: http://localhost:3= 203/build/RemoteInstance/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>nil, "db"=3D>0.7699179999999999, = "backend"=3D>0, "xml"=3D>0, :runtime=3D>23.221, :all=3D>26.295395} [=1B[32mINFO =1B[0m|# 4309] Rendered project/_buildstatus.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 4309] Completed 200 OK in 28ms (Views: 0.6ms | ActiveRe= cord: 0.0ms | API: 26.3ms (XML: 0.0ms , Backend: 0.0ms , DB: 0.8ms , HTTP: 3.= 1ms) | XML: 0.1ms) [=1B[32mINFO =1B[0m|# 4309]=20 Started GET "/project/requests?project=3DRemoteInstance" for 127.0.0.1 at 201= 2-10-02 14:25:31 +0200 [=1B[32mINFO =1B[0m|# 4309] Processing by ProjectController#requests as HTML [=1B[32mINFO =1B[0m|# 4309] Parameters: {"project"=3D>"RemoteInstance"} [=1B[0;37mDEBUG=1B[0m|# 4309] Setting return_to: "/project/requests?project= =3DRemoteInstance" [=1B[0;37mDEBUG=1B[0m|# 4309] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 4309] Anonymous request to /project/requests?project=3D= RemoteInstance [=1B[0;37mDEBUG=1B[0m|# 4309] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 4309] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.5694, "db"=3D>1.40318, "backend= "=3D>0, "xml"=3D>0.067466, :runtime=3D>9.173, :all=3D>12.473995} [=1B[0;37mDEBUG=1B[0m|# 4309] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 4309] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DRemoteIns= tance [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.356223, "db"=3D>0.4788980000000= 0005, "backend"=3D>0, "xml"=3D>0.036792000000000005, :runtime=3D>6.2059999999= 999995, :all=3D>45.976692} [=1B[0;37mDEBUG=1B[0m|# 4309] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 4309] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&roles=3Dsource&types=3Dma= intenance_incident&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.326837, "db"=3D>0.50889, "backe= nd"=3D>0, "xml"=3D>0.033339, :runtime=3D>6.5329999999999995, :all=3D>47.59983= 1} [=1B[32mINFO =1B[0m|# 4309] Rendered project/_tabs.html.erb (1.6ms) [=1B[32mINFO =1B[0m|# 4309] Rendered shared/_select_requests.html.erb (1.1m= s) [=1B[32mINFO =1B[0m|# 4309] Rendered project/requests.html.erb within layou= ts/application (3.5ms) [=1B[32mINFO =1B[0m|# 4309] Rendered layouts/_watch_and_search.html.erb (0.= 4ms) [=1B[32mINFO =1B[0m|# 4309] Rendered vendor/neutral/layouts/_header.html.er= b (0.9ms) [=1B[32mINFO =1B[0m|# 4309] Rendered layouts/_personal_navigation.html.erb = (1.0ms) [=1B[32mINFO =1B[0m|# 4309] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 4309] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|# 4309] Completed 200 OK in 119ms (Views: 9.1ms | ActiveR= ecord: 0.0ms | API: 106.1ms (XML: 0.1ms , View: 1.3ms , Backend: 0.0ms , DB: = 2.4ms , HTTP: 84.1ms) | XML: 0.1ms) [=1B[32mINFO =1B[0m|# 4309]=20 Started GET "/request/list?states=3Dnew%2Creview&types=3Dall&project=3DRemote= Instance&no_target=3Dtrue" for 127.0.0.1 at 2012-10-02 14:25:31 +0200 [=1B[32mINFO =1B[0m|# 4309] Processing by RequestController#list as */* [=1B[32mINFO =1B[0m|# 4309] Parameters: {"states"=3D>"new,review", "types"= =3D>"all", "project"=3D>"RemoteInstance", "no_target"=3D>"true"} [=1B[0;37mDEBUG=1B[0m|# 4309] Setting return_to: "/request/list?states=3Dnew%= 2Creview&types=3Dall&project=3DRemoteInstance&no_target=3Dtrue" [=1B[0;37mDEBUG=1B[0m|# 4309] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 4309] Anonymous request to /request/list?states=3Dnew%2= Creview&types=3Dall&project=3DRemoteInstance&no_target=3Dtrue [=1B[0;37mDEBUG=1B[0m|# 4309] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 4309] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.44530600000000004, "db"=3D>0.91= 5753, "backend"=3D>0, "xml"=3D>0.054939999999999996, :runtime=3D>7.3759999999= 999994, :all=3D>61.975443999999996} [=1B[32mINFO =1B[0m|# 4309] Rendered shared/_requests.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 4309] Completed 200 OK in 65ms (Views: 0.7ms | ActiveRe= cord: 0.0ms | API: 62.0ms (XML: 0.1ms , View: 0.4ms , Backend: 0.0ms , DB: 0.= 9ms , HTTP: 54.6ms) | XML: 0.3ms) [=1B[32mINFO =1B[0m|# 4309]=20 Started GET "/project/show" for 127.0.0.1 at 2012-10-02 14:25:31 +0200 [=1B[32mINFO =1B[0m|# 4309] Processing by ProjectController#show as HTML [=1B[0;37mDEBUG=1B[0m|# 4309] Setting return_to: "/project/show" [=1B[0;37mDEBUG=1B[0m|# 4309] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 4309] Anonymous request to /project/show [=1B[32mINFO =1B[0m|# 4309] Redirected to http://localhost:3199/project/list_= public?nextstatus=3D404 [=1B[32mINFO =1B[0m|# 4309] Filter chain halted as :require_project rendered = or redirected [=1B[32mINFO =1B[0m|# 4309] Completed 302 Found in 1ms (ActiveRecord: 0.0ms |= XML: 0.0ms) [=1B[32mINFO =1B[0m|# 4309]=20 Started GET "/project/list_public?nextstatus=3D404" for 127.0.0.1 at 2012-10-= 02 14:25:31 +0200 [=1B[32mINFO =1B[0m|# 4309] Processing by ProjectController#list_public as HT= ML [=1B[32mINFO =1B[0m|# 4309] Parameters: {"nextstatus"=3D>"404"} [=1B[0;37mDEBUG=1B[0m|# 4309] Setting return_to: "/project/list_public?nextst= atus=3D404" [=1B[0;37mDEBUG=1B[0m|# 4309] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 4309] Anonymous request to /project/list_public?nextsta= tus=3D404 [=1B[32mINFO =1B[0m|# 4309] Rendered project/_filter_projects.html.erb (1.1= ms) [=1B[32mINFO =1B[0m|# 4309] Rendered project/_search_project.html.erb (6.5m= s) [=1B[32mINFO =1B[0m|# 4309] Rendered project/list.html.erb within layouts/a= pplication (9.0ms) [=1B[32mINFO =1B[0m|# 4309] Rendered layouts/_watch_and_search.html.erb (0.= 5ms) [=1B[32mINFO =1B[0m|# 4309] Rendered vendor/neutral/layouts/_header.html.er= b (1.1ms) [=1B[32mINFO =1B[0m|# 4309] Rendered layouts/_personal_navigation.html.erb = (1.2ms) [=1B[32mINFO =1B[0m|# 4309] Rendered layouts/_flash.html.erb (0.1ms) [=1B[32mINFO =1B[0m|# 4309] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|# 4309] Completed 404 Not Found in 18ms (Views: 14.5ms | = ActiveRecord: 0.0ms | XML: 1.8ms) [=1B[32mINFO =1B[0m|# 4309]=20 Started GET "/project/show?project=3DSourceprotectedProject" for 127.0.0.1 at= 2012-10-02 14:25:32 +0200 [=1B[32mINFO =1B[0m|# 4309] Processing by ProjectController#show as HTML [=1B[32mINFO =1B[0m|# 4309] Parameters: {"project"=3D>"SourceprotectedProje= ct"} [=1B[0;37mDEBUG=1B[0m|# 4309] Setting return_to: "/project/show?project=3DSou= rceprotectedProject" [=1B[0;37mDEBUG=1B[0m|# 4309] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 4309] Anonymous request to /project/show?project=3DSour= ceprotectedProject [=1B[0;37mDEBUG=1B[0m|# 4309] [REST] find( Project, [{:name=3D>"Sourceprotect= edProject"}] ) [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/_meta? [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.6134419999999999, "db"=3D>2.726= 9959999999993, "backend"=3D>0, "xml"=3D>0, :runtime=3D>23.162, :all=3D>26.408= 535} [=1B[0;37mDEBUG=1B[0m|# 4309] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 4309] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DSourceprotectedProject [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.5688909999999999, "db"=3D>0.589= 547, "backend"=3D>0, "xml"=3D>0.104489, :runtime=3D>8.052, :all=3D>49.388775} [=1B[0;37mDEBUG=1B[0m|# 4309] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 4309] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DSourcepro= tectedProject [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.28150400000000003, "db"=3D>0.39= 8706, "backend"=3D>0, "xml"=3D>0.035356, :runtime=3D>5.46, :all=3D>47.2067030= 00000005} [=1B[0;37mDEBUG=1B[0m|# 4309] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 4309] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&roles=3Dsource&types=3Dma= intenance_incident&project=3DSourceprotectedProject [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.337634, "db"=3D>0.578352, "back= end"=3D>0, "xml"=3D>0.034704, :runtime=3D>7.526, :all=3D>47.470917} [=1B[0;37mDEBUG=1B[0m|# 4309] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: GET url: http://localhost:3= 203/search/project/?match=3Dstarts-with%28%40name%2C%27SourceprotectedProject= %3A%27%29+and+%40kind%3D%27maintenance_incident%27+and+repository%2Freleaseta= rget%2F%40trigger%3D%27maintenance%27 [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.5112880000000001, "db"=3D>0.975= 139, "backend"=3D>0, "xml"=3D>0.049302000000000006, :runtime=3D>11.811, :all= =3D>55.407061} [=1B[0;37mDEBUG=1B[0m|# 4309] [REST] find( Package, [:all, {:project=3D>"Sour= ceprotectedProject"}] ) [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.500379, "db"=3D>1.058836, "back= end"=3D>0, "xml"=3D>0, :runtime=3D>10.77, :all=3D>50.867022} [=1B[0;37mDEBUG=1B[0m|# 4309] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :view=3D>"status", :code=3D>["failed", "broken", "unresolv= able"]}] ) [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dstatus&code=3Dfailed&code=3Db= roken&code=3Dunresolvable [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>nil, "db"=3D>0.51163, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>34.184, :all=3D>75.00159500000001} [=1B[0;37mDEBUG=1B[0m|# 4309] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: POST url: http://localhost:= 3203/source/SourceprotectedProject?cmd=3Dshowlinked [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.539091, "db"=3D>0.898529, "back= end"=3D>0, "xml"=3D>0, :runtime=3D>12.002, :all=3D>16.18056} [=1B[0;37mDEBUG=1B[0m|# 4309] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>nil, "db"=3D>0.532523, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>25.554000000000002, :all=3D>66.495025} [=1B[0;37mDEBUG=1B[0m|# 4309] [REST] find( Collection, [:id, {:what=3D>"proje= ct", :predicate=3D>"maintenance/maintains/@project=3D'SourceprotectedProject'= "}] ) [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: get url: http://localhost:3= 203/search/project/id?match=3Dmaintenance%2Fmaintains%2F%40project%3D%27Sourc= eprotectedProject%27 [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.602432, "db"=3D>0.935724, "back= end"=3D>0, "xml"=3D>0.061636, :runtime=3D>9.768, :all=3D>51.162147} [=1B[0;37mDEBUG=1B[0m|# 4309] [REST] find( Project, [{:name=3D>"Sourceprotect= edProject"}] ) [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/_meta? [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.597158, "db"=3D>0.628644, "back= end"=3D>0, "xml"=3D>0, :runtime=3D>12.116999999999999, :all=3D>54.292058} [=1B[32mINFO =1B[0m|# 4309] Rendered project/_tabs.html.erb (2.7ms) [=1B[32mINFO =1B[0m|# 4309] Rendered shared/_open_requests.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 4309] Rendered shared/_buildresult_box.html.erb (0.6m= s) [=1B[32mINFO =1B[0m|# 4309] Rendered project/show.html.erb within layouts/a= pplication (61.2ms) [=1B[32mINFO =1B[0m|# 4309] Rendered layouts/_watch_and_search.html.erb (0.= 5ms) [=1B[32mINFO =1B[0m|# 4309] Rendered vendor/neutral/layouts/_header.html.er= b (1.0ms) [=1B[32mINFO =1B[0m|# 4309] Rendered layouts/_personal_navigation.html.erb = (1.0ms) [=1B[32mINFO =1B[0m|# 4309] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 4309] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|# 4309] Completed 200 OK in 562ms (Views: 66.4ms | Active= Record: 0.0ms | API: 539.9ms (XML: 0.3ms , View: 4.6ms , Backend: 0.0ms , DB:= 9.8ms , HTTP: 379.5ms) | XML: 1.4ms) [=1B[32mINFO =1B[0m|# 4309]=20 Started GET "/project/buildresult?project=3DSourceprotectedProject&index=3D" = for 127.0.0.1 at 2012-10-02 14:25:33 +0200 [=1B[32mINFO =1B[0m|# 4309] Processing by ProjectController#buildresult as */* [=1B[32mINFO =1B[0m|# 4309] Parameters: {"project"=3D>"SourceprotectedProje= ct", "index"=3D>""} [=1B[0;37mDEBUG=1B[0m|# 4309] Setting return_to: "/project/buildresult?projec= t=3DSourceprotectedProject&index=3D" [=1B[0;37mDEBUG=1B[0m|# 4309] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 4309] Anonymous request to /project/buildresult?project= =3DSourceprotectedProject&index=3D [=1B[0;37mDEBUG=1B[0m|# 4309] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>nil, "db"=3D>0.891395, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>24.763, :all=3D>28.203918} [=1B[32mINFO =1B[0m|# 4309] Rendered project/_buildstatus.html.erb (1.5ms) [=1B[32mINFO =1B[0m|# 4309] Completed 200 OK in 33ms (Views: 2.0ms | ActiveRe= cord: 0.0ms | API: 28.2ms (XML: 0.0ms , Backend: 0.0ms , DB: 0.9ms , HTTP: 3.= 4ms) | XML: 0.1ms) [=1B[32mINFO =1B[0m|# 4309]=20 Started GET "/package/show?package=3Dpack&project=3DSourceprotectedProject" f= or 127.0.0.1 at 2012-10-02 14:25:34 +0200 [=1B[32mINFO =1B[0m|# 4309] Processing by PackageController#show as HTML [=1B[32mINFO =1B[0m|# 4309] Parameters: {"package"=3D>"pack", "project"=3D>= "SourceprotectedProject"} [=1B[0;37mDEBUG=1B[0m|# 4309] Setting return_to: "/package/show?package=3Dpac= k&project=3DSourceprotectedProject" [=1B[0;37mDEBUG=1B[0m|# 4309] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 4309] Anonymous request to /package/show?package=3Dpack= &project=3DSourceprotectedProject [=1B[0;37mDEBUG=1B[0m|# 4309] [REST] find( Package, [{:project=3D>"Sourceprot= ectedProject", :name=3D>"pack"}] ) [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/pack/_meta? [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.5530729999999999, "db"=3D>2.027= 5849999999997, "backend"=3D>0, "xml"=3D>0, :runtime=3D>17.860999999999997, :a= ll=3D>21.094079} [=1B[0;37mDEBUG=1B[0m|# 4309] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 4309] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DSourceprotectedProject&package=3Dpack [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.43092, "db"=3D>0.59895, "backen= d"=3D>0, "xml"=3D>0.03884, :runtime=3D>8.01, :all=3D>47.621854} [=1B[0;37mDEBUG=1B[0m|# 4309] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 4309] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DSourcepro= tectedProject&package=3Dpack [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.5112209999999999, "db"=3D>0.477= 23499999999996, "backend"=3D>0, "xml"=3D>0.037512000000000004, :runtime=3D>6.= 213, :all=3D>47.579386} [=1B[0;37mDEBUG=1B[0m|# 4309] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :package=3D>"pack", :view=3D>"status"}] ) [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dstatus&package=3Dpack [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>nil, "db"=3D>0.609785, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>73.729, :all=3D>115.259722} [=1B[0;37mDEBUG=1B[0m|# 4309] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: POST url: http://localhost:= 3203/source/SourceprotectedProject/pack?cmd=3Dshowlinked [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.780311, "db"=3D>1.178471, "back= end"=3D>6.370247, "xml"=3D>0, :runtime=3D>22.415999999999997, :all=3D>26.3755= 12} [=1B[0;37mDEBUG=1B[0m|# 4309] [REST] find( Directory, [{:project=3D>"Sourcepr= otectedProject", :package=3D>"pack"}] ) [=1B[0;37mDEBUG=1B[0m|# 4309] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/pack? [=1B[0;37mDEBUG=1B[0m|# 4309] RT {"view"=3D>0.9090969999999999, "db"=3D>2.639= 603, "backend"=3D>0, "xml"=3D>0, :runtime=3D>23.494999999999997, :all=3D>63.7= 9164900000001} [=1B[31mERROR=1B[0m|# 4309] rescue_action: caught ActiveXML::Transport::Forbi= ddenError: SourceprotectedProject/pack
[=1B[0;37mDEBUG=1B[0m|# 4309] ERROR: 401; SourceprotectedProject/pack [=1B[32mINFO =1B[0m|# 4309] Rendered error.html.erb within layouts/applicat= ion (1.7ms) [=1B[32mINFO =1B[0m|# 4309] Rendered layouts/_watch_and_search.html.erb (0.= 7ms) [=1B[32mINFO =1B[0m|# 4309] Rendered vendor/neutral/layouts/_header.html.er= b (1.7ms) [=1B[32mINFO =1B[0m|# 4309] Rendered layouts/_personal_navigation.html.erb = (1.4ms) [=1B[32mINFO =1B[0m|# 4309] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 4309] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|# 4309] Completed 401 Unauthorized in 341ms (Views: 11.3m= s | ActiveRecord: 0.0ms | API: 321.7ms (XML: 0.1ms , View: 3.2ms , Backend: 6= .4ms , DB: 7.5ms , HTTP: 170.0ms) | XML: 0.5ms) + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v + egrep 'perl|ruby' USER PID ACCESS COMMAND : 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/back= end_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/ap= i/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 --=20 To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org --===============3155222674997902497==-- From admin@opensuse.org Tue Oct 2 13:11:09 2012 From: admin@opensuse.org To: obs-tests@lists.opensuse.org Subject: [obs-tests] Build failed in Jenkins: obs_master_testsuite_webui-testsuite #339 Date: Tue, 02 Oct 2012 15:10:51 +0200 Message-ID: <2122677893.241349183451587.JavaMail.jenkins@ci-opensuse> In-Reply-To: <537141252.231349181039452.JavaMail.jenkins@ci-opensuse> MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="===============5034105205881938634==" --===============5034105205881938634== Content-Type: text/plain; charset="utf-8" Content-Transfer-Encoding: quoted-printable See Changes: [mls] [backend] do not transfer isos with the kiwitree mechanism ------------------------------------------ [...truncated 302062 lines...] [=1B[0;37mDEBUG=1B[0m|#18906] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#18906] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.42778700000000003, "db"=3D>0.71= 6499, "backend"=3D>0, "xml"=3D>0.044763, :runtime=3D>8.558, :all=3D>47.502045} [=1B[0;37mDEBUG=1B[0m|#18906] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#18906] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DRemoteIns= tance [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.497808, "db"=3D>0.539969, "back= end"=3D>0, "xml"=3D>0.049472999999999996, :runtime=3D>7.31, :all=3D>47.528967} [=1B[0;37mDEBUG=1B[0m|#18906] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#18906] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&roles=3Dsource&types=3Dma= intenance_incident&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.502195, "db"=3D>0.4703199999999= 9996, "backend"=3D>0, "xml"=3D>0.078768, :runtime=3D>7.045, :all=3D>47.425855= 000000006} [=1B[0;37mDEBUG=1B[0m|#18906] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: GET url: http://localhost:3= 203/search/project/?match=3Dstarts-with%28%40name%2C%27RemoteInstance%3A%27%2= 9+and+%40kind%3D%27maintenance_incident%27+and+repository%2Freleasetarget%2F%= 40trigger%3D%27maintenance%27 [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.498457, "db"=3D>1.355022, "back= end"=3D>0, "xml"=3D>0.049011, :runtime=3D>11.974, :all=3D>51.352864999999994} [=1B[0;37mDEBUG=1B[0m|#18906] [REST] find( Package, [:all, {:project=3D>"Remo= teInstance"}] ) [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: get url: http://localhost:3= 203/source/RemoteInstance [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.505267, "db"=3D>0.793087, "back= end"=3D>0, "xml"=3D>0, :runtime=3D>10.583, :all=3D>55.379490000000004} [=1B[0;37mDEBUG=1B[0m|#18906] [REST] find( Buildresult, [{:project=3D>"Remote= Instance", :view=3D>"status", :code=3D>["failed", "broken", "unresolvable"]}]= ) [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: get url: http://localhost:3= 203/build/RemoteInstance/_result?view=3Dstatus&code=3Dfailed&code=3Dbroken&co= de=3Dunresolvable [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>nil, "db"=3D>0.9183529999999999, = "backend"=3D>0, "xml"=3D>0, :runtime=3D>17.971, :all=3D>58.604506} [=1B[0;37mDEBUG=1B[0m|#18906] [REST] find( Buildresult, [{:project=3D>"Remote= Instance", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: get url: http://localhost:3= 203/build/RemoteInstance/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>nil, "db"=3D>5.467459, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>23.349999999999998, :all=3D>127.12369100000001} [=1B[0;37mDEBUG=1B[0m|#18906] [REST] find( Collection, [:id, {:what=3D>"proje= ct", :predicate=3D>"maintenance/maintains/@project=3D'RemoteInstance'"}] ) [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: get url: http://localhost:3= 203/search/project/id?match=3Dmaintenance%2Fmaintains%2F%40project%3D%27Remot= eInstance%27 [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.611964, "db"=3D>0.867139, "back= end"=3D>0, "xml"=3D>0.07448199999999999, :runtime=3D>10.629, :all=3D>51.67219= 1999999995} [=1B[32mINFO =1B[0m|#18906] Rendered project/_tabs.html.erb (1.7ms) [=1B[32mINFO =1B[0m|#18906] Rendered shared/_open_requests.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#18906] Rendered shared/_buildresult_box.html.erb (0.8m= s) [=1B[32mINFO =1B[0m|#18906] Rendered project/show.html.erb within layouts/a= pplication (5.2ms) [=1B[32mINFO =1B[0m|#18906] Rendered layouts/_watch_and_search.html.erb (0.= 8ms) [=1B[32mINFO =1B[0m|#18906] Rendered vendor/neutral/layouts/_header.html.er= b (1.7ms) [=1B[32mINFO =1B[0m|#18906] Rendered layouts/_personal_navigation.html.erb = (1.6ms) [=1B[32mINFO =1B[0m|#18906] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#18906] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#18906] Completed 200 OK in 535ms (Views: 13.8ms | Active= Record: 0.0ms | API: 510.8ms (XML: 0.3ms , View: 3.7ms , Backend: 0.0ms , DB:= 13.3ms , HTTP: 393.0ms) | XML: 1.5ms) [=1B[32mINFO =1B[0m|#18906]=20 Started GET "/project/buildresult?project=3DRemoteInstance&index=3D" for 127.= 0.0.1 at 2012-10-02 15:05:40 +0200 [=1B[32mINFO =1B[0m|#18906] Processing by ProjectController#buildresult as */* [=1B[32mINFO =1B[0m|#18906] Parameters: {"project"=3D>"RemoteInstance", "in= dex"=3D>""} [=1B[0;37mDEBUG=1B[0m|#18906] Setting return_to: "/project/buildresult?projec= t=3DRemoteInstance&index=3D" [=1B[0;37mDEBUG=1B[0m|#18906] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#18906] Anonymous request to /project/buildresult?project= =3DRemoteInstance&index=3D [=1B[0;37mDEBUG=1B[0m|#18906] [REST] find( Buildresult, [{:project=3D>"Remote= Instance", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: get url: http://localhost:3= 203/build/RemoteInstance/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>nil, "db"=3D>0.9990239999999999, = "backend"=3D>0, "xml"=3D>0, :runtime=3D>17.278000000000002, :all=3D>20.770905} [=1B[32mINFO =1B[0m|#18906] Rendered project/_buildstatus.html.erb (0.1ms) [=1B[32mINFO =1B[0m|#18906] Completed 200 OK in 23ms (Views: 0.7ms | ActiveRe= cord: 0.0ms | API: 20.8ms (XML: 0.0ms , Backend: 0.0ms , DB: 1.0ms , HTTP: 3.= 5ms) | XML: 0.2ms) [=1B[32mINFO =1B[0m|#18906]=20 Started GET "/project/requests?project=3DRemoteInstance" for 127.0.0.1 at 201= 2-10-02 15:05:41 +0200 [=1B[32mINFO =1B[0m|#18906] Processing by ProjectController#requests as HTML [=1B[32mINFO =1B[0m|#18906] Parameters: {"project"=3D>"RemoteInstance"} [=1B[0;37mDEBUG=1B[0m|#18906] Setting return_to: "/project/requests?project= =3DRemoteInstance" [=1B[0;37mDEBUG=1B[0m|#18906] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#18906] Anonymous request to /project/requests?project=3D= RemoteInstance [=1B[0;37mDEBUG=1B[0m|#18906] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#18906] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.574333, "db"=3D>1.110319, "back= end"=3D>0, "xml"=3D>0.087867, :runtime=3D>10.222999999999999, :all=3D>13.8013= 90999999999} [=1B[0;37mDEBUG=1B[0m|#18906] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#18906] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DRemoteIns= tance [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.314974, "db"=3D>0.44297, "backe= nd"=3D>0, "xml"=3D>0.044022, :runtime=3D>5.168, :all=3D>44.932066} [=1B[0;37mDEBUG=1B[0m|#18906] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#18906] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&roles=3Dsource&types=3Dma= intenance_incident&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.334482, "db"=3D>0.5212829999999= 999, "backend"=3D>0, "xml"=3D>0.046468, :runtime=3D>6.981, :all=3D>47.783155} [=1B[32mINFO =1B[0m|#18906] Rendered project/_tabs.html.erb (1.7ms) [=1B[32mINFO =1B[0m|#18906] Rendered shared/_select_requests.html.erb (0.8m= s) [=1B[32mINFO =1B[0m|#18906] Rendered project/requests.html.erb within layou= ts/application (4.1ms) [=1B[32mINFO =1B[0m|#18906] Rendered layouts/_watch_and_search.html.erb (0.= 5ms) [=1B[32mINFO =1B[0m|#18906] Rendered vendor/neutral/layouts/_header.html.er= b (1.1ms) [=1B[32mINFO =1B[0m|#18906] Rendered layouts/_personal_navigation.html.erb = (1.1ms) [=1B[32mINFO =1B[0m|#18906] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#18906] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#18906] Completed 200 OK in 121ms (Views: 10.2ms | Active= Record: 0.0ms | API: 106.5ms (XML: 0.2ms , View: 1.2ms , Backend: 0.0ms , DB:= 2.1ms , HTTP: 84.1ms) | XML: 0.4ms) [=1B[32mINFO =1B[0m|#18906]=20 Started GET "/request/list?states=3Dnew%2Creview&types=3Dall&project=3DRemote= Instance&no_target=3Dtrue" for 127.0.0.1 at 2012-10-02 15:05:41 +0200 [=1B[32mINFO =1B[0m|#18906] Processing by RequestController#list as */* [=1B[32mINFO =1B[0m|#18906] Parameters: {"states"=3D>"new,review", "types"= =3D>"all", "project"=3D>"RemoteInstance", "no_target"=3D>"true"} [=1B[0;37mDEBUG=1B[0m|#18906] Setting return_to: "/request/list?states=3Dnew%= 2Creview&types=3Dall&project=3DRemoteInstance&no_target=3Dtrue" [=1B[0;37mDEBUG=1B[0m|#18906] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#18906] Anonymous request to /request/list?states=3Dnew%2= Creview&types=3Dall&project=3DRemoteInstance&no_target=3Dtrue [=1B[0;37mDEBUG=1B[0m|#18906] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#18906] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.651358, "db"=3D>1.420288, "back= end"=3D>0, "xml"=3D>0.0, :runtime=3D>9.248, :all=3D>12.515848} [=1B[32mINFO =1B[0m|#18906] Rendered shared/_requests.html.erb (0.1ms) [=1B[32mINFO =1B[0m|#18906] Completed 200 OK in 16ms (Views: 0.9ms | ActiveRe= cord: 0.0ms | API: 12.5ms (XML: 0.0ms , View: 0.7ms , Backend: 0.0ms , DB: 1.= 4ms , HTTP: 3.3ms) | XML: 0.1ms) [=1B[32mINFO =1B[0m|#18906]=20 Started GET "/project/show" for 127.0.0.1 at 2012-10-02 15:05:42 +0200 [=1B[32mINFO =1B[0m|#18906] Processing by ProjectController#show as HTML [=1B[0;37mDEBUG=1B[0m|#18906] Setting return_to: "/project/show" [=1B[0;37mDEBUG=1B[0m|#18906] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#18906] Anonymous request to /project/show [=1B[32mINFO =1B[0m|#18906] Redirected to http://localhost:3199/project/list_= public?nextstatus=3D404 [=1B[32mINFO =1B[0m|#18906] Filter chain halted as :require_project rendered = or redirected [=1B[32mINFO =1B[0m|#18906] Completed 302 Found in 1ms (ActiveRecord: 0.0ms |= XML: 0.0ms) [=1B[32mINFO =1B[0m|#18906]=20 Started GET "/project/list_public?nextstatus=3D404" for 127.0.0.1 at 2012-10-= 02 15:05:42 +0200 [=1B[32mINFO =1B[0m|#18906] Processing by ProjectController#list_public as HT= ML [=1B[32mINFO =1B[0m|#18906] Parameters: {"nextstatus"=3D>"404"} [=1B[0;37mDEBUG=1B[0m|#18906] Setting return_to: "/project/list_public?nextst= atus=3D404" [=1B[0;37mDEBUG=1B[0m|#18906] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#18906] Anonymous request to /project/list_public?nextsta= tus=3D404 [=1B[32mINFO =1B[0m|#18906] Rendered project/_filter_projects.html.erb (0.9= ms) [=1B[32mINFO =1B[0m|#18906] Rendered project/_search_project.html.erb (5.3m= s) [=1B[32mINFO =1B[0m|#18906] Rendered project/list.html.erb within layouts/a= pplication (6.9ms) [=1B[32mINFO =1B[0m|#18906] Rendered layouts/_watch_and_search.html.erb (0.= 5ms) [=1B[32mINFO =1B[0m|#18906] Rendered vendor/neutral/layouts/_header.html.er= b (1.0ms) [=1B[32mINFO =1B[0m|#18906] Rendered layouts/_personal_navigation.html.erb = (1.1ms) [=1B[32mINFO =1B[0m|#18906] Rendered layouts/_flash.html.erb (0.1ms) [=1B[32mINFO =1B[0m|#18906] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#18906] Completed 404 Not Found in 17ms (Views: 12.8ms | = ActiveRecord: 0.0ms | XML: 2.3ms) [=1B[32mINFO =1B[0m|#18906]=20 Started GET "/project/show?project=3DSourceprotectedProject" for 127.0.0.1 at= 2012-10-02 15:05:42 +0200 [=1B[32mINFO =1B[0m|#18906] Processing by ProjectController#show as HTML [=1B[32mINFO =1B[0m|#18906] Parameters: {"project"=3D>"SourceprotectedProje= ct"} [=1B[0;37mDEBUG=1B[0m|#18906] Setting return_to: "/project/show?project=3DSou= rceprotectedProject" [=1B[0;37mDEBUG=1B[0m|#18906] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#18906] Anonymous request to /project/show?project=3DSour= ceprotectedProject [=1B[0;37mDEBUG=1B[0m|#18906] [REST] find( Project, [{:name=3D>"Sourceprotect= edProject"}] ) [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/_meta? [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.661977, "db"=3D>3.4004390000000= 004, "backend"=3D>0, "xml"=3D>0, :runtime=3D>25.814, :all=3D>28.98226} [=1B[0;37mDEBUG=1B[0m|#18906] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#18906] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DSourceprotectedProject [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.407004, "db"=3D>0.701465, "back= end"=3D>0, "xml"=3D>0.068581, :runtime=3D>8.547, :all=3D>48.258738} [=1B[0;37mDEBUG=1B[0m|#18906] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#18906] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DSourcepro= tectedProject [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.372172, "db"=3D>0.611853, "back= end"=3D>0, "xml"=3D>0.044387, :runtime=3D>8.314, :all=3D>47.478572} [=1B[0;37mDEBUG=1B[0m|#18906] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#18906] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&roles=3Dsource&types=3Dma= intenance_incident&project=3DSourceprotectedProject [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.469268, "db"=3D>0.595329, "back= end"=3D>0, "xml"=3D>0.041328000000000004, :runtime=3D>6.689, :all=3D>81.83810= 4} [=1B[0;37mDEBUG=1B[0m|#18906] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: GET url: http://localhost:3= 203/search/project/?match=3Dstarts-with%28%40name%2C%27SourceprotectedProject= %3A%27%29+and+%40kind%3D%27maintenance_incident%27+and+repository%2Freleaseta= rget%2F%40trigger%3D%27maintenance%27 [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.844267, "db"=3D>1.386067, "back= end"=3D>0, "xml"=3D>0.073197, :runtime=3D>13.583, :all=3D>56.924476} [=1B[0;37mDEBUG=1B[0m|#18906] [REST] find( Package, [:all, {:project=3D>"Sour= ceprotectedProject"}] ) [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.651667, "db"=3D>1.117529, "back= end"=3D>0, "xml"=3D>0, :runtime=3D>13.431000000000001, :all=3D>54.497997} [=1B[0;37mDEBUG=1B[0m|#18906] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :view=3D>"status", :code=3D>["failed", "broken", "unresolv= able"]}] ) [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dstatus&code=3Dfailed&code=3Db= roken&code=3Dunresolvable [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>nil, "db"=3D>0.781854, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>33.332, :all=3D>74.854601} [=1B[0;37mDEBUG=1B[0m|#18906] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: POST url: http://localhost:= 3203/source/SourceprotectedProject?cmd=3Dshowlinked [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.661722, "db"=3D>0.888406, "back= end"=3D>0, "xml"=3D>0, :runtime=3D>12.786999999999999, :all=3D>30.294312} [=1B[0;37mDEBUG=1B[0m|#18906] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>nil, "db"=3D>0.7750310000000001, = "backend"=3D>0, "xml"=3D>0, :runtime=3D>30.311, :all=3D>71.03183200000001} [=1B[0;37mDEBUG=1B[0m|#18906] [REST] find( Collection, [:id, {:what=3D>"proje= ct", :predicate=3D>"maintenance/maintains/@project=3D'SourceprotectedProject'= "}] ) [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: get url: http://localhost:3= 203/search/project/id?match=3Dmaintenance%2Fmaintains%2F%40project%3D%27Sourc= eprotectedProject%27 [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.629791, "db"=3D>7.6872779999999= 99, "backend"=3D>0, "xml"=3D>0.035307, :runtime=3D>20.773, :all=3D>62.540501} [=1B[0;37mDEBUG=1B[0m|#18906] [REST] find( Project, [{:name=3D>"Sourceprotect= edProject"}] ) [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/_meta? [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.624274, "db"=3D>2.2991969999999= 995, "backend"=3D>0, "xml"=3D>0, :runtime=3D>14.056000000000001, :all=3D>53.2= 26276000000006} [=1B[32mINFO =1B[0m|#18906] Rendered project/_tabs.html.erb (6.6ms) [=1B[32mINFO =1B[0m|#18906] Rendered shared/_open_requests.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#18906] Rendered shared/_buildresult_box.html.erb (0.7m= s) [=1B[32mINFO =1B[0m|#18906] Rendered project/show.html.erb within layouts/a= pplication (65.4ms) [=1B[32mINFO =1B[0m|#18906] Rendered layouts/_watch_and_search.html.erb (0.= 6ms) [=1B[32mINFO =1B[0m|#18906] Rendered vendor/neutral/layouts/_header.html.er= b (1.2ms) [=1B[32mINFO =1B[0m|#18906] Rendered layouts/_personal_navigation.html.erb = (2.0ms) [=1B[32mINFO =1B[0m|#18906] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#18906] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#18906] Completed 200 OK in 643ms (Views: 73.3ms | Active= Record: 0.0ms | API: 609.9ms (XML: 0.3ms , View: 5.3ms , Backend: 0.0ms , DB:= 20.2ms , HTTP: 422.3ms) | XML: 1.6ms) [=1B[32mINFO =1B[0m|#18906]=20 Started GET "/project/buildresult?project=3DSourceprotectedProject&index=3D" = for 127.0.0.1 at 2012-10-02 15:05:43 +0200 [=1B[32mINFO =1B[0m|#18906] Processing by ProjectController#buildresult as */* [=1B[32mINFO =1B[0m|#18906] Parameters: {"project"=3D>"SourceprotectedProje= ct", "index"=3D>""} [=1B[0;37mDEBUG=1B[0m|#18906] Setting return_to: "/project/buildresult?projec= t=3DSourceprotectedProject&index=3D" [=1B[0;37mDEBUG=1B[0m|#18906] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#18906] Anonymous request to /project/buildresult?project= =3DSourceprotectedProject&index=3D [=1B[0;37mDEBUG=1B[0m|#18906] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>nil, "db"=3D>0.821562, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>26.013, :all=3D>29.235359} [=1B[32mINFO =1B[0m|#18906] Rendered project/_buildstatus.html.erb (1.7ms) [=1B[32mINFO =1B[0m|#18906] Completed 200 OK in 34ms (Views: 2.4ms | ActiveRe= cord: 0.0ms | API: 29.2ms (XML: 0.0ms , Backend: 0.0ms , DB: 0.8ms , HTTP: 3.= 2ms) | XML: 0.2ms) [=1B[32mINFO =1B[0m|#18906]=20 Started GET "/package/show?package=3Dpack&project=3DSourceprotectedProject" f= or 127.0.0.1 at 2012-10-02 15:05:44 +0200 [=1B[32mINFO =1B[0m|#18906] Processing by PackageController#show as HTML [=1B[32mINFO =1B[0m|#18906] Parameters: {"package"=3D>"pack", "project"=3D>= "SourceprotectedProject"} [=1B[0;37mDEBUG=1B[0m|#18906] Setting return_to: "/package/show?package=3Dpac= k&project=3DSourceprotectedProject" [=1B[0;37mDEBUG=1B[0m|#18906] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|#18906] Anonymous request to /package/show?package=3Dpack= &project=3DSourceprotectedProject [=1B[0;37mDEBUG=1B[0m|#18906] [REST] find( Package, [{:project=3D>"Sourceprot= ectedProject", :name=3D>"pack"}] ) [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/pack/_meta? [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.797207, "db"=3D>2.157502, "back= end"=3D>0, "xml"=3D>0, :runtime=3D>19.125, :all=3D>22.921742} [=1B[0;37mDEBUG=1B[0m|#18906] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#18906] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DSourceprotectedProject&package=3Dpack [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.579747, "db"=3D>0.720435, "back= end"=3D>0, "xml"=3D>0.0, :runtime=3D>8.692, :all=3D>50.323573} [=1B[0;37mDEBUG=1B[0m|#18906] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|#18906] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DSourcepro= tectedProject&package=3Dpack [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.5814149999999999, "db"=3D>0.567= 1, "backend"=3D>0, "xml"=3D>0.081133, :runtime=3D>7.236000000000001, :all=3D>= 47.484016} [=1B[0;37mDEBUG=1B[0m|#18906] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :package=3D>"pack", :view=3D>"status"}] ) [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dstatus&package=3Dpack [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>nil, "db"=3D>0.648173, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>87.01299999999999, :all=3D>131.040864} [=1B[0;37mDEBUG=1B[0m|#18906] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: POST url: http://localhost:= 3203/source/SourceprotectedProject/pack?cmd=3Dshowlinked [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>0.927134, "db"=3D>1.355525, "back= end"=3D>6.792584, "xml"=3D>0, :runtime=3D>29.765, :all=3D>34.331911} [=1B[0;37mDEBUG=1B[0m|#18906] [REST] find( Directory, [{:project=3D>"Sourcepr= otectedProject", :package=3D>"pack"}] ) [=1B[0;37mDEBUG=1B[0m|#18906] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/pack? [=1B[0;37mDEBUG=1B[0m|#18906] RT {"view"=3D>1.5379230000000002, "db"=3D>26.76= 419, "backend"=3D>0, "xml"=3D>0, :runtime=3D>55.303, :all=3D>95.396154} [=1B[31mERROR=1B[0m|#18906] rescue_action: caught ActiveXML::Transport::Forbi= ddenError: SourceprotectedProject/pack
[=1B[0;37mDEBUG=1B[0m|#18906] ERROR: 401; SourceprotectedProject/pack [=1B[32mINFO =1B[0m|#18906] Rendered error.html.erb within layouts/applicat= ion (1.2ms) [=1B[32mINFO =1B[0m|#18906] Rendered layouts/_watch_and_search.html.erb (0.= 7ms) [=1B[32mINFO =1B[0m|#18906] Rendered vendor/neutral/layouts/_header.html.er= b (2.4ms) [=1B[32mINFO =1B[0m|#18906] Rendered layouts/_personal_navigation.html.erb = (1.5ms) [=1B[32mINFO =1B[0m|#18906] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|#18906] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|#18906] Completed 401 Unauthorized in 402ms (Views: 10.7m= s | ActiveRecord: 0.0ms | API: 381.5ms (XML: 0.1ms , View: 4.4ms , Backend: 6= .8ms , DB: 32.2ms , HTTP: 174.4ms) | XML: 0.6ms) + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v + egrep 'perl|ruby' USER PID ACCESS COMMAND : 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/back= end_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/ap= i/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 --=20 To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org --===============5034105205881938634==-- From admin@opensuse.org Tue Oct 2 15:00:51 2012 From: admin@opensuse.org To: obs-tests@lists.opensuse.org Subject: [obs-tests] Build failed in Jenkins: obs_master_testsuite_webui-testsuite #340 Date: Tue, 02 Oct 2012 17:00:34 +0200 Message-ID: <671033343.251349190035002.JavaMail.jenkins@ci-opensuse> In-Reply-To: <2122677893.241349183451587.JavaMail.jenkins@ci-opensuse> MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="===============3585509003169629489==" --===============3585509003169629489== Content-Type: text/plain; charset="utf-8" Content-Transfer-Encoding: quoted-printable See Changes: [mls] [backend] publisher: call genhdlist2 with --allow-empty-media [adrian] [api/backend] define version 0.1 of build jobs constraints ------------------------------------------ [...truncated 301803 lines...] [=1B[0;37mDEBUG=1B[0m|# 2899] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.392148, "db"=3D>0.583906, "back= end"=3D>0, "xml"=3D>0.039692, :runtime=3D>8.191, :all=3D>50.537358999999995} [=1B[0;37mDEBUG=1B[0m|# 2899] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 2899] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DRemoteIns= tance [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.347464, "db"=3D>0.525033, "back= end"=3D>0, "xml"=3D>0.037849, :runtime=3D>7.144, :all=3D>47.407656} [=1B[0;37mDEBUG=1B[0m|# 2899] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 2899] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&roles=3Dsource&types=3Dma= intenance_incident&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.34509500000000004, "db"=3D>0.44= 985200000000003, "backend"=3D>0, "xml"=3D>0.035514, :runtime=3D>6.87, :all=3D= >47.431488} [=1B[0;37mDEBUG=1B[0m|# 2899] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: GET url: http://localhost:3= 203/search/project/?match=3Dstarts-with%28%40name%2C%27RemoteInstance%3A%27%2= 9+and+%40kind%3D%27maintenance_incident%27+and+repository%2Freleasetarget%2F%= 40trigger%3D%27maintenance%27 [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.645557, "db"=3D>1.137893, "back= end"=3D>0, "xml"=3D>0.064997, :runtime=3D>14.247, :all=3D>55.58112} [=1B[0;37mDEBUG=1B[0m|# 2899] [REST] find( Package, [:all, {:project=3D>"Remo= teInstance"}] ) [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: get url: http://localhost:3= 203/source/RemoteInstance [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.6622279999999999, "db"=3D>0.926= 2929999999999, "backend"=3D>0, "xml"=3D>0, :runtime=3D>11.06, :all=3D>51.1692= 35} [=1B[0;37mDEBUG=1B[0m|# 2899] [REST] find( Buildresult, [{:project=3D>"Remote= Instance", :view=3D>"status", :code=3D>["failed", "broken", "unresolvable"]}]= ) [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: get url: http://localhost:3= 203/build/RemoteInstance/_result?view=3Dstatus&code=3Dfailed&code=3Dbroken&co= de=3Dunresolvable [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>nil, "db"=3D>0.63434, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>17.707, :all=3D>59.270283} [=1B[0;37mDEBUG=1B[0m|# 2899] [REST] find( Buildresult, [{:project=3D>"Remote= Instance", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: get url: http://localhost:3= 203/build/RemoteInstance/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>nil, "db"=3D>0.931021, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>65.80199999999999, :all=3D>107.296239} [=1B[0;37mDEBUG=1B[0m|# 2899] [REST] find( Collection, [:id, {:what=3D>"proje= ct", :predicate=3D>"maintenance/maintains/@project=3D'RemoteInstance'"}] ) [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: get url: http://localhost:3= 203/search/project/id?match=3Dmaintenance%2Fmaintains%2F%40project%3D%27Remot= eInstance%27 [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.487718, "db"=3D>0.710462, "back= end"=3D>0, "xml"=3D>0.077174, :runtime=3D>9.593, :all=3D>51.052023000000005} [=1B[32mINFO =1B[0m|# 2899] Rendered project/_tabs.html.erb (1.8ms) [=1B[32mINFO =1B[0m|# 2899] Rendered shared/_open_requests.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 2899] Rendered shared/_buildresult_box.html.erb (0.6m= s) [=1B[32mINFO =1B[0m|# 2899] Rendered project/show.html.erb within layouts/a= pplication (4.6ms) [=1B[32mINFO =1B[0m|# 2899] Rendered layouts/_watch_and_search.html.erb (0.= 4ms) [=1B[32mINFO =1B[0m|# 2899] Rendered vendor/neutral/layouts/_header.html.er= b (0.9ms) [=1B[32mINFO =1B[0m|# 2899] Rendered layouts/_personal_navigation.html.erb = (1.1ms) [=1B[32mINFO =1B[0m|# 2899] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 2899] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|# 2899] Completed 200 OK in 510ms (Views: 10.3ms | Active= Record: 0.0ms | API: 491.2ms (XML: 0.3ms , View: 3.4ms , Backend: 0.0ms , DB:= 8.1ms , HTTP: 332.5ms) | XML: 0.9ms) [=1B[32mINFO =1B[0m|# 2899]=20 Started GET "/project/buildresult?project=3DRemoteInstance&index=3D" for 127.= 0.0.1 at 2012-10-02 16:55:25 +0200 [=1B[32mINFO =1B[0m|# 2899] Processing by ProjectController#buildresult as */* [=1B[32mINFO =1B[0m|# 2899] Parameters: {"project"=3D>"RemoteInstance", "in= dex"=3D>""} [=1B[0;37mDEBUG=1B[0m|# 2899] Setting return_to: "/project/buildresult?projec= t=3DRemoteInstance&index=3D" [=1B[0;37mDEBUG=1B[0m|# 2899] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 2899] Anonymous request to /project/buildresult?project= =3DRemoteInstance&index=3D [=1B[0;37mDEBUG=1B[0m|# 2899] [REST] find( Buildresult, [{:project=3D>"Remote= Instance", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: get url: http://localhost:3= 203/build/RemoteInstance/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>nil, "db"=3D>0.719787, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>14.119, :all=3D>16.966641000000003} [=1B[32mINFO =1B[0m|# 2899] Rendered project/_buildstatus.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 2899] Completed 200 OK in 19ms (Views: 0.6ms | ActiveRe= cord: 0.0ms | API: 17.0ms (XML: 0.0ms , Backend: 0.0ms , DB: 0.7ms , HTTP: 2.= 8ms) | XML: 0.1ms) [=1B[32mINFO =1B[0m|# 2899]=20 Started GET "/project/requests?project=3DRemoteInstance" for 127.0.0.1 at 201= 2-10-02 16:55:26 +0200 [=1B[32mINFO =1B[0m|# 2899] Processing by ProjectController#requests as HTML [=1B[32mINFO =1B[0m|# 2899] Parameters: {"project"=3D>"RemoteInstance"} [=1B[0;37mDEBUG=1B[0m|# 2899] Setting return_to: "/project/requests?project= =3DRemoteInstance" [=1B[0;37mDEBUG=1B[0m|# 2899] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 2899] Anonymous request to /project/requests?project=3D= RemoteInstance [=1B[0;37mDEBUG=1B[0m|# 2899] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 2899] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.487503, "db"=3D>0.757995, "back= end"=3D>0, "xml"=3D>0.09861199999999999, :runtime=3D>9.94, :all=3D>14.007396} [=1B[0;37mDEBUG=1B[0m|# 2899] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 2899] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DRemoteIns= tance [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.315944, "db"=3D>0.392717, "back= end"=3D>0, "xml"=3D>0.035907, :runtime=3D>5.602, :all=3D>46.698800999999996} [=1B[0;37mDEBUG=1B[0m|# 2899] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 2899] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&roles=3Dsource&types=3Dma= intenance_incident&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.340495, "db"=3D>0.641664, "back= end"=3D>0, "xml"=3D>0.039499, :runtime=3D>7.106999999999999, :all=3D>47.47710= 7} [=1B[32mINFO =1B[0m|# 2899] Rendered project/_tabs.html.erb (1.5ms) [=1B[32mINFO =1B[0m|# 2899] Rendered shared/_select_requests.html.erb (1.0m= s) [=1B[32mINFO =1B[0m|# 2899] Rendered project/requests.html.erb within layou= ts/application (3.6ms) [=1B[32mINFO =1B[0m|# 2899] Rendered layouts/_watch_and_search.html.erb (0.= 8ms) [=1B[32mINFO =1B[0m|# 2899] Rendered vendor/neutral/layouts/_header.html.er= b (50.3ms) [=1B[32mINFO =1B[0m|# 2899] Rendered layouts/_personal_navigation.html.erb = (3.2ms) [=1B[32mINFO =1B[0m|# 2899] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 2899] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|# 2899] Completed 200 OK in 175ms (Views: 62.3ms | Active= Record: 0.0ms | API: 108.2ms (XML: 0.2ms , View: 1.1ms , Backend: 0.0ms , DB:= 1.8ms , HTTP: 85.5ms) | XML: 0.1ms) [=1B[32mINFO =1B[0m|# 2899]=20 Started GET "/request/list?states=3Dnew%2Creview&types=3Dall&project=3DRemote= Instance&no_target=3Dtrue" for 127.0.0.1 at 2012-10-02 16:55:26 +0200 [=1B[32mINFO =1B[0m|# 2899] Processing by RequestController#list as */* [=1B[32mINFO =1B[0m|# 2899] Parameters: {"states"=3D>"new,review", "types"= =3D>"all", "project"=3D>"RemoteInstance", "no_target"=3D>"true"} [=1B[0;37mDEBUG=1B[0m|# 2899] Setting return_to: "/request/list?states=3Dnew%= 2Creview&types=3Dall&project=3DRemoteInstance&no_target=3Dtrue" [=1B[0;37mDEBUG=1B[0m|# 2899] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 2899] Anonymous request to /request/list?states=3Dnew%2= Creview&types=3Dall&project=3DRemoteInstance&no_target=3Dtrue [=1B[0;37mDEBUG=1B[0m|# 2899] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 2899] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&project=3DRemoteInstance [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.383925, "db"=3D>0.916115, "back= end"=3D>0, "xml"=3D>0.057705, :runtime=3D>7.729, :all=3D>10.462154} [=1B[32mINFO =1B[0m|# 2899] Rendered shared/_requests.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 2899] Completed 200 OK in 13ms (Views: 0.7ms | ActiveRe= cord: 0.0ms | API: 10.5ms (XML: 0.1ms , View: 0.4ms , Backend: 0.0ms , DB: 0.= 9ms , HTTP: 2.7ms) | XML: 0.1ms) [=1B[32mINFO =1B[0m|# 2899]=20 Started GET "/project/show" for 127.0.0.1 at 2012-10-02 16:55:27 +0200 [=1B[32mINFO =1B[0m|# 2899] Processing by ProjectController#show as HTML [=1B[0;37mDEBUG=1B[0m|# 2899] Setting return_to: "/project/show" [=1B[0;37mDEBUG=1B[0m|# 2899] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 2899] Anonymous request to /project/show [=1B[32mINFO =1B[0m|# 2899] Redirected to http://localhost:3199/project/list_= public?nextstatus=3D404 [=1B[32mINFO =1B[0m|# 2899] Filter chain halted as :require_project rendered = or redirected [=1B[32mINFO =1B[0m|# 2899] Completed 302 Found in 1ms (ActiveRecord: 0.0ms |= XML: 0.0ms) [=1B[32mINFO =1B[0m|# 2899]=20 Started GET "/project/list_public?nextstatus=3D404" for 127.0.0.1 at 2012-10-= 02 16:55:27 +0200 [=1B[32mINFO =1B[0m|# 2899] Processing by ProjectController#list_public as HT= ML [=1B[32mINFO =1B[0m|# 2899] Parameters: {"nextstatus"=3D>"404"} [=1B[0;37mDEBUG=1B[0m|# 2899] Setting return_to: "/project/list_public?nextst= atus=3D404" [=1B[0;37mDEBUG=1B[0m|# 2899] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 2899] Anonymous request to /project/list_public?nextsta= tus=3D404 [=1B[32mINFO =1B[0m|# 2899] Rendered project/_filter_projects.html.erb (0.9= ms) [=1B[32mINFO =1B[0m|# 2899] Rendered project/_search_project.html.erb (5.8m= s) [=1B[32mINFO =1B[0m|# 2899] Rendered project/list.html.erb within layouts/a= pplication (7.2ms) [=1B[32mINFO =1B[0m|# 2899] Rendered layouts/_watch_and_search.html.erb (0.= 4ms) [=1B[32mINFO =1B[0m|# 2899] Rendered vendor/neutral/layouts/_header.html.er= b (1.0ms) [=1B[32mINFO =1B[0m|# 2899] Rendered layouts/_personal_navigation.html.erb = (1.1ms) [=1B[32mINFO =1B[0m|# 2899] Rendered layouts/_flash.html.erb (0.1ms) [=1B[32mINFO =1B[0m|# 2899] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|# 2899] Completed 404 Not Found in 16ms (Views: 12.8ms | = ActiveRecord: 0.0ms | XML: 1.7ms) [=1B[32mINFO =1B[0m|# 2899]=20 Started GET "/project/show?project=3DSourceprotectedProject" for 127.0.0.1 at= 2012-10-02 16:55:27 +0200 [=1B[32mINFO =1B[0m|# 2899] Processing by ProjectController#show as HTML [=1B[32mINFO =1B[0m|# 2899] Parameters: {"project"=3D>"SourceprotectedProje= ct"} [=1B[0;37mDEBUG=1B[0m|# 2899] Setting return_to: "/project/show?project=3DSou= rceprotectedProject" [=1B[0;37mDEBUG=1B[0m|# 2899] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 2899] Anonymous request to /project/show?project=3DSour= ceprotectedProject [=1B[0;37mDEBUG=1B[0m|# 2899] [REST] find( Project, [{:name=3D>"Sourceprotect= edProject"}] ) [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/_meta? [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.57679, "db"=3D>2.58313, "backen= d"=3D>0, "xml"=3D>0, :runtime=3D>22.081, :all=3D>25.338552} [=1B[0;37mDEBUG=1B[0m|# 2899] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 2899] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DSourceprotectedProject [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.39416, "db"=3D>0.60055100000000= 01, "backend"=3D>0, "xml"=3D>0.042039999999999994, :runtime=3D>8.7, :all=3D>4= 8.361081999999996} [=1B[0;37mDEBUG=1B[0m|# 2899] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 2899] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DSourcepro= tectedProject [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.47435799999999995, "db"=3D>0.52= 053, "backend"=3D>0, "xml"=3D>0.046981, :runtime=3D>6.959, :all=3D>46.64116} [=1B[0;37mDEBUG=1B[0m|# 2899] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 2899] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew%2Creview&roles=3Dsource&types=3Dma= intenance_incident&project=3DSourceprotectedProject [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.394862, "db"=3D>0.525163, "back= end"=3D>0, "xml"=3D>0.074513, :runtime=3D>7.073, :all=3D>47.305018} [=1B[0;37mDEBUG=1B[0m|# 2899] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: GET url: http://localhost:3= 203/search/project/?match=3Dstarts-with%28%40name%2C%27SourceprotectedProject= %3A%27%29+and+%40kind%3D%27maintenance_incident%27+and+repository%2Freleaseta= rget%2F%40trigger%3D%27maintenance%27 [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.49779900000000005, "db"=3D>1.03= 8687, "backend"=3D>0, "xml"=3D>0.050055, :runtime=3D>13.058, :all=3D>55.59271= 8} [=1B[0;37mDEBUG=1B[0m|# 2899] [REST] find( Package, [:all, {:project=3D>"Sour= ceprotectedProject"}] ) [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.43415800000000004, "db"=3D>0.98= 0332, "backend"=3D>0, "xml"=3D>0, :runtime=3D>11.969999999999999, :all=3D>50.= 721398} [=1B[0;37mDEBUG=1B[0m|# 2899] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :view=3D>"status", :code=3D>["failed", "broken", "unresolv= able"]}] ) [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dstatus&code=3Dfailed&code=3Db= roken&code=3Dunresolvable [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>nil, "db"=3D>0.75514, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>29.034000000000002, :all=3D>71.525246} [=1B[0;37mDEBUG=1B[0m|# 2899] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: POST url: http://localhost:= 3203/source/SourceprotectedProject?cmd=3Dshowlinked [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.62021, "db"=3D>0.88884, "backen= d"=3D>0, "xml"=3D>0, :runtime=3D>14.942, :all=3D>19.475686} [=1B[0;37mDEBUG=1B[0m|# 2899] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>nil, "db"=3D>0.511807, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>25.657, :all=3D>65.9824} [=1B[0;37mDEBUG=1B[0m|# 2899] [REST] find( Collection, [:id, {:what=3D>"proje= ct", :predicate=3D>"maintenance/maintains/@project=3D'SourceprotectedProject'= "}] ) [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: get url: http://localhost:3= 203/search/project/id?match=3Dmaintenance%2Fmaintains%2F%40project%3D%27Sourc= eprotectedProject%27 [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.599249, "db"=3D>0.649482, "back= end"=3D>0, "xml"=3D>0.060898, :runtime=3D>9.112, :all=3D>51.110577} [=1B[0;37mDEBUG=1B[0m|# 2899] [REST] find( Project, [{:name=3D>"Sourceprotect= edProject"}] ) [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/_meta? [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.7528269999999999, "db"=3D>0.613= 68, "backend"=3D>0, "xml"=3D>0, :runtime=3D>10.024999999999999, :all=3D>51.76= 2621} [=1B[32mINFO =1B[0m|# 2899] Rendered project/_tabs.html.erb (3.2ms) [=1B[32mINFO =1B[0m|# 2899] Rendered shared/_open_requests.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 2899] Rendered shared/_buildresult_box.html.erb (0.7m= s) [=1B[32mINFO =1B[0m|# 2899] Rendered project/show.html.erb within layouts/a= pplication (59.5ms) [=1B[32mINFO =1B[0m|# 2899] Rendered layouts/_watch_and_search.html.erb (0.= 8ms) [=1B[32mINFO =1B[0m|# 2899] Rendered vendor/neutral/layouts/_header.html.er= b (1.4ms) [=1B[32mINFO =1B[0m|# 2899] Rendered layouts/_personal_navigation.html.erb = (1.2ms) [=1B[32mINFO =1B[0m|# 2899] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 2899] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|# 2899] Completed 200 OK in 559ms (Views: 66.1ms | Active= Record: 0.0ms | API: 533.8ms (XML: 0.3ms , View: 4.7ms , Backend: 0.0ms , DB:= 9.7ms , HTTP: 375.2ms) | XML: 1.3ms) [=1B[32mINFO =1B[0m|# 2899]=20 Started GET "/project/buildresult?project=3DSourceprotectedProject&index=3D" = for 127.0.0.1 at 2012-10-02 16:55:28 +0200 [=1B[32mINFO =1B[0m|# 2899] Processing by ProjectController#buildresult as */* [=1B[32mINFO =1B[0m|# 2899] Parameters: {"project"=3D>"SourceprotectedProje= ct", "index"=3D>""} [=1B[0;37mDEBUG=1B[0m|# 2899] Setting return_to: "/project/buildresult?projec= t=3DSourceprotectedProject&index=3D" [=1B[0;37mDEBUG=1B[0m|# 2899] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 2899] Anonymous request to /project/buildresult?project= =3DSourceprotectedProject&index=3D [=1B[0;37mDEBUG=1B[0m|# 2899] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :view=3D>"summary"}] ) [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dsummary [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>nil, "db"=3D>1.019245, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>26.686999999999998, :all=3D>29.82794} [=1B[32mINFO =1B[0m|# 2899] Rendered project/_buildstatus.html.erb (1.4ms) [=1B[32mINFO =1B[0m|# 2899] Completed 200 OK in 34ms (Views: 2.0ms | ActiveRe= cord: 0.0ms | API: 29.8ms (XML: 0.0ms , Backend: 0.0ms , DB: 1.0ms , HTTP: 3.= 1ms) | XML: 0.1ms) [=1B[32mINFO =1B[0m|# 2899]=20 Started GET "/package/show?package=3Dpack&project=3DSourceprotectedProject" f= or 127.0.0.1 at 2012-10-02 16:55:29 +0200 [=1B[32mINFO =1B[0m|# 2899] Processing by PackageController#show as HTML [=1B[32mINFO =1B[0m|# 2899] Parameters: {"package"=3D>"pack", "project"=3D>= "SourceprotectedProject"} [=1B[0;37mDEBUG=1B[0m|# 2899] Setting return_to: "/package/show?package=3Dpac= k&project=3DSourceprotectedProject" [=1B[0;37mDEBUG=1B[0m|# 2899] Authenticating with iChain mode: off [=1B[32mINFO =1B[0m|# 2899] Anonymous request to /package/show?package=3Dpack= &project=3DSourceprotectedProject [=1B[0;37mDEBUG=1B[0m|# 2899] [REST] find( Package, [{:project=3D>"Sourceprot= ectedProject", :name=3D>"pack"}] ) [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/pack/_meta? [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.550451, "db"=3D>1.7041419999999= 998, "backend"=3D>0, "xml"=3D>0, :runtime=3D>18.855, :all=3D>21.991985} [=1B[0;37mDEBUG=1B[0m|# 2899] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 2899] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dreview&roles=3Dreviewer&reviewstates= =3Dnew&project=3DSourceprotectedProject&package=3Dpack [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.39919899999999997, "db"=3D>0.61= 591, "backend"=3D>0, "xml"=3D>0.021906, :runtime=3D>9.501000000000001, :all= =3D>53.282348999999996} [=1B[0;37mDEBUG=1B[0m|# 2899] Fetching request list from api [=1B[0;37mDEBUG=1B[0m|# 2899] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: GET url: http://localhost:3= 203/request?view=3Dcollection&states=3Dnew&roles=3Dtarget&project=3DSourcepro= tectedProject&package=3Dpack [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.464913, "db"=3D>0.533102, "back= end"=3D>0, "xml"=3D>0.047033, :runtime=3D>8.08, :all=3D>51.542298} [=1B[0;37mDEBUG=1B[0m|# 2899] [REST] find( Buildresult, [{:project=3D>"Source= protectedProject", :package=3D>"pack", :view=3D>"status"}] ) [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: get url: http://localhost:3= 203/build/SourceprotectedProject/_result?view=3Dstatus&package=3Dpack [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>nil, "db"=3D>0.564737, "backend"= =3D>0, "xml"=3D>0, :runtime=3D>78.602, :all=3D>123.31906400000001} [=1B[0;37mDEBUG=1B[0m|# 2899] --> direct_http url: # [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: POST url: http://localhost:= 3203/source/SourceprotectedProject/pack?cmd=3Dshowlinked [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>0.812233, "db"=3D>1.5317340000000= 002, "backend"=3D>7.975701, "xml"=3D>0, :runtime=3D>36.326, :all=3D>42.288612} [=1B[0;37mDEBUG=1B[0m|# 2899] [REST] find( Directory, [{:project=3D>"Sourcepr= otectedProject", :package=3D>"pack"}] ) [=1B[0;37mDEBUG=1B[0m|# 2899] http_do #1: method: get url: http://localhost:3= 203/source/SourceprotectedProject/pack? [=1B[0;37mDEBUG=1B[0m|# 2899] RT {"view"=3D>1.506999, "db"=3D>11.073576999999= 998, "backend"=3D>0, "xml"=3D>0, :runtime=3D>68.325, :all=3D>112.16057} [=1B[31mERROR=1B[0m|# 2899] rescue_action: caught ActiveXML::Transport::Forbi= ddenError: SourceprotectedProject/pack
[=1B[0;37mDEBUG=1B[0m|# 2899] ERROR: 401; SourceprotectedProject/pack [=1B[32mINFO =1B[0m|# 2899] Rendered error.html.erb within layouts/applicat= ion (1.5ms) [=1B[32mINFO =1B[0m|# 2899] Rendered layouts/_watch_and_search.html.erb (0.= 9ms) [=1B[32mINFO =1B[0m|# 2899] Rendered vendor/neutral/layouts/_header.html.er= b (2.0ms) [=1B[32mINFO =1B[0m|# 2899] Rendered layouts/_personal_navigation.html.erb = (1.1ms) [=1B[32mINFO =1B[0m|# 2899] Rendered layouts/_flash.html.erb (0.0ms) [=1B[32mINFO =1B[0m|# 2899] Rendered vendor/neutral/layouts/_sponsor.html.e= rb (0.0ms) [=1B[32mINFO =1B[0m|# 2899] Completed 401 Unauthorized in 426ms (Views: 11.3m= s | ActiveRecord: 0.0ms | API: 404.6ms (XML: 0.1ms , View: 3.7ms , Backend: 8= .0ms , DB: 16.0ms , HTTP: 184.9ms) | XML: 0.8ms) + echo + cleanup + echo 'Killing backend processes' Killing backend processes + egrep 'perl|ruby' + fuser -v Cannot stat file /proc/2928/fd/8: No such file or directory USER PID ACCESS COMMAND : 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/back= end_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/ap= i/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 --=20 To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org --===============3585509003169629489==-- From admin@opensuse.org Tue Oct 2 15:46:28 2012 From: admin@opensuse.org To: obs-tests@lists.opensuse.org Subject: [obs-tests] Jenkins build is back to normal : obs_master_testsuite_webui-testsuite #341 Date: Tue, 02 Oct 2012 17:46:10 +0200 Message-ID: <230755777.261349192770643.JavaMail.jenkins@ci-opensuse> In-Reply-To: <671033343.251349190035002.JavaMail.jenkins@ci-opensuse> MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="===============6999758588316500379==" --===============6999758588316500379== Content-Type: text/plain; charset="utf-8" Content-Transfer-Encoding: quoted-printable See --=20 To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org --===============6999758588316500379==--