See http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/44/
------------------------------------------
[...truncated 88467 lines...]
[[0;37mDEBUG[0m|#11125] Cache write (will save 149.56ms): Directory_2137d60896b8bcb016d0b5a993809e70
[[0;37mDEBUG[0m|#11125] --> direct_http url: #http://localhost:3203/source/BinaryprotectedProject/bdpack?cmd=diff&view=xml&withissues=1&linkrev=base&rev=2&expand=1>
[[0;37mDEBUG[0m|#11125] http_do #1: method: POST url: http://localhost:3203/source/BinaryprotectedProject/bdpack?cmd=diff&view=xml&withissues=1&linkrev=base&rev=2&expand=1
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 3002 AND (name = BINARY 'bdpack') LIMIT 1[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[35mDbProject Load (0.3ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 3002 LIMIT 1
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[36mFlag Load (0.3ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 11007 ORDER BY position[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] flagcheck: package has default state, checking project
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 3002
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] disabled_for sourceaccess repo: arch: state:enable
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] disabled_for sourceaccess repo: arch: state:enable
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [backend] VOLLEY: /source/BinaryprotectedProject/bdpack?
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11136] 2012-04-01 14:03:36 [12802]: GET /source/BinaryprotectedProject/bdpack?
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Sent file /tmp/volley20120401-11127-g337du-0 (0.2ms)
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Completed 200 OK in 141ms (ActiveRecord: 3.1ms | Backend: 0.0ms | XML: 0.0ms)
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Started POST "/source/BinaryprotectedProject/bdpack?cmd=diff&view=xml&withissues=1&linkrev=base&rev=2&expand=1" for 127.0.0.1 at Sun Apr 01 14:03:36 +0200 2012
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Processing by SourceController#index_package as XML
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Parameters: {"withissues"=>"1", "view"=>"xml", "project"=>"BinaryprotectedProject", "package"=>"bdpack", "rev"=>"2", "expand"=>"1", "linkrev"=>"base", "cmd"=>"diff"}
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] Validate XML request: #ActionDispatch::Request:0x7f776624b730
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] no schema found, skipping validation for {"withissues"=>"1", "view"=>"xml", "controller"=>"source", "format"=>"xml", "project"=>"BinaryprotectedProject", "action"=>"index_package", "package"=>"bdpack", "rev"=>"2", "expand"=>"1", "method"=>"POST", "linkrev"=>"base", "cmd"=>"diff", "type"=>"request"}
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] AUTH: nil
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[36mUser Load (0.4ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`login` = x'5f6e6f626f64795f' LIMIT 1[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] User _nobody_ initialised
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [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` = 5 AND `roles`.`title` = 'Admin' LIMIT 1
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[36mDbProject Load (0.3ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE (name = BINARY 'BinaryprotectedProject') LIMIT 1[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[35mDbPackage Load (0.3ms)[0m SELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 3002 AND (name = BINARY 'bdpack') LIMIT 1
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 3002 LIMIT 1[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[35mFlag Load (0.4ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 11007 ORDER BY position
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] flagcheck: package has default state, checking project
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 3002[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] disabled_for sourceaccess repo: arch: state:enable
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] disabled_for sourceaccess repo: arch: state:enable
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] dispatch_command: trying to call method 'index_package_diff'
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [backend] POST: /source/BinaryprotectedProject/bdpack?cmd=diff&rev=2&expand=1&linkrev=base&view=xml&withissues=1
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11136] 2012-04-01 14:03:37 [12803]: POST /source/BinaryprotectedProject/bdpack?cmd=diff&rev=2&expand=1&linkrev=base&view=xml&withissues=1
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] request took 0.199
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Rendered text template (0.0ms)
[[0;37mDEBUG[0m|#11125] Request took 0.230697 seconds
[[32mINFO [0m|#11125] Rendering template within layouts/application
[[32mINFO [0m|#11125] Rendering package/rdiff
[[0;37mDEBUG[0m|#11125] Cache hit: parent_projects_BinaryprotectedProject ({:expires_in=>7 days})
[[0;37mDEBUG[0m|#11125] Rendered package/_tabs (3.1ms)
[[0;37mDEBUG[0m|#11125] Rendered shared/_editor (0.7ms)
[[0;37mDEBUG[0m|#11125] Rendered shared/_sourcediff (3.1ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_watch_and_search (0.8ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_header (1.6ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_personal_navigation (1.8ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#11125] Completed in 403ms (View: 15, DB: 0, API: 414, XML: 1) | 200 OK [http://localhost/package/rdiff?linkrev=base&package=bdpack&project=BinaryprotectedProject&rev=2]
[[32mINFO [0m|#11125] Processing PackageController#repositories (for 127.0.0.1 at 2012-04-01 14:03:38) [GET]
[[32mINFO [0m|#11125] Parameters: {"project"=>"BinaryprotectedProject", "package"=>"bdpack"}
[[0;37mDEBUG[0m|#11125] Setting return_to: "/package/repositories?package=bdpack&project=BinaryprotectedProject"
[[0;37mDEBUG[0m|#11125] Authenticating with iChain mode: off
[[32mINFO [0m|#11125] Anonymous request to /package/repositories?package=bdpack&project=BinaryprotectedProject
[[0;37mDEBUG[0m|#11125] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#11125] Project.find( 300 seconds, BinaryprotectedProject, )
[[0;37mDEBUG[0m|#11125] Cache hit: Project_ceb033cc783987344fb1117f7f7cef83 ({:expires_in=>300 seconds})
[[0;37mDEBUG[0m|#11125] Package.find( 1800 seconds, bdpack, projectBinaryprotectedProject)
[[0;37mDEBUG[0m|#11125] Cache hit: Package_6835e2f14fbff7760eac4182041a9b3f ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#11125] Cache hit: package_reviews_BinaryprotectedProject_bdpack ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#11125] Package.find( 1800 seconds, bdpack, projectBinaryprotectedProjectviewflagdetails)
[[0;37mDEBUG[0m|#11125] Cache miss: Package_892cb1b7695f8865212e82a9db07e311 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#11125] [REST] find( Package, [{:project=>"BinaryprotectedProject", :view=>"flagdetails", :name=>"bdpack"}] )
[[0;37mDEBUG[0m|#11125] http_do #1: method: get url: http://localhost:3203/source/BinaryprotectedProject/bdpack/_meta?view=flagde...
[[0;37mDEBUG[0m|#11125] Request took 0.032401 seconds
[[0;37mDEBUG[0m|#11125] Cache write (will save 33.08ms): Package_892cb1b7695f8865212e82a9db07e311
[[32mINFO [0m|#11125] Rendering template within layouts/application
[[32mINFO [0m|#11125] Rendering package/repositories
[[0;37mDEBUG[0m|#11125] Cache hit: parent_projects_BinaryprotectedProject ({:expires_in=>7 days})
[[0;37mDEBUG[0m|#11125] Rendered package/_tabs (3.0ms)
[[0;37mDEBUG[0m|#11125] Rendered shared/_repositories_flag_table (2.7ms)
[[0;37mDEBUG[0m|#11125] Rendered shared/_repositories_flag_table (2.3ms)
[[0;37mDEBUG[0m|#11125] Rendered shared/_repositories_flag_table (2.3ms)
[[0;37mDEBUG[0m|#11125] Rendered shared/_repositories_flag_table (2.5ms)
[[0;37mDEBUG[0m|#11125] Rendered shared/_repositories (12.5ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_watch_and_search (0.7ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_header (1.5ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_personal_navigation (1.7ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#11125] Completed in 67ms (View: 25, DB: 0, API: 33, XML: 1) | 200 OK [http://localhost/package/repositories?package=bdpack&project=BinaryprotectedProject]
[[32mINFO [0m|#11125] Processing PackageController#binaries (for 127.0.0.1 at 2012-04-01 14:03:38) [GET]
[[32mINFO [0m|#11125] Parameters: {"project"=>"BinaryprotectedProject", "repository"=>"nada", "package"=>"bdpack"}
[[0;37mDEBUG[0m|#11125] Setting return_to: "/package/binaries?package=bdpack&project=BinaryprotectedProject&repository=nada"
[[0;37mDEBUG[0m|#11125] Authenticating with iChain mode: off
[[32mINFO [0m|#11125] Anonymous request to /package/binaries?package=bdpack&project=BinaryprotectedProject&repository=nada
[[0;37mDEBUG[0m|#11125] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#11125] Project.find( 300 seconds, BinaryprotectedProject, )
[[0;37mDEBUG[0m|#11125] Cache hit: Project_ceb033cc783987344fb1117f7f7cef83 ({:expires_in=>300 seconds})
[[0;37mDEBUG[0m|#11125] Package.find( 1800 seconds, bdpack, projectBinaryprotectedProject)
[[0;37mDEBUG[0m|#11125] Cache hit: Package_6835e2f14fbff7760eac4182041a9b3f ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#11125] Cache hit: package_reviews_BinaryprotectedProject_bdpack ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#11125] Buildresult.find( 60 seconds, projectBinaryprotectedProjectviewbinaryliststatusrepositorynadapackagebdpack)
[[0;37mDEBUG[0m|#11125] Cache miss: Buildresult_c277e5899a028bb4a3222177f6120a63 ({:expires_in=>60 seconds})
[[0;37mDEBUG[0m|#11125] [REST] find( Buildresult, [{:project=>"BinaryprotectedProject", :repository=>"nada", :view=>["binarylist", "status"], :package=>"bdpack"}] )
[[0;37mDEBUG[0m|#11125] http_do #1: method: get url: http://localhost:3203/build/BinaryprotectedProject/_result?view=binarylist&view=status&package=bdpack&repository=nada
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Sent data (1.0ms)
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Completed 200 OK in 220ms (Views: 0.8ms | ActiveRecord: 2.2ms | Backend: 199.0ms | XML: 0.0ms)
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Started GET "/source/BinaryprotectedProject/bdpack/_meta?view=flagdetails" for 127.0.0.1 at Sun Apr 01 14:03:38 +0200 2012
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Processing by SourceController#package_meta as XML
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Parameters: {"view"=>"flagdetails", "project"=>"BinaryprotectedProject", "package"=>"bdpack"}
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] Validate XML request: #ActionDispatch::Request:0x7f77661608e8
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] no schema found, skipping validation for {"view"=>"flagdetails", "controller"=>"source", "format"=>"xml", "project"=>"BinaryprotectedProject", "action"=>"package_meta", "package"=>"bdpack", "method"=>"GET", "type"=>"request"}
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] AUTH: nil
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[35mUser Load (0.4ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`login` = x'5f6e6f626f64795f' LIMIT 1
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] User _nobody_ initialised
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [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` = 5 AND `roles`.`title` = 'Admin' LIMIT 1[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE (name = BINARY 'BinaryprotectedProject') LIMIT 1
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 3002 AND (name = BINARY 'bdpack') LIMIT 1[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 3002 LIMIT 1
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] ----------------- rendering package bdpack ------------------------
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[36mUser Load (0.3ms)[0m [1mSELECT bu.*, r.title AS role_name FROM `users` bu, package_user_role_relationships purr, roles r WHERE (bu.id = purr.bs_user_id AND purr.db_package_id = 11007 AND r.id = purr.role_id)[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[35mGroup Load (0.2ms)[0m SELECT bg.*, r.title AS role_name FROM `groups` bg, package_group_role_relationships pgrr, roles r WHERE (bg.id = pgrr.bs_group_id AND pgrr.db_package_id = 11007 AND r.id = pgrr.role_id)
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[36mRepository Load (0.3ms)[0m [1mSELECT `repositories`.* FROM `repositories` WHERE `repositories`.`db_project_id` = 3002 AND (ISNULL(remote_project_name))[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 3002
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 11007 ORDER BY position[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[35mArchitecture Load (0.3ms)[0m SELECT `architectures`.* FROM `architectures` INNER JOIN `repository_architectures` ON `architectures`.`id` = `repository_architectures`.`architecture_id` WHERE `repository_architectures`.`repository_id` = 90 ORDER BY position
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] ----------------- end rendering package bdpack ------------------------
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Rendered text template (0.0ms)
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Completed 200 OK in 24ms (Views: 0.7ms | ActiveRecord: 2.9ms | Backend: 0.0ms | XML: 0.0ms)
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Started GET "/build/BinaryprotectedProject/_result?view=binarylist&view=status&package=bdpack&repository=nada" for 127.0.0.1 at Sun Apr 01 14:03:38 +0200 2012
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Processing by BuildController#result as XML
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Parameters: {"view"=>"status", "package"=>"bdpack", "project"=>"BinaryprotectedProject", "repository"=>"nada"}
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] Validate XML request: #ActionDispatch::Request:0x7f776603fdb0
[[0;37mDEBUG[0m|#11125] Request took 0.04345 seconds
[[0;37mDEBUG[0m|#11125] Cache write (will save 44.14ms): Buildresult_c277e5899a028bb4a3222177f6120a63
[[0;37mDEBUG[0m|#11125] Package.find( 1800 seconds, bdpack, projectBinaryprotectedProjectviewflagdetails)
[[0;37mDEBUG[0m|#11125] Cache hit: Package_892cb1b7695f8865212e82a9db07e311 ({:expires_in=>1800 seconds})
[[32mINFO [0m|#11125] Rendering template within layouts/application
[[32mINFO [0m|#11125] Rendering package/binaries
[[0;37mDEBUG[0m|#11125] Cache hit: parent_projects_BinaryprotectedProject ({:expires_in=>7 days})
[[0;37mDEBUG[0m|#11125] Rendered package/_tabs (3.1ms)
[[0;37mDEBUG[0m|#11125] Rendered shared/_download_repository_link (0.8ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_watch_and_search (0.8ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_header (1.7ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_personal_navigation (1.8ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_sponsor (0.1ms)
[[32mINFO [0m|#11125] Completed in 90ms (View: 23, DB: 0, API: 44, XML: 0) | 200 OK [http://localhost/package/binaries?package=bdpack&project=BinaryprotectedProject&repository=nada]
[[32mINFO [0m|#11125] Processing PackageController#binary (for 127.0.0.1 at 2012-04-01 14:03:39) [GET]
[[32mINFO [0m|#11125] Parameters: {"project"=>"BinaryprotectedProject", "arch"=>"i586", "filename"=>"delete_me-1.0-1.i586.rpm", "repository"=>"nada", "package"=>"bdpack"}
[[0;37mDEBUG[0m|#11125] Setting return_to: "/package/binary?arch=i586&filename=delete_me-1.0-1.i586.rpm&package=bdpack&project=BinaryprotectedProject&repository=nada"
[[0;37mDEBUG[0m|#11125] Authenticating with iChain mode: off
[[32mINFO [0m|#11125] Anonymous request to /package/binary?arch=i586&filename=delete_me-1.0-1.i586.rpm&package=bdpack&project=BinaryprotectedProject&repository=nada
[[0;37mDEBUG[0m|#11125] Cache hit: configuration ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#11125] Project.find( 300 seconds, BinaryprotectedProject, )
[[0;37mDEBUG[0m|#11125] Cache hit: Project_ceb033cc783987344fb1117f7f7cef83 ({:expires_in=>300 seconds})
[[0;37mDEBUG[0m|#11125] Package.find( 1800 seconds, bdpack, projectBinaryprotectedProject)
[[0;37mDEBUG[0m|#11125] Cache hit: Package_6835e2f14fbff7760eac4182041a9b3f ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#11125] Cache hit: package_reviews_BinaryprotectedProject_bdpack ({:expires_in=>600 seconds})
[[0;37mDEBUG[0m|#11125] Fileinfo.find( 1800 seconds, projectBinaryprotectedProjectviewfileinfo_extrepositorynadafilenamedelete_me-1.0-1.i586.rpmarchi586packagebdpack)
[[0;37mDEBUG[0m|#11125] Cache miss: Fileinfo_e66c04b8c26fa8121d0e73afd5e0d523 ({:expires_in=>1800 seconds})
[[0;37mDEBUG[0m|#11125] [REST] find( Fileinfo, [{:project=>"BinaryprotectedProject", :repository=>"nada", :view=>"fileinfo_ext", :package=>"bdpack", :arch=>"i586", :filename=>"delete_me-1.0-1.i586.rpm"}] )
[[0;37mDEBUG[0m|#11125] http_do #1: method: get url: http://localhost:3203/build/BinaryprotectedProject/nada/i586/bdpack/delete_m...
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] no schema found, skipping validation for {"view"=>"status", "controller"=>"build", "format"=>"xml", "package"=>"bdpack", "project"=>"BinaryprotectedProject", "action"=>"result", "repository"=>"nada", "method"=>"GET", "type"=>"request"}
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] AUTH: nil
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[36mUser Load (0.4ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`login` = x'5f6e6f626f64795f' LIMIT 1[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] User _nobody_ initialised
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [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` = 5 AND `roles`.`title` = 'Admin' LIMIT 1
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[36mDbProject Load (0.2ms)[0m [1mSELECT `db_projects`.* FROM `db_projects` WHERE (name = BINARY 'BinaryprotectedProject') LIMIT 1[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [backend] VOLLEY: /build/BinaryprotectedProject/_result?view=binarylist&view=status&package=bdpack&repository=nada
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11136] 2012-04-01 14:03:38 [12805]: GET /build/BinaryprotectedProject/_result?view=binarylist&view=status&package=bdpack&repository=nada
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11136] 2012-04-01 14:03:38 [12806]: GET /_result?prpa=BinaryprotectedProject/nada/i586&package=bdpack&withbinarylist
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Sent file /tmp/volley20120401-11127-11ghvpd-0 (0.2ms)
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Completed 200 OK in 34ms (ActiveRecord: 0.9ms | Backend: 0.0ms | XML: 0.0ms)
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Started GET "/build/BinaryprotectedProject/nada/i586/bdpack/delete_me-1.0-1.i586.rpm?view=fileinfo_ext" for 127.0.0.1 at Sun Apr 01 14:03:39 +0200 2012
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Processing by BuildController#file as XML
[[0;37mDEBUG[0m|#11126] [[32mINFO [0m|#11127] Parameters: {"view"=>"fileinfo_ext", "project"=>"BinaryprotectedProject", "package"=>"bdpack", "repository"=>"nada", "arch"=>"i586", "filename"=>"delete_me-1.0-1.i586.rpm"}
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] Validate XML request: #ActionDispatch::Request:0x7f7765fe66c0
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] no schema found, skipping validation for {"view"=>"fileinfo_ext", "controller"=>"build", "format"=>"xml", "project"=>"BinaryprotectedProject", "action"=>"file", "package"=>"bdpack", "repository"=>"nada", "method"=>"GET", "arch"=>"i586", "filename"=>"delete_me-1.0-1.i586.rpm", "type"=>"request"}
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] AUTH: nil
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[35mUser Load (0.4ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`login` = x'5f6e6f626f64795f' LIMIT 1
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] User _nobody_ initialised
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [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` = 5 AND `roles`.`title` = 'Admin' LIMIT 1[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE (name = BINARY 'BinaryprotectedProject') LIMIT 1
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[36mDbPackage Load (0.3ms)[0m [1mSELECT `db_packages`.* FROM `db_packages` WHERE `db_packages`.`db_project_id` = 3002 AND (name = BINARY 'bdpack') LIMIT 1[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[35mDbProject Load (0.2ms)[0m SELECT `db_projects`.* FROM `db_projects` WHERE `db_projects`.`id` = 3002 LIMIT 1
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 3002[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] disabled_for binarydownload repo:nada arch:i586 state:disable
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[35mCACHE (0.0ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 5 AND `roles`.`title` = 'Admin' LIMIT 1
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[36mRole Load (0.3ms)[0m [1mSELECT DISTINCT `roles`.* FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 5[0m
[[0;37mDEBUG[0m|#11126] [[0;37mDEBUG[0m|#11127] [1m[35mGroup Load (0.3ms)[0m SELECT DISTINCT `groups`.* FROM `groups` INNER JOIN `groups_users` ON `groups`.`id` = `groups_users`.`group_id` WHERE `groups_users`.`user_id` = 5
[[0;37mDEBUG[0m|#11125] Request took 0.031663 seconds
[[31mFATAL[0m|#11125] ActiveXML::Transport::ForbiddenError (<?xml version="1.0"?>
<status code="download_binary_no_permission">
<summary>No permission to download binaries from package bdpack, project BinaryprotectedProject</summary>
<details></details>
</status>
):
lib/activexml/transport.rb:558:in `handle_response'
lib/activexml/transport.rb:546:in `http_do'
lib/activexml/transport.rb:344:in `find'
lib/activexml/base.rb:103:in `find_priv'
lib/activexml/base.rb:102:in `find_priv'
lib/activexml/base.rb:132:in `find_cached'
app/controllers/application_controller.rb:294:in `find_cached'
app/controllers/package_controller.rb:64:in `binary'
/usr/lib64/ruby/1.8/webrick/httpserver.rb:104:in `service'
/usr/lib64/ruby/1.8/webrick/httpserver.rb:65:in `run'
/usr/lib64/ruby/1.8/webrick/server.rb:173:in `start_thread'
/usr/lib64/ruby/1.8/webrick/server.rb:162:in `start'
/usr/lib64/ruby/1.8/webrick/server.rb:162:in `start_thread'
/usr/lib64/ruby/1.8/webrick/server.rb:95:in `start'
/usr/lib64/ruby/1.8/webrick/server.rb:92:in `each'
/usr/lib64/ruby/1.8/webrick/server.rb:92:in `start'
/usr/lib64/ruby/1.8/webrick/server.rb:23:in `start'
/usr/lib64/ruby/1.8/webrick/server.rb:82:in `start'
[[31mERROR[0m|#11125] rescue_action: caught ActiveXML::Transport::ForbiddenError: <?xml version="1.0"?>
<status code="download_binary_no_permission">
<summary>No permission to download binaries from package bdpack, project BinaryprotectedProject</summary>
<details></details>
</status>
[[0;37mDEBUG[0m|#11125] ERROR: 401; No permission to download binaries from package bdpack, project BinaryprotectedProject
[[32mINFO [0m|#11125] Rendering template within layouts/application
[[32mINFO [0m|#11125] Rendering error (401)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_watch_and_search (0.8ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_header (1.8ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_personal_navigation (2.0ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_flash (0.1ms)
[[0;37mDEBUG[0m|#11125] Rendered layouts/_sponsor (0.1ms)
+ echo
+ cleanup
+ echo 'Killing backend processes'
Killing backend processes
+ fuser -v http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/
+ egrep 'perl|ruby'
USER PID ACCESS COMMAND
http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/:
jenkins ..c.. sh
jenkins ..c.. sh
jenkins ..c.. egrep
+ echo 'Remove log/tmp files to save disc space'
Remove log/tmp files to save disc space
+ rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets
+ exit 1
Build step 'Execute shell' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
--
To unsubscribe, e-mail: obs-tests+unsubscribe@opensuse.org
To contact the owner, e-mail: obs-tests+owner@opensuse.org