See http://ci.opensuse.org/job/obs_master_testsuite_api/1011/changes Changes: [mls] [backend] support project binary versions for remote projects, support package filtering ------------------------------------------ [...truncated 129135 lines...] [DEBUG|# 4918] Validate XML response: #ActionDispatch::Response:0x00000006c070c8 took 1ms [INFO |# 4918] Completed 200 OK in 78ms (Views: 1.2ms | ActiveRecord: 9.2ms | Backend: 18.2ms | XML: 0.3ms) [INFO |# 4918] Started GET "/source/kde4/_meta" for 127.0.0.1 at 2012-08-31 17:24:30 +0200 [INFO |# 4918] Processing by SourceController#project_meta as XML [INFO |# 4918] Parameters: {"project"=>"kde4"} [DEBUG|# 4918] Validate XML request: #ActionDispatch::Request:0x00000005f46be0 [DEBUG|# 4918] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"project_meta", "project"=>"kde4", "method"=>"GET", "type"=>"request"} [DEBUG|# 4918] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [DEBUG|# 4918] User Load (0.4ms) SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [DEBUG|# 4918] USER found: king [DEBUG|# 4918] User king initialised [DEBUG|# 4918] ProjectUserRoleRelationship Load (0.3ms) 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 [DEBUG|# 4918] Role Load (0.3ms) 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 [DEBUG|# 4918] DbProject Load (0.3ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] Validate XML response: #ActionDispatch::Response:0x00000005f46848 took 2ms [INFO |# 4918] Completed 200 OK in 11ms (Views: 0.6ms | ActiveRecord: 1.4ms | Backend: 0.0ms | XML: 0.0ms) [INFO |# 4918] Started GET "/source/kde4" for 127.0.0.1 at 2012-08-31 17:24:30 +0200 [INFO |# 4918] Processing by SourceController#index_project as XML [INFO |# 4918] Parameters: {"project"=>"kde4"} [DEBUG|# 4918] Validate XML request: #ActionDispatch::Request:0x000000062fcc30 [DEBUG|# 4918] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"request"} [DEBUG|# 4918] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [DEBUG|# 4918] User Load (0.4ms) SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [DEBUG|# 4918] USER found: king [DEBUG|# 4918] User king initialised [DEBUG|# 4918] Role Load (0.4ms) 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 [DEBUG|# 4918] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] DbPackage Load (0.3ms) SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND (db_packages.db_project_id not in (0)) [DEBUG|# 4918] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] CACHE (0.0ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"response"} [DEBUG|# 4918] Validate XML response: #ActionDispatch::Response:0x000000062fcc08 took 0ms [INFO |# 4918] Completed 200 OK in 10ms (Views: 0.5ms | ActiveRecord: 1.5ms | Backend: 0.0ms | XML: 0.0ms) [INFO |# 4918] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-08-31 17:24:30 +0200 [INFO |# 4918] Processing by SourceController#package_meta as XML [INFO |# 4918] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [DEBUG|# 4918] Validate XML request: #ActionDispatch::Request:0x00000006778bb0 [DEBUG|# 4918] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [DEBUG|# 4918] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [DEBUG|# 4918] User Load (0.4ms) SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [DEBUG|# 4918] USER found: king [DEBUG|# 4918] User king initialised [DEBUG|# 4918] get_by_project_and_name {} [DEBUG|# 4918] Role Load (0.4ms) 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 [DEBUG|# 4918] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] DbPackage Load (0.3ms) 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 [DEBUG|# 4918] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] Validate XML response: #ActionDispatch::Response:0x00000006778b10 took 2ms [INFO |# 4918] Completed 200 OK in 12ms (Views: 0.5ms | ActiveRecord: 1.5ms | Backend: 0.0ms | XML: 0.3ms) [INFO |# 4918] Started PUT "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-08-31 17:24:30 +0200 [INFO |# 4918] Processing by SourceController#package_meta as XML [INFO |# 4918] Parameters: {""\"kdebase\" project=\"kde4\">\n <title>blub</title>\n <description>blub</description>\n</package>\n", "project"=>"kde4", "package"=>"kdebase"} [WARN |# 4918] WARNING: Can't verify CSRF token authenticity [DEBUG|# 4918] Validate XML request: #ActionDispatch::Request:0x00000006859070 [DEBUG|# 4918] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [DEBUG|# 4918] User Load (0.4ms) SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [DEBUG|# 4918] USER found: king [DEBUG|# 4918] User king initialised [DEBUG|# 4918] Role Load (0.4ms) 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 [DEBUG|# 4918] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] DbPackage Load (0.3ms) 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 [DEBUG|# 4918] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] get_by_project_and_name {} [DEBUG|# 4918] CACHE (0.0ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] CACHE (0.0ms) 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 [DEBUG|# 4918] CACHE (0.0ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] Flag Load (0.4ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1 [DEBUG|# 4918] Flag Load (0.3ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1 [DEBUG|# 4918] Flag Load (0.2ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position [DEBUG|# 4918] flagcheck: package has default state, checking project [DEBUG|# 4918] Flag Load (0.2ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 [DEBUG|# 4918] disabled_for sourceaccess repo: arch: state:enable [DEBUG|# 4918]  (0.1ms) SAVEPOINT active_record_1 [DEBUG|# 4918] DbPackage Load (0.3ms)  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'  [DEBUG|# 4918] CACHE (0.0ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] PackageUserRoleRelationship Load (0.2ms) SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 2 [DEBUG|# 4918] PackageGroupRoleRelationship Load (0.2ms) SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 2 [DEBUG|# 4918] CACHE (0.0ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position [DEBUG|# 4918] DbPackage Load (0.3ms) 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 [DEBUG|# 4918]  (0.3ms) UPDATE `db_packages` SET `activity_index` = 85.3110061353901, `updated_at` = '2012-08-31 15:24:30', `update_counter` = 1 WHERE `db_packages`.`id` = 2 [DEBUG|# 4918] ----------------- rendering package kdebase ------------------------ [DEBUG|# 4918] PackageUserRoleRelationship Load (0.3ms) 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 [DEBUG|# 4918] PackageGroupRoleRelationship Load (0.3ms) 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 [DEBUG|# 4918] ----------------- end rendering package kdebase ------------------------ [DEBUG|# 4918] [backend] PUT: /source/kde4/kdebase/_meta?user=king [DEBUG|# 4923] 2012-08-31 17:24:30 [10338]: PUT /source/kde4/kdebase/_meta?user=king [DEBUG|# 4918] request took 0.015846357 [DEBUG|# 4918]  (0.2ms) RELEASE SAVEPOINT active_record_1 [DEBUG|# 4918] Validate XML response: #ActionDispatch::Response:0x00000006859048 took 1ms [INFO |# 4918] Completed 200 OK in 51ms (Views: 1.2ms | ActiveRecord: 4.8ms | Backend: 15.8ms | XML: 0.1ms) [INFO |# 4918] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-08-31 17:24:30 +0200 [INFO |# 4918] Processing by SourceController#package_meta as XML [INFO |# 4918] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [DEBUG|# 4918] Validate XML request: #ActionDispatch::Request:0x000000068fe480 [DEBUG|# 4918] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [DEBUG|# 4918] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [DEBUG|# 4918] User Load (0.4ms) SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [DEBUG|# 4918] USER found: king [DEBUG|# 4918] User king initialised [DEBUG|# 4918] get_by_project_and_name {} [DEBUG|# 4918] Role Load (0.4ms) 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 [DEBUG|# 4918] DbProject Load (0.3ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] DbPackage Load (0.3ms) 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 [DEBUG|# 4918] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] Validate XML response: #ActionDispatch::Response:0x000000068fe458 took 2ms [INFO |# 4918] Completed 200 OK in 13ms (Views: 0.6ms | ActiveRecord: 1.6ms | Backend: 0.0ms | XML: 0.0ms) [INFO |# 4918] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-08-31 17:24:30 +0200 [INFO |# 4918] Processing by SourceController#package_meta as XML [INFO |# 4918] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [DEBUG|# 4918] Validate XML request: #ActionDispatch::Request:0x00000006c39258 [DEBUG|# 4918] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [DEBUG|# 4918] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [DEBUG|# 4918] User Load (0.4ms) SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [DEBUG|# 4918] USER found: king [DEBUG|# 4918] User king initialised [DEBUG|# 4918] get_by_project_and_name {} [DEBUG|# 4918] Role Load (0.4ms) 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 [DEBUG|# 4918] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] DbPackage Load (0.3ms) 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 [DEBUG|# 4918] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] Validate XML response: #ActionDispatch::Response:0x00000006c39230 took 2ms [INFO |# 4918] Completed 200 OK in 12ms (Views: 0.6ms | ActiveRecord: 1.5ms | Backend: 0.0ms | XML: 0.0ms) [INFO |# 4918] Started PUT "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-08-31 17:24:30 +0200 [INFO |# 4918] Processing by SourceController#package_meta as XML [INFO |# 4918] Parameters: {""\"kdelibs\" project=\"kde4\">\n <title>blub</title>\n <description>Changed description</description>\n \n \n \n \n \n \n \n <build>\n <enable/>\n </build>\n</package>\n", "project"=>"kde4", "package"=>"kdelibs"} [WARN |# 4918] WARNING: Can't verify CSRF token authenticity [DEBUG|# 4918] Validate XML request: #ActionDispatch::Request:0x00000006cd2610 [DEBUG|# 4918] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [DEBUG|# 4918] User Load (0.4ms) SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [DEBUG|# 4918] USER found: king [DEBUG|# 4918] User king initialised [DEBUG|# 4918] Role Load (0.4ms) 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 [DEBUG|# 4918] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] DbPackage Load (0.3ms) 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 [DEBUG|# 4918] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] get_by_project_and_name {} [DEBUG|# 4918] CACHE (0.0ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] CACHE (0.0ms) 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 [DEBUG|# 4918] CACHE (0.0ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] Flag Load (0.3ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1 [DEBUG|# 4918] Flag Load (0.3ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1 [DEBUG|# 4918] Flag Load (0.2ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position [DEBUG|# 4918] flagcheck: package has default state, checking project [DEBUG|# 4918] Flag Load (0.2ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 [DEBUG|# 4918] disabled_for sourceaccess repo: arch: state:enable [DEBUG|# 4918]  (0.1ms) SAVEPOINT active_record_1 [DEBUG|# 4918] DbPackage Load (0.3ms) 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' [DEBUG|# 4918] CACHE (0.0ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'686f6d653a49676779' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] DbPackage Load (0.3ms) 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 [DEBUG|# 4918] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 502 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] PackageUserRoleRelationship Load (0.2ms) SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 1 [DEBUG|# 4918] User Load (0.2ms) SELECT `users`.* FROM `users` WHERE `users`.`id` = 4 LIMIT 1 [DEBUG|# 4918] Role Load (0.2ms) SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1 [DEBUG|# 4918] User Load (0.2ms) SELECT `users`.* FROM `users` WHERE `users`.`id` = 42 LIMIT 1 [DEBUG|# 4918] Role Load (0.2ms) SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 6 LIMIT 1 [DEBUG|# 4918] User Load (0.2ms) SELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1 [DEBUG|# 4918] PackageUserRoleRelationship Load (0.2ms) 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 [DEBUG|# 4918] SQL (0.2ms) INSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 3) [DEBUG|# 4918] User Load (0.2ms) SELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1 [DEBUG|# 4918] PackageUserRoleRelationship Load (0.2ms) 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 [DEBUG|# 4918] SQL (0.2ms) INSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 4) [DEBUG|# 4918] PackageGroupRoleRelationship Load (0.2ms) SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 1 [DEBUG|# 4918] Group Load (0.2ms) SELECT `groups`.* FROM `groups` WHERE `groups`.`id` = 99 LIMIT 1 [DEBUG|# 4918] Role Load (0.2ms) SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1 [DEBUG|# 4918] Group Load (0.2ms) SELECT `groups`.* FROM `groups` WHERE `groups`.`title` = 'test_group' LIMIT 1 [DEBUG|# 4918] PackageGroupRoleRelationship Load (0.2ms) 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 [DEBUG|# 4918] SQL (0.2ms) INSERT INTO `package_group_role_relationships` (`bs_group_id`, `db_package_id`, `role_id`) VALUES (99, 1, 7) [DEBUG|# 4918] Flag Load (0.2ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position [DEBUG|# 4918] DbPackage Load (0.2ms) SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`id` = 1 AND (db_packages.db_project_id not in (0)) LIMIT 1 [DEBUG|# 4918]  (0.2ms) SELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1 [DEBUG|# 4918] SQL (0.2ms) INSERT INTO `flags` (`architecture_id`, `db_package_id`, `db_project_id`, `flag`, `position`, `repo`, `status`) VALUES (NULL, 1, NULL, 'build', 1, NULL, 'enable') [DEBUG|# 4918] DbPackage Load (0.3ms) 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 [DEBUG|# 4918]  (0.3ms) UPDATE `db_packages` SET `description` = 'Changed description', `develpackage_id` = 10095, `activity_index` = 85.3110061353901, `updated_at` = '2012-08-31 15:24:30', `update_counter` = 1 WHERE `db_packages`.`id` = 1 [DEBUG|# 4918] ----------------- rendering package kdelibs ------------------------ [DEBUG|# 4918] PackageUserRoleRelationship Load (0.3ms) 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 [DEBUG|# 4918] PackageGroupRoleRelationship Load (0.3ms) 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 [DEBUG|# 4918] ----------------- end rendering package kdelibs ------------------------ [DEBUG|# 4918] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [DEBUG|# 4923] 2012-08-31 17:24:30 [10339]: PUT /source/kde4/kdelibs/_meta?user=king [DEBUG|# 4918] request took 0.017733113 [DEBUG|# 4918]  (0.2ms) RELEASE SAVEPOINT active_record_1 [DEBUG|# 4918] Validate XML response: #ActionDispatch::Response:0x00000006cd25c0 took 1ms [INFO |# 4918] Completed 200 OK in 80ms (Views: 1.2ms | ActiveRecord: 9.5ms | Backend: 17.7ms | XML: 0.4ms) [INFO |# 4918] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-08-31 17:24:30 +0200 [INFO |# 4918] Processing by SourceController#package_meta as XML [INFO |# 4918] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [DEBUG|# 4918] Validate XML request: #ActionDispatch::Request:0x0000000727ed20 [DEBUG|# 4918] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [DEBUG|# 4918] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [DEBUG|# 4918] User Load (0.4ms) SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [DEBUG|# 4918] USER found: king [DEBUG|# 4918] User king initialised [DEBUG|# 4918] get_by_project_and_name {} [DEBUG|# 4918] Role Load (0.5ms) 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 [DEBUG|# 4918] DbProject Load (0.3ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] DbPackage Load (0.3ms) 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 [DEBUG|# 4918] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|# 4918] Validate XML response: #ActionDispatch::Response:0x0000000727ec58 took 149ms [INFO |# 4918] Completed 200 OK in 160ms (Views: 0.6ms | ActiveRecord: 1.6ms | Backend: 0.0ms | XML: 0.0ms) [DEBUG|# 4918]  (227.9ms) ROLLBACK [DEBUG|# 4918] kill 4923 + 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@opensuse.org To contact the owner, e-mail: obs-tests+owner@opensuse.org

