Open Build Service Tests
Threads by month
- ----- 2024 -----
- May
- April
- March
- February
- January
- ----- 2023 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2022 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2021 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2020 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2019 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2018 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2017 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2016 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2015 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2014 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2013 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2012 -----
- December
- November
- October
- September
- August
- July
- June
- May
- April
- March
- February
- January
- ----- 2011 -----
- December
- November
- October
November 2012
- 1 participants
- 34 discussions
[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui-testsuite #466
by admin@opensuse.org 21 Nov '12
by admin@opensuse.org 21 Nov '12
21 Nov '12
See <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/466/changes>
Changes:
[Stephan Kulow] [webui] Remove homebrewn exception notification in favour of errbit
[Stephan Kulow] [webui] Don't use our own routing as exception middleware
[Stephan Kulow] [webui] Port all the exception logic to the new system
[Stephan Kulow] [webui] move the configuration of the errbit installation to options.yml
------------------------------------------
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Building in workspace <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/>
Checkout:workspace / <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/> - hudson.remoting.LocalChannel@28e928e9
Using strategy: Default
Last Built Revision: Revision 7bfbbd27f2f62247448d735239f8f1090a955793 (origin/master)
Checkout:workspace / <http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/> - hudson.remoting.LocalChannel@28e928e9
Fetching changes from 1 remote Git repository
Fetching upstream changes from git://github.com/openSUSE/open-build-service.git
Commencing build of Revision 1c08ed07862146d9a7fb942b685dc022a908dd81 (origin/master)
Checking out Revision 1c08ed07862146d9a7fb942b685dc022a908dd81 (origin/master)
Cleaning workspace
[locks-and-latches] Checking to see if we really have the locks
[locks-and-latches] Have all the locks, build can start
[workspace] $ /bin/sh -xe /tmp/hudson6192001055705361838.sh
+ sh dist/ci/obs_testsuite_webui-testsuite.sh
++ dirname dist/ci/obs_testsuite_webui-testsuite.sh
+ . dist/ci/obs_testsuite_common.sh
+ setup_git
+ echo 'Checking status'
Checking status
+ git status
# Not currently on any branch.
nothing to commit (working directory clean)
+ echo 'Setup git submodules'
Setup git submodules
+ git submodule init
+ git submodule update
+ echo 'Setup backend configuration template'
Setup backend configuration template
+ sed -i -e 's|my $hostname = .*$|my $hostname = '\''localhost'\'';|' -e 's|our $bsuser = '\''obsrun'\'';|our $bsuser = '\''jenkins'\'';|' -e 's|our $bsgroup = '\''obsrun'\'';|our $bsgroup = '\''jenkins'\'';|' src/backend/BSConfig.pm.template
+ cp src/backend/BSConfig.pm.template src/backend/BSConfig.pm
+ echo 'Set environment variables'
Set environment variables
+ export RAILS_ENV=test
+ RAILS_ENV=test
+ ret=0
+ setup_api
+ echo 'Enter API rails root'
Enter API rails root
+ cd src/api
+ echo 'Setup database configuration'
Setup database configuration
+ cp config/database.yml.example config/database.yml
+ sed -i 's|database: api|database: ci_api|' config/database.yml
+ echo 'Setup additional configuration'
Setup additional configuration
+ cp config/options.yml.example config/options.yml
+ echo 'Install missing gems locally'
Install missing gems locally
+ mv Gemfile.lock Gemfile.lock.orig
+ bundle list
Gems included by the bundle:
* actionmailer (3.2.9)
* actionpack (3.2.9)
* activemodel (3.2.9)
* activerecord (3.2.9)
* activesupport (3.2.9)
* arel (3.0.2)
* builder (3.0.4)
* bundler (1.2.2)
* ci_reporter (1.7.3)
* coderay (1.0.8)
* daemons (1.1.9)
* database_cleaner (0.9.1)
* delayed_job (3.0.4)
* delayed_job_active_record (0.3.3)
* erubis (2.7.0)
* eventmachine (1.0.0)
* exception_notification (3.0.0)
* faraday (0.8.4)
* faraday_middleware (0.9.0)
* fast_xs (0.8.0)
* hashie (1.2.0)
* hike (1.2.1)
* http_parser.rb (0.5.3)
* i18n (0.6.1)
* journey (1.0.4)
* json (1.7.5)
* mail (2.4.4)
* memcache-client (1.8.5)
* method_source (0.8.1)
* mime-types (1.19)
* minitest (4.2.0)
* multi_json (1.3.7)
* multipart-post (1.1.5)
* mysql2 (0.3.11)
* nokogiri (1.5.5)
* pkg-config (1.1.4)
* polyglot (0.3.3)
* pry (0.9.10)
* rack (1.4.1)
* rack-cache (1.2)
* rack-ssl (1.3.2)
* rack-test (0.6.2)
* rails-api (0.0.2)
* railties (3.2.9)
* rake (0.9.2.2)
* rdoc (3.12)
* simple_oauth (0.1.9)
* simplecov (0.7.1)
* simplecov-html (0.7.1)
* simplecov-rcov (0.2.3)
* slop (3.3.3)
* sprockets (2.2.1)
* thor (0.16.0)
* tilt (1.3.3)
* tinder (1.9.1)
* treetop (1.4.12)
* twitter-stream (0.1.16)
* tzinfo (0.3.35)
* xmlhash (1.3.3)
* yajl-ruby (1.1.0)
+ diff -u Gemfile.lock.orig Gemfile.lock
+ chmod a+x script/start_test_backend
+ echo 'Initialize test database, load seed data'
Initialize test database, load seed data
+ rake db:drop db:create db:setup --trace
** Invoke db:drop (first_time)
** Invoke db:load_config (first_time)
** Execute db:load_config
** Invoke rails_env (first_time)
** Execute rails_env
** Execute db:drop
** Invoke db:create (first_time)
** Invoke db:load_config
** Invoke rails_env
** Execute db:create
** Invoke db:setup (first_time)
** Execute db:setup
** Invoke db:structure:load (first_time)
** Invoke environment (first_time)
** Execute environment
** Invoke db:load_config
** Execute db:structure:load
** Invoke db:seed (first_time)
** Execute db:seed
** Invoke db:abort_if_pending_migrations (first_time)
** Invoke environment
** Invoke db:load_config
** Execute db:abort_if_pending_migrations
Seeding architectures table...
Seeding roles table...
Seeding users table...
Seeding roles_users table...
Seeding static_permissions table...
Seeding static permissions for admin role in roles_static_permissions table...
Seeding static permissions for maintainer role in roles_static_permissions table...
Seeding static permissions for reader role in roles_static_permissions table...
Seeding static permissions for downloader role in roles_static_permissions table...
Seeding attrib_namespaces table...
Seeding attrib_types table...
Seeding db_project_type table by loading test fixtures
Seeding issue trackers ...
+ cd ../..
+ setup_webui
+ echo 'Enter Webui rails root'
Enter Webui rails root
+ cd src/webui
+ echo 'Setup database configuration'
Setup database configuration
+ cp config/database.yml.example config/database.yml
+ echo 'Setup additional configuration'
Setup additional configuration
+ cp config/options.yml.example config/options.yml
+ echo 'Install missing gems locally'
Install missing gems locally
+ mv Gemfile.lock Gemfile.lock.orig
+ bundle list
[31mCould not find gem 'hoptoad_notifier (~> 2.3) ruby' in the gems available on this machine.[0m
Build step 'Execute shell' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
--
To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org
To contact the owner, e-mail: obs-tests+owner(a)opensuse.org
1
1
[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui #655
by admin@opensuse.org 21 Nov '12
by admin@opensuse.org 21 Nov '12
21 Nov '12
See <http://ci.opensuse.org/job/obs_master_testsuite_webui/655/changes>
Changes:
[Stephan Kulow] [webui] Remove homebrewn exception notification in favour of errbit
[Stephan Kulow] [webui] Don't use our own routing as exception middleware
[Stephan Kulow] [webui] Port all the exception logic to the new system
[Stephan Kulow] [webui] move the configuration of the errbit installation to options.yml
------------------------------------------
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Building in workspace <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/>
Checkout:workspace / <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/> - hudson.remoting.LocalChannel@28e928e9
Using strategy: Default
Last Built Revision: Revision 7bfbbd27f2f62247448d735239f8f1090a955793 (origin/master)
Checkout:workspace / <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/> - hudson.remoting.LocalChannel@28e928e9
Fetching changes from 1 remote Git repository
Fetching upstream changes from git://github.com/openSUSE/open-build-service.git
Commencing build of Revision 1c08ed07862146d9a7fb942b685dc022a908dd81 (origin/master)
Checking out Revision 1c08ed07862146d9a7fb942b685dc022a908dd81 (origin/master)
[locks-and-latches] Checking to see if we really have the locks
[locks-and-latches] Have all the locks, build can start
[workspace] $ /bin/sh -xe /tmp/hudson550168564257529846.sh
+ sh dist/ci/obs_testsuite_webui.sh
++ dirname dist/ci/obs_testsuite_webui.sh
+ . dist/ci/obs_testsuite_common.sh
+ setup_git
+ echo 'Checking status'
Checking status
+ git status
# Not currently on any branch.
# Untracked files:
# (use "git add <file>..." to include in what will be committed)
#
# src/api/Gemfile.lock.orig
# src/webui/Gemfile.lock.orig
# src/webui/db/structure.sql
# src/webui/public/themes/
# src/webui/results/
# src/webui/webrat.log
nothing added to commit but untracked files present (use "git add" to track)
+ echo 'Setup git submodules'
Setup git submodules
+ git submodule init
+ git submodule update
+ echo 'Setup backend configuration template'
Setup backend configuration template
+ sed -i -e 's|my $hostname = .*$|my $hostname = '\''localhost'\'';|' -e 's|our $bsuser = '\''obsrun'\'';|our $bsuser = '\''jenkins'\'';|' -e 's|our $bsgroup = '\''obsrun'\'';|our $bsgroup = '\''jenkins'\'';|' src/backend/BSConfig.pm.template
+ cp src/backend/BSConfig.pm.template src/backend/BSConfig.pm
+ echo 'Set environment variables'
Set environment variables
+ export RAILS_ENV=test
+ RAILS_ENV=test
+ ret=0
+ setup_api
+ echo 'Enter API rails root'
Enter API rails root
+ cd src/api
+ echo 'Setup database configuration'
Setup database configuration
+ cp config/database.yml.example config/database.yml
+ sed -i 's|database: api|database: ci_api|' config/database.yml
+ echo 'Setup additional configuration'
Setup additional configuration
+ cp config/options.yml.example config/options.yml
+ echo 'Install missing gems locally'
Install missing gems locally
+ mv Gemfile.lock Gemfile.lock.orig
+ bundle list
Gems included by the bundle:
* actionmailer (3.2.9)
* actionpack (3.2.9)
* activemodel (3.2.9)
* activerecord (3.2.9)
* activesupport (3.2.9)
* arel (3.0.2)
* builder (3.0.4)
* bundler (1.2.2)
* ci_reporter (1.7.3)
* coderay (1.0.8)
* daemons (1.1.9)
* database_cleaner (0.9.1)
* delayed_job (3.0.4)
* delayed_job_active_record (0.3.3)
* erubis (2.7.0)
* eventmachine (1.0.0)
* exception_notification (3.0.0)
* faraday (0.8.4)
* faraday_middleware (0.9.0)
* fast_xs (0.8.0)
* hashie (1.2.0)
* hike (1.2.1)
* http_parser.rb (0.5.3)
* i18n (0.6.1)
* journey (1.0.4)
* json (1.7.5)
* mail (2.4.4)
* memcache-client (1.8.5)
* method_source (0.8.1)
* mime-types (1.19)
* minitest (4.2.0)
* multi_json (1.3.7)
* multipart-post (1.1.5)
* mysql2 (0.3.11)
* nokogiri (1.5.5)
* pkg-config (1.1.4)
* polyglot (0.3.3)
* pry (0.9.10)
* rack (1.4.1)
* rack-cache (1.2)
* rack-ssl (1.3.2)
* rack-test (0.6.2)
* rails-api (0.0.2)
* railties (3.2.9)
* rake (0.9.2.2)
* rdoc (3.12)
* simple_oauth (0.1.9)
* simplecov (0.7.1)
* simplecov-html (0.7.1)
* simplecov-rcov (0.2.3)
* slop (3.3.3)
* sprockets (2.2.1)
* thor (0.16.0)
* tilt (1.3.3)
* tinder (1.9.1)
* treetop (1.4.12)
* twitter-stream (0.1.16)
* tzinfo (0.3.35)
* xmlhash (1.3.3)
* yajl-ruby (1.1.0)
+ diff -u Gemfile.lock.orig Gemfile.lock
+ chmod a+x script/start_test_backend
+ echo 'Initialize test database, load seed data'
Initialize test database, load seed data
+ rake db:drop db:create db:setup --trace
** Invoke db:drop (first_time)
** Invoke db:load_config (first_time)
** Execute db:load_config
** Invoke rails_env (first_time)
** Execute rails_env
** Execute db:drop
** Invoke db:create (first_time)
** Invoke db:load_config
** Invoke rails_env
** Execute db:create
** Invoke db:setup (first_time)
** Execute db:setup
** Invoke db:structure:load (first_time)
** Invoke environment (first_time)
** Execute environment
** Invoke db:load_config
** Execute db:structure:load
** Invoke db:seed (first_time)
** Execute db:seed
** Invoke db:abort_if_pending_migrations (first_time)
** Invoke environment
** Invoke db:load_config
** Execute db:abort_if_pending_migrations
Seeding architectures table...
Seeding roles table...
Seeding users table...
Seeding roles_users table...
Seeding static_permissions table...
Seeding static permissions for admin role in roles_static_permissions table...
Seeding static permissions for maintainer role in roles_static_permissions table...
Seeding static permissions for reader role in roles_static_permissions table...
Seeding static permissions for downloader role in roles_static_permissions table...
Seeding attrib_namespaces table...
Seeding attrib_types table...
Seeding db_project_type table by loading test fixtures
Seeding issue trackers ...
+ cd ../..
+ setup_webui
+ echo 'Enter Webui rails root'
Enter Webui rails root
+ cd src/webui
+ echo 'Setup database configuration'
Setup database configuration
+ cp config/database.yml.example config/database.yml
+ echo 'Setup additional configuration'
Setup additional configuration
+ cp config/options.yml.example config/options.yml
+ echo 'Install missing gems locally'
Install missing gems locally
+ mv Gemfile.lock Gemfile.lock.orig
+ bundle list
[31mCould not find gem 'hoptoad_notifier (~> 2.3) ruby' in the gems available on this machine.[0m
Build step 'Execute shell' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
--
To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org
To contact the owner, e-mail: obs-tests+owner(a)opensuse.org
1
1
[obs-tests] Build failed in Jenkins: obs_master_testsuite_api #1202
by admin@opensuse.org 21 Nov '12
by admin@opensuse.org 21 Nov '12
21 Nov '12
See <http://ci.opensuse.org/job/obs_master_testsuite_api/1202/changes>
Changes:
[Stephan Kulow] [webui] test the groups with capybara
[Stephan Kulow] [webui] port the messages test to capybara
[Stephan Kulow] [webui] fix request show for requests that have revs
[Stephan Kulow] [webui] trying to make the testsuite work again
[adrian] [api] fix caching of forbidden projects via all rails instances using memcache
[adrian] [api] add test case for standard package branch using noaccess
------------------------------------------
[...truncated 129971 lines...]
[[0;37mDEBUG[0m|#32242] [1m[35mPackage Load (0.4ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND (packages.db_project_id not in (0))
[[0;37mDEBUG[0m|#32242] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"response"}
[[0;37mDEBUG[0m|#32242] Validate XML response: #<ActionDispatch::Response:0x00000006d4d6a8> took 0ms
[[32mINFO [0m|#32242] Completed 200 OK in 18ms (Views: 0.5ms | ActiveRecord: 7.9ms | Backend: 0.0ms | XML: 0.0ms)
[[32mINFO [0m|#32242] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-11-21 12:21:29 +0100
[[32mINFO [0m|#32242] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#32242] Parameters: {"project"=>"kde4", "package"=>"kdebase"}
[[0;37mDEBUG[0m|#32242] Validate XML request: #<ActionDispatch::Request:0x00000006fa9578>
[[0;37mDEBUG[0m|#32242] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"}
[[0;37mDEBUG[0m|#32242] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#32242] [1m[36mUser Load (0.3ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] USER found: king
[[0;37mDEBUG[0m|#32242] User king initialised
[[0;37mDEBUG[0m|#32242] get_by_project_and_name {}
[[0;37mDEBUG[0m|#32242] [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|#32242] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mPackage Load (6.7ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b646562617365' AND (packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mProject Load (0.3ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] Validate XML response: #<ActionDispatch::Response:0x00000006fa9550> took 2ms
[[32mINFO [0m|#32242] Completed 200 OK in 26ms (Views: 0.5ms | ActiveRecord: 7.7ms | Backend: 0.0ms | XML: 0.3ms)
[[32mINFO [0m|#32242] Started PUT "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-11-21 12:21:29 +0100
[[32mINFO [0m|#32242] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#32242] Parameters: {"<package name"=>"\"kdebase\" project=\"kde4\">\n <title>blub</title>\n <description>blub</description>\n</package>\n", "project"=>"kde4", "package"=>"kdebase"}
[[0;37mDEBUG[0m|#32242] Validate XML request: #<ActionDispatch::Request:0x000000070e9f78>
[[0;37mDEBUG[0m|#32242] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#32242] [1m[35mUser Load (0.3ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1
[[0;37mDEBUG[0m|#32242] USER found: king
[[0;37mDEBUG[0m|#32242] User king initialised
[[0;37mDEBUG[0m|#32242] [1m[36mRole Load (0.3ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mProject Load (0.2ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mPackage Load (0.2ms)[0m [1mSELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b646562617365' AND (packages.db_project_id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mProject Load (0.2ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#32242] get_by_project_and_name {}
[[0;37mDEBUG[0m|#32242] [1m[36mCACHE (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mCACHE (0.0ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b646562617365' AND (packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mCACHE (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position
[[0;37mDEBUG[0m|#32242] flagcheck: package has default state, checking project
[[0;37mDEBUG[0m|#32242] [1m[36mFlag Load (0.1ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2[0m
[[0;37mDEBUG[0m|#32242] disabled_for sourceaccess repo: arch: state:enable
[[0;37mDEBUG[0m|#32242] xml_disabled? X({"name"=>"kdebase", "project"=>"kde4", "title"=>"blub", "description"=>"blub"})
[[0;37mDEBUG[0m|#32242] [1m[35mPackageUserRoleRelationship Load (0.1ms)[0m SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 2
[[0;37mDEBUG[0m|#32242] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#32242] ----------------- rendering package kdebase ------------------------
[[0;37mDEBUG[0m|#32242] [1m[35mPackageUserRoleRelationship Load (0.2ms)[0m SELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 2
[[0;37mDEBUG[0m|#32242] [1m[36mPackageGroupRoleRelationship Load (0.2ms)[0m [1mSELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 2[0m
[[0;37mDEBUG[0m|#32242] ----------------- end rendering package kdebase ------------------------
[[0;37mDEBUG[0m|#32242] [backend] PUT: /source/kde4/kdebase/_meta?user=king
[[0;37mDEBUG[0m|#32247] 2012-11-21 12:21:29 [5832]: PUT /source/kde4/kdebase/_meta?user=king
[[0;37mDEBUG[0m|#32242] request took 0.01280039
[[0;37mDEBUG[0m|#32242] [1m[35mPackage Load (0.4ms)[0m SELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp,
IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 2 LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mPackageGroupRoleRelationship Load (0.5ms)[0m SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 2
[[0;37mDEBUG[0m|#32242] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#32242] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#32242] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#32242] ----------------- rendering package kdebase ------------------------
[[0;37mDEBUG[0m|#32242] [1m[35mCACHE (0.0ms)[0m SELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 2
[[0;37mDEBUG[0m|#32242] [1m[36mCACHE (0.0ms)[0m [1mSELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 2[0m
[[0;37mDEBUG[0m|#32242] ----------------- end rendering package kdebase ------------------------
[[0;37mDEBUG[0m|#32242] [backend] PUT: /source/kde4/kdebase/_meta?user=king
[[0;37mDEBUG[0m|#32247] 2012-11-21 12:21:29 [5833]: PUT /source/kde4/kdebase/_meta?user=king
[[0;37mDEBUG[0m|#32242] request took 0.01911368
[[0;37mDEBUG[0m|#32242] [1m[35mCACHE (0.0ms)[0m SELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp,
IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 2 LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#32242] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#32242] ----------------- rendering package kdebase ------------------------
[[0;37mDEBUG[0m|#32242] [1m[36mCACHE (0.0ms)[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|#32242] [1m[35mCACHE (0.0ms)[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|#32242] ----------------- end rendering package kdebase ------------------------
[[0;37mDEBUG[0m|#32242] [backend] PUT: /source/kde4/kdebase/_meta?user=king
[[0;37mDEBUG[0m|#32247] 2012-11-21 12:21:29 [5834]: PUT /source/kde4/kdebase/_meta?user=king
[[0;37mDEBUG[0m|#32242] request took 0.023427103
[[0;37mDEBUG[0m|#32242] [1m[36mCACHE (0.0ms)[0m [1mSELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp,
IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 2 LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35m (6.2ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#32242] Validate XML response: #<ActionDispatch::Response:0x000000070e9f50> took 1ms
[[32mINFO [0m|#32242] Completed 200 OK in 117ms (Views: 1.1ms | ActiveRecord: 10.3ms | Backend: 55.3ms | XML: 0.0ms)
[[32mINFO [0m|#32242] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-11-21 12:21:29 +0100
[[32mINFO [0m|#32242] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#32242] Parameters: {"project"=>"kde4", "package"=>"kdebase"}
[[0;37mDEBUG[0m|#32242] Validate XML request: #<ActionDispatch::Request:0x00000007317868>
[[0;37mDEBUG[0m|#32242] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"}
[[0;37mDEBUG[0m|#32242] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#32242] [1m[36mUser Load (0.3ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] USER found: king
[[0;37mDEBUG[0m|#32242] User king initialised
[[0;37mDEBUG[0m|#32242] get_by_project_and_name {}
[[0;37mDEBUG[0m|#32242] [1m[35mRole Load (6.9ms)[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|#32242] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mPackage Load (6.9ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b646562617365' AND (packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] Validate XML response: #<ActionDispatch::Response:0x00000007317750> took 2ms
[[32mINFO [0m|#32242] Completed 200 OK in 28ms (Views: 0.6ms | ActiveRecord: 14.6ms | Backend: 0.0ms | XML: 0.0ms)
[[32mINFO [0m|#32242] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-21 12:21:29 +0100
[[32mINFO [0m|#32242] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#32242] Parameters: {"project"=>"kde4", "package"=>"kdelibs"}
[[0;37mDEBUG[0m|#32242] Validate XML request: #<ActionDispatch::Request:0x00000007664520>
[[0;37mDEBUG[0m|#32242] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"}
[[0;37mDEBUG[0m|#32242] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#32242] [1m[35mUser Load (8.7ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1
[[0;37mDEBUG[0m|#32242] USER found: king
[[0;37mDEBUG[0m|#32242] User king initialised
[[0;37mDEBUG[0m|#32242] get_by_project_and_name {}
[[0;37mDEBUG[0m|#32242] [1m[36mRole Load (5.9ms)[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|#32242] [1m[35mProject Load (0.2ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mPackage Load (0.4ms)[0m [1mSELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b64656c696273' AND (packages.db_project_id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mProject Load (0.2ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#32242] Validate XML response: #<ActionDispatch::Response:0x000000076644f8> took 2ms
[[32mINFO [0m|#32242] Completed 200 OK in 29ms (Views: 0.4ms | ActiveRecord: 15.4ms | Backend: 0.0ms | XML: 0.0ms)
[[32mINFO [0m|#32242] Started PUT "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-21 12:21:30 +0100
[[32mINFO [0m|#32242] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#32242] Parameters: {"<package name"=>"\"kdelibs\" project=\"kde4\">\n <title>blub</title>\n <description>Changed description</description>\n <devel project=\"home:Iggy\" package=\"TestPack\"/>\n <person userid=\"fredlibs\" role=\"maintainer\"/>\n <person userid=\"adrian\" role=\"reviewer\"/>\n <person userid=\"Iggy\" role=\"maintainer\"/>\n <person userid=\"Iggy\" role=\"bugowner\"/>\n <group groupid=\"test_group\" role=\"maintainer\"/>\n <group groupid=\"test_group\" role=\"reader\"/>\n <build>\n <enable/>\n </build>\n</package>\n", "project"=>"kde4", "package"=>"kdelibs"}
[[0;37mDEBUG[0m|#32242] Validate XML request: #<ActionDispatch::Request:0x000000077a0ad8>
[[0;37mDEBUG[0m|#32242] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#32242] [1m[36mUser Load (0.2ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] USER found: king
[[0;37mDEBUG[0m|#32242] User king initialised
[[0;37mDEBUG[0m|#32242] [1m[35mRole Load (0.2ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mPackage Load (0.2ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b64656c696273' AND (packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] get_by_project_and_name {}
[[0;37mDEBUG[0m|#32242] [1m[35mCACHE (0.0ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mCACHE (0.0ms)[0m [1mSELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b64656c696273' AND (packages.db_project_id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mCACHE (0.0ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mFlag Load (3.5ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mFlag Load (0.3ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position[0m
[[0;37mDEBUG[0m|#32242] flagcheck: package has default state, checking project
[[0;37mDEBUG[0m|#32242] [1m[35mFlag Load (0.1ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2
[[0;37mDEBUG[0m|#32242] disabled_for sourceaccess repo: arch: state:enable
[[0;37mDEBUG[0m|#32242] xml_disabled? X({"name"=>"kdelibs", "project"=>"kde4", "title"=>"blub", "description"=>"Changed description", "devel"=>X({"project"=>"home:Iggy", "package"=>"TestPack"}), "person"=>[X({"userid"=>"fredlibs", "role"=>"maintainer"}), X({"userid"=>"adrian", "role"=>"reviewer"}), X({"userid"=>"Iggy", "role"=>"maintainer"}), X({"userid"=>"Iggy", "role"=>"bugowner"})], "group"=>[X({"groupid"=>"test_group", "role"=>"maintainer"}), X({"groupid"=>"test_group", "role"=>"reader"})], "build"=>X({"enable"=>X({})})})
[[0;37mDEBUG[0m|#32242] [1m[36mProject Load (0.5ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'686f6d653a49676779' AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mPackage Load (0.2ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 502 AND `packages`.`name` = x'546573745061636b' AND (packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 502 AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [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|#32242] [1m[36mUser Load (0.2ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`id` = 4 LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mRole Load (0.1ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mUser Load (0.1ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`id` = 42 LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mRole Load (0.1ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 6 LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#32242] [1m[35m (0.3ms)[0m UPDATE `packages` SET `description` = 'Changed description', `develpackage_id` = 10095, `updated_at` = '2012-11-21 11:21:30' WHERE `packages`.`id` = 1
[[0;37mDEBUG[0m|#32242] ----------------- rendering package kdelibs ------------------------
[[0;37mDEBUG[0m|#32242] [1m[36mPackageUserRoleRelationship Load (0.2ms)[0m [1mSELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mPackageGroupRoleRelationship Load (0.2ms)[0m SELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 1
[[0;37mDEBUG[0m|#32242] ----------------- end rendering package kdelibs ------------------------
[[0;37mDEBUG[0m|#32242] [backend] PUT: /source/kde4/kdelibs/_meta?user=king
[[0;37mDEBUG[0m|#32247] 2012-11-21 12:21:30 [5835]: PUT /source/kde4/kdelibs/_meta?user=king
[[0;37mDEBUG[0m|#32247] 2012-11-21 12:21:30 [5836]: POST /event?type=package&project=kde4&package=kdelibs
[[0;37mDEBUG[0m|#32242] request took 0.044982294
[[0;37mDEBUG[0m|#32242] [1m[36mPackage Load (0.8ms)[0m [1mSELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp,
IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 1 LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35m (0.3ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#32242] [1m[36mUser Load (0.3ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#32242] [1m[36mPackageUserRoleRelationship Load (0.2ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 3 AND bs_user_id = 267) LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mSQL (16.3ms)[0m INSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 3)
[[0;37mDEBUG[0m|#32242] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#32242] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#32242] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mUser Load (0.3ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mPackageUserRoleRelationship Load (0.2ms)[0m SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 4 AND bs_user_id = 267) LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mSQL (0.2ms)[0m [1mINSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 4)[0m
[[0;37mDEBUG[0m|#32242] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#32242] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#32242] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#32242] [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|#32242] [1m[35mGroup Load (0.1ms)[0m SELECT `groups`.* FROM `groups` WHERE `groups`.`id` = 99 LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mRole Load (0.1ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mGroup Load (0.2ms)[0m SELECT `groups`.* FROM `groups` WHERE `groups`.`title` = 'test_group' LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mPackageGroupRoleRelationship Load (0.1ms)[0m SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE (db_package_id = 1 AND role_id = 7 AND bs_group_id = 99) LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mSQL (0.1ms)[0m [1mINSERT INTO `package_group_role_relationships` (`bs_group_id`, `db_package_id`, `role_id`) VALUES (99, 1, 7)[0m
[[0;37mDEBUG[0m|#32242] [1m[35m (8.1ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#32242] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#32242] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#32242] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mPackage Load (0.3ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`id` = 1 AND (packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36m (0.3ms)[0m [1mSELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mCACHE (0.0ms)[0m SELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1
[[0;37mDEBUG[0m|#32242] [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|#32242] ----------------- rendering package kdelibs ------------------------
[[0;37mDEBUG[0m|#32242] [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|#32242] [1m[36mPackageGroupRoleRelationship Load (0.2ms)[0m [1mSELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 1[0m
[[0;37mDEBUG[0m|#32242] ----------------- end rendering package kdelibs ------------------------
[[0;37mDEBUG[0m|#32242] [backend] PUT: /source/kde4/kdelibs/_meta?user=king
[[0;37mDEBUG[0m|#32247] 2012-11-21 12:21:30 [5837]: PUT /source/kde4/kdelibs/_meta?user=king
[[0;37mDEBUG[0m|#32247] 2012-11-21 12:21:30 [5838]: POST /event?type=package&project=kde4&package=kdelibs
[[0;37mDEBUG[0m|#32242] request took 0.045254932
[[0;37mDEBUG[0m|#32242] [1m[35mPackage Load (0.7ms)[0m SELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp,
IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 1 LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#32242] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#32242] ----------------- rendering package kdelibs ------------------------
[[0;37mDEBUG[0m|#32242] [1m[36mCACHE (0.0ms)[0m [1mSELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mCACHE (0.0ms)[0m SELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 1
[[0;37mDEBUG[0m|#32242] ----------------- end rendering package kdelibs ------------------------
[[0;37mDEBUG[0m|#32242] [backend] PUT: /source/kde4/kdelibs/_meta?user=king
[[0;37mDEBUG[0m|#32247] 2012-11-21 12:21:30 [5839]: PUT /source/kde4/kdelibs/_meta?user=king
[[0;37mDEBUG[0m|#32242] request took 0.020816995
[[0;37mDEBUG[0m|#32242] [1m[36mCACHE (0.0ms)[0m [1mSELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp,
IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 1 LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#32242] Validate XML response: #<ActionDispatch::Response:0x000000077a0ab0> took 1ms
[[32mINFO [0m|#32242] Completed 200 OK in 396ms (Views: 1.0ms | ActiveRecord: 38.4ms | Backend: 111.1ms | XML: 0.0ms)
[[32mINFO [0m|#32242] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-21 12:21:30 +0100
[[32mINFO [0m|#32242] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#32242] Parameters: {"project"=>"kde4", "package"=>"kdelibs"}
[[0;37mDEBUG[0m|#32242] Validate XML request: #<ActionDispatch::Request:0x00000006a34c78>
[[0;37mDEBUG[0m|#32242] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"}
[[0;37mDEBUG[0m|#32242] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#32242] [1m[36mUser Load (5.9ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] USER found: king
[[0;37mDEBUG[0m|#32242] User king initialised
[[0;37mDEBUG[0m|#32242] get_by_project_and_name {}
[[0;37mDEBUG[0m|#32242] [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|#32242] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] [1m[35mPackage Load (8.1ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b64656c696273' AND (packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#32242] [1m[36mProject Load (5.4ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#32242] Validate XML response: #<ActionDispatch::Response:0x00000006a34c50> took 3ms
[[32mINFO [0m|#32242] Completed 200 OK in 36ms (Views: 0.6ms | ActiveRecord: 20.1ms | Backend: 0.0ms | XML: 0.0ms)
[[0;37mDEBUG[0m|#32242] [1m[35m (8.7ms)[0m ROLLBACK
[[0;37mDEBUG[0m|#32242] kill 32247
+ echo
+ cleanup
+ echo 'Killing backend processes'
Killing backend processes
+ fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_api/ws/>
+ egrep 'perl|ruby'
USER PID ACCESS COMMAND
<http://ci.opensuse.org/job/obs_master_testsuite_api/ws/>:
jenkins ..c.. sh
jenkins ..c.. sh
jenkins ..c.. sh
+ echo 'Remove log/tmp files to save disc space'
Remove log/tmp files to save disc space
+ rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/scheduler.done src/api/tmp/sessions src/api/tmp/sockets 'src/webui/log/*' src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets
+ exit 1
Build step 'Execute shell' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
--
To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org
To contact the owner, e-mail: obs-tests+owner(a)opensuse.org
1
1
[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui #648
by admin@opensuse.org 21 Nov '12
by admin@opensuse.org 21 Nov '12
21 Nov '12
See <http://ci.opensuse.org/job/obs_master_testsuite_webui/648/changes>
Changes:
[Sascha Peilicke] Revert "[webui] Add 'database_cleaner' to :test group in Gemfile"
------------------------------------------
[...truncated 53474 lines...]
],
"xml": "<project name=\"BaseDistro\">\n <title>This is a base distro</title>\n <description>This could be openSUSE:11.2 project for example</description>\n <repository name=\"BaseDistro_repo\">\n <releasetarget project=\"BaseDistro:Update\" repository=\"BaseDistroUpdateProject_repo\"/>\n <arch>i586</arch>\n </repository>\n</project>\n",
"maintenance_project": "My:Maintenance",
"linking_projects": [
"BaseDistro:Update"
],
"requests": [
],
"nr_of_problem_packages": 0
}
[[32mINFO [0m|#26371] Rendered project/_tabs.html.erb (2.8ms)
[[32mINFO [0m|#26371] Rendered shared/_open_requests.html.erb (0.0ms)
[[32mINFO [0m|#26371] Rendered shared/_buildresult_box.html.erb (0.6ms)
[[32mINFO [0m|#26371] Rendered layouts/_watch_and_search.html.erb (1.2ms)
[[32mINFO [0m|#26371] Rendered layouts/_header.html.erb (2.0ms)
[[32mINFO [0m|#26371] Rendered layouts/_personal_navigation.html.erb (1.0ms)
[[32mINFO [0m|#26371] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|#26371] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|#26371] Completed 200 OK in 219ms (Views: 14.4ms | ActiveRecord: 0.0ms | API: 199.9ms (XML: 0.0ms , View: 1.5ms , Backend: 0.0ms , DB: 6.5ms , HTTP: 118.5ms) | XML: 0.6ms)
[[32mINFO [0m|#26371] Started GET "/project/buildresult?project=BaseDistro&index=" for 127.0.0.1 at 2012-11-20 19:17:07 +0100
[[32mINFO [0m|#26371] Processing by ProjectController#buildresult as */*
[[32mINFO [0m|#26371] Parameters: {"project"=>"BaseDistro", "index"=>""}
[[0;37mDEBUG[0m|#26371] Setting return_to: "/project/buildresult?project=BaseDistro&index="
[[0;37mDEBUG[0m|#26371] Authenticating with iChain mode: off
[[32mINFO [0m|#26371] Authenticated request to "/project/buildresult?project=BaseDistro&index=" from tom
[[0;37mDEBUG[0m|#26371] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#26371] params {:is_current=>"true", :login=>"tom"}
[[0;37mDEBUG[0m|#26371] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|#26371] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#26371] RT http://localhost:3203/person/tom {"view"=>0.517969, "db"=>0.889409, "backend"=>0, "xml"=>0, :runtime=>9.846, :all=>12.998944}
[[0;37mDEBUG[0m|#26371] [REST] find( Buildresult, [{:project=>"BaseDistro", :view=>"summary"}] )
[[0;37mDEBUG[0m|#26371] params {:project=>"BaseDistro", :view=>"summary"}
[[0;37mDEBUG[0m|#26371] uri is: http://localhost:3203/build/:project/_result?:view&:package&:code&:lastbuil…
[[0;37mDEBUG[0m|#26371] http_do #1: method: get url: http://localhost:3203/build/BaseDistro/_result?view=summary
[[0;37mDEBUG[0m|#26371] RT http://localhost:3203/build/BaseDistro/_result?view=summary {"view"=>nil, "db"=>0.623529, "backend"=>0, "xml"=>0, :runtime=>27.2, :all=>68.218658}
[[32mINFO [0m|#26371] Rendered project/_buildstatus.html.erb (0.1ms)
[[32mINFO [0m|#26371] Completed 200 OK in 84ms (Views: 0.7ms | ActiveRecord: 0.0ms | API: 81.2ms (XML: 0.0ms , View: 0.5ms , Backend: 0.0ms , DB: 1.5ms , HTTP: 44.2ms) | XML: 0.2ms)
[[32mINFO [0m|#26371] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-20 19:17:08 +0100
[[32mINFO [0m|#26371] Processing by ProjectController#show as HTML
[[32mINFO [0m|#26371] Parameters: {"project"=>"My:Maintenance"}
[[0;37mDEBUG[0m|#26371] Setting return_to: "/project/show?project=My%3AMaintenance"
[[0;37mDEBUG[0m|#26371] Authenticating with iChain mode: off
[[32mINFO [0m|#26371] Authenticated request to "/project/show?project=My%3AMaintenance" from tom
[[0;37mDEBUG[0m|#26371] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#26371] params {:is_current=>"true", :login=>"tom"}
[[0;37mDEBUG[0m|#26371] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|#26371] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#26371] RT http://localhost:3203/person/tom {"view"=>0.551543, "db"=>1.05809, "backend"=>0, "xml"=>0, :runtime=>10.144, :all=>13.544260000000001}
[[0;37mDEBUG[0m|#26371] --> direct_http url: #<URI::Generic:0x00000007819370 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom>
[[0;37mDEBUG[0m|#26371] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom
[[0;37mDEBUG[0m|#26371] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.225582, "db"=>3.2810309999999996, "backend"=>0, "xml"=>0, :runtime=>30.66, :all=>72.926725}
[[0;37mDEBUG[0m|#26371] data {
"declined": [
],
"new": [
],
"reviews": [
1000
]
}
[[0;37mDEBUG[0m|#26371] --> direct_http url: #<URI::Generic:0x00000007837cd0 URL:http://localhost:3203/webui/project_infos?project=My%3AMaintenance>
[[0;37mDEBUG[0m|#26371] http_do #1: method: GET url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance
[[0;37mDEBUG[0m|#26371] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.393236, "db"=>3.0441700000000003, "backend"=>0, "xml"=>0, :runtime=>31.343000000000004, :all=>71.557782}
[[0;37mDEBUG[0m|#26371] data {
"name": "My:Maintenance",
"packages": [
],
"xml": "<project name=\"My:Maintenance\" kind=\"maintenance\">\n <title>official maintenance space</title>\n <description>official maintenance space, maintained by maintenance ;)</description>\n <person userid=\"maintenance_coord\" role=\"maintainer\"/>\n <maintenance>\n <maintains project=\"BaseDistro\"/>\n <maintains project=\"BaseDistro2.0:LinkedUpdateProject\"/>\n <maintains project=\"BaseDistro3\"/>\n </maintenance>\n</project>\n",
"incidents": [
],
"maintained_projects": [
"BaseDistro",
"BaseDistro2.0:LinkedUpdateProject",
"BaseDistro3"
],
"linking_projects": [
],
"requests": [
],
"nr_of_problem_packages": 0
}
[[32mINFO [0m|#26371] Rendered project/_tabs.html.erb (3.0ms)
[[32mINFO [0m|#26371] Rendered shared/_open_requests.html.erb (0.0ms)
[[32mINFO [0m|#26371] Rendered layouts/_watch_and_search.html.erb (1.9ms)
[[32mINFO [0m|#26371] Rendered layouts/_header.html.erb (2.9ms)
[[32mINFO [0m|#26371] Rendered layouts/_personal_navigation.html.erb (1.1ms)
[[32mINFO [0m|#26371] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|#26371] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|#26371] Completed 200 OK in 177ms (Views: 14.9ms | ActiveRecord: 0.0ms | API: 158.0ms (XML: 0.0ms , View: 1.2ms , Backend: 0.0ms , DB: 7.4ms , HTTP: 85.9ms) | XML: 0.5ms)
[[32mINFO [0m|#26371] Started GET "/project/toggle_watch?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-20 19:17:08 +0100
[[32mINFO [0m|#26371] Processing by ProjectController#toggle_watch as HTML
[[32mINFO [0m|#26371] Parameters: {"project"=>"My:Maintenance"}
[[0;37mDEBUG[0m|#26371] Setting return_to: "/project/toggle_watch?project=My%3AMaintenance"
[[0;37mDEBUG[0m|#26371] Authenticating with iChain mode: off
[[32mINFO [0m|#26371] Authenticated request to "/project/toggle_watch?project=My%3AMaintenance" from tom
[[0;37mDEBUG[0m|#26371] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#26371] params {:is_current=>"true", :login=>"tom"}
[[0;37mDEBUG[0m|#26371] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|#26371] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#26371] RT http://localhost:3203/person/tom {"view"=>0.921768, "db"=>1.646994, "backend"=>0, "xml"=>0, :runtime=>11.359, :all=>15.140104}
[[0;37mDEBUG[0m|#26371] --> direct_http url: #<URI::Generic:0x00000005fa8a70 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom>
[[0;37mDEBUG[0m|#26371] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom
[[0;37mDEBUG[0m|#26371] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.222659, "db"=>3.151033, "backend"=>0, "xml"=>0, :runtime=>31.102, :all=>73.779377}
[[0;37mDEBUG[0m|#26371] data {
"declined": [
],
"new": [
],
"reviews": [
1000
]
}
[[0;37mDEBUG[0m|#26371] Remove My:Maintenance from watchlist for tom
[[0;37mDEBUG[0m|#26371] user 'tom' removes project 'My:Maintenance' from watchlist
[[0;37mDEBUG[0m|#26371] saving object Person ({:is_current=>"true", :login=>"tom"}) to api:
<person>
<login>tom</login>
<email>tschmidt(a)suse.de</email>
<realname>Thor</realname>
<watchlist>
</watchlist>
</person>
[[0;37mDEBUG[0m|#26371] http_do #1: method: put url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#26371] RT http://localhost:3203/person/tom {"view"=>0.870871, "db"=>1.200027, "backend"=>0, "xml"=>0, :runtime=>15.915, :all=>22.409047}
[[32mINFO [0m|#26371] Redirected to http://127.0.0.1:39720/project/show?project=My%3AMaintenance
[[32mINFO [0m|#26371] Completed 302 Found in 115ms (ActiveRecord: 0.0ms | API: 111.3ms (XML: 0.0ms , View: 2.0ms , Backend: 0.0ms , DB: 6.0ms , HTTP: 53.0ms) | XML: 0.2ms)
[[32mINFO [0m|#26371] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-20 19:17:08 +0100
[[32mINFO [0m|#26371] Processing by ProjectController#show as HTML
[[32mINFO [0m|#26371] Parameters: {"project"=>"My:Maintenance"}
[[0;37mDEBUG[0m|#26371] Setting return_to: "/project/show?project=My%3AMaintenance"
[[0;37mDEBUG[0m|#26371] Authenticating with iChain mode: off
[[32mINFO [0m|#26371] Authenticated request to "/project/show?project=My%3AMaintenance" from tom
[[0;37mDEBUG[0m|#26371] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#26371] params {:is_current=>"true", :login=>"tom"}
[[0;37mDEBUG[0m|#26371] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|#26371] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#26371] RT http://localhost:3203/person/tom {"view"=>0.816483, "db"=>1.098901, "backend"=>0, "xml"=>0, :runtime=>61.379999999999995, :all=>104.359752}
[[0;37mDEBUG[0m|#26371] --> direct_http url: #<URI::Generic:0x00000007850230 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom>
[[0;37mDEBUG[0m|#26371] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom
[[0;37mDEBUG[0m|#26371] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.227922, "db"=>3.418996, "backend"=>0, "xml"=>0, :runtime=>34.205, :all=>75.946662}
[[0;37mDEBUG[0m|#26371] data {
"declined": [
],
"new": [
],
"reviews": [
1000
]
}
[[0;37mDEBUG[0m|#26371] --> direct_http url: #<URI::Generic:0x00000007862700 URL:http://localhost:3203/webui/project_infos?project=My%3AMaintenance>
[[0;37mDEBUG[0m|#26371] http_do #1: method: GET url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance
[[0;37mDEBUG[0m|#26371] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.422037, "db"=>2.867614, "backend"=>0, "xml"=>0, :runtime=>33.508, :all=>75.34647}
[[0;37mDEBUG[0m|#26371] data {
"name": "My:Maintenance",
"packages": [
],
"xml": "<project name=\"My:Maintenance\" kind=\"maintenance\">\n <title>official maintenance space</title>\n <description>official maintenance space, maintained by maintenance ;)</description>\n <person userid=\"maintenance_coord\" role=\"maintainer\"/>\n <maintenance>\n <maintains project=\"BaseDistro\"/>\n <maintains project=\"BaseDistro2.0:LinkedUpdateProject\"/>\n <maintains project=\"BaseDistro3\"/>\n </maintenance>\n</project>\n",
"incidents": [
],
"maintained_projects": [
"BaseDistro",
"BaseDistro2.0:LinkedUpdateProject",
"BaseDistro3"
],
"linking_projects": [
],
"requests": [
],
"nr_of_problem_packages": 0
}
[[32mINFO [0m|#26371] Rendered project/_tabs.html.erb (2.3ms)
[[32mINFO [0m|#26371] Rendered shared/_open_requests.html.erb (0.0ms)
[[32mINFO [0m|#26371] Rendered layouts/_watch_and_search.html.erb (1.8ms)
[[32mINFO [0m|#26371] Rendered layouts/_header.html.erb (2.7ms)
[[32mINFO [0m|#26371] Rendered layouts/_personal_navigation.html.erb (1.1ms)
[[32mINFO [0m|#26371] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|#26371] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|#26371] Completed 200 OK in 275ms (Views: 14.2ms | ActiveRecord: 0.0ms | API: 255.7ms (XML: 0.0ms , View: 1.5ms , Backend: 0.0ms , DB: 7.4ms , HTTP: 126.6ms) | XML: 0.5ms)
[[32mINFO [0m|#26371] Started POST "/user/logout" for 127.0.0.1 at 2012-11-20 19:17:09 +0100
[[32mINFO [0m|#26371] Processing by UserController#logout as HTML
[[0;37mDEBUG[0m|#26371] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|#26371] Authenticating with iChain mode: off
[[32mINFO [0m|#26371] Authenticated request to "/user/logout" from tom
[[32mINFO [0m|#26371] Logging out: tom
[[32mINFO [0m|#26371] Redirected to http://127.0.0.1:39720/
[[32mINFO [0m|#26371] Completed 302 Found in 34ms (ActiveRecord: 32.5ms | XML: 0.0ms)
[[32mINFO [0m|#26371] Started GET "/" for 127.0.0.1 at 2012-11-20 19:17:09 +0100
[[32mINFO [0m|#26371] Processing by MainController#index as HTML
[[0;37mDEBUG[0m|#26371] Setting return_to: "/"
[[0;37mDEBUG[0m|#26371] Authenticating with iChain mode: off
[[32mINFO [0m|#26371] Anonymous request to /
[[32mINFO [0m|#26371] Rendered main/_news.html.erb (0.6ms)
[[32mINFO [0m|#26371] Rendered main/_contact.html.erb (0.1ms)
[[32mINFO [0m|#26371] Rendered main/_latest_updates.erb (4.7ms)
[[32mINFO [0m|#26371] Rendered main/_countdown.html.erb (0.1ms)
[[32mINFO [0m|#26371] Rendered main/_sponsors.html.erb (0.1ms)
[[32mINFO [0m|#26371] Rendered main/_feeds.html.erb (0.1ms)
[[32mINFO [0m|#26371] Rendered layouts/_watch_and_search.html.erb (0.6ms)
[[32mINFO [0m|#26371] Rendered layouts/_header.html.erb (1.2ms)
[[32mINFO [0m|#26371] Rendered layouts/_personal_navigation.html.erb (1.2ms)
[[32mINFO [0m|#26371] Rendered layouts/_flash.html.erb (0.1ms)
[[32mINFO [0m|#26371] Rendered layouts/_sponsor.html.erb (0.1ms)
[[32mINFO [0m|#26371] Completed 200 OK in 18ms (Views: 15.3ms | ActiveRecord: 0.0ms | XML: 0.8ms)
[[32mINFO [0m|#26371] Started GET "/main/systemstatus" for 127.0.0.1 at 2012-11-20 19:17:09 +0100
[[32mINFO [0m|#26371] Processing by MainController#systemstatus as */*
[[0;37mDEBUG[0m|#26371] Setting return_to: "/main/systemstatus"
[[0;37mDEBUG[0m|#26371] Authenticating with iChain mode: off
[[32mINFO [0m|#26371] Anonymous request to /main/systemstatus
[[32mINFO [0m|#26371] Rendered main/_systemstatus.html.erb (1.4ms)
[[32mINFO [0m|#26371] Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.0ms | XML: 0.4ms)
[[0;37mDEBUG[0m|#26371] --> direct_http url: #<URI::Generic:0x000000060e17e8 URL:http://localhost:3203/test/test_end>
[[0;37mDEBUG[0m|#26371] http_do #1: method: GET url: http://localhost:3203/test/test_end
[[0;37mDEBUG[0m|#26371] RT http://localhost:3203/test/test_end {"view"=>1.194891, "db"=>20.906999, "backend"=>0, "xml"=>0, :runtime=>26.200999999999997, :all=>29.485383}
+ echo
+ cleanup
+ echo 'Killing backend processes'
Killing backend processes
+ fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/>
+ egrep 'perl|ruby'
USER PID ACCESS COMMAND
<http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/>:
jenkins ..c.. sh
jenkins ..c.. sh
jenkins ..c.. egrep
+ echo 'Remove log/tmp files to save disc space'
Remove log/tmp files to save disc space
+ rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/scheduler.done src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/capybara src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets
+ exit 1
Build step 'Execute shell' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
--
To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org
To contact the owner, e-mail: obs-tests+owner(a)opensuse.org
1
3
[obs-tests] Build failed in Jenkins: obs_master_testsuite_api #1200
by admin@opensuse.org 20 Nov '12
by admin@opensuse.org 20 Nov '12
20 Nov '12
See <http://ci.opensuse.org/job/obs_master_testsuite_api/1200/changes>
Changes:
[Sascha Peilicke] [webui] Add 'database_cleaner' to :test group in Gemfile
[mls] [backend] remove excess space
[mls] [backend] send projpack data with ymp/fileinfo_ext requests so that we cannot deadlock
------------------------------------------
[...truncated 129075 lines...]
[[0;37mDEBUG[0m|#22821] [1m[35mPackage Load (0.2ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND (packages.db_project_id not in (0))
[[0;37mDEBUG[0m|#22821] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"index_project", "project"=>"kde4", "method"=>"GET", "type"=>"response"}
[[0;37mDEBUG[0m|#22821] Validate XML response: #<ActionDispatch::Response:0x00000007858980> took 0ms
[[32mINFO [0m|#22821] Completed 200 OK in 8ms (Views: 0.3ms | ActiveRecord: 0.7ms | Backend: 0.0ms | XML: 0.0ms)
[[32mINFO [0m|#22821] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-11-20 17:40:01 +0100
[[32mINFO [0m|#22821] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#22821] Parameters: {"project"=>"kde4", "package"=>"kdebase"}
[[0;37mDEBUG[0m|#22821] Validate XML request: #<ActionDispatch::Request:0x00000007827da8>
[[0;37mDEBUG[0m|#22821] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"}
[[0;37mDEBUG[0m|#22821] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#22821] [1m[36mUser Load (0.1ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] USER found: king
[[0;37mDEBUG[0m|#22821] User king initialised
[[0;37mDEBUG[0m|#22821] get_by_project_and_name {}
[[0;37mDEBUG[0m|#22821] [1m[35mRole Load (0.2ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mPackage Load (0.2ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b646562617365' AND (packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mProject Load (0.1ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] Validate XML response: #<ActionDispatch::Response:0x00000007827d80> took 99ms
[[32mINFO [0m|#22821] Completed 200 OK in 109ms (Views: 0.3ms | ActiveRecord: 0.8ms | Backend: 0.0ms | XML: 0.3ms)
[[32mINFO [0m|#22821] Started PUT "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-11-20 17:40:01 +0100
[[32mINFO [0m|#22821] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#22821] Parameters: {"<package name"=>"\"kdebase\" project=\"kde4\">\n <title>blub</title>\n <description>blub</description>\n</package>\n", "project"=>"kde4", "package"=>"kdebase"}
[[0;37mDEBUG[0m|#22821] Validate XML request: #<ActionDispatch::Request:0x00000007273c18>
[[0;37mDEBUG[0m|#22821] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#22821] [1m[35mUser Load (0.3ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1
[[0;37mDEBUG[0m|#22821] USER found: king
[[0;37mDEBUG[0m|#22821] User king initialised
[[0;37mDEBUG[0m|#22821] [1m[36mRole Load (0.2ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mProject Load (0.2ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mPackage Load (0.2ms)[0m [1mSELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b646562617365' AND (packages.db_project_id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mProject Load (0.2ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#22821] get_by_project_and_name {}
[[0;37mDEBUG[0m|#22821] [1m[36mCACHE (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mCACHE (0.0ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b646562617365' AND (packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mCACHE (0.0ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mFlag Load (0.2ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 2 ORDER BY position
[[0;37mDEBUG[0m|#22821] flagcheck: package has default state, checking project
[[0;37mDEBUG[0m|#22821] [1m[36mFlag Load (0.1ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2[0m
[[0;37mDEBUG[0m|#22821] disabled_for sourceaccess repo: arch: state:enable
[[0;37mDEBUG[0m|#22821] xml_disabled? X({"name"=>"kdebase", "project"=>"kde4", "title"=>"blub", "description"=>"blub"})
[[0;37mDEBUG[0m|#22821] [1m[35mPackageUserRoleRelationship Load (0.1ms)[0m SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE `package_user_role_relationships`.`db_package_id` = 2
[[0;37mDEBUG[0m|#22821] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#22821] ----------------- rendering package kdebase ------------------------
[[0;37mDEBUG[0m|#22821] [1m[35mPackageUserRoleRelationship Load (0.2ms)[0m SELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 2
[[0;37mDEBUG[0m|#22821] [1m[36mPackageGroupRoleRelationship Load (0.2ms)[0m [1mSELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 2[0m
[[0;37mDEBUG[0m|#22821] ----------------- end rendering package kdebase ------------------------
[[0;37mDEBUG[0m|#22821] [backend] PUT: /source/kde4/kdebase/_meta?user=king
[[0;37mDEBUG[0m|#22826] 2012-11-20 17:40:01 [28585]: PUT /source/kde4/kdebase/_meta?user=king
[[0;37mDEBUG[0m|#22821] request took 0.012037455
[[0;37mDEBUG[0m|#22821] [1m[35mPackage Load (0.3ms)[0m SELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp,
IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 2 LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mPackageGroupRoleRelationship Load (0.2ms)[0m SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE `package_group_role_relationships`.`db_package_id` = 2
[[0;37mDEBUG[0m|#22821] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#22821] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#22821] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#22821] ----------------- rendering package kdebase ------------------------
[[0;37mDEBUG[0m|#22821] [1m[35mCACHE (0.0ms)[0m SELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 2
[[0;37mDEBUG[0m|#22821] [1m[36mCACHE (0.0ms)[0m [1mSELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 2[0m
[[0;37mDEBUG[0m|#22821] ----------------- end rendering package kdebase ------------------------
[[0;37mDEBUG[0m|#22821] [backend] PUT: /source/kde4/kdebase/_meta?user=king
[[0;37mDEBUG[0m|#22826] 2012-11-20 17:40:01 [28586]: PUT /source/kde4/kdebase/_meta?user=king
[[0;37mDEBUG[0m|#22821] request took 0.00955412
[[0;37mDEBUG[0m|#22821] [1m[35mCACHE (0.0ms)[0m SELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp,
IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 2 LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36m (0.2ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#22821] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#22821] ----------------- rendering package kdebase ------------------------
[[0;37mDEBUG[0m|#22821] [1m[36mCACHE (0.0ms)[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|#22821] [1m[35mCACHE (0.0ms)[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|#22821] ----------------- end rendering package kdebase ------------------------
[[0;37mDEBUG[0m|#22821] [backend] PUT: /source/kde4/kdebase/_meta?user=king
[[0;37mDEBUG[0m|#22826] 2012-11-20 17:40:01 [28587]: PUT /source/kde4/kdebase/_meta?user=king
[[0;37mDEBUG[0m|#22821] request took 0.02353586
[[0;37mDEBUG[0m|#22821] [1m[36mCACHE (0.0ms)[0m [1mSELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp,
IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 2 LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#22821] Validate XML response: #<ActionDispatch::Response:0x00000007273c90> took 1ms
[[32mINFO [0m|#22821] Completed 200 OK in 87ms (Views: 0.8ms | ActiveRecord: 3.4ms | Backend: 45.1ms | XML: 0.0ms)
[[32mINFO [0m|#22821] Started GET "/source/kde4/kdebase/_meta" for 127.0.0.1 at 2012-11-20 17:40:01 +0100
[[32mINFO [0m|#22821] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#22821] Parameters: {"project"=>"kde4", "package"=>"kdebase"}
[[0;37mDEBUG[0m|#22821] Validate XML request: #<ActionDispatch::Request:0x000000065e6dd8>
[[0;37mDEBUG[0m|#22821] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdebase", "method"=>"GET", "type"=>"request"}
[[0;37mDEBUG[0m|#22821] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#22821] [1m[36mUser Load (10.5ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] USER found: king
[[0;37mDEBUG[0m|#22821] User king initialised
[[0;37mDEBUG[0m|#22821] get_by_project_and_name {}
[[0;37mDEBUG[0m|#22821] [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|#22821] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mPackage Load (0.2ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b646562617365' AND (packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] Validate XML response: #<ActionDispatch::Response:0x000000065f1378> took 2ms
[[32mINFO [0m|#22821] Completed 200 OK in 25ms (Views: 0.4ms | ActiveRecord: 11.4ms | Backend: 0.0ms | XML: 0.0ms)
[[32mINFO [0m|#22821] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-20 17:40:02 +0100
[[32mINFO [0m|#22821] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#22821] Parameters: {"project"=>"kde4", "package"=>"kdelibs"}
[[0;37mDEBUG[0m|#22821] Validate XML request: #<ActionDispatch::Request:0x0000000626bac8>
[[0;37mDEBUG[0m|#22821] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"}
[[0;37mDEBUG[0m|#22821] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#22821] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1
[[0;37mDEBUG[0m|#22821] USER found: king
[[0;37mDEBUG[0m|#22821] User king initialised
[[0;37mDEBUG[0m|#22821] get_by_project_and_name {}
[[0;37mDEBUG[0m|#22821] [1m[36mRole Load (0.2ms)[0m [1mSELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mProject Load (0.2ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mPackage Load (0.2ms)[0m [1mSELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b64656c696273' AND (packages.db_project_id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mProject Load (0.2ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#22821] Validate XML response: #<ActionDispatch::Response:0x0000000626ba78> took 3ms
[[32mINFO [0m|#22821] Completed 200 OK in 15ms (Views: 0.3ms | ActiveRecord: 1.0ms | Backend: 0.0ms | XML: 0.0ms)
[[32mINFO [0m|#22821] Started PUT "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-20 17:40:02 +0100
[[32mINFO [0m|#22821] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#22821] Parameters: {"<package name"=>"\"kdelibs\" project=\"kde4\">\n <title>blub</title>\n <description>Changed description</description>\n <devel project=\"home:Iggy\" package=\"TestPack\"/>\n <person userid=\"fredlibs\" role=\"maintainer\"/>\n <person userid=\"adrian\" role=\"reviewer\"/>\n <person userid=\"Iggy\" role=\"maintainer\"/>\n <person userid=\"Iggy\" role=\"bugowner\"/>\n <group groupid=\"test_group\" role=\"maintainer\"/>\n <group groupid=\"test_group\" role=\"reader\"/>\n <build>\n <enable/>\n </build>\n</package>\n", "project"=>"kde4", "package"=>"kdelibs"}
[[0;37mDEBUG[0m|#22821] Validate XML request: #<ActionDispatch::Request:0x00000006970b48>
[[0;37mDEBUG[0m|#22821] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#22821] [1m[36mUser Load (0.3ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] USER found: king
[[0;37mDEBUG[0m|#22821] User king initialised
[[0;37mDEBUG[0m|#22821] [1m[35mRole Load (0.2ms)[0m SELECT DISTINCT roles.id FROM `roles` INNER JOIN `roles_users` ON `roles`.`id` = `roles_users`.`role_id` WHERE `roles_users`.`user_id` = 2 AND `roles`.`title` = 'Admin' LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mPackage Load (0.3ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b64656c696273' AND (packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] get_by_project_and_name {}
[[0;37mDEBUG[0m|#22821] [1m[35mCACHE (0.0ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mCACHE (0.0ms)[0m [1mSELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b64656c696273' AND (packages.db_project_id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mCACHE (0.0ms)[0m SELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mFlag Load (0.3ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 AND `flags`.`flag` = 'lock' AND `flags`.`status` = 'enable' ORDER BY position LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [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|#22821] [1m[36mFlag Load (0.2ms)[0m [1mSELECT `flags`.* FROM `flags` WHERE `flags`.`db_package_id` = 1 ORDER BY position[0m
[[0;37mDEBUG[0m|#22821] flagcheck: package has default state, checking project
[[0;37mDEBUG[0m|#22821] [1m[35mFlag Load (0.1ms)[0m SELECT `flags`.* FROM `flags` WHERE `flags`.`db_project_id` = 2
[[0;37mDEBUG[0m|#22821] disabled_for sourceaccess repo: arch: state:enable
[[0;37mDEBUG[0m|#22821] xml_disabled? X({"name"=>"kdelibs", "project"=>"kde4", "title"=>"blub", "description"=>"Changed description", "devel"=>X({"project"=>"home:Iggy", "package"=>"TestPack"}), "person"=>[X({"userid"=>"fredlibs", "role"=>"maintainer"}), X({"userid"=>"adrian", "role"=>"reviewer"}), X({"userid"=>"Iggy", "role"=>"maintainer"}), X({"userid"=>"Iggy", "role"=>"bugowner"})], "group"=>[X({"groupid"=>"test_group", "role"=>"maintainer"}), X({"groupid"=>"test_group", "role"=>"reader"})], "build"=>X({"enable"=>X({})})})
[[0;37mDEBUG[0m|#22821] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'686f6d653a49676779' AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mPackage Load (0.2ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 502 AND `packages`.`name` = x'546573745061636b' AND (packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 502 AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [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|#22821] [1m[36mUser Load (0.1ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`id` = 4 LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mRole Load (0.1ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mUser Load (0.1ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`id` = 42 LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mRole Load (0.1ms)[0m SELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 6 LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#22821] [1m[35m (0.2ms)[0m UPDATE `packages` SET `description` = 'Changed description', `develpackage_id` = 10095, `updated_at` = '2012-11-20 16:40:02' WHERE `packages`.`id` = 1
[[0;37mDEBUG[0m|#22821] ----------------- rendering package kdelibs ------------------------
[[0;37mDEBUG[0m|#22821] [1m[36mPackageUserRoleRelationship Load (0.2ms)[0m [1mSELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mPackageGroupRoleRelationship Load (0.2ms)[0m SELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 1
[[0;37mDEBUG[0m|#22821] ----------------- end rendering package kdelibs ------------------------
[[0;37mDEBUG[0m|#22821] [backend] PUT: /source/kde4/kdelibs/_meta?user=king
[[0;37mDEBUG[0m|#22826] 2012-11-20 17:40:02 [28588]: PUT /source/kde4/kdelibs/_meta?user=king
[[0;37mDEBUG[0m|#22826] 2012-11-20 17:40:02 [28589]: POST /event?type=package&project=kde4&package=kdelibs
[[0;37mDEBUG[0m|#22821] request took 0.052211685
[[0;37mDEBUG[0m|#22821] [1m[36mPackage Load (0.4ms)[0m [1mSELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp,
IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 1 LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#22821] [1m[36mUser Load (0.1ms)[0m [1mSELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#22821] [1m[36mPackageUserRoleRelationship Load (0.2ms)[0m [1mSELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 3 AND bs_user_id = 267) LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mSQL (0.2ms)[0m INSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 3)
[[0;37mDEBUG[0m|#22821] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#22821] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#22821] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mUser Load (0.2ms)[0m SELECT `users`.* FROM `users` WHERE `users`.`login` = x'49676779' LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mPackageUserRoleRelationship Load (0.1ms)[0m SELECT `package_user_role_relationships`.* FROM `package_user_role_relationships` WHERE (db_package_id = 1 AND role_id = 4 AND bs_user_id = 267) LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mSQL (0.1ms)[0m [1mINSERT INTO `package_user_role_relationships` (`bs_user_id`, `db_package_id`, `role_id`) VALUES (267, 1, 4)[0m
[[0;37mDEBUG[0m|#22821] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#22821] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#22821] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#22821] [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|#22821] [1m[35mGroup Load (0.1ms)[0m SELECT `groups`.* FROM `groups` WHERE `groups`.`id` = 99 LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mRole Load (0.1ms)[0m [1mSELECT `roles`.* FROM `roles` WHERE `roles`.`id` = 3 LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mGroup Load (0.2ms)[0m SELECT `groups`.* FROM `groups` WHERE `groups`.`title` = 'test_group' LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mPackageGroupRoleRelationship Load (0.1ms)[0m SELECT `package_group_role_relationships`.* FROM `package_group_role_relationships` WHERE (db_package_id = 1 AND role_id = 7 AND bs_group_id = 99) LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mSQL (0.1ms)[0m [1mINSERT INTO `package_group_role_relationships` (`bs_group_id`, `db_package_id`, `role_id`) VALUES (99, 1, 7)[0m
[[0;37mDEBUG[0m|#22821] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#22821] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#22821] [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#22821] [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mPackage Load (0.2ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`id` = 1 AND (packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36m (0.2ms)[0m [1mSELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mCACHE (0.0ms)[0m SELECT MAX(`flags`.`position`) AS max_id FROM `flags` WHERE `flags`.`db_package_id` = 1
[[0;37mDEBUG[0m|#22821] [1m[36mSQL (0.2ms)[0m [1mINSERT INTO `flags` (`architecture_id`, `db_package_id`, `db_project_id`, `flag`, `position`, `repo`, `status`) VALUES (NULL, 1, NULL, 'build', 1, NULL, 'enable')[0m
[[0;37mDEBUG[0m|#22821] ----------------- rendering package kdelibs ------------------------
[[0;37mDEBUG[0m|#22821] [1m[35mPackageUserRoleRelationship Load (0.2ms)[0m SELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 1
[[0;37mDEBUG[0m|#22821] [1m[36mPackageGroupRoleRelationship Load (0.2ms)[0m [1mSELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 1[0m
[[0;37mDEBUG[0m|#22821] ----------------- end rendering package kdelibs ------------------------
[[0;37mDEBUG[0m|#22821] [backend] PUT: /source/kde4/kdelibs/_meta?user=king
[[0;37mDEBUG[0m|#22826] 2012-11-20 17:40:02 [28590]: PUT /source/kde4/kdelibs/_meta?user=king
[[0;37mDEBUG[0m|#22826] 2012-11-20 17:40:02 [28591]: POST /event?type=package&project=kde4&package=kdelibs
[[0;37mDEBUG[0m|#22821] request took 0.059390204
[[0;37mDEBUG[0m|#22821] [1m[35mPackage Load (0.7ms)[0m SELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp,
IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 1 LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36m (0.1ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
[[0;37mDEBUG[0m|#22821] [1m[35m (0.1ms)[0m SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#22821] ----------------- rendering package kdelibs ------------------------
[[0;37mDEBUG[0m|#22821] [1m[36mCACHE (0.0ms)[0m [1mSELECT users.login as login, roles.title AS role_name FROM `package_user_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_user_role_relationships`.`role_id` INNER JOIN `users` ON `users`.`id` = `package_user_role_relationships`.`bs_user_id` WHERE `package_user_role_relationships`.`db_package_id` = 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mCACHE (0.0ms)[0m SELECT groups.title as title, roles.title as role_name FROM `package_group_role_relationships` INNER JOIN `roles` ON `roles`.`id` = `package_group_role_relationships`.`role_id` INNER JOIN `groups` ON `groups`.`id` = `package_group_role_relationships`.`bs_group_id` WHERE `package_group_role_relationships`.`db_package_id` = 1
[[0;37mDEBUG[0m|#22821] ----------------- end rendering package kdelibs ------------------------
[[0;37mDEBUG[0m|#22821] [backend] PUT: /source/kde4/kdelibs/_meta?user=king
[[0;37mDEBUG[0m|#22826] 2012-11-20 17:40:02 [28592]: PUT /source/kde4/kdelibs/_meta?user=king
[[0;37mDEBUG[0m|#22821] request took 0.02350183
[[0;37mDEBUG[0m|#22821] [1m[36mCACHE (0.0ms)[0m [1mSELECT packages.*, ( @activity:=( packages.activity_index - POWER( TIME_TO_SEC( TIMEDIFF( NOW(), packages.updated_at ))/86400, 1.55 ) /10 ) ) AS act_tmp,
IF( @activity<0, 0, @activity ) AS activity_value FROM `packages` WHERE id = 1 LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35m (0.2ms)[0m RELEASE SAVEPOINT active_record_1
[[0;37mDEBUG[0m|#22821] Validate XML response: #<ActionDispatch::Response:0x00000006970ad0> took 0ms
[[32mINFO [0m|#22821] Completed 200 OK in 219ms (Views: 0.8ms | ActiveRecord: 8.9ms | Backend: 135.1ms | XML: 0.0ms)
[[32mINFO [0m|#22821] Started GET "/source/kde4/kdelibs/_meta" for 127.0.0.1 at 2012-11-20 17:40:02 +0100
[[32mINFO [0m|#22821] Processing by SourceController#package_meta as XML
[[32mINFO [0m|#22821] Parameters: {"project"=>"kde4", "package"=>"kdelibs"}
[[0;37mDEBUG[0m|#22821] Validate XML request: #<ActionDispatch::Request:0x000000071ea058>
[[0;37mDEBUG[0m|#22821] no schema found, skipping validation for {"format"=>"xml", "controller"=>"source", "action"=>"package_meta", "project"=>"kde4", "package"=>"kdelibs", "method"=>"GET", "type"=>"request"}
[[0;37mDEBUG[0m|#22821] AUTH: ["Basic", "a2luZzpzdW5mbG93ZXI="]
[[0;37mDEBUG[0m|#22821] [1m[36mUser Load (5.1ms)[0m [1mSELECT `users`.* FROM `users` WHERE (login = 'king') LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] USER found: king
[[0;37mDEBUG[0m|#22821] User king initialised
[[0;37mDEBUG[0m|#22821] get_by_project_and_name {}
[[0;37mDEBUG[0m|#22821] [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|#22821] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`name` = x'6b646534' AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] [1m[35mPackage Load (0.2ms)[0m SELECT `packages`.* FROM `packages` WHERE `packages`.`db_project_id` = 2 AND `packages`.`name` = x'6b64656c696273' AND (packages.db_project_id not in (0)) LIMIT 1
[[0;37mDEBUG[0m|#22821] [1m[36mProject Load (0.2ms)[0m [1mSELECT `projects`.* FROM `projects` WHERE `projects`.`id` = 2 AND (projects.id not in (0)) LIMIT 1[0m
[[0;37mDEBUG[0m|#22821] Validate XML response: #<ActionDispatch::Response:0x000000071e9f90> took 2ms
[[32mINFO [0m|#22821] Completed 200 OK in 18ms (Views: 0.4ms | ActiveRecord: 5.9ms | Backend: 0.0ms | XML: 0.0ms)
[[0;37mDEBUG[0m|#22821] [1m[35m (7.3ms)[0m ROLLBACK
[[0;37mDEBUG[0m|#22821] kill 22826
+ 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/scheduler.done src/api/tmp/sessions src/api/tmp/sockets 'src/webui/log/*' src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets
+ exit 1
Build step 'Execute shell' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
--
To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org
To contact the owner, e-mail: obs-tests+owner(a)opensuse.org
1
1
[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui #639
by admin@opensuse.org 20 Nov '12
by admin@opensuse.org 20 Nov '12
20 Nov '12
See <http://ci.opensuse.org/job/obs_master_testsuite_webui/639/changes>
Changes:
[Stephan Kulow] [webui] port the maintenance test to capybara
[Stephan Kulow] [webui] port the watchlist test to capybara
[Stephan Kulow] [dist] run the all testsuite headless too
------------------------------------------
[...truncated 38637 lines...]
],
"xml": "<project name=\"BaseDistro\">\n <title>This is a base distro</title>\n <description>This could be openSUSE:11.2 project for example</description>\n <repository name=\"BaseDistro_repo\">\n <releasetarget project=\"BaseDistro:Update\" repository=\"BaseDistroUpdateProject_repo\"/>\n <arch>i586</arch>\n </repository>\n</project>\n",
"maintenance_project": "My:Maintenance",
"linking_projects": [
"BaseDistro:Update"
],
"requests": [
],
"nr_of_problem_packages": 0
}
[[32mINFO [0m|#14294] Rendered project/_tabs.html.erb (4.1ms)
[[32mINFO [0m|#14294] Rendered shared/_open_requests.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered shared/_buildresult_box.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered layouts/_watch_and_search.html.erb (3.3ms)
[[32mINFO [0m|#14294] Rendered layouts/_header.html.erb (3.3ms)
[[32mINFO [0m|#14294] Rendered layouts/_personal_navigation.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|#14294] Completed 200 OK in 474ms (Views: 19.3ms | ActiveRecord: 0.0ms | API: 288.0ms (XML: 0.0ms , View: 0.0ms , Backend: 0.0ms , DB: 22.1ms , HTTP: 121.3ms) | XML: 0.0ms)
[[32mINFO [0m|#14294] Started GET "/project/buildresult?project=BaseDistro&index=" for 127.0.0.1 at 2012-11-20 06:48:24 +0100
[[32mINFO [0m|#14294] Processing by ProjectController#buildresult as */*
[[32mINFO [0m|#14294] Parameters: {"project"=>"BaseDistro", "index"=>""}
[[0;37mDEBUG[0m|#14294] Setting return_to: "/project/buildresult?project=BaseDistro&index="
[[0;37mDEBUG[0m|#14294] Authenticating with iChain mode: off
[[32mINFO [0m|#14294] Authenticated request to "/project/buildresult?project=BaseDistro&index=" from tom
[[0;37mDEBUG[0m|#14294] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#14294] params {:is_current=>"true", :login=>"tom"}
[[0;37mDEBUG[0m|#14294] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|#14294] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#14294] RT http://localhost:3203/person/tom {"view"=>0.631734, "db"=>0.839862, "backend"=>0, "xml"=>0, :runtime=>9.848, :all=>19.62356}
[[0;37mDEBUG[0m|#14294] [REST] find( Buildresult, [{:project=>"BaseDistro", :view=>"summary"}] )
[[0;37mDEBUG[0m|#14294] params {:project=>"BaseDistro", :view=>"summary"}
[[0;37mDEBUG[0m|#14294] uri is: http://localhost:3203/build/:project/_result?:view&:package&:code&:lastbuil…
[[0;37mDEBUG[0m|#14294] http_do #1: method: get url: http://localhost:3203/build/BaseDistro/_result?view=summary
[[0;37mDEBUG[0m|#14294] RT http://localhost:3203/build/BaseDistro/_result?view=summary {"view"=>nil, "db"=>0.6064430000000001, "backend"=>0, "xml"=>0, :runtime=>79.116, :all=>122.547328}
[[32mINFO [0m|#14294] Rendered project/_buildstatus.html.erb (0.0ms)
[[32mINFO [0m|#14294] Completed 200 OK in 153ms (Views: 1.2ms | ActiveRecord: 0.0ms | API: 142.2ms (XML: 0.0ms , View: 0.6ms , Backend: 0.0ms , DB: 1.4ms , HTTP: 53.2ms) | XML: 0.0ms)
[[32mINFO [0m|#14294] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-20 06:48:24 +0100
[[32mINFO [0m|#14294] Processing by ProjectController#show as HTML
[[32mINFO [0m|#14294] Parameters: {"project"=>"My:Maintenance"}
[[0;37mDEBUG[0m|#14294] Setting return_to: "/project/show?project=My%3AMaintenance"
[[0;37mDEBUG[0m|#14294] Authenticating with iChain mode: off
[[32mINFO [0m|#14294] Authenticated request to "/project/show?project=My%3AMaintenance" from tom
[[0;37mDEBUG[0m|#14294] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#14294] params {:is_current=>"true", :login=>"tom"}
[[0;37mDEBUG[0m|#14294] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|#14294] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#14294] RT http://localhost:3203/person/tom {"view"=>0.841356, "db"=>0.631795, "backend"=>0, "xml"=>0, :runtime=>16.674000000000003, :all=>19.626065}
[[0;37mDEBUG[0m|#14294] --> direct_http url: #<URI::Generic:0x00000006841740 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom>
[[0;37mDEBUG[0m|#14294] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom
[[0;37mDEBUG[0m|#14294] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.438099, "db"=>1.222997, "backend"=>0, "xml"=>0, :runtime=>52.212, :all=>102.474905}
[[0;37mDEBUG[0m|#14294] data {
"declined": [
],
"new": [
],
"reviews": [
1000
]
}
[[0;37mDEBUG[0m|#14294] --> direct_http url: #<URI::Generic:0x00000006868ac0 URL:http://localhost:3203/webui/project_infos?project=My%3AMaintenance>
[[0;37mDEBUG[0m|#14294] http_do #1: method: GET url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance
[[0;37mDEBUG[0m|#14294] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.0, "db"=>17.28914, "backend"=>0, "xml"=>0, :runtime=>92.004, :all=>133.58118399999998}
[[0;37mDEBUG[0m|#14294] data {
"name": "My:Maintenance",
"packages": [
],
"xml": "<project name=\"My:Maintenance\" kind=\"maintenance\">\n <title>official maintenance space</title>\n <description>official maintenance space, maintained by maintenance ;)</description>\n <person userid=\"maintenance_coord\" role=\"maintainer\"/>\n <maintenance>\n <maintains project=\"BaseDistro\"/>\n <maintains project=\"BaseDistro2.0:LinkedUpdateProject\"/>\n <maintains project=\"BaseDistro3\"/>\n </maintenance>\n</project>\n",
"incidents": [
],
"maintained_projects": [
"BaseDistro",
"BaseDistro2.0:LinkedUpdateProject",
"BaseDistro3"
],
"linking_projects": [
],
"requests": [
],
"nr_of_problem_packages": 0
}
[[32mINFO [0m|#14294] Rendered project/_tabs.html.erb (4.0ms)
[[32mINFO [0m|#14294] Rendered shared/_open_requests.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered layouts/_watch_and_search.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered layouts/_header.html.erb (2.7ms)
[[32mINFO [0m|#14294] Rendered layouts/_personal_navigation.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|#14294] Completed 200 OK in 291ms (Views: 32.3ms | ActiveRecord: 0.0ms | API: 255.7ms (XML: 0.0ms , View: 1.3ms , Backend: 0.0ms , DB: 19.1ms , HTTP: 94.8ms) | XML: 0.2ms)
[[32mINFO [0m|#14294] Started GET "/project/toggle_watch?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-20 06:48:25 +0100
[[32mINFO [0m|#14294] Processing by ProjectController#toggle_watch as HTML
[[32mINFO [0m|#14294] Parameters: {"project"=>"My:Maintenance"}
[[0;37mDEBUG[0m|#14294] Setting return_to: "/project/toggle_watch?project=My%3AMaintenance"
[[0;37mDEBUG[0m|#14294] Authenticating with iChain mode: off
[[32mINFO [0m|#14294] Authenticated request to "/project/toggle_watch?project=My%3AMaintenance" from tom
[[0;37mDEBUG[0m|#14294] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#14294] params {:is_current=>"true", :login=>"tom"}
[[0;37mDEBUG[0m|#14294] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|#14294] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#14294] RT http://localhost:3203/person/tom {"view"=>0.6094050000000001, "db"=>0.818617, "backend"=>0, "xml"=>0, :runtime=>10.301, :all=>13.631759}
[[0;37mDEBUG[0m|#14294] --> direct_http url: #<URI::Generic:0x00000006db9ad8 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom>
[[0;37mDEBUG[0m|#14294] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom
[[0;37mDEBUG[0m|#14294] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.0, "db"=>3.860488, "backend"=>0, "xml"=>0, :runtime=>79.153, :all=>135.919254}
[[0;37mDEBUG[0m|#14294] data {
"declined": [
],
"new": [
],
"reviews": [
1000
]
}
[[0;37mDEBUG[0m|#14294] Remove My:Maintenance from watchlist for tom
[[0;37mDEBUG[0m|#14294] user 'tom' removes project 'My:Maintenance' from watchlist
[[0;37mDEBUG[0m|#14294] saving object Person ({:is_current=>"true", :login=>"tom"}) to api:
<person>
<login>tom</login>
<email>tschmidt(a)suse.de</email>
<realname>Thor</realname>
<watchlist>
</watchlist>
</person>
[[0;37mDEBUG[0m|#14294] http_do #1: method: put url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#14294] RT http://localhost:3203/person/tom {"view"=>0.9964310000000001, "db"=>30.083093000000005, "backend"=>0, "xml"=>0, :runtime=>48.735, :all=>59.226423}
[[32mINFO [0m|#14294] Redirected to http://127.0.0.1:43146/project/show?project=My%3AMaintenance
[[32mINFO [0m|#14294] Completed 302 Found in 212ms (ActiveRecord: 0.0ms | API: 208.8ms (XML: 0.0ms , View: 1.6ms , Backend: 0.0ms , DB: 34.8ms , HTTP: 70.6ms) | XML: 0.2ms)
[[32mINFO [0m|#14294] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-20 06:48:25 +0100
[[32mINFO [0m|#14294] Processing by ProjectController#show as HTML
[[32mINFO [0m|#14294] Parameters: {"project"=>"My:Maintenance"}
[[0;37mDEBUG[0m|#14294] Setting return_to: "/project/show?project=My%3AMaintenance"
[[0;37mDEBUG[0m|#14294] Authenticating with iChain mode: off
[[32mINFO [0m|#14294] Authenticated request to "/project/show?project=My%3AMaintenance" from tom
[[0;37mDEBUG[0m|#14294] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#14294] params {:is_current=>"true", :login=>"tom"}
[[0;37mDEBUG[0m|#14294] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|#14294] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#14294] RT http://localhost:3203/person/tom {"view"=>0.793925, "db"=>3.150845, "backend"=>0, "xml"=>0, :runtime=>12.095, :all=>75.699116}
[[0;37mDEBUG[0m|#14294] --> direct_http url: #<URI::Generic:0x000000073a08c0 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom>
[[0;37mDEBUG[0m|#14294] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom
[[0;37mDEBUG[0m|#14294] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.24476, "db"=>36.155280000000005, "backend"=>0, "xml"=>0, :runtime=>63.647, :all=>126.90109899999999}
[[0;37mDEBUG[0m|#14294] data {
"declined": [
],
"new": [
],
"reviews": [
1000
]
}
[[0;37mDEBUG[0m|#14294] --> direct_http url: #<URI::Generic:0x000000073b4050 URL:http://localhost:3203/webui/project_infos?project=My%3AMaintenance>
[[0;37mDEBUG[0m|#14294] http_do #1: method: GET url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance
[[0;37mDEBUG[0m|#14294] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.435259, "db"=>18.814143999999995, "backend"=>0, "xml"=>0, :runtime=>84.238, :all=>131.654428}
[[0;37mDEBUG[0m|#14294] data {
"name": "My:Maintenance",
"packages": [
],
"xml": "<project name=\"My:Maintenance\" kind=\"maintenance\">\n <title>official maintenance space</title>\n <description>official maintenance space, maintained by maintenance ;)</description>\n <person userid=\"maintenance_coord\" role=\"maintainer\"/>\n <maintenance>\n <maintains project=\"BaseDistro\"/>\n <maintains project=\"BaseDistro2.0:LinkedUpdateProject\"/>\n <maintains project=\"BaseDistro3\"/>\n </maintenance>\n</project>\n",
"incidents": [
],
"maintained_projects": [
"BaseDistro",
"BaseDistro2.0:LinkedUpdateProject",
"BaseDistro3"
],
"linking_projects": [
],
"requests": [
],
"nr_of_problem_packages": 0
}
[[32mINFO [0m|#14294] Rendered project/_tabs.html.erb (2.1ms)
[[32mINFO [0m|#14294] Rendered shared/_open_requests.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered layouts/_watch_and_search.html.erb (1.0ms)
[[32mINFO [0m|#14294] Rendered layouts/_header.html.erb (13.8ms)
[[32mINFO [0m|#14294] Rendered layouts/_personal_navigation.html.erb (1.0ms)
[[32mINFO [0m|#14294] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|#14294] Completed 200 OK in 365ms (Views: 26.3ms | ActiveRecord: 0.0ms | API: 334.3ms (XML: 0.0ms , View: 1.5ms , Backend: 0.0ms , DB: 58.1ms , HTTP: 174.3ms) | XML: 0.5ms)
[[32mINFO [0m|#14294] Started POST "/user/logout" for 127.0.0.1 at 2012-11-20 06:48:26 +0100
[[32mINFO [0m|#14294] Processing by UserController#logout as HTML
[[0;37mDEBUG[0m|#14294] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|#14294] Authenticating with iChain mode: off
[[32mINFO [0m|#14294] Authenticated request to "/user/logout" from tom
[[32mINFO [0m|#14294] Logging out: tom
[[32mINFO [0m|#14294] Redirected to http://127.0.0.1:43146/
[[32mINFO [0m|#14294] Completed 302 Found in 20ms (ActiveRecord: 20.0ms | XML: 0.0ms)
[[32mINFO [0m|#14294] Started GET "/" for 127.0.0.1 at 2012-11-20 06:48:26 +0100
[[32mINFO [0m|#14294] Processing by MainController#index as HTML
[[0;37mDEBUG[0m|#14294] Setting return_to: "/"
[[0;37mDEBUG[0m|#14294] Authenticating with iChain mode: off
[[32mINFO [0m|#14294] Anonymous request to /
[[32mINFO [0m|#14294] Rendered main/_news.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered main/_contact.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered main/_latest_updates.erb (4.8ms)
[[32mINFO [0m|#14294] Rendered main/_countdown.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered main/_sponsors.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered main/_feeds.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered layouts/_watch_and_search.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered layouts/_header.html.erb (1.7ms)
[[32mINFO [0m|#14294] Rendered layouts/_personal_navigation.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|#14294] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|#14294] Completed 200 OK in 29ms (Views: 27.3ms | ActiveRecord: 0.0ms | XML: 0.2ms)
[[32mINFO [0m|#14294] Started GET "/main/systemstatus" for 127.0.0.1 at 2012-11-20 06:48:27 +0100
[[32mINFO [0m|#14294] Processing by MainController#systemstatus as */*
[[0;37mDEBUG[0m|#14294] Setting return_to: "/main/systemstatus"
[[0;37mDEBUG[0m|#14294] Authenticating with iChain mode: off
[[32mINFO [0m|#14294] Anonymous request to /main/systemstatus
[[32mINFO [0m|#14294] Rendered main/_systemstatus.html.erb (1.4ms)
[[32mINFO [0m|#14294] Completed 200 OK in 5ms (Views: 1.4ms | ActiveRecord: 0.0ms | XML: 0.0ms)
[[0;37mDEBUG[0m|#14294] --> direct_http url: #<URI::Generic:0x00000005d769f0 URL:http://localhost:3203/test/test_end>
[[0;37mDEBUG[0m|#14294] http_do #1: method: GET url: http://localhost:3203/test/test_end
[[0;37mDEBUG[0m|#14294] RT http://localhost:3203/test/test_end {"view"=>0.0, "db"=>34.883537000000004, "backend"=>0, "xml"=>0, :runtime=>41.050000000000004, :all=>44.001654}
+ echo
+ cleanup
+ echo 'Killing backend processes'
Killing backend processes
+ egrep 'perl|ruby'
+ fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/>
USER PID ACCESS COMMAND
<http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/>:
jenkins ..c.. sh
jenkins ..c.. sh
jenkins ..c.. egrep
+ echo 'Remove log/tmp files to save disc space'
Remove log/tmp files to save disc space
+ rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/cache src/api/tmp/pids src/api/tmp/scheduler.done src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/capybara src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets
+ exit 1
Build step 'Execute shell' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
--
To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org
To contact the owner, e-mail: obs-tests+owner(a)opensuse.org
1
8
20 Nov '12
See <http://ci.opensuse.org/job/obs_osc_testsuite_master/170/changes>
Changes:
[adrian] - add support for delete requests.
[adrian] support single binary download via getbinaries command
------------------------------------------
Started by an SCM change
Started by an SCM change
Building in workspace <http://ci.opensuse.org/job/obs_osc_testsuite_master/ws/>
Checkout:workspace / <http://ci.opensuse.org/job/obs_osc_testsuite_master/ws/> - hudson.remoting.LocalChannel@74e474e4
Using strategy: Default
Last Built Revision: Revision 3af97625d47c68f5e4600e3d7463dc6eaa97a539 (origin/master)
Checkout:workspace / <http://ci.opensuse.org/job/obs_osc_testsuite_master/ws/> - hudson.remoting.LocalChannel@74e474e4
Fetching changes from 1 remote Git repository
Fetching upstream changes from git://github.com/openSUSE/osc.git
Commencing build of Revision 7353cf99d7b87c98583c54ed32ee92824c05d839 (origin/master)
Checking out Revision 7353cf99d7b87c98583c54ed32ee92824c05d839 (origin/master)
[workspace] $ /bin/sh -xe /tmp/hudson1094907398546968604.sh
+ cd tests
+ python suite.py
Running tests...
----------------------------------------------------------------------
................................................................................................................................................................EEEE.......
======================================================================
ERROR [0.025s]: test the list_view method
----------------------------------------------------------------------
Traceback (most recent call last):
File "<http://ci.opensuse.org/job/obs_osc_testsuite_master/ws/tests/test_request.py",> line 447, in test_request_list_view1
self.assertEqual(exp, r.list_view())
File "<http://ci.opensuse.org/job/obs_osc_testsuite_master/ws/tests/osc/core.py",> line 2464, in list_view
lines.append(tmpl % Request.format_action(action))
File "<http://ci.opensuse.org/job/obs_osc_testsuite_master/ws/tests/osc/core.py",> line 2419, in format_action
d['target'] = prj_pkg_join(action.tgt_project, action.tgt_package)
TypeError: prj_pkg_join() takes exactly 3 arguments (2 given)
======================================================================
ERROR [0.025s]: test the list_view method (with history elements and description)
----------------------------------------------------------------------
Traceback (most recent call last):
File "<http://ci.opensuse.org/job/obs_osc_testsuite_master/ws/tests/test_request.py",> line 462, in test_request_list_view2
self.assertEqual(exp, r.list_view())
File "<http://ci.opensuse.org/job/obs_osc_testsuite_master/ws/tests/osc/core.py",> line 2464, in list_view
lines.append(tmpl % Request.format_action(action))
File "<http://ci.opensuse.org/job/obs_osc_testsuite_master/ws/tests/osc/core.py",> line 2419, in format_action
d['target'] = prj_pkg_join(action.tgt_project, action.tgt_package)
TypeError: prj_pkg_join() takes exactly 3 arguments (2 given)
======================================================================
ERROR [0.025s]: test_request_str1 (test_request.TestRequest)
----------------------------------------------------------------------
Traceback (most recent call last):
File "<http://ci.opensuse.org/job/obs_osc_testsuite_master/ws/tests/test_request.py",> line 493, in test_request_str1
self.assertEqual(exp, str(r))
File "<http://ci.opensuse.org/job/obs_osc_testsuite_master/ws/tests/osc/core.py",> line 2486, in __str__
lines.append(tmpl % Request.format_action(action, show_srcupdate=True))
File "<http://ci.opensuse.org/job/obs_osc_testsuite_master/ws/tests/osc/core.py",> line 2440, in format_action
d['source'] = '%s%s ->' % (prj_pkg_join(action.src_project, action.src_package), srcupdate)
TypeError: prj_pkg_join() takes exactly 3 arguments (2 given)
======================================================================
ERROR [0.025s]: test the __str__ method
----------------------------------------------------------------------
Traceback (most recent call last):
File "<http://ci.opensuse.org/job/obs_osc_testsuite_master/ws/tests/test_request.py",> line 524, in test_request_str2
self.assertEqual(exp, str(r))
File "<http://ci.opensuse.org/job/obs_osc_testsuite_master/ws/tests/osc/core.py",> line 2486, in __str__
lines.append(tmpl % Request.format_action(action, show_srcupdate=True))
File "<http://ci.opensuse.org/job/obs_osc_testsuite_master/ws/tests/osc/core.py",> line 2421, in format_action
d['source'] = prj_pkg_join(action.tgt_project, action.tgt_package)
TypeError: prj_pkg_join() takes exactly 3 arguments (2 given)
----------------------------------------------------------------------
Ran 171 tests in 18.392s
FAILED (errors=4)
Generating XML reports...
Build step 'Execute shell' marked build as failure
Recording test results
--
To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org
To contact the owner, e-mail: obs-tests+owner(a)opensuse.org
1
1
[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui #636
by admin@opensuse.org 19 Nov '12
by admin@opensuse.org 19 Nov '12
19 Nov '12
See <http://ci.opensuse.org/job/obs_master_testsuite_webui/636/changes>
Changes:
[Sascha Peilicke] [api] Make thinks perfect
[Stephan Kulow] [webui] replacing webrat with capybara
[Stephan Kulow] [webui] sync the logging code with the api
------------------------------------------
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Building in workspace <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/>
Checkout:workspace / <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/> - hudson.remoting.LocalChannel@74e474e4
Using strategy: Default
Last Built Revision: Revision 31f9b417260560484b60ddff2f213937683196a6 (origin/master)
Checkout:workspace / <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/> - hudson.remoting.LocalChannel@74e474e4
Fetching changes from 1 remote Git repository
Fetching upstream changes from git://github.com/openSUSE/open-build-service.git
Commencing build of Revision 5add0698cce812d6bcdea2e71cc61b07b27f562c (origin/master)
Checking out Revision 5add0698cce812d6bcdea2e71cc61b07b27f562c (origin/master)
[locks-and-latches] Checking to see if we really have the locks
[locks-and-latches] Have all the locks, build can start
[workspace] $ /bin/sh -xe /tmp/hudson5083163758574517878.sh
+ sh dist/ci/obs_testsuite_webui.sh
++ dirname dist/ci/obs_testsuite_webui.sh
+ . dist/ci/obs_testsuite_common.sh
+ setup_git
+ echo 'Checking status'
Checking status
+ git status
# Not currently on any branch.
# Untracked files:
# (use "git add <file>..." to include in what will be committed)
#
# src/api/Gemfile.lock.orig
# src/webui/Gemfile.lock.orig
# src/webui/db/structure.sql
# src/webui/public/themes/
# src/webui/results/
# src/webui/webrat.log
nothing added to commit but untracked files present (use "git add" to track)
+ echo 'Setup git submodules'
Setup git submodules
+ git submodule init
+ git submodule update
+ echo 'Setup backend configuration template'
Setup backend configuration template
+ sed -i -e 's|my $hostname = .*$|my $hostname = '\''localhost'\'';|' -e 's|our $bsuser = '\''obsrun'\'';|our $bsuser = '\''jenkins'\'';|' -e 's|our $bsgroup = '\''obsrun'\'';|our $bsgroup = '\''jenkins'\'';|' src/backend/BSConfig.pm.template
+ cp src/backend/BSConfig.pm.template src/backend/BSConfig.pm
+ echo 'Set environment variables'
Set environment variables
+ export RAILS_ENV=test
+ RAILS_ENV=test
+ ret=0
+ setup_api
+ echo 'Enter API rails root'
Enter API rails root
+ cd src/api
+ echo 'Setup database configuration'
Setup database configuration
+ cp config/database.yml.example config/database.yml
+ sed -i 's|database: api|database: ci_api|' config/database.yml
+ echo 'Setup additional configuration'
Setup additional configuration
+ cp config/options.yml.example config/options.yml
+ echo 'Install missing gems locally'
Install missing gems locally
+ mv Gemfile.lock Gemfile.lock.orig
+ bundle list
Gems included by the bundle:
* actionmailer (3.2.9)
* actionpack (3.2.9)
* activemodel (3.2.9)
* activerecord (3.2.9)
* activesupport (3.2.9)
* arel (3.0.2)
* builder (3.0.4)
* bundler (1.2.2)
* ci_reporter (1.7.3)
* daemons (1.1.9)
* delayed_job (3.0.4)
* delayed_job_active_record (0.3.3)
* erubis (2.7.0)
* eventmachine (1.0.0)
* exception_notification (3.0.0)
* faraday (0.8.4)
* faraday_middleware (0.9.0)
* fast_xs (0.8.0)
* hashie (1.2.0)
* hike (1.2.1)
* http_parser.rb (0.5.3)
* i18n (0.6.1)
* journey (1.0.4)
* json (1.7.5)
* mail (2.4.4)
* memcache-client (1.8.5)
* mime-types (1.19)
* minitest (4.2.0)
* multi_json (1.3.7)
* multipart-post (1.1.5)
* mysql2 (0.3.11)
* nokogiri (1.5.5)
* pkg-config (1.1.4)
* polyglot (0.3.3)
* rack (1.4.1)
* rack-cache (1.2)
* rack-ssl (1.3.2)
* rack-test (0.6.2)
* rails-api (0.0.2)
* railties (3.2.9)
* rake (0.9.2.2)
* rdoc (3.12)
* simple_oauth (0.1.9)
* simplecov (0.7.1)
* simplecov-html (0.7.1)
* simplecov-rcov (0.2.3)
* sprockets (2.2.1)
* thor (0.16.0)
* tilt (1.3.3)
* tinder (1.9.1)
* treetop (1.4.12)
* twitter-stream (0.1.16)
* tzinfo (0.3.35)
* xmlhash (1.3.3)
* yajl-ruby (1.1.0)
+ diff -u Gemfile.lock.orig Gemfile.lock
+ chmod a+x script/start_test_backend
+ echo 'Initialize test database, load seed data'
Initialize test database, load seed data
+ rake db:drop db:create db:setup --trace
** Invoke db:drop (first_time)
** Invoke db:load_config (first_time)
** Execute db:load_config
** Invoke rails_env (first_time)
** Execute rails_env
** Execute db:drop
** Invoke db:create (first_time)
** Invoke db:load_config
** Invoke rails_env
** Execute db:create
** Invoke db:setup (first_time)
** Execute db:setup
** Invoke db:structure:load (first_time)
** Invoke environment (first_time)
** Execute environment
** Invoke db:load_config
** Execute db:structure:load
** Invoke db:seed (first_time)
** Execute db:seed
** Invoke db:abort_if_pending_migrations (first_time)
** Invoke environment
** Invoke db:load_config
** Execute db:abort_if_pending_migrations
Seeding architectures table...
Seeding roles table...
Seeding users table...
Seeding roles_users table...
Seeding static_permissions table...
Seeding static permissions for admin role in roles_static_permissions table...
Seeding static permissions for maintainer role in roles_static_permissions table...
Seeding static permissions for reader role in roles_static_permissions table...
Seeding static permissions for downloader role in roles_static_permissions table...
Seeding attrib_namespaces table...
Seeding attrib_types table...
Seeding db_project_type table by loading test fixtures
Seeding issue trackers ...
+ cd ../..
+ setup_webui
+ echo 'Enter Webui rails root'
Enter Webui rails root
+ cd src/webui
+ echo 'Setup database configuration'
Setup database configuration
+ cp config/database.yml.example config/database.yml
+ echo 'Setup additional configuration'
Setup additional configuration
+ cp config/options.yml.example config/options.yml
+ echo 'Install missing gems locally'
Install missing gems locally
+ mv Gemfile.lock Gemfile.lock.orig
+ bundle list
[31mCould not find gem 'capybara (>= 2.0) ruby' in the gems available on this machine.[0m
Build step 'Execute shell' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
--
To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org
To contact the owner, e-mail: obs-tests+owner(a)opensuse.org
1
2
[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui #628
by admin@opensuse.org 17 Nov '12
by admin@opensuse.org 17 Nov '12
17 Nov '12
See <http://ci.opensuse.org/job/obs_master_testsuite_webui/628/changes>
Changes:
[Stephan Kulow] [dist] update to rails 3.2.9, no code changes required
[Stephan Kulow] [webui-testsuite] start on maintenance testing
------------------------------------------
[...truncated 38351 lines...]
[[32mINFO [0m|# 1227] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Completed 200 OK in 582ms (Views: 243.1ms | ActiveRecord: 0.0ms | API: 541.6ms (XML: 0.0ms , View: 1.7ms , Backend: 0.0ms , DB: 47.0ms , HTTP: 268.1ms) | XML: 0.8ms)
[[32mINFO [0m|# 1227] Started GET "/package/view_file?expand=0&file=_link&package=pack2_linked&project=BaseDistro2.0" for 127.0.0.1 at 2012-11-17 13:29:57 +0100
[[32mINFO [0m|# 1227] Processing by PackageController#view_file as HTML
[[32mINFO [0m|# 1227] Parameters: {"expand"=>"0", "file"=>"_link", "package"=>"pack2_linked", "project"=>"BaseDistro2.0"}
[[0;37mDEBUG[0m|# 1227] Setting return_to: "/package/view_file?expand=0&file=_link&package=pack2_linked&project=BaseDistro2.0"
[[0;37mDEBUG[0m|# 1227] Authenticating with iChain mode: off
[[32mINFO [0m|# 1227] Anonymous request to /package/view_file?expand=0&file=_link&package=pack2_linked&project=BaseDistro2.0
[[0;37mDEBUG[0m|# 1227] --> get_source: {:project=>"BaseDistro2.0", :package=>"pack2_linked", :filename=>"_link", :rev=>nil, :expand=>"0"}
[[0;37mDEBUG[0m|# 1227] --> get_source path: /source/BaseDistro2.0/pack2_linked/_link?expand=0
[[0;37mDEBUG[0m|# 1227] --> direct_http url: #<URI::Generic:0x0000000757e1d8 URL:http://localhost:3203/source/BaseDistro2.0/pack2_linked/_link?expand=0>
[[0;37mDEBUG[0m|# 1227] http_do #1: method: GET url: http://localhost:3203/source/BaseDistro2.0/pack2_linked/_link?expand=0
[[0;37mDEBUG[0m|# 1227] RT http://localhost:3203/source/BaseDistro2.0/pack2_linked/_link?expand=0 {"view"=>nil, "db"=>6.211296, "backend"=>0, "xml"=>0, :runtime=>47.867, :all=>92.81066200000001}
[[32mINFO [0m|# 1227] Rendered package/_tabs.html.erb (1.9ms)
[[32mINFO [0m|# 1227] Rendered shared/_editor.html.erb (94.5ms)
[[32mINFO [0m|# 1227] Rendered layouts/_watch_and_search.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_header.html.erb (5.2ms)
[[32mINFO [0m|# 1227] Rendered layouts/_personal_navigation.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Completed 200 OK in 223ms (Views: 127.6ms | ActiveRecord: 0.0ms | API: 92.8ms (XML: 0.0ms , Backend: 0.0ms , DB: 6.2ms , HTTP: 44.9ms) | XML: 0.5ms)
[[32mINFO [0m|# 1227] Started GET "/package/view_file?file=my_file&package=bdpack&project=BinaryprotectedProject" for 127.0.0.1 at 2012-11-17 13:29:58 +0100
[[32mINFO [0m|# 1227] Processing by PackageController#view_file as HTML
[[32mINFO [0m|# 1227] Parameters: {"file"=>"my_file", "package"=>"bdpack", "project"=>"BinaryprotectedProject"}
[[0;37mDEBUG[0m|# 1227] Setting return_to: "/package/view_file?file=my_file&package=bdpack&project=BinaryprotectedProject"
[[0;37mDEBUG[0m|# 1227] Authenticating with iChain mode: off
[[32mINFO [0m|# 1227] Anonymous request to /package/view_file?file=my_file&package=bdpack&project=BinaryprotectedProject
[[0;37mDEBUG[0m|# 1227] --> get_source: {:project=>"BinaryprotectedProject", :package=>"bdpack", :filename=>"my_file", :rev=>nil, :expand=>nil}
[[0;37mDEBUG[0m|# 1227] --> get_source path: /source/BinaryprotectedProject/bdpack/my_file?
[[0;37mDEBUG[0m|# 1227] --> direct_http url: #<URI::Generic:0x0000000367f0e0 URL:http://localhost:3203/source/BinaryprotectedProject/bdpack/my_file?>
[[0;37mDEBUG[0m|# 1227] http_do #1: method: GET url: http://localhost:3203/source/BinaryprotectedProject/bdpack/my_file?
[[0;37mDEBUG[0m|# 1227] RT http://localhost:3203/source/BinaryprotectedProject/bdpack/my_file? {"view"=>nil, "db"=>1.4244320000000001, "backend"=>0, "xml"=>0, :runtime=>44.615, :all=>48.034032999999994}
[[32mINFO [0m|# 1227] Rendered package/_tabs.html.erb (2.4ms)
[[32mINFO [0m|# 1227] Rendered shared/_editor.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_watch_and_search.html.erb (1.5ms)
[[32mINFO [0m|# 1227] Rendered layouts/_header.html.erb (2.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_personal_navigation.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Completed 200 OK in 72ms (Views: 21.6ms | ActiveRecord: 0.0ms | API: 48.0ms (XML: 0.0ms , Backend: 0.0ms , DB: 1.4ms , HTTP: 3.4ms) | XML: 0.6ms)
[[32mINFO [0m|# 1227] Started GET "/package/binaries?arch=i586&package=pack&project=SourceprotectedProject&repository=repo" for 127.0.0.1 at 2012-11-17 13:29:58 +0100
[[32mINFO [0m|# 1227] Processing by PackageController#binaries as HTML
[[32mINFO [0m|# 1227] Parameters: {"arch"=>"i586", "package"=>"pack", "project"=>"SourceprotectedProject", "repository"=>"repo"}
[[0;37mDEBUG[0m|# 1227] Setting return_to: "/package/binaries?arch=i586&package=pack&project=SourceprotectedProject&repository=repo"
[[0;37mDEBUG[0m|# 1227] Authenticating with iChain mode: off
[[32mINFO [0m|# 1227] Anonymous request to /package/binaries?arch=i586&package=pack&project=SourceprotectedProject&repository=repo
[[32mINFO [0m|# 1227] Rendered package/_tabs.html.erb (3.4ms)
[[32mINFO [0m|# 1227] Rendered shared/_download_repository_link.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_watch_and_search.html.erb (3.1ms)
[[32mINFO [0m|# 1227] Rendered layouts/_header.html.erb (11.7ms)
[[32mINFO [0m|# 1227] Rendered layouts/_personal_navigation.html.erb (1.4ms)
[[32mINFO [0m|# 1227] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Completed 200 OK in 25ms (Views: 22.6ms | ActiveRecord: 0.0ms | XML: 0.0ms)
[[32mINFO [0m|# 1227] Started GET "/package/binary?arch=i586&filename=delete_me-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" for 127.0.0.1 at 2012-11-17 13:29:58 +0100
[[32mINFO [0m|# 1227] Processing by PackageController#binary as HTML
[[32mINFO [0m|# 1227] Parameters: {"arch"=>"i586", "filename"=>"delete_me-1.0-1.i586.rpm", "package"=>"aggregate", "project"=>"home:adrian:ProtectionTest", "repository"=>"repo"}
[[0;37mDEBUG[0m|# 1227] Setting return_to: "/package/binary?arch=i586&filename=delete_me-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo"
[[0;37mDEBUG[0m|# 1227] Authenticating with iChain mode: off
[[32mINFO [0m|# 1227] Anonymous request to /package/binary?arch=i586&filename=delete_me-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo
[[0;37mDEBUG[0m|# 1227] [REST] find( Fileinfo, [{:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :repository=>"repo", :arch=>"i586", :filename=>"delete_me-1.0-1.i586.rpm", :view=>"fileinfo_ext"}] )
[[0;37mDEBUG[0m|# 1227] params {:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :repository=>"repo", :arch=>"i586", :filename=>"delete_me-1.0-1.i586.rpm", :view=>"fileinfo_ext"}
[[0;37mDEBUG[0m|# 1227] uri is: http://localhost:3203/build/:project/:repository/:arch/:package/:filename?:…
[[0;37mDEBUG[0m|# 1227] http_do #1: method: get url: http://localhost:3203/build/home:adrian:ProtectionTest/repo/i586/aggregate/…
[[0;37mDEBUG[0m|# 1227] RT http://localhost:3203/build/home:adrian:ProtectionTest/repo/i586/aggregate/… {"view"=>nil, "db"=>16.521015999999996, "backend"=>0, "xml"=>0, :runtime=>103.948, :all=>215.920066}
[[0;37mDEBUG[0m|# 1227] Checking url: http://download.opensuse.org/repositories/home:/adrian:/ProtectionTest/repo…
[[0;37mDEBUG[0m|# 1227] accepting format:text/html
[[32mINFO [0m|# 1227] Rendered package/_tabs.html.erb (4.0ms)
[[32mINFO [0m|# 1227] Rendered package/_deps.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_watch_and_search.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_header.html.erb (2.2ms)
[[32mINFO [0m|# 1227] Rendered layouts/_personal_navigation.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_sponsor.html.erb (0.8ms)
[[32mINFO [0m|# 1227] Completed 200 OK in 269ms (Views: 23.4ms | ActiveRecord: 0.0ms | API: 215.9ms (XML: 0.0ms , Backend: 0.0ms , DB: 16.5ms , HTTP: 112.0ms) | XML: 0.0ms)
[[32mINFO [0m|# 1227] Started GET "/package/binary?arch=i586&filename=package-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" for 127.0.0.1 at 2012-11-17 13:29:58 +0100
[[32mINFO [0m|# 1227] Processing by PackageController#binary as HTML
[[32mINFO [0m|# 1227] Parameters: {"arch"=>"i586", "filename"=>"package-1.0-1.i586.rpm", "package"=>"aggregate", "project"=>"home:adrian:ProtectionTest", "repository"=>"repo"}
[[0;37mDEBUG[0m|# 1227] Setting return_to: "/package/binary?arch=i586&filename=package-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo"
[[0;37mDEBUG[0m|# 1227] Authenticating with iChain mode: off
[[32mINFO [0m|# 1227] Anonymous request to /package/binary?arch=i586&filename=package-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo
[[0;37mDEBUG[0m|# 1227] [REST] find( Fileinfo, [{:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :repository=>"repo", :arch=>"i586", :filename=>"package-1.0-1.i586.rpm", :view=>"fileinfo_ext"}] )
[[0;37mDEBUG[0m|# 1227] params {:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :repository=>"repo", :arch=>"i586", :filename=>"package-1.0-1.i586.rpm", :view=>"fileinfo_ext"}
[[0;37mDEBUG[0m|# 1227] uri is: http://localhost:3203/build/:project/:repository/:arch/:package/:filename?:…
[[0;37mDEBUG[0m|# 1227] http_do #1: method: get url: http://localhost:3203/build/home:adrian:ProtectionTest/repo/i586/aggregate/…
[[0;37mDEBUG[0m|# 1227] RT http://localhost:3203/build/home:adrian:ProtectionTest/repo/i586/aggregate/… {"view"=>nil, "db"=>8.946622999999999, "backend"=>0, "xml"=>0, :runtime=>121.754, :all=>124.664651}
[[0;37mDEBUG[0m|# 1227] Checking url: http://download.opensuse.org/repositories/home:/adrian:/ProtectionTest/repo…
[[0;37mDEBUG[0m|# 1227] accepting format:text/html
[[32mINFO [0m|# 1227] Rendered package/_tabs.html.erb (4.0ms)
[[32mINFO [0m|# 1227] Rendered package/_deps.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_watch_and_search.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_header.html.erb (2.6ms)
[[32mINFO [0m|# 1227] Rendered layouts/_personal_navigation.html.erb (1.1ms)
[[32mINFO [0m|# 1227] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Completed 200 OK in 164ms (Views: 21.3ms | ActiveRecord: 0.0ms | API: 124.7ms (XML: 0.0ms , Backend: 0.0ms , DB: 8.9ms , HTTP: 2.9ms) | XML: 0.8ms)
[[32mINFO [0m|# 1227] Started GET "/package/view_file?file=_aggregate&package=aggregate&project=home%3Aadrian%3AProtectionTest" for 127.0.0.1 at 2012-11-17 13:29:58 +0100
[[32mINFO [0m|# 1227] Processing by PackageController#view_file as HTML
[[32mINFO [0m|# 1227] Parameters: {"file"=>"_aggregate", "package"=>"aggregate", "project"=>"home:adrian:ProtectionTest"}
[[0;37mDEBUG[0m|# 1227] Setting return_to: "/package/view_file?file=_aggregate&package=aggregate&project=home%3Aadrian%3AProtectionTest"
[[0;37mDEBUG[0m|# 1227] Authenticating with iChain mode: off
[[32mINFO [0m|# 1227] Anonymous request to /package/view_file?file=_aggregate&package=aggregate&project=home%3Aadrian%3AProtectionTest
[[0;37mDEBUG[0m|# 1227] --> get_source: {:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :filename=>"_aggregate", :rev=>nil, :expand=>nil}
[[0;37mDEBUG[0m|# 1227] --> get_source path: /source/home:adrian:ProtectionTest/aggregate/_aggregate?
[[0;37mDEBUG[0m|# 1227] --> direct_http url: #<URI::Generic:0x00000007162a90 URL:http://localhost:3203/source/home:adrian:ProtectionTest/aggregate/_aggregate?>
[[0;37mDEBUG[0m|# 1227] http_do #1: method: GET url: http://localhost:3203/source/home:adrian:ProtectionTest/aggregate/_aggregat…
[[0;37mDEBUG[0m|# 1227] RT http://localhost:3203/source/home:adrian:ProtectionTest/aggregate/_aggregat… {"view"=>nil, "db"=>1.924476, "backend"=>0, "xml"=>0, :runtime=>47.89, :all=>51.034017}
[[32mINFO [0m|# 1227] Rendered package/_tabs.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered shared/_editor.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_watch_and_search.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_header.html.erb (3.2ms)
[[32mINFO [0m|# 1227] Rendered layouts/_personal_navigation.html.erb (0.8ms)
[[32mINFO [0m|# 1227] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Completed 200 OK in 62ms (Views: 9.3ms | ActiveRecord: 0.0ms | API: 51.0ms (XML: 0.0ms , Backend: 0.0ms , DB: 1.9ms , HTTP: 3.1ms) | XML: 0.0ms)
[[32mINFO [0m|# 1227] Started GET "/package/binaries?arch=i586&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" for 127.0.0.1 at 2012-11-17 13:29:58 +0100
[[32mINFO [0m|# 1227] Processing by PackageController#binaries as HTML
[[32mINFO [0m|# 1227] Parameters: {"arch"=>"i586", "package"=>"aggregate", "project"=>"home:adrian:ProtectionTest", "repository"=>"repo"}
[[0;37mDEBUG[0m|# 1227] Setting return_to: "/package/binaries?arch=i586&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo"
[[0;37mDEBUG[0m|# 1227] Authenticating with iChain mode: off
[[32mINFO [0m|# 1227] Anonymous request to /package/binaries?arch=i586&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo
[[32mINFO [0m|# 1227] Rendered package/_tabs.html.erb (2.4ms)
[[32mINFO [0m|# 1227] Rendered shared/_download_repository_link.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_watch_and_search.html.erb (2.4ms)
[[32mINFO [0m|# 1227] Rendered layouts/_header.html.erb (2.7ms)
[[32mINFO [0m|# 1227] Rendered layouts/_personal_navigation.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Completed 200 OK in 13ms (Views: 11.5ms | ActiveRecord: 0.0ms | XML: 0.0ms)
[[0;37mDEBUG[0m|# 1227] [1m[35m (3.1ms)[0m rollback transaction
[[0;37mDEBUG[0m|# 1227] [1m[36m (0.0ms)[0m [1mbegin transaction[0m
[[32mINFO [0m|# 1227] Started POST "/user/do_login" for 127.0.0.1 at 2012-11-17 13:29:58 +0100
[[32mINFO [0m|# 1227] Processing by UserController#do_login as HTML
[[32mINFO [0m|# 1227] Parameters: {"username"=>"tom", "password"=>"[FILTERED]", "return_to_path"=>"/"}
[[0;37mDEBUG[0m|# 1227] Setting return_to: "/"
[[0;37mDEBUG[0m|# 1227] Authenticating with iChain mode: off
[[32mINFO [0m|# 1227] Anonymous request to /
[[0;37mDEBUG[0m|# 1227] Doing form authorization to login user tom
[[0;37mDEBUG[0m|# 1227] [REST] find( Person, [{:login=>"tom"}] )
[[0;37mDEBUG[0m|# 1227] params {:login=>"tom"}
[[0;37mDEBUG[0m|# 1227] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|# 1227] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|# 1227] RT http://localhost:3203/person/tom {"view"=>0.626126, "db"=>1.7148089999999998, "backend"=>0, "xml"=>0, :runtime=>21.964000000000002, :all=>25.144863}
[[32mINFO [0m|# 1227] Redirected to http://example.org/
[[32mINFO [0m|# 1227] Completed 302 Found in 28ms (ActiveRecord: 0.0ms | API: 25.1ms (XML: 0.0ms , View: 0.6ms , Backend: 0.0ms , DB: 1.7ms , HTTP: 3.2ms) | XML: 0.0ms)
[[32mINFO [0m|# 1227] Started GET "/" for 127.0.0.1 at 2012-11-17 13:29:58 +0100
[[32mINFO [0m|# 1227] Processing by MainController#index as HTML
[[0;37mDEBUG[0m|# 1227] Setting return_to: "/"
[[0;37mDEBUG[0m|# 1227] Authenticating with iChain mode: off
[[32mINFO [0m|# 1227] Authenticated request to "/" from tom
[[0;37mDEBUG[0m|# 1227] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|# 1227] params {:is_current=>"true", :login=>"tom"}
[[0;37mDEBUG[0m|# 1227] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|# 1227] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|# 1227] RT http://localhost:3203/person/tom {"view"=>0.564385, "db"=>1.2113239999999998, "backend"=>0, "xml"=>0, :runtime=>10.379, :all=>13.526391}
[[0;37mDEBUG[0m|# 1227] --> direct_http url: #<URI::Generic:0x000000071bbca8 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom>
[[0;37mDEBUG[0m|# 1227] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom
[[0;37mDEBUG[0m|# 1227] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.292235, "db"=>11.052293000000002, "backend"=>0, "xml"=>0, :runtime=>53.992, :all=>109.024681}
[[0;37mDEBUG[0m|# 1227] data {
"declined": [
],
"new": [
],
"reviews": [
1000
]
}
[[32mINFO [0m|# 1227] Rendered main/_news.html.erb (0.6ms)
[[32mINFO [0m|# 1227] Rendered main/_contact.html.erb (0.1ms)
[[32mINFO [0m|# 1227] Rendered main/_latest_updates.erb (19.3ms)
[[32mINFO [0m|# 1227] Rendered main/_countdown.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered main/_sponsors.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered main/_feeds.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_watch_and_search.html.erb (0.8ms)
[[32mINFO [0m|# 1227] Rendered layouts/_header.html.erb (1.8ms)
[[32mINFO [0m|# 1227] Rendered layouts/_personal_navigation.html.erb (1.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_flash.html.erb (0.1ms)
[[32mINFO [0m|# 1227] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Completed 200 OK in 160ms (Views: 32.8ms | ActiveRecord: 0.0ms | API: 122.6ms (XML: 0.0ms , View: 0.9ms , Backend: 0.0ms , DB: 12.3ms , HTTP: 58.2ms) | XML: 1.0ms)
[[32mINFO [0m|# 1227] Started GET "/project/repository_state?project=BaseDistro%3AUpdate&repository=BaseDistroUpdateProject_repo" for 127.0.0.1 at 2012-11-17 13:29:59 +0100
[[32mINFO [0m|# 1227] Processing by ProjectController#repository_state as HTML
[[32mINFO [0m|# 1227] Parameters: {"project"=>"BaseDistro:Update", "repository"=>"BaseDistroUpdateProject_repo"}
[[0;37mDEBUG[0m|# 1227] Setting return_to: "/project/repository_state?project=BaseDistro%3AUpdate&repository=BaseDistroUpdateProject_repo"
[[0;37mDEBUG[0m|# 1227] Authenticating with iChain mode: off
[[32mINFO [0m|# 1227] Authenticated request to "/project/repository_state?project=BaseDistro%3AUpdate&repository=BaseDistroUpdateProject_repo" from tom
[[0;37mDEBUG[0m|# 1227] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|# 1227] params {:is_current=>"true", :login=>"tom"}
[[0;37mDEBUG[0m|# 1227] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|# 1227] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|# 1227] RT http://localhost:3203/person/tom {"view"=>0.5472009999999999, "db"=>1.0080559999999998, "backend"=>0, "xml"=>0, :runtime=>10.305, :all=>23.675451}
[[0;37mDEBUG[0m|# 1227] --> direct_http url: #<URI::Generic:0x0000000635f560 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom>
[[0;37mDEBUG[0m|# 1227] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom
[[0;37mDEBUG[0m|# 1227] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.238388, "db"=>12.776899999999998, "backend"=>0, "xml"=>0, :runtime=>48.029, :all=>94.262466}
[[0;37mDEBUG[0m|# 1227] data {
"declined": [
],
"new": [
],
"reviews": [
1000
]
}
[[0;37mDEBUG[0m|# 1227] [REST] find( BuilddepInfo, [{:project=>"BaseDistro:Update", :package=>"-", :repository=>"BaseDistroUpdateProject_repo", :arch=>"i586"}] )
[[0;37mDEBUG[0m|# 1227] params {:project=>"BaseDistro:Update", :package=>"-", :repository=>"BaseDistroUpdateProject_repo", :arch=>"i586"}
[[0;37mDEBUG[0m|# 1227] uri is: http://localhost:3203/build/:project/:repository/:arch/_builddepinfo?:packa…
[[0;37mDEBUG[0m|# 1227] http_do #1: method: get url: http://localhost:3203/build/BaseDistro:Update/BaseDistroUpdateProject_repo/…
[[0;37mDEBUG[0m|# 1227] RT http://localhost:3203/build/BaseDistro:Update/BaseDistroUpdateProject_repo/… {"view"=>nil, "db"=>6.83799, "backend"=>0, "xml"=>0, :runtime=>49.014, :all=>94.39884900000001}
[[32mINFO [0m|# 1227] Rendered project/_tabs.html.erb (3.2ms)
[[32mINFO [0m|# 1227] Rendered shared/_download_repository_link.html.erb (0.3ms)
[[32mINFO [0m|# 1227] Rendered layouts/_watch_and_search.html.erb (1.1ms)
[[32mINFO [0m|# 1227] Rendered layouts/_header.html.erb (1.9ms)
[[32mINFO [0m|# 1227] Rendered layouts/_personal_navigation.html.erb (1.0ms)
[[32mINFO [0m|# 1227] Rendered layouts/_flash.html.erb (0.1ms)
[[32mINFO [0m|# 1227] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|# 1227] Completed 200 OK in 243ms (Views: 25.2ms | ActiveRecord: 0.0ms | API: 212.3ms (XML: 0.0ms , View: 0.8ms , Backend: 0.0ms , DB: 20.6ms , HTTP: 105.0ms) | XML: 0.6ms)
[[32mINFO [0m|# 1227] Started POST "/user/logout" for 127.0.0.1 at 2012-11-17 13:29:59 +0100
[[32mINFO [0m|# 1227] Processing by UserController#logout as HTML
[[0;37mDEBUG[0m|# 1227] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|# 1227] Authenticating with iChain mode: off
[[32mINFO [0m|# 1227] Authenticated request to "/user/logout" from tom
[[32mINFO [0m|# 1227] Logging out: tom
[[32mINFO [0m|# 1227] Redirected to http://example.org/
[[32mINFO [0m|# 1227] Completed 302 Found in 2ms (ActiveRecord: 0.2ms | XML: 0.0ms)
[[0;37mDEBUG[0m|# 1227] [1m[35m (9.3ms)[0m rollback transaction
+ echo
+ cleanup
+ echo 'Killing backend processes'
Killing backend processes
+ fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/>
+ egrep 'perl|ruby'
USER PID ACCESS COMMAND
<http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/>:
jenkins ..c.. sh
jenkins ..c.. sh
jenkins ..c.. sh
+ echo 'Remove log/tmp files to save disc space'
Remove log/tmp files to save disc space
+ rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets
+ exit 1
Build step 'Execute shell' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
--
To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org
To contact the owner, e-mail: obs-tests+owner(a)opensuse.org
1
1
[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui #626
by admin@opensuse.org 16 Nov '12
by admin@opensuse.org 16 Nov '12
16 Nov '12
See <http://ci.opensuse.org/job/obs_master_testsuite_webui/626/changes>
Changes:
[Stephan Kulow] [webui] removing some unused code
[Stephan Kulow] [api] allow request list by user too
------------------------------------------
[...truncated 41280 lines...]
[[32mINFO [0m|#19774] Started GET "/package/view_file?file=my_file&package=bdpack&project=BinaryprotectedProject" for 127.0.0.1 at 2012-11-16 22:02:24 +0100
[[32mINFO [0m|#19774] Processing by PackageController#view_file as HTML
[[32mINFO [0m|#19774] Parameters: {"file"=>"my_file", "package"=>"bdpack", "project"=>"BinaryprotectedProject"}
[[0;37mDEBUG[0m|#19774] Setting return_to: "/package/view_file?file=my_file&package=bdpack&project=BinaryprotectedProject"
[[0;37mDEBUG[0m|#19774] Authenticating with iChain mode: off
[[32mINFO [0m|#19774] Anonymous request to /package/view_file?file=my_file&package=bdpack&project=BinaryprotectedProject
[[0;37mDEBUG[0m|#19774] --> get_source: {:project=>"BinaryprotectedProject", :package=>"bdpack", :filename=>"my_file", :rev=>nil, :expand=>nil}
[[0;37mDEBUG[0m|#19774] --> get_source path: /source/BinaryprotectedProject/bdpack/my_file?
[[0;37mDEBUG[0m|#19774] --> direct_http url: #<URI::Generic:0x00000003b6c4e0 URL:http://localhost:3203/source/BinaryprotectedProject/bdpack/my_file?>
[[0;37mDEBUG[0m|#19774] http_do #1: method: GET url: http://localhost:3203/source/BinaryprotectedProject/bdpack/my_file?
[[0;37mDEBUG[0m|#19774] RT http://localhost:3203/source/BinaryprotectedProject/bdpack/my_file? {"view"=>nil, "db"=>11.865215, "backend"=>0, "xml"=>0, :runtime=>41.986999999999995, :all=>45.616573}
[[32mINFO [0m|#19774] Rendered package/_tabs.html.erb (3.4ms)
[[32mINFO [0m|#19774] Rendered shared/_editor.html.erb (0.7ms)
[[32mINFO [0m|#19774] Rendered layouts/_watch_and_search.html.erb (0.5ms)
[[32mINFO [0m|#19774] Rendered layouts/_header.html.erb (1.2ms)
[[32mINFO [0m|#19774] Rendered layouts/_personal_navigation.html.erb (1.3ms)
[[32mINFO [0m|#19774] Rendered layouts/_flash.html.erb (0.1ms)
[[32mINFO [0m|#19774] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|#19774] Completed 200 OK in 71ms (Views: 21.2ms | ActiveRecord: 0.0ms | API: 45.6ms (XML: 0.0ms , Backend: 0.0ms , DB: 11.9ms , HTTP: 3.6ms) | XML: 0.7ms)
[[32mINFO [0m|#19774] Started GET "/package/binaries?arch=i586&package=pack&project=SourceprotectedProject&repository=repo" for 127.0.0.1 at 2012-11-16 22:02:24 +0100
[[32mINFO [0m|#19774] Processing by PackageController#binaries as HTML
[[32mINFO [0m|#19774] Parameters: {"arch"=>"i586", "package"=>"pack", "project"=>"SourceprotectedProject", "repository"=>"repo"}
[[0;37mDEBUG[0m|#19774] Setting return_to: "/package/binaries?arch=i586&package=pack&project=SourceprotectedProject&repository=repo"
[[0;37mDEBUG[0m|#19774] Authenticating with iChain mode: off
[[32mINFO [0m|#19774] Anonymous request to /package/binaries?arch=i586&package=pack&project=SourceprotectedProject&repository=repo
[[32mINFO [0m|#19774] Rendered package/_tabs.html.erb (3.3ms)
[[32mINFO [0m|#19774] Rendered shared/_download_repository_link.html.erb (0.3ms)
[[32mINFO [0m|#19774] Rendered layouts/_watch_and_search.html.erb (0.5ms)
[[32mINFO [0m|#19774] Rendered layouts/_header.html.erb (1.2ms)
[[32mINFO [0m|#19774] Rendered layouts/_personal_navigation.html.erb (1.2ms)
[[32mINFO [0m|#19774] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|#19774] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|#19774] Completed 200 OK in 33ms (Views: 29.6ms | ActiveRecord: 0.0ms | XML: 0.7ms)
[[32mINFO [0m|#19774] Started GET "/package/binary?arch=i586&filename=delete_me-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" for 127.0.0.1 at 2012-11-16 22:02:24 +0100
[[32mINFO [0m|#19774] Processing by PackageController#binary as HTML
[[32mINFO [0m|#19774] Parameters: {"arch"=>"i586", "filename"=>"delete_me-1.0-1.i586.rpm", "package"=>"aggregate", "project"=>"home:adrian:ProtectionTest", "repository"=>"repo"}
[[0;37mDEBUG[0m|#19774] Setting return_to: "/package/binary?arch=i586&filename=delete_me-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo"
[[0;37mDEBUG[0m|#19774] Authenticating with iChain mode: off
[[32mINFO [0m|#19774] Anonymous request to /package/binary?arch=i586&filename=delete_me-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo
[[0;37mDEBUG[0m|#19774] [REST] find( Fileinfo, [{:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :repository=>"repo", :arch=>"i586", :filename=>"delete_me-1.0-1.i586.rpm", :view=>"fileinfo_ext"}] )
[[0;37mDEBUG[0m|#19774] params {:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :repository=>"repo", :arch=>"i586", :filename=>"delete_me-1.0-1.i586.rpm", :view=>"fileinfo_ext"}
[[0;37mDEBUG[0m|#19774] uri is: http://localhost:3203/build/:project/:repository/:arch/:package/:filename?:…
[[0;37mDEBUG[0m|#19774] http_do #1: method: get url: http://localhost:3203/build/home:adrian:ProtectionTest/repo/i586/aggregate/…
[[0;37mDEBUG[0m|#19774] RT http://localhost:3203/build/home:adrian:ProtectionTest/repo/i586/aggregate/… {"view"=>nil, "db"=>9.542597, "backend"=>0, "xml"=>0, :runtime=>218.37400000000002, :all=>221.524824}
[[0;37mDEBUG[0m|#19774] Checking url: http://download.opensuse.org/repositories/home:/adrian:/ProtectionTest/repo…
[[0;37mDEBUG[0m|#19774] accepting format:text/html
[[32mINFO [0m|#19774] Rendered package/_tabs.html.erb (11.2ms)
[[32mINFO [0m|#19774] Rendered package/_deps.html.erb (0.7ms)
[[32mINFO [0m|#19774] Rendered layouts/_watch_and_search.html.erb (0.5ms)
[[32mINFO [0m|#19774] Rendered layouts/_header.html.erb (1.2ms)
[[32mINFO [0m|#19774] Rendered layouts/_personal_navigation.html.erb (1.2ms)
[[32mINFO [0m|#19774] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|#19774] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|#19774] Completed 200 OK in 267ms (Views: 28.1ms | ActiveRecord: 0.0ms | API: 221.5ms (XML: 0.0ms , Backend: 0.0ms , DB: 9.5ms , HTTP: 3.2ms) | XML: 1.1ms)
[[32mINFO [0m|#19774] Started GET "/package/binary?arch=i586&filename=package-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" for 127.0.0.1 at 2012-11-16 22:02:24 +0100
[[32mINFO [0m|#19774] Processing by PackageController#binary as HTML
[[32mINFO [0m|#19774] Parameters: {"arch"=>"i586", "filename"=>"package-1.0-1.i586.rpm", "package"=>"aggregate", "project"=>"home:adrian:ProtectionTest", "repository"=>"repo"}
[[0;37mDEBUG[0m|#19774] Setting return_to: "/package/binary?arch=i586&filename=package-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo"
[[0;37mDEBUG[0m|#19774] Authenticating with iChain mode: off
[[32mINFO [0m|#19774] Anonymous request to /package/binary?arch=i586&filename=package-1.0-1.i586.rpm&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo
[[0;37mDEBUG[0m|#19774] [REST] find( Fileinfo, [{:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :repository=>"repo", :arch=>"i586", :filename=>"package-1.0-1.i586.rpm", :view=>"fileinfo_ext"}] )
[[0;37mDEBUG[0m|#19774] params {:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :repository=>"repo", :arch=>"i586", :filename=>"package-1.0-1.i586.rpm", :view=>"fileinfo_ext"}
[[0;37mDEBUG[0m|#19774] uri is: http://localhost:3203/build/:project/:repository/:arch/:package/:filename?:…
[[0;37mDEBUG[0m|#19774] http_do #1: method: get url: http://localhost:3203/build/home:adrian:ProtectionTest/repo/i586/aggregate/…
[[0;37mDEBUG[0m|#19774] RT http://localhost:3203/build/home:adrian:ProtectionTest/repo/i586/aggregate/… {"view"=>nil, "db"=>12.916841999999999, "backend"=>0, "xml"=>0, :runtime=>107.735, :all=>111.153186}
[[0;37mDEBUG[0m|#19774] Checking url: http://download.opensuse.org/repositories/home:/adrian:/ProtectionTest/repo…
[[0;37mDEBUG[0m|#19774] accepting format:text/html
[[32mINFO [0m|#19774] Rendered package/_tabs.html.erb (11.0ms)
[[32mINFO [0m|#19774] Rendered package/_deps.html.erb (0.7ms)
[[32mINFO [0m|#19774] Rendered layouts/_watch_and_search.html.erb (0.5ms)
[[32mINFO [0m|#19774] Rendered layouts/_header.html.erb (1.3ms)
[[32mINFO [0m|#19774] Rendered layouts/_personal_navigation.html.erb (6.8ms)
[[32mINFO [0m|#19774] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|#19774] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|#19774] Completed 200 OK in 161ms (Views: 27.6ms | ActiveRecord: 0.0ms | API: 111.2ms (XML: 0.0ms , Backend: 0.0ms , DB: 12.9ms , HTTP: 3.4ms) | XML: 1.0ms)
[[32mINFO [0m|#19774] Started GET "/package/view_file?file=_aggregate&package=aggregate&project=home%3Aadrian%3AProtectionTest" for 127.0.0.1 at 2012-11-16 22:02:24 +0100
[[32mINFO [0m|#19774] Processing by PackageController#view_file as HTML
[[32mINFO [0m|#19774] Parameters: {"file"=>"_aggregate", "package"=>"aggregate", "project"=>"home:adrian:ProtectionTest"}
[[0;37mDEBUG[0m|#19774] Setting return_to: "/package/view_file?file=_aggregate&package=aggregate&project=home%3Aadrian%3AProtectionTest"
[[0;37mDEBUG[0m|#19774] Authenticating with iChain mode: off
[[32mINFO [0m|#19774] Anonymous request to /package/view_file?file=_aggregate&package=aggregate&project=home%3Aadrian%3AProtectionTest
[[0;37mDEBUG[0m|#19774] --> get_source: {:project=>"home:adrian:ProtectionTest", :package=>"aggregate", :filename=>"_aggregate", :rev=>nil, :expand=>nil}
[[0;37mDEBUG[0m|#19774] --> get_source path: /source/home:adrian:ProtectionTest/aggregate/_aggregate?
[[0;37mDEBUG[0m|#19774] --> direct_http url: #<URI::Generic:0x000000071a8c70 URL:http://localhost:3203/source/home:adrian:ProtectionTest/aggregate/_aggregate?>
[[0;37mDEBUG[0m|#19774] http_do #1: method: GET url: http://localhost:3203/source/home:adrian:ProtectionTest/aggregate/_aggregat…
[[0;37mDEBUG[0m|#19774] RT http://localhost:3203/source/home:adrian:ProtectionTest/aggregate/_aggregat… {"view"=>nil, "db"=>11.353447999999997, "backend"=>0, "xml"=>0, :runtime=>44.528, :all=>47.893971}
[[32mINFO [0m|#19774] Rendered package/_tabs.html.erb (2.9ms)
[[32mINFO [0m|#19774] Rendered shared/_editor.html.erb (0.7ms)
[[32mINFO [0m|#19774] Rendered layouts/_watch_and_search.html.erb (0.5ms)
[[32mINFO [0m|#19774] Rendered layouts/_header.html.erb (1.2ms)
[[32mINFO [0m|#19774] Rendered layouts/_personal_navigation.html.erb (1.1ms)
[[32mINFO [0m|#19774] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|#19774] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|#19774] Completed 200 OK in 73ms (Views: 21.5ms | ActiveRecord: 0.0ms | API: 47.9ms (XML: 0.0ms , Backend: 0.0ms , DB: 11.4ms , HTTP: 3.4ms) | XML: 0.4ms)
[[32mINFO [0m|#19774] Started GET "/package/binaries?arch=i586&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo" for 127.0.0.1 at 2012-11-16 22:02:24 +0100
[[32mINFO [0m|#19774] Processing by PackageController#binaries as HTML
[[32mINFO [0m|#19774] Parameters: {"arch"=>"i586", "package"=>"aggregate", "project"=>"home:adrian:ProtectionTest", "repository"=>"repo"}
[[0;37mDEBUG[0m|#19774] Setting return_to: "/package/binaries?arch=i586&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo"
[[0;37mDEBUG[0m|#19774] Authenticating with iChain mode: off
[[32mINFO [0m|#19774] Anonymous request to /package/binaries?arch=i586&package=aggregate&project=home%3Aadrian%3AProtectionTest&repository=repo
[[32mINFO [0m|#19774] Rendered package/_tabs.html.erb (2.9ms)
[[32mINFO [0m|#19774] Rendered shared/_download_repository_link.html.erb (0.2ms)
[[32mINFO [0m|#19774] Rendered layouts/_watch_and_search.html.erb (0.5ms)
[[32mINFO [0m|#19774] Rendered layouts/_header.html.erb (15.6ms)
[[32mINFO [0m|#19774] Rendered layouts/_personal_navigation.html.erb (1.1ms)
[[32mINFO [0m|#19774] Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|#19774] Rendered layouts/_sponsor.html.erb (0.0ms)
[[32mINFO [0m|#19774] Completed 200 OK in 28ms (Views: 26.1ms | ActiveRecord: 0.0ms | XML: 0.4ms)
[[0;37mDEBUG[0m|#19774] [1m[35m (0.5ms)[0m rollback transaction
[[0;37mDEBUG[0m|#19774] [1m[36m (0.0ms)[0m [1mbegin transaction[0m
[[32mINFO [0m|#19774] Started POST "/user/do_login" for 127.0.0.1 at 2012-11-16 22:02:24 +0100
[[32mINFO [0m|#19774] Processing by UserController#do_login as HTML
[[32mINFO [0m|#19774] Parameters: {"username"=>"tom", "password"=>"[FILTERED]", "return_to_path"=>"/"}
[[0;37mDEBUG[0m|#19774] Setting return_to: "/"
[[0;37mDEBUG[0m|#19774] Authenticating with iChain mode: off
[[32mINFO [0m|#19774] Anonymous request to /
[[0;37mDEBUG[0m|#19774] Doing form authorization to login user tom
[[0;37mDEBUG[0m|#19774] [REST] find( Person, [{:login=>"tom"}] )
[[0;37mDEBUG[0m|#19774] params {:login=>"tom"}
[[0;37mDEBUG[0m|#19774] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|#19774] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#19774] RT http://localhost:3203/person/tom {"view"=>0.624132, "db"=>1.0883720000000001, "backend"=>0, "xml"=>0, :runtime=>10.024999999999999, :all=>13.194333}
[[32mINFO [0m|#19774] Redirected to http://example.org/
[[32mINFO [0m|#19774] Completed 302 Found in 26ms (ActiveRecord: 0.0ms | API: 13.2ms (XML: 0.0ms , View: 0.6ms , Backend: 0.0ms , DB: 1.1ms , HTTP: 3.2ms) | XML: 0.4ms)
[[32mINFO [0m|#19774] Started GET "/" for 127.0.0.1 at 2012-11-16 22:02:24 +0100
[[32mINFO [0m|#19774] Processing by MainController#index as HTML
[[0;37mDEBUG[0m|#19774] Setting return_to: "/"
[[0;37mDEBUG[0m|#19774] Authenticating with iChain mode: off
[[32mINFO [0m|#19774] Authenticated request to "/" from tom
[[0;37mDEBUG[0m|#19774] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#19774] params {:is_current=>"true", :login=>"tom"}
[[0;37mDEBUG[0m|#19774] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|#19774] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#19774] RT http://localhost:3203/person/tom {"view"=>0.577509, "db"=>1.0918029999999999, "backend"=>0, "xml"=>0, :runtime=>9.671000000000001, :all=>13.113596999999999}
[[0;37mDEBUG[0m|#19774] --> direct_http url: #<URI::Generic:0x00000007281b88 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom>
[[0;37mDEBUG[0m|#19774] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom
[[0;37mDEBUG[0m|#19774] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.274225, "db"=>10.723481000000003, "backend"=>0, "xml"=>0, :runtime=>43.788000000000004, :all=>101.052243}
[[0;37mDEBUG[0m|#19774] data {
"declined": [
],
"new": [
],
"reviews": [
1000
]
}
[[32mINFO [0m|#19774] Rendered main/_news.html.erb (0.7ms)
[[32mINFO [0m|#19774] Rendered main/_contact.html.erb (0.1ms)
[[32mINFO [0m|#19774] Rendered main/_latest_updates.erb (19.1ms)
[[32mINFO [0m|#19774] Rendered main/_countdown.html.erb (0.1ms)
[[32mINFO [0m|#19774] Rendered main/_sponsors.html.erb (0.0ms)
[[32mINFO [0m|#19774] Rendered main/_feeds.html.erb (0.0ms)
[[32mINFO [0m|#19774] Rendered layouts/_watch_and_search.html.erb (0.8ms)
[[32mINFO [0m|#19774] Rendered layouts/_header.html.erb (1.6ms)
[[32mINFO [0m|#19774] Rendered layouts/_personal_navigation.html.erb (1.1ms)
[[32mINFO [0m|#19774] Rendered layouts/_flash.html.erb (0.1ms)
[[32mINFO [0m|#19774] Rendered layouts/_sponsor.html.erb (0.1ms)
[[32mINFO [0m|#19774] Completed 200 OK in 152ms (Views: 32.8ms | ActiveRecord: 0.0ms | API: 114.2ms (XML: 0.0ms , View: 0.9ms , Backend: 0.0ms , DB: 11.8ms , HTTP: 60.7ms) | XML: 1.1ms)
[[32mINFO [0m|#19774] Started GET "/project/maintained_projects?project=My%3AMaintenance" for 127.0.0.1 at 2012-11-16 22:02:25 +0100
[[32mINFO [0m|#19774] Processing by ProjectController#maintained_projects as HTML
[[32mINFO [0m|#19774] Parameters: {"project"=>"My:Maintenance"}
[[0;37mDEBUG[0m|#19774] Setting return_to: "/project/maintained_projects?project=My%3AMaintenance"
[[0;37mDEBUG[0m|#19774] Authenticating with iChain mode: off
[[32mINFO [0m|#19774] Authenticated request to "/project/maintained_projects?project=My%3AMaintenance" from tom
[[0;37mDEBUG[0m|#19774] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#19774] params {:is_current=>"true", :login=>"tom"}
[[0;37mDEBUG[0m|#19774] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|#19774] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#19774] RT http://localhost:3203/person/tom {"view"=>0.5323129999999999, "db"=>1.122406, "backend"=>0, "xml"=>0, :runtime=>9.887, :all=>13.062066000000002}
[[0;37mDEBUG[0m|#19774] --> direct_http url: #<URI::Generic:0x00000005d57b40 URL:http://localhost:3203/webui/person_requests_that_need_work?login=tom>
[[0;37mDEBUG[0m|#19774] http_do #1: method: GET url: http://localhost:3203/webui/person_requests_that_need_work?login=tom
[[0;37mDEBUG[0m|#19774] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.219122, "db"=>2.999669, "backend"=>0, "xml"=>0, :runtime=>27.237000000000002, :all=>74.178151}
[[0;37mDEBUG[0m|#19774] data {
"declined": [
],
"new": [
],
"reviews": [
1000
]
}
[[31mERROR[0m|#19774] rescue_action: caught NoMethodError: undefined method `<<' for nil:NilClass
[[0;37mDEBUG[0m|#19774] ERROR: 400; No message set
[[0;37mDEBUG[0m|#19774] <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/webui/app/cont…>:1379:in `block in maintained_projects'
<http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/webui/lib/acti…>:281:in `block in each'
<http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/webui/lib/acti…>:297:in `block in each_with_index'
<http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/webui/lib/acti…>:296:in `each_with_index'
<http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/webui/lib/acti…>:279:in `each'
<http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/webui/app/cont…>:1378:in `maintained_projects'
<http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/src/webui/test/fun…>:296:in `test_2respider'
[[32mINFO [0m|#19774] Rendered layouts/_watch_and_search.html.erb (1.1ms)
[[32mINFO [0m|#19774] Rendered layouts/_header.html.erb (2.1ms)
[[32mINFO [0m|#19774] Rendered layouts/_personal_navigation.html.erb (1.0ms)
[[32mINFO [0m|#19774] Rendered layouts/_flash.html.erb (0.1ms)
[[32mINFO [0m|#19774] Rendered layouts/_sponsor.html.erb (0.1ms)
[[32mINFO [0m|#19774] Completed 400 Bad Request in 108ms (Views: 16.3ms | ActiveRecord: 0.0ms | API: 87.2ms (XML: 0.0ms , View: 0.8ms , Backend: 0.0ms , DB: 4.1ms , HTTP: 50.1ms) | XML: 0.2ms)
[[32mINFO [0m|#19774] Started POST "/user/logout" for 127.0.0.1 at 2012-11-16 22:02:25 +0100
[[32mINFO [0m|#19774] Processing by UserController#logout as HTML
[[0;37mDEBUG[0m|#19774] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|#19774] Authenticating with iChain mode: off
[[32mINFO [0m|#19774] Authenticated request to "/user/logout" from tom
[[32mINFO [0m|#19774] Logging out: tom
[[32mINFO [0m|#19774] Redirected to http://example.org/
[[32mINFO [0m|#19774] Completed 302 Found in 2ms (ActiveRecord: 0.2ms | XML: 0.0ms)
[[0;37mDEBUG[0m|#19774] [1m[35m (0.7ms)[0m rollback transaction
+ echo
+ cleanup
+ echo 'Killing backend processes'
Killing backend processes
+ fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/>
+ egrep 'perl|ruby'
USER PID ACCESS COMMAND
<http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/>:
jenkins ..c.. sh
jenkins ..c.. sh
jenkins ..c.. egrep
+ echo 'Remove log/tmp files to save disc space'
Remove log/tmp files to save disc space
+ rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets
+ exit 1
Build step 'Execute shell' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
--
To unsubscribe, e-mail: obs-tests+unsubscribe(a)opensuse.org
To contact the owner, e-mail: obs-tests+owner(a)opensuse.org
1
1