obs103:/srv/www/obs/frontend/log # tail -f *
==> backend_access.log <==
I, [2008-03-28T22:48:03.816014 #5860]  INFO -- : 20080328T224803 PUT localhost:6362/source/home:admin/_meta 200
I, [2008-03-28T22:48:03.816137 #5860]  INFO -- : <project name='home:admin'><title>admin&apos;s Home Project</title><description/><person role='maintainer' userid='admin'/><person role='maintainer' userid='Admin'/></project>
I, [2008-03-28T22:48:04.856148 #5859]  INFO -- : 20080328T224804 GET localhost:6362/build/home:admin/_result?view=summary 200
I, [2008-03-28T22:48:04.856345 #5859]  INFO -- : <resultlist state="00000000000000000000000000000000" />

I, [2008-03-28T22:48:44.324873 #5860]  INFO -- : 20080328T224844 PUT localhost:6362/source/base_toolchain/_meta 200
I, [2008-03-28T22:48:44.324962 #5860]  INFO -- : <project name='base_toolchain'><title>base_toolchain</title><description>base_toolchain</description><person role='maintainer' userid='admin'/><person role='maintainer' userid='Admin'/></project>
I, [2008-03-28T22:48:44.960531 #5861]  INFO -- : 20080328T224844 GET localhost:6362/build/base_toolchain/_result?view=summary 200
I, [2008-03-28T22:48:44.960703 #5861]  INFO -- : <resultlist state="00000000000000000000000000000000" />


==> development.log <==
    /usr/lib/ruby/vendor_ruby/1.8/fcgi.rb:609:in `each_cgi'
    /usr/lib/ruby/gems/1.8/gems/rails-1.2.6/lib/fcgi_handler.rb:141:in `process_each_request!'
    /usr/lib/ruby/gems/1.8/gems/rails-1.2.6/lib/fcgi_handler.rb:55:in `process!'
    /usr/lib/ruby/gems/1.8/gems/rails-1.2.6/lib/fcgi_handler.rb:25:in `process!'
    /srv/www/obs/frontend/public/dispatch.fcgi:24


[I|# 5861] Rendering status
[I|# 5861] errorcode 'uncaught_exception' - Mysql::Error: Duplicate entry '2-1-3' for key 2: INSERT INTO package_user_role_relationships (`role_id`, `bs_user_id`, `db_package_id`) VALUES(3, 1, 2)
[I|# 5861] Rendering status (500)

==> fastcgi.crash.log <==
I, [2008-03-28T22:45:35.096937 #5646]  INFO -- : [28/Mar/2008:22:45:35 :: 5646] terminated by explicit exit
I, [2008-03-28T22:45:35.320043 #5649]  INFO -- : [28/Mar/2008:22:45:35 :: 5649] asked to terminate immediately
I, [2008-03-28T22:45:35.320218 #5649]  INFO -- : [28/Mar/2008:22:45:35 :: 5649] terminated by explicit exit
I, [2008-03-28T22:45:35.401178 #5648]  INFO -- : [28/Mar/2008:22:45:35 :: 5648] asked to terminate immediately
I, [2008-03-28T22:45:35.401411 #5648]  INFO -- : [28/Mar/2008:22:45:35 :: 5648] terminated by explicit exit
I, [2008-03-28T22:45:46.845551 #5857]  INFO -- : [28/Mar/2008:22:45:46 :: 5857] starting
I, [2008-03-28T22:45:47.234211 #5861]  INFO -- : [28/Mar/2008:22:45:47 :: 5861] starting
I, [2008-03-28T22:45:47.254403 #5858]  INFO -- : [28/Mar/2008:22:45:47 :: 5858] starting
I, [2008-03-28T22:45:47.309395 #5859]  INFO -- : [28/Mar/2008:22:45:47 :: 5859] starting
I, [2008-03-28T22:45:47.332403 #5860]  INFO -- : [28/Mar/2008:22:45:47 :: 5860] starting

==> obs-frontend-access.log <==
::ffff:192.168.10.241 192.168.10.241 - [28/Mar/2008:22:59:31 +0100] "GET /source/base_toolchain/automake/_meta HTTP/1.1" 401 140 "-" "osc/0.99"
::ffff:192.168.10.241 192.168.10.241 - [28/Mar/2008:22:59:31 +0100] "GET /source/base_toolchain/automake/_meta HTTP/1.1" 404 199 "-" "osc/0.99"
::ffff:192.168.10.241 192.168.10.241 - [28/Mar/2008:22:59:31 +0100] "GET /source/base_toolchain/bash/_meta HTTP/1.1" 401 140 "-" "osc/0.99"
::ffff:192.168.10.241 192.168.10.241 - [28/Mar/2008:22:59:31 +0100] "GET /source/base_toolchain/bash/_meta HTTP/1.1" 404 195 "-" "osc/0.99"
::ffff:192.168.10.241 192.168.10.241 - [28/Mar/2008:23:01:00 +0100] "GET /source/base_toolchain/aaa_base/_meta HTTP/1.1" 401 140 "-" "osc/0.99"
::ffff:192.168.10.241 192.168.10.241 - [28/Mar/2008:23:01:00 +0100] "GET /source/base_toolchain/aaa_base/_meta HTTP/1.1" 404 199 "-" "osc/0.99"
::ffff:192.168.10.241 192.168.10.241 - [28/Mar/2008:23:01:09 +0100] "PUT /source/base_toolchain/aaa_base/_meta HTTP/1.1" 401 140 "-" "osc/0.99"
::ffff:192.168.10.241 192.168.10.241 - [28/Mar/2008:23:01:10 +0100] "PUT /source/base_toolchain/aaa_base/_meta HTTP/1.1" 500 7012 "-" "osc/0.99"
::ffff:192.168.10.241 192.168.10.241 - [28/Mar/2008:23:01:49 +0100] "PUT /source/base_toolchain/aaa_base/_meta HTTP/1.1" 401 140 "-" "osc/0.99"
::ffff:192.168.10.241 192.168.10.241 - [28/Mar/2008:23:01:50 +0100] "PUT /source/base_toolchain/aaa_base/_meta HTTP/1.1" 500 7012 "-" "osc/0.99"

==> development.log <==
[D|# 5861] add validation mapping: "source", :index => :directory
[D|# 5861] add validation mapping: "source", :packagelist => :directory
[D|# 5861] add validation mapping: "source", :filelist => :directory
[D|# 5861] add validation mapping: "source", :project_meta => :project
[D|# 5861] add validation mapping: "source", :package_meta => :package
[D|# 5861] add validation mapping: "source", :pattern_meta => :pattern
[I|# 5861]

Processing SourceController#package_meta (for ::ffff:192.168.10.241 at 2008-03-28 23:04:51) [PUT]
[I|# 5861]   Session ID: 38d9d180aab89ad7a9f4dc95ff053022
[I|# 5861]   Parameters: {"project"=>"base_toolchain", "action"=>"package_meta", "package"=>"aaa_base", "controller"=>"source"}
[D|# 5861] checking schema map for controller 'source', action 'package_meta'
[D|# 5861] schema_file: package
[D|# 5861] schema_path: /srv/www/docs/api/frontend/package.xsd
[D|# 5861] trying to validate against schema '/srv/www/docs/api/frontend/package.xsd'
[D|# 5861] validation tmpfile: /srv/www/obs/frontend/tmp/opensuse_frontend_validator.5861.0
[D|# 5861] validation succeeded
[D|# 5861] AUTH:
[D|# 5861] no authentication string was sent
[I|# 5861] Rendering status
[I|# 5861] errorcode 'unknown' - Authentication required
[I|# 5861] Rendering status (401)
[I|# 5861] Filter chain halted as [#<ActionController::Filters::ClassMethods::SymbolFilter:0xb71cfbd0 @filter=:extract_user>] returned_false.
[I|# 5861] Completed in 0.03427 (29 reqs/sec) | Rendering: 0.00381 (11%) | DB: 0.00000 (0%) | 401 Unauthorized [http://192.168.10.241/source/base_toolchain/aaa_base/_meta]
[D|# 5861] add validation mapping: "source", :index => :directory
[D|# 5861] add validation mapping: "source", :packagelist => :directory
[D|# 5861] add validation mapping: "source", :filelist => :directory
[D|# 5861] add validation mapping: "source", :project_meta => :project
[D|# 5861] add validation mapping: "source", :package_meta => :package
[D|# 5861] add validation mapping: "source", :pattern_meta => :pattern
[I|# 5861]

Processing SourceController#package_meta (for ::ffff:192.168.10.241 at 2008-03-28 23:04:51) [PUT]
[I|# 5861]   Session ID: 38d9d180aab89ad7a9f4dc95ff053022
[I|# 5861]   Parameters: {"project"=>"base_toolchain", "action"=>"package_meta", "package"=>"aaa_base", "controller"=>"source"}
[D|# 5861] checking schema map for controller 'source', action 'package_meta'
[D|# 5861] schema_file: package
[D|# 5861] schema_path: /srv/www/docs/api/frontend/package.xsd
[D|# 5861] trying to validate against schema '/srv/www/docs/api/frontend/package.xsd'
[D|# 5861] validation tmpfile: /srv/www/obs/frontend/tmp/opensuse_frontend_validator.5861.1
[D|# 5861] validation succeeded
[D|# 5861] AUTH: BasicYWRtaW46b3BlbnN1c2U=
[D|# 5861]   User Load (0.002331)   SELECT * FROM users WHERE (login = 'admin') LIMIT 1
[D|# 5861]   User Columns (0.003291)   SHOW FIELDS FROM users
[D|# 5861] USER found: Admin
[D|# 5861] User Admin initialised
[D|# 5861] User's source backend <:>, rpm backend: <:>
[D|# 5861] initializing model Package
[D|# 5861] Package.find( "aaa_base", {:project=>"base_toolchain"} )
[D|# 5861] prepared find args: ["aaa_base", {:name=>"aaa_base", :project=>"base_toolchain"}]
[D|# 5861] [BSSQL] find( Package, ["aaa_base", {:name=>"aaa_base", :project=>"base_toolchain"}] )
[D|# 5861] retrieving target_uri for model ':package'
[D|# 5861] retrieving option hash for model ':package'
[D|# 5861] [BSSQL] query_from_options: {:name=>"aaa_base", :project=>"base_toolchain"}
[D|# 5861] [BSSQL] query_from_options: query is: '@name='aaa_base' and @project='base_toolchain''
[D|# 5861]   DbPackage Columns (0.001745)   SHOW FIELDS FROM db_packages
[D|# 5861] [BSSQL] join string: ", db_projects"
[D|# 5861] [BSSQL] find conditions: ["db_packages.name LIKE BINARY ? AND db_packages.db_project_id = db_projects.id AND db_projects.name = ?", "aaa\\_base", "base_toolchain"]
[D|# 5861]   DbPackage Load (0.001154)   SELECT db_packages.* FROM db_packages , db_projects WHERE (db_packages.name LIKE BINARY 'aaa\\_base' AND db_packages.db_project_id = db_projects.id AND db_projects.name = 'base_toolchain')
[D|# 5861] [BSSQL] query @name='aaa_base' and @project='base_toolchain' returned no objects
[D|# 5861] User Admin wants to create a package in base_toolchain
[D|# 5861]   Join Table Columns (0.001451)   SHOW FIELDS FROM roles_users
[D|# 5861]   Role Load (0.000922)   SELECT * FROM roles INNER JOIN roles_users ON roles.id = roles_users.role_id WHERE (roles_users.user_id = 1 )
[D|# 5861]   Role Columns (0.001113)   SHOW FIELDS FROM roles
[D|# 5861]   Join Table Columns (0.001575)   SHOW FIELDS FROM groups_users
[D|# 5861]   Group Load (0.000889)   SELECT * FROM groups INNER JOIN groups_users ON groups.id = groups_users.group_id WHERE (groups_users.user_id = 1 )
[D|# 5861]   Join Table Columns (0.001603)   SHOW FIELDS FROM roles_static_permissions
[D|# 5861]   StaticPermission Load (0.000964)   SELECT * FROM static_permissions INNER JOIN roles_static_permissions ON static_permissions.id = roles_static_permissions.static_permission_id WHERE (roles_static_permissions.role_id = 1 )
[D|# 5861]   StaticPermission Columns (0.001190)   SHOW FIELDS FROM static_permissions
[D|# 5861] add_person: role: "maintainer"
[D|# 5861] Save Package
[D|# 5861] XML <package name='aaa_base'>

  <title>Title of New Package</title>

  <description>LONG DESCRIPTION
GOES
HERE
  </description>

  <person role='maintainer' userid='admin'/>
  <person role='bugowner' userid='admin'/>


<!--
  use one of the examples below to disable building of this package
  on a certain architecture, in a certain repository,
  or a combination thereof:

  <disable arch="x86_64"/>
  <disable repository="SUSE_SLE-10"/>
  <disable repository="SUSE_SLE-10" arch="x86_64"/>

-->

<person role='maintainer' userid='Admin'/></package>
[D|# 5861] [BSSQL] saving object












[D|# 5861]   SQL (0.000682)   BEGIN
[D|# 5861]   DbPackage Load (0.000902)    SELECT pack.*
 FROM db_packages pack
 LEFT OUTER JOIN db_projects pro ON pack.db_project_id = pro.id
 WHERE pro.name = BINARY 'base_toolchain' AND pack.name = BINARY 'aaa_base'

[D|# 5861]   DbProject Load (0.001281)   SELECT * FROM db_projects WHERE (name = BINARY 'base_toolchain') LIMIT 1
[D|# 5861]   DbProject Columns (0.001234)   SHOW FIELDS FROM db_projects
[D|# 5861]   DbPackage Load (0.000772)   SELECT * FROM db_packages WHERE (db_packages.db_project_id = 5)
[D|# 5861]   SQL (0.000778)   INSERT INTO db_packages (`name`, `updated_at`, `activity_index`, `db_project_id`, `title`, `url`, `description`, `created_at`, `update_counter`) VALUES('aaa_base', NULL, 100.0, 5, NULL, NULL, NULL, NULL, 0)
[D|# 5861]   PackageUserRoleRelationship Load (0.000846)   SELECT * FROM package_user_role_relationships WHERE (package_user_role_relationships.db_package_id = 3)
[D|# 5861]   User Load (0.000917)   SELECT * FROM users WHERE (users.`login` = 'admin') LIMIT 1
[D|# 5861]   Role Load (0.000772)   SELECT * FROM roles
[D|# 5861]   PackageUserRoleRelationship Columns (0.001074)   SHOW FIELDS FROM package_user_role_relationships
[D|# 5861]   SQL (0.000617)   INSERT INTO package_user_role_relationships (`role_id`, `bs_user_id`, `db_package_id`) VALUES(3, 1, 3)
[D|# 5861]   User Load (0.000652)   SELECT * FROM users WHERE (users.`login` = 'admin') LIMIT 1
[D|# 5861]   SQL (0.000502)   INSERT INTO package_user_role_relationships (`role_id`, `bs_user_id`, `db_package_id`) VALUES(NULL, 1, 3)
[D|# 5861]   User Load (0.000749)   SELECT * FROM users WHERE (users.`login` = 'Admin') LIMIT 1
[D|# 5861]   SQL (0.000000)   Mysql::Error: Duplicate entry '3-1-3' for key 2: INSERT INTO package_user_role_relationships (`role_id`, `bs_user_id`, `db_package_id`) VALUES(3, 1, 3)
[D|# 5861]   SQL (0.010840)   ROLLBACK
[F|# 5861]

ActiveRecord::StatementInvalid (Mysql::Error: Duplicate entry '3-1-3' for key 2: INSERT INTO package_user_role_relationships (`role_id`, `bs_user_id`, `db_package_id`) VALUES(3, 1, 3)):
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/connection_adapters/abstract_adapter.rb:128:in `log'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/connection_adapters/mysql_adapter.rb:243:in `execute'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/connection_adapters/mysql_adapter.rb:253:in `insert'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/base.rb:1814:in `create_without_callbacks'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/callbacks.rb:254:in `create_without_timestamps'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/timestamp.rb:30:in `create'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/base.rb:1792:in `create_or_update_without_callbacks'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/callbacks.rb:242:in `create_or_update'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/base.rb:1548:in `save_without_validation'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/validations.rb:752:in `save_without_transactions'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/transactions.rb:129:in `save'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/connection_adapters/abstract/database_statements.rb:59:in `transaction'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/transactions.rb:95:in `transaction'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/transactions.rb:121:in `transaction'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/transactions.rb:129:in `save'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/base.rb:451:in `create'
    /srv/www/obs/frontend/app/models/db_package.rb:105:in `store_axml'
    /srv/www/obs/common/lib/activexml/node.rb:308:in `call'
    /srv/www/obs/common/lib/activexml/node.rb:308:in `method_missing'
    /usr/lib/ruby/1.8/rexml/element.rb:934:in `each'
    /usr/lib/ruby/1.8/rexml/xpath.rb:53:in `each'
    /usr/lib/ruby/1.8/rexml/element.rb:934:in `each'
    /srv/www/obs/common/lib/activexml/node.rb:306:in `method_missing'
    /srv/www/obs/frontend/app/models/db_package.rb:85:in `store_axml'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/connection_adapters/abstract/database_statements.rb:59:in `transaction'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/transactions.rb:95:in `transaction'
    /srv/www/obs/frontend/app/models/db_package.rb:67:in `store_axml'
    /srv/www/obs/frontend/app/models/db_package.rb:39:in `store_axml'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/connection_adapters/abstract/database_statements.rb:59:in `transaction'
    /usr/lib/ruby/gems/1.8/gems/activerecord-1.15.6/lib/active_record/transactions.rb:95:in `transaction'
    /srv/www/obs/frontend/app/models/db_package.rb:29:in `store_axml'
    /srv/www/obs/common/lib/activexml/transport.rb:230:in `save'
    /srv/www/obs/common/lib/activexml/base.rb:89:in `save'
    /srv/www/obs/frontend/app/controllers/source_controller.rb:359:in `package_meta'
    /usr/lib/ruby/gems/1.8/gems/actionpack-1.13.6/lib/action_controller/base.rb:1101:in `send'
    /usr/lib/ruby/gems/1.8/gems/actionpack-1.13.6/lib/action_controller/base.rb:1101:in `perform_action_without_filters'
    /usr/lib/ruby/gems/1.8/gems/actionpack-1.13.6/lib/action_controller/filters.rb:696:in `call_filters'
    /usr/lib/ruby/gems/1.8/gems/actionpack-1.13.6/lib/action_controller/filters.rb:688:in `perform_action_without_benchmark'
    /usr/lib/ruby/gems/1.8/gems/actionpack-1.13.6/lib/action_controller/benchmarking.rb:66:in `perform_action_without_rescue'
    /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
    /usr/lib/ruby/gems/1.8/gems/actionpack-1.13.6/lib/action_controller/benchmarking.rb:66:in `perform_action_without_rescue'
    /usr/lib/ruby/gems/1.8/gems/actionpack-1.13.6/lib/action_controller/rescue.rb:83:in `perform_action'
    /usr/lib/ruby/gems/1.8/gems/actionpack-1.13.6/lib/action_controller/base.rb:435:in `send'
    /usr/lib/ruby/gems/1.8/gems/actionpack-1.13.6/lib/action_controller/base.rb:435:in `process_without_filters'
    /usr/lib/ruby/gems/1.8/gems/actionpack-1.13.6/lib/action_controller/filters.rb:684:in `process_without_session_management_support'
    /usr/lib/ruby/gems/1.8/gems/actionpack-1.13.6/lib/action_controller/session_management.rb:114:in `process'
    /usr/lib/ruby/gems/1.8/gems/actionpack-1.13.6/lib/action_controller/base.rb:334:in `process'
    /usr/lib/ruby/gems/1.8/gems/rails-1.2.6/lib/dispatcher.rb:41:in `dispatch'
    /usr/lib/ruby/gems/1.8/gems/rails-1.2.6/lib/fcgi_handler.rb:168:in `process_request'
    /usr/lib/ruby/gems/1.8/gems/rails-1.2.6/lib/fcgi_handler.rb:143:in `process_each_request!'
    /usr/lib/ruby/gems/1.8/gems/rails-1.2.6/lib/fcgi_handler.rb:109:in `with_signal_handler'
    /usr/lib/ruby/gems/1.8/gems/rails-1.2.6/lib/fcgi_handler.rb:142:in `process_each_request!'
    /usr/lib/ruby/vendor_ruby/1.8/fcgi.rb:612:in `each_cgi'
    /usr/lib/ruby/vendor_ruby/1.8/fcgi.rb:609:in `each'
    /usr/lib/ruby/vendor_ruby/1.8/fcgi.rb:609:in `each_cgi'
    /usr/lib/ruby/gems/1.8/gems/rails-1.2.6/lib/fcgi_handler.rb:141:in `process_each_request!'
    /usr/lib/ruby/gems/1.8/gems/rails-1.2.6/lib/fcgi_handler.rb:55:in `process!'
    /usr/lib/ruby/gems/1.8/gems/rails-1.2.6/lib/fcgi_handler.rb:25:in `process!'
    /srv/www/obs/frontend/public/dispatch.fcgi:24


[I|# 5861] Rendering status
[I|# 5861] errorcode 'uncaught_exception' - Mysql::Error: Duplicate entry '3-1-3' for key 2: INSERT INTO package_user_role_relationships (`role_id`, `bs_user_id`, `db_package_id`) VALUES(3, 1, 3)
[I|# 5861] Rendering status (500)