Project

General

Profile

Bug #9871 » 9871_debug.txt

debug level - Dominic Cleal, 03/27/2015 11:35 AM

 
2015-03-27 16:35:10 [I] Started POST "/hosts" for 194.0.88.214 at 2015-03-27 16:35:10 +0200
2015-03-27 16:35:10 [I] Processing by HostsController#create as */*
2015-03-27 16:35:10 [I] Parameters: {"utf8"=>"✓", "authenticity_token"=>"UK/aj9kJiDBgIi3aft9QMtTY+vV5K3uVYje9iUdvZGs=", "host"=>{"name"=>"centos", "location_id"=>"1", "hostgroup_id"=>"16", "compute_resource_id"=>"3", "compute_profile_id"=>"7", "environment_id"=>"1", "puppet_ca_proxy_id"=>"1", "puppet_proxy_id"=>"1", "config_group_ids"=>[""], "puppetclass_ids"=>[""], "managed"=>"true", "progress_report_id"=>"[FILTERED]", "type"=>"Host::Managed", "compute_attributes"=>{"cpus"=>"1", "corespersocket"=>"1", "memory_mb"=>"1024", "cluster"=>"Development", "path"=>"/Datacenters/UA/vm", "guest_id"=>"otherGuest", "hardware_version"=>"Default", "start"=>"1", "volumes_attributes"=>{"new_volumes"=>{"_delete"=>"", "datastore"=>"ls1.sr1.dc1", "name"=>"Hard disk", "size_gb"=>"10", "thin"=>"true", "eager_zero"=>"false"}, "0"=>{"_delete"=>"", "datastore"=>"ua.vl6.iscsi.eql.lun1", "name"=>"disk1", "size_gb"=>"20", "thin"=>"true", "eager_zero"=>"false"}}}, "interfaces_attributes"=>{"0"=>{"_destroy"=>"0", "type"=>"Nic::Managed", "mac"=>"", "identifier"=>"", "name"=>"centos", "domain_id"=>"6", "subnet_id"=>"3", "ip"=>"172.16.160.78", "managed"=>"1", "primary"=>"1", "provision"=>"1", "virtual"=>"0", "tag"=>"", "attached_to"=>"", "compute_attributes"=>{"type"=>"VirtualVmxnet3", "network"=>"vlan60-Internal"}}, "new_interfaces"=>{"_destroy"=>"1", "type"=>"Nic::Managed", "mac"=>"", "identifier"=>"", "name"=>"", "domain_id"=>"", "subnet_id"=>"", "ip"=>"", "managed"=>"1", "primary"=>"0", "provision"=>"0", "virtual"=>"0", "tag"=>"", "attached_to"=>"", "compute_attributes"=>{"type"=>"VirtualE1000", "network"=>"DVUplinks-Adamant"}}}, "architecture_id"=>"1", "operatingsystem_id"=>"11", "provision_method"=>"build", "build"=>"1", "medium_id"=>"1", "ptable_id"=>"7", "disk"=>"", "root_pass"=>"[FILTERED]", "is_owned_by"=>"4-Users", "enabled"=>"1", "model_id"=>"", "comment"=>"", "overwrite"=>"false"}, "capabilities"=>"build image", "provider"=>"Vmware"}
2015-03-27 16:35:10 [D] User Load (0.6ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 4]]
2015-03-27 16:35:10 [D] Setting current user thread-local variable to altvnk
2015-03-27 16:35:10 [D] (0.6ms) SELECT COUNT(*) FROM "taxonomies" WHERE "taxonomies"."type" IN ('Location')
2015-03-27 16:35:10 [D] Setting current location thread-local variable to none
2015-03-27 16:35:10 [D] AuthSource Load (0.6ms) SELECT "auth_sources".* FROM "auth_sources" WHERE "auth_sources"."id" = 1 LIMIT 1
2015-03-27 16:35:10 [D] SQL (0.4ms) SELECT id FROM "taxonomies" WHERE "taxonomies"."type" IN ('Location') LIMIT 1
2015-03-27 16:35:10 [D] User Load (0.5ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 ORDER BY firstname LIMIT 1 [["id", 4]]
2015-03-27 16:35:10 [D] (0.3ms) BEGIN
2015-03-27 16:35:10 [D] Hostgroup Load (0.7ms) SELECT "hostgroups".* FROM "hostgroups" WHERE "hostgroups"."id" = 16 ORDER BY hostgroups.title LIMIT 1
2015-03-27 16:35:10 [D] Subnet Load (1.0ms) SELECT "subnets".* FROM "subnets" WHERE "subnets"."id" = 3 ORDER BY vlanid LIMIT 1
2015-03-27 16:35:10 [D] Operatingsystem Load (0.7ms) SELECT "operatingsystems".* FROM "operatingsystems" WHERE "operatingsystems"."id" = 11 ORDER BY title LIMIT 1
2015-03-27 16:35:10 [D] Domain Load (0.6ms) SELECT "domains".* FROM "domains" WHERE "domains"."id" = 6 ORDER BY domains.name LIMIT 1
2015-03-27 16:35:10 [D] CACHE (0.0ms) SELECT "domains".* FROM "domains" WHERE "domains"."id" = 6 ORDER BY domains.name LIMIT 1
2015-03-27 16:35:10 [D] ComputeResource Load (0.7ms) SELECT "compute_resources".* FROM "compute_resources" WHERE "compute_resources"."id" = 3 ORDER BY compute_resources.name LIMIT 1
2015-03-27 16:35:10 [D] Nic::Interface Exists (1.1ms) SELECT 1 AS one FROM "nics" WHERE "nics"."type" IN ('Nic::Interface', 'Nic::Managed', 'Nic::BMC', 'Nic::Bond', 'Nic::Bootable') AND "nics"."ip" = '172.16.160.78' LIMIT 1
2015-03-27 16:35:10 [D] Nic::Interface Exists (0.6ms) SELECT 1 AS one FROM "nics" WHERE "nics"."type" IN ('Nic::Interface', 'Nic::Managed', 'Nic::BMC', 'Nic::Bond', 'Nic::Bootable') AND ("nics"."name" = 'centos.ua.int' AND "nics"."domain_id" = 6) LIMIT 1
2015-03-27 16:35:10 [D] SmartProxy Load (0.9ms) SELECT "smart_proxies".* FROM "smart_proxies" WHERE "smart_proxies"."id" = 12 ORDER BY smart_proxies.name LIMIT 1
2015-03-27 16:35:10 [D] Scheduling new DHCP reservations for centos.ua.int
2015-03-27 16:35:10 [D] Using host queue
2015-03-27 16:35:10 [D] SmartProxy Load (0.6ms) SELECT "smart_proxies".* FROM "smart_proxies" WHERE "smart_proxies"."id" = 9 ORDER BY smart_proxies.name LIMIT 1
2015-03-27 16:35:10 [D] CACHE (0.0ms) SELECT "smart_proxies".* FROM "smart_proxies" WHERE "smart_proxies"."id" = 9 ORDER BY smart_proxies.name LIMIT 1
2015-03-27 16:35:10 [D] Scheduling new DNS entries
2015-03-27 16:35:10 [D] Using host queue
2015-03-27 16:35:10 [D] Using host queue
2015-03-27 16:35:10 [D] CACHE (0.0ms) SELECT "smart_proxies".* FROM "smart_proxies" WHERE "smart_proxies"."id" = 12 ORDER BY smart_proxies.name LIMIT 1
2015-03-27 16:35:10 [D] ConfigTemplate Load (1.7ms) SELECT "config_templates".* FROM "config_templates" INNER JOIN "config_templates_operatingsystems" ON "config_templates_operatingsystems"."config_template_id" = "config_templates"."id" INNER JOIN "operatingsystems" ON "operatingsystems"."id" = "config_templates_operatingsystems"."operatingsystem_id" INNER JOIN "template_kinds" ON "template_kinds"."id" = "config_templates"."template_kind_id" INNER JOIN "template_combinations" ON "template_combinations"."config_template_id" = "config_templates"."id" WHERE "operatingsystems"."id" = 11 AND "template_kinds"."name" = 'PXELinux' AND "template_combinations"."hostgroup_id" = 16 AND "template_combinations"."environment_id" = 1 ORDER BY config_templates.name LIMIT 1
2015-03-27 16:35:10 [D] ConfigTemplate Load (1.7ms) SELECT "config_templates".* FROM "config_templates" INNER JOIN "config_templates_operatingsystems" ON "config_templates_operatingsystems"."config_template_id" = "config_templates"."id" INNER JOIN "operatingsystems" ON "operatingsystems"."id" = "config_templates_operatingsystems"."operatingsystem_id" INNER JOIN "template_kinds" ON "template_kinds"."id" = "config_templates"."template_kind_id" INNER JOIN "template_combinations" ON "template_combinations"."config_template_id" = "config_templates"."id" WHERE "operatingsystems"."id" = 11 AND "template_kinds"."name" = 'PXELinux' AND "template_combinations"."hostgroup_id" = 16 AND "template_combinations"."environment_id" IS NULL ORDER BY config_templates.name LIMIT 1
2015-03-27 16:35:10 [D] ConfigTemplate Load (1.5ms) SELECT "config_templates".* FROM "config_templates" INNER JOIN "config_templates_operatingsystems" ON "config_templates_operatingsystems"."config_template_id" = "config_templates"."id" INNER JOIN "operatingsystems" ON "operatingsystems"."id" = "config_templates_operatingsystems"."operatingsystem_id" INNER JOIN "template_kinds" ON "template_kinds"."id" = "config_templates"."template_kind_id" INNER JOIN "template_combinations" ON "template_combinations"."config_template_id" = "config_templates"."id" WHERE "operatingsystems"."id" = 11 AND "template_kinds"."name" = 'PXELinux' AND "template_combinations"."hostgroup_id" IS NULL AND "template_combinations"."environment_id" = 1 ORDER BY config_templates.name LIMIT 1
2015-03-27 16:35:10 [D] ConfigTemplate Load (1.8ms) SELECT "config_templates".* FROM "config_templates" INNER JOIN "config_templates_operatingsystems" ON "config_templates_operatingsystems"."config_template_id" = "config_templates"."id" INNER JOIN "operatingsystems" ON "operatingsystems"."id" = "config_templates_operatingsystems"."operatingsystem_id" INNER JOIN "template_kinds" ON "template_kinds"."id" = "config_templates"."template_kind_id" INNER JOIN "os_default_templates" ON "os_default_templates"."config_template_id" = "config_templates"."id" WHERE "operatingsystems"."id" = 11 AND "template_kinds"."name" = 'PXELinux' AND "os_default_templates"."operatingsystem_id" = 11 ORDER BY config_templates.name LIMIT 1
2015-03-27 16:35:10 [D] Using host queue
2015-03-27 16:35:10 [D] Using host queue
2015-03-27 16:35:10 [D] Host::Base Exists (0.7ms) SELECT 1 AS one FROM "hosts" WHERE "hosts"."name" = 'centos.ua.int' LIMIT 1
2015-03-27 16:35:10 [D] SmartProxy Load (0.5ms) SELECT "smart_proxies".* FROM "smart_proxies" WHERE "smart_proxies"."id" = 1 ORDER BY smart_proxies.name LIMIT 1
2015-03-27 16:35:10 [D] Queueing Realm
2015-03-27 16:35:10 [D] Ptable Load (0.6ms) SELECT "ptables".* FROM "ptables" WHERE "ptables"."id" = 7 ORDER BY ptables.name LIMIT 1
2015-03-27 16:35:10 [D] Ptable Exists (0.6ms) SELECT 1 AS one FROM "ptables" INNER JOIN "operatingsystems_ptables" ON "ptables"."id" = "operatingsystems_ptables"."ptable_id" WHERE "operatingsystems_ptables"."operatingsystem_id" = 11 AND "ptables"."id" = 7 ORDER BY ptables.name LIMIT 1
2015-03-27 16:35:10 [D] Medium Load (0.7ms) SELECT "media".* FROM "media" WHERE "media"."id" = 1 ORDER BY media.name LIMIT 1
2015-03-27 16:35:10 [D] Medium Exists (0.6ms) SELECT 1 AS one FROM "media" INNER JOIN "media_operatingsystems" ON "media"."id" = "media_operatingsystems"."medium_id" WHERE "media_operatingsystems"."operatingsystem_id" = 11 AND "media"."id" = 1 ORDER BY media.name LIMIT 1
2015-03-27 16:35:10 [D] Architecture Load (0.5ms) SELECT "architectures".* FROM "architectures" WHERE "architectures"."id" = 1 LIMIT 1
2015-03-27 16:35:10 [D] Architecture Exists (0.7ms) SELECT 1 AS one FROM "architectures" INNER JOIN "architectures_operatingsystems" ON "architectures"."id" = "architectures_operatingsystems"."architecture_id" WHERE "architectures_operatingsystems"."operatingsystem_id" = 11 AND "architectures"."id" = 1 LIMIT 1
2015-03-27 16:35:10 [D] Environment Load (0.7ms) SELECT "environments".* FROM "environments" WHERE "environments"."id" = 1 ORDER BY environments.name LIMIT 1
2015-03-27 16:35:10 [D] Puppetclass Load (1.3ms) SELECT DISTINCT puppetclasses.id,puppetclasses.name FROM "puppetclasses" INNER JOIN "host_classes" ON "puppetclasses"."id" = "host_classes"."puppetclass_id" WHERE "host_classes"."host_id" IS NULL ORDER BY puppetclasses.name
2015-03-27 16:35:10 [I] Adding Compute instance for centos.ua.int
2015-03-27 16:35:10 [D] ComputeResource Exists (1.0ms) SELECT 1 AS one FROM "compute_resources" WHERE ("compute_resources"."name" = 'vmware-ua' AND "compute_resources"."id" != 3) LIMIT 1
2015-03-27 16:35:10 [I] Successfully decrypted field for Foreman::Model::Vmware vmware-ua
2015-03-27 16:35:10 [I] Successfully decrypted field for Foreman::Model::Vmware vmware-ua
2015-03-27 16:35:11 [I] Started GET "/tasks/d8d07891-eb09-4c7d-84ba-d91bc92acce4" for 194.0.88.214 at 2015-03-27 16:35:11 +0200
2015-03-27 16:35:11 [I] Processing by TasksController#show as */*
2015-03-27 16:35:11 [I] Parameters: {"id"=>"d8d07891-eb09-4c7d-84ba-d91bc92acce4"}
2015-03-27 16:35:11 [D] User Load (0.6ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 4]]

