October 2012 - Open Build Service Tests

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

08 Oct '12

See <http://ci.opensuse.org/job/obs_master_testsuite_api/1082/changes> Changes: [Stephan Kulow] [api,webui] update xmlhash dependency ------------------------------------------ [...truncated 123310 lines...] [[0;37mDEBUG[0m|#13394] Validate XML response: #<ActionDispatch::Response:0x00000006283920> took 1ms [[32mINFO [0m|#13394] Completed 200 OK in 76ms (Views: 0.9ms | ActiveRecord: 6.2ms | Backend: 14.4ms | XML: 0.3ms) [[32mINFO [0m|#13394] Started GET "/source/kde4/_meta" for 127.0.0.1 at 2012-10-08 12:24:09 +0200 [[32mINFO [0m|#13394] Processing by SourceController#project_meta as XML [[32mINFO [0m|#13394] Parameters: {"project"=>"kde4"} [[0;37mDEBUG[0m|#13394] Validate XML request: #<ActionDispatch::Request:0x00000006bd83b8> [[0;37mDEBUG[0m|#13394] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"project_meta", "project"=>"kde4", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#13394] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#13394] [1m[36mUser Load (0.3ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#13394] USER found: king [[0;37mDEBUG[0m|#13394] User king initialised [[0;37mDEBUG[0m|#13394] [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|#13394] [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|#13394] [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|#13394] Validate XML response: #<ActionDispatch::Response:0x00000006bd8340> took 2ms [[32mINFO [0m|#13394] Completed 200 OK in 11ms (Views: 0.6ms | ActiveRecord: 1.0ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#13394] Started GET "/source/kde4" for 127.0.0.1 at 2012-10-08 12:24:09 +0200 [[32mINFO [0m|#13394] Processing by SourceController#index_project as XML [[32mINFO [0m|#13394] Parameters: {"project"=>"kde4"} [[0;37mDEBUG[0m|#13394] Validate XML request: #<ActionDispatch::Request:0x00000006c86800> [[0;37mDEBUG[0m|#13394] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#13394] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#13394] [1m[36mUser Load (0.2ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#13394] USER found: king [[0;37mDEBUG[0m|#13394] User king initialised [[0;37mDEBUG[0m|#13394] [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|#13394] [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|#13394] [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|#13394] [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|#13394] [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|#13394] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"response"} [[0;37mDEBUG[0m|#13394] Validate XML response: #<ActionDispatch::Response:0x00000006c86738> took 0ms [[32mINFO [0m|#13394] Completed 200 OK in 10ms (Views: 0.3ms | ActiveRecord: 0.9ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#13394] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-10-08 12:24:09 +0200 [[32mINFO [0m|#13394] Processing by SourceController#package_meta as XML [[32mINFO [0m|#13394] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|#13394] Validate XML request: #<ActionDispatch::Request:0x00000006d551a0> [[0;37mDEBUG[0m|#13394] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#13394] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#13394] [1m[36mUser Load (0.3ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#13394] USER found: king [[0;37mDEBUG[0m|#13394] User king initialised [[0;37mDEBUG[0m|#13394] get_by_project_and_name {} [[0;37mDEBUG[0m|#13394] [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|#13394] [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|#13394] [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|#13394] [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|#13394] Validate XML response: #<ActionDispatch::Response:0x00000006d55178> took 2ms [[32mINFO [0m|#13394] Completed 200 OK in 12ms (Views: 0.5ms | ActiveRecord: 1.0ms | Backend: 0.0ms | XML: 0.2ms) [[32mINFO [0m|#13394] Started PUT "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-10-08 12:24:09 +0200 [[32mINFO [0m|#13394] Processing by SourceController#package_meta as XML [[32mINFO [0m|#13394] Parameters: {"<package name"=>"\"kdebase\" project=\"kde4\">\n <title>blub</title>\n <description>blub</description>\n</package>\n", "project"=>"kde4", "package"=>"kdebase"} [[33mWARN [0m|#13394] WARNING: Can't verify CSRF token authenticity [[0;37mDEBUG[0m|#13394] Validate XML request: #<ActionDispatch::Request:0x00000006de4d28> [[0;37mDEBUG[0m|#13394] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#13394] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#13394] USER found: king [[0;37mDEBUG[0m|#13394] User king initialised [[0;37mDEBUG[0m|#13394] [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|#13394] [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|#13394] [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|#13394] [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|#13394] get_by_project_and_name {} [[0;37mDEBUG[0m|#13394] [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|#13394] [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|#13394] [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|#13394] [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|#13394] [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|#13394] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position [[0;37mDEBUG[0m|#13394] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|#13394] [1m[36mFlag Load (0.1ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2[0m [[0;37mDEBUG[0m|#13394] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|#13394] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#13394] [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|#13394] [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|#13394] [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|#13394] [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|#13394] [1m[36mCACHE (0.0ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position[0m [[0;37mDEBUG[0m|#13394] [1m[35mDbPackage Load (1.7ms)[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|#13394] [1m[36m (0.3ms)[0m [1mUPDATE `db_packages` SET `activity_index` = 85.3110061353901, `updated_at` = '2012-10-08 10:24:09', `update_counter` = 1 WHERE `db_packages`.`id` = 2[0m [[0;37mDEBUG[0m|#13394] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#13394] [1m[35mPackageUserRoleRelationship Load (0.8ms)[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|#13394] [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|#13394] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#13394] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#13399] 2012-10-08 12:24:09 [18300]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#13394] request took 0.012049801 [[0;37mDEBUG[0m|#13394] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#13394] Validate XML response: #<ActionDispatch::Response:0x00000006de4d00> took 1ms [[32mINFO [0m|#13394] Completed 200 OK in 166ms (Views: 1.4ms | ActiveRecord: 5.4ms | Backend: 12.0ms | XML: 0.0ms) [[32mINFO [0m|#13394] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-10-08 12:24:09 +0200 [[32mINFO [0m|#13394] Processing by SourceController#package_meta as XML [[32mINFO [0m|#13394] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|#13394] Validate XML request: #<ActionDispatch::Request:0x000000046f4650> [[0;37mDEBUG[0m|#13394] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#13394] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#13394] [1m[36mUser Load (0.2ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#13394] USER found: king [[0;37mDEBUG[0m|#13394] User king initialised [[0;37mDEBUG[0m|#13394] get_by_project_and_name {} [[0;37mDEBUG[0m|#13394] [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|#13394] [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|#13394] [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|#13394] [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|#13394] Validate XML response: #<ActionDispatch::Response:0x000000046f4628> took 2ms [[32mINFO [0m|#13394] Completed 200 OK in 12ms (Views: 0.4ms | ActiveRecord: 1.1ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#13394] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-10-08 12:24:09 +0200 [[32mINFO [0m|#13394] Processing by SourceController#package_meta as XML [[32mINFO [0m|#13394] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#13394] Validate XML request: #<ActionDispatch::Request:0x000000052217a8> [[0;37mDEBUG[0m|#13394] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#13394] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#13394] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#13394] USER found: king [[0;37mDEBUG[0m|#13394] User king initialised [[0;37mDEBUG[0m|#13394] get_by_project_and_name {} [[0;37mDEBUG[0m|#13394] [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|#13394] [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|#13394] [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|#13394] [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|#13394] Validate XML response: #<ActionDispatch::Response:0x0000000522e408> took 2ms [[32mINFO [0m|#13394] Completed 200 OK in 13ms (Views: 0.4ms | ActiveRecord: 0.8ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#13394] Started PUT "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-10-08 12:24:09 +0200 [[32mINFO [0m|#13394] Processing by SourceController#package_meta as XML [[32mINFO [0m|#13394] 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|#13394] WARNING: Can't verify CSRF token authenticity [[0;37mDEBUG[0m|#13394] Validate XML request: #<ActionDispatch::Request:0x000000057f6278> [[0;37mDEBUG[0m|#13394] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#13394] [1m[36mUser Load (0.2ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#13394] USER found: king [[0;37mDEBUG[0m|#13394] User king initialised [[0;37mDEBUG[0m|#13394] [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|#13394] [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|#13394] [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|#13394] [1m[36mDbProject Load (0.3ms)[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|#13394] get_by_project_and_name {} [[0;37mDEBUG[0m|#13394] [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|#13394] [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|#13394] [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|#13394] [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|#13394] [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|#13394] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position[0m [[0;37mDEBUG[0m|#13394] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|#13394] [1m[35mFlag Load (0.1ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 [[0;37mDEBUG[0m|#13394] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|#13394] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#13394] [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|#13394] [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|#13394] [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|#13394] [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|#13394] [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|#13394] [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|#13394] [1m[35mUser Load (0.1ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`id` = 4 LIMIT 1 [[0;37mDEBUG[0m|#13394] [1m[36mRole Load (0.1ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1[0m [[0;37mDEBUG[0m|#13394] [1m[35mUser Load (0.1ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`id` = 42 LIMIT 1 [[0;37mDEBUG[0m|#13394] [1m[36mRole Load (0.1ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 6 LIMIT 1[0m [[0;37mDEBUG[0m|#13394] [1m[35mUser Load (0.1ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1 [[0;37mDEBUG[0m|#13394] [1m[36mPackageUserRoleRelationship Load (0.1ms)[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|#13394] [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|#13394] [1m[36mUser Load (0.1ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1[0m [[0;37mDEBUG[0m|#13394] [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|#13394] [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|#13394] [1m[35mPackageGroupRoleRelationship Load (0.1ms)[0m SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|#13394] [1m[36mGroup Load (0.2ms)[0m [1mSELECT `groups`.* FROM `groups` WHERE `groups`.`id` = 99 LIMIT 1[0m [[0;37mDEBUG[0m|#13394] [1m[35mRole Load (0.1ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1 [[0;37mDEBUG[0m|#13394] [1m[36mGroup Load (0.2ms)[0m [1mSELECT `groups`.* FROM `groups` WHERE `groups`.`title` = 'test_group' LIMIT 1[0m [[0;37mDEBUG[0m|#13394] [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|#13394] [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|#13394] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position [[0;37mDEBUG[0m|#13394] [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|#13394] [1m[35m (0.2ms)[0m SELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1 [[0;37mDEBUG[0m|#13394] [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|#13394] [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|#13394] [1m[36m (0.2ms)[0m [1mUPDATE `db_packages` SET `description` = 'Changed description', `develpackage_id` = 10095, `activity_index` = 85.3110061353901, `updated_at` = '2012-10-08 10:24:09', `update_counter` = 1 WHERE `db_packages`.`id` = 1[0m [[0;37mDEBUG[0m|#13394] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#13394] [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|#13394] [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|#13394] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#13394] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#13399] 2012-10-08 12:24:09 [18301]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#13394] request took 0.014317899 [[0;37mDEBUG[0m|#13394] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#13394] Validate XML response: #<ActionDispatch::Response:0x000000057f6228> took 1ms [[32mINFO [0m|#13394] Completed 200 OK in 79ms (Views: 1.3ms | ActiveRecord: 6.9ms | Backend: 14.3ms | XML: 0.3ms) [[32mINFO [0m|#13394] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-10-08 12:24:09 +0200 [[32mINFO [0m|#13394] Processing by SourceController#package_meta as XML [[32mINFO [0m|#13394] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#13394] Validate XML request: #<ActionDispatch::Request:0x00000006a264c0> [[0;37mDEBUG[0m|#13394] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#13394] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#13394] [1m[36mUser Load (0.2ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#13394] USER found: king [[0;37mDEBUG[0m|#13394] User king initialised [[0;37mDEBUG[0m|#13394] get_by_project_and_name {} [[0;37mDEBUG[0m|#13394] [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|#13394] [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|#13394] [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|#13394] [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|#13394] Validate XML response: #<ActionDispatch::Response:0x00000006a26470> took 2ms [[32mINFO [0m|#13394] Completed 200 OK in 13ms (Views: 0.4ms | ActiveRecord: 0.9ms | Backend: 0.0ms | XML: 0.0ms) [[0;37mDEBUG[0m|#13394] [1m[35m (6.2ms)[0m ROLLBACK [[0;37mDEBUG[0m|#13394] kill 13399 + 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] Build failed in Jenkins: obs_master_testsuite_webui #483
by admin＠opensuse.org 08 Oct '12