See http://ci.opensuse.org/job/obs_master_testsuite_api/1013/changes Changes: [Stephan Kulow] [dist] do not fail ci jobs if the gems are not yet uptodate ------------------------------------------ [...truncated 129204 lines...] [DEBUG|#10975] Validate XML response: #ActionDispatch::Response:0x00000005f434b8 took 1ms [INFO |#10975] Completed 200 OK in 75ms (Views: 1.1ms | ActiveRecord: 9.6ms | Backend: 15.8ms | XML: 0.4ms) [INFO |#10975] Started GET "/source/kde4/_meta" for 127.0.0.1 at 2012-09-03 08:41:43 +0200 [INFO |#10975] Processing by SourceController#project_meta as XML [INFO |#10975] Parameters: {"project"=>"kde4"} [DEBUG|#10975] Validate XML request: #ActionDispatch::Request:0x00000006a98d18 [DEBUG|#10975] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"project_meta", "project"=>"kde4", "method"=>"GET", "type"=>"request"} [DEBUG|#10975] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [DEBUG|#10975] User Load (0.4ms) SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [DEBUG|#10975] USER found: king [DEBUG|#10975] User king initialised [DEBUG|#10975] ProjectUserRoleRelationship Load (0.3ms) 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 [DEBUG|#10975] Role Load (0.4ms) 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 [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] Validate XML response: #ActionDispatch::Response:0x00000006a98cf0 took 2ms [INFO |#10975] Completed 200 OK in 10ms (Views: 0.6ms | ActiveRecord: 1.4ms | Backend: 0.0ms | XML: 0.0ms) [INFO |#10975] Started GET "/source/kde4" for 127.0.0.1 at 2012-09-03 08:41:43 +0200 [INFO |#10975] Processing by SourceController#index_project as XML [INFO |#10975] Parameters: {"project"=>"kde4"} [DEBUG|#10975] Validate XML request: #ActionDispatch::Request:0x00000006b809d8 [DEBUG|#10975] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"request"} [DEBUG|#10975] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [DEBUG|#10975] User Load (0.4ms) SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [DEBUG|#10975] USER found: king [DEBUG|#10975] User king initialised [DEBUG|#10975] Role Load (0.4ms) 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 [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] DbPackage Load (0.3ms) SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND (db_packages.db_project_id not in (0)) [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] CACHE (0.0ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"response"} [DEBUG|#10975] Validate XML response: #ActionDispatch::Response:0x00000006b809b0 took 0ms [INFO |#10975] Completed 200 OK in 10ms (Views: 0.5ms | ActiveRecord: 1.6ms | Backend: 0.0ms | XML: 0.0ms) [INFO |#10975] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-09-03 08:41:43 +0200 [INFO |#10975] Processing by SourceController#package_meta as XML [INFO |#10975] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [DEBUG|#10975] Validate XML request: #ActionDispatch::Request:0x00000006c5c190 [DEBUG|#10975] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [DEBUG|#10975] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [DEBUG|#10975] User Load (0.4ms) SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [DEBUG|#10975] USER found: king [DEBUG|#10975] User king initialised [DEBUG|#10975] get_by_project_and_name {} [DEBUG|#10975] Role Load (0.4ms) 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 [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] DbPackage Load (0.3ms) 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 [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] Validate XML response: #ActionDispatch::Response:0x00000006c5c168 took 2ms [INFO |#10975] Completed 200 OK in 12ms (Views: 0.5ms | ActiveRecord: 1.5ms | Backend: 0.0ms | XML: 0.3ms) [INFO |#10975] Started PUT "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-09-03 08:41:43 +0200 [INFO |#10975] Processing by SourceController#package_meta as XML [INFO |#10975] Parameters: {""\"kdebase\" project=\"kde4\">\n <title>blub</title>\n <description>blub</description>\n</package>\n", "project"=>"kde4", "package"=>"kdebase"} [WARN |#10975] WARNING: Can't verify CSRF token authenticity [DEBUG|#10975] Validate XML request: #ActionDispatch::Request:0x00000006d026f8 [DEBUG|#10975] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [DEBUG|#10975] User Load (0.4ms) SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [DEBUG|#10975] USER found: king [DEBUG|#10975] User king initialised [DEBUG|#10975] Role Load (0.4ms) 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 [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] DbPackage Load (0.3ms) 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 [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] get_by_project_and_name {} [DEBUG|#10975] CACHE (0.0ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] CACHE (0.0ms) 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 [DEBUG|#10975] CACHE (0.0ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] Flag Load (0.4ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1 [DEBUG|#10975] Flag Load (0.3ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1 [DEBUG|#10975] Flag Load (0.2ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position [DEBUG|#10975] flagcheck: package has default state, checking project [DEBUG|#10975] Flag Load (0.2ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 [DEBUG|#10975] disabled_for sourceaccess repo: arch: state:enable [DEBUG|#10975]  (0.1ms) SAVEPOINT active_record_1 [DEBUG|#10975] DbPackage Load (0.3ms)  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'  [DEBUG|#10975] CACHE (0.0ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] PackageUserRoleRelationship Load (0.2ms) SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 2 [DEBUG|#10975] PackageGroupRoleRelationship Load (0.2ms) SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 2 [DEBUG|#10975] CACHE (0.0ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position [DEBUG|#10975] DbPackage Load (0.3ms) 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 [DEBUG|#10975]  (0.3ms) UPDATE `db_packages` SET `activity_index` = 85.3110061353901, `updated_at` = '2012-09-03 06:41:43', `update_counter` = 1 WHERE `db_packages`.`id` = 2 [DEBUG|#10975] ----------------- rendering package kdebase ------------------------ [DEBUG|#10975] PackageUserRoleRelationship Load (0.3ms) 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 [DEBUG|#10975] PackageGroupRoleRelationship Load (0.3ms) 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 [DEBUG|#10975] ----------------- end rendering package kdebase ------------------------ [DEBUG|#10975] [backend] PUT: /source/kde4/kdebase/_meta?user=king [DEBUG|#10980] 2012-09-03 08:41:43 [16399]: PUT /source/kde4/kdebase/_meta?user=king [DEBUG|#10975] request took 0.018745484 [DEBUG|#10975]  (0.2ms) RELEASE SAVEPOINT active_record_1 [DEBUG|#10975] Validate XML response: #ActionDispatch::Response:0x00000006d026d0 took 1ms [INFO |#10975] Completed 200 OK in 53ms (Views: 1.1ms | ActiveRecord: 4.7ms | Backend: 18.7ms | XML: 0.1ms) [INFO |#10975] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-09-03 08:41:43 +0200 [INFO |#10975] Processing by SourceController#package_meta as XML [INFO |#10975] Parameters: {"project"=>"kde4", "package"=>"kdebase"} [DEBUG|#10975] Validate XML request: #ActionDispatch::Request:0x00000006fc4e18 [DEBUG|#10975] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"} [DEBUG|#10975] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [DEBUG|#10975] User Load (0.4ms) SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [DEBUG|#10975] USER found: king [DEBUG|#10975] User king initialised [DEBUG|#10975] get_by_project_and_name {} [DEBUG|#10975] Role Load (0.4ms) 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 [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] DbPackage Load (0.3ms) 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 [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] Validate XML response: #ActionDispatch::Response:0x00000006fc4df0 took 2ms [INFO |#10975] Completed 200 OK in 12ms (Views: 0.6ms | ActiveRecord: 1.5ms | Backend: 0.0ms | XML: 0.0ms) [INFO |#10975] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-09-03 08:41:43 +0200 [INFO |#10975] Processing by SourceController#package_meta as XML [INFO |#10975] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [DEBUG|#10975] Validate XML request: #ActionDispatch::Request:0x000000071bbfa0 [DEBUG|#10975] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [DEBUG|#10975] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [DEBUG|#10975] User Load (0.4ms) SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [DEBUG|#10975] USER found: king [DEBUG|#10975] User king initialised [DEBUG|#10975] get_by_project_and_name {} [DEBUG|#10975] Role Load (0.3ms) 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 [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] DbPackage Load (0.5ms) 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 [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] Validate XML response: #ActionDispatch::Response:0x000000071bbf28 took 3ms [INFO |#10975] Completed 200 OK in 155ms (Views: 0.5ms | ActiveRecord: 1.8ms | Backend: 0.0ms | XML: 0.0ms) [INFO |#10975] Started PUT "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-09-03 08:41:43 +0200 [INFO |#10975] Processing by SourceController#package_meta as XML [INFO |#10975] Parameters: {""\"kdelibs\" project=\"kde4\">\n <title>blub</title>\n <description>Changed description</description>\n \n \n \n \n \n \n \n <build>\n <enable/>\n </build>\n</package>\n", "project"=>"kde4", "package"=>"kdelibs"} [WARN |#10975] WARNING: Can't verify CSRF token authenticity [DEBUG|#10975] Validate XML request: #ActionDispatch::Request:0x00000005940d90 [DEBUG|#10975] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [DEBUG|#10975] User Load (0.3ms) SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [DEBUG|#10975] USER found: king [DEBUG|#10975] User king initialised [DEBUG|#10975] Role Load (0.4ms) 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 [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] DbPackage Load (0.3ms) 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 [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] get_by_project_and_name {} [DEBUG|#10975] CACHE (0.0ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] CACHE (0.0ms) 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 [DEBUG|#10975] CACHE (0.0ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] Flag Load (0.3ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1 [DEBUG|#10975] Flag Load (0.2ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1 [DEBUG|#10975] Flag Load (0.2ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position [DEBUG|#10975] flagcheck: package has default state, checking project [DEBUG|#10975] Flag Load (0.2ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 [DEBUG|#10975] disabled_for sourceaccess repo: arch: state:enable [DEBUG|#10975]  (0.1ms) SAVEPOINT active_record_1 [DEBUG|#10975] DbPackage Load (0.3ms) 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' [DEBUG|#10975] CACHE (0.0ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'686f6d653a49676779' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] DbPackage Load (0.2ms) 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 [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 502 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] PackageUserRoleRelationship Load (0.2ms) SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 1 [DEBUG|#10975] User Load (0.2ms) SELECT `users`.* FROM `users` WHERE `users`.`id` = 4 LIMIT 1 [DEBUG|#10975] Role Load (0.2ms) SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1 [DEBUG|#10975] User Load (0.2ms) SELECT `users`.* FROM `users` WHERE `users`.`id` = 42 LIMIT 1 [DEBUG|#10975] Role Load (0.2ms) SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 6 LIMIT 1 [DEBUG|#10975] User Load (0.2ms) SELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1 [DEBUG|#10975] PackageUserRoleRelationship Load (0.2ms) 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 [DEBUG|#10975] SQL (0.3ms) INSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 3) [DEBUG|#10975] User Load (0.2ms) SELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1 [DEBUG|#10975] PackageUserRoleRelationship Load (0.2ms) 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 [DEBUG|#10975] SQL (0.2ms) INSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 4) [DEBUG|#10975] PackageGroupRoleRelationship Load (0.2ms) SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 1 [DEBUG|#10975] Group Load (0.2ms) SELECT `groups`.* FROM `groups` WHERE `groups`.`id` = 99 LIMIT 1 [DEBUG|#10975] Role Load (0.2ms) SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1 [DEBUG|#10975] Group Load (0.2ms) SELECT `groups`.* FROM `groups` WHERE `groups`.`title` = 'test_group' LIMIT 1 [DEBUG|#10975] PackageGroupRoleRelationship Load (0.2ms) 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 [DEBUG|#10975] SQL (0.2ms) INSERT INTO `package_group_role_relationships` (`bs_group_id`, `db_package_id`, `role_id`) VALUES (99, 1, 7) [DEBUG|#10975] Flag Load (0.2ms) SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position [DEBUG|#10975] DbPackage Load (0.2ms) SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`id` = 1 AND (db_packages.db_project_id not in (0)) LIMIT 1 [DEBUG|#10975]  (0.2ms) SELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1 [DEBUG|#10975] SQL (0.2ms) INSERT INTO `flags` (`architecture_id`, `db_package_id`, `db_project_id`, `flag`, `position`, `repo`, `status`) VALUES (NULL, 1, NULL, 'build', 1, NULL, 'enable') [DEBUG|#10975] DbPackage Load (0.3ms) 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 [DEBUG|#10975]  (0.3ms) UPDATE `db_packages` SET `description` = 'Changed description', `develpackage_id` = 10095, `activity_index` = 85.3110061353901, `updated_at` = '2012-09-03 06:41:43', `update_counter` = 1 WHERE `db_packages`.`id` = 1 [DEBUG|#10975] ----------------- rendering package kdelibs ------------------------ [DEBUG|#10975] PackageUserRoleRelationship Load (0.3ms) 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 [DEBUG|#10975] PackageGroupRoleRelationship Load (0.3ms) 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 [DEBUG|#10975] ----------------- end rendering package kdelibs ------------------------ [DEBUG|#10975] [backend] PUT: /source/kde4/kdelibs/_meta?user=king [DEBUG|#10980] 2012-09-03 08:41:43 [16400]: PUT /source/kde4/kdelibs/_meta?user=king [DEBUG|#10975] request took 0.020253426 [DEBUG|#10975]  (0.2ms) RELEASE SAVEPOINT active_record_1 [DEBUG|#10975] Validate XML response: #ActionDispatch::Response:0x00000005941880 took 1ms [INFO |#10975] Completed 200 OK in 82ms (Views: 1.1ms | ActiveRecord: 9.2ms | Backend: 20.3ms | XML: 0.4ms) [INFO |#10975] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-09-03 08:41:43 +0200 [INFO |#10975] Processing by SourceController#package_meta as XML [INFO |#10975] Parameters: {"project"=>"kde4", "package"=>"kdelibs"} [DEBUG|#10975] Validate XML request: #ActionDispatch::Request:0x000000060e69c8 [DEBUG|#10975] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"} [DEBUG|#10975] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="] [DEBUG|#10975] User Load (0.4ms) SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1 [DEBUG|#10975] USER found: king [DEBUG|#10975] User king initialised [DEBUG|#10975] get_by_project_and_name {} [DEBUG|#10975] Role Load (0.4ms) 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 [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] DbPackage Load (0.3ms) 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 [DEBUG|#10975] DbProject Load (0.2ms) SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 2 AND (db_projects.id not in (0)) LIMIT 1 [DEBUG|#10975] Validate XML response: #ActionDispatch::Response:0x000000060e69a0 took 2ms [INFO |#10975] Completed 200 OK in 13ms (Views: 0.6ms | ActiveRecord: 1.5ms | Backend: 0.0ms | XML: 0.0ms) [DEBUG|#10975]  (461.9ms) ROLLBACK [DEBUG|#10975] kill 10980 + echo + cleanup + echo 'Killing backend processes' Killing backend processes + egrep 'perl|ruby' + fuser -v http://ci.opensuse.org/job/obs_master_testsuite_api/ws/ USER PID ACCESS COMMAND http://ci.opensuse.org/job/obs_master_testsuite_api/ws/: jenkins ..c.. 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@opensuse.org To contact the owner, e-mail: obs-tests+owner@opensuse.org

See http://ci.opensuse.org/job/obs_master_testsuite_api/1015/changes -- To unsubscribe, e-mail: obs-tests+unsubscribe@opensuse.org To contact the owner, e-mail: obs-tests+owner@opensuse.org