See http://ci.opensuse.org/job/obs_master_testsuite_api/934/changes
Changes:
[Stephan Kulow] [backend] don't know much about perl, but vim does
------------------------------------------
[...truncated 76685 lines...]
[[0;37mDEBUG[0m|#16165] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`id` = 42 LIMIT 1
[[0;37mDEBUG[0m|#16165] [1m[36mRole Load (0.2ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 6 LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [1m[35mProjectGroupRoleRelationship Load (0.2ms)[0m SELECT `project_group_role_relationships`.* FROM `project_group_role_relationships` WHERE `project_group_role_relationships`.`db_project_id` = 2
[[0;37mDEBUG[0m|#16165] [1m[36mGroup Load (0.2ms)[0m [1mSELECT `groups`.* FROM `groups` WHERE `groups`.`id` = 99 LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [1m[35mCACHE (0.0ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1
[[0;37mDEBUG[0m|#16165] [1m[36mCACHE (0.0ms)[0m [1mSELECT `groups`.* FROM `groups` WHERE `groups`.`id` = 99 LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [1m[35mCACHE (0.0ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 6 LIMIT 1
[[0;37mDEBUG[0m|#16165] [1m[36mFlag Load (0.7ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2[0m
[[0;37mDEBUG[0m|#16165] [1m[35mDownload Load (0.2ms)[0m SELECT `downloads`.* FROM `downloads` WHERE `downloads`.`db_project_id` = 2
[[0;37mDEBUG[0m|#16165] [1m[36mRepository Load (0.2ms)[0m [1mSELECT `repositories`.* FROM `repositories` WHERE `repositories`.`db_project_id` = 2[0m
[[0;37mDEBUG[0m|#16165] ----------------- rendering project kde4 ------------------------
[[0;37mDEBUG[0m|#16165] [1m[35mDbProjectType Load (0.2ms)[0m SELECT `db_project_types`.* FROM `db_project_types` WHERE `db_project_types`.`id` = 1 LIMIT 1
[[0;37mDEBUG[0m|#16165] [1m[36mCACHE (0.0ms)[0m [1mSELECT `db_project_types`.* FROM `db_project_types` WHERE `db_project_types`.`id` = 1 LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [1m[35mProjectUserRoleRelationship Load (0.3ms)[0m SELECT users.login as login, roles.title as roletitle FROM `project_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `project_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `project_user_role_relationships`.`bs_user_id` WHERE `project_user_role_relationships`.`db_project_id` = 2
[[0;37mDEBUG[0m|#16165] [1m[36mProjectGroupRoleRelationship Load (0.3ms)[0m [1mSELECT groups.title as grouptitle, roles.title as roletitle FROM `project_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `project_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `project_group_role_relationships`.`bs_group_id` WHERE `project_group_role_relationships`.`db_project_id` = 2[0m
[[0;37mDEBUG[0m|#16165] [1m[35mRepository Load (0.3ms)[0m SELECT `repositories`.* FROM `repositories` WHERE `repositories`.`db_project_id` = 2 AND `repositories`.`remote_project_name` IS NULL
[[0;37mDEBUG[0m|#16165] ----------------- end rendering project kde4 ------------------------
[[0;37mDEBUG[0m|#16165] [backend] PUT: /source/kde4/_meta?user=king
[[0;37mDEBUG[0m|#16165] request took 0.016649624
[[0;37mDEBUG[0m|#16165] [1m[36m (0.2ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#16165] Validate XML response: #ActionDispatch::Response:0x00000006040f28 took 1ms
[[32mINFO [0m|#16165] Completed 200 OK in 171ms (Views: 1.2ms | ActiveRecord: 8.0ms | Backend: 16.6ms | XML: 99.3ms)
[[32mINFO [0m|#16165]
Started GET "/source/kde4/_meta" for 127.0.0.1 at 2012-06-18 14:22:23 +0200
[[32mINFO [0m|#16165] Processing by SourceController#project_meta as XML
[[32mINFO [0m|#16165] Parameters: {"project"=>"kde4"}
[[0;37mDEBUG[0m|#16165] Validate XML request: #ActionDispatch::Request:0x000000042ed138
[[0;37mDEBUG[0m|#16165] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"project_meta", "project"=>"kde4", "method"=>"GET", "type"=>"request"}
[[0;37mDEBUG[0m|#16165] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#16165] [1m[35mUser Load (2.4ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1
[[0;37mDEBUG[0m|#16165] USER found: king
[[0;37mDEBUG[0m|#16165] User king initialised
[[0;37mDEBUG[0m|#16165] [1m[36mProjectUserRoleRelationship Load (0.4ms)[0m [1mSELECT ur.db_project_id, ur.bs_user_id from flags f,
project_user_role_relationships ur where f.flag = 'access' and ur.db_project_id = f.db_project_id[0m
[[0;37mDEBUG[0m|#16165] [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|#16165] [1m[36mDbProject Load (0.3ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] Validate XML response: #ActionDispatch::Response:0x000000042ed0e8 took 2ms
[[32mINFO [0m|#16165] Completed 200 OK in 14ms (Views: 0.6ms | ActiveRecord: 3.4ms | Backend: 0.0ms | XML: 0.0ms)
[[32mINFO [0m|#16165]
Started GET "/source/kde4" for 127.0.0.1 at 2012-06-18 14:22:23 +0200
[[32mINFO [0m|#16165] Processing by SourceController#index_project as XML
[[32mINFO [0m|#16165] Parameters: {"project"=>"kde4"}
[[0;37mDEBUG[0m|#16165] Validate XML request: #ActionDispatch::Request:0x00000004ff9818
[[0;37mDEBUG[0m|#16165] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"request"}
[[0;37mDEBUG[0m|#16165] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#16165] [1m[35mUser Load (0.4ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1
[[0;37mDEBUG[0m|#16165] USER found: king
[[0;37mDEBUG[0m|#16165] User king initialised
[[0;37mDEBUG[0m|#16165] [1m[36mRole Load (0.4ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [1m[35mDbProject Load (0.2ms)[0m SELECT id,name FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#16165] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT db_packages.name FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND (db_packages.db_project_id not in (0))[0m
[[0;37mDEBUG[0m|#16165] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"response"}
[[0;37mDEBUG[0m|#16165] Validate XML response: #ActionDispatch::Response:0x00000004ff9750 took 0ms
[[32mINFO [0m|#16165] Completed 200 OK in 8ms (Views: 0.5ms | ActiveRecord: 1.3ms | Backend: 0.0ms | XML: 0.0ms)
[[32mINFO [0m|#16165]
Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-06-18 14:22:23 +0200
[[32mINFO [0m|#16165] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#16165] Parameters: {"project"=>"kde4", "package"=>"kdebase"}
[[0;37mDEBUG[0m|#16165] Validate XML request: #ActionDispatch::Request:0x000000051654e0
[[0;37mDEBUG[0m|#16165] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"}
[[0;37mDEBUG[0m|#16165] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#16165] [1m[35mUser Load (0.4ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1
[[0;37mDEBUG[0m|#16165] USER found: king
[[0;37mDEBUG[0m|#16165] User king initialised
[[0;37mDEBUG[0m|#16165] [1m[36mRole Load (0.4ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [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|#16165] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b646562617365' AND (db_packages.db_project_id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [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|#16165] Validate XML response: #ActionDispatch::Response:0x00000005165490 took 2ms
[[32mINFO [0m|#16165] Completed 200 OK in 12ms (Views: 0.6ms | ActiveRecord: 1.5ms | Backend: 0.0ms | XML: 0.3ms)
[[32mINFO [0m|#16165]
Started PUT "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-06-18 14:22:23 +0200
[[32mINFO [0m|#16165] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#16165] Parameters: {""\"kdebase\" project=\"kde4\">\n <title>blub</title>\n <description>blub</description>\n</package>\n", "project"=>"kde4", "package"=>"kdebase"}
[[33mWARN [0m|#16165] WARNING: Can't verify CSRF token authenticity
[[0;37mDEBUG[0m|#16165] Validate XML request: #ActionDispatch::Request:0x0000000536cec8
[[0;37mDEBUG[0m|#16165] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#16165] [1m[36mUser Load (0.4ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] USER found: king
[[0;37mDEBUG[0m|#16165] User king initialised
[[0;37mDEBUG[0m|#16165] [1m[35mRole Load (0.4ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1
[[0;37mDEBUG[0m|#16165] [1m[36mDbProject Load (0.3ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [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|#16165] [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|#16165] [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|#16165] [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|#16165] [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|#16165] [1m[36mFlag Load (0.3ms)[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|#16165] [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|#16165] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position[0m
[[0;37mDEBUG[0m|#16165] flagcheck: package has default state, checking project
[[0;37mDEBUG[0m|#16165] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2
[[0;37mDEBUG[0m|#16165] disabled_for sourceaccess repo: arch: state:enable
[[0;37mDEBUG[0m|#16165] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#16165] [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|#16165] [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|#16165] [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|#16165] [1m[36mPackageGroupRoleRelationship Load (0.2ms)[0m [1mSELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 2[0m
[[0;37mDEBUG[0m|#16165] [1m[35mCACHE (0.0ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position
[[0;37mDEBUG[0m|#16165] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT db_packages.*, ( @activity:=( db_packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), db_packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp,
IF( @activity<0, 0, @activity ) AS activity_value FROM `db_packages` WHERE id = 2 LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [1m[35m (0.5ms)[0m UPDATE `db_packages` SET `activity_index` = 85.3110061353901, `updated_at` = '2012-06-18 12:22:23', `update_counter` = 1 WHERE `db_packages`.`id` = 2
[[0;37mDEBUG[0m|#16165] ----------------- rendering package kdebase ------------------------
[[0;37mDEBUG[0m|#16165] [1m[36mPackageUserRoleRelationship Load (0.3ms)[0m [1mSELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 2[0m
[[0;37mDEBUG[0m|#16165] [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|#16165] ----------------- end rendering package kdebase ------------------------
[[0;37mDEBUG[0m|#16165] [backend] PUT: /source/kde4/kdebase/_meta?user=king
[[0;37mDEBUG[0m|#16165] request took 0.229584119
[[0;37mDEBUG[0m|#16165] [1m[36m (0.2ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#16165] Validate XML response: #ActionDispatch::Response:0x0000000536cdb0 took 1ms
[[32mINFO [0m|#16165] Completed 200 OK in 264ms (Views: 1.2ms | ActiveRecord: 4.8ms | Backend: 229.6ms | XML: 0.1ms)
[[32mINFO [0m|#16165]
Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-06-18 14:22:24 +0200
[[32mINFO [0m|#16165] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#16165] Parameters: {"project"=>"kde4", "package"=>"kdebase"}
[[0;37mDEBUG[0m|#16165] Validate XML request: #ActionDispatch::Request:0x0000000505f708
[[0;37mDEBUG[0m|#16165] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"}
[[0;37mDEBUG[0m|#16165] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#16165] [1m[35mUser Load (0.4ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1
[[0;37mDEBUG[0m|#16165] USER found: king
[[0;37mDEBUG[0m|#16165] User king initialised
[[0;37mDEBUG[0m|#16165] [1m[36mRole Load (0.4ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [1m[35mDbProject Load (0.3ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#16165] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b646562617365' AND (db_packages.db_project_id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [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|#16165] Validate XML response: #ActionDispatch::Response:0x0000000505f690 took 2ms
[[32mINFO [0m|#16165] Completed 200 OK in 13ms (Views: 0.7ms | ActiveRecord: 1.6ms | Backend: 0.0ms | XML: 0.0ms)
[[32mINFO [0m|#16165]
Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-06-18 14:22:24 +0200
[[32mINFO [0m|#16165] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#16165] Parameters: {"project"=>"kde4", "package"=>"kdelibs"}
[[0;37mDEBUG[0m|#16165] Validate XML request: #ActionDispatch::Request:0x00000004f0a7b8
[[0;37mDEBUG[0m|#16165] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"}
[[0;37mDEBUG[0m|#16165] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#16165] [1m[36mUser Load (0.4ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] USER found: king
[[0;37mDEBUG[0m|#16165] User king initialised
[[0;37mDEBUG[0m|#16165] [1m[35mRole Load (0.4ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1
[[0;37mDEBUG[0m|#16165] [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|#16165] [1m[35mDbPackage Load (0.3ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b64656c696273' AND (db_packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#16165] [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|#16165] Validate XML response: #ActionDispatch::Response:0x00000004f0a790 took 2ms
[[32mINFO [0m|#16165] Completed 200 OK in 12ms (Views: 0.6ms | ActiveRecord: 1.5ms | Backend: 0.0ms | XML: 0.0ms)
[[32mINFO [0m|#16165]
Started PUT "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-06-18 14:22:24 +0200
[[32mINFO [0m|#16165] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#16165] Parameters: {""\"kdelibs\" project=\"kde4\">\n <title>blub</title>\n <description>Changed description</description>\n \n \n \n \n <build>\n <enable/>\n </build>\n</package>\n", "project"=>"kde4", "package"=>"kdelibs"}
[[33mWARN [0m|#16165] WARNING: Can't verify CSRF token authenticity
[[0;37mDEBUG[0m|#16165] Validate XML request: #ActionDispatch::Request:0x00000005492578
[[0;37mDEBUG[0m|#16165] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#16165] [1m[35mUser Load (0.4ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1
[[0;37mDEBUG[0m|#16165] USER found: king
[[0;37mDEBUG[0m|#16165] User king initialised
[[0;37mDEBUG[0m|#16165] [1m[36mRole Load (0.4ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [1m[35mDbProject Load (0.3ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#16165] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b64656c696273' AND (db_packages.db_project_id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [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|#16165] [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|#16165] [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|#16165] [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|#16165] [1m[35mFlag Load (0.5ms)[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|#16165] [1m[36mFlag Load (0.3ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position
[[0;37mDEBUG[0m|#16165] flagcheck: package has default state, checking project
[[0;37mDEBUG[0m|#16165] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2[0m
[[0;37mDEBUG[0m|#16165] disabled_for sourceaccess repo: arch: state:enable
[[0;37mDEBUG[0m|#16165] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#16165] [1m[36mDbPackage Load (0.3ms)[0m [1m SELECT pack.*
FROM db_packages pack
LEFT OUTER JOIN db_projects pro ON pack.db_project_id = pro.id
WHERE pro.name = 'kde4' AND pack.name = 'kdelibs'
[0m
[[0;37mDEBUG[0m|#16165] [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|#16165] [1m[36mDbProject Load (0.3ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'686f6d653a636f6f6c6f3a74657374' AND (db_projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [1m[35mDbPackage Load (0.3ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 601 AND `db_packages`.`name` = x'6b64656c6962735f444556454c5f7061636b616765' AND (db_packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#16165] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 601 AND (db_projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [1m[35mPackageUserRoleRelationship Load (0.2ms)[0m SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 1
[[0;37mDEBUG[0m|#16165] [1m[36mUser Load (0.2ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`id` = 4 LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [1m[35mRole Load (0.2ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1
[[0;37mDEBUG[0m|#16165] [1m[36mUser Load (0.2ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`id` = 42 LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [1m[35mRole Load (0.2ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 6 LIMIT 1
[[0;37mDEBUG[0m|#16165] [1m[36mPackageGroupRoleRelationship Load (0.2ms)[0m [1mSELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 1[0m
[[0;37mDEBUG[0m|#16165] [1m[35mGroup Load (0.2ms)[0m SELECT `groups`.* FROM `groups` WHERE `groups`.`id` = 99 LIMIT 1
[[0;37mDEBUG[0m|#16165] [1m[36mCACHE (0.0ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [1m[35mCACHE (0.0ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position
[[0;37mDEBUG[0m|#16165] [1m[36mDbPackage Load (0.3ms)[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|#16165] [1m[35m (0.3ms)[0m SELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1
[[0;37mDEBUG[0m|#16165] [1m[36mSQL (0.3ms)[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|#16165] [1m[35mDbPackage Load (0.3ms)[0m SELECT db_packages.*, ( @activity:=( db_packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), db_packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp,
IF( @activity<0, 0, @activity ) AS activity_value FROM `db_packages` WHERE id = 1 LIMIT 1
[[0;37mDEBUG[0m|#16165] [1m[36m (0.3ms)[0m [1mUPDATE `db_packages` SET `description` = 'Changed description', `activity_index` = 85.3110061353901, `updated_at` = '2012-06-18 12:22:24', `update_counter` = 1 WHERE `db_packages`.`id` = 1[0m
[[0;37mDEBUG[0m|#16165] ----------------- rendering package kdelibs ------------------------
[[0;37mDEBUG[0m|#16165] [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|#16165] [1m[36mPackageGroupRoleRelationship Load (0.3ms)[0m [1mSELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 1[0m
[[0;37mDEBUG[0m|#16165] ----------------- end rendering package kdelibs ------------------------
[[0;37mDEBUG[0m|#16165] [backend] PUT: /source/kde4/kdelibs/_meta?user=king
[[0;37mDEBUG[0m|#16165] request took 0.173988975
[[0;37mDEBUG[0m|#16165] [1m[35m (0.2ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#16165] Validate XML response: #ActionDispatch::Response:0x00000005492550 took 0ms
[[32mINFO [0m|#16165] Completed 200 OK in 226ms (Views: 1.1ms | ActiveRecord: 7.7ms | Backend: 174.0ms | XML: 0.4ms)
[[32mINFO [0m|#16165]
Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-06-18 14:22:24 +0200
[[32mINFO [0m|#16165] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#16165] Parameters: {"project"=>"kde4", "package"=>"kdelibs"}
[[0;37mDEBUG[0m|#16165] Validate XML request: #ActionDispatch::Request:0x00000005f67e58
[[0;37mDEBUG[0m|#16165] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"}
[[0;37mDEBUG[0m|#16165] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#16165] [1m[36mUser Load (0.4ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] USER found: king
[[0;37mDEBUG[0m|#16165] User king initialised
[[0;37mDEBUG[0m|#16165] [1m[35mRole Load (0.4ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1
[[0;37mDEBUG[0m|#16165] [1m[36mDbProject Load (0.3ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`name` = x'6b646534' AND (db_projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#16165] [1m[35mDbPackage Load (0.3ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 2 AND `db_packages`.`name` = x'6b64656c696273' AND (db_packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#16165] [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|#16165] Validate XML response: #ActionDispatch::Response:0x00000005f67e30 took 2ms
[[32mINFO [0m|#16165] Completed 200 OK in 12ms (Views: 0.6ms | ActiveRecord: 1.5ms | Backend: 0.0ms | XML: 0.0ms)
[[0;37mDEBUG[0m|#16165] [1m[35m (34.1ms)[0m ROLLBACK
+ 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