08 Oct '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui/483/changes> Changes: [Stephan Kulow] [api,webui] fix logging of entries with starting newlines [Stephan Kulow] [api,webui] update xmlhash dependency ------------------------------------------ [...truncated 17927 lines...] /usr/lib64/ruby/gems/1.9.1/gems/ci_reporter-1.7.2/lib/ci/reporter/minitest.rb:93:in `block in _run_suite' /usr/lib64/ruby/gems/1.9.1/gems/ci_reporter-1.7.2/lib/ci/reporter/minitest.rb:92:in `each' /usr/lib64/ruby/gems/1.9.1/gems/ci_reporter-1.7.2/lib/ci/reporter/minitest.rb:92:in `_run_suite' /usr/lib64/ruby/gems/1.9.1/gems/ci_reporter-1.7.2/lib/ci/reporter/minitest.rb:83:in `block in _run_suites' /usr/lib64/ruby/gems/1.9.1/gems/ci_reporter-1.7.2/lib/ci/reporter/minitest.rb:83:in `map' /usr/lib64/ruby/gems/1.9.1/gems/ci_reporter-1.7.2/lib/ci/reporter/minitest.rb:83:in `_run_suites' /usr/lib64/ruby/gems/1.9.1/gems/ci_reporter-1.7.2/lib/ci/reporter/minitest.rb:75:in `_run_anything' /usr/lib64/ruby/1.9.1/minitest/unit.rb:909:in `run_tests' /usr/lib64/ruby/1.9.1/minitest/unit.rb:896:in `block in _run' /usr/lib64/ruby/1.9.1/minitest/unit.rb:895:in `each' /usr/lib64/ruby/1.9.1/minitest/unit.rb:895:in `_run' /usr/lib64/ruby/1.9.1/minitest/unit.rb:884:in `run' /usr/lib64/ruby/1.9.1/test/unit.rb:21:in `run' /usr/lib64/ruby/1.9.1/test/unit.rb:326:in `block (2 levels) in autorun' /usr/lib64/ruby/1.9.1/test/unit.rb:27:in `run_once' /usr/lib64/ruby/1.9.1/test/unit.rb:325:in `block in autorun' [[32mINFO [0m|#19131] Rendered layouts/_watch_and_search.html.erb (0.6ms) [[32mINFO [0m|#19131] Rendered vendor/neutral/layouts/_header.html.erb (1.5ms) [[32mINFO [0m|#19131] Rendered layouts/_personal_navigation.html.erb (1.4ms) [[32mINFO [0m|#19131] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#19131] Rendered vendor/neutral/layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#19131] Completed 400 Bad Request in 391ms (Views: 8.0ms | ActiveRecord: 0.0ms | API: 374.7ms (XML: 0.2ms , View: 2.8ms , Backend: 0.0ms , DB: 7.0ms , HTTP: 289.0ms) | XML: 0.6ms) [[0;37mDEBUG[0m|#19131] [1m[35m (0.1ms)[0m rollback transaction [[0;37mDEBUG[0m|#19131] [1m[36m (0.0ms)[0m [1mbegin transaction[0m [[32mINFO [0m|#19131] Started POST "/user/do_login" for 127.0.0.1 at 2012-10-08 12:26:40 +0200 [[32mINFO [0m|#19131] Processing by UserController#do_login as HTML [[32mINFO [0m|#19131] Parameters: {"username"=>"tom", "password"=>"[FILTERED]", "return_to_path"=>"/"} [[0;37mDEBUG[0m|#19131] Setting return_to: "/" [[0;37mDEBUG[0m|#19131] Authenticating with iChain mode: off [[32mINFO [0m|#19131] Anonymous request to / [[0;37mDEBUG[0m|#19131] Doing form authorization to login user tom [[0;37mDEBUG[0m|#19131] [REST] find( Person, [{:login=>"tom"}] ) [[0;37mDEBUG[0m|#19131] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#19131] RT {"view"=>0.546821, "db"=>0.990101, "backend"=>0, "xml"=>0, :runtime=>11.857, :all=>52.80605} [[32mINFO [0m|#19131] Redirected to http://example.org/ [[32mINFO [0m|#19131] Completed 302 Found in 55ms (ActiveRecord: 0.0ms | API: 52.8ms (XML: 0.0ms , View: 0.5ms , Backend: 0.0ms , DB: 1.0ms , HTTP: 40.9ms) | XML: 0.5ms) [[32mINFO [0m|#19131] Started GET "/" for 127.0.0.1 at 2012-10-08 12:26:40 +0200 [[32mINFO [0m|#19131] Processing by MainController#index as HTML [[0;37mDEBUG[0m|#19131] Setting return_to: "/" [[0;37mDEBUG[0m|#19131] Authenticating with iChain mode: off [[32mINFO [0m|#19131] Authenticated request to "/" from tom [[0;37mDEBUG[0m|#19131] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#19131] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#19131] RT {"view"=>0.5553030000000001, "db"=>0.724164, "backend"=>0, "xml"=>0, :runtime=>8.975999999999999, :all=>46.883503} [[0;37mDEBUG[0m|#19131] Fetching request list from api [[0;37mDEBUG[0m|#19131] --> direct_http url: #<URI::Generic:0x00000006cf0610 URL:http://localhost:3203/request?view=collection&states=declined&roles=creator&user=tom> [[0;37mDEBUG[0m|#19131] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=declined&roles=creator… [[0;37mDEBUG[0m|#19131] RT {"view"=>0.563034, "db"=>0.726665, "backend"=>0, "xml"=>0.039641, :runtime=>10.173, :all=>51.192831} [[0;37mDEBUG[0m|#19131] Fetching request list from api [[0;37mDEBUG[0m|#19131] --> direct_http url: #<URI::Generic:0x00000007019300 URL:http://localhost:3203/request?view=collection&states=review&roles=reviewer&reviewstates=new&user=tom> [[0;37mDEBUG[0m|#19131] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=review&roles=reviewer&… [[0;37mDEBUG[0m|#19131] RT {"view"=>0.47664, "db"=>2.2546039999999996, "backend"=>0, "xml"=>0.031172, :runtime=>22.988000000000003, :all=>62.875834000000005} [[0;37mDEBUG[0m|#19131] Fetching request list from api [[0;37mDEBUG[0m|#19131] --> direct_http url: #<URI::Generic:0x00000006920440 URL:http://localhost:3203/request?view=collection&states=new&roles=maintainer&user=tom> [[0;37mDEBUG[0m|#19131] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=new&roles=maintainer&u… [[0;37mDEBUG[0m|#19131] RT {"view"=>0.495674, "db"=>2.048372, "backend"=>0, "xml"=>0.043205, :runtime=>18.827, :all=>59.438159999999996} [[32mINFO [0m|#19131] Rendered main/_news.html.erb (0.6ms) [[32mINFO [0m|#19131] Rendered main/_contact.html.erb (0.1ms) [[32mINFO [0m|#19131] Rendered main/_latest_updates.erb (55.8ms) [[32mINFO [0m|#19131] Rendered vendor/neutral/main/_countdown.html.erb (0.1ms) [[32mINFO [0m|#19131] Rendered vendor/neutral/main/_sponsors.html.erb (0.0ms) [[32mINFO [0m|#19131] Rendered vendor/neutral/main/_feeds.html.erb (0.0ms) [[32mINFO [0m|#19131] Rendered layouts/_watch_and_search.html.erb (0.7ms) [[32mINFO [0m|#19131] Rendered vendor/neutral/layouts/_header.html.erb (1.5ms) [[32mINFO [0m|#19131] Rendered layouts/_personal_navigation.html.erb (0.9ms) [[32mINFO [0m|#19131] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#19131] Rendered vendor/neutral/layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#19131] Completed 200 OK in 296ms (Views: 69.5ms | ActiveRecord: 0.0ms | API: 220.4ms (XML: 0.1ms , View: 2.1ms , Backend: 0.0ms , DB: 5.8ms , HTTP: 159.4ms) | XML: 2.3ms) [[32mINFO [0m|#19131] Started GET "/project/show?project=home%3Aadrian" for 127.0.0.1 at 2012-10-08 12:26:41 +0200 [[32mINFO [0m|#19131] Processing by ProjectController#show as HTML [[32mINFO [0m|#19131] Parameters: {"project"=>"home:adrian"} [[0;37mDEBUG[0m|#19131] Setting return_to: "/project/show?project=home%3Aadrian" [[0;37mDEBUG[0m|#19131] Authenticating with iChain mode: off [[32mINFO [0m|#19131] Authenticated request to "/project/show?project=home%3Aadrian" from tom [[0;37mDEBUG[0m|#19131] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] ) [[0;37mDEBUG[0m|#19131] http_do #1: method: get url: http://localhost:3203/person/tom [[0;37mDEBUG[0m|#19131] RT {"view"=>0.47232300000000005, "db"=>0.757694, "backend"=>0, "xml"=>0, :runtime=>8.168999999999999, :all=>11.347149000000002} [[0;37mDEBUG[0m|#19131] Fetching request list from api [[0;37mDEBUG[0m|#19131] --> direct_http url: #<URI::Generic:0x000000062688c8 URL:http://localhost:3203/request?view=collection&states=declined&roles=creator&user=tom> [[0;37mDEBUG[0m|#19131] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=declined&roles=creator… [[0;37mDEBUG[0m|#19131] RT {"view"=>0.337689, "db"=>0.487082, "backend"=>0, "xml"=>0.035276, :runtime=>6.8919999999999995, :all=>47.973219} [[0;37mDEBUG[0m|#19131] Fetching request list from api [[0;37mDEBUG[0m|#19131] --> direct_http url: #<URI::Generic:0x00000005d548f0 URL:http://localhost:3203/request?view=collection&states=review&roles=reviewer&reviewstates=new&user=tom> [[0;37mDEBUG[0m|#19131] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=review&roles=reviewer&… [[0;37mDEBUG[0m|#19131] RT {"view"=>0.533568, "db"=>2.6143490000000003, "backend"=>0, "xml"=>0.023833999999999998, :runtime=>23.313, :all=>63.008277} [[0;37mDEBUG[0m|#19131] Fetching request list from api [[0;37mDEBUG[0m|#19131] --> direct_http url: #<URI::Generic:0x000000062c9510 URL:http://localhost:3203/request?view=collection&states=new&roles=maintainer&user=tom> [[0;37mDEBUG[0m|#19131] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=new&roles=maintainer&u… [[0;37mDEBUG[0m|#19131] RT {"view"=>0.480879, "db"=>2.0906029999999998, "backend"=>0, "xml"=>0.047181, :runtime=>16.048, :all=>58.997439} [[0;37mDEBUG[0m|#19131] Fetching request list from api [[0;37mDEBUG[0m|#19131] --> direct_http url: #<URI::Generic:0x00000006245f58 URL:http://localhost:3203/request?view=collection&states=review&roles=reviewer&reviewstates=new&project=home%3Aadrian> [[0;37mDEBUG[0m|#19131] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=review&roles=reviewer&… [[0;37mDEBUG[0m|#19131] RT {"view"=>0.331588, "db"=>0.571129, "backend"=>0, "xml"=>0.034227, :runtime=>7.955, :all=>50.853819} [[0;37mDEBUG[0m|#19131] Fetching request list from api [[0;37mDEBUG[0m|#19131] --> direct_http url: #<URI::Generic:0x000000066367e8 URL:http://localhost:3203/request?view=collection&states=new&roles=target&project=home%3Aadrian> [[0;37mDEBUG[0m|#19131] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=new&roles=target&proje… [[0;37mDEBUG[0m|#19131] RT {"view"=>0.375651, "db"=>0.5596300000000001, "backend"=>0, "xml"=>0.03519, :runtime=>6.872999999999999, :all=>47.419326999999996} [[0;37mDEBUG[0m|#19131] Fetching request list from api [[0;37mDEBUG[0m|#19131] --> direct_http url: #<URI::Generic:0x000000065eb338 URL:http://localhost:3203/request?view=collection&states=new%2Creview&roles=source&types=maintenance_incident&project=home%3Aadrian> [[0;37mDEBUG[0m|#19131] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=new%2Creview&roles=sou… [[0;37mDEBUG[0m|#19131] RT {"view"=>0.36534300000000003, "db"=>0.403981, "backend"=>0, "xml"=>0.032884, :runtime=>4.875, :all=>43.392843} [[0;37mDEBUG[0m|#19131] --> direct_http url: #<URI::Generic:0x000000065a4a28 URL:http://localhost:3203/search/project/?match=starts-with%28%40name%2C%27home%3Aadrian%3A%27%29+and+%40kind%3D%27maintenance_incident%27+and+repository%2Freleasetarget%2F%40trigger%3D%27maintenance%27> [[0;37mDEBUG[0m|#19131] http_do #1: method: GET url: http://localhost:3203/search/project/?match=starts-with%28%40name%2C%27home… [[0;37mDEBUG[0m|#19131] RT {"view"=>0.639031, "db"=>1.369211, "backend"=>0, "xml"=>0.06969, :runtime=>13.748, :all=>55.667976} [[0;37mDEBUG[0m|#19131] [REST] find( Buildresult, [{:project=>"home:adrian", :view=>"status", :code=>["failed", "broken", "unresolvable"]}] ) [[0;37mDEBUG[0m|#19131] http_do #1: method: get url: http://localhost:3203/build/home:adrian/_result?view=status&code=failed&cod… [[0;37mDEBUG[0m|#19131] RT {"view"=>nil, "db"=>0.576883, "backend"=>0, "xml"=>0, :runtime=>77.174, :all=>118.51977199999999} [[0;37mDEBUG[0m|#19131] Buildresult.find( {:project=>"home:adrian", :view=>"status", :code=>["failed", "broken", "unresolvable"]} ) did not find anything, return nil [[31mERROR[0m|#19131] rescue_action: caught NoMethodError: undefined method `elements' for {}:Hash [[0;37mDEBUG[0m|#19131] ERROR: unknown; undefined method `elements' for {}:Hash [[0;37mDEBUG[0m|#19131] <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/webui/app/cont…>:281:in `block (2 levels) in show' /usr/lib64/ruby/gems/1.9.1/gems/xmlhash-1.3.0/lib/xmlhash.rb:24:in `elements' <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/webui/app/cont…>:280:in `block in show' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/cache.rb:297:in `block in fetch' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/cache.rb:520:in `instrument' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/cache.rb:296:in `fetch' <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/webui/app/cont…>:274:in `show' /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:524:in `_run__21218560515736917__process_action__272469748476272265__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__3338227249702356378__call__2959151665265279285__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-test-0.6.1/lib/rack/mock_session.rb:30:in `request' /usr/lib64/ruby/gems/1.9.1/gems/rack-test-0.6.1/lib/rack/test.rb:219:in `process_request' /usr/lib64/ruby/gems/1.9.1/gems/rack-test-0.6.1/lib/rack/test.rb:57:in `get' /usr/lib64/ruby/1.9.1/forwardable.rb:201:in `get' <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/webui/test/fun…>:296:in `test_2respider' /usr/lib64/ruby/1.9.1/minitest/unit.rb:949:in `run' /usr/lib64/ruby/1.9.1/test/unit/testcase.rb:17:in `run' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/testing/setup_and_teardown.rb:36:in `block in run' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:447:in `_run__126053493682792556__setup__2959151665265279285__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_setup_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/activesupport-3.2.8/lib/active_support/testing/setup_and_teardown.rb:35:in `run' /usr/lib64/ruby/gems/1.9.1/gems/ci_reporter-1.7.2/lib/ci/reporter/minitest.rb:175:in `run_test' /usr/lib64/ruby/gems/1.9.1/gems/ci_reporter-1.7.2/lib/ci/reporter/minitest.rb:102:in `_run_test' /usr/lib64/ruby/gems/1.9.1/gems/ci_reporter-1.7.2/lib/ci/reporter/minitest.rb:93:in `block in _run_suite' /usr/lib64/ruby/gems/1.9.1/gems/ci_reporter-1.7.2/lib/ci/reporter/minitest.rb:92:in `each' /usr/lib64/ruby/gems/1.9.1/gems/ci_reporter-1.7.2/lib/ci/reporter/minitest.rb:92:in `_run_suite' /usr/lib64/ruby/gems/1.9.1/gems/ci_reporter-1.7.2/lib/ci/reporter/minitest.rb:83:in `block in _run_suites' /usr/lib64/ruby/gems/1.9.1/gems/ci_reporter-1.7.2/lib/ci/reporter/minitest.rb:83:in `map' /usr/lib64/ruby/gems/1.9.1/gems/ci_reporter-1.7.2/lib/ci/reporter/minitest.rb:83:in `_run_suites' /usr/lib64/ruby/gems/1.9.1/gems/ci_reporter-1.7.2/lib/ci/reporter/minitest.rb:75:in `_run_anything' /usr/lib64/ruby/1.9.1/minitest/unit.rb:909:in `run_tests' /usr/lib64/ruby/1.9.1/minitest/unit.rb:896:in `block in _run' /usr/lib64/ruby/1.9.1/minitest/unit.rb:895:in `each' /usr/lib64/ruby/1.9.1/minitest/unit.rb:895:in `_run' /usr/lib64/ruby/1.9.1/minitest/unit.rb:884:in `run' /usr/lib64/ruby/1.9.1/test/unit.rb:21:in `run' /usr/lib64/ruby/1.9.1/test/unit.rb:326:in `block (2 levels) in autorun' /usr/lib64/ruby/1.9.1/test/unit.rb:27:in `run_once' /usr/lib64/ruby/1.9.1/test/unit.rb:325:in `block in autorun' [[32mINFO [0m|#19131] Rendered layouts/_watch_and_search.html.erb (1.2ms) [[32mINFO [0m|#19131] Rendered vendor/neutral/layouts/_header.html.erb (2.3ms) [[32mINFO [0m|#19131] Rendered layouts/_personal_navigation.html.erb (1.1ms) [[32mINFO [0m|#19131] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|#19131] Rendered vendor/neutral/layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#19131] Completed 400 Bad Request in 519ms (Views: 9.8ms | ActiveRecord: 0.0ms | API: 497.2ms (XML: 0.3ms , View: 3.5ms , Backend: 0.0ms , DB: 9.4ms , HTTP: 332.1ms) | XML: 0.9ms) [[32mINFO [0m|#19131] Started POST "/user/logout" for 127.0.0.1 at 2012-10-08 12:26:41 +0200 [[32mINFO [0m|#19131] Processing by UserController#logout as HTML [[0;37mDEBUG[0m|#19131] Setting return_to: "/user/logout" [[0;37mDEBUG[0m|#19131] Authenticating with iChain mode: off [[32mINFO [0m|#19131] Authenticated request to "/user/logout" from tom [[32mINFO [0m|#19131] Logging out: tom [[32mINFO [0m|#19131] Redirected to http://example.org/ [[32mINFO [0m|#19131] Completed 302 Found in 1ms (ActiveRecord: 0.0ms | XML: 0.0ms) [[0;37mDEBUG[0m|#19131] [1m[35m (0.1ms)[0m rollback transaction + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/> + egrep 'perl|ruby' USER PID ACCESS COMMAND <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/>: jenkins ..c.. sh jenkins ..c.. sh jenkins ..c.. egrep + echo 'Remove log/tmp files to save disc space' Remove log/tmp files to save disc space + rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets + exit 1 Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui-testsuite #346
by admin＠opensuse.org 08 Oct '12

08 Oct '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/346/changes> Changes: [adrian] [dist] obs_mirror_project*: skip debug packages [adrian] [dist] drop double obs_mirror_project script. Go with the ruby version to avoid a new language here. [Stephan Kulow] [activexml] set the XML data we get to UTF-8 [Stephan Kulow] [api,webui] fix logging of entries with starting newlines [Stephan Kulow] [api,webui] update xmlhash dependency ------------------------------------------ [...truncated 237198 lines...] [[0;37mDEBUG[0m|# 6913] [REST] find( Buildresult, [{:project=>"Apache", :package=>"Taskjuggler", :view=>"status"}] ) [[0;37mDEBUG[0m|# 6913] http_do #1: method: get url: http://localhost:3203/build/Apache/_result?view=status&package=Taskjuggler [[0;37mDEBUG[0m|# 6913] RT {"view"=>nil, "db"=>0.753878, "backend"=>0, "xml"=>0, :runtime=>22.736, :all=>63.937256999999995} [[0;37mDEBUG[0m|# 6913] Buildresult.find( {:project=>"Apache", :package=>"Taskjuggler", :view=>"status"} ) did not find anything, return nil [[32mINFO [0m|# 6913] Rendered package/_buildstatus.html.erb (0.1ms) [[32mINFO [0m|# 6913] Completed 200 OK in 67ms (Views: 0.9ms | ActiveRecord: 0.0ms | API: 63.9ms (XML: 0.0ms , Backend: 0.0ms , DB: 0.8ms , HTTP: 41.2ms) | XML: 0.0ms) [[32mINFO [0m|# 6913] Started GET "/package/files?package=Taskjuggler&project=Apache" for 127.0.0.1 at 2012-10-08 12:12:50 +0200 [[32mINFO [0m|# 6913] Processing by PackageController#files as HTML [[32mINFO [0m|# 6913] Parameters: {"package"=>"Taskjuggler", "project"=>"Apache"} [[0;37mDEBUG[0m|# 6913] Setting return_to: "/package/files?package=Taskjuggler&project=Apache" [[0;37mDEBUG[0m|# 6913] Authenticating with iChain mode: off [[32mINFO [0m|# 6913] Anonymous request to /package/files?package=Taskjuggler&project=Apache [[0;37mDEBUG[0m|# 6913] Fetching request list from api [[0;37mDEBUG[0m|# 6913] --> direct_http url: #<URI::Generic:0x000000060c7b40 URL:http://localhost:3203/request?view=collection&states=review&roles=reviewer&reviewstates=new&project=Apache&package=Taskjuggler> [[0;37mDEBUG[0m|# 6913] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=review&roles=reviewer&… [[0;37mDEBUG[0m|# 6913] RT {"view"=>0.5317069999999999, "db"=>0.8700869999999999, "backend"=>0, "xml"=>0.060062000000000004, :runtime=>10.501, :all=>13.917287} [[0;37mDEBUG[0m|# 6913] Fetching request list from api [[0;37mDEBUG[0m|# 6913] --> direct_http url: #<URI::Generic:0x00000006227c88 URL:http://localhost:3203/request?view=collection&states=new&roles=target&project=Apache&package=Taskjuggler> [[0;37mDEBUG[0m|# 6913] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=new&roles=target&proje… [[0;37mDEBUG[0m|# 6913] RT {"view"=>0.434659, "db"=>0.420091, "backend"=>0, "xml"=>0.03889, :runtime=>6.158, :all=>49.381968} [[0;37mDEBUG[0m|# 6913] [REST] find( Directory, [{:project=>"Apache", :package=>"Taskjuggler"}] ) [[0;37mDEBUG[0m|# 6913] http_do #1: method: get url: http://localhost:3203/source/Apache/Taskjuggler? [[0;37mDEBUG[0m|# 6913] RT {"view"=>nil, "db"=>1.343986, "backend"=>0, "xml"=>0, :runtime=>23.525000000000002, :all=>62.140347999999996} [[0;37mDEBUG[0m|# 6913] Directory.find( {:project=>"Apache", :package=>"Taskjuggler"} ) did not find anything, return nil [[0;37mDEBUG[0m|# 6913] [REST] find( Directory, [{:project=>"Apache", :package=>"Taskjuggler"}] ) [[0;37mDEBUG[0m|# 6913] http_do #1: method: get url: http://localhost:3203/source/Apache/Taskjuggler? [[0;37mDEBUG[0m|# 6913] RT {"view"=>nil, "db"=>1.509935, "backend"=>0, "xml"=>0, :runtime=>23.539, :all=>63.496306000000004} [[0;37mDEBUG[0m|# 6913] Directory.find( {:project=>"Apache", :package=>"Taskjuggler"} ) did not find anything, return nil [[0;37mDEBUG[0m|# 6913] [REST] find( Directory, [{:project=>"Apache", :package=>"Taskjuggler", :expand=>"1"}] ) [[0;37mDEBUG[0m|# 6913] http_do #1: method: get url: http://localhost:3203/source/Apache/Taskjuggler?expand=1 [[0;37mDEBUG[0m|# 6913] RT {"view"=>nil, "db"=>1.8136679999999998, "backend"=>0, "xml"=>0, :runtime=>26.055, :all=>68.120115} [[0;37mDEBUG[0m|# 6913] Directory.find( {:project=>"Apache", :package=>"Taskjuggler", :expand=>"1"} ) did not find anything, return nil [[0;37mDEBUG[0m|# 6913] [REST] find( Service, [{:project=>"Apache", :package=>"Taskjuggler"}] ) [[0;37mDEBUG[0m|# 6913] http_do #1: method: get url: http://localhost:3203/source/Apache/Taskjuggler/_service [[0;37mDEBUG[0m|# 6913] RT {"view"=>nil, "db"=>1.516037, "backend"=>0, "xml"=>0, :runtime=>23.884, :all=>62.076119999999996} [[0;37mDEBUG[0m|# 6913] Service.find( {:project=>"Apache", :package=>"Taskjuggler"} ) did not find anything, return nil [[0;37mDEBUG[0m|# 6913] [REST] find( Directory, [{:project=>"Apache", :package=>"Taskjuggler"}] ) [[0;37mDEBUG[0m|# 6913] http_do #1: method: get url: http://localhost:3203/source/Apache/Taskjuggler? [[0;37mDEBUG[0m|# 6913] RT {"view"=>nil, "db"=>1.5513440000000003, "backend"=>0, "xml"=>0, :runtime=>23.449, :all=>63.212697999999996} [[0;37mDEBUG[0m|# 6913] Directory.find( {:project=>"Apache", :package=>"Taskjuggler"} ) did not find anything, return nil [[32mINFO [0m|# 6913] Rendered package/_tabs.html.erb (2.9ms) [[0;37mDEBUG[0m|# 6913] [REST] find( Directory, [{:project=>"Apache", :package=>"Taskjuggler"}] ) [[0;37mDEBUG[0m|# 6913] http_do #1: method: get url: http://localhost:3203/source/Apache/Taskjuggler? [[0;37mDEBUG[0m|# 6913] RT {"view"=>nil, "db"=>1.6005090000000002, "backend"=>0, "xml"=>0, :runtime=>22.776, :all=>66.89825400000001} [[0;37mDEBUG[0m|# 6913] Directory.find( {:project=>"Apache", :package=>"Taskjuggler"} ) did not find anything, return nil [[32mINFO [0m|# 6913] Rendered package/_services.html.erb (0.1ms) [[0;37mDEBUG[0m|# 6913] [REST] find( Directory, [{:project=>"Apache", :package=>"Taskjuggler"}] ) [[0;37mDEBUG[0m|# 6913] http_do #1: method: get url: http://localhost:3203/source/Apache/Taskjuggler? [[0;37mDEBUG[0m|# 6913] RT {"view"=>nil, "db"=>1.4174200000000001, "backend"=>0, "xml"=>0, :runtime=>23.463, :all=>66.29041400000001} [[0;37mDEBUG[0m|# 6913] Directory.find( {:project=>"Apache", :package=>"Taskjuggler"} ) did not find anything, return nil [[32mINFO [0m|# 6913] Rendered package/_files_view.html.erb (0.1ms) [[32mINFO [0m|# 6913] Rendered package/files.html.erb within layouts/application (141.1ms) [[32mINFO [0m|# 6913] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|# 6913] Rendered vendor/neutral/layouts/_header.html.erb (1.3ms) [[32mINFO [0m|# 6913] Rendered layouts/_personal_navigation.html.erb (1.2ms) [[32mINFO [0m|# 6913] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 6913] Rendered vendor/neutral/layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 6913] Completed 200 OK in 538ms (Views: 147.2ms | ActiveRecord: 0.0ms | API: 515.5ms (XML: 0.1ms , View: 1.0ms , Backend: 0.0ms , DB: 12.0ms , HTTP: 332.2ms) | XML: 0.2ms) [[32mINFO [0m|# 6913] Started GET "/package/repositories?package=Taskjuggler&project=Apache" for 127.0.0.1 at 2012-10-08 12:12:51 +0200 [[32mINFO [0m|# 6913] Processing by PackageController#repositories as HTML [[32mINFO [0m|# 6913] Parameters: {"package"=>"Taskjuggler", "project"=>"Apache"} [[0;37mDEBUG[0m|# 6913] Setting return_to: "/package/repositories?package=Taskjuggler&project=Apache" [[0;37mDEBUG[0m|# 6913] Authenticating with iChain mode: off [[32mINFO [0m|# 6913] Anonymous request to /package/repositories?package=Taskjuggler&project=Apache [[0;37mDEBUG[0m|# 6913] Fetching request list from api [[0;37mDEBUG[0m|# 6913] --> direct_http url: #<URI::Generic:0x000000069c18e0 URL:http://localhost:3203/request?view=collection&states=review&roles=reviewer&reviewstates=new&project=Apache&package=Taskjuggler> [[0;37mDEBUG[0m|# 6913] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=review&roles=reviewer&… [[0;37mDEBUG[0m|# 6913] RT {"view"=>0.5471739999999999, "db"=>1.0507939999999998, "backend"=>0, "xml"=>0.136715, :runtime=>10.255, :all=>13.380068} [[0;37mDEBUG[0m|# 6913] Fetching request list from api [[0;37mDEBUG[0m|# 6913] --> direct_http url: #<URI::Generic:0x00000006929c48 URL:http://localhost:3203/request?view=collection&states=new&roles=target&project=Apache&package=Taskjuggler> [[0;37mDEBUG[0m|# 6913] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=new&roles=target&proje… [[0;37mDEBUG[0m|# 6913] RT {"view"=>0.336521, "db"=>0.535811, "backend"=>0, "xml"=>0.042644, :runtime=>6.87, :all=>48.505248} [[0;37mDEBUG[0m|# 6913] [REST] find( Package, [{:project=>"Apache", :view=>"flagdetails", :name=>"Taskjuggler"}] ) [[0;37mDEBUG[0m|# 6913] http_do #1: method: get url: http://localhost:3203/source/Apache/Taskjuggler/_meta?view=flagdetails [[0;37mDEBUG[0m|# 6913] RT {"view"=>0.6719430000000001, "db"=>2.577915, "backend"=>0, "xml"=>0, :runtime=>95.104, :all=>135.60559} [[32mINFO [0m|# 6913] Rendered package/_tabs.html.erb (3.7ms) [[32mINFO [0m|# 6913] Rendered shared/_repositories_flag_table.html.erb (2.1ms) [[32mINFO [0m|# 6913] Rendered shared/_repositories_flag_table.html.erb (1.3ms) [[32mINFO [0m|# 6913] Rendered shared/_repositories_flag_table.html.erb (1.4ms) [[32mINFO [0m|# 6913] Rendered shared/_repositories_flag_table.html.erb (1.3ms) [[32mINFO [0m|# 6913] Rendered shared/_repositories.html.erb (8.2ms) [[32mINFO [0m|# 6913] Rendered package/repositories.html.erb within layouts/application (14.8ms) [[32mINFO [0m|# 6913] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|# 6913] Rendered vendor/neutral/layouts/_header.html.erb (1.0ms) [[32mINFO [0m|# 6913] Rendered layouts/_personal_navigation.html.erb (1.1ms) [[32mINFO [0m|# 6913] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 6913] Rendered vendor/neutral/layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 6913] Completed 200 OK in 224ms (Views: 20.8ms | ActiveRecord: 0.0ms | API: 197.5ms (XML: 0.2ms , View: 1.6ms , Backend: 0.0ms , DB: 4.2ms , HTTP: 85.3ms) | XML: 1.1ms) [[32mINFO [0m|# 6913] Started GET "/assets/icons-s9df5a73f38.png" for 127.0.0.1 at 2012-10-08 12:12:51 +0200 [[32mINFO [0m|# 6913] Served asset /icons-s9df5a73f38.png - 304 Not Modified (0ms) [[32mINFO [0m|# 6913] Started GET "/package/binaries?package=Taskjuggler&project=Apache&repository=SUSE_Linux_10.1" for 127.0.0.1 at 2012-10-08 12:12:52 +0200 [[32mINFO [0m|# 6913] Processing by PackageController#binaries as HTML [[32mINFO [0m|# 6913] Parameters: {"package"=>"Taskjuggler", "project"=>"Apache", "repository"=>"SUSE_Linux_10.1"} [[0;37mDEBUG[0m|# 6913] Setting return_to: "/package/binaries?package=Taskjuggler&project=Apache&repository=SUSE_Linux_10.1" [[0;37mDEBUG[0m|# 6913] Authenticating with iChain mode: off [[32mINFO [0m|# 6913] Anonymous request to /package/binaries?package=Taskjuggler&project=Apache&repository=SUSE_Linux_10.1 [[0;37mDEBUG[0m|# 6913] Fetching request list from api [[0;37mDEBUG[0m|# 6913] --> direct_http url: #<URI::Generic:0x00000006b45900 URL:http://localhost:3203/request?view=collection&states=review&roles=reviewer&reviewstates=new&project=Apache&package=Taskjuggler> [[0;37mDEBUG[0m|# 6913] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=review&roles=reviewer&… [[0;37mDEBUG[0m|# 6913] RT {"view"=>0.613589, "db"=>1.0027650000000001, "backend"=>0, "xml"=>0.07179200000000001, :runtime=>10.667, :all=>14.271599} [[0;37mDEBUG[0m|# 6913] Fetching request list from api [[0;37mDEBUG[0m|# 6913] --> direct_http url: #<URI::Generic:0x00000006e82668 URL:http://localhost:3203/request?view=collection&states=new&roles=target&project=Apache&package=Taskjuggler> [[0;37mDEBUG[0m|# 6913] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=new&roles=target&proje… [[0;37mDEBUG[0m|# 6913] RT {"view"=>0.511944, "db"=>0.523482, "backend"=>0, "xml"=>0.048093000000000004, :runtime=>7.71, :all=>50.687799} [[0;37mDEBUG[0m|# 6913] [REST] find( Buildresult, [{:project=>"Apache", :package=>"Taskjuggler", :repository=>"SUSE_Linux_10.1", :view=>["binarylist", "status"]}] ) [[0;37mDEBUG[0m|# 6913] http_do #1: method: get url: http://localhost:3203/build/Apache/_result?view=binarylist&view=status&pack… [[0;37mDEBUG[0m|# 6913] RT {"view"=>nil, "db"=>0.8621549999999999, "backend"=>0, "xml"=>0, :runtime=>18.566, :all=>57.871377} [[0;37mDEBUG[0m|# 6913] Buildresult.find( {:project=>"Apache", :package=>"Taskjuggler", :repository=>"SUSE_Linux_10.1", :view=>["binarylist", "status"]} ) did not find anything, return nil [[0;37mDEBUG[0m|# 6913] [REST] find( Package, [{:project=>"Apache", :view=>"flagdetails", :name=>"Taskjuggler"}] ) [[0;37mDEBUG[0m|# 6913] http_do #1: method: get url: http://localhost:3203/source/Apache/Taskjuggler/_meta?view=flagdetails [[0;37mDEBUG[0m|# 6913] RT {"view"=>0.6521440000000001, "db"=>2.73929, "backend"=>0, "xml"=>0, :runtime=>26.629, :all=>67.097812} [[32mINFO [0m|# 6913] Rendered package/_tabs.html.erb (4.2ms) [[32mINFO [0m|# 6913] Rendered shared/_download_repository_link.html.erb (0.7ms) [[32mINFO [0m|# 6913] Rendered package/binaries.html.erb within layouts/application (89.6ms) [[31mERROR[0m|# 6913] rescue_action: caught ActionView::Template::Error: undefined method `get' for {}:Hash [[0;37mDEBUG[0m|# 6913] ERROR: unknown; undefined method `get' for {}:Hash [[0;37mDEBUG[0m|# 6913] <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…>:20:in `block in _app_views_package_binaries_html_erb___3191855762880970729_56678880' /usr/lib64/ruby/gems/1.9.1/gems/xmlhash-1.3.0/lib/xmlhash.rb:24:in `elements' <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…>:14:in `_app_views_package_binaries_html_erb___3191855762880970729_56678880' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_view/template.rb:145:in `block in render' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/notifications.rb:125:in `instrument' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_view/template.rb:143:in `render' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_view/renderer/template_renderer.rb:47:in `block (2 levels) in render_template' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_view/renderer/abstract_renderer.rb:38:in `block in instrument' /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_view/renderer/abstract_renderer.rb:38:in `instrument' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_view/renderer/template_renderer.rb:46:in `block in render_template' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_view/renderer/template_renderer.rb:54:in `render_with_layout' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_view/renderer/template_renderer.rb:45:in `render_template' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_view/renderer/template_renderer.rb:18:in `render' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_view/renderer/renderer.rb:36:in `render_template' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_view/renderer/renderer.rb:17:in `render' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/rendering.rb:110:in `_render_template' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/streaming.rb:225:in `_render_template' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/rendering.rb:103:in `render_to_body' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/renderers.rb:28:in `render_to_body' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/compatibility.rb:50:in `render_to_body' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/rendering.rb:88:in `render' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/rendering.rb:16:in `render' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:40:in `block (2 levels) in render' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/core_ext/benchmark.rb:5:in `block in ms' /usr/lib64/ruby/1.9.1/benchmark.rb:295:in `realtime' /usr/lib64/ruby/gems/1.9.1/gems/activesupport-3.2.8/lib/active_support/core_ext/benchmark.rb:5:in `ms' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:40:in `block in render' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:83:in `cleanup_view_runtime' /usr/lib64/ruby/gems/1.9.1/gems/activerecord-3.2.8/lib/active_record/railties/controller_runtime.rb:24:in `cleanup_view_runtime' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:39:in `render' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/implicit_render.rb:10:in `default_render' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/implicit_render.rb:5: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:535:in `_run__2755893842913310419__process_action__1448137549392654511__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__4229050622068230015__call__3738547730555176930__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|# 6913] Rendered error.html.erb within layouts/application (0.7ms) [[32mINFO [0m|# 6913] Rendered layouts/_watch_and_search.html.erb (0.6ms) [[32mINFO [0m|# 6913] Rendered vendor/neutral/layouts/_header.html.erb (1.3ms) [[32mINFO [0m|# 6913] Rendered layouts/_personal_navigation.html.erb (1.3ms) [[32mINFO [0m|# 6913] Rendered layouts/_flash.html.erb (0.0ms) [[32mINFO [0m|# 6913] Rendered vendor/neutral/layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|# 6913] Completed 400 Bad Request in 316ms (Views: 7.8ms | ActiveRecord: 0.0ms | API: 189.9ms (XML: 0.1ms , View: 1.8ms , Backend: 0.0ms , DB: 5.1ms , HTTP: 126.4ms) | XML: 0.6ms) + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/> + egrep 'perl|ruby' USER PID ACCESS COMMAND <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/>: jenkins ..c.. sh jenkins ..c.. sh jenkins ..c.. egrep + echo 'Remove log/tmp files to save disc space' Remove log/tmp files to save disc space + rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets + exit 1 Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 1

[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui-testsuite #334
by admin＠opensuse.org 02 Oct '12

02 Oct '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/334/changes> Changes: [Stephan Kulow] [webui] fixes for the overview page for packages ------------------------------------------ [...truncated 242467 lines...] [[32mINFO [0m|#28705] Served asset /icons-sf0a0401b17.png - 304 Not Modified (0ms) [[32mINFO [0m|#28705] Started GET "/package/buildresult?project=home%3Auser2&package=HomePackage1-Duplicate&index=" for 127.0.0.1 at 2012-10-02 11:36:31 +0200 [[32mINFO [0m|#28705] Processing by PackageController#buildresult as */* [[32mINFO [0m|#28705] Parameters: {"project"=>"home:user2", "package"=>"HomePackage1-Duplicate", "index"=>""} [[0;37mDEBUG[0m|#28705] Setting return_to: "/package/buildresult?project=home%3Auser2&package=HomePackage1-Duplicate&index=" [[0;37mDEBUG[0m|#28705] Authenticating with iChain mode: off [[32mINFO [0m|#28705] Anonymous request to /package/buildresult?project=home%3Auser2&package=HomePackage1-Duplicate&index= [[0;37mDEBUG[0m|#28705] Project.find( 300 seconds, home:user2, {}) [[0;37mDEBUG[0m|#28705] Package.find( 1800 seconds, HomePackage1-Duplicate, {:project=><project name="home:user2"> <title>HomeProject Title</title> <description>Test generated empty home project for second user.</description> <person userid="user2" role="maintainer"/> <person userid="user2" role="bugowner"/> </project> }) [[0;37mDEBUG[0m|#28705] Buildresult.find( 300 seconds, {:project=><project name="home:user2"> <title>HomeProject Title</title> <description>Test generated empty home project for second user.</description> <person userid="user2" role="maintainer"/> <person userid="user2" role="bugowner"/> </project> , :package=><package name="HomePackage1-Duplicate" project="home:user2"> <title>Title for HomePackage1</title> <description>Empty home project package created by user1.</description> </package> , :view=>"status"}) [[0;37mDEBUG[0m|#28705] [REST] find( Buildresult, [{:project=>"home:user2", :package=>"HomePackage1-Duplicate", :view=>"status"}] ) [[0;37mDEBUG[0m|#28705] http_do #1: method: get url: http://localhost:3203/build/home:user2/_result?view=status&package=HomePack… [[0;37mDEBUG[0m|#28705] RT {"view"=>nil, "db"=>0.809048, "backend"=>0, "xml"=>0, :runtime=>77.142, :all=>82.560637} [[32mINFO [0m|#28705] Rendered package/_no_repositories.html.erb (0.4ms) [[32mINFO [0m|#28705] Rendered package/_buildstatus.html.erb (0.7ms) [[32mINFO [0m|#28705] Completed 200 OK in 88ms (Views: 1.2ms | ActiveRecord: 0.0ms | API: 82.6ms (XML: 0.0ms , Backend: 0.0ms , DB: 0.8ms , HTTP: 5.4ms) | XML: 0.2ms) [[32mINFO [0m|#28705] Started GET "/package/rpmlint_result?project=home%3Auser2&package=HomePackage1-Duplicate&index=" for 127.0.0.1 at 2012-10-02 11:36:31 +0200 [[32mINFO [0m|#28705] Processing by PackageController#rpmlint_result as */* [[32mINFO [0m|#28705] Parameters: {"project"=>"home:user2", "package"=>"HomePackage1-Duplicate", "index"=>""} [[0;37mDEBUG[0m|#28705] Setting return_to: "/package/rpmlint_result?project=home%3Auser2&package=HomePackage1-Duplicate&index=" [[0;37mDEBUG[0m|#28705] Authenticating with iChain mode: off [[32mINFO [0m|#28705] Anonymous request to /package/rpmlint_result?project=home%3Auser2&package=HomePackage1-Duplicate&index= [[0;37mDEBUG[0m|#28705] Project.find( 300 seconds, home:user2, {}) [[0;37mDEBUG[0m|#28705] Package.find( 1800 seconds, HomePackage1-Duplicate, {:project=><project name="home:user2"> <title>HomeProject Title</title> <description>Test generated empty home project for second user.</description> <person userid="user2" role="maintainer"/> <person userid="user2" role="bugowner"/> </project> }) [[0;37mDEBUG[0m|#28705] Buildresult.find( 300 seconds, {:project=><project name="home:user2"> <title>HomeProject Title</title> <description>Test generated empty home project for second user.</description> <person userid="user2" role="maintainer"/> <person userid="user2" role="bugowner"/> </project> , :package=><package name="HomePackage1-Duplicate" project="home:user2"> <title>Title for HomePackage1</title> <description>Empty home project package created by user1.</description> </package> , :view=>"status"}) [[32mINFO [0m|#28705] Rendered package/_no_repositories.html.erb (0.4ms) [[32mINFO [0m|#28705] Completed 200 OK in 6ms (Views: 4.6ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#28705] Started GET "/home?user=user2" for 127.0.0.1 at 2012-10-02 11:36:32 +0200 [[32mINFO [0m|#28705] Processing by HomeController#index as HTML [[32mINFO [0m|#28705] Parameters: {"user"=>"user2"} [[0;37mDEBUG[0m|#28705] Setting return_to: "/home?user=user2" [[0;37mDEBUG[0m|#28705] Authenticating with iChain mode: off [[32mINFO [0m|#28705] Anonymous request to /home?user=user2 [[32mINFO [0m|#28705] Redirected to http://localhost:3199/user/login?return_to_host=http%3A%2F%2Flocalhost&retu… [[32mINFO [0m|#28705] Filter chain halted as :require_login rendered or redirected [[32mINFO [0m|#28705] Completed 302 Found in 2ms (ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#28705] Started GET "/user/login?return_to_host=http%3A%2F%2Flocalhost&return_to_path=%2Fhome%3Fuser%3Duser2" for 127.0.0.1 at 2012-10-02 11:36:32 +0200 [[32mINFO [0m|#28705] Processing by UserController#login as HTML [[32mINFO [0m|#28705] Parameters: {"return_to_host"=>"http://localhost", "return_to_path"=>"/home?user=user2"} [[0;37mDEBUG[0m|#28705] Setting return_to: "/home?user=user2" [[0;37mDEBUG[0m|#28705] Authenticating with iChain mode: off [[32mINFO [0m|#28705] Anonymous request to /home?user=user2 [[32mINFO [0m|#28705] Rendered user/login.html.erb within layouts/application (0.7ms) [[32mINFO [0m|#28705] Rendered layouts/_watch_and_search.html.erb (0.4ms) [[32mINFO [0m|#28705] Rendered vendor/neutral/layouts/_header.html.erb (1.0ms) [[32mINFO [0m|#28705] Rendered layouts/_personal_navigation.html.erb (1.1ms) [[32mINFO [0m|#28705] Rendered layouts/_flash.html.erb (0.1ms) [[32mINFO [0m|#28705] Rendered vendor/neutral/layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#28705] Completed 200 OK in 7ms (Views: 5.9ms | ActiveRecord: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#28705] Started GET "/package/files?package=HomePackage1-Duplicate&project=home%3Auser2" for 127.0.0.1 at 2012-10-02 11:36:32 +0200 [[32mINFO [0m|#28705] Processing by PackageController#files as HTML [[32mINFO [0m|#28705] Parameters: {"package"=>"HomePackage1-Duplicate", "project"=>"home:user2"} [[0;37mDEBUG[0m|#28705] Setting return_to: "/package/files?package=HomePackage1-Duplicate&project=home%3Auser2" [[0;37mDEBUG[0m|#28705] Authenticating with iChain mode: off [[32mINFO [0m|#28705] Anonymous request to /package/files?package=HomePackage1-Duplicate&project=home%3Auser2 [[0;37mDEBUG[0m|#28705] Project.find( 300 seconds, home:user2, {}) [[0;37mDEBUG[0m|#28705] Package.find( 1800 seconds, HomePackage1-Duplicate, {:project=><project name="home:user2"> <title>HomeProject Title</title> <description>Test generated empty home project for second user.</description> <person userid="user2" role="maintainer"/> <person userid="user2" role="bugowner"/> </project> }) [[0;37mDEBUG[0m|#28705] Fetching request list from api [[0;37mDEBUG[0m|#28705] --> direct_http url: #<URI::Generic:0x00000006e23be0 URL:http://localhost:3203/request?view=collection&states=review&roles=reviewer&reviewstates=new&project=home%3Auser2&package=HomePackage1-Duplicate> [[0;37mDEBUG[0m|#28705] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=review&roles=reviewer&… [[0;37mDEBUG[0m|#28705] RT {"view"=>0.5025109999999999, "db"=>0.735209, "backend"=>0, "xml"=>0.053294, :runtime=>9.573, :all=>12.43686} [[0;37mDEBUG[0m|#28705] Fetching request list from api [[0;37mDEBUG[0m|#28705] --> direct_http url: #<URI::Generic:0x00000006ab76c8 URL:http://localhost:3203/request?view=collection&states=new&roles=target&project=home%3Auser2&package=HomePackage1-Duplicate> [[0;37mDEBUG[0m|#28705] http_do #1: method: GET url: http://localhost:3203/request?view=collection&states=new&roles=target&proje… [[0;37mDEBUG[0m|#28705] RT {"view"=>0.34943, "db"=>0.7162200000000001, "backend"=>0, "xml"=>0.044919, :runtime=>6.919, :all=>53.718327} [[0;37mDEBUG[0m|#28705] Directory.find( 1800 seconds, {:project=>"home:user2", :package=>"HomePackage1-Duplicate"}) [[0;37mDEBUG[0m|#28705] [REST] find( Directory, [{:project=>"home:user2", :package=>"HomePackage1-Duplicate"}] ) [[0;37mDEBUG[0m|#28705] http_do #1: method: get url: http://localhost:3203/source/home:user2/HomePackage1-Duplicate? [[0;37mDEBUG[0m|#28705] RT {"view"=>nil, "db"=>1.7212429999999999, "backend"=>0, "xml"=>0, :runtime=>28.675, :all=>71.129945} [[0;37mDEBUG[0m|#28705] Directory.find( 1800 seconds, {:project=>"home:user2", :package=>"HomePackage1-Duplicate"}) [[0;37mDEBUG[0m|#28705] [REST] find( Directory, [{:project=>"home:user2", :package=>"HomePackage1-Duplicate"}] ) [[0;37mDEBUG[0m|#28705] http_do #1: method: get url: http://localhost:3203/source/home:user2/HomePackage1-Duplicate? [[0;37mDEBUG[0m|#28705] RT {"view"=>nil, "db"=>1.809103, "backend"=>0, "xml"=>0, :runtime=>27.825, :all=>70.53166399999999} [[0;37mDEBUG[0m|#28705] Directory.find( nil, {:project=>"home:user2", :package=>"HomePackage1-Duplicate", :expand=>1, :rev=>"1"}) [[0;37mDEBUG[0m|#28705] [REST] find( Directory, [{:project=>"home:user2", :package=>"HomePackage1-Duplicate", :expand=>"1", :rev=>"1"}] ) [[0;37mDEBUG[0m|#28705] http_do #1: method: get url: http://localhost:3203/source/home:user2/HomePackage1-Duplicate?expand=1&rev… [[0;37mDEBUG[0m|#28705] RT {"view"=>nil, "db"=>1.9306930000000002, "backend"=>0, "xml"=>0, :runtime=>28.253, :all=>71.920923} [[0;37mDEBUG[0m|#28705] Directory.find( nil, {:project=>"home:user2", :package=>"HomePackage1-Duplicate", :expand=>0, :rev=>"1"}) [[0;37mDEBUG[0m|#28705] [REST] find( Directory, [{:project=>"home:user2", :package=>"HomePackage1-Duplicate", :expand=>"0", :rev=>"1"}] ) [[0;37mDEBUG[0m|#28705] http_do #1: method: get url: http://localhost:3203/source/home:user2/HomePackage1-Duplicate?expand=0&rev… [[0;37mDEBUG[0m|#28705] RT {"view"=>nil, "db"=>1.884612, "backend"=>0, "xml"=>0, :runtime=>28.646, :all=>68.61569200000001} [[0;37mDEBUG[0m|#28705] Link.find( 1800 seconds, {:project=><project name="home:user2"> <title>HomeProject Title</title> <description>Test generated empty home project for second user.</description> <person userid="user2" role="maintainer"/> <person userid="user2" role="bugowner"/> </project> , :package=><package name="HomePackage1-Duplicate" project="home:user2"> <title>Title for HomePackage1</title> <description>Empty home project package created by user1.</description> </package>, :rev=>"1"}) [[0;37mDEBUG[0m|#28705] Service.find( 1800 seconds, {:project=><project name="home:user2"> <title>HomeProject Title</title> <description>Test generated empty home project for second user.</description> <person userid="user2" role="maintainer"/> <person userid="user2" role="bugowner"/> </project> , :package=><package name="HomePackage1-Duplicate" project="home:user2"> <title>Title for HomePackage1</title> <description>Empty home project package created by user1.</description> </package>}) [[0;37mDEBUG[0m|#28705] [REST] find( Service, [{:project=>"home:user2", :package=>"HomePackage1-Duplicate"}] ) [[0;37mDEBUG[0m|#28705] http_do #1: method: get url: http://localhost:3203/source/home:user2/HomePackage1-Duplicate/_service [[0;37mDEBUG[0m|#28705] RT {"view"=>nil, "db"=>1.573187, "backend"=>0, "xml"=>0, :runtime=>25.04, :all=>65.14563} [[0;37mDEBUG[0m|#28705] Service.find( {:project=>"home:user2", :package=>"HomePackage1-Duplicate"} ) did not find anything, return nil [[31mERROR[0m|#28705] rescue_action: caught NameError: undefined local variable or method `forced_unexpand' for #<PackageController:0x00000006fef348> [[31mERROR[0m|#28705] Couldn't parse error xml: #<REXML::ParseException: No close tag for /PackageController:0x00000006fef348> /usr/lib64/ruby/1.9.1/rexml/parsers/treepa [[0;37mDEBUG[0m|#28705] ERROR: unknown; undefined local variable or method `forced_unexpand' for #<PackageController:0x00000006fef348> [[0;37mDEBUG[0m|#28705] <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/src/webu…>:165:in `files' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/implicit_render.rb:4:in `send_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:167:in `process_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/action_controller/metal/rendering.rb:10:in `process_action' /usr/lib64/ruby/gems/1.9.1/gems/actionpack-3.2.8/lib/abstract_controller/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:524:in `_run__2120106953382052421__process_action__589064157985815815__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__93265294073113600__call__1677348900581626612__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|#28705] Rendered error.html.erb within layouts/application (1.1ms) [[32mINFO [0m|#28705] Rendered layouts/_watch_and_search.html.erb (0.5ms) [[32mINFO [0m|#28705] Rendered vendor/neutral/layouts/_header.html.erb (1.7ms) [[32mINFO [0m|#28705] Rendered layouts/_personal_navigation.html.erb (51.1ms) [[32mINFO [0m|#28705] Rendered layouts/_flash.html.erb (0.3ms) [[32mINFO [0m|#28705] Rendered vendor/neutral/layouts/_sponsor.html.erb (0.0ms) [[32mINFO [0m|#28705] Completed 400 Bad Request in 490ms (Views: 59.6ms | ActiveRecord: 0.0ms | API: 413.5ms (XML: 0.1ms , View: 0.9ms , Backend: 0.0ms , DB: 10.4ms , HTTP: 258.6ms) | XML: 1.1ms) + echo + cleanup + echo 'Killing backend processes' Killing backend processes + egrep 'perl|ruby' + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/> USER PID ACCESS COMMAND <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/>: jenkins ..c.. sh jenkins ..c.. sh jenkins ..c.. egrep + echo 'Remove log/tmp files to save disc space' Remove log/tmp files to save disc space + rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets + exit 1 Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 7

[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui-testsuite #332
by admin＠opensuse.org 02 Oct '12

02 Oct '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/332/changes> Changes: [Stephan Kulow] [api] require newer xmlhash version [Stephan Kulow] [dist] travis needs an apt-get update ------------------------------------------ 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 6b69ba33946dfd6015f401fffe1b1f9bfbb8bee0 (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 aea7e01ffe96e87b22725dd1f98742a011aa07a5 (origin/master) Checking out Revision aea7e01ffe96e87b22725dd1f98742a011aa07a5 (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/hudson9023236626055651034.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 [31mCould not find gem 'xmlhash (>= 1.3.0) 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 #470
by admin＠opensuse.org 02 Oct '12

02 Oct '12

See <http://ci.opensuse.org/job/obs_master_testsuite_webui/470/changes> Changes: [Stephan Kulow] [api] require newer xmlhash version [Stephan Kulow] [dist] travis needs an apt-get update ------------------------------------------ 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 6b69ba33946dfd6015f401fffe1b1f9bfbb8bee0 (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 aea7e01ffe96e87b22725dd1f98742a011aa07a5 (origin/master) Checking out Revision aea7e01ffe96e87b22725dd1f98742a011aa07a5 (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/hudson3189031461830764139.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 [31mCould not find gem 'xmlhash (>= 1.3.0) 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_api #1067
by admin＠opensuse.org 02 Oct '12

02 Oct '12

See <http://ci.opensuse.org/job/obs_master_testsuite_api/1067/changes> Changes: [mls] [backend] implement archorder repotype hint [Stephan Kulow] [api] make sure the first line of the huge cat is the encoding [Stephan Kulow] [webui] do not crash if the API doesn't return all infos [Stephan Kulow] [api] require newer xmlhash version [Stephan Kulow] [dist] travis needs an apt-get update ------------------------------------------ Started by an SCM change Building in workspace <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/> Checkout:workspace / <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/> - hudson.remoting.LocalChannel@5ce65ce6 Using strategy: Default Last Built Revision: Revision d5ae97cc96b038a8fce4f1add512af853890880c (origin/master) Checkout:workspace / <http://ci.opensuse.org/job/obs_master_testsuite_api/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 aea7e01ffe96e87b22725dd1f98742a011aa07a5 (origin/master) Checking out Revision aea7e01ffe96e87b22725dd1f98742a011aa07a5 (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/hudson3026438514822369720.sh + sh dist/ci/obs_testsuite_api.sh ++ dirname dist/ci/obs_testsuite_api.sh + . dist/ci/obs_testsuite_common.sh + setup_git + echo 'Checking status' Checking status + git status # Not currently on any branch. # Untracked files: # (use "git add <file>..." to include in what will be committed) # # src/api/Gemfile.lock.orig # src/api/results/ # src/webui/public/themes/ nothing added to commit but untracked files present (use "git add" to track) + echo 'Setup git submodules' Setup git submodules + git submodule init + git submodule update + echo 'Setup backend configuration template' Setup backend configuration template + sed -i -e 's|my $hostname = .*$|my $hostname = '\''localhost'\'';|' -e 's|our $bsuser = '\''obsrun'\'';|our $bsuser = '\''jenkins'\'';|' -e 's|our $bsgroup = '\''obsrun'\'';|our $bsgroup = '\''jenkins'\'';|' src/backend/BSConfig.pm.template + cp src/backend/BSConfig.pm.template src/backend/BSConfig.pm + echo 'Set environment variables' Set environment variables + export RAILS_ENV=test + RAILS_ENV=test + ret=0 + setup_api + echo 'Enter API rails root' Enter API rails root + cd src/api + echo 'Setup database configuration' Setup database configuration + cp config/database.yml.example config/database.yml + sed -i 's|database: api|database: ci_api|' config/database.yml + echo 'Setup additional configuration' Setup additional configuration + cp config/options.yml.example config/options.yml + echo 'Install missing gems locally' Install missing gems locally + mv Gemfile.lock Gemfile.lock.orig + bundle list [31mCould not find gem 'xmlhash (>= 1.3.0) 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_api #1063
by admin＠opensuse.org 01 Oct '12

01 Oct '12

See <http://ci.opensuse.org/job/obs_master_testsuite_api/1063/changes> Changes: [Stephan Kulow] [api] speed up code_quality_test ------------------------------------------ [...truncated 129783 lines...] [[0;37mDEBUG[0m|#31770] Validate XML response: #<ActionDispatch::Response:0x00000004f6f398> took 0ms [[32mINFO [0m|#31770] Completed 200 OK in 67ms (Views: 0.9ms | ActiveRecord: 6.9ms | Backend: 11.1ms | XML: 0.3ms) [[32mINFO [0m|#31770] Started GET "/source/kde4/_meta" for 127.0.0.1 at 2012-10-01 15:00:22 +0200 [[32mINFO [0m|#31770] Processing by SourceController#project_meta as XML [[32mINFO [0m|#31770] Parameters: {"project"=>"kde4"} [[0;37mDEBUG[0m|#31770] Validate XML request: #<ActionDispatch::Request:0x00000006706df8> [[0;37mDEBUG[0m|#31770] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"project_meta", "project"=>"kde4", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#31770] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#31770] [1m[35mUser Load (0.3ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#31770] USER found: king [[0;37mDEBUG[0m|#31770] User king initialised [[0;37mDEBUG[0m|#31770] [1m[36mProjectUserRoleRelationship Load (0.2ms)[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|#31770] [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|#31770] [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|#31770] Validate XML response: #<ActionDispatch::Response:0x00000006706da8> took 2ms [[32mINFO [0m|#31770] Completed 200 OK in 10ms (Views: 0.5ms | ActiveRecord: 0.9ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#31770] Started GET "/source/kde4" for 127.0.0.1 at 2012-10-01 15:00:22 +0200 [[32mINFO [0m|#31770] Processing by SourceController#index_project as XML [[32mINFO [0m|#31770] Parameters: {"project"=>"kde4"} [[0;37mDEBUG[0m|#31770] Validate XML request: #<ActionDispatch::Request:0x00000006824898> [[0;37mDEBUG[0m|#31770] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#31770] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#31770] [1m[35mUser Load (0.3ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#31770] USER found: king [[0;37mDEBUG[0m|#31770] User king initialised [[0;37mDEBUG[0m|#31770] [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|#31770] [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|#31770] [1m[36mDbPackage Load (0.2ms)[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|#31770] [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|#31770] [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|#31770] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"response"} [[0;37mDEBUG[0m|#31770] Validate XML response: #<ActionDispatch::Response:0x00000006824848> took 0ms [[32mINFO [0m|#31770] Completed 200 OK in 10ms (Views: 0.4ms | ActiveRecord: 1.1ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#31770] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-10-01 15:00:22 +0200 [[32mINFO [0m|#31770] Processing by SourceController#package_meta as XML [[32mINFO [0m|#31770] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|#31770] Validate XML request: #<ActionDispatch::Request:0x00000006ad5d58> [[0;37mDEBUG[0m|#31770] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#31770] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#31770] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#31770] USER found: king [[0;37mDEBUG[0m|#31770] User king initialised [[0;37mDEBUG[0m|#31770] get_by_project_and_name {} [[0;37mDEBUG[0m|#31770] [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|#31770] [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|#31770] [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|#31770] [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|#31770] Validate XML response: #<ActionDispatch::Response:0x00000006ad5d30> took 2ms [[32mINFO [0m|#31770] Completed 200 OK in 11ms (Views: 0.4ms | ActiveRecord: 0.9ms | Backend: 0.0ms | XML: 0.3ms) [[32mINFO [0m|#31770] Started PUT "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-10-01 15:00:22 +0200 [[32mINFO [0m|#31770] Processing by SourceController#package_meta as XML [[32mINFO [0m|#31770] Parameters: {"<package name"=>"\"kdebase\" project=\"kde4\">\n <title>blub</title>\n <description>blub</description>\n</package>\n", "project"=>"kde4", "package"=>"kdebase"} [[33mWARN [0m|#31770] WARNING: Can't verify CSRF token authenticity [[0;37mDEBUG[0m|#31770] Validate XML request: #<ActionDispatch::Request:0x00000006bb13a8> [[0;37mDEBUG[0m|#31770] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#31770] [1m[36mUser Load (0.2ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#31770] USER found: king [[0;37mDEBUG[0m|#31770] User king initialised [[0;37mDEBUG[0m|#31770] [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|#31770] [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|#31770] [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|#31770] [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|#31770] get_by_project_and_name {} [[0;37mDEBUG[0m|#31770] [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|#31770] [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|#31770] [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|#31770] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1[0m [[0;37mDEBUG[0m|#31770] [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|#31770] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position[0m [[0;37mDEBUG[0m|#31770] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|#31770] [1m[35mFlag Load (0.1ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 [[0;37mDEBUG[0m|#31770] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|#31770] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#31770] [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 = 'kdebase' [[0;37mDEBUG[0m|#31770] [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|#31770] [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|#31770] [1m[36mPackageGroupRoleRelationship Load (0.1ms)[0m [1mSELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|#31770] [1m[35mCACHE (0.0ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position [[0;37mDEBUG[0m|#31770] [1m[36mDbPackage Load (0.2ms)[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|#31770] [1m[35m (0.2ms)[0m UPDATE `db_packages` SET `activity_index` = 85.3110061353901, `updated_at` = '2012-10-01 13:00:22', `update_counter` = 1 WHERE `db_packages`.`id` = 2 [[0;37mDEBUG[0m|#31770] ----------------- rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#31770] [1m[36mPackageUserRoleRelationship Load (0.2ms)[0m [1mSELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 2[0m [[0;37mDEBUG[0m|#31770] [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|#31770] ----------------- end rendering package kdebase ------------------------ [[0;37mDEBUG[0m|#31770] [backend] PUT: /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#31775] 2012-10-01 15:00:22 [4385]: PUT /source/kde4/kdebase/_meta?user=king [[0;37mDEBUG[0m|#31770] request took 0.009667451 [[0;37mDEBUG[0m|#31770] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#31770] Validate XML response: #<ActionDispatch::Response:0x00000006bb1380> took 1ms [[32mINFO [0m|#31770] Completed 200 OK in 43ms (Views: 1.0ms | ActiveRecord: 3.0ms | Backend: 9.7ms | XML: 0.0ms) [[32mINFO [0m|#31770] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-10-01 15:00:22 +0200 [[32mINFO [0m|#31770] Processing by SourceController#package_meta as XML [[32mINFO [0m|#31770] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [[0;37mDEBUG[0m|#31770] Validate XML request: #<ActionDispatch::Request:0x00000006f1ca88> [[0;37mDEBUG[0m|#31770] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#31770] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#31770] [1m[35mUser Load (0.3ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#31770] USER found: king [[0;37mDEBUG[0m|#31770] User king initialised [[0;37mDEBUG[0m|#31770] get_by_project_and_name {} [[0;37mDEBUG[0m|#31770] [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|#31770] [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|#31770] [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|#31770] [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|#31770] Validate XML response: #<ActionDispatch::Response:0x00000006f1ca60> took 2ms [[32mINFO [0m|#31770] Completed 200 OK in 12ms (Views: 0.5ms | ActiveRecord: 1.1ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#31770] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-10-01 15:00:22 +0200 [[32mINFO [0m|#31770] Processing by SourceController#package_meta as XML [[32mINFO [0m|#31770] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#31770] Validate XML request: #<ActionDispatch::Request:0x00000006f9b3b0> [[0;37mDEBUG[0m|#31770] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#31770] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#31770] [1m[36mUser Load (0.3ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m [[0;37mDEBUG[0m|#31770] USER found: king [[0;37mDEBUG[0m|#31770] User king initialised [[0;37mDEBUG[0m|#31770] get_by_project_and_name {} [[0;37mDEBUG[0m|#31770] [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|#31770] [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|#31770] [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|#31770] [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|#31770] Validate XML response: #<ActionDispatch::Response:0x00000006f9b338> took 1ms [[32mINFO [0m|#31770] Completed 200 OK in 12ms (Views: 0.5ms | ActiveRecord: 1.1ms | Backend: 0.0ms | XML: 0.0ms) [[32mINFO [0m|#31770] Started PUT "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-10-01 15:00:22 +0200 [[32mINFO [0m|#31770] Processing by SourceController#package_meta as XML [[32mINFO [0m|#31770] 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|#31770] WARNING: Can't verify CSRF token authenticity [[0;37mDEBUG[0m|#31770] Validate XML request: #<ActionDispatch::Request:0x000000070eca48> [[0;37mDEBUG[0m|#31770] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#31770] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#31770] USER found: king [[0;37mDEBUG[0m|#31770] User king initialised [[0;37mDEBUG[0m|#31770] [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|#31770] [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|#31770] [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|#31770] [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|#31770] get_by_project_and_name {} [[0;37mDEBUG[0m|#31770] [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|#31770] [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|#31770] [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|#31770] [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|#31770] [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|#31770] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position [[0;37mDEBUG[0m|#31770] flagcheck: package has default state, checking project [[0;37mDEBUG[0m|#31770] [1m[36mFlag Load (0.1ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2[0m [[0;37mDEBUG[0m|#31770] disabled_for sourceaccess repo: arch: state:enable [[0;37mDEBUG[0m|#31770] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1 [[0;37mDEBUG[0m|#31770] [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 = 'kdelibs' [0m [[0;37mDEBUG[0m|#31770] [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|#31770] [1m[36mDbProject Load (0.2ms)[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|#31770] [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|#31770] [1m[36mDbProject Load (0.1ms)[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|#31770] [1m[35mPackageUserRoleRelationship Load (0.1ms)[0m SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|#31770] [1m[36mUser Load (0.1ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`id` = 4 LIMIT 1[0m [[0;37mDEBUG[0m|#31770] [1m[35mRole Load (0.1ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1 [[0;37mDEBUG[0m|#31770] [1m[36mUser Load (0.1ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`id` = 42 LIMIT 1[0m [[0;37mDEBUG[0m|#31770] [1m[35mRole Load (0.1ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 6 LIMIT 1 [[0;37mDEBUG[0m|#31770] [1m[36mUser Load (0.1ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1[0m [[0;37mDEBUG[0m|#31770] [1m[35mPackageUserRoleRelationship Load (0.1ms)[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|#31770] [1m[36mSQL (0.2ms)[0m [1mINSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 3)[0m [[0;37mDEBUG[0m|#31770] [1m[35mUser Load (0.1ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1 [[0;37mDEBUG[0m|#31770] [1m[36mPackageUserRoleRelationship Load (0.1ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 4 AND bs_user_id = 267) LIMIT 1[0m [[0;37mDEBUG[0m|#31770] [1m[35mSQL (0.1ms)[0m INSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 4) [[0;37mDEBUG[0m|#31770] [1m[36mPackageGroupRoleRelationship Load (0.1ms)[0m [1mSELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|#31770] [1m[35mGroup Load (0.1ms)[0m SELECT `groups`.* FROM `groups` WHERE `groups`.`id` = 99 LIMIT 1 [[0;37mDEBUG[0m|#31770] [1m[36mRole Load (0.1ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1[0m [[0;37mDEBUG[0m|#31770] [1m[35mGroup Load (0.2ms)[0m SELECT `groups`.* FROM `groups` WHERE `groups`.`title` = 'test_group' LIMIT 1 [[0;37mDEBUG[0m|#31770] [1m[36mPackageGroupRoleRelationship Load (0.1ms)[0m [1mSELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE (db_package_id = 1 AND role_id = 7 AND bs_group_id = 99) LIMIT 1[0m [[0;37mDEBUG[0m|#31770] [1m[35mSQL (88.1ms)[0m INSERT INTO `package_group_role_relationships` (`bs_group_id`, `db_package_id`, `role_id`) VALUES (99, 1, 7) [[0;37mDEBUG[0m|#31770] [1m[36mFlag Load (0.4ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position[0m [[0;37mDEBUG[0m|#31770] [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|#31770] [1m[36m (0.1ms)[0m [1mSELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|#31770] [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|#31770] [1m[36mDbPackage Load (0.2ms)[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|#31770] [1m[35m (0.2ms)[0m UPDATE `db_packages` SET `description` = 'Changed description', `develpackage_id` = 10095, `activity_index` = 85.3110061353901, `updated_at` = '2012-10-01 13:00:22', `update_counter` = 1 WHERE `db_packages`.`id` = 1 [[0;37mDEBUG[0m|#31770] ----------------- rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#31770] [1m[36mPackageUserRoleRelationship Load (0.2ms)[0m [1mSELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 1[0m [[0;37mDEBUG[0m|#31770] [1m[35mPackageGroupRoleRelationship Load (0.2ms)[0m SELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 1 [[0;37mDEBUG[0m|#31770] ----------------- end rendering package kdelibs ------------------------ [[0;37mDEBUG[0m|#31770] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#31775] 2012-10-01 15:00:22 [4386]: PUT /source/kde4/kdelibs/_meta?user=king [[0;37mDEBUG[0m|#31770] request took 0.013957678 [[0;37mDEBUG[0m|#31770] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m [[0;37mDEBUG[0m|#31770] Validate XML response: #<ActionDispatch::Response:0x000000070eca20> took 1ms [[32mINFO [0m|#31770] Completed 200 OK in 165ms (Views: 0.9ms | ActiveRecord: 94.5ms | Backend: 14.0ms | XML: 0.3ms) [[32mINFO [0m|#31770] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-10-01 15:00:22 +0200 [[32mINFO [0m|#31770] Processing by SourceController#package_meta as XML [[32mINFO [0m|#31770] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [[0;37mDEBUG[0m|#31770] Validate XML request: #<ActionDispatch::Request:0x00000004166aa8> [[0;37mDEBUG[0m|#31770] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [[0;37mDEBUG[0m|#31770] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [[0;37mDEBUG[0m|#31770] [1m[35mUser Load (0.3ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [[0;37mDEBUG[0m|#31770] USER found: king [[0;37mDEBUG[0m|#31770] User king initialised [[0;37mDEBUG[0m|#31770] get_by_project_and_name {} [[0;37mDEBUG[0m|#31770] [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|#31770] [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|#31770] [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|#31770] [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|#31770] Validate XML response: #<ActionDispatch::Response:0x00000004166a30> took 2ms [[32mINFO [0m|#31770] Completed 200 OK in 13ms (Views: 0.5ms | ActiveRecord: 1.1ms | Backend: 0.0ms | XML: 0.0ms) [[0;37mDEBUG[0m|#31770] [1m[36m (4.7ms)[0m [1mROLLBACK[0m [[0;37mDEBUG[0m|#31770] kill 31775 + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/> + egrep 'perl|ruby' USER PID ACCESS COMMAND <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/>: jenkins ..c.. sh jenkins ..c.. sh jenkins ..c.. sh + echo 'Remove log/tmp files to save disc space' Remove log/tmp files to save disc space + rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets 'src/webui/log/*' src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets + exit 1 Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org To contact the owner, e-mail: obs-tests+owner(a)opensuse.org

1 2