September 2012 - Open Build Service Tests

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

28 Sep '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui/463/changes> Changes: [Stephan Kulow] [api] fix that xpath queries only showed one review and one history per request [Stephan Kulow] [api] revert the flag on home:Iggy ------------------------------------------ [...truncated 21906 lines...] [[0;37mDEBUG[0m|# 7138] RT {"view"=>0.8938470000000001, "db"=>5.3318200000000004, "backend"=>0, "xml"=>0.0, :runtime=>66.645, :all=>107.619834} [[0;37mDEBUG[0m|# 7138] Fetching request list from api [[0;37mDEBUG[0m|# 7138] --> direct_http url: #<URI::Generic:0x00000006cfa2c8 URL:http://localhost:3203/request?view=collection&states=new&roles=maintainer&user=tom> [[0;37mDEBUG[0m|# 7138] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=new&roles=maintainer&u… [[0;37mDEBUG[0m|# 7138] RT {"view"=>0.708008, "db"=>2.200287, "backend"=>0, "xml"=>0.0, :runtime=>17.575, :all=>60.189355} [[0;37mDEBUG[0m|# 7138] method_missing -to_str- nil [[0;37mDEBUG[0m|# 7138] method_missing -to_str- nil [[0;37mDEBUG[0m|# 7138] method_missing -to_ary- nil [[0;37mDEBUG[0m|# 7138] Collection.find( 1800 seconds, {:what=>"project", :predicate=>"[attribute/@name='OBS:VeryImportantProject']"}) [[0;37mDEBUG[0m|# 7138] method_missing -to_str- nil [[0;37mDEBUG[0m|# 7138] method_missing -to_ary- nil [[0;37mDEBUG[0m|# 7138] Collection.find( 120 seconds, id, {:what=>"project", :predicate=>"not(starts-with(@name,'home:')) and not(@kind='maintenance_incident')"}) [[0;37mDEBUG[0m|# 7138] [REST] find( Collection, [:id, {:what=>"project", :predicate=>"not(starts-with(@name,'home:')) and not(@kind='maintenance_incident')"}] ) [[0;37mDEBUG[0m|# 7138] http_do #1: method: get url: http://localhost:3203/search/project/id?match=not%28starts-with%28%40name%2… [[0;37mDEBUG[0m|# 7138] RT {"view"=>2.4906479999999998, "db"=>0.49493, "backend"=>0, "xml"=>0, :runtime=>11.948, :all=>53.144437} [[31mERROR[0m|# 7138] rescue_action: caught ActiveXML::Transport::Error: <?xml version="1.0"?> <status code="unknown"> <summary>Uncaught exception: Mysql2::Error: Operand should contain 1 column(s): SELECT distinct(db_projects.id,db_projects.name) FROM `db_projects` LEFT JOIN db_project_types pt ON db_projects.type_id = pt.id WHERE (db_projects.id not in (3000,3006)) AND (1 AND ((NOT db_projects.name LIKE CONCAT('home:','%') OR db_projects.name LIKE CONCAT('home:','%') IS NULL) AND (NOT pt.name = 'maintenance_incident' OR pt.name = 'maintenance_incident' IS NULL))) AND (`db_projects`.`id` >= 0) ORDER BY `db_projects`.`id` ASC LIMIT 1000 /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:245:in `query' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:245:in `block in execute' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_adapter.rb:280:in `block in log' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_adapter.rb:275:in `log' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:245:in `execute' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/mysql2_adapter.rb:211:in `execute' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/mysql2_adapter.rb:215:in `exec_query' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/mysql2_adapter.rb:224:in `select' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/database_statements.rb:18:in `select_all' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/query_cache.rb:61:in `block in select_all' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/query_cache.rb:75:in `cache_sql' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/query_cache.rb:61:in `select_all' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/querying.rb:38:in `block in find_by_sql' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/explain.rb:40:in `logging_query_plan' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/querying.rb:37:in `find_by_sql' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:171:in `exec_queries' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:160:in `block in to_a' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/explain.rb:40:in `logging_query_plan' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:159:in `to_a' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:189:in `exec_queries' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:160:in `block in to_a' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/explain.rb:40:in `logging_query_plan' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:159:in `to_a' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation/finder_methods.rb:159:in `all' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation/batches.rb:66:in `find_in_batches' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation/batches.rb:20:in `find_each' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/querying.rb:8:in `find_each' <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/api/lib/xpath_…>:275:in `find' <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/api/app/contro…>:69:in `search' <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/api/app/contro…>:11:in `project_id' /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/callbacks.rb:18:in `block in process_action' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:491:in `_run__1076733721310821255__process_action__687053855051104768__callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.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/notifications.rb:123:in `block in instrument' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifications.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/rendering.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 `block in call' /usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `each' /usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `call' /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/middleware/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/middleware/head.rb:14:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/params_parser.rb:21:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/flash.rb:242:in `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:205:in `context' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:200:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/cookies.rb:339:in `call' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/query_cache.rb:64:in `call' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/connection_pool.rb:473:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `_run__2362481055202625064__call__3966339363132472169__callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_call_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:27:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/remote_ip.rb:31:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/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/middleware/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/middleware/static.rb:62:in `call' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/engine.rb:479:in `call' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/application.rb:223:in `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:17: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'</summary> <details></details> </status> [[0;37mDEBUG[0m|# 7138] ERROR: unknown; Uncaught exception: Mysql2::Error: Operand should contain 1 column(s): SELECT distinct(db_projects.id,db_projects.name) FROM `db_projects` LEFT JOIN db_project_types pt ON db_projects.type_id = pt.id WHERE (db_projects.id not in (3000,3006)) AND (1 AND ((NOT db_projects.name LIKE CONCAT('home:','%') OR db_projects.name LIKE CONCAT('home:','%') IS NULL) AND (NOT pt.name = 'maintenance_incident' OR pt.name = 'maintenance_incident' IS NULL))) AND (`db_projects`.`id` >= 0) ORDER BY `db_projects`.`id` ASC LIMIT 1000 /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:245:in `query' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:245:in `block in execute' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_adapter.rb:280:in `block in log' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_adapter.rb:275:in `log' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:245:in `execute' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/mysql2_adapter.rb:211:in `execute' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/mysql2_adapter.rb:215:in `exec_query' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/mysql2_adapter.rb:224:in `select' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/database_statements.rb:18:in `select_all' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/query_cache.rb:61:in `block in select_all' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/query_cache.rb:75:in `cache_sql' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/query_cache.rb:61:in `select_all' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/querying.rb:38:in `block in find_by_sql' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/explain.rb:40:in `logging_query_plan' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/querying.rb:37:in `find_by_sql' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:171:in `exec_queries' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:160:in `block in to_a' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/explain.rb:40:in `logging_query_plan' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:159:in `to_a' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:189:in `exec_queries' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:160:in `block in to_a' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/explain.rb:40:in `logging_query_plan' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:159:in `to_a' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation/finder_methods.rb:159:in `all' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation/batches.rb:66:in `find_in_batches' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation/batches.rb:20:in `find_each' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/querying.rb:8:in `find_each' <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/api/lib/xpath_…>:275:in `find' <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/api/app/contro…>:69:in `search' <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/api/app/contro…>:11:in `project_id' /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/callbacks.rb:18:in `block in process_action' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:491:in `_run__1076733721310821255__process_action__687053855051104768__callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.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/notifications.rb:123:in `block in instrument' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifications.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/rendering.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 `block in call' /usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `each' /usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `call' /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/middleware/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/middleware/head.rb:14:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/params_parser.rb:21:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/flash.rb:242:in `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:205:in `context' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:200:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/cookies.rb:339:in `call' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/query_cache.rb:64:in `call' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/connection_pool.rb:473:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `_run__2362481055202625064__call__3966339363132472169__callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_call_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:27:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/remote_ip.rb:31:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/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/middleware/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/middleware/static.rb:62:in `call' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/engine.rb:479:in `call' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/application.rb:223:in `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:17: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' [[32mINFO [0m|# 7138] Rendered layouts/_watch_and_search.html.erb (0.7ms) [[32mINFO [0m|# 7138] Rendered vendor/neutral/layouts/_header.html.erb (1.5ms) [[32mINFO [0m|# 7138] Rendered layouts/_personal_navigation.html.erb (0.9ms) [[32mINFO [0m|# 7138] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 7138] Rendered vendor/neutral/layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 7138] Completed 400 Bad Request in 338ms (Views: 8.4ms | ActiveRecord: 0.0ms | API: 318.8ms (XML: 0.0ms , View: 4.5ms , Backend: 0.0ms , DB: 9.4ms , HTTP: 206.9ms) | XML: 0.7ms) [[32mINFO [0m|# 7138] Started POST "/user/logout" for 127.0.0.1 at 2012-09-28 17:12:11 +0200 [[32mINFO [0m|# 7138] Processing by UserController#logout as HTML [[0;37mDEBUG[0m|# 7138] Setting return_to: "/user/logout" [[0;37mDEBUG[0m|# 7138] Authenticating with iChain mode: off [[32mINFO [0m|# 7138] Authenticated request to "/user/logout" from tom [[32mINFO [0m|# 7138] Logging out: tom [[32mINFO [0m|# 7138] Redirected to http://example.org/ [[31mERROR[0m|# 7138] Could not log "process_action.action_controller" event. TypeError: can't convert nil into Float [[0;37mDEBUG[0m|# 7138] [1m[35m (0.0ms)[0m rollback transaction + echo + cleanup + echo 'Killing backend processes' Killing backend processes + egrep 'perl|ruby' + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/> USER PID ACCESS COMMAND <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/>: jenkins ..c.. sh jenkins ..c.. sh jenkins ..c.. egrep + echo 'Remove log/tmp files to save disc space' Remove log/tmp files to save disc space + rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets + exit 1 Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

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

28 Sep '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/325/changes> Changes: [Stephan Kulow] [api] fix that xpath queries only showed one review and one history per request [Stephan Kulow] [api] revert the flag on home:Iggy ------------------------------------------ [...truncated 19738 lines...] [[32mINFO [0m|#30079] Rendered layouts/_personal_navigation.html.erb (1.3ms) [[32mINFO [0m|#30079] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#30079] Rendered vendor/neutral/layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#30079] Completed 200 OK in 33ms (Views: 17.8ms | ActiveRecord: 0.0ms | API: 10.7ms (XML: 0.2ms , View: 0.5ms , Backend: 0.0ms , DB: 0.9ms , HTTP: 3.1ms) | XML: 0.2ms) [[32mINFO [0m|#30079] Started GET "/project/list_public" for 127.0.0.1 at 2012-09-28 16:58:25 +0200 [[32mINFO [0m|#30079] Processing by ProjectController#list_public as HTML [[0;37mDEBUG[0m|#30079] Setting return_to: "/project/list_public" [[0;37mDEBUG[0m|#30079] Authenticating with iChain mode: off [[32mINFO [0m|#30079] Anonymous request to /project/list_public [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_ary- nil [[0;37mDEBUG[0m|#30079] Collection.find( 1800 seconds, {:what=>"project", :predicate=>"[attribute/@name='OBS:VeryImportantProject']"}) [[0;37mDEBUG[0m|#30079] [REST] find( Collection, [{:what=>"project", :predicate=>"[attribute/@name='OBS:VeryImportantProject']"}] ) [[0;37mDEBUG[0m|#30079] [REST] Transport.find using POST-method [[0;37mDEBUG[0m|#30079] http_do #1: method: post url: http://localhost:3203/search/project [[0;37mDEBUG[0m|#30079] RT {"view"=>0.476219, "db"=>1.139882, "backend"=>0, "xml"=>0.065328, :runtime=>10.259, :all=>15.098745000000001} [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_ary- nil [[0;37mDEBUG[0m|#30079] Collection.find( 120 seconds, id, {:what=>"project", :predicate=>"not(starts-with(@name,'home:')) and not(@kind='maintenance_incident')"}) [[0;37mDEBUG[0m|#30079] [REST] find( Collection, [:id, {:what=>"project", :predicate=>"not(starts-with(@name,'home:')) and not(@kind='maintenance_incident')"}] ) [[0;37mDEBUG[0m|#30079] http_do #1: method: get url: http://localhost:3203/search/project/id?match=not%28starts-with%28%40name%2… [[0;37mDEBUG[0m|#30079] RT {"view"=>3.459474, "db"=>0.805398, "backend"=>0, "xml"=>0, :runtime=>11.94, :all=>50.148155} [[31mERROR[0m|#30079] rescue_action: caught ActiveXML::Transport::Error: <?xml version="1.0"?> <status code="unknown"> <summary>Uncaught exception: Mysql2::Error: Operand should contain 1 column(s): SELECT distinct(db_projects.id,db_projects.name) FROM `db_projects` LEFT JOIN db_project_types pt ON db_projects.type_id = pt.id WHERE (db_projects.id not in (3000,3006)) AND (1 AND ((NOT db_projects.name LIKE CONCAT('home:','%') OR db_projects.name LIKE CONCAT('home:','%') IS NULL) AND (NOT pt.name = 'maintenance_incident' OR pt.name = 'maintenance_incident' IS NULL))) AND (`db_projects`.`id` >= 0) ORDER BY `db_projects`.`id` ASC LIMIT 1000 /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:245:in `query' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:245:in `block in execute' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_adapter.rb:280:in `block in log' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_adapter.rb:275:in `log' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:245:in `execute' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/mysql2_adapter.rb:211:in `execute' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/mysql2_adapter.rb:215:in `exec_query' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/mysql2_adapter.rb:224:in `select' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/database_statements.rb:18:in `select_all' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/query_cache.rb:61:in `block in select_all' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/query_cache.rb:75:in `cache_sql' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/query_cache.rb:61:in `select_all' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/querying.rb:38:in `block in find_by_sql' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/explain.rb:40:in `logging_query_plan' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/querying.rb:37:in `find_by_sql' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:171:in `exec_queries' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:160:in `block in to_a' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/explain.rb:40:in `logging_query_plan' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:159:in `to_a' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:189:in `exec_queries' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:160:in `block in to_a' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/explain.rb:40:in `logging_query_plan' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:159:in `to_a' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation/finder_methods.rb:159:in `all' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation/batches.rb:66:in `find_in_batches' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation/batches.rb:20:in `find_each' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/querying.rb:8:in `find_each' <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/api/…>:275:in `find' <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/api/…>:69:in `search' <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/api/…>:11:in `project_id' /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/callbacks.rb:18:in `block in process_action' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:491:in `_run__1528190421440429965__process_action__1977918188756986957__callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.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/notifications.rb:123:in `block in instrument' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifications.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/rendering.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 `block in call' /usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `each' /usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `call' /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/middleware/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/middleware/head.rb:14:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/params_parser.rb:21:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/flash.rb:242:in `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:205:in `context' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:200:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/cookies.rb:339:in `call' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/query_cache.rb:64:in `call' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/connection_pool.rb:473:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `_run__2295012559858493937__call__3677474548551255765__callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_call_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:27:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/remote_ip.rb:31:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/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/middleware/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/middleware/static.rb:62:in `call' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/engine.rb:479:in `call' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/application.rb:223:in `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:17: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'</summary> <details></details> </status> [[0;37mDEBUG[0m|#30079] ERROR: unknown; Uncaught exception: Mysql2::Error: Operand should contain 1 column(s): SELECT distinct(db_projects.id,db_projects.name) FROM `db_projects` LEFT JOIN db_project_types pt ON db_projects.type_id = pt.id WHERE (db_projects.id not in (3000,3006)) AND (1 AND ((NOT db_projects.name LIKE CONCAT('home:','%') OR db_projects.name LIKE CONCAT('home:','%') IS NULL) AND (NOT pt.name = 'maintenance_incident' OR pt.name = 'maintenance_incident' IS NULL))) AND (`db_projects`.`id` >= 0) ORDER BY `db_projects`.`id` ASC LIMIT 1000 /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:245:in `query' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:245:in `block in execute' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_adapter.rb:280:in `block in log' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_adapter.rb:275:in `log' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:245:in `execute' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/mysql2_adapter.rb:211:in `execute' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/mysql2_adapter.rb:215:in `exec_query' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/mysql2_adapter.rb:224:in `select' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/database_statements.rb:18:in `select_all' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/query_cache.rb:61:in `block in select_all' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/query_cache.rb:75:in `cache_sql' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/query_cache.rb:61:in `select_all' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/querying.rb:38:in `block in find_by_sql' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/explain.rb:40:in `logging_query_plan' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/querying.rb:37:in `find_by_sql' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:171:in `exec_queries' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:160:in `block in to_a' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/explain.rb:40:in `logging_query_plan' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:159:in `to_a' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:189:in `exec_queries' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:160:in `block in to_a' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/explain.rb:40:in `logging_query_plan' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation.rb:159:in `to_a' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation/finder_methods.rb:159:in `all' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation/batches.rb:66:in `find_in_batches' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/relation/batches.rb:20:in `find_each' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/querying.rb:8:in `find_each' <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/api/…>:275:in `find' <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/api/…>:69:in `search' <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/api/…>:11:in `project_id' /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/callbacks.rb:18:in `block in process_action' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:491:in `_run__1528190421440429965__process_action__1977918188756986957__callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.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/notifications.rb:123:in `block in instrument' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifications.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/rendering.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 `block in call' /usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `each' /usr/lib64/ruby/gems/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `call' /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/middleware/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/middleware/head.rb:14:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/params_parser.rb:21:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/flash.rb:242:in `call' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:205:in `context' /usr/lib64/ruby/gems/1.9.1/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:200:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/cookies.rb:339:in `call' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/query_cache.rb:64:in `call' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/connection_pool.rb:473:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `_run__2295012559858493937__call__3677474548551255765__callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_call_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:27:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/remote_ip.rb:31:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_dispatch/middleware/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/middleware/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/middleware/static.rb:62:in `call' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/engine.rb:479:in `call' /usr/lib64/ruby/gems/1.9.1/gems/railties-3.2.8/lib/rails/application.rb:223:in `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:17: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' [[32mINFO [0m|#30079] Rendered error.html.erb within layouts/application (0.9ms) [[32mINFO [0m|#30079] Rendered layouts/_watch_and_search.html.erb (0.8ms) [[32mINFO [0m|#30079] Rendered vendor/neutral/layouts/_header.html.erb (1.9ms) [[32mINFO [0m|#30079] Rendered layouts/_personal_navigation.html.erb (1.7ms) [[32mINFO [0m|#30079] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#30079] Rendered vendor/neutral/layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#30079] Completed 400 Bad Request in 83ms (Views: 9.2ms | ActiveRecord: 0.0ms | API: 65.2ms (XML: 0.1ms , View: 3.9ms , Backend: 0.0ms , DB: 1.9ms , HTTP: 43.0ms) | XML: 0.1ms) + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/> + egrep 'perl|ruby' USER PID ACCESS COMMAND <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/>: jenkins ..c.. sh jenkins ..c.. sh jenkins ..c.. egrep + echo 'Remove log/tmp files to save disc space' Remove log/tmp files to save disc space + rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets + exit 1 Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

[obs-tests] Build failed in Jenkins: obs_master_testsuite_api #1058
by admin＠opensuse.org 28 Sep '12

28 Sep '12

See <http://ci.opensuse.org/job/obs_master_testsuite_api/1058/changes> Changes: [Stephan Kulow] [api,webui] update gems - xmlhash fix is important enough [Stephan Kulow] [api] Fix display of project flags in the webui [Stephan Kulow] [webui] Fix some quotation problems with build status/logs [Stephan Kulow] [api] add 3 reviews to request 1000, so we have reviewers in the database [Stephan Kulow] [api] fix ruby syntax check to ignore all ruby internals [Stephan Kulow] [dist] trying to fix testsuite_all for webui-testsuite ------------------------------------------ [...truncated 129757 lines...] [[0;37mDEBUG[0m|# 3088] Validate XML response: #<ActionDispatch::Response:0x000000063c9e38> took 1ms [[32mINFO [0m|# 3088] Completed 200 OK in 68ms (Views: 1.0ms | ActiveRecord: 6.2ms | Backend: 12.8ms | XML: 0.3ms) [[32mINFO [0m|# 3088] Started GET "/source/kde4/_meta" for 127.0.0.1 at 2012-09-28 15:36:32 +0200 [[32mINFO [0m|# 3088] Processing by SourceController#project_meta as XML [[32mINFO [0m|# 3088] Parameters: {"project"=>"kde4"} [[0;37mDEBUG[0m|# 3088] Validate XML request: #<ActionDispatch::Request:0x00000006d463f8> [[0;37mDEBUG[0m|# 3088] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"project_meta", "project"=>"kde4", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|# 3088] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|# 3088] [1m[36mUser Load (0.3ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] USER found: king [[0;37mDEBUG[0m|# 3088] User king initialised [[0;37mDEBUG[0m|# 3088] [1m[35mProjectUserRoleRelationship Load (0.3ms)[0m SELECT ur.db_project_id, ur.bs_user_id from flags f, project_user_role_relationships ur where f.flag = 'access' and ur.db_project_id = f.db_project_id [[0;37mDEBUG[0m|# 3088] [1m[36mRole Load (0.3ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 3088] Validate XML response: #<ActionDispatch::Response:0x00000006d46380> took 2ms [[32mINFO [0m|# 3088] Completed 200 OK in 11ms (Views: 0.6ms | ActiveRecord: 1.1ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 3088] Started GET "/source/kde4" for 127.0.0.1 at 2012-09-28 15:36:32 +0200 [[32mINFO [0m|# 3088] Processing by SourceController#index_project as XML [[32mINFO [0m|# 3088] Parameters: {"project"=>"kde4"} [[0;37mDEBUG[0m|# 3088] Validate XML request: #<ActionDispatch::Request:0x00000006b44f00> [[0;37mDEBUG[0m|# 3088] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|# 3088] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|# 3088] [1m[36mUser Load (0.3ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] USER found: king [[0;37mDEBUG[0m|# 3088] User king initialised [[0;37mDEBUG[0m|# 3088] [1m[35mRole Load (0.3ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mDbPackage Load (0.2ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND (db_packages.db_project_id not in (0)) [[0;37mDEBUG[0m|# 3088] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mCACHE (0.0ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 3088] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"response"} [[0;37mDEBUG[0m|# 3088] Validate XML response: #<ActionDispatch::Response:0x00000006b44ed8> took 0ms [[32mINFO [0m|# 3088] Completed 200 OK in 11ms (Views: 0.6ms | ActiveRecord: 1.3ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 3088] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-09-28 15:36:32 +0200 [[32mINFO [0m|# 3088] Processing by SourceController#package_meta as XML [[32mINFO [0m|# 3088] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|# 3088] Validate XML request: #<ActionDispatch::Request:0x00000006ef2b70> [[0;37mDEBUG[0m|# 3088] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|# 3088] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|# 3088] [1m[36mUser Load (0.3ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] USER found: king [[0;37mDEBUG[0m|# 3088] User king initialised [[0;37mDEBUG[0m|# 3088] get_by_project_and_name {} [[0;37mDEBUG[0m|# 3088] [1m[35mRole Load (0.3ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mDbPackage Load (0.2ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b646562617365' AND (db_packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] Validate XML response: #<ActionDispatch::Response:0x00000006ef2b20> took 2ms [[32mINFO [0m|# 3088] Completed 200 OK in 12ms (Views: 0.4ms | ActiveRecord: 1.1ms | Backend: 0.0ms | XML: 0.3ms) [[32mINFO [0m|# 3088] Started PUT "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-09-28 15:36:32 +0200 [[32mINFO [0m|# 3088] Processing by SourceController#package_meta as XML [[32mINFO [0m|# 3088] Parameters: {"<package name"=>"\"kdebase\" project=\"kde4\">\n <title>blub</title>\n <description>blub</description>\n</package>\n", "project"=>"kde4", "package"=>"kdebase"} [[33mWARN [0m|# 3088] WARNING: Can't verify CSRF token authenticity [[0;37mDEBUG[0m|# 3088] Validate XML request: #<ActionDispatch::Request:0x00000006da4a70> [[0;37mDEBUG[0m|# 3088] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|# 3088] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|# 3088] USER found: king [[0;37mDEBUG[0m|# 3088] User king initialised [[0;37mDEBUG[0m|# 3088] [1m[36mRole Load (0.2ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mDbPackage Load (0.2ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b646562617365' AND (db_packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mDbProject Load (0.1ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 3088] get_by_project_and_name {} [[0;37mDEBUG[0m|# 3088] [1m[36mCACHE (0.0ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mCACHE (0.0ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b646562617365' AND (db_packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mCACHE (0.0ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position [[0;37mDEBUG[0m|# 3088] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|# 3088] [1m[36mFlag Load (0.1ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2[0m [[0;37mDEBUG[0m|# 3088] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|# 3088] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 3088] [1m[36mDbPackage Load (0.2ms)[0m [1m SELECT pack.* FROM db_packages pack LEFT OUTER JOIN db_projects pro ON pack.db_project_id = pro.id WHERE pro.name = 'kde4' AND pack.name = 'kdebase' [0m [[0;37mDEBUG[0m|# 3088] [1m[35mCACHE (0.0ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mPackageUserRoleRelationship Load (0.1ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|# 3088] [1m[35mPackageGroupRoleRelationship Load (0.1ms)[0m SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 2 [[0;37mDEBUG[0m|# 3088] [1m[36mCACHE (0.0ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position[0m [[0;37mDEBUG[0m|# 3088] [1m[35mDbPackage Load (0.2ms)[0m SELECT db_packages.*, ( @activity:=( db_packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), db_packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp, IF( @activity<0, 0, @activity ) AS activity_value FROM `db_packages` WHERE id = 2 LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36m (0.2ms)[0m [1mUPDATE `db_packages` SET `activity_index` = 85.3110061353901, `updated_at` = '2012-09-28 13:36:32', `update_counter` = 1 WHERE `db_packages`.`id` = 2[0m [[0;37mDEBUG[0m|# 3088] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|# 3088] [1m[35mPackageUserRoleRelationship Load (0.2ms)[0m SELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 2 [[0;37mDEBUG[0m|# 3088] [1m[36mPackageGroupRoleRelationship Load (0.2ms)[0m [1mSELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|# 3088] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|# 3088] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|# 3093] 2012-09-28 15:36:32 [8200]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|# 3088] request took 0.011880738 [[0;37mDEBUG[0m|# 3088] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 3088] Validate XML response: #<ActionDispatch::Response:0x00000006da4a20> took 1ms [[32mINFO [0m|# 3088] Completed 200 OK in 44ms (Views: 1.2ms | ActiveRecord: 3.0ms | Backend: 11.9ms | XML: 0.0ms) [[32mINFO [0m|# 3088] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-09-28 15:36:32 +0200 [[32mINFO [0m|# 3088] Processing by SourceController#package_meta as XML [[32mINFO [0m|# 3088] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|# 3088] Validate XML request: #<ActionDispatch::Request:0x000000071a97b0> [[0;37mDEBUG[0m|# 3088] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|# 3088] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|# 3088] [1m[36mUser Load (0.3ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] USER found: king [[0;37mDEBUG[0m|# 3088] User king initialised [[0;37mDEBUG[0m|# 3088] get_by_project_and_name {} [[0;37mDEBUG[0m|# 3088] [1m[35mRole Load (0.5ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mDbPackage Load (0.2ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b646562617365' AND (db_packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mDbProject Load (0.1ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] Validate XML response: #<ActionDispatch::Response:0x000000071a9788> took 2ms [[32mINFO [0m|# 3088] Completed 200 OK in 137ms (Views: 0.6ms | ActiveRecord: 1.4ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 3088] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-09-28 15:36:33 +0200 [[32mINFO [0m|# 3088] Processing by SourceController#package_meta as XML [[32mINFO [0m|# 3088] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|# 3088] Validate XML request: #<ActionDispatch::Request:0x00000000f6e348> [[0;37mDEBUG[0m|# 3088] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|# 3088] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|# 3088] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|# 3088] USER found: king [[0;37mDEBUG[0m|# 3088] User king initialised [[0;37mDEBUG[0m|# 3088] get_by_project_and_name {} [[0;37mDEBUG[0m|# 3088] [1m[36mRole Load (0.2ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mDbPackage Load (0.2ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b64656c696273' AND (db_packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 3088] Validate XML response: #<ActionDispatch::Response:0x00000000f6dab0> took 2ms [[32mINFO [0m|# 3088] Completed 200 OK in 11ms (Views: 0.4ms | ActiveRecord: 0.9ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|# 3088] Started PUT "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-09-28 15:36:33 +0200 [[32mINFO [0m|# 3088] Processing by SourceController#package_meta as XML [[32mINFO [0m|# 3088] Parameters: {"<package name"=>"\"kdelibs\" project=\"kde4\">\n <title>blub</title>\n <description>Changed description</description>\n <devel project=\"home:Iggy\" package=\"TestPack\"/>\n <person userid=\"fredlibs\" role=\"maintainer\"/>\n <person userid=\"adrian\" role=\"reviewer\"/>\n <person userid=\"Iggy\" role=\"maintainer\"/>\n <person userid=\"Iggy\" role=\"bugowner\"/>\n <group groupid=\"test_group\" role=\"maintainer\"/>\n <group groupid=\"test_group\" role=\"reader\"/>\n <build>\n <enable/>\n </build>\n</package>\n", "project"=>"kde4", "package"=>"kdelibs"} [[33mWARN [0m|# 3088] WARNING: Can't verify CSRF token authenticity [[0;37mDEBUG[0m|# 3088] Validate XML request: #<ActionDispatch::Request:0x00000005120390> [[0;37mDEBUG[0m|# 3088] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|# 3088] [1m[36mUser Load (0.2ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] USER found: king [[0;37mDEBUG[0m|# 3088] User king initialised [[0;37mDEBUG[0m|# 3088] [1m[35mRole Load (0.2ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mDbPackage Load (0.2ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b64656c696273' AND (db_packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] get_by_project_and_name {} [[0;37mDEBUG[0m|# 3088] [1m[35mCACHE (0.0ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mCACHE (0.0ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b64656c696273' AND (db_packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mCACHE (0.0ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position[0m [[0;37mDEBUG[0m|# 3088] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|# 3088] [1m[35mFlag Load (0.1ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 [[0;37mDEBUG[0m|# 3088] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|# 3088] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mDbPackage Load (0.2ms)[0m SELECT pack.* FROM db_packages pack LEFT OUTER JOIN db_projects pro ON pack.db_project_id = pro.id WHERE pro.name = 'kde4' AND pack.name = 'kdelibs' [[0;37mDEBUG[0m|# 3088] [1m[36mCACHE (0.0ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'686f6d653a49676779' AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mDbPackage Load (0.2ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 502 AND `db_packages`.`name` = x'546573745061636b' AND (db_packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mDbProject Load (0.1ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 502 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mPackageUserRoleRelationship Load (0.2ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mUser Load (0.1ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`id` = 4 LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mRole Load (0.1ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`id` = 42 LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mRole Load (0.1ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 6 LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mUser Load (0.1ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mPackageUserRoleRelationship Load (0.2ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 3 AND bs_user_id = 267) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mSQL (0.2ms)[0m INSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 3) [[0;37mDEBUG[0m|# 3088] [1m[36mUser Load (0.1ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mPackageUserRoleRelationship Load (0.1ms)[0m SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 4 AND bs_user_id = 267) LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mSQL (0.1ms)[0m [1mINSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 4)[0m [[0;37mDEBUG[0m|# 3088] [1m[35mPackageGroupRoleRelationship Load (0.2ms)[0m SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|# 3088] [1m[36mGroup Load (0.1ms)[0m [1mSELECT `groups`.* FROM `groups` WHERE `groups`.`id` = 99 LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mRole Load (0.1ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mGroup Load (0.2ms)[0m [1mSELECT `groups`.* FROM `groups` WHERE `groups`.`title` = 'test_group' LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mPackageGroupRoleRelationship Load (0.1ms)[0m SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE (db_package_id = 1 AND role_id = 7 AND bs_group_id = 99) LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mSQL (0.1ms)[0m [1mINSERT INTO `package_group_role_relationships` (`bs_group_id`, `db_package_id`, `role_id`) VALUES (99, 1, 7)[0m [[0;37mDEBUG[0m|# 3088] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position [[0;37mDEBUG[0m|# 3088] [1m[36mDbPackage Load (0.2ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`id` = 1 AND (db_packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35m (0.2ms)[0m SELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1 [[0;37mDEBUG[0m|# 3088] [1m[36mSQL (0.2ms)[0m [1mINSERT INTO `flags` (`architecture_id`, `db_package_id`, `db_project_id`, `flag`, `position`, `repo`, `status`) VALUES (NULL, 1, NULL, 'build', 1, NULL, 'enable')[0m [[0;37mDEBUG[0m|# 3088] [1m[35mDbPackage Load (0.2ms)[0m SELECT db_packages.*, ( @activity:=( db_packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), db_packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp, IF( @activity<0, 0, @activity ) AS activity_value FROM `db_packages` WHERE id = 1 LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36m (0.2ms)[0m [1mUPDATE `db_packages` SET `description` = 'Changed description', `develpackage_id` = 10095, `activity_index` = 85.3110061353901, `updated_at` = '2012-09-28 13:36:33', `update_counter` = 1 WHERE `db_packages`.`id` = 1[0m [[0;37mDEBUG[0m|# 3088] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|# 3088] [1m[35mPackageUserRoleRelationship Load (0.2ms)[0m SELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|# 3088] [1m[36mPackageGroupRoleRelationship Load (0.2ms)[0m [1mSELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|# 3088] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|# 3088] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|# 3093] 2012-09-28 15:36:33 [8201]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|# 3088] request took 0.017585354 [[0;37mDEBUG[0m|# 3088] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|# 3088] Validate XML response: #<ActionDispatch::Response:0x00000005120318> took 1ms [[32mINFO [0m|# 3088] Completed 200 OK in 80ms (Views: 1.2ms | ActiveRecord: 6.4ms | Backend: 17.6ms | XML: 0.3ms) [[32mINFO [0m|# 3088] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-09-28 15:36:33 +0200 [[32mINFO [0m|# 3088] Processing by SourceController#package_meta as XML [[32mINFO [0m|# 3088] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|# 3088] Validate XML request: #<ActionDispatch::Request:0x00000006747150> [[0;37mDEBUG[0m|# 3088] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|# 3088] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|# 3088] [1m[36mUser Load (0.4ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] USER found: king [[0;37mDEBUG[0m|# 3088] User king initialised [[0;37mDEBUG[0m|# 3088] get_by_project_and_name {} [[0;37mDEBUG[0m|# 3088] [1m[35mRole Load (0.4ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mDbProject Load (0.3ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] [1m[35mDbPackage Load (0.2ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b64656c696273' AND (db_packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|# 3088] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|# 3088] Validate XML response: #<ActionDispatch::Response:0x000000066de600> took 2ms [[32mINFO [0m|# 3088] Completed 200 OK in 14ms (Views: 0.7ms | ActiveRecord: 1.4ms | Backend: 0.0ms | XML: 0.0ms) [[0;37mDEBUG[0m|# 3088] [1m[35m (6.6ms)[0m ROLLBACK [[0;37mDEBUG[0m|# 3088] kill 3093 + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/> + egrep 'perl|ruby' USER PID ACCESS COMMAND <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/>: jenkins ..c.. sh jenkins ..c.. sh jenkins ..c.. sh + echo 'Remove log/tmp files to save disc space' Remove log/tmp files to save disc space + rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets 'src/webui/log/*' src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets + exit 1 Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

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

28 Sep '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui/460/changes> Changes: [Stephan Kulow] [api,webui] update gems - xmlhash fix is important enough ------------------------------------------ Started by an SCM change Building in workspace <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/> Checkout:workspace / <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/> - hudson.remoting.LocalChannel@5ce65ce6 Using strategy: Default Last Built Revision: Revision 748cca1fe7ac6efa5bf5ea0fe1574262579a7b6f (origin/master) Checkout:workspace / <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/> - hudson.remoting.LocalChannel@5ce65ce6 Fetching changes from 1 remote Git repository Fetching upstream changes from git://github.com/openSUSE/open-build-service.git Commencing build of Revision fa9ed35cb39f7c09cc059f324ac6507f619bdb39 (origin/master) Checking out Revision fa9ed35cb39f7c09cc059f324ac6507f619bdb39 (origin/master) [locks-and-latches] Checking to see if we really have the locks [locks-and-latches] Have all the locks, build can start [workspace] $ /bin/sh -xe /tmp/hudson3720847077794130258.sh + sh dist/ci/obs_testsuite_webui.sh ++ dirname dist/ci/obs_testsuite_webui.sh + . dist/ci/obs_testsuite_common.sh + setup_git + echo 'Checking status' Checking status + git status # Not currently on any branch. # Untracked files: # (use "git add <file>..." to include in what will be committed) # # src/api/Gemfile.lock.orig # src/webui/Gemfile.lock.orig # src/webui/db/structure.sql # src/webui/public/themes/ # src/webui/results/ # src/webui/webrat.log nothing added to commit but untracked files present (use "git add" to track) + echo 'Setup git submodules' Setup git submodules + git submodule init + git submodule update + echo 'Setup backend configuration template' Setup backend configuration template + sed -i -e 's|my $hostname = .*$|my $hostname = '\''localhost'\'';|' -e 's|our $bsuser = '\''obsrun'\'';|our $bsuser = '\''jenkins'\'';|' -e 's|our $bsgroup = '\''obsrun'\'';|our $bsgroup = '\''jenkins'\'';|' src/backend/BSConfig.pm.template + cp src/backend/BSConfig.pm.template src/backend/BSConfig.pm + echo 'Set environment variables' Set environment variables + export RAILS_ENV=test + RAILS_ENV=test + ret=0 + setup_api + echo 'Enter API rails root' Enter API rails root + cd src/api + echo 'Setup database configuration' Setup database configuration + cp config/database.yml.example config/database.yml + sed -i 's|database: api|database: ci_api|' config/database.yml + echo 'Setup additional configuration' Setup additional configuration + cp config/options.yml.example config/options.yml + echo 'Install missing gems locally' Install missing gems locally + mv Gemfile.lock Gemfile.lock.orig + bundle list Gems included by the bundle: * actionmailer (3.2.8) * actionpack (3.2.8) * activemodel (3.2.8) * activerecord (3.2.8) * activeresource (3.2.8) * activesupport (3.2.8) * arel (3.0.2) * builder (3.0.0) * bundler (1.2.1) * ci_reporter (1.7.2) * daemons (1.1.9) * delayed_job (3.0.3) * delayed_job_active_record (0.3.2) * erubis (2.7.0) * exception_notification (2.6.1) * fast_xs (0.8.0) * hike (1.2.1) * i18n (0.6.1) * journey (1.0.4) * json (1.7.5) * mail (2.4.4) * memcache-client (1.8.5) * mime-types (1.19) * minitest (3.5.0) * multi_json (1.3.6) * mysql2 (0.3.11) * nokogiri (1.5.5) * pkg-config (1.1.4) * polyglot (0.3.3) * rack (1.4.1) * rack-cache (1.2) * rack-ssl (1.3.2) * rack-test (0.6.1) * rails (3.2.8) * railties (3.2.8) * rake (0.9.2.2) * rdoc (3.12) * simplecov (0.6.4) * simplecov-html (0.5.3) * simplecov-rcov (0.2.3) * sprockets (2.1.3) * thor (0.16.0) * tilt (1.3.3) * treetop (1.4.10) * tzinfo (0.3.33) * xmlhash (1.2.2) * yajl-ruby (1.1.0) + diff -u Gemfile.lock.orig Gemfile.lock --- Gemfile.lock.orig 2012-09-27 17:40:53.000000000 +0200 +++ Gemfile.lock 2012-09-27 17:40:55.000000000 +0200 @@ -99,7 +99,7 @@ polyglot polyglot (>= 0.3.1) tzinfo (0.3.33) - xmlhash (1.2.4) + xmlhash (1.2.2) pkg-config yajl-ruby (1.1.0) + : + chmod a+x script/start_test_backend + echo 'Initialize test database, run migrations, load seed data' Initialize test database, run migrations, load seed data + rake --trace db:drop db:create db:setup db:migrate ** Invoke db:drop (first_time) ** Invoke db:load_config (first_time) ** Execute db:load_config ** Invoke rails_env (first_time) ** Execute rails_env ** Execute db:drop ** Invoke db:create (first_time) ** Invoke db:load_config ** Invoke rails_env ** Execute db:create ** Invoke db:setup (first_time) ** Execute db:setup ** Invoke db:structure:load (first_time) ** Invoke environment (first_time) ** Execute environment ** Invoke db:load_config ** Execute db:structure:load ** Invoke db:seed (first_time) ** Execute db:seed ** Invoke db:abort_if_pending_migrations (first_time) ** Invoke environment ** Invoke db:load_config ** Execute db:abort_if_pending_migrations ** Invoke db:migrate (first_time) ** Invoke environment ** Invoke db:load_config ** Execute db:migrate ** Invoke db:_dump (first_time) ** Execute db:_dump ** Invoke db:structure:dump (first_time) ** Invoke environment ** Invoke db:load_config ** Execute db:structure:dump Seeding architectures table... Seeding roles table... Seeding users table... Seeding roles_users table... Seeding static_permissions table... Seeding static permissions for admin role in roles_static_permissions table... Seeding static permissions for maintainer role in roles_static_permissions table... Seeding static permissions for reader role in roles_static_permissions table... Seeding static permissions for downloader role in roles_static_permissions table... Seeding attrib_namespaces table... Seeding attrib_types table... Seeding db_project_type table by loading test fixtures Seeding issue trackers ... + cd ../.. + setup_webui + echo 'Enter Webui rails root' Enter Webui rails root + cd src/webui + echo 'Setup database configuration' Setup database configuration + cp config/database.yml.example config/database.yml + echo 'Setup additional configuration' Setup additional configuration + cp config/options.yml.example config/options.yml + echo 'Install missing gems locally' Install missing gems locally + mv Gemfile.lock Gemfile.lock.orig + bundle list [31mCould not find gem 'xmlhash (>= 1.2.4) ruby' in the gems available on this machine.[0m Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

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

28 Sep '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/320/changes> Changes: [Stephan Kulow] [api,webui] update gems - xmlhash fix is important enough ------------------------------------------ Started by an SCM change Building in workspace <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/> Checkout:workspace / <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/> - hudson.remoting.LocalChannel@5ce65ce6 Using strategy: Default Last Built Revision: Revision 748cca1fe7ac6efa5bf5ea0fe1574262579a7b6f (origin/master) Checkout:workspace / <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/> - hudson.remoting.LocalChannel@5ce65ce6 Fetching changes from 1 remote Git repository Fetching upstream changes from git://github.com/openSUSE/open-build-service.git Commencing build of Revision fa9ed35cb39f7c09cc059f324ac6507f619bdb39 (origin/master) Checking out Revision fa9ed35cb39f7c09cc059f324ac6507f619bdb39 (origin/master) Cleaning workspace [locks-and-latches] Checking to see if we really have the locks [locks-and-latches] Have all the locks, build can start [workspace] $ /bin/sh -xe /tmp/hudson2894300139650866749.sh + sh dist/ci/obs_testsuite_webui-testsuite.sh ++ dirname dist/ci/obs_testsuite_webui-testsuite.sh + . dist/ci/obs_testsuite_common.sh + setup_git + echo 'Checking status' Checking status + git status # Not currently on any branch. # Untracked files: # (use "git add <file>..." to include in what will be committed) # # src/webui/public/themes/ nothing added to commit but untracked files present (use "git add" to track) + echo 'Setup git submodules' Setup git submodules + git submodule init + git submodule update + echo 'Setup backend configuration template' Setup backend configuration template + sed -i -e 's|my $hostname = .*$|my $hostname = '\''localhost'\'';|' -e 's|our $bsuser = '\''obsrun'\'';|our $bsuser = '\''jenkins'\'';|' -e 's|our $bsgroup = '\''obsrun'\'';|our $bsgroup = '\''jenkins'\'';|' src/backend/BSConfig.pm.template + cp src/backend/BSConfig.pm.template src/backend/BSConfig.pm + echo 'Set environment variables' Set environment variables + export RAILS_ENV=test + RAILS_ENV=test + ret=0 + setup_api + echo 'Enter API rails root' Enter API rails root + cd src/api + echo 'Setup database configuration' Setup database configuration + cp config/database.yml.example config/database.yml + sed -i 's|database: api|database: ci_api|' config/database.yml + echo 'Setup additional configuration' Setup additional configuration + cp config/options.yml.example config/options.yml + echo 'Install missing gems locally' Install missing gems locally + mv Gemfile.lock Gemfile.lock.orig + bundle list Gems included by the bundle: * actionmailer (3.2.8) * actionpack (3.2.8) * activemodel (3.2.8) * activerecord (3.2.8) * activeresource (3.2.8) * activesupport (3.2.8) * arel (3.0.2) * builder (3.0.0) * bundler (1.2.1) * ci_reporter (1.7.2) * daemons (1.1.9) * delayed_job (3.0.3) * delayed_job_active_record (0.3.2) * erubis (2.7.0) * exception_notification (2.6.1) * fast_xs (0.8.0) * hike (1.2.1) * i18n (0.6.1) * journey (1.0.4) * json (1.7.5) * mail (2.4.4) * memcache-client (1.8.5) * mime-types (1.19) * minitest (3.5.0) * multi_json (1.3.6) * mysql2 (0.3.11) * nokogiri (1.5.5) * pkg-config (1.1.4) * polyglot (0.3.3) * rack (1.4.1) * rack-cache (1.2) * rack-ssl (1.3.2) * rack-test (0.6.1) * rails (3.2.8) * railties (3.2.8) * rake (0.9.2.2) * rdoc (3.12) * simplecov (0.6.4) * simplecov-html (0.5.3) * simplecov-rcov (0.2.3) * sprockets (2.1.3) * thor (0.16.0) * tilt (1.3.3) * treetop (1.4.10) * tzinfo (0.3.33) * xmlhash (1.2.2) * yajl-ruby (1.1.0) + diff -u Gemfile.lock.orig Gemfile.lock --- Gemfile.lock.orig 2012-09-27 17:40:39.000000000 +0200 +++ Gemfile.lock 2012-09-27 17:40:41.000000000 +0200 @@ -99,7 +99,7 @@ polyglot polyglot (>= 0.3.1) tzinfo (0.3.33) - xmlhash (1.2.4) + xmlhash (1.2.2) pkg-config yajl-ruby (1.1.0) + : + chmod a+x script/start_test_backend + echo 'Initialize test database, run migrations, load seed data' Initialize test database, run migrations, load seed data + rake --trace db:drop db:create db:setup db:migrate ** Invoke db:drop (first_time) ** Invoke db:load_config (first_time) ** Execute db:load_config ** Invoke rails_env (first_time) ** Execute rails_env ** Execute db:drop ** Invoke db:create (first_time) ** Invoke db:load_config ** Invoke rails_env ** Execute db:create ** Invoke db:setup (first_time) ** Execute db:setup ** Invoke db:structure:load (first_time) ** Invoke environment (first_time) ** Execute environment ** Invoke db:load_config ** Execute db:structure:load ** Invoke db:seed (first_time) ** Execute db:seed ** Invoke db:abort_if_pending_migrations (first_time) ** Invoke environment ** Invoke db:load_config ** Execute db:abort_if_pending_migrations ** Invoke db:migrate (first_time) ** Invoke environment ** Invoke db:load_config ** Execute db:migrate ** Invoke db:_dump (first_time) ** Execute db:_dump ** Invoke db:structure:dump (first_time) ** Invoke environment ** Invoke db:load_config ** Execute db:structure:dump Seeding architectures table... Seeding roles table... Seeding users table... Seeding roles_users table... Seeding static_permissions table... Seeding static permissions for admin role in roles_static_permissions table... Seeding static permissions for maintainer role in roles_static_permissions table... Seeding static permissions for reader role in roles_static_permissions table... Seeding static permissions for downloader role in roles_static_permissions table... Seeding attrib_namespaces table... Seeding attrib_types table... Seeding db_project_type table by loading test fixtures Seeding issue trackers ... + cd ../.. + setup_webui + echo 'Enter Webui rails root' Enter Webui rails root + cd src/webui + echo 'Setup database configuration' Setup database configuration + cp config/database.yml.example config/database.yml + echo 'Setup additional configuration' Setup additional configuration + cp config/options.yml.example config/options.yml + echo 'Install missing gems locally' Install missing gems locally + mv Gemfile.lock Gemfile.lock.orig + bundle list [31mCould not find gem 'xmlhash (>= 1.2.4) ruby' in the gems available on this machine.[0m Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui-testsuite #306
by admin＠opensuse.org 14 Sep '12

14 Sep '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/306/changes> Changes: [Stephan Kulow] [webui] do not take everything from bento, but hand pick some links ------------------------------------------ [...truncated 5769 lines...] [[0;37mDEBUG[0m|# 6019] method_missing -to_ary- nil [[0;37mDEBUG[0m|# 6019] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5}) [[0;37mDEBUG[0m|# 6019] [REST] find( Statusmessage, [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"}] ) [[0;37mDEBUG[0m|# 6019] http_do #1: method: get url: http://localhost:3203/status/messages/?limit=5 [[0;37mDEBUG[0m|# 6019] RT {"view"=>4.0953550000000005, "db"=>3.923969, "backend"=>0, "xml"=>0, :runtime=>132.25, :all=>137.431166} [[0;37mDEBUG[0m|# 6019] method_missing -to_str- nil [[0;37mDEBUG[0m|# 6019] method_missing -to_str- nil [[0;37mDEBUG[0m|# 6019] method_missing -to_ary- nil [[0;37mDEBUG[0m|# 6019] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true}) [[0;37mDEBUG[0m|# 6019] [REST] find( LatestUpdated, [{:limit=>"6", :shared=>"true"}] ) [[0;37mDEBUG[0m|# 6019] http_do #1: method: get url: http://localhost:3203/statistics/latest_updated?limit=6 [[0;37mDEBUG[0m|# 6019] RT {"view"=>5.432512, "db"=>11.452726999999998, "backend"=>0, "xml"=>0, :runtime=>263.329, :all=>302.431801} [[32mINFO [0m|# 6019] Rendered main/_news.html.erb (2.4ms) [[32mINFO [0m|# 6019] Rendered main/_contact.html.erb (0.4ms) [[32mINFO [0m|# 6019] Rendered main/_latest_updates.erb (7.2ms) [[32mINFO [0m|# 6019] Rendered vendor/neutral/main/_countdown.html.erb (0.4ms) [[32mINFO [0m|# 6019] Rendered vendor/neutral/main/_sponsors.html.erb (0.3ms) [[32mINFO [0m|# 6019] Rendered vendor/neutral/main/_feeds.html.erb (0.3ms) [[32mINFO [0m|# 6019] Rendered main/index.html.erb within layouts/application (20.2ms) [[32mINFO [0m|# 6019] Rendered layouts/_watch_and_search.html.erb (0.6ms) [[32mINFO [0m|# 6019] Rendered vendor/neutral/layouts/_header.html.erb (1.3ms) [[32mINFO [0m|# 6019] Rendered layouts/_personal_navigation.html.erb (1.4ms) [[32mINFO [0m|# 6019] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 6019] Rendered vendor/neutral/layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 6019] Completed 200 OK in 480ms (Views: 28.0ms | ActiveRecord: 0.0ms | API: 439.9ms (XML: 0.0ms , View: 9.5ms , Backend: 0.0ms , DB: 15.4ms , HTTP: 44.3ms) | XML: 1.8ms) [[32mINFO [0m|# 6019] Started GET "/assets/application.css" for 127.0.0.1 at 2012-09-14 08:42:57 +0200 [[31mERROR[0m|# 6019] Error compiling asset application.css: [[31mERROR[0m|# 6019] Sass::SyntaxError: File to import not found or unreadable: bento/reset. Load paths: Sass::Rails::Importer(<http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…)> <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…> /usr/lib64/ruby/gems/1.9.1/gems/compass-0.12.2/frameworks/blueprint/stylesheets /usr/lib64/ruby/gems/1.9.1/gems/compass-0.12.2/frameworks/compass/stylesheets Compass::SpriteImporter (in <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…)> [[32mINFO [0m|# 6019] Served asset /application.css - 500 Internal Server Error [[32mINFO [0m|# 6019] Started GET "/assets/application.js" for 127.0.0.1 at 2012-09-14 08:42:59 +0200 [[32mINFO [0m|# 6019] Compiled jquery.js (4ms) (pid 6019) [[32mINFO [0m|# 6019] Compiled jquery_ujs.js (0ms) (pid 6019) [[32mINFO [0m|# 6019] Compiled jquery.dataTables.js (3ms) (pid 6019) [[32mINFO [0m|# 6019] Compiled jquery.expander.js (0ms) (pid 6019) [[32mINFO [0m|# 6019] Compiled jquery.flot.js (1ms) (pid 6019) [[32mINFO [0m|# 6019] Compiled jquery.flot.stack.js (0ms) (pid 6019) [[32mINFO [0m|# 6019] Compiled jquery.tooltip.js (0ms) (pid 6019) [[32mINFO [0m|# 6019] Compiled bento/script.js (0ms) (pid 6019) [[32mINFO [0m|# 6019] Compiled application.js (142ms) (pid 6019) [[32mINFO [0m|# 6019] Served asset /application.js - 200 OK (226ms) [[32mINFO [0m|# 6019] Started GET "/main/systemstatus" for 127.0.0.1 at 2012-09-14 08:42:59 +0200 [[32mINFO [0m|# 6019] Processing by MainController#systemstatus as */* [[0;37mDEBUG[0m|# 6019] Setting return_to: "/main/systemstatus" [[0;37mDEBUG[0m|# 6019] Authenticating with iChain mode: off [[32mINFO [0m|# 6019] Anonymous request to /main/systemstatus [[0;37mDEBUG[0m|# 6019] Workerstatus.find( nil, all) [[0;37mDEBUG[0m|# 6019] [REST] find( Workerstatus, [:all] ) [[0;37mDEBUG[0m|# 6019] http_do #1: method: get url: http://localhost:3203/status/workerstatus [[0;37mDEBUG[0m|# 6019] RT {"view"=>0.6557499999999999, "db"=>628.3367209999999, "backend"=>0, "xml"=>0.397617, :runtime=>720.376, :all=>725.447549} [[0;37mDEBUG[0m|# 6019] method_missing -to_str- nil [[0;37mDEBUG[0m|# 6019] method_missing -to_str- nil [[0;37mDEBUG[0m|# 6019] method_missing -to_ary- nil [[0;37mDEBUG[0m|# 6019] GlobalCounters.find( 900 seconds, {:shared=>true}) [[0;37mDEBUG[0m|# 6019] [REST] find( GlobalCounters, [{:shared=>"true"}] ) [[0;37mDEBUG[0m|# 6019] http_do #1: method: get url: http://localhost:3203/statistics/global_counters [[0;37mDEBUG[0m|# 6019] RT {"view"=>1.9536279999999997, "db"=>2.9779860000000005, "backend"=>0, "xml"=>0, :runtime=>16.579, :all=>57.425043} [[0;37mDEBUG[0m|# 6019] Architecture.find( 1800 seconds, available) [[0;37mDEBUG[0m|# 6019] [REST] find( Architecture, [:available] ) [[0;37mDEBUG[0m|# 6019] http_do #1: method: get url: http://localhost:3203/architectures?available=1 [[0;37mDEBUG[0m|# 6019] RT {"view"=>0.198493, "db"=>12.461603000000002, "backend"=>0, "xml"=>0, :runtime=>71.889, :all=>116.079876} [[32mINFO [0m|# 6019] Rendered main/_systemstatus.html.erb (9.0ms) [[32mINFO [0m|# 6019] Completed 200 OK in 919ms (Views: 15.3ms | ActiveRecord: 0.0ms | API: 899.0ms (XML: 0.4ms , View: 2.8ms , Backend: 0.0ms , DB: 643.8ms , HTTP: 90.1ms) | XML: 1.1ms) [[32mINFO [0m|# 6019] Started GET "/assets/obs-logo_small.png" for 127.0.0.1 at 2012-09-14 08:43:00 +0200 [[32mINFO [0m|# 6019] Served asset /obs-logo_small.png - 200 OK (7ms) [[32mINFO [0m|# 6019] Started GET "/assets/home_grey.png" for 127.0.0.1 at 2012-09-14 08:43:00 +0200 [[32mINFO [0m|# 6019] Served asset /home_grey.png - 200 OK (6ms) [[32mINFO [0m|# 6019] Started GET "/assets/feeds.png" for 127.0.0.1 at 2012-09-14 08:43:00 +0200 [[32mINFO [0m|# 6019] Served asset /feeds.png - 200 OK (5ms) [[32mINFO [0m|# 6019] Started GET "/search" for 127.0.0.1 at 2012-09-14 09:23:03 +0200 [[32mINFO [0m|# 6019] Processing by SearchController#index as HTML [[0;37mDEBUG[0m|# 6019] Setting return_to: "/search" [[0;37mDEBUG[0m|# 6019] Authenticating with iChain mode: off [[32mINFO [0m|# 6019] Anonymous request to /search [[0;37mDEBUG[0m|# 6019] --> direct_http url: #<URI::Generic:0x0000000664f310 URL:http://localhost:3203/configuration.json> [[0;37mDEBUG[0m|# 6019] http_do #1: method: GET url: http://localhost:3203/configuration.json [[31mERROR[0m|# 6019] --> caught EOFError: end of file reached, retrying with new HTTP connection [[0;37mDEBUG[0m|# 6019] http_do #2: method: GET url: http://localhost:3203/configuration.json [[0;37mDEBUG[0m|# 6019] RT {"view"=>0.327314, "db"=>0.351401, "backend"=>0, "xml"=>0, :runtime=>6.58, :all=>11.599846000000001} [[0;37mDEBUG[0m|# 6019] Attribute.find( 1800 seconds, namespaces) [[0;37mDEBUG[0m|# 6019] [REST] find( Attribute, [:namespaces] ) [[0;37mDEBUG[0m|# 6019] http_do #1: method: get url: http://localhost:3203/attribute [[0;37mDEBUG[0m|# 6019] RT {"view"=>1.0566309999999999, "db"=>1.700282, "backend"=>0, "xml"=>0, :runtime=>13.693999999999999, :all=>52.539032999999996} [[0;37mDEBUG[0m|# 6019] method_missing -to_str- nil [[0;37mDEBUG[0m|# 6019] method_missing -to_ary- nil [[0;37mDEBUG[0m|# 6019] Attribute.find( 1800 seconds, attributes, {:namespace=>"NSTEST"}) [[0;37mDEBUG[0m|# 6019] [REST] find( Attribute, [:attributes, {:namespace=>"NSTEST"}] ) [[0;37mDEBUG[0m|# 6019] http_do #1: method: get url: http://localhost:3203/attribute/NSTEST [[0;37mDEBUG[0m|# 6019] RT {"view"=>0.83301, "db"=>2.272777, "backend"=>0, "xml"=>0, :runtime=>82.054, :all=>122.788079} [[0;37mDEBUG[0m|# 6019] method_missing -to_str- nil [[0;37mDEBUG[0m|# 6019] method_missing -to_ary- nil [[0;37mDEBUG[0m|# 6019] Attribute.find( 1800 seconds, attributes, {:namespace=>"OBS"}) [[0;37mDEBUG[0m|# 6019] [REST] find( Attribute, [:attributes, {:namespace=>"OBS"}] ) [[0;37mDEBUG[0m|# 6019] http_do #1: method: get url: http://localhost:3203/attribute/OBS [[0;37mDEBUG[0m|# 6019] RT {"view"=>0.39175899999999997, "db"=>0.961452, "backend"=>0, "xml"=>0, :runtime=>9.044, :all=>50.990812000000005} [[0;37mDEBUG[0m|# 6019] IssueTracker.find( 1800 seconds, all) [[0;37mDEBUG[0m|# 6019] [REST] find( IssueTracker, [:all] ) [[0;37mDEBUG[0m|# 6019] http_do #1: method: get url: http://localhost:3203/issue_trackers [[0;37mDEBUG[0m|# 6019] RT {"view"=>0.166095, "db"=>1.9290580000000002, "backend"=>0, "xml"=>0, :runtime=>46.15, :all=>86.64761} [[32mINFO [0m|# 6019] Rendered search/index.html.erb within layouts/application (3.6ms) [[32mINFO [0m|# 6019] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|# 6019] Rendered vendor/neutral/layouts/_header.html.erb (1.1ms) [[32mINFO [0m|# 6019] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|# 6019] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 6019] Rendered vendor/neutral/layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 6019] Completed 200 OK in 350ms (Views: 11.7ms | ActiveRecord: 0.0ms | API: 324.6ms (XML: 0.0ms , View: 2.8ms , Backend: 0.0ms , DB: 7.2ms , HTTP: 167.0ms) | XML: 3.7ms) [[32mINFO [0m|# 6019] Started GET "/assets/application.css" for 127.0.0.1 at 2012-09-14 09:23:04 +0200 [[31mERROR[0m|# 6019] Error compiling asset application.css: [[31mERROR[0m|# 6019] Sass::SyntaxError: File to import not found or unreadable: bento/reset. Load paths: Sass::Rails::Importer(<http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…)> <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…> /usr/lib64/ruby/gems/1.9.1/gems/compass-0.12.2/frameworks/blueprint/stylesheets /usr/lib64/ruby/gems/1.9.1/gems/compass-0.12.2/frameworks/compass/stylesheets Compass::SpriteImporter (in <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…)> [[32mINFO [0m|# 6019] Served asset /application.css - 500 Internal Server Error [[32mINFO [0m|# 6019] Started GET "/assets/application.js" for 127.0.0.1 at 2012-09-14 09:23:04 +0200 [[32mINFO [0m|# 6019] Served asset /application.js - 304 Not Modified (0ms) [[32mINFO [0m|# 6019] Started GET "/" for 127.0.0.1 at 2012-09-14 09:58:07 +0200 [[32mINFO [0m|# 6019] Processing by MainController#index as HTML [[0;37mDEBUG[0m|# 6019] Setting return_to: "/" [[0;37mDEBUG[0m|# 6019] Authenticating with iChain mode: off [[32mINFO [0m|# 6019] Anonymous request to / [[0;37mDEBUG[0m|# 6019] --> direct_http url: #<URI::Generic:0x000000071c62e8 URL:http://localhost:3203/configuration.json> [[0;37mDEBUG[0m|# 6019] http_do #1: method: GET url: http://localhost:3203/configuration.json [[31mERROR[0m|# 6019] --> caught EOFError: end of file reached, retrying with new HTTP connection [[0;37mDEBUG[0m|# 6019] http_do #2: method: GET url: http://localhost:3203/configuration.json [[0;37mDEBUG[0m|# 6019] RT {"view"=>0.33699799999999996, "db"=>0.398983, "backend"=>0, "xml"=>0, :runtime=>5.218, :all=>87.424015} [[0;37mDEBUG[0m|# 6019] method_missing -to_str- nil [[0;37mDEBUG[0m|# 6019] method_missing -to_str- nil [[0;37mDEBUG[0m|# 6019] method_missing -to_ary- nil [[0;37mDEBUG[0m|# 6019] Statusmessage.find( 900 seconds, {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>5}) [[0;37mDEBUG[0m|# 6019] [REST] find( Statusmessage, [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"5"}] ) [[0;37mDEBUG[0m|# 6019] http_do #1: method: get url: http://localhost:3203/status/messages/?limit=5 [[0;37mDEBUG[0m|# 6019] RT {"view"=>1.139796, "db"=>0.6432610000000001, "backend"=>0, "xml"=>0, :runtime=>8.567, :all=>46.901893} [[0;37mDEBUG[0m|# 6019] method_missing -to_str- nil [[0;37mDEBUG[0m|# 6019] method_missing -to_str- nil [[0;37mDEBUG[0m|# 6019] method_missing -to_ary- nil [[0;37mDEBUG[0m|# 6019] LatestUpdated.find( 300 seconds, {:limit=>6, :shared=>true}) [[0;37mDEBUG[0m|# 6019] [REST] find( LatestUpdated, [{:limit=>"6", :shared=>"true"}] ) [[0;37mDEBUG[0m|# 6019] http_do #1: method: get url: http://localhost:3203/statistics/latest_updated?limit=6 [[0;37mDEBUG[0m|# 6019] RT {"view"=>1.717929, "db"=>3.1040430000000008, "backend"=>0, "xml"=>0, :runtime=>21.43, :all=>62.636221000000006} [[32mINFO [0m|# 6019] Rendered main/_news.html.erb (0.7ms) [[32mINFO [0m|# 6019] Rendered main/_contact.html.erb (0.1ms) [[32mINFO [0m|# 6019] Rendered main/_latest_updates.erb (5.1ms) [[32mINFO [0m|# 6019] Rendered vendor/neutral/main/_countdown.html.erb (0.0ms) [[32mINFO [0m|# 6019] Rendered vendor/neutral/main/_sponsors.html.erb (0.0ms) [[32mINFO [0m|# 6019] Rendered vendor/neutral/main/_feeds.html.erb (0.0ms) [[32mINFO [0m|# 6019] Rendered main/index.html.erb within layouts/application (9.8ms) [[32mINFO [0m|# 6019] Rendered layouts/_watch_and_search.html.erb (0.6ms) [[32mINFO [0m|# 6019] Rendered vendor/neutral/layouts/_header.html.erb (1.2ms) [[32mINFO [0m|# 6019] Rendered layouts/_personal_navigation.html.erb (1.3ms) [[32mINFO [0m|# 6019] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 6019] Rendered vendor/neutral/layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 6019] Completed 200 OK in 221ms (Views: 16.4ms | ActiveRecord: 0.0ms | API: 197.0ms (XML: 0.0ms , View: 3.2ms , Backend: 0.0ms , DB: 4.1ms , HTTP: 161.7ms) | XML: 1.3ms) [[32mINFO [0m|# 6019] Started GET "/assets/application.css" for 127.0.0.1 at 2012-09-14 09:58:07 +0200 [[31mERROR[0m|# 6019] Error compiling asset application.css: [[31mERROR[0m|# 6019] Sass::SyntaxError: File to import not found or unreadable: bento/reset. Load paths: Sass::Rails::Importer(<http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…)> <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…> /usr/lib64/ruby/gems/1.9.1/gems/compass-0.12.2/frameworks/blueprint/stylesheets /usr/lib64/ruby/gems/1.9.1/gems/compass-0.12.2/frameworks/compass/stylesheets Compass::SpriteImporter (in <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…)> [[32mINFO [0m|# 6019] Served asset /application.css - 500 Internal Server Error [[32mINFO [0m|# 6019] Started GET "/assets/application.js" for 127.0.0.1 at 2012-09-14 09:58:07 +0200 [[32mINFO [0m|# 6019] Served asset /application.js - 304 Not Modified (0ms) [[32mINFO [0m|# 6019] Started GET "/main/systemstatus" for 127.0.0.1 at 2012-09-14 09:58:08 +0200 [[32mINFO [0m|# 6019] Processing by MainController#systemstatus as */* [[0;37mDEBUG[0m|# 6019] Setting return_to: "/main/systemstatus" [[0;37mDEBUG[0m|# 6019] Authenticating with iChain mode: off [[32mINFO [0m|# 6019] Anonymous request to /main/systemstatus [[0;37mDEBUG[0m|# 6019] Workerstatus.find( nil, all) [[0;37mDEBUG[0m|# 6019] [REST] find( Workerstatus, [:all] ) [[0;37mDEBUG[0m|# 6019] http_do #1: method: get url: http://localhost:3203/status/workerstatus [[0;37mDEBUG[0m|# 6019] RT {"view"=>0.57668, "db"=>222.34077400000007, "backend"=>0, "xml"=>0.18725799999999998, :runtime=>285.582, :all=>289.138369} [[0;37mDEBUG[0m|# 6019] method_missing -to_str- nil [[0;37mDEBUG[0m|# 6019] method_missing -to_str- nil [[0;37mDEBUG[0m|# 6019] method_missing -to_ary- nil [[0;37mDEBUG[0m|# 6019] GlobalCounters.find( 900 seconds, {:shared=>true}) [[0;37mDEBUG[0m|# 6019] [REST] find( GlobalCounters, [{:shared=>"true"}] ) [[0;37mDEBUG[0m|# 6019] http_do #1: method: get url: http://localhost:3203/statistics/global_counters [[0;37mDEBUG[0m|# 6019] RT {"view"=>1.223121, "db"=>1.7502630000000001, "backend"=>0, "xml"=>0, :runtime=>11.144, :all=>55.402623} [[0;37mDEBUG[0m|# 6019] Architecture.find( 1800 seconds, available) [[0;37mDEBUG[0m|# 6019] [REST] find( Architecture, [:available] ) [[0;37mDEBUG[0m|# 6019] http_do #1: method: get url: http://localhost:3203/architectures?available=1 [[0;37mDEBUG[0m|# 6019] RT {"view"=>0.149366, "db"=>8.912636, "backend"=>0, "xml"=>0, :runtime=>55.553, :all=>100.89641300000001} [[32mINFO [0m|# 6019] Rendered main/_systemstatus.html.erb (1.4ms) [[32mINFO [0m|# 6019] Completed 200 OK in 452ms (Views: 2.4ms | ActiveRecord: 0.0ms | API: 445.4ms (XML: 0.2ms , View: 1.9ms , Backend: 0.0ms , DB: 233.0ms , HTTP: 93.2ms) | XML: 0.8ms) + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/> + egrep 'perl|ruby' USER PID ACCESS COMMAND <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/>: jenkins ..c.. sh jenkins ..c.. sh jenkins ..c.. egrep + echo 'Remove log/tmp files to save disc space' Remove log/tmp files to save disc space + rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets + exit 1 Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

[obs-tests] Build failed in Jenkins: obs_master_testsuite_api #1046
by admin＠opensuse.org 13 Sep '12

13 Sep '12

See <http://ci.opensuse.org/job/obs_master_testsuite_api/1046/changes> Changes: [adrian] [api] document current misrendering of requests in search collections. Just one review element gets rendered. ------------------------------------------ [...truncated 132157 lines...] [[0;37mDEBUG[0m|#10678] Validate XML response: #<ActionDispatch::Response:0x00000006418b78> took 1ms [[32mINFO [0m|#10678] Completed 200 OK in 79ms (Views: 1.2ms | ActiveRecord: 9.7ms | Backend: 18.4ms | XML: 0.4ms) [[32mINFO [0m|#10678] Started GET "/source/kde4/_meta" for 127.0.0.1 at 2012-09-13 18:52:54 +0200 [[32mINFO [0m|#10678] Processing by SourceController#project_meta as XML [[32mINFO [0m|#10678] Parameters: {"project"=>"kde4"} [[0;37mDEBUG[0m|#10678] Validate XML request: #<ActionDispatch::Request:0x00000006cd1b70> [[0;37mDEBUG[0m|#10678] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"project_meta", "project"=>"kde4", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#10678] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#10678] [1m[35mUser Load (0.5ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#10678] USER found: king [[0;37mDEBUG[0m|#10678] User king initialised [[0;37mDEBUG[0m|#10678] [1m[36mProjectUserRoleRelationship Load (0.4ms)[0m [1mSELECT ur.db_project_id, ur.bs_user_id from flags f, project_user_role_relationships ur where f.flag = 'access' and ur.db_project_id = f.db_project_id[0m [[0;37mDEBUG[0m|#10678] [1m[35mRole Load (0.4ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mDbProject Load (0.3ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] Validate XML response: #<ActionDispatch::Response:0x00000006cd1b48> took 2ms [[32mINFO [0m|#10678] Completed 200 OK in 11ms (Views: 0.6ms | ActiveRecord: 1.4ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#10678] Started GET "/source/kde4" for 127.0.0.1 at 2012-09-13 18:52:54 +0200 [[32mINFO [0m|#10678] Processing by SourceController#index_project as XML [[32mINFO [0m|#10678] Parameters: {"project"=>"kde4"} [[0;37mDEBUG[0m|#10678] Validate XML request: #<ActionDispatch::Request:0x00000006d17760> [[0;37mDEBUG[0m|#10678] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#10678] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#10678] [1m[35mUser Load (0.4ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#10678] USER found: king [[0;37mDEBUG[0m|#10678] User king initialised [[0;37mDEBUG[0m|#10678] [1m[36mRole Load (0.4ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND (db_packages.db_project_id not in (0))[0m [[0;37mDEBUG[0m|#10678] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mCACHE (0.0ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"response"} [[0;37mDEBUG[0m|#10678] Validate XML response: #<ActionDispatch::Response:0x00000006d17738> took 0ms [[32mINFO [0m|#10678] Completed 200 OK in 11ms (Views: 0.5ms | ActiveRecord: 1.6ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#10678] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-09-13 18:52:54 +0200 [[32mINFO [0m|#10678] Processing by SourceController#package_meta as XML [[32mINFO [0m|#10678] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|#10678] Validate XML request: #<ActionDispatch::Request:0x00000006dd3f28> [[0;37mDEBUG[0m|#10678] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#10678] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#10678] [1m[35mUser Load (0.4ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#10678] USER found: king [[0;37mDEBUG[0m|#10678] User king initialised [[0;37mDEBUG[0m|#10678] get_by_project_and_name {} [[0;37mDEBUG[0m|#10678] [1m[36mRole Load (0.4ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b646562617365' AND (db_packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] Validate XML response: #<ActionDispatch::Response:0x00000006dd3ed8> took 2ms [[32mINFO [0m|#10678] Completed 200 OK in 12ms (Views: 0.5ms | ActiveRecord: 1.5ms | Backend: 0.0ms | XML: 0.3ms) [[32mINFO [0m|#10678] Started PUT "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-09-13 18:52:54 +0200 [[32mINFO [0m|#10678] Processing by SourceController#package_meta as XML [[32mINFO [0m|#10678] Parameters: {"<package name"=>"\"kdebase\" project=\"kde4\">\n <title>blub</title>\n <description>blub</description>\n</package>\n", "project"=>"kde4", "package"=>"kdebase"} [[33mWARN [0m|#10678] WARNING: Can't verify CSRF token authenticity [[0;37mDEBUG[0m|#10678] Validate XML request: #<ActionDispatch::Request:0x00000006ea7a08> [[0;37mDEBUG[0m|#10678] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#10678] [1m[36mUser Load (0.5ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#10678] USER found: king [[0;37mDEBUG[0m|#10678] User king initialised [[0;37mDEBUG[0m|#10678] [1m[35mRole Load (0.4ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mDbProject Load (0.3ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mDbPackage Load (0.3ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b646562617365' AND (db_packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] get_by_project_and_name {} [[0;37mDEBUG[0m|#10678] [1m[35mCACHE (0.0ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mCACHE (0.0ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b646562617365' AND (db_packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mCACHE (0.0ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mFlag Load (0.4ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mFlag Load (0.3ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position[0m [[0;37mDEBUG[0m|#10678] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|#10678] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 [[0;37mDEBUG[0m|#10678] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|#10678] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#10678] [1m[35mDbPackage Load (0.3ms)[0m SELECT pack.* FROM db_packages pack LEFT OUTER JOIN db_projects pro ON pack.db_project_id = pro.id WHERE pro.name = 'kde4' AND pack.name = 'kdebase' [[0;37mDEBUG[0m|#10678] [1m[36mCACHE (0.0ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mPackageUserRoleRelationship Load (0.2ms)[0m SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 2 [[0;37mDEBUG[0m|#10678] [1m[36mPackageGroupRoleRelationship Load (0.2ms)[0m [1mSELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|#10678] [1m[35mCACHE (0.0ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position [[0;37mDEBUG[0m|#10678] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT db_packages.*, ( @activity:=( db_packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), db_packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp, IF( @activity<0, 0, @activity ) AS activity_value FROM `db_packages` WHERE id = 2 LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35m (0.3ms)[0m UPDATE `db_packages` SET `activity_index` = 85.3110061353901, `updated_at` = '2012-09-13 16:52:54', `update_counter` = 1 WHERE `db_packages`.`id` = 2 [[0;37mDEBUG[0m|#10678] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#10678] [1m[36mPackageUserRoleRelationship Load (0.3ms)[0m [1mSELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|#10678] [1m[35mPackageGroupRoleRelationship Load (0.2ms)[0m SELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 2 [[0;37mDEBUG[0m|#10678] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#10678] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#10683] 2012-09-13 18:52:54 [16070]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#10678] request took 0.01561658 [[0;37mDEBUG[0m|#10678] [1m[36m (0.2ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#10678] Validate XML response: #<ActionDispatch::Response:0x00000006ea79b8> took 1ms [[32mINFO [0m|#10678] Completed 200 OK in 50ms (Views: 1.3ms | ActiveRecord: 4.8ms | Backend: 15.6ms | XML: 0.1ms) [[32mINFO [0m|#10678] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-09-13 18:52:54 +0200 [[32mINFO [0m|#10678] Processing by SourceController#package_meta as XML [[32mINFO [0m|#10678] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|#10678] Validate XML request: #<ActionDispatch::Request:0x000000071b6410> [[0;37mDEBUG[0m|#10678] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#10678] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#10678] [1m[35mUser Load (0.4ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#10678] USER found: king [[0;37mDEBUG[0m|#10678] User king initialised [[0;37mDEBUG[0m|#10678] get_by_project_and_name {} [[0;37mDEBUG[0m|#10678] [1m[36mRole Load (0.5ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mDbProject Load (0.3ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mDbPackage Load (4.0ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b646562617365' AND (db_packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mDbProject Load (0.3ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] Validate XML response: #<ActionDispatch::Response:0x000000071b63e8> took 3ms [[32mINFO [0m|#10678] Completed 200 OK in 175ms (Views: 0.7ms | ActiveRecord: 5.5ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#10678] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-09-13 18:52:55 +0200 [[32mINFO [0m|#10678] Processing by SourceController#package_meta as XML [[32mINFO [0m|#10678] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#10678] Validate XML request: #<ActionDispatch::Request:0x0000000516fd28> [[0;37mDEBUG[0m|#10678] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#10678] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#10678] [1m[36mUser Load (0.3ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#10678] USER found: king [[0;37mDEBUG[0m|#10678] User king initialised [[0;37mDEBUG[0m|#10678] get_by_project_and_name {} [[0;37mDEBUG[0m|#10678] [1m[35mRole Load (0.4ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mDbPackage Load (0.3ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b64656c696273' AND (db_packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] Validate XML response: #<ActionDispatch::Response:0x00000005165878> took 2ms [[32mINFO [0m|#10678] Completed 200 OK in 12ms (Views: 0.5ms | ActiveRecord: 1.4ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#10678] Started PUT "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-09-13 18:52:55 +0200 [[32mINFO [0m|#10678] Processing by SourceController#package_meta as XML [[32mINFO [0m|#10678] Parameters: {"<package name"=>"\"kdelibs\" project=\"kde4\">\n <title>blub</title>\n <description>Changed description</description>\n <devel project=\"home:Iggy\" package=\"TestPack\"/>\n <person userid=\"fredlibs\" role=\"maintainer\"/>\n <person userid=\"adrian\" role=\"reviewer\"/>\n <person userid=\"Iggy\" role=\"maintainer\"/>\n <person userid=\"Iggy\" role=\"bugowner\"/>\n <group groupid=\"test_group\" role=\"maintainer\"/>\n <group groupid=\"test_group\" role=\"reader\"/>\n <build>\n <enable/>\n </build>\n</package>\n", "project"=>"kde4", "package"=>"kdelibs"} [[33mWARN [0m|#10678] WARNING: Can't verify CSRF token authenticity [[0;37mDEBUG[0m|#10678] Validate XML request: #<ActionDispatch::Request:0x00000004e1e480> [[0;37mDEBUG[0m|#10678] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#10678] [1m[35mUser Load (0.3ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#10678] USER found: king [[0;37mDEBUG[0m|#10678] User king initialised [[0;37mDEBUG[0m|#10678] [1m[36mRole Load (0.3ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mDbProject Load (0.3ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b64656c696273' AND (db_packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] get_by_project_and_name {} [[0;37mDEBUG[0m|#10678] [1m[36mCACHE (0.0ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mCACHE (0.0ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b64656c696273' AND (db_packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mCACHE (0.0ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mFlag Load (0.3ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mFlag Load (0.3ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position [[0;37mDEBUG[0m|#10678] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|#10678] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2[0m [[0;37mDEBUG[0m|#10678] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|#10678] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#10678] [1m[36mDbPackage Load (0.3ms)[0m [1m SELECT pack.* FROM db_packages pack LEFT OUTER JOIN db_projects pro ON pack.db_project_id = pro.id WHERE pro.name = 'kde4' AND pack.name = 'kdelibs' [0m [[0;37mDEBUG[0m|#10678] [1m[35mCACHE (0.0ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mDbProject Load (0.3ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'686f6d653a49676779' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mDbPackage Load (0.2ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 502 AND `db_packages`.`name` = x'546573745061636b' AND (db_packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 502 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mPackageUserRoleRelationship Load (0.2ms)[0m SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|#10678] [1m[36mUser Load (0.2ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`id` = 4 LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mRole Load (0.2ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mUser Load (0.2ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`id` = 42 LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mRole Load (0.2ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 6 LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mUser Load (0.2ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mPackageUserRoleRelationship Load (0.2ms)[0m SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 3 AND bs_user_id = 267) LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mSQL (0.3ms)[0m [1mINSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 3)[0m [[0;37mDEBUG[0m|#10678] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mPackageUserRoleRelationship Load (0.2ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 4 AND bs_user_id = 267) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mSQL (0.2ms)[0m INSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 4) [[0;37mDEBUG[0m|#10678] [1m[36mPackageGroupRoleRelationship Load (0.2ms)[0m [1mSELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mGroup Load (0.2ms)[0m SELECT `groups`.* FROM `groups` WHERE `groups`.`id` = 99 LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mRole Load (0.2ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mGroup Load (0.2ms)[0m SELECT `groups`.* FROM `groups` WHERE `groups`.`title` = 'test_group' LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mPackageGroupRoleRelationship Load (0.2ms)[0m [1mSELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE (db_package_id = 1 AND role_id = 7 AND bs_group_id = 99) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mSQL (0.2ms)[0m INSERT INTO `package_group_role_relationships` (`bs_group_id`, `db_package_id`, `role_id`) VALUES (99, 1, 7) [[0;37mDEBUG[0m|#10678] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position[0m [[0;37mDEBUG[0m|#10678] [1m[35mDbPackage Load (0.2ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`id` = 1 AND (db_packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36m (0.2ms)[0m [1mSELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mSQL (0.2ms)[0m INSERT INTO `flags` (`architecture_id`, `db_package_id`, `db_project_id`, `flag`, `position`, `repo`, `status`) VALUES (NULL, 1, NULL, 'build', 1, NULL, 'enable') [[0;37mDEBUG[0m|#10678] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT db_packages.*, ( @activity:=( db_packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), db_packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp, IF( @activity<0, 0, @activity ) AS activity_value FROM `db_packages` WHERE id = 1 LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35m (0.3ms)[0m UPDATE `db_packages` SET `description` = 'Changed description', `develpackage_id` = 10095, `activity_index` = 85.3110061353901, `updated_at` = '2012-09-13 16:52:55', `update_counter` = 1 WHERE `db_packages`.`id` = 1 [[0;37mDEBUG[0m|#10678] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#10678] [1m[36mPackageUserRoleRelationship Load (0.3ms)[0m [1mSELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mPackageGroupRoleRelationship Load (0.3ms)[0m SELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|#10678] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#10678] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#10683] 2012-09-13 18:52:55 [16071]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#10678] request took 0.02192959 [[0;37mDEBUG[0m|#10678] [1m[36m (0.3ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#10678] Validate XML response: #<ActionDispatch::Response:0x00000004e1e408> took 1ms [[32mINFO [0m|#10678] Completed 200 OK in 86ms (Views: 1.2ms | ActiveRecord: 9.4ms | Backend: 21.9ms | XML: 0.3ms) [[32mINFO [0m|#10678] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-09-13 18:52:55 +0200 [[32mINFO [0m|#10678] Processing by SourceController#package_meta as XML [[32mINFO [0m|#10678] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#10678] Validate XML request: #<ActionDispatch::Request:0x00000006701df8> [[0;37mDEBUG[0m|#10678] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#10678] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#10678] [1m[35mUser Load (0.5ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#10678] USER found: king [[0;37mDEBUG[0m|#10678] User king initialised [[0;37mDEBUG[0m|#10678] get_by_project_and_name {} [[0;37mDEBUG[0m|#10678] [1m[36mRole Load (0.4ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mDbProject Load (0.3ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b64656c696273' AND (db_packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#10678] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#10678] Validate XML response: #<ActionDispatch::Response:0x00000006701d58> took 2ms [[32mINFO [0m|#10678] Completed 200 OK in 13ms (Views: 0.6ms | ActiveRecord: 1.6ms | Backend: 0.0ms | XML: 0.0ms) [[0;37mDEBUG[0m|#10678] [1m[36m (111.1ms)[0m [1mROLLBACK[0m [[0;37mDEBUG[0m|#10678] kill 10683 + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/> + egrep 'perl|ruby' USER PID ACCESS COMMAND <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/>: jenkins ..c.. sh jenkins ..c.. sh jenkins ..c.. egrep + echo 'Remove log/tmp files to save disc space' Remove log/tmp files to save disc space + rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets 'src/webui/log/*' src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets + exit 1 Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

[obs-tests] Jenkins build became unstable: obs-2.3_testsuite_api_with_master_backend #21
by hudson＠suse.de 12 Sep '12

12 Sep '12

See <http://river.suse.de/job/obs-2.3_testsuite_api_with_master_backend/21/chang…> -- To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

[obs-tests] Jenkins build became unstable: obs-2.3_testsuite_api #63
by hudson＠suse.de 12 Sep '12

12 Sep '12

See <http://river.suse.de/job/obs-2.3_testsuite_api/63/changes> -- To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

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

12 Sep '12

See <http://ci.opensuse.org/job/obs_master_testsuite_api/1037/changes> Changes: [mls] [backend] don't change complete :packstatus when a package is finished, just add a line to :packstatus.finished [mls] [backend] make reading of :packstatus.finished non-fatal ------------------------------------------ [...truncated 124076 lines...] [[0;37mDEBUG[0m|#20230] Validate XML response: #<ActionDispatch::Response:0x00000007092b60> took 1ms [[32mINFO [0m|#20230] Completed 200 OK in 239ms (Views: 1.4ms | ActiveRecord: 9.8ms | Backend: 19.3ms | XML: 0.4ms) [[32mINFO [0m|#20230] Started GET "/source/kde4/_meta" for 127.0.0.1 at 2012-09-12 10:43:48 +0200 [[32mINFO [0m|#20230] Processing by SourceController#project_meta as XML [[32mINFO [0m|#20230] Parameters: {"project"=>"kde4"} [[0;37mDEBUG[0m|#20230] Validate XML request: #<ActionDispatch::Request:0x00000005f304a8> [[0;37mDEBUG[0m|#20230] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"project_meta", "project"=>"kde4", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#20230] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#20230] [1m[36mUser Load (0.5ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#20230] USER found: king [[0;37mDEBUG[0m|#20230] User king initialised [[0;37mDEBUG[0m|#20230] [1m[35mProjectUserRoleRelationship Load (0.4ms)[0m SELECT ur.db_project_id, ur.bs_user_id from flags f, project_user_role_relationships ur where f.flag = 'access' and ur.db_project_id = f.db_project_id [[0;37mDEBUG[0m|#20230] [1m[36mRole Load (0.4ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mDbProject Load (0.3ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#20230] Validate XML response: #<ActionDispatch::Response:0x00000005f30458> took 3ms [[32mINFO [0m|#20230] Completed 200 OK in 12ms (Views: 0.7ms | ActiveRecord: 1.5ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#20230] Started GET "/source/kde4" for 127.0.0.1 at 2012-09-12 10:43:48 +0200 [[32mINFO [0m|#20230] Processing by SourceController#index_project as XML [[32mINFO [0m|#20230] Parameters: {"project"=>"kde4"} [[0;37mDEBUG[0m|#20230] Validate XML request: #<ActionDispatch::Request:0x00000006211c30> [[0;37mDEBUG[0m|#20230] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#20230] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#20230] [1m[36mUser Load (0.5ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#20230] USER found: king [[0;37mDEBUG[0m|#20230] User king initialised [[0;37mDEBUG[0m|#20230] [1m[35mRole Load (0.4ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mDbProject Load (0.3ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mDbPackage Load (0.3ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND (db_packages.db_project_id not in (0)) [[0;37mDEBUG[0m|#20230] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mCACHE (0.0ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#20230] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"response"} [[0;37mDEBUG[0m|#20230] Validate XML response: #<ActionDispatch::Response:0x00000006211c08> took 0ms [[32mINFO [0m|#20230] Completed 200 OK in 11ms (Views: 0.6ms | ActiveRecord: 1.7ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#20230] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-09-12 10:43:48 +0200 [[32mINFO [0m|#20230] Processing by SourceController#package_meta as XML [[32mINFO [0m|#20230] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|#20230] Validate XML request: #<ActionDispatch::Request:0x00000006530ba0> [[0;37mDEBUG[0m|#20230] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#20230] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#20230] [1m[36mUser Load (0.5ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#20230] USER found: king [[0;37mDEBUG[0m|#20230] User king initialised [[0;37mDEBUG[0m|#20230] get_by_project_and_name {} [[0;37mDEBUG[0m|#20230] [1m[35mRole Load (0.4ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mDbProject Load (0.3ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mDbPackage Load (0.3ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b646562617365' AND (db_packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] Validate XML response: #<ActionDispatch::Response:0x00000006530b78> took 2ms [[32mINFO [0m|#20230] Completed 200 OK in 14ms (Views: 0.6ms | ActiveRecord: 1.7ms | Backend: 0.0ms | XML: 0.4ms) [[32mINFO [0m|#20230] Started PUT "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-09-12 10:43:48 +0200 [[32mINFO [0m|#20230] Processing by SourceController#package_meta as XML [[32mINFO [0m|#20230] Parameters: {"<package name"=>"\"kdebase\" project=\"kde4\">\n <title>blub</title>\n <description>blub</description>\n</package>\n", "project"=>"kde4", "package"=>"kdebase"} [[33mWARN [0m|#20230] WARNING: Can't verify CSRF token authenticity [[0;37mDEBUG[0m|#20230] Validate XML request: #<ActionDispatch::Request:0x0000000661ce38> [[0;37mDEBUG[0m|#20230] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#20230] [1m[35mUser Load (0.5ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#20230] USER found: king [[0;37mDEBUG[0m|#20230] User king initialised [[0;37mDEBUG[0m|#20230] [1m[36mRole Load (0.5ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mDbProject Load (0.3ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b646562617365' AND (db_packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#20230] get_by_project_and_name {} [[0;37mDEBUG[0m|#20230] [1m[36mCACHE (0.0ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mCACHE (0.0ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b646562617365' AND (db_packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mCACHE (0.0ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mFlag Load (0.5ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mFlag Load (0.3ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position [[0;37mDEBUG[0m|#20230] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|#20230] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2[0m [[0;37mDEBUG[0m|#20230] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|#20230] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#20230] [1m[36mDbPackage Load (0.3ms)[0m [1m SELECT pack.* FROM db_packages pack LEFT OUTER JOIN db_projects pro ON pack.db_project_id = pro.id WHERE pro.name = 'kde4' AND pack.name = 'kdebase' [0m [[0;37mDEBUG[0m|#20230] [1m[35mCACHE (0.0ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mPackageUserRoleRelationship Load (0.2ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|#20230] [1m[35mPackageGroupRoleRelationship Load (0.2ms)[0m SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 2 [[0;37mDEBUG[0m|#20230] [1m[36mCACHE (0.0ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position[0m [[0;37mDEBUG[0m|#20230] [1m[35mDbPackage Load (0.3ms)[0m SELECT db_packages.*, ( @activity:=( db_packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), db_packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp, IF( @activity<0, 0, @activity ) AS activity_value FROM `db_packages` WHERE id = 2 LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36m (0.3ms)[0m [1mUPDATE `db_packages` SET `activity_index` = 85.3110061353901, `updated_at` = '2012-09-12 08:43:48', `update_counter` = 1 WHERE `db_packages`.`id` = 2[0m [[0;37mDEBUG[0m|#20230] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#20230] [1m[35mPackageUserRoleRelationship Load (0.3ms)[0m SELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 2 [[0;37mDEBUG[0m|#20230] [1m[36mPackageGroupRoleRelationship Load (0.3ms)[0m [1mSELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|#20230] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#20230] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#20347] 2012-09-12 10:43:48 [24825]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#20230] request took 0.017053917 [[0;37mDEBUG[0m|#20230] [1m[35m (0.2ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#20230] Validate XML response: #<ActionDispatch::Response:0x0000000661ce10> took 1ms [[32mINFO [0m|#20230] Completed 200 OK in 54ms (Views: 1.3ms | ActiveRecord: 5.2ms | Backend: 17.1ms | XML: 0.1ms) [[32mINFO [0m|#20230] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-09-12 10:43:48 +0200 [[32mINFO [0m|#20230] Processing by SourceController#package_meta as XML [[32mINFO [0m|#20230] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|#20230] Validate XML request: #<ActionDispatch::Request:0x00000006bd8458> [[0;37mDEBUG[0m|#20230] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#20230] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#20230] [1m[36mUser Load (0.4ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#20230] USER found: king [[0;37mDEBUG[0m|#20230] User king initialised [[0;37mDEBUG[0m|#20230] get_by_project_and_name {} [[0;37mDEBUG[0m|#20230] [1m[35mRole Load (0.5ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mDbProject Load (0.3ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mDbPackage Load (0.3ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b646562617365' AND (db_packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] Validate XML response: #<ActionDispatch::Response:0x00000006bd8430> took 2ms [[32mINFO [0m|#20230] Completed 200 OK in 13ms (Views: 0.7ms | ActiveRecord: 1.7ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#20230] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-09-12 10:43:48 +0200 [[32mINFO [0m|#20230] Processing by SourceController#package_meta as XML [[32mINFO [0m|#20230] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#20230] Validate XML request: #<ActionDispatch::Request:0x00000006c473d0> [[0;37mDEBUG[0m|#20230] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#20230] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#20230] [1m[35mUser Load (0.5ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#20230] USER found: king [[0;37mDEBUG[0m|#20230] User king initialised [[0;37mDEBUG[0m|#20230] get_by_project_and_name {} [[0;37mDEBUG[0m|#20230] [1m[36mRole Load (0.4ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mDbProject Load (0.3ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b64656c696273' AND (db_packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#20230] Validate XML response: #<ActionDispatch::Response:0x00000006c473a8> took 2ms [[32mINFO [0m|#20230] Completed 200 OK in 13ms (Views: 0.6ms | ActiveRecord: 1.7ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#20230] Started PUT "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-09-12 10:43:48 +0200 [[32mINFO [0m|#20230] Processing by SourceController#package_meta as XML [[32mINFO [0m|#20230] Parameters: {"<package name"=>"\"kdelibs\" project=\"kde4\">\n <title>blub</title>\n <description>Changed description</description>\n <devel project=\"home:Iggy\" package=\"TestPack\"/>\n <person userid=\"fredlibs\" role=\"maintainer\"/>\n <person userid=\"adrian\" role=\"reviewer\"/>\n <person userid=\"Iggy\" role=\"maintainer\"/>\n <person userid=\"Iggy\" role=\"bugowner\"/>\n <group groupid=\"test_group\" role=\"maintainer\"/>\n <group groupid=\"test_group\" role=\"reader\"/>\n <build>\n <enable/>\n </build>\n</package>\n", "project"=>"kde4", "package"=>"kdelibs"} [[33mWARN [0m|#20230] WARNING: Can't verify CSRF token authenticity [[0;37mDEBUG[0m|#20230] Validate XML request: #<ActionDispatch::Request:0x00000006d847e8> [[0;37mDEBUG[0m|#20230] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#20230] [1m[36mUser Load (0.5ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#20230] USER found: king [[0;37mDEBUG[0m|#20230] User king initialised [[0;37mDEBUG[0m|#20230] [1m[35mRole Load (0.4ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mDbProject Load (0.3ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mDbPackage Load (0.3ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b64656c696273' AND (db_packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] get_by_project_and_name {} [[0;37mDEBUG[0m|#20230] [1m[35mCACHE (0.0ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mCACHE (0.0ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b64656c696273' AND (db_packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mCACHE (0.0ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mFlag Load (0.4ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mFlag Load (0.3ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position[0m [[0;37mDEBUG[0m|#20230] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|#20230] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 [[0;37mDEBUG[0m|#20230] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|#20230] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#20230] [1m[35mDbPackage Load (0.3ms)[0m SELECT pack.* FROM db_packages pack LEFT OUTER JOIN db_projects pro ON pack.db_project_id = pro.id WHERE pro.name = 'kde4' AND pack.name = 'kdelibs' [[0;37mDEBUG[0m|#20230] [1m[36mCACHE (0.0ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mDbProject Load (0.3ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'686f6d653a49676779' AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 502 AND `db_packages`.`name` = x'546573745061636b' AND (db_packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 502 AND (db_projects.id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mPackageUserRoleRelationship Load (0.2ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`id` = 4 LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mRole Load (0.2ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`id` = 42 LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mRole Load (0.2ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 6 LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mPackageUserRoleRelationship Load (0.2ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 3 AND bs_user_id = 267) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mSQL (0.3ms)[0m INSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 3) [[0;37mDEBUG[0m|#20230] [1m[36mUser Load (0.2ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mPackageUserRoleRelationship Load (0.2ms)[0m SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 4 AND bs_user_id = 267) LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mSQL (0.2ms)[0m [1mINSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 4)[0m [[0;37mDEBUG[0m|#20230] [1m[35mPackageGroupRoleRelationship Load (0.2ms)[0m SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|#20230] [1m[36mGroup Load (0.2ms)[0m [1mSELECT `groups`.* FROM `groups` WHERE `groups`.`id` = 99 LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mRole Load (0.2ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mGroup Load (0.2ms)[0m [1mSELECT `groups`.* FROM `groups` WHERE `groups`.`title` = 'test_group' LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mPackageGroupRoleRelationship Load (0.2ms)[0m SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE (db_package_id = 1 AND role_id = 7 AND bs_group_id = 99) LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mSQL (0.2ms)[0m [1mINSERT INTO `package_group_role_relationships` (`bs_group_id`, `db_package_id`, `role_id`) VALUES (99, 1, 7)[0m [[0;37mDEBUG[0m|#20230] [1m[35mFlag Load (0.3ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position [[0;37mDEBUG[0m|#20230] [1m[36mDbPackage Load (0.2ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`id` = 1 AND (db_packages.db_project_id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35m (0.2ms)[0m SELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1 [[0;37mDEBUG[0m|#20230] [1m[36mSQL (0.2ms)[0m [1mINSERT INTO `flags` (`architecture_id`, `db_package_id`, `db_project_id`, `flag`, `position`, `repo`, `status`) VALUES (NULL, 1, NULL, 'build', 1, NULL, 'enable')[0m [[0;37mDEBUG[0m|#20230] [1m[35mDbPackage Load (0.3ms)[0m SELECT db_packages.*, ( @activity:=( db_packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), db_packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp, IF( @activity<0, 0, @activity ) AS activity_value FROM `db_packages` WHERE id = 1 LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36m (0.3ms)[0m [1mUPDATE `db_packages` SET `description` = 'Changed description', `develpackage_id` = 10095, `activity_index` = 85.3110061353901, `updated_at` = '2012-09-12 08:43:48', `update_counter` = 1 WHERE `db_packages`.`id` = 1[0m [[0;37mDEBUG[0m|#20230] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#20230] [1m[35mPackageUserRoleRelationship Load (0.3ms)[0m SELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|#20230] [1m[36mPackageGroupRoleRelationship Load (0.3ms)[0m [1mSELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|#20230] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#20230] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#20347] 2012-09-12 10:43:48 [24826]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#20230] request took 0.020955151 [[0;37mDEBUG[0m|#20230] [1m[35m (0.2ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#20230] Validate XML response: #<ActionDispatch::Response:0x00000006d847c0> took 1ms [[32mINFO [0m|#20230] Completed 200 OK in 85ms (Views: 1.2ms | ActiveRecord: 9.8ms | Backend: 21.0ms | XML: 0.4ms) [[32mINFO [0m|#20230] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-09-12 10:43:48 +0200 [[32mINFO [0m|#20230] Processing by SourceController#package_meta as XML [[32mINFO [0m|#20230] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#20230] Validate XML request: #<ActionDispatch::Request:0x00000007204bd8> [[0;37mDEBUG[0m|#20230] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#20230] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#20230] [1m[36mUser Load (0.4ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#20230] USER found: king [[0;37mDEBUG[0m|#20230] User king initialised [[0;37mDEBUG[0m|#20230] get_by_project_and_name {} [[0;37mDEBUG[0m|#20230] [1m[35mRole Load (0.4ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mDbProject Load (0.3ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] [1m[35mDbPackage Load (0.3ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b64656c696273' AND (db_packages.db_project_id not in (0)) LIMIT 1 [[0;37mDEBUG[0m|#20230] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1[0m [[0;37mDEBUG[0m|#20230] Validate XML response: #<ActionDispatch::Response:0x00000007204bb0> took 2ms [[32mINFO [0m|#20230] Completed 200 OK in 170ms (Views: 0.6ms | ActiveRecord: 1.6ms | Backend: 0.0ms | XML: 0.0ms) [[0;37mDEBUG[0m|#20230] [1m[35m (26.6ms)[0m ROLLBACK [[0;37mDEBUG[0m|#20230] kill 20347 + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/> + egrep 'perl|ruby' USER PID ACCESS COMMAND <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/>: jenkins ..c.. sh jenkins ..c.. sh jenkins ..c.. egrep + echo 'Remove log/tmp files to save disc space' Remove log/tmp files to save disc space + rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets 'src/webui/log/*' src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets + exit 1 Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1