2015-03-27 16:35:11 [D] Setting current user thread-local variable to altvnk
2015-03-27 16:35:11 [D] AuthSource Load (0.7ms) SELECT "auth_sources".* FROM "auth_sources" WHERE "auth_sources"."id" = 1 LIMIT 1
2015-03-27 16:35:11 [D] SQL (0.5ms) SELECT id FROM "taxonomies" WHERE "taxonomies"."type" IN ('Location') LIMIT 1
2015-03-27 16:35:11 [I] Rendered tasks/_list.html.erb (1.5ms)
2015-03-27 16:35:11 [I] Completed 200 OK in 13.9ms (Views: 2.8ms | ActiveRecord: 1.8ms)

2015-03-27 16:35:13 [I] Started GET "/tasks/d8d07891-eb09-4c7d-84ba-d91bc92acce4" for 194.0.88.214 at 2015-03-27 16:35:13 +0200
2015-03-27 16:35:13 [I] Processing by TasksController#show as */*
2015-03-27 16:35:13 [I] Parameters: {"id"=>"d8d07891-eb09-4c7d-84ba-d91bc92acce4"}
2015-03-27 16:35:13 [D] User Load (0.5ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 4]]
2015-03-27 16:35:13 [D] Setting current user thread-local variable to altvnk
2015-03-27 16:35:13 [D] AuthSource Load (0.7ms) SELECT "auth_sources".* FROM "auth_sources" WHERE "auth_sources"."id" = 1 LIMIT 1
2015-03-27 16:35:13 [D] SQL (0.5ms) SELECT id FROM "taxonomies" WHERE "taxonomies"."type" IN ('Location') LIMIT 1
2015-03-27 16:35:13 [I] Rendered tasks/_list.html.erb (1.3ms)
2015-03-27 16:35:13 [I] Completed 200 OK in 12.7ms (Views: 2.2ms | ActiveRecord: 1.6ms)
2015-03-27 16:35:14 [D] Host::Managed Load (1.1ms) SELECT "hosts".* FROM "hosts" WHERE "hosts"."uuid" = '502d6132-75a3-d512-62e1-4df7bbc9248f' LIMIT 1
2015-03-27 16:35:14 [I] Add DNS A record for centos.ua.int/172.16.160.78
2015-03-27 16:35:14 [I] Add DNS PTR record for 172.16.160.78/centos.ua.int
2015-03-27 16:35:14 [W] Create DHCP Settings for centos.ua.int task failed with the following error: Invalid MAC /usr/share/foreman/lib/net/validations.rb:40:in `validate_mac'
/usr/share/foreman/lib/net/dhcp/record.rb:7:in `initialize'
/usr/share/foreman/app/models/nic/managed.rb:40:in `new'
/usr/share/foreman/app/models/nic/managed.rb:40:in `dhcp_record'
/usr/share/foreman/app/models/concerns/orchestration/dhcp.rb:24:in `set_dhcp'
/usr/share/foreman/app/models/concerns/orchestration.rb:141:in `execute'
/usr/share/foreman/app/models/concerns/orchestration.rb:86:in `block in process'
/usr/share/foreman/app/models/concerns/orchestration.rb:78:in `each'
/usr/share/foreman/app/models/concerns/orchestration.rb:78:in `process'
/usr/share/foreman/app/models/concerns/orchestration.rb:19:in `on_save'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:649:in `_run__4421816235511062842__save__1018504801781028737__callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:405:in `__run_callback'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:385:in `_run_save_callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:81:in `run_callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/callbacks.rb:264:in `create_or_update'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/persistence.rb:84:in `save'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/validations.rb:50:in `save'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/attribute_methods/dirty.rb:22:in `save'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/transactions.rb:259:in `block (2 levels) in save'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/transactions.rb:313:in `block in with_transaction_returning_status'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/connection_adapters/abstract/database_statements.rb:192:in `transaction'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/transactions.rb:208:in `transaction'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/transactions.rb:311:in `with_transaction_returning_status'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/transactions.rb:259:in `block in save'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/transactions.rb:270:in `rollback_active_record_state!'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/transactions.rb:258:in `save'
/usr/share/foreman/app/models/concerns/foreman/sti.rb:29:in `save_with_type'
/usr/share/foreman/app/controllers/hosts_controller.rb:83:in `create'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal/implicit_render.rb:4:in `send_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/abstract_controller/base.rb:167:in `process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal/rendering.rb:10:in `process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/abstract_controller/callbacks.rb:18:in `block in process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:627:in `block (4 levels) in _run__2647368983540186650__process_action__2677311356650940371__callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7765'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:326:in `around'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:310:in `_callback_around_2205'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7765'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:626:in `block (3 levels) in _run__2647368983540186650__process_action__2677311356650940371__callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7764'
/usr/share/foreman/app/controllers/concerns/application_shared.rb:13:in `set_timezone'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7764'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:625:in `block (2 levels) in _run__2647368983540186650__process_action__2677311356650940371__callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7763'
/usr/share/foreman/app/models/concerns/foreman/thread_session.rb:32:in `clear_thread'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7763'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:448:in `block in _run__2647368983540186650__process_action__2677311356650940371__callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7762'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:326:in `around'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:310:in `_callback_around_13'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7762'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:414:in `_run__2647368983540186650__process_action__2677311356650940371__callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:405:in `__run_callback'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:81:in `run_callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/abstract_controller/callbacks.rb:17:in `process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal/rescue.rb:29:in `process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/notifications.rb:123:in `block in instrument'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/notifications/instrumenter.rb:20:in `instrument'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/notifications.rb:123:in `instrument'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal/instrumentation.rb:29:in `process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/railties/controller_runtime.rb:18:in `process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/abstract_controller/base.rb:121:in `process'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/abstract_controller/rendering.rb:45:in `process'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal.rb:203:in `dispatch'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal.rb:246:in `block in action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/routing/route_set.rb:73:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/routing/route_set.rb:36:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'
/usr/share/foreman/vendor/ruby/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/routing/route_set.rb:608:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/apipie-rails-0.2.6/lib/apipie/extractor/recorder.rb:97:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/apipie-rails-0.2.6/lib/apipie/middleware/checksum_in_headers.rb:27:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/etag.rb:23:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/conditionalget.rb:35:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/head.rb:14:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/params_parser.rb:21:in `call'
/usr/share/foreman/lib/middleware/catch_json_parse_errors.rb:9:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/flash.rb:242:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/session/abstract/id.rb:210:in `context'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/session/abstract/id.rb:205:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/cookies.rb:341:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/query_cache.rb:64:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:405:in `_run__3109927047773415632__call__1018504801781028737__callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:405:in `__run_callback'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:81:in `run_callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/callbacks.rb:27:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/railties-3.2.21/lib/rails/rack/logger.rb:32:in `call_app'
/usr/share/foreman/vendor/ruby/1.9.1/gems/railties-3.2.21/lib/rails/rack/logger.rb:16:in `block in call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/tagged_logging.rb:22:in `tagged'
/usr/share/foreman/vendor/ruby/1.9.1/gems/railties-3.2.21/lib/rails/rack/logger.rb:16:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/request_id.rb:22:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/methodoverride.rb:21:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/runtime.rb:17:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/cache/strategy/local_cache.rb:72:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/lock.rb:15:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/static.rb:83:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:136:in `forward'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:143:in `pass'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:155:in `invalidate'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:71:in `call!'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:51:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/railties-3.2.21/lib/rails/engine.rb:484:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/railties-3.2.21/lib/rails/application.rb:231:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/railties-3.2.21/lib/rails/railtie/configurable.rb:30:in `method_missing'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/builder.rb:134:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/urlmap.rb:64:in `block in call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/urlmap.rb:49:in `each'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/urlmap.rb:49:in `call'
/usr/lib/ruby/vendor_ruby/phusion_passenger/rack/request_handler.rb:96:in `process_request'
/usr/lib/ruby/vendor_ruby/phusion_passenger/abstract_request_handler.rb:516:in `accept_and_process_next_request'
/usr/lib/ruby/vendor_ruby/phusion_passenger/abstract_request_handler.rb:274:in `main_loop'
/usr/lib/ruby/vendor_ruby/phusion_passenger/rack/application_spawner.rb:206:in `start_request_handler'
/usr/lib/ruby/vendor_ruby/phusion_passenger/rack/application_spawner.rb:171:in `block in handle_spawn_application'
/usr/lib/ruby/vendor_ruby/phusion_passenger/utils.rb:479:in `safe_fork'
/usr/lib/ruby/vendor_ruby/phusion_passenger/rack/application_spawner.rb:166:in `handle_spawn_application'
/usr/lib/ruby/vendor_ruby/phusion_passenger/abstract_server.rb:357:in `server_main_loop'
/usr/lib/ruby/vendor_ruby/phusion_passenger/abstract_server.rb:206:in `start_synchronously'
/usr/lib/ruby/vendor_ruby/phusion_passenger/abstract_server.rb:180:in `start'
/usr/lib/ruby/vendor_ruby/phusion_passenger/rack/application_spawner.rb:129:in `start'
/usr/lib/ruby/vendor_ruby/phusion_passenger/spawn_manager.rb:253:in `block (2 levels) in spawn_rack_application'
/usr/lib/ruby/vendor_ruby/phusion_passenger/abstract_server_collection.rb:132:in `lookup_or_add'
/usr/lib/ruby/vendor_ruby/phusion_passenger/spawn_manager.rb:246:in `block in spawn_rack_application'
/usr/lib/ruby/vendor_ruby/phusion_passenger/abstract_server_collection.rb:82:in `block in synchronize'
<internal:prelude>:10:in `synchronize'
/usr/lib/ruby/vendor_ruby/phusion_passenger/abstract_server_collection.rb:79:in `synchronize'
/usr/lib/ruby/vendor_ruby/phusion_passenger/spawn_manager.rb:244:in `spawn_rack_application'
/usr/lib/ruby/vendor_ruby/phusion_passenger/spawn_manager.rb:137:in `spawn_application'
/usr/lib/ruby/vendor_ruby/phusion_passenger/spawn_manager.rb:275:in `handle_spawn_application'
/usr/lib/ruby/vendor_ruby/phusion_passenger/abstract_server.rb:357:in `server_main_loop'
/usr/lib/ruby/vendor_ruby/phusion_passenger/abstract_server.rb:206:in `start_synchronously'
/usr/share/phusion-passenger/helper-scripts/passenger-spawn-server:99:in `<main>'
2015-03-27 16:35:14 [W] Rolling back due to a problem: [Create DHCP Settings for centos.ua.int 10 failed [#<Nic::Managed id: nil, mac: nil, ip: "172.16.160.78", type: "Nic::Managed", name: "centos.ua.int", host_id: nil, subnet_id: 3, domain_id: 6, attrs: {}, created_at: nil, updated_at: nil, provider: nil, username: nil, password: nil, virtual: false, link: true, identifier: "", tag: "", attached_to: "", managed: true, mode: "balance-rr", attached_devices: "", bond_options: "", primary: true, provision: true, compute_attributes: {"type"=>"VirtualVmxnet3", "network"=>"vlan60-Internal"}>, :set_dhcp]]
2015-03-27 16:35:14 [I] Delete the DNS PTR record for 172.16.160.78/centos.ua.int
2015-03-27 16:35:14 [I] Delete the DNS A record for centos.ua.int/172.16.160.78
2015-03-27 16:35:15 [I] Started GET "/tasks/d8d07891-eb09-4c7d-84ba-d91bc92acce4" for 194.0.88.214 at 2015-03-27 16:35:15 +0200
2015-03-27 16:35:15 [I] Processing by TasksController#show as */*
2015-03-27 16:35:15 [I] Parameters: {"id"=>"d8d07891-eb09-4c7d-84ba-d91bc92acce4"}
2015-03-27 16:35:15 [D] User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 4]]
2015-03-27 16:35:15 [D] Setting current user thread-local variable to altvnk
2015-03-27 16:35:15 [D] AuthSource Load (0.6ms) SELECT "auth_sources".* FROM "auth_sources" WHERE "auth_sources"."id" = 1 LIMIT 1
2015-03-27 16:35:15 [D] SQL (0.4ms) SELECT id FROM "taxonomies" WHERE "taxonomies"."type" IN ('Location') LIMIT 1
2015-03-27 16:35:15 [I] Rendered tasks/_list.html.erb (1.2ms)
2015-03-27 16:35:15 [I] Completed 200 OK in 12.9ms (Views: 2.3ms | ActiveRecord: 1.4ms)
2015-03-27 16:35:15 [I] Removing Compute instance for centos.ua.int
2015-03-27 16:35:15 [D] (0.5ms) ROLLBACK
2015-03-27 16:35:15 [D] Organization Load (0.9ms) SELECT "taxonomies".* FROM "taxonomies" WHERE "taxonomies"."type" IN ('Organization') AND "taxonomies"."id" IS NULL ORDER BY title LIMIT 1
2015-03-27 16:35:15 [D] Location Load (0.6ms) SELECT "taxonomies".* FROM "taxonomies" WHERE "taxonomies"."type" IN ('Location') AND "taxonomies"."id" = 1 ORDER BY title LIMIT 1
2015-03-27 16:35:15 [I] Failed to save: Create DHCP Settings for centos.ua.int task failed with the following error: Invalid MAC
2015-03-27 16:35:15 [I] Rendered hosts/_progress.html.erb (1.6ms)
2015-03-27 16:35:15 [D] Setting current organization thread-local variable to none
2015-03-27 16:35:15 [D] Setting current location thread-local variable to UA
2015-03-27 16:35:15 [D] Taxonomy Load (0.9ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:15 [D] SQL (0.7ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'SmartProxy' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:15 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:15 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'SmartProxy' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:15 [D] (1.2ms) SELECT COUNT(*) FROM "smart_proxies" INNER JOIN "features_smart_proxies" ON "features_smart_proxies"."smart_proxy_id" = "smart_proxies"."id" INNER JOIN "features" ON "features"."id" = "features_smart_proxies"."feature_id" WHERE "smart_proxies"."id" IN (1, 9, 12) AND "features"."name" IN ('Puppet')
2015-03-27 16:35:15 [D] Location Load (0.6ms) SELECT "taxonomies".* FROM "taxonomies" WHERE "taxonomies"."type" IN ('Location') ORDER BY title
2015-03-27 16:35:15 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:15 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:15 [D] Hostgroup Load (0.9ms) SELECT "hostgroups".* FROM "hostgroups" WHERE "hostgroups"."id" IN (1, 4, 7, 8, 9, 10, 13, 14, 15, 16, 17, 19, 20, 21, 22, 23, 24) AND (1=1)
2015-03-27 16:35:15 [D] SQL (0.6ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'ComputeResource' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:15 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'ComputeResource' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:15 [D] ComputeResource Load (0.6ms) SELECT "compute_resources".* FROM "compute_resources" WHERE "compute_resources"."id" IN (1, 3) AND (1=1) ORDER BY name
2015-03-27 16:35:15 [D] (1.1ms) SELECT COUNT(DISTINCT "compute_profiles"."id") FROM "compute_profiles" LEFT OUTER JOIN "compute_attributes" ON "compute_attributes"."compute_profile_id" = "compute_profiles"."id" WHERE (compute_attributes.id > 0)
2015-03-27 16:35:15 [D] SQL (1.1ms) SELECT "compute_profiles"."id" AS t0_r0, "compute_profiles"."name" AS t0_r1, "compute_profiles"."created_at" AS t0_r2, "compute_profiles"."updated_at" AS t0_r3, "compute_attributes"."id" AS t1_r0, "compute_attributes"."compute_profile_id" AS t1_r1, "compute_attributes"."compute_resource_id" AS t1_r2, "compute_attributes"."name" AS t1_r3, "compute_attributes"."vm_attrs" AS t1_r4, "compute_attributes"."created_at" AS t1_r5, "compute_attributes"."updated_at" AS t1_r6 FROM "compute_profiles" LEFT OUTER JOIN "compute_attributes" ON "compute_attributes"."compute_profile_id" = "compute_profiles"."id" WHERE (compute_attributes.id > 0) ORDER BY compute_profiles.name
2015-03-27 16:35:15 [D] SQL (0.6ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Environment' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:15 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Environment' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:15 [D] Environment Load (0.5ms) SELECT "environments".* FROM "environments" WHERE "environments"."id" IN (1) AND (1=1) ORDER BY name
2015-03-27 16:35:15 [D] SQL (0.6ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'SmartProxy' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:15 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'SmartProxy' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:15 [D] (0.8ms) SELECT COUNT(*) FROM "smart_proxies" INNER JOIN "features_smart_proxies" ON "features_smart_proxies"."smart_proxy_id" = "smart_proxies"."id" INNER JOIN "features" ON "features"."id" = "features_smart_proxies"."feature_id" WHERE "features"."name" IN ('Puppet CA') AND "smart_proxies"."id" IN (1, 9, 12) AND (1=1)
2015-03-27 16:35:15 [D] SmartProxy Load (0.8ms) SELECT "smart_proxies".* FROM "smart_proxies" INNER JOIN "features_smart_proxies" ON "features_smart_proxies"."smart_proxy_id" = "smart_proxies"."id" INNER JOIN "features" ON "features"."id" = "features_smart_proxies"."feature_id" WHERE "features"."name" IN ('Puppet CA') AND "smart_proxies"."id" IN (1, 9, 12) AND (1=1)
2015-03-27 16:35:15 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'SmartProxy' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:15 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'SmartProxy' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:15 [D] (0.8ms) SELECT COUNT(*) FROM "smart_proxies" INNER JOIN "features_smart_proxies" ON "features_smart_proxies"."smart_proxy_id" = "smart_proxies"."id" INNER JOIN "features" ON "features"."id" = "features_smart_proxies"."feature_id" WHERE "features"."name" IN ('Puppet') AND "smart_proxies"."id" IN (1, 9, 12) AND (1=1)
2015-03-27 16:35:15 [D] SmartProxy Load (0.8ms) SELECT "smart_proxies".* FROM "smart_proxies" INNER JOIN "features_smart_proxies" ON "features_smart_proxies"."smart_proxy_id" = "smart_proxies"."id" INNER JOIN "features" ON "features"."id" = "features_smart_proxies"."feature_id" WHERE "features"."name" IN ('Puppet') AND "smart_proxies"."id" IN (1, 9, 12) AND (1=1)
2015-03-27 16:35:15 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:15 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Realm' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:15 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:15 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Realm' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:15 [D] (0.8ms) SELECT COUNT(*) FROM "realms" WHERE 1=0
2015-03-27 16:35:15 [D] (0.8ms) SELECT COUNT(*) FROM "config_groups"
2015-03-27 16:35:15 [D] ConfigGroup Load (0.9ms) SELECT "config_groups".* FROM "config_groups" INNER JOIN "host_config_groups" ON "config_groups"."id" = "host_config_groups"."config_group_id" WHERE "host_config_groups"."host_id" = 16 AND "host_config_groups"."host_type" = 'Hostgroup' ORDER BY config_groups.name
2015-03-27 16:35:15 [D] ConfigGroup Load (0.5ms) SELECT "config_groups".* FROM "config_groups" ORDER BY config_groups.name
2015-03-27 16:35:15 [D] SQL (1.1ms) SELECT "puppetclasses".id FROM "puppetclasses" INNER JOIN "config_group_classes" ON "puppetclasses"."id" = "config_group_classes"."puppetclass_id" WHERE "config_group_classes"."config_group_id" = 5 ORDER BY puppetclasses.name
2015-03-27 16:35:15 [D] SQL (1.0ms) SELECT "puppetclasses".id FROM "puppetclasses" INNER JOIN "config_group_classes" ON "puppetclasses"."id" = "config_group_classes"."puppetclass_id" WHERE "config_group_classes"."config_group_id" = 5 ORDER BY puppetclasses.name
2015-03-27 16:35:15 [D] Puppetclass Load (1.1ms) SELECT "puppetclasses".* FROM "puppetclasses" INNER JOIN "config_group_classes" ON "puppetclasses"."id" = "config_group_classes"."puppetclass_id" WHERE "config_group_classes"."config_group_id" = 5 ORDER BY puppetclasses.name
2015-03-27 16:35:15 [D] Puppetclass Load (15.1ms) SELECT DISTINCT "puppetclasses".* FROM "puppetclasses" INNER JOIN "environment_classes" ON "puppetclasses"."id" = "environment_classes"."puppetclass_id" WHERE "environment_classes"."environment_id" = 1 ORDER BY puppetclasses.name
2015-03-27 16:35:16 [I] Rendered config_groups/_config_group.html.erb (107.1ms)
2015-03-27 16:35:16 [D] SQL (0.9ms) SELECT "puppetclasses".id FROM "puppetclasses" INNER JOIN "config_group_classes" ON "puppetclasses"."id" = "config_group_classes"."puppetclass_id" WHERE "config_group_classes"."config_group_id" = 4 ORDER BY puppetclasses.name
2015-03-27 16:35:16 [D] SQL (0.8ms) SELECT "puppetclasses".id FROM "puppetclasses" INNER JOIN "config_group_classes" ON "puppetclasses"."id" = "config_group_classes"."puppetclass_id" WHERE "config_group_classes"."config_group_id" = 4 ORDER BY puppetclasses.name
2015-03-27 16:35:16 [D] Puppetclass Load (1.8ms) SELECT "puppetclasses".* FROM "puppetclasses" INNER JOIN "config_group_classes" ON "puppetclasses"."id" = "config_group_classes"."puppetclass_id" WHERE "config_group_classes"."config_group_id" = 4 ORDER BY puppetclasses.name
2015-03-27 16:35:16 [I] Rendered config_groups/_config_group.html.erb (214.2ms)
2015-03-27 16:35:16 [I] Rendered config_groups/_config_groups_selection.html.erb (362.4ms)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:16 [D] SQL (0.7ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:16 [D] Hostgroup Load (0.9ms) SELECT "hostgroups".* FROM "hostgroups" WHERE "hostgroups"."id" IN (1, 4, 7, 8, 9, 10, 13, 14, 15, 16, 17, 19, 20, 21, 22, 23, 24) AND "hostgroups"."id" IN (16) ORDER BY (case when hostgroups.ancestry is null then 0 else 1 end), hostgroups.ancestry
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "config_groups".id FROM "config_groups" INNER JOIN "host_config_groups" ON "config_groups"."id" = "host_config_groups"."config_group_id" WHERE "host_config_groups"."host_id" = 16 AND "host_config_groups"."host_type" = 'Hostgroup' ORDER BY config_groups.name
2015-03-27 16:35:16 [D] SQL (0.4ms) SELECT "config_group_classes"."puppetclass_id" FROM "config_group_classes" WHERE 1=0
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:16 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT "hostgroups".* FROM "hostgroups" WHERE "hostgroups"."id" IN (1, 4, 7, 8, 9, 10, 13, 14, 15, 16, 17, 19, 20, 21, 22, 23, 24) AND "hostgroups"."id" IN (16) ORDER BY (case when hostgroups.ancestry is null then 0 else 1 end), hostgroups.ancestry
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "config_groups".id FROM "config_groups" INNER JOIN "host_config_groups" ON "config_groups"."id" = "host_config_groups"."config_group_id" WHERE "host_config_groups"."host_id" = 16 AND "host_config_groups"."host_type" = 'Hostgroup' ORDER BY config_groups.name
2015-03-27 16:35:16 [D] SQL (0.4ms) SELECT "config_group_classes"."puppetclass_id" FROM "config_group_classes" WHERE 1=0
2015-03-27 16:35:16 [D] SQL (0.8ms) SELECT "hostgroup_classes"."puppetclass_id" FROM "hostgroup_classes" WHERE "hostgroup_classes"."hostgroup_id" IN (16)
2015-03-27 16:35:16 [D] Puppetclass Load (0.7ms) SELECT DISTINCT "puppetclasses".* FROM "puppetclasses" INNER JOIN "environment_classes" ON "puppetclasses"."id" = "environment_classes"."puppetclass_id" WHERE "environment_classes"."environment_id" = 1 AND 1=0 ORDER BY puppetclasses.name
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT DISTINCT "puppetclasses".* FROM "puppetclasses" INNER JOIN "environment_classes" ON "puppetclasses"."id" = "environment_classes"."puppetclass_id" WHERE "environment_classes"."environment_id" = 1 AND 1=0 ORDER BY puppetclasses.name
2015-03-27 16:35:16 [I] Rendered puppetclasses/_selectedClasses.html.erb (0.0ms)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:16 [D] SQL (0.7ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:16 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT "hostgroups".* FROM "hostgroups" WHERE "hostgroups"."id" IN (1, 4, 7, 8, 9, 10, 13, 14, 15, 16, 17, 19, 20, 21, 22, 23, 24) AND "hostgroups"."id" IN (16) ORDER BY (case when hostgroups.ancestry is null then 0 else 1 end), hostgroups.ancestry
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "config_groups".id FROM "config_groups" INNER JOIN "host_config_groups" ON "config_groups"."id" = "host_config_groups"."config_group_id" WHERE "host_config_groups"."host_id" = 16 AND "host_config_groups"."host_type" = 'Hostgroup' ORDER BY config_groups.name
2015-03-27 16:35:16 [D] SQL (0.4ms) SELECT "config_group_classes"."puppetclass_id" FROM "config_group_classes" WHERE 1=0
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT "hostgroups".* FROM "hostgroups" WHERE "hostgroups"."id" IN (1, 4, 7, 8, 9, 10, 13, 14, 15, 16, 17, 19, 20, 21, 22, 23, 24) AND "hostgroups"."id" IN (16) ORDER BY (case when hostgroups.ancestry is null then 0 else 1 end), hostgroups.ancestry
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "config_groups".id FROM "config_groups" INNER JOIN "host_config_groups" ON "config_groups"."id" = "host_config_groups"."config_group_id" WHERE "host_config_groups"."host_id" = 16 AND "host_config_groups"."host_type" = 'Hostgroup' ORDER BY config_groups.name
2015-03-27 16:35:16 [D] SQL (0.4ms) SELECT "config_group_classes"."puppetclass_id" FROM "config_group_classes" WHERE 1=0
2015-03-27 16:35:16 [D] SQL (0.4ms) SELECT "hostgroup_classes"."puppetclass_id" FROM "hostgroup_classes" WHERE "hostgroup_classes"."hostgroup_id" IN (16)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT DISTINCT "puppetclasses".* FROM "puppetclasses" INNER JOIN "environment_classes" ON "puppetclasses"."id" = "environment_classes"."puppetclass_id" WHERE "environment_classes"."environment_id" = 1 AND 1=0 ORDER BY puppetclasses.name
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT DISTINCT "puppetclasses".* FROM "puppetclasses" INNER JOIN "environment_classes" ON "puppetclasses"."id" = "environment_classes"."puppetclass_id" WHERE "environment_classes"."environment_id" = 1 AND 1=0 ORDER BY puppetclasses.name
2015-03-27 16:35:16 [I] Rendered puppetclasses/_classes_in_groups.html.erb (0.0ms)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:16 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT "hostgroups".* FROM "hostgroups" WHERE "hostgroups"."id" IN (1, 4, 7, 8, 9, 10, 13, 14, 15, 16, 17, 19, 20, 21, 22, 23, 24) AND "hostgroups"."id" IN (16) ORDER BY (case when hostgroups.ancestry is null then 0 else 1 end), hostgroups.ancestry
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "config_groups".id FROM "config_groups" INNER JOIN "host_config_groups" ON "config_groups"."id" = "host_config_groups"."config_group_id" WHERE "host_config_groups"."host_id" = 16 AND "host_config_groups"."host_type" = 'Hostgroup' ORDER BY config_groups.name
2015-03-27 16:35:16 [D] SQL (0.4ms) SELECT "config_group_classes"."puppetclass_id" FROM "config_group_classes" WHERE 1=0
2015-03-27 16:35:16 [D] SQL (0.4ms) SELECT "hostgroup_classes"."puppetclass_id" FROM "hostgroup_classes" WHERE "hostgroup_classes"."hostgroup_id" IN (16)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT DISTINCT "puppetclasses".* FROM "puppetclasses" INNER JOIN "environment_classes" ON "puppetclasses"."id" = "environment_classes"."puppetclass_id" WHERE "environment_classes"."environment_id" = 1 AND 1=0 ORDER BY puppetclasses.name
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:16 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT "hostgroups".* FROM "hostgroups" WHERE "hostgroups"."id" IN (1, 4, 7, 8, 9, 10, 13, 14, 15, 16, 17, 19, 20, 21, 22, 23, 24) AND "hostgroups"."id" IN (16) ORDER BY (case when hostgroups.ancestry is null then 0 else 1 end), hostgroups.ancestry
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "config_groups".id FROM "config_groups" INNER JOIN "host_config_groups" ON "config_groups"."id" = "host_config_groups"."config_group_id" WHERE "host_config_groups"."host_id" = 16 AND "host_config_groups"."host_type" = 'Hostgroup' ORDER BY config_groups.name
2015-03-27 16:35:16 [D] SQL (0.4ms) SELECT "config_group_classes"."puppetclass_id" FROM "config_group_classes" WHERE 1=0
2015-03-27 16:35:16 [D] SQL (0.4ms) SELECT "hostgroup_classes"."puppetclass_id" FROM "hostgroup_classes" WHERE "hostgroup_classes"."hostgroup_id" IN (16)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT DISTINCT "puppetclasses".* FROM "puppetclasses" INNER JOIN "environment_classes" ON "puppetclasses"."id" = "environment_classes"."puppetclass_id" WHERE "environment_classes"."environment_id" = 1 AND 1=0 ORDER BY puppetclasses.name
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:16 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT "hostgroups".* FROM "hostgroups" WHERE "hostgroups"."id" IN (1, 4, 7, 8, 9, 10, 13, 14, 15, 16, 17, 19, 20, 21, 22, 23, 24) AND "hostgroups"."id" IN (16) ORDER BY (case when hostgroups.ancestry is null then 0 else 1 end), hostgroups.ancestry
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "config_groups".id FROM "config_groups" INNER JOIN "host_config_groups" ON "config_groups"."id" = "host_config_groups"."config_group_id" WHERE "host_config_groups"."host_id" = 16 AND "host_config_groups"."host_type" = 'Hostgroup' ORDER BY config_groups.name
2015-03-27 16:35:16 [D] SQL (0.3ms) SELECT "config_group_classes"."puppetclass_id" FROM "config_group_classes" WHERE 1=0
2015-03-27 16:35:16 [D] SQL (0.4ms) SELECT "hostgroup_classes"."puppetclass_id" FROM "hostgroup_classes" WHERE "hostgroup_classes"."hostgroup_id" IN (16)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT DISTINCT "puppetclasses".* FROM "puppetclasses" INNER JOIN "environment_classes" ON "puppetclasses"."id" = "environment_classes"."puppetclass_id" WHERE "environment_classes"."environment_id" = 1 AND 1=0 ORDER BY puppetclasses.name
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT "hostgroups".* FROM "hostgroups" WHERE "hostgroups"."id" IN (1, 4, 7, 8, 9, 10, 13, 14, 15, 16, 17, 19, 20, 21, 22, 23, 24) AND "hostgroups"."id" IN (16) ORDER BY (case when hostgroups.ancestry is null then 0 else 1 end), hostgroups.ancestry
2015-03-27 16:35:16 [D] SQL (0.6ms) SELECT "config_groups".id FROM "config_groups" INNER JOIN "host_config_groups" ON "config_groups"."id" = "host_config_groups"."config_group_id" WHERE "host_config_groups"."host_id" = 16 AND "host_config_groups"."host_type" = 'Hostgroup' ORDER BY config_groups.name
2015-03-27 16:35:16 [D] SQL (0.4ms) SELECT "config_group_classes"."puppetclass_id" FROM "config_group_classes" WHERE 1=0
2015-03-27 16:35:16 [D] SQL (0.4ms) SELECT "hostgroup_classes"."puppetclass_id" FROM "hostgroup_classes" WHERE "hostgroup_classes"."hostgroup_id" IN (16)
2015-03-27 16:35:16 [D] SQL (0.4ms) SELECT "host_classes"."puppetclass_id" FROM "host_classes" WHERE "host_classes"."host_id" IS NULL
2015-03-27 16:35:16 [D] CACHE (0.0ms) SELECT DISTINCT "puppetclasses".* FROM "puppetclasses" INNER JOIN "environment_classes" ON "puppetclasses"."id" = "environment_classes"."puppetclass_id" WHERE "environment_classes"."environment_id" = 1 AND 1=0 ORDER BY puppetclasses.name
2015-03-27 16:35:16 [I] Started GET "/tasks/d8d07891-eb09-4c7d-84ba-d91bc92acce4" for 194.0.88.214 at 2015-03-27 16:35:16 +0200
2015-03-27 16:35:16 [I] Processing by TasksController#show as */*
2015-03-27 16:35:16 [I] Parameters: {"id"=>"d8d07891-eb09-4c7d-84ba-d91bc92acce4"}
2015-03-27 16:35:16 [D] User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 4]]
2015-03-27 16:35:16 [D] Setting current user thread-local variable to altvnk
2015-03-27 16:35:16 [D] AuthSource Load (0.6ms) SELECT "auth_sources".* FROM "auth_sources" WHERE "auth_sources"."id" = 1 LIMIT 1
2015-03-27 16:35:16 [D] SQL (0.5ms) SELECT id FROM "taxonomies" WHERE "taxonomies"."type" IN ('Location') LIMIT 1
2015-03-27 16:35:16 [I] Rendered tasks/_list.html.erb (1.2ms)
2015-03-27 16:35:16 [I] Completed 200 OK in 10.8ms (Views: 1.8ms | ActiveRecord: 1.5ms)
2015-03-27 16:35:17 [I] Rendered puppetclasses/_classes.html.erb (1156.5ms)
2015-03-27 16:35:17 [I] Rendered puppetclasses/_class_selection.html.erb (1702.0ms)
2015-03-27 16:35:17 [I] Rendered hosts/_compute.html.erb (5.7ms)
2015-03-27 16:35:17 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Domain' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Domain' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Domain' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Domain' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] (0.2ms) SELECT COUNT(*) FROM "domains" WHERE "domains"."id" IN (2, 1, 4, 6, 8) AND (1=1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Domain' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Domain' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] CACHE (0.0ms) SELECT COUNT(*) FROM "domains" WHERE "domains"."id" IN (2, 1, 4, 6, 8) AND (1=1)
2015-03-27 16:35:17 [D] Domain Load (0.3ms) SELECT "domains".* FROM "domains" WHERE "domains"."id" IN (2, 1, 4, 6, 8) AND (1=1) ORDER BY name
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Subnet' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Subnet' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Subnet' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Subnet' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] (0.2ms) SELECT COUNT(*) FROM "subnets" WHERE "subnets"."id" IN (1, 3, 6) AND (1=1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Subnet' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Subnet' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] CACHE (0.0ms) SELECT COUNT(*) FROM "subnets" WHERE "subnets"."id" IN (1, 3, 6) AND (1=1)
2015-03-27 16:35:17 [D] Subnet Load (0.4ms) SELECT "subnets".* FROM "subnets" WHERE "subnets"."id" IN (1, 3, 6) AND (1=1) ORDER BY name
2015-03-27 16:35:17 [I] Rendered nic/_base_form.html.erb (26.7ms)
2015-03-27 16:35:17 [I] Rendered nic/_virtual_form.html.erb (2.1ms)
2015-03-27 16:35:17 [I] Rendered compute_resources_vms/form/vmware/_network.html.erb (235.9ms)
2015-03-27 16:35:17 [I] Rendered nic/_provider_specific_form.html.erb (237.9ms)
2015-03-27 16:35:17 [I] Rendered nic/manageds/_managed.html.erb (271.0ms)
2015-03-27 16:35:17 [D] SQL (0.4ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Domain' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] SQL (0.3ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Domain' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Domain' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Domain' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] CACHE (0.0ms) SELECT COUNT(*) FROM "domains" WHERE "domains"."id" IN (2, 1, 4, 6, 8) AND (1=1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Domain' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Domain' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] CACHE (0.0ms) SELECT COUNT(*) FROM "domains" WHERE "domains"."id" IN (2, 1, 4, 6, 8) AND (1=1)
2015-03-27 16:35:17 [D] CACHE (0.0ms) SELECT "domains".* FROM "domains" WHERE "domains"."id" IN (2, 1, 4, 6, 8) AND (1=1) ORDER BY name
2015-03-27 16:35:17 [D] SQL (0.5ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Subnet' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] SQL (0.3ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Subnet' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Subnet' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Subnet' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] CACHE (0.0ms) SELECT COUNT(*) FROM "subnets" WHERE "subnets"."id" IN (1, 3, 6) AND (1=1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Subnet' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Subnet' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:17 [D] CACHE (0.0ms) SELECT COUNT(*) FROM "subnets" WHERE "subnets"."id" IN (1, 3, 6) AND (1=1)
2015-03-27 16:35:17 [D] CACHE (0.0ms) SELECT "subnets".* FROM "subnets" WHERE "subnets"."id" IN (1, 3, 6) AND (1=1) ORDER BY name
2015-03-27 16:35:17 [I] Rendered nic/_base_form.html.erb (136.4ms)
2015-03-27 16:35:17 [I] Rendered nic/_virtual_form.html.erb (1.1ms)
2015-03-27 16:35:18 [I] Rendered compute_resources_vms/form/vmware/_network.html.erb (233.6ms)
2015-03-27 16:35:18 [I] Rendered nic/_provider_specific_form.html.erb (234.6ms)
2015-03-27 16:35:18 [I] Rendered nic/manageds/_managed.html.erb (375.3ms)
2015-03-27 16:35:18 [I] Rendered hosts/_interfaces.html.erb (651.4ms)
2015-03-27 16:35:18 [D] Architecture Load (0.4ms) SELECT "architectures".* FROM "architectures"
2015-03-27 16:35:18 [D] Operatingsystem Load (0.5ms) SELECT "operatingsystems".* FROM "operatingsystems" INNER JOIN "architectures_operatingsystems" ON "operatingsystems"."id" = "architectures_operatingsystems"."operatingsystem_id" WHERE "architectures_operatingsystems"."architecture_id" = 1 ORDER BY title
2015-03-27 16:35:18 [I] Rendered common/os_selection/_architecture.html.erb (21.8ms)
2015-03-27 16:35:18 [D] SQL (0.3ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Medium' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:18 [D] SQL (0.3ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Medium' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:18 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Medium' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:18 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Medium' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:18 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:18 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Medium' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:18 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:18 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Medium' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:18 [D] (0.3ms) SELECT COUNT(*) FROM "media" INNER JOIN "media_operatingsystems" ON "media"."id" = "media_operatingsystems"."medium_id" WHERE "media_operatingsystems"."operatingsystem_id" = 11 AND "media"."id" IN (2, 1) AND (1=1)
2015-03-27 16:35:18 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:18 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Medium' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:18 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:18 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Medium' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:18 [D] Medium Load (0.3ms) SELECT "media".* FROM "media" INNER JOIN "media_operatingsystems" ON "media"."id" = "media_operatingsystems"."medium_id" WHERE "media_operatingsystems"."operatingsystem_id" = 11 AND "media"."id" IN (2, 1) AND (1=1) ORDER BY media.name
2015-03-27 16:35:18 [D] Ptable Load (0.3ms) SELECT "ptables".* FROM "ptables" INNER JOIN "operatingsystems_ptables" ON "ptables"."id" = "operatingsystems_ptables"."ptable_id" WHERE "operatingsystems_ptables"."operatingsystem_id" = 11 ORDER BY ptables.name
2015-03-27 16:35:18 [I] Rendered common/os_selection/_operatingsystem.html.erb (18.4ms)
2015-03-27 16:35:18 [I] Rendered hosts/_operating_system.html.erb (48.8ms)
2015-03-27 16:35:18 [I] Rendered hosts/_unattended.html.erb (708.3ms)
2015-03-27 16:35:18 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:18 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:18 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:18 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'Hostgroup' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:18 [D] CACHE (0.0ms) SELECT "hostgroups".* FROM "hostgroups" WHERE "hostgroups"."id" IN (1, 4, 7, 8, 9, 10, 13, 14, 15, 16, 17, 19, 20, 21, 22, 23, 24) AND "hostgroups"."id" IN (16) ORDER BY (case when hostgroups.ancestry is null then 0 else 1 end), hostgroups.ancestry
2015-03-27 16:35:18 [D] SQL (0.3ms) SELECT "config_groups".id FROM "config_groups" INNER JOIN "host_config_groups" ON "config_groups"."id" = "host_config_groups"."config_group_id" WHERE "host_config_groups"."host_id" = 16 AND "host_config_groups"."host_type" = 'Hostgroup' ORDER BY config_groups.name
2015-03-27 16:35:18 [D] SQL (0.2ms) SELECT "config_group_classes"."puppetclass_id" FROM "config_group_classes" WHERE 1=0
2015-03-27 16:35:18 [D] SQL (0.2ms) SELECT "hostgroup_classes"."puppetclass_id" FROM "hostgroup_classes" WHERE "hostgroup_classes"."hostgroup_id" IN (16)
2015-03-27 16:35:18 [D] SQL (0.1ms) SELECT "host_classes"."puppetclass_id" FROM "host_classes" WHERE "host_classes"."host_id" IS NULL
2015-03-27 16:35:18 [D] CACHE (0.0ms) SELECT DISTINCT "puppetclasses".* FROM "puppetclasses" INNER JOIN "environment_classes" ON "puppetclasses"."id" = "environment_classes"."puppetclass_id" WHERE "environment_classes"."environment_id" = 1 AND 1=0 ORDER BY puppetclasses.name
2015-03-27 16:35:18 [D] (0.1ms) SHOW max_identifier_length
2015-03-27 16:35:18 [D] (0.5ms) SELECT COUNT(*) AS count_all, puppetclass_id AS puppetclass_id FROM "lookup_keys" WHERE 1=0 GROUP BY puppetclass_id
2015-03-27 16:35:18 [D] (0.3ms) SELECT COUNT(*) AS count_all, environment_classes.puppetclass_id AS environment_classes_puppetclass_id FROM "lookup_keys" INNER JOIN "environment_classes" ON "environment_classes"."lookup_key_id" = "lookup_keys"."id" WHERE 1=0 GROUP BY environment_classes.puppetclass_id
2015-03-27 16:35:18 [I] Rendered puppetclasses/_class_parameters.html.erb (0.0ms)
2015-03-27 16:35:18 [I] Rendered puppetclasses/_classes_parameters.html.erb (16.6ms)
2015-03-27 16:35:18 [D] CommonParameter Load (0.2ms) SELECT "parameters".* FROM "parameters" WHERE "parameters"."type" IN ('CommonParameter') ORDER BY parameters.name
2015-03-27 16:35:18 [D] CACHE (0.0ms) SELECT "taxonomies".* FROM "taxonomies" WHERE "taxonomies"."type" IN ('Location') AND "taxonomies"."id" = 1 ORDER BY title LIMIT 1
2015-03-27 16:35:18 [D] LocationParameter Load (0.2ms) SELECT "parameters".* FROM "parameters" WHERE "parameters"."type" IN ('LocationParameter') AND "parameters"."reference_id" = 1 ORDER BY parameters.name
2015-03-27 16:35:18 [D] DomainParameter Load (0.2ms) SELECT "parameters".* FROM "parameters" WHERE "parameters"."type" IN ('DomainParameter') AND "parameters"."reference_id" = 6 ORDER BY parameters.name
2015-03-27 16:35:18 [D] OsParameter Load (0.2ms) SELECT "parameters".* FROM "parameters" WHERE "parameters"."type" IN ('OsParameter') AND "parameters"."reference_id" = 11 ORDER BY parameters.name
2015-03-27 16:35:18 [D] GroupParameter Load (0.2ms) SELECT "parameters".* FROM "parameters" WHERE "parameters"."type" IN ('GroupParameter') AND "parameters"."reference_id" = 16 ORDER BY parameters.name
2015-03-27 16:35:18 [I] Rendered common_parameters/_inherited_parameters.html.erb (2.3ms)
2015-03-27 16:35:18 [I] Started GET "/tasks/d8d07891-eb09-4c7d-84ba-d91bc92acce4" for 194.0.88.214 at 2015-03-27 16:35:18 +0200
2015-03-27 16:35:18 [I] Processing by TasksController#show as */*
2015-03-27 16:35:18 [I] Parameters: {"id"=>"d8d07891-eb09-4c7d-84ba-d91bc92acce4"}
2015-03-27 16:35:18 [D] User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 4]]
2015-03-27 16:35:18 [D] Setting current user thread-local variable to altvnk
2015-03-27 16:35:18 [D] AuthSource Load (0.3ms) SELECT "auth_sources".* FROM "auth_sources" WHERE "auth_sources"."id" = 1 LIMIT 1
2015-03-27 16:35:18 [D] SQL (0.2ms) SELECT id FROM "taxonomies" WHERE "taxonomies"."type" IN ('Location') LIMIT 1
2015-03-27 16:35:18 [I] Rendered tasks/_list.html.erb (0.6ms)
2015-03-27 16:35:18 [I] Completed 200 OK in 5.3ms (Views: 0.9ms | ActiveRecord: 0.7ms)
2015-03-27 16:35:18 [I] Rendered common_parameters/_puppetclass_parameter.html.erb (4.4ms)
2015-03-27 16:35:18 [I] Rendered common_parameters/_puppetclasses_parameters.html.erb (129.8ms)
2015-03-27 16:35:18 [I] Rendered common_parameters/_parameter.html.erb (2.9ms)
2015-03-27 16:35:18 [I] Rendered common_parameters/_parameters.html.erb (16.7ms)
2015-03-27 16:35:18 [D] AuthSourceHidden Load (0.5ms) SELECT "auth_sources".* FROM "auth_sources" WHERE "auth_sources"."type" IN ('AuthSourceHidden')
2015-03-27 16:35:18 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:18 [D] SQL (0.3ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'User' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:18 [D] SQL (0.2ms) SELECT "users"."id" FROM "users" WHERE "users"."admin" = 't'
2015-03-27 16:35:18 [D] CACHE (0.0ms) SELECT id FROM "taxonomies" WHERE ((("taxonomies"."id" = 1 OR "taxonomies"."ancestry" ILIKE '1/%') OR "taxonomies"."ancestry" = '1')) ORDER BY title
2015-03-27 16:35:18 [D] SQL (0.2ms) SELECT "taxable_taxonomies"."taxable_id" FROM "taxable_taxonomies" WHERE "taxable_taxonomies"."taxable_type" = 'User' AND "taxable_taxonomies"."taxonomy_id" IN (1)
2015-03-27 16:35:18 [D] SQL (0.2ms) SELECT "users"."id" FROM "users" WHERE "users"."admin" = 't'
2015-03-27 16:35:18 [D] User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (6, 1, 2, 3, 6, 4, 5) AND (users.auth_source_id <> 2) ORDER BY firstname
2015-03-27 16:35:18 [D] Usergroup Load (0.3ms) SELECT "usergroups".* FROM "usergroups" ORDER BY usergroups.name
2015-03-27 16:35:18 [D] Setting current location thread-local variable to none
2015-03-27 16:35:18 [D] Setting current organization thread-local variable to none
2015-03-27 16:35:18 [I] Rendered hosts/_form.html.erb (2788.4ms)
2015-03-27 16:35:18 [I] Rendered hosts/new.html.erb within layouts/application (2791.5ms)
2015-03-27 16:35:18 [I] Rendered home/_user_dropdown.html.erb (2.5ms)
2015-03-27 16:35:18 [I] Read fragment views/tabs_and_title_records-4 0.1ms
2015-03-27 16:35:18 [I] Rendered home/_topbar.html.erb (118.6ms)
2015-03-27 16:35:18 [I] Rendered layouts/base.html.erb (121.5ms)
2015-03-27 16:35:18 [I] Completed 200 OK in 8438.1ms (Views: 3029.9ms | ActiveRecord: 138.0ms)
2015-03-27 16:35:19 [I] Started GET "/tasks/d8d07891-eb09-4c7d-84ba-d91bc92acce4" for 194.0.88.214 at 2015-03-27 16:35:19 +0200
2015-03-27 16:35:19 [I] Processing by TasksController#show as */*
2015-03-27 16:35:19 [I] Parameters: {"id"=>"d8d07891-eb09-4c7d-84ba-d91bc92acce4"}
2015-03-27 16:35:19 [D] User Load (0.6ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 4]]
2015-03-27 16:35:19 [D] Setting current user thread-local variable to altvnk
2015-03-27 16:35:19 [D] AuthSource Load (0.7ms) SELECT "auth_sources".* FROM "auth_sources" WHERE "auth_sources"."id" = 1 LIMIT 1
2015-03-27 16:35:19 [D] SQL (0.4ms) SELECT id FROM "taxonomies" WHERE "taxonomies"."type" IN ('Location') LIMIT 1
2015-03-27 16:35:19 [I] Rendered tasks/_list.html.erb (2.2ms)
2015-03-27 16:35:19 [I] Completed 200 OK in 22.4ms (Views: 7.2ms | ActiveRecord: 1.7ms)
(3-3/4)