Project

General

Profile

Bug #13455 » production-debug.log

Karli Sjöberg, 03/19/2016 06:41 PM

 
2016-03-19T22:51:00 [app] [I] Rendered home/_topbar.html.erb (2.2ms)
2016-03-19T22:51:00 [app] [I] Rendered layouts/base.html.erb (3.7ms)
2016-03-19T22:51:00 [app] [I] Completed 200 OK in 269.0ms (Views: 60.0ms | ActiveRecord: 2.9ms)
2016-03-19T22:51:13 [app] [I] Started GET "/node/projector.inparadise.se?format=yml" for 172.16.1.11 at 2016-03-19 22:51:13 +0100
2016-03-19T22:51:13 [app] [I] Processing by HostsController#externalNodes as YML
2016-03-19T22:51:13 [app] [I] Parameters: {"name"=>"projector.inparadise.se"}
2016-03-19T22:51:13 [app] [D] Verifying request from ["puppet.inparadise.se"] against ["puppet.inparadise.se"]
2016-03-19T22:51:13 [app] [D] Setting current user thread-local variable to foreman_api_admin
2016-03-19T22:51:13 [app] [I] Rendered text template (0.0ms)
2016-03-19T22:51:13 [app] [I] Completed 200 OK in 70.1ms (Views: 0.7ms | ActiveRecord: 5.0ms)
2016-03-19T22:51:17 [app] [I] Started POST "/api/hosts/facts" for 172.16.1.11 at 2016-03-19 22:51:17 +0100
2016-03-19T22:51:17 [app] [I] Processing by Api::V2::HostsController#facts as JSON
2016-03-19T22:51:17 [app] [I] Parameters: {"facts"=>"[FILTERED]", "name"=>"projector.inparadise.se", "certname"=>"projector.inparadise.se", "apiv"=>"v2", :host=>{"name"=>"projector.inparadise.se", "certname"=>"projector.inparadise.se"}}
2016-03-19T22:51:17 [app] [D] Verifying request from ["puppet.inparadise.se"] against ["puppet.inparadise.se"]
2016-03-19T22:51:17 [app] [D] Setting current user thread-local variable to foreman_api_admin
2016-03-19T22:51:17 [app] [D] Merging facts for 'projector.inparadise.se': deleted 0 facts
2016-03-19T22:51:17 [app] [D] Merging facts for 'projector.inparadise.se': added 0 facts
2016-03-19T22:51:17 [app] [D] Merging facts for 'projector.inparadise.se': updated 3 facts
2016-03-19T22:51:17 [app] [I] Import facts for 'projector.inparadise.se' completed. Added: 0, Updated: 3, Deleted 0 facts
2016-03-19T22:51:17 [app] [D] We have following interfaces 'vtnet0, lo0' based on facts
2016-03-19T22:51:17 [app] [D] Interface vtnet0 facts: {"ipaddress"=>"172.16.1.14", "macaddress"=>"52:54:00:58:de:a1", "netmask"=>"255.255.255.0", "mtu"=>1500, "network"=>"172.16.1.0"}
2016-03-19T22:51:17 [app] [D] Interface lo0 facts: {"ipaddress"=>"127.0.0.1", "netmask"=>"255.0.0.0", "mtu"=>16384, "network"=>"127.0.0.0"}
2016-03-19T22:51:17 [app] [D] Body: {"architecture_id":1,"build":false,"certname":"projector.inparadise.se","comment":null,"compute_profile_id":null,"compute_resource_id":1,"created_at":"2016-02-28T19:59:54Z","disk":null,"enabled":true,"environment_id":1,"global_status":0,"grub_pass":"","hostgroup_id":null,"id":1,"image_file":"","image_id":null,"installed_at":null,"last_compile":"2016-03-19T21:51:16Z","last_report":"2016-03-19T21:46:13Z","location_id":null,"lookup_value_matcher":"fqdn=projector.inparadise.se","managed":false,"medium_id":null,"model_id":1,"name":"projector.inparadise.se","operatingsystem_id":2,"organization_id":null,"otp":null,"owner_id":null,"owner_type":null,"provision_method":"build","ptable_id":null,"puppet_ca_proxy_id":1,"puppet_proxy_id":1,"realm_id":null,"root_pass":null,"updated_at":"2016-03-19T21:51:17Z","use_image":null,"uuid":"5a2e4bd5-bcde-4e65-9a55-a5d1c2630071"}
2016-03-19T22:51:17 [app] [I] Completed 201 Created in 139.8ms (Views: 5.5ms | ActiveRecord: 0.2ms)
2016-03-19T22:51:17 [app] [I] Started GET "/node/projector.inparadise.se?format=yml" for 172.16.1.11 at 2016-03-19 22:51:17 +0100
2016-03-19T22:51:17 [app] [I] Processing by HostsController#externalNodes as YML
2016-03-19T22:51:17 [app] [I] Parameters: {"name"=>"projector.inparadise.se"}
2016-03-19T22:51:17 [app] [D] Verifying request from ["puppet.inparadise.se"] against ["puppet.inparadise.se"]
2016-03-19T22:51:17 [app] [D] Setting current user thread-local variable to foreman_api_admin
2016-03-19T22:51:17 [app] [I] Rendered text template (0.0ms)
2016-03-19T22:51:17 [app] [I] Completed 200 OK in 71.7ms (Views: 0.8ms | ActiveRecord: 5.3ms)
2016-03-19T22:51:20 [app] [I] Started GET "/hosts/new" for 172.16.1.106 at 2016-03-19 22:51:20 +0100
2016-03-19T22:51:20 [app] [I] Processing by HostsController#new as HTML
2016-03-19T22:51:20 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:51:20 [app] [I] Rendered hosts/_progress.html.erb (0.3ms)
2016-03-19T22:51:20 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:51:20 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:51:21 [app] [I] Rendered nic/_base_form.html.erb (185.8ms)
2016-03-19T22:51:21 [app] [I] Rendered nic/_virtual_form.html.erb (1.1ms)
2016-03-19T22:51:21 [app] [I] Rendered nic/_provider_specific_form.html.erb (0.2ms)
2016-03-19T22:51:21 [app] [I] Rendered nic/manageds/_managed.html.erb (189.8ms)
2016-03-19T22:51:21 [app] [I] Rendered nic/_base_form.html.erb (11.1ms)
2016-03-19T22:51:21 [app] [I] Rendered nic/_virtual_form.html.erb (1.0ms)
2016-03-19T22:51:21 [app] [I] Rendered nic/_provider_specific_form.html.erb (0.2ms)
2016-03-19T22:51:21 [app] [I] Rendered nic/manageds/_managed.html.erb (14.9ms)
2016-03-19T22:51:21 [app] [I] Rendered hosts/_interfaces.html.erb (207.4ms)
2016-03-19T22:51:21 [app] [I] Rendered common/os_selection/_architecture.html.erb (1.9ms)
2016-03-19T22:51:21 [app] [I] Rendered common/os_selection/_operatingsystem.html.erb (2.7ms)
2016-03-19T22:51:21 [app] [I] Rendered hosts/_operating_system.html.erb (11.6ms)
2016-03-19T22:51:21 [app] [I] Rendered hosts/_unattended.html.erb (12.0ms)
2016-03-19T22:51:21 [app] [I] Rendered puppetclasses/_class_parameters.html.erb (0.0ms)
2016-03-19T22:51:21 [app] [I] Rendered puppetclasses/_classes_parameters.html.erb (6.5ms)
2016-03-19T22:51:21 [app] [I] Rendered common_parameters/_puppetclass_parameter.html.erb (2.1ms)
2016-03-19T22:51:21 [app] [I] Rendered common_parameters/_puppetclasses_parameters.html.erb (3.8ms)
2016-03-19T22:51:21 [app] [I] Rendered common_parameters/_inherited_parameters.html.erb (0.1ms)
2016-03-19T22:51:21 [app] [I] Rendered common_parameters/_parameter.html.erb (1.6ms)
2016-03-19T22:51:21 [app] [I] Rendered common_parameters/_parameters.html.erb (4.0ms)
2016-03-19T22:51:21 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:51:21 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:51:21 [app] [I] Rendered hosts/_form.html.erb (306.3ms)
2016-03-19T22:51:21 [app] [I] Rendered hosts/new.html.erb within layouts/application (307.1ms)
2016-03-19T22:51:21 [app] [I] Rendered home/_user_dropdown.html.erb (1.5ms)
2016-03-19T22:51:21 [app] [I] Read fragment views/tabs_and_title_records-4 0.2ms
2016-03-19T22:51:21 [app] [I] Rendered home/_topbar.html.erb (2.8ms)
2016-03-19T22:51:21 [app] [I] Rendered layouts/base.html.erb (4.9ms)
2016-03-19T22:51:21 [app] [I] Completed 200 OK in 334.0ms (Views: 308.2ms | ActiveRecord: 7.6ms)
2016-03-19T22:51:21 [app] [I] Started GET "/hosts/new" for 172.16.1.106 at 2016-03-19 22:51:21 +0100
2016-03-19T22:51:21 [app] [I] Processing by HostsController#new as HTML
2016-03-19T22:51:21 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:51:21 [app] [I] Rendered hosts/_progress.html.erb (0.2ms)
2016-03-19T22:51:21 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:51:21 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:51:21 [app] [I] Rendered nic/_base_form.html.erb (16.6ms)
2016-03-19T22:51:21 [app] [I] Rendered nic/_virtual_form.html.erb (1.6ms)
2016-03-19T22:51:21 [app] [I] Rendered nic/_provider_specific_form.html.erb (0.2ms)
2016-03-19T22:51:21 [app] [I] Rendered nic/manageds/_managed.html.erb (22.5ms)
2016-03-19T22:51:21 [app] [I] Rendered nic/_base_form.html.erb (17.1ms)
2016-03-19T22:51:21 [app] [I] Rendered nic/_virtual_form.html.erb (1.3ms)
2016-03-19T22:51:21 [app] [I] Rendered nic/_provider_specific_form.html.erb (0.1ms)
2016-03-19T22:51:21 [app] [I] Rendered nic/manageds/_managed.html.erb (22.0ms)
2016-03-19T22:51:21 [app] [I] Rendered hosts/_interfaces.html.erb (48.4ms)
2016-03-19T22:51:21 [app] [I] Rendered common/os_selection/_architecture.html.erb (2.8ms)
2016-03-19T22:51:21 [app] [I] Rendered common/os_selection/_operatingsystem.html.erb (3.7ms)
2016-03-19T22:51:21 [app] [I] Rendered hosts/_operating_system.html.erb (15.8ms)
2016-03-19T22:51:21 [app] [I] Rendered hosts/_unattended.html.erb (16.2ms)
2016-03-19T22:51:21 [app] [I] Rendered puppetclasses/_class_parameters.html.erb (0.0ms)
2016-03-19T22:51:21 [app] [I] Rendered puppetclasses/_classes_parameters.html.erb (7.9ms)
2016-03-19T22:51:21 [app] [I] Rendered common_parameters/_puppetclass_parameter.html.erb (3.0ms)
2016-03-19T22:51:21 [app] [I] Rendered common_parameters/_puppetclasses_parameters.html.erb (5.5ms)
2016-03-19T22:51:21 [app] [I] Rendered common_parameters/_inherited_parameters.html.erb (0.2ms)
2016-03-19T22:51:21 [app] [I] Rendered common_parameters/_parameter.html.erb (1.9ms)
2016-03-19T22:51:21 [app] [I] Rendered common_parameters/_parameters.html.erb (4.8ms)
2016-03-19T22:51:21 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:51:21 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:51:21 [app] [I] Rendered hosts/_form.html.erb (146.1ms)
2016-03-19T22:51:21 [app] [I] Rendered hosts/new.html.erb within layouts/application (147.0ms)
2016-03-19T22:51:21 [app] [I] Rendered home/_user_dropdown.html.erb (1.7ms)
2016-03-19T22:51:21 [app] [I] Read fragment views/tabs_and_title_records-4 0.2ms
2016-03-19T22:51:21 [app] [I] Rendered home/_topbar.html.erb (3.3ms)
2016-03-19T22:51:21 [app] [I] Rendered layouts/base.html.erb (5.6ms)
2016-03-19T22:51:21 [app] [I] Completed 200 OK in 167.3ms (Views: 149.0ms | ActiveRecord: 6.4ms)
2016-03-19T22:51:25 [app] [I] Started POST "/api/reports" for 172.16.1.11 at 2016-03-19 22:51:25 +0100
2016-03-19T22:51:25 [app] [I] Processing by Api::V2::ReportsController#create as JSON
2016-03-19T22:51:25 [app] [I] Parameters: {"report"=>"[FILTERED]", "apiv"=>"v2"}
2016-03-19T22:51:25 [app] [D] Verifying request from ["puppet.inparadise.se"] against ["puppet.inparadise.se"]
2016-03-19T22:51:25 [app] [D] Setting current user thread-local variable to foreman_api_admin
2016-03-19T22:51:25 [app] [I] processing report for projector.inparadise.se
2016-03-19T22:51:25 [app] [D] Report: {"host"=>"projector.inparadise.se", "reported_at"=>"2016-03-19 21:51:13 UTC", "status"=>{"applied"=>0, "restarted"=>0, "failed"=>0, "failed_restarts"=>0, "skipped"=>1, "pending"=>0}, "metrics"=>{"resources"=>{"changed"=>0, "failed"=>0, "failed_to_restart"=>0, "out_of_sync"=>0, "restarted"=>0, "scheduled"=>0, "skipped"=>1, "total"=>35}, "time"=>{"config_retrieval"=>1.397619154, "exec"=>6.350663401000001, "file"=>0.14302543200000004, "filebucket"=>0.000658184, "schedule"=>0.0016178030000000001, "service"=>0.444126807, "total"=>8.337710781000002}, "changes"=>{"total"=>0}, "events"=>{"failure"=>0, "success"=>0, "total"=>0}}, "logs"=>nil}
2016-03-19T22:51:25 [app] [I] Imported report for projector.inparadise.se in 0.03 seconds
2016-03-19T22:51:25 [app] [I] Rendered api/v2/reports/create.json.rabl (9.9ms)
2016-03-19T22:51:25 [app] [D] Body: {"metrics":{"resources":{"changed":0,"failed":0,"failed_to_restart":0,"out_of_sync":0,"restarted":0,"scheduled":0,"skipped":1,"total":35},"time":{"config_retrieval":1.397619154,"exec":6.350663401000001,"file":0.14302543200000004,"filebucket":0.000658184,"schedule":0.0016178030000000001,"service":0.444126807,"total":8.337710781000002},"changes":{"total":0},"events":{"failure":0,"success":0,"total":0}},"created_at":"2016-03-19T21:51:25Z","updated_at":"2016-03-19T21:51:25Z","id":43307,"host_id":1,"host_name":"projector.inparadise.se","reported_at":"2016-03-19T21:51:13Z","status":{"applied":0,"restarted":0,"failed":0,"failed_restarts":0,"skipped":1,"pending":0},"logs":[],"summary":"Success"}
2016-03-19T22:51:25 [app] [I] Completed 201 Created in 71.6ms (Views: 10.4ms | ActiveRecord: 23.5ms)
2016-03-19T22:51:32 [app] [I] Started POST "/hosts/compute_resource_selected" for 172.16.1.106 at 2016-03-19 22:51:32 +0100
2016-03-19T22:51:32 [app] [I] Processing by HostsController#compute_resource_selected as */*
2016-03-19T22:51:32 [app] [I] Parameters: {"utf8"=>"✓", "authenticity_token"=>"cXiw93lcpKzmWpck+S6siTlZrNPk9ksT9efrYSRe55I=", "host"=>{"name"=>"blah", "hostgroup_id"=>"", "compute_resource_id"=>"1", "compute_profile_id"=>"", "environment_id"=>"", "puppet_ca_proxy_id"=>"", "puppet_proxy_id"=>"", "managed"=>"true", "progress_report_id"=>"[FILTERED]", "type"=>"Host::Managed", "interfaces_attributes"=>{"0"=>{"_destroy"=>"0", "type"=>"Nic::Managed", "mac"=>"", "identifier"=>"", "name"=>"blah", "domain_id"=>"", "subnet_id"=>"", "ip"=>"", "managed"=>"1", "primary"=>"1", "provision"=>"1", "virtual"=>"0", "tag"=>"", "attached_to"=>""}, "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"=>""}}, "architecture_id"=>"", "provision_method"=>"build", "build"=>"1", "disk"=>"", "root_pass"=>"[FILTERED]", "is_owned_by"=>"4-Users", "enabled"=>"1", "model_id"=>"", "comment"=>"", "overwrite"=>"false"}, "fakepassword"=>"[FILTERED]"}
2016-03-19T22:51:32 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:51:32 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:51:32 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:51:32 [app] [I] Rendered hosts/_compute.html.erb (13.0ms)
2016-03-19T22:51:32 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:51:32 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:51:32 [app] [I] Completed 200 OK in 45.0ms (Views: 14.2ms | ActiveRecord: 4.2ms)
2016-03-19T22:51:32 [app] [I] Started POST "/hosts/interfaces" for 172.16.1.106 at 2016-03-19 22:51:32 +0100
2016-03-19T22:51:32 [app] [I] Processing by HostsController#interfaces as */*
2016-03-19T22:51:32 [app] [I] Parameters: {"utf8"=>"✓", "authenticity_token"=>"cXiw93lcpKzmWpck+S6siTlZrNPk9ksT9efrYSRe55I=", "host"=>{"name"=>"blah", "hostgroup_id"=>"", "compute_resource_id"=>"1", "compute_profile_id"=>"", "environment_id"=>"", "puppet_ca_proxy_id"=>"", "puppet_proxy_id"=>"", "managed"=>"true", "progress_report_id"=>"[FILTERED]", "type"=>"Host::Managed", "interfaces_attributes"=>{"0"=>{"_destroy"=>"0", "type"=>"Nic::Managed", "mac"=>"", "identifier"=>"", "name"=>"blah", "domain_id"=>"", "subnet_id"=>"", "ip"=>"", "managed"=>"1", "primary"=>"1", "provision"=>"1", "virtual"=>"0", "tag"=>"", "attached_to"=>""}, "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"=>""}}, "architecture_id"=>"", "provision_method"=>"build", "build"=>"1", "disk"=>"", "root_pass"=>"[FILTERED]", "is_owned_by"=>"4-Users", "enabled"=>"1", "model_id"=>"", "comment"=>"", "overwrite"=>"false"}, "capabilities"=>"build image", "provider"=>"Libvirt", "fakepassword"=>"[FILTERED]"}
2016-03-19T22:51:32 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:51:32 [app] [I] Rendered nic/_base_form.html.erb (20.7ms)
2016-03-19T22:51:32 [app] [I] Rendered nic/_virtual_form.html.erb (1.8ms)
2016-03-19T22:51:33 [app] [I] Rendered compute_resources_vms/form/libvirt/_network.html.erb (858.3ms)
2016-03-19T22:51:33 [app] [I] Rendered nic/_provider_specific_form.html.erb (859.9ms)
2016-03-19T22:51:33 [app] [I] Rendered nic/manageds/_managed.html.erb (886.2ms)
2016-03-19T22:51:33 [app] [I] Rendered nic/_base_form.html.erb (24.5ms)
2016-03-19T22:51:33 [app] [I] Rendered nic/_virtual_form.html.erb (1.4ms)
2016-03-19T22:51:33 [app] [I] Rendered compute_resources_vms/form/libvirt/_network.html.erb (708.8ms)
2016-03-19T22:51:33 [app] [I] Rendered nic/_provider_specific_form.html.erb (710.1ms)
2016-03-19T22:51:33 [app] [I] Rendered nic/manageds/_managed.html.erb (739.1ms)
2016-03-19T22:51:33 [app] [I] Rendered hosts/_interfaces.html.erb (1629.2ms)
2016-03-19T22:51:33 [app] [I] Rendered hosts/_interfaces_tab.html.erb (1629.9ms)
2016-03-19T22:51:33 [app] [I] Completed 200 OK in 1660.1ms (Views: 1630.0ms | ActiveRecord: 3.4ms)
2016-03-19T22:51:34 [app] [I] Started POST "/hosts/compute_resource_selected" for 172.16.1.106 at 2016-03-19 22:51:34 +0100
2016-03-19T22:51:34 [app] [I] Processing by HostsController#compute_resource_selected as */*
2016-03-19T22:51:34 [app] [I] Parameters: {"utf8"=>"✓", "authenticity_token"=>"cXiw93lcpKzmWpck+S6siTlZrNPk9ksT9efrYSRe55I=", "host"=>{"name"=>"blah", "hostgroup_id"=>"", "compute_resource_id"=>"1", "compute_profile_id"=>"1", "environment_id"=>"", "puppet_ca_proxy_id"=>"", "puppet_proxy_id"=>"", "managed"=>"true", "progress_report_id"=>"[FILTERED]", "type"=>"Host::Managed", "interfaces_attributes"=>{"0"=>{"_destroy"=>"0", "type"=>"Nic::Managed", "mac"=>"", "identifier"=>"", "name"=>"blah", "domain_id"=>"", "subnet_id"=>"", "ip"=>"", "managed"=>"1", "primary"=>"1", "provision"=>"1", "virtual"=>"0", "tag"=>"", "attached_to"=>"", "compute_attributes"=>{"type"=>"bridge", "bridge"=>"Jumbo_iSCSI_1", "model"=>"virtio"}}, "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"=>"bridge", "bridge"=>"Jumbo_iSCSI_1", "model"=>"virtio"}}}, "architecture_id"=>"", "provision_method"=>"build", "build"=>"1", "disk"=>"", "root_pass"=>"[FILTERED]", "is_owned_by"=>"4-Users", "enabled"=>"1", "model_id"=>"", "comment"=>"", "overwrite"=>"false"}, "capabilities"=>"build image", "provider"=>"Libvirt", "fakepassword"=>"[FILTERED]"}
2016-03-19T22:51:34 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:51:34 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:51:34 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:51:34 [app] [I] Rendered compute_resources_vms/form/libvirt/_base.html.erb (173.7ms)
2016-03-19T22:51:35 [app] [I] Rendered compute_resources_vms/form/libvirt/_volume.html.erb (320.7ms)
2016-03-19T22:51:35 [app] [I] Rendered compute_resources_vms/form/libvirt/_volume.html.erb (363.9ms)
2016-03-19T22:51:35 [app] [I] Rendered compute_resources_vms/form/_volumes.html.erb (796.1ms)
2016-03-19T22:51:35 [app] [I] Rendered hosts/_compute_detail.html.erb (971.9ms)
2016-03-19T22:51:35 [app] [I] Rendered hosts/_compute.html.erb (1250.3ms)
2016-03-19T22:51:35 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:51:35 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:51:35 [app] [I] Completed 200 OK in 1276.3ms (Views: 1251.5ms | ActiveRecord: 2.6ms)
2016-03-19T22:51:36 [app] [I] Started POST "/hosts/interfaces" for 172.16.1.106 at 2016-03-19 22:51:36 +0100
2016-03-19T22:51:36 [app] [I] Processing by HostsController#interfaces as */*
2016-03-19T22:51:36 [app] [I] Parameters: {"utf8"=>"✓", "authenticity_token"=>"cXiw93lcpKzmWpck+S6siTlZrNPk9ksT9efrYSRe55I=", "host"=>{"name"=>"blah", "hostgroup_id"=>"", "compute_resource_id"=>"1", "compute_profile_id"=>"1", "environment_id"=>"", "puppet_ca_proxy_id"=>"", "puppet_proxy_id"=>"", "managed"=>"true", "progress_report_id"=>"[FILTERED]", "type"=>"Host::Managed", "interfaces_attributes"=>{"0"=>{"_destroy"=>"0", "type"=>"Nic::Managed", "mac"=>"", "identifier"=>"", "name"=>"blah", "domain_id"=>"", "subnet_id"=>"", "ip"=>"", "managed"=>"1", "primary"=>"1", "provision"=>"1", "virtual"=>"0", "tag"=>"", "attached_to"=>"", "compute_attributes"=>{"type"=>"bridge", "bridge"=>"Jumbo_iSCSI_1", "model"=>"virtio"}}, "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"=>"bridge", "bridge"=>"Jumbo_iSCSI_1", "model"=>"virtio"}}}, "compute_attributes"=>{"cpus"=>"1", "memory"=>"536870912", "start"=>"1", "volumes_attributes"=>{"new_volumes"=>{"_delete"=>"", "pool_name"=>"default", "capacity"=>"10G", "allocation"=>"0G", "format_type"=>"raw"}, "0"=>{"_delete"=>"", "pool_name"=>"VMs", "capacity"=>"12G", "allocation"=>"0G", "format_type"=>"qcow2"}}}, "architecture_id"=>"", "provision_method"=>"build", "build"=>"1", "disk"=>"", "root_pass"=>"[FILTERED]", "is_owned_by"=>"4-Users", "enabled"=>"1", "model_id"=>"", "comment"=>"", "overwrite"=>"false"}, "capabilities"=>"build image", "provider"=>"Libvirt", "fakepassword"=>"[FILTERED]"}
2016-03-19T22:51:36 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:51:36 [app] [I] Rendered nic/_base_form.html.erb (18.1ms)
2016-03-19T22:51:36 [app] [I] Rendered nic/_virtual_form.html.erb (1.5ms)
2016-03-19T22:51:36 [app] [I] Rendered compute_resources_vms/form/libvirt/_network.html.erb (519.0ms)
2016-03-19T22:51:36 [app] [I] Rendered nic/_provider_specific_form.html.erb (521.0ms)
2016-03-19T22:51:36 [app] [I] Rendered nic/manageds/_managed.html.erb (544.7ms)
2016-03-19T22:51:36 [app] [I] Rendered nic/_base_form.html.erb (19.1ms)
2016-03-19T22:51:36 [app] [I] Rendered nic/_virtual_form.html.erb (1.8ms)
2016-03-19T22:51:37 [app] [I] Rendered compute_resources_vms/form/libvirt/_network.html.erb (404.8ms)
2016-03-19T22:51:37 [app] [I] Rendered nic/_provider_specific_form.html.erb (406.2ms)
2016-03-19T22:51:37 [app] [I] Rendered nic/manageds/_managed.html.erb (430.7ms)
2016-03-19T22:51:37 [app] [I] Rendered hosts/_interfaces.html.erb (979.4ms)
2016-03-19T22:51:37 [app] [I] Rendered hosts/_interfaces_tab.html.erb (980.1ms)
2016-03-19T22:51:37 [app] [I] Completed 200 OK in 1009.9ms (Views: 980.1ms | ActiveRecord: 3.1ms)
2016-03-19T22:51:37 [app] [I] Started POST "/hosts/hostgroup_or_environment_selected" for 172.16.1.106 at 2016-03-19 22:51:37 +0100
2016-03-19T22:51:37 [app] [I] Processing by HostsController#hostgroup_or_environment_selected as */*
2016-03-19T22:51:37 [app] [I] Parameters: {"utf8"=>"✓", "authenticity_token"=>"cXiw93lcpKzmWpck+S6siTlZrNPk9ksT9efrYSRe55I=", "host"=>{"name"=>"blah", "hostgroup_id"=>"", "compute_resource_id"=>"1", "compute_profile_id"=>"1", "environment_id"=>"1", "puppet_ca_proxy_id"=>"", "puppet_proxy_id"=>"", "managed"=>"true", "progress_report_id"=>"[FILTERED]", "type"=>"Host::Managed", "interfaces_attributes"=>{"0"=>{"_destroy"=>"0", "type"=>"Nic::Managed", "mac"=>"", "identifier"=>"", "name"=>"blah", "domain_id"=>"", "subnet_id"=>"", "ip"=>"", "managed"=>"1", "primary"=>"1", "provision"=>"1", "virtual"=>"0", "tag"=>"", "attached_to"=>"", "compute_attributes"=>{"type"=>"bridge", "bridge"=>"Jumbo_iSCSI_1", "model"=>"virtio"}}, "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"=>"bridge", "bridge"=>"Jumbo_iSCSI_1", "model"=>"virtio"}}}, "compute_attributes"=>{"cpus"=>"1", "memory"=>"536870912", "start"=>"1", "volumes_attributes"=>{"new_volumes"=>{"_delete"=>"", "pool_name"=>"default", "capacity"=>"10G", "allocation"=>"0G", "format_type"=>"raw"}, "0"=>{"_delete"=>"", "pool_name"=>"VMs", "capacity"=>"12G", "allocation"=>"0G", "format_type"=>"qcow2"}}}, "architecture_id"=>"", "provision_method"=>"build", "build"=>"1", "disk"=>"", "root_pass"=>"[FILTERED]", "is_owned_by"=>"4-Users", "enabled"=>"1", "model_id"=>"", "comment"=>"", "overwrite"=>"false"}, "capabilities"=>"build image", "provider"=>"Libvirt", "fakepassword"=>"[FILTERED]", "host_id"=>"null"}
2016-03-19T22:51:37 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:51:37 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:51:37 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:51:38 [app] [I] Rendered puppetclasses/_selectedClasses.html.erb (0.0ms)
2016-03-19T22:51:38 [app] [I] Rendered puppetclasses/_classes_in_groups.html.erb (0.0ms)
2016-03-19T22:51:38 [app] [I] Rendered puppetclasses/_classes.html.erb (57.1ms)
2016-03-19T22:51:38 [app] [I] Rendered puppetclasses/_class_selection.html.erb (91.3ms)
2016-03-19T22:51:38 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:51:38 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:51:38 [app] [I] Completed 200 OK in 126.1ms (Views: 90.3ms | ActiveRecord: 5.9ms)
2016-03-19T22:51:38 [app] [I] Started POST "/hosts/puppetclass_parameters" for 172.16.1.106 at 2016-03-19 22:51:38 +0100
2016-03-19T22:51:38 [app] [I] Processing by HostsController#puppetclass_parameters as */*
2016-03-19T22:51:38 [app] [I] Parameters: {"utf8"=>"✓", "authenticity_token"=>"cXiw93lcpKzmWpck+S6siTlZrNPk9ksT9efrYSRe55I=", "host"=>{"name"=>"blah", "hostgroup_id"=>"", "compute_resource_id"=>"1", "compute_profile_id"=>"1", "environment_id"=>"1", "puppet_ca_proxy_id"=>"", "puppet_proxy_id"=>"", "puppetclass_ids"=>[""], "managed"=>"true", "progress_report_id"=>"[FILTERED]", "type"=>"Host::Managed", "interfaces_attributes"=>{"0"=>{"_destroy"=>"0", "type"=>"Nic::Managed", "mac"=>"", "identifier"=>"", "name"=>"blah", "domain_id"=>"", "subnet_id"=>"", "ip"=>"", "managed"=>"1", "primary"=>"1", "provision"=>"1", "virtual"=>"0", "tag"=>"", "attached_to"=>"", "compute_attributes"=>{"type"=>"bridge", "bridge"=>"Jumbo_iSCSI_1", "model"=>"virtio"}}, "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"=>"bridge", "bridge"=>"Jumbo_iSCSI_1", "model"=>"virtio"}}}, "compute_attributes"=>{"cpus"=>"1", "memory"=>"536870912", "start"=>"1", "volumes_attributes"=>{"new_volumes"=>{"_delete"=>"", "pool_name"=>"default", "capacity"=>"10G", "allocation"=>"0G", "format_type"=>"raw"}, "0"=>{"_delete"=>"", "pool_name"=>"VMs", "capacity"=>"12G", "allocation"=>"0G", "format_type"=>"qcow2"}}}, "architecture_id"=>"", "provision_method"=>"build", "build"=>"1", "disk"=>"", "root_pass"=>"[FILTERED]", "is_owned_by"=>"4-Users", "enabled"=>"1", "model_id"=>"", "comment"=>"", "overwrite"=>"false"}, "capabilities"=>"build image", "provider"=>"Libvirt", "fakepassword"=>"[FILTERED]", "host_id"=>"null"}
2016-03-19T22:51:38 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:51:38 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:51:38 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:51:38 [app] [I] Rendered puppetclasses/_class_parameters.html.erb (0.0ms)
2016-03-19T22:51:38 [app] [I] Rendered puppetclasses/_classes_parameters.html.erb (21.7ms)
2016-03-19T22:51:38 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:51:38 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:51:38 [app] [I] Completed 200 OK in 65.9ms (Views: 21.1ms | ActiveRecord: 5.6ms)
2016-03-19T22:51:48 [app] [I] Started POST "/hosts/domain_selected" for 172.16.1.106 at 2016-03-19 22:51:48 +0100
2016-03-19T22:51:48 [app] [I] Processing by HostsController#domain_selected as JSON
2016-03-19T22:51:48 [app] [I] Parameters: {"domain_id"=>"1", "interface"=>"true"}
2016-03-19T22:51:48 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:51:48 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:51:48 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:51:48 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:51:48 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:51:48 [app] [I] Completed 200 OK in 11.8ms (Views: 2.8ms | ActiveRecord: 1.4ms)
2016-03-19T22:51:48 [app] [I] Started POST "/subnets/freeip" for 172.16.1.106 at 2016-03-19 22:51:48 +0100
2016-03-19T22:51:48 [app] [I] Processing by SubnetsController#freeip as JSON
2016-03-19T22:51:48 [app] [I] Parameters: {"subnet_id"=>"1", "host_mac"=>"", "taken_ips"=>["", ""]}
2016-03-19T22:51:48 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:51:48 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:51:48 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:51:48 [app] [I] Rendered common/404.html.erb within layouts/application (1.8ms)
2016-03-19T22:51:48 [app] [I] Rendered home/_user_dropdown.html.erb (3.0ms)
2016-03-19T22:51:48 [app] [I] Read fragment views/tabs_and_title_records-4 0.4ms
2016-03-19T22:51:48 [app] [I] Rendered home/_topbar.html.erb (5.1ms)
2016-03-19T22:51:48 [app] [I] Rendered layouts/base.html.erb (9.4ms)
2016-03-19T22:51:48 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:51:48 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:51:48 [app] [I] Completed 404 Not Found in 34.1ms (Views: 14.6ms | ActiveRecord: 2.4ms)
2016-03-19T22:52:06 [app] [I] Started POST "/hosts/architecture_selected" for 172.16.1.106 at 2016-03-19 22:52:06 +0100
2016-03-19T22:52:06 [app] [I] Processing by HostsController#architecture_selected as */*
2016-03-19T22:52:06 [app] [I] Parameters: {"architecture_id"=>"1"}
2016-03-19T22:52:06 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:52:06 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:52:06 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:52:06 [app] [I] Rendered common/os_selection/_architecture.html.erb (9.4ms)
2016-03-19T22:52:06 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:52:06 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:52:06 [app] [I] Completed 200 OK in 38.4ms (Views: 10.5ms | ActiveRecord: 2.6ms)
2016-03-19T22:52:08 [app] [I] Started POST "/hosts/os_selected" for 172.16.1.106 at 2016-03-19 22:52:08 +0100
2016-03-19T22:52:08 [app] [I] Processing by HostsController#os_selected as */*
2016-03-19T22:52:08 [app] [I] Parameters: {"operatingsystem_id"=>"3"}
2016-03-19T22:52:08 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:52:08 [app] [I] Started GET "/compute_resources/1/images?search=operatingsystem%3D3%20architecture%3D1" for 172.16.1.106 at 2016-03-19 22:52:08 +0100
2016-03-19T22:52:08 [app] [I] Processing by ImagesController#index as JSON
2016-03-19T22:52:08 [app] [I] Parameters: {"search"=>"operatingsystem=3 architecture=1", "compute_resource_id"=>"1"}
2016-03-19T22:52:08 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:52:08 [app] [I] Completed 200 OK in 119.6ms (Views: 3.9ms | ActiveRecord: 31.7ms)
2016-03-19T22:52:08 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:52:08 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:52:08 [app] [I] Rendered common/os_selection/_operatingsystem.html.erb (9.1ms)
2016-03-19T22:52:08 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:52:08 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:52:08 [app] [I] Completed 200 OK in 255.6ms (Views: 9.6ms | ActiveRecord: 2.3ms)
2016-03-19T22:52:08 [app] [I] Started POST "/hosts/current_parameters" for 172.16.1.106 at 2016-03-19 22:52:08 +0100
2016-03-19T22:52:08 [app] [I] Processing by HostsController#current_parameters as */*
2016-03-19T22:52:08 [app] [I] Parameters: {"utf8"=>"✓", "authenticity_token"=>"cXiw93lcpKzmWpck+S6siTlZrNPk9ksT9efrYSRe55I=", "host"=>{"name"=>"blah", "hostgroup_id"=>"", "compute_resource_id"=>"1", "compute_profile_id"=>"1", "environment_id"=>"1", "puppet_ca_proxy_id"=>"1", "puppet_proxy_id"=>"1", "puppetclass_ids"=>[""], "managed"=>"true", "progress_report_id"=>"[FILTERED]", "type"=>"Host::Managed", "interfaces_attributes"=>{"0"=>{"_destroy"=>"0", "type"=>"Nic::Managed", "mac"=>"", "identifier"=>"", "name"=>"blah", "domain_id"=>"1", "subnet_id"=>"1", "ip"=>"172.16.1.20", "managed"=>"1", "primary"=>"1", "provision"=>"1", "virtual"=>"0", "tag"=>"", "attached_to"=>"", "compute_attributes"=>{"type"=>"bridge", "bridge"=>"Public", "model"=>"virtio"}}, "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"=>"bridge", "bridge"=>"Jumbo_iSCSI_1", "model"=>"virtio"}}}, "compute_attributes"=>{"cpus"=>"1", "memory"=>"536870912", "start"=>"1", "volumes_attributes"=>{"new_volumes"=>{"_delete"=>"", "pool_name"=>"default", "capacity"=>"10G", "allocation"=>"0G", "format_type"=>"raw"}, "0"=>{"_delete"=>"", "pool_name"=>"VMs", "capacity"=>"12G", "allocation"=>"0G", "format_type"=>"qcow2"}}}, "architecture_id"=>"1", "operatingsystem_id"=>"3", "provision_method"=>"build", "build"=>"1", "medium_id"=>"", "disk"=>"", "root_pass"=>"[FILTERED]", "is_owned_by"=>"4-Users", "enabled"=>"1", "model_id"=>"", "comment"=>"", "overwrite"=>"false"}, "capabilities"=>"build image", "provider"=>"Libvirt", "fakepassword"=>"[FILTERED]", "host_id"=>"null"}
2016-03-19T22:52:08 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:52:08 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:52:08 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:52:08 [app] [I] Rendered common_parameters/_inherited_parameters.html.erb (0.3ms)
2016-03-19T22:52:08 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:52:08 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:52:08 [app] [I] Completed 200 OK in 51.7ms (Views: 1.9ms | ActiveRecord: 4.0ms)
2016-03-19T22:52:10 [app] [I] Started POST "/compute_resources/1-HIVE/template_selected" for 172.16.1.106 at 2016-03-19 22:52:10 +0100
2016-03-19T22:52:10 [app] [I] Processing by ComputeResourcesController#template_selected as */*
2016-03-19T22:52:10 [app] [I] Parameters: {"template_id"=>"/mnt/hive-1/VMs/centos7.qcow2", "id"=>"1-HIVE"}
2016-03-19T22:52:10 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:52:10 [app] [I] Completed 200 OK in 161.7ms (Views: 1.1ms | ActiveRecord: 1.7ms)
2016-03-19T22:52:14 [app] [I] Started GET "/node/evolution.inparadise.se?format=yml" for 172.16.1.11 at 2016-03-19 22:52:14 +0100
2016-03-19T22:52:14 [app] [I] Processing by HostsController#externalNodes as YML
2016-03-19T22:52:14 [app] [I] Parameters: {"name"=>"evolution.inparadise.se"}
2016-03-19T22:52:14 [app] [D] Verifying request from ["puppet.inparadise.se"] against ["puppet.inparadise.se"]
2016-03-19T22:52:14 [app] [D] Setting current user thread-local variable to foreman_api_admin
2016-03-19T22:52:14 [app] [I] Rendered text template (0.0ms)
2016-03-19T22:52:14 [app] [I] Completed 200 OK in 73.4ms (Views: 0.9ms | ActiveRecord: 5.4ms)
2016-03-19T22:52:17 [app] [I] Started POST "/api/hosts/facts" for 172.16.1.11 at 2016-03-19 22:52:17 +0100
2016-03-19T22:52:17 [app] [I] Processing by Api::V2::HostsController#facts as JSON
2016-03-19T22:52:17 [app] [I] Parameters: {"facts"=>"[FILTERED]", "name"=>"evolution.inparadise.se", "certname"=>"evolution.inparadise.se", "apiv"=>"v2", :host=>{"name"=>"evolution.inparadise.se", "certname"=>"evolution.inparadise.se"}}
2016-03-19T22:52:17 [app] [D] Verifying request from ["puppet.inparadise.se"] against ["puppet.inparadise.se"]
2016-03-19T22:52:17 [app] [D] Setting current user thread-local variable to foreman_api_admin
2016-03-19T22:52:17 [app] [D] Merging facts for 'evolution.inparadise.se': deleted 0 facts
2016-03-19T22:52:17 [app] [D] Merging facts for 'evolution.inparadise.se': added 0 facts
2016-03-19T22:52:17 [app] [D] Merging facts for 'evolution.inparadise.se': updated 5 facts
2016-03-19T22:52:17 [app] [I] Import facts for 'evolution.inparadise.se' completed. Added: 0, Updated: 5, Deleted 0 facts
2016-03-19T22:52:17 [app] [D] We have following interfaces 'vtnet0, lo0' based on facts
2016-03-19T22:52:17 [app] [D] Interface vtnet0 facts: {"ipaddress"=>"172.16.1.117", "macaddress"=>"52:54:00:53:04:ac", "netmask"=>"255.255.255.0", "mtu"=>1500, "network"=>"172.16.1.0"}
2016-03-19T22:52:17 [app] [D] Interface lo0 facts: {"ipaddress"=>"127.0.0.1", "netmask"=>"255.0.0.0", "mtu"=>16384, "network"=>"127.0.0.0"}
2016-03-19T22:52:17 [app] [D] Body: {"architecture_id":1,"build":false,"certname":"evolution.inparadise.se","comment":null,"compute_profile_id":null,"compute_resource_id":1,"created_at":"2016-02-28T20:00:27Z","disk":null,"enabled":true,"environment_id":1,"global_status":0,"grub_pass":"","hostgroup_id":null,"id":6,"image_file":"","image_id":null,"installed_at":null,"last_compile":"2016-03-19T21:52:17Z","last_report":"2016-03-19T21:47:13Z","location_id":null,"lookup_value_matcher":"fqdn=evolution.inparadise.se","managed":false,"medium_id":null,"model_id":1,"name":"evolution.inparadise.se","operatingsystem_id":2,"organization_id":null,"otp":null,"owner_id":null,"owner_type":null,"provision_method":"build","ptable_id":null,"puppet_ca_proxy_id":1,"puppet_proxy_id":1,"realm_id":null,"root_pass":null,"updated_at":"2016-03-19T21:52:17Z","use_image":null,"uuid":"7e4970dd-874e-47ad-b050-6342157befce"}
2016-03-19T22:52:17 [app] [I] Completed 201 Created in 278.2ms (Views: 7.3ms | ActiveRecord: 0.2ms)
2016-03-19T22:52:18 [app] [I] Started GET "/node/evolution.inparadise.se?format=yml" for 172.16.1.11 at 2016-03-19 22:52:18 +0100
2016-03-19T22:52:18 [app] [I] Processing by HostsController#externalNodes as YML
2016-03-19T22:52:18 [app] [I] Parameters: {"name"=>"evolution.inparadise.se"}
2016-03-19T22:52:18 [app] [D] Verifying request from ["puppet.inparadise.se"] against ["puppet.inparadise.se"]
2016-03-19T22:52:18 [app] [D] Setting current user thread-local variable to foreman_api_admin
2016-03-19T22:52:18 [app] [I] Rendered text template (0.0ms)
2016-03-19T22:52:18 [app] [I] Completed 200 OK in 71.1ms (Views: 1.0ms | ActiveRecord: 4.9ms)
2016-03-19T22:52:18 [app] [I] Started GET "/node/transformer.inparadise.se?format=yml" for 172.16.1.11 at 2016-03-19 22:52:18 +0100
2016-03-19T22:52:18 [app] [I] Processing by HostsController#externalNodes as YML
2016-03-19T22:52:18 [app] [I] Parameters: {"name"=>"transformer.inparadise.se"}
2016-03-19T22:52:18 [app] [D] Verifying request from ["puppet.inparadise.se"] against ["puppet.inparadise.se"]
2016-03-19T22:52:18 [app] [D] Setting current user thread-local variable to foreman_api_admin
2016-03-19T22:52:18 [app] [I] Rendered text template (0.0ms)
2016-03-19T22:52:18 [app] [I] Completed 200 OK in 81.9ms (Views: 1.1ms | ActiveRecord: 5.9ms)
2016-03-19T22:52:21 [app] [I] Started POST "/hosts/template_used?provisioning=image" for 172.16.1.106 at 2016-03-19 22:52:21 +0100
2016-03-19T22:52:21 [app] [I] Processing by HostsController#template_used as */*
2016-03-19T22:52:21 [app] [I] Parameters: {"utf8"=>"✓", "authenticity_token"=>"cXiw93lcpKzmWpck+S6siTlZrNPk9ksT9efrYSRe55I=", "host"=>{"name"=>"blah", "hostgroup_id"=>"", "compute_resource_id"=>"1", "compute_profile_id"=>"1", "environment_id"=>"1", "puppet_ca_proxy_id"=>"1", "puppet_proxy_id"=>"1", "puppetclass_ids"=>[""], "managed"=>"true", "progress_report_id"=>"[FILTERED]", "type"=>"Host::Managed", "interfaces_attributes"=>{"0"=>{"_destroy"=>"0", "type"=>"Nic::Managed", "mac"=>"", "identifier"=>"", "name"=>"blah", "domain_id"=>"1", "subnet_id"=>"1", "ip"=>"172.16.1.20", "managed"=>"1", "primary"=>"1", "provision"=>"1", "virtual"=>"0", "tag"=>"", "attached_to"=>"", "compute_attributes"=>{"type"=>"bridge", "bridge"=>"Public", "model"=>"virtio"}}, "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"=>"bridge", "bridge"=>"Jumbo_iSCSI_1", "model"=>"virtio"}}}, "compute_attributes"=>{"cpus"=>"1", "memory"=>"536870912", "start"=>"1", "volumes_attributes"=>{"new_volumes"=>{"_delete"=>"", "pool_name"=>"default", "capacity"=>"10G", "allocation"=>"0G", "format_type"=>"raw"}, "0"=>{"_delete"=>"", "pool_name"=>"VMs", "capacity"=>"12G", "allocation"=>"0G", "format_type"=>"qcow2"}}, "image_id"=>"/mnt/hive-1/VMs/centos7.qcow2"}, "architecture_id"=>"1", "operatingsystem_id"=>"3", "provision_method"=>"image", "build"=>"1", "medium_id"=>"", "disk"=>"", "root_pass"=>"[FILTERED]", "is_owned_by"=>"4-Users", "enabled"=>"1", "model_id"=>"", "comment"=>"", "overwrite"=>"false"}, "capabilities"=>"build image", "provider"=>"Libvirt", "fakepassword"=>"[FILTERED]", "provisioning"=>"image"}
2016-03-19T22:52:21 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:52:21 [app] [I] Rendered hosts/_provisioning.html.erb (2.9ms)
2016-03-19T22:52:21 [app] [I] Completed 200 OK in 32.9ms (Views: 3.9ms | ActiveRecord: 2.9ms)
2016-03-19T22:52:21 [app] [I] Started POST "/api/hosts/facts" for 172.16.1.11 at 2016-03-19 22:52:21 +0100
2016-03-19T22:52:21 [app] [I] Processing by Api::V2::HostsController#facts as JSON
2016-03-19T22:52:21 [app] [I] Parameters: {"facts"=>"[FILTERED]", "name"=>"transformer.inparadise.se", "certname"=>"transformer.inparadise.se", "apiv"=>"v2", :host=>{"name"=>"transformer.inparadise.se", "certname"=>"transformer.inparadise.se"}}
2016-03-19T22:52:21 [app] [D] Verifying request from ["puppet.inparadise.se"] against ["puppet.inparadise.se"]
2016-03-19T22:52:21 [app] [D] Setting current user thread-local variable to foreman_api_admin
2016-03-19T22:52:21 [app] [D] Merging facts for 'transformer.inparadise.se': deleted 0 facts
2016-03-19T22:52:22 [app] [D] Merging facts for 'transformer.inparadise.se': added 0 facts
2016-03-19T22:52:22 [app] [D] Merging facts for 'transformer.inparadise.se': updated 5 facts
2016-03-19T22:52:22 [app] [I] Import facts for 'transformer.inparadise.se' completed. Added: 0, Updated: 5, Deleted 0 facts
2016-03-19T22:52:22 [app] [D] We have following interfaces 'vtnet0, lo0' based on facts
2016-03-19T22:52:22 [app] [D] Interface vtnet0 facts: {"ipaddress"=>"172.16.1.15", "macaddress"=>"52:54:00:64:75:80", "netmask"=>"255.255.255.0", "mtu"=>1500, "network"=>"172.16.1.0"}
2016-03-19T22:52:22 [app] [D] Interface lo0 facts: {"ipaddress"=>"127.0.0.1", "netmask"=>"255.0.0.0", "mtu"=>16384, "network"=>"127.0.0.0"}
2016-03-19T22:52:22 [app] [D] Body: {"architecture_id":1,"build":false,"certname":"transformer.inparadise.se","comment":null,"compute_profile_id":null,"compute_resource_id":1,"created_at":"2016-02-28T19:59:55Z","disk":null,"enabled":true,"environment_id":1,"global_status":0,"grub_pass":"","hostgroup_id":null,"id":2,"image_file":"","image_id":null,"installed_at":null,"last_compile":"2016-03-19T21:52:21Z","last_report":"2016-03-19T21:47:17Z","location_id":null,"lookup_value_matcher":"fqdn=transformer.inparadise.se","managed":false,"medium_id":null,"model_id":1,"name":"transformer.inparadise.se","operatingsystem_id":2,"organization_id":null,"otp":null,"owner_id":null,"owner_type":null,"provision_method":"build","ptable_id":null,"puppet_ca_proxy_id":1,"puppet_proxy_id":1,"realm_id":null,"root_pass":null,"updated_at":"2016-03-19T21:52:22Z","use_image":null,"uuid":"424e05fd-adc2-4cda-b6a6-79d4d655552e"}
2016-03-19T22:52:22 [app] [I] Completed 201 Created in 245.2ms (Views: 8.1ms | ActiveRecord: 0.2ms)
2016-03-19T22:52:22 [app] [I] Started GET "/node/transformer.inparadise.se?format=yml" for 172.16.1.11 at 2016-03-19 22:52:22 +0100
2016-03-19T22:52:22 [app] [I] Processing by HostsController#externalNodes as YML
2016-03-19T22:52:22 [app] [I] Parameters: {"name"=>"transformer.inparadise.se"}
2016-03-19T22:52:22 [app] [D] Verifying request from ["puppet.inparadise.se"] against ["puppet.inparadise.se"]
2016-03-19T22:52:22 [app] [D] Setting current user thread-local variable to foreman_api_admin
2016-03-19T22:52:22 [app] [I] Rendered text template (0.0ms)
2016-03-19T22:52:22 [app] [I] Completed 200 OK in 72.6ms (Views: 0.9ms | ActiveRecord: 5.3ms)
2016-03-19T22:52:25 [app] [I] Started POST "/api/reports" for 172.16.1.11 at 2016-03-19 22:52:25 +0100
2016-03-19T22:52:25 [app] [I] Processing by Api::V2::ReportsController#create as JSON
2016-03-19T22:52:25 [app] [I] Parameters: {"report"=>"[FILTERED]", "apiv"=>"v2"}
2016-03-19T22:52:25 [app] [D] Verifying request from ["puppet.inparadise.se"] against ["puppet.inparadise.se"]
2016-03-19T22:52:25 [app] [D] Setting current user thread-local variable to foreman_api_admin
2016-03-19T22:52:25 [app] [I] processing report for evolution.inparadise.se
2016-03-19T22:52:25 [app] [D] Report: {"host"=>"evolution.inparadise.se", "reported_at"=>"2016-03-19 21:52:13 UTC", "status"=>{"applied"=>0, "restarted"=>0, "failed"=>0, "failed_restarts"=>0, "skipped"=>1, "pending"=>0}, "metrics"=>{"resources"=>{"changed"=>0, "failed"=>0, "failed_to_restart"=>0, "out_of_sync"=>0, "restarted"=>0, "scheduled"=>0, "skipped"=>1, "total"=>33}, "time"=>{"config_retrieval"=>1.526560802, "exec"=>6.466285344, "file"=>0.21886860200000002, "filebucket"=>0.00036736500000000003, "schedule"=>0.0011493080000000001, "service"=>0.172492318, "total"=>8.385723739}, "changes"=>{"total"=>0}, "events"=>{"failure"=>0, "success"=>0, "total"=>0}}, "logs"=>nil}
2016-03-19T22:52:25 [app] [I] Imported report for evolution.inparadise.se in 0.05 seconds
2016-03-19T22:52:25 [app] [I] Rendered api/v2/reports/create.json.rabl (14.1ms)
2016-03-19T22:52:25 [app] [D] Body: {"metrics":{"resources":{"changed":0,"failed":0,"failed_to_restart":0,"out_of_sync":0,"restarted":0,"scheduled":0,"skipped":1,"total":33},"time":{"config_retrieval":1.526560802,"exec":6.466285344,"file":0.21886860200000002,"filebucket":0.00036736500000000003,"schedule":0.0011493080000000001,"service":0.172492318,"total":8.385723739},"changes":{"total":0},"events":{"failure":0,"success":0,"total":0}},"created_at":"2016-03-19T21:52:25Z","updated_at":"2016-03-19T21:52:25Z","id":43308,"host_id":6,"host_name":"evolution.inparadise.se","reported_at":"2016-03-19T21:52:13Z","status":{"applied":0,"restarted":0,"failed":0,"failed_restarts":0,"skipped":1,"pending":0},"logs":[],"summary":"Success"}
2016-03-19T22:52:25 [app] [I] Completed 201 Created in 99.1ms (Views: 14.6ms | ActiveRecord: 27.9ms)
2016-03-19T22:52:30 [app] [I] Started POST "/api/reports" for 172.16.1.11 at 2016-03-19 22:52:30 +0100
2016-03-19T22:52:30 [app] [I] Processing by Api::V2::ReportsController#create as JSON
2016-03-19T22:52:30 [app] [I] Parameters: {"report"=>"[FILTERED]", "apiv"=>"v2"}
2016-03-19T22:52:30 [app] [D] Verifying request from ["puppet.inparadise.se"] against ["puppet.inparadise.se"]
2016-03-19T22:52:30 [app] [D] Setting current user thread-local variable to foreman_api_admin
2016-03-19T22:52:30 [app] [I] processing report for transformer.inparadise.se
2016-03-19T22:52:30 [app] [D] Report: {"host"=>"transformer.inparadise.se", "reported_at"=>"2016-03-19 21:52:17 UTC", "status"=>{"applied"=>0, "restarted"=>0, "failed"=>0, "failed_restarts"=>0, "skipped"=>1, "pending"=>0}, "metrics"=>{"resources"=>{"changed"=>0, "failed"=>0, "failed_to_restart"=>0, "out_of_sync"=>0, "restarted"=>0, "scheduled"=>0, "skipped"=>1, "total"=>35}, "time"=>{"config_retrieval"=>1.308820585, "exec"=>6.4530653760000005, "file"=>0.26144050199999996, "filebucket"=>0.000727187, "schedule"=>0.0014314660000000002, "service"=>0.32641999, "total"=>8.351905106}, "changes"=>{"total"=>0}, "events"=>{"failure"=>0, "success"=>0, "total"=>0}}, "logs"=>nil}
2016-03-19T22:52:30 [app] [I] Imported report for transformer.inparadise.se in 0.03 seconds
2016-03-19T22:52:30 [app] [I] Rendered api/v2/reports/create.json.rabl (7.3ms)
2016-03-19T22:52:30 [app] [D] Body: {"metrics":{"resources":{"changed":0,"failed":0,"failed_to_restart":0,"out_of_sync":0,"restarted":0,"scheduled":0,"skipped":1,"total":35},"time":{"config_retrieval":1.308820585,"exec":6.4530653760000005,"file":0.26144050199999996,"filebucket":0.000727187,"schedule":0.0014314660000000002,"service":0.32641999,"total":8.351905106},"changes":{"total":0},"events":{"failure":0,"success":0,"total":0}},"created_at":"2016-03-19T21:52:30Z","updated_at":"2016-03-19T21:52:30Z","id":43309,"host_id":2,"host_name":"transformer.inparadise.se","reported_at":"2016-03-19T21:52:17Z","status":{"applied":0,"restarted":0,"failed":0,"failed_restarts":0,"skipped":1,"pending":0},"logs":[],"summary":"Success"}
2016-03-19T22:52:30 [app] [I] Completed 201 Created in 56.0ms (Views: 7.5ms | ActiveRecord: 17.7ms)
2016-03-19T22:52:32 [app] [I] Started POST "/hosts" for 172.16.1.106 at 2016-03-19 22:52:32 +0100
2016-03-19T22:52:32 [app] [I] Processing by HostsController#create as */*
2016-03-19T22:52:32 [app] [I] Parameters: {"utf8"=>"✓", "authenticity_token"=>"cXiw93lcpKzmWpck+S6siTlZrNPk9ksT9efrYSRe55I=", "host"=>{"name"=>"blah", "hostgroup_id"=>"", "compute_resource_id"=>"1", "compute_profile_id"=>"1", "environment_id"=>"1", "puppet_ca_proxy_id"=>"1", "puppet_proxy_id"=>"1", "puppetclass_ids"=>[""], "managed"=>"true", "progress_report_id"=>"[FILTERED]", "type"=>"Host::Managed", "interfaces_attributes"=>{"0"=>{"_destroy"=>"0", "type"=>"Nic::Managed", "mac"=>"", "identifier"=>"", "name"=>"blah", "domain_id"=>"1", "subnet_id"=>"1", "ip"=>"172.16.1.20", "managed"=>"1", "primary"=>"1", "provision"=>"1", "virtual"=>"0", "tag"=>"", "attached_to"=>"", "compute_attributes"=>{"type"=>"bridge", "bridge"=>"Public", "model"=>"virtio"}}}, "compute_attributes"=>{"cpus"=>"1", "memory"=>"536870912", "start"=>"1", "volumes_attributes"=>{"0"=>{"_delete"=>"", "pool_name"=>"VMs", "capacity"=>"12G", "allocation"=>"0G", "format_type"=>"qcow2"}}, "image_id"=>"/mnt/hive-1/VMs/centos7.qcow2"}, "architecture_id"=>"1", "operatingsystem_id"=>"3", "provision_method"=>"image", "build"=>"1", "medium_id"=>"", "disk"=>"", "root_pass"=>"[FILTERED]", "is_owned_by"=>"4-Users", "enabled"=>"1", "model_id"=>"", "comment"=>"", "overwrite"=>"false"}, "capabilities"=>"build image", "provider"=>"Libvirt"}
2016-03-19T22:52:32 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:52:34 [app] [I] Started GET "/tasks/5868d9b6-2350-4551-b31c-6b085eccf604" for 172.16.1.106 at 2016-03-19 22:52:34 +0100
2016-03-19T22:52:34 [app] [I] Processing by TasksController#show as */*
2016-03-19T22:52:34 [app] [I] Parameters: {"id"=>"5868d9b6-2350-4551-b31c-6b085eccf604"}
2016-03-19T22:52:34 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:52:34 [app] [I] Rendered tasks/_list.html.erb (0.4ms)
2016-03-19T22:52:34 [app] [I] Completed 200 OK in 7.7ms (Views: 1.2ms | ActiveRecord: 0.6ms)
2016-03-19T22:52:35 [app] [W] Unhandled Libvirt error
| Fog::Errors::Error: Error saving the server: undefined method `Error' for Fog::Errors:Module
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/fog-libvirt-0.0.3/lib/fog/libvirt/models/compute/server.rb:67:in `rescue in save'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/fog-libvirt-0.0.3/lib/fog/libvirt/models/compute/server.rb:60:in `save'
| /usr/local/share/foreman/app/models/compute_resources/foreman/model/libvirt.rb:125:in `create_vm'
| /usr/local/share/foreman/app/models/concerns/orchestration/compute.rb:76:in `setCompute'
| /usr/local/share/foreman/app/models/concerns/orchestration.rb:168:in `execute'
| /usr/local/share/foreman/app/models/concerns/orchestration.rb:113:in `block in process'
| /usr/local/share/foreman/app/models/concerns/orchestration.rb:105:in `each'
| /usr/local/share/foreman/app/models/concerns/orchestration.rb:105:in `process'
| /usr/local/share/foreman/app/models/concerns/orchestration.rb:35:in `on_save'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:671:in `_run__3463928257020795376__save__1520798254074918870__callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:405:in `__run_callback'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:385:in `_run_save_callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:81:in `run_callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/callbacks.rb:264:in `create_or_update'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/persistence.rb:84:in `save'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/validations.rb:50:in `save'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/attribute_methods/dirty.rb:22:in `save'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/transactions.rb:259:in `block (2 levels) in save'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/transactions.rb:313:in `block in with_transaction_returning_status'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/connection_adapters/abstract/database_statements.rb:192:in `transaction'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/transactions.rb:208:in `transaction'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/transactions.rb:311:in `with_transaction_returning_status'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/transactions.rb:259:in `block in save'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/transactions.rb:270:in `rollback_active_record_state!'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/transactions.rb:258:in `save'
| /usr/local/share/foreman/app/models/concerns/foreman/sti.rb:29:in `save_with_type'
| /usr/local/share/foreman/app/controllers/hosts_controller.rb:85:in `create'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal/implicit_render.rb:4:in `send_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/abstract_controller/base.rb:167:in `process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal/rendering.rb:10:in `process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/abstract_controller/callbacks.rb:18:in `block in process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:627:in `block (4 levels) in _run__1719681225941926187__process_action__138088269260041299__callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7486'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:326:in `around'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:310:in `_callback_around_1817'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7486'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:626:in `block (3 levels) in _run__1719681225941926187__process_action__138088269260041299__callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7485'
| /usr/local/share/foreman/app/controllers/concerns/application_shared.rb:13:in `set_timezone'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7485'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:625:in `block (2 levels) in _run__1719681225941926187__process_action__138088269260041299__callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7484'
| /usr/local/share/foreman/app/models/concerns/foreman/thread_session.rb:32:in `clear_thread'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7484'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:448:in `block in _run__1719681225941926187__process_action__138088269260041299__callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7483'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:326:in `around'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:310:in `_callback_around_13'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7483'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:414:in `_run__1719681225941926187__process_action__138088269260041299__callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:405:in `__run_callback'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:81:in `run_callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/abstract_controller/callbacks.rb:17:in `process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal/rescue.rb:29:in `process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/notifications.rb:123:in `block in instrument'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/notifications/instrumenter.rb:20:in `instrument'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/notifications.rb:123:in `instrument'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal/instrumentation.rb:29:in `process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/railties/controller_runtime.rb:18:in `process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/abstract_controller/base.rb:121:in `process'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/abstract_controller/rendering.rb:45:in `process'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal.rb:203:in `dispatch'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal.rb:246:in `block in action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/routing/route_set.rb:73:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/routing/route_set.rb:36:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/routing/route_set.rb:608:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/apipie-rails-0.2.6/lib/apipie/static_dispatcher.rb:65:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-openid-1.4.2/lib/rack/openid.rb:98:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/apipie-rails-0.2.6/lib/apipie/extractor/recorder.rb:97:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/apipie-rails-0.2.6/lib/apipie/middleware/checksum_in_headers.rb:27:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/etag.rb:23:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/conditionalget.rb:35:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/head.rb:14:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/params_parser.rb:21:in `call'
| /usr/local/share/foreman/lib/middleware/catch_json_parse_errors.rb:9:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/flash.rb:242:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/session/abstract/id.rb:210:in `context'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/session/abstract/id.rb:205:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/cookies.rb:341:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/query_cache.rb:64:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:405:in `_run__1792767919261819373__call__1520798254074918870__callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:405:in `__run_callback'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:81:in `run_callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/callbacks.rb:27:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/railties-3.2.21/lib/rails/rack/logger.rb:32:in `call_app'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/railties-3.2.21/lib/rails/rack/logger.rb:18:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/request_id.rb:22:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/methodoverride.rb:21:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/runtime.rb:17:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/cache/strategy/local_cache.rb:72:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/lock.rb:15:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/static.rb:83:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-cache-1.2/lib/rack/cache/context.rb:136:in `forward'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-cache-1.2/lib/rack/cache/context.rb:143:in `pass'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-cache-1.2/lib/rack/cache/context.rb:155:in `invalidate'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-cache-1.2/lib/rack/cache/context.rb:71:in `call!'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-cache-1.2/lib/rack/cache/context.rb:51:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/railties-3.2.21/lib/rails/engine.rb:484:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/railties-3.2.21/lib/rails/application.rb:231:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/railties-3.2.21/lib/rails/railtie/configurable.rb:30:in `method_missing'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/builder.rb:134:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/urlmap.rb:64:in `block in call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/urlmap.rb:49:in `each'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/urlmap.rb:49:in `call'
| /usr/local/lib/ruby/gems/2.0/gems/passenger-5.0.26/src/ruby_supportlib/phusion_passenger/rack/thread_handler_extension.rb:97:in `process_request'
| /usr/local/lib/ruby/gems/2.0/gems/passenger-5.0.26/src/ruby_supportlib/phusion_passenger/request_handler/thread_handler.rb:160:in `accept_and_process_next_request'
| /usr/local/lib/ruby/gems/2.0/gems/passenger-5.0.26/src/ruby_supportlib/phusion_passenger/request_handler/thread_handler.rb:113:in `main_loop'
| /usr/local/lib/ruby/gems/2.0/gems/passenger-5.0.26/src/ruby_supportlib/phusion_passenger/request_handler.rb:416:in `block (3 levels) in start_threads'
| /usr/local/lib/ruby/gems/2.0/gems/passenger-5.0.26/src/ruby_supportlib/phusion_passenger/utils.rb:113:in `block in create_thread_and_abort_on_exception'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/logging-2.0.0/lib/logging/diagnostic_context.rb:448:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/logging-2.0.0/lib/logging/diagnostic_context.rb:448:in `block in create_with_logging_context'
2016-03-19T22:52:35 [app] [W] Failed to create a compute HIVE (Libvirt) instance blah.inparadise.se: no implicit conversion of nil into String
|
| TypeError: no implicit conversion of nil into String
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/fog-libvirt-0.0.3/lib/fog/libvirt/requests/compute/list_domains.rb:9:in `lookup_domain_by_uuid'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/fog-libvirt-0.0.3/lib/fog/libvirt/requests/compute/list_domains.rb:9:in `list_domains'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/fog-libvirt-0.0.3/lib/fog/libvirt/models/compute/servers.rb:15:in `get'
| /usr/local/share/foreman/app/models/compute_resource.rb:137:in `find_vm_by_uuid'
| /usr/local/share/foreman/app/models/compute_resources/foreman/model/libvirt.rb:33:in `find_vm_by_uuid'
| /usr/local/share/foreman/app/models/compute_resources/foreman/model/libvirt.rb:41:in `destroy_vm'
| /usr/local/share/foreman/app/models/compute_resources/foreman/model/libvirt.rb:128:in `rescue in create_vm'
| /usr/local/share/foreman/app/models/compute_resources/foreman/model/libvirt.rb:123:in `create_vm'
| /usr/local/share/foreman/app/models/concerns/orchestration/compute.rb:76:in `setCompute'
| /usr/local/share/foreman/app/models/concerns/orchestration.rb:168:in `execute'
| /usr/local/share/foreman/app/models/concerns/orchestration.rb:113:in `block in process'
| /usr/local/share/foreman/app/models/concerns/orchestration.rb:105:in `each'
| /usr/local/share/foreman/app/models/concerns/orchestration.rb:105:in `process'
| /usr/local/share/foreman/app/models/concerns/orchestration.rb:35:in `on_save'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:671:in `_run__3463928257020795376__save__1520798254074918870__callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:405:in `__run_callback'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:385:in `_run_save_callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:81:in `run_callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/callbacks.rb:264:in `create_or_update'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/persistence.rb:84:in `save'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/validations.rb:50:in `save'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/attribute_methods/dirty.rb:22:in `save'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/transactions.rb:259:in `block (2 levels) in save'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/transactions.rb:313:in `block in with_transaction_returning_status'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/connection_adapters/abstract/database_statements.rb:192:in `transaction'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/transactions.rb:208:in `transaction'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/transactions.rb:311:in `with_transaction_returning_status'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/transactions.rb:259:in `block in save'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/transactions.rb:270:in `rollback_active_record_state!'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/transactions.rb:258:in `save'
| /usr/local/share/foreman/app/models/concerns/foreman/sti.rb:29:in `save_with_type'
| /usr/local/share/foreman/app/controllers/hosts_controller.rb:85:in `create'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal/implicit_render.rb:4:in `send_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/abstract_controller/base.rb:167:in `process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal/rendering.rb:10:in `process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/abstract_controller/callbacks.rb:18:in `block in process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:627:in `block (4 levels) in _run__1719681225941926187__process_action__138088269260041299__callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7486'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:326:in `around'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:310:in `_callback_around_1817'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7486'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:626:in `block (3 levels) in _run__1719681225941926187__process_action__138088269260041299__callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7485'
| /usr/local/share/foreman/app/controllers/concerns/application_shared.rb:13:in `set_timezone'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7485'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:625:in `block (2 levels) in _run__1719681225941926187__process_action__138088269260041299__callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7484'
| /usr/local/share/foreman/app/models/concerns/foreman/thread_session.rb:32:in `clear_thread'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7484'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:448:in `block in _run__1719681225941926187__process_action__138088269260041299__callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7483'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:326:in `around'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:310:in `_callback_around_13'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7483'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:414:in `_run__1719681225941926187__process_action__138088269260041299__callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:405:in `__run_callback'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:81:in `run_callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/abstract_controller/callbacks.rb:17:in `process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal/rescue.rb:29:in `process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/notifications.rb:123:in `block in instrument'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/notifications/instrumenter.rb:20:in `instrument'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/notifications.rb:123:in `instrument'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal/instrumentation.rb:29:in `process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/railties/controller_runtime.rb:18:in `process_action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/abstract_controller/base.rb:121:in `process'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/abstract_controller/rendering.rb:45:in `process'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal.rb:203:in `dispatch'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_controller/metal.rb:246:in `block in action'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/routing/route_set.rb:73:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/routing/route_set.rb:36:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/routing/route_set.rb:608:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/apipie-rails-0.2.6/lib/apipie/static_dispatcher.rb:65:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-openid-1.4.2/lib/rack/openid.rb:98:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/apipie-rails-0.2.6/lib/apipie/extractor/recorder.rb:97:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/apipie-rails-0.2.6/lib/apipie/middleware/checksum_in_headers.rb:27:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/etag.rb:23:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/conditionalget.rb:35:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/head.rb:14:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/params_parser.rb:21:in `call'
| /usr/local/share/foreman/lib/middleware/catch_json_parse_errors.rb:9:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/flash.rb:242:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/session/abstract/id.rb:210:in `context'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/session/abstract/id.rb:205:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/cookies.rb:341:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/query_cache.rb:64:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activerecord-3.2.21/lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:405:in `_run__1792767919261819373__call__1520798254074918870__callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:405:in `__run_callback'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:81:in `run_callbacks'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/callbacks.rb:27:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/railties-3.2.21/lib/rails/rack/logger.rb:32:in `call_app'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/railties-3.2.21/lib/rails/rack/logger.rb:18:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/request_id.rb:22:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/methodoverride.rb:21:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/runtime.rb:17:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/activesupport-3.2.21/lib/active_support/cache/strategy/local_cache.rb:72:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/lock.rb:15:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/actionpack-3.2.21/lib/action_dispatch/middleware/static.rb:83:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-cache-1.2/lib/rack/cache/context.rb:136:in `forward'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-cache-1.2/lib/rack/cache/context.rb:143:in `pass'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-cache-1.2/lib/rack/cache/context.rb:155:in `invalidate'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-cache-1.2/lib/rack/cache/context.rb:71:in `call!'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-cache-1.2/lib/rack/cache/context.rb:51:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/railties-3.2.21/lib/rails/engine.rb:484:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/railties-3.2.21/lib/rails/application.rb:231:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/railties-3.2.21/lib/rails/railtie/configurable.rb:30:in `method_missing'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/builder.rb:134:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/urlmap.rb:64:in `block in call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/urlmap.rb:49:in `each'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/rack-1.4.7/lib/rack/urlmap.rb:49:in `call'
| /usr/local/lib/ruby/gems/2.0/gems/passenger-5.0.26/src/ruby_supportlib/phusion_passenger/rack/thread_handler_extension.rb:97:in `process_request'
| /usr/local/lib/ruby/gems/2.0/gems/passenger-5.0.26/src/ruby_supportlib/phusion_passenger/request_handler/thread_handler.rb:160:in `accept_and_process_next_request'
| /usr/local/lib/ruby/gems/2.0/gems/passenger-5.0.26/src/ruby_supportlib/phusion_passenger/request_handler/thread_handler.rb:113:in `main_loop'
| /usr/local/lib/ruby/gems/2.0/gems/passenger-5.0.26/src/ruby_supportlib/phusion_passenger/request_handler.rb:416:in `block (3 levels) in start_threads'
| /usr/local/lib/ruby/gems/2.0/gems/passenger-5.0.26/src/ruby_supportlib/phusion_passenger/utils.rb:113:in `block in create_thread_and_abort_on_exception'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/logging-2.0.0/lib/logging/diagnostic_context.rb:448:in `call'
| /usr/local/share/foreman/vendor/bundle/ruby/2.0/gems/logging-2.0.0/lib/logging/diagnostic_context.rb:448:in `block in create_with_logging_context'
2016-03-19T22:52:36 [app] [I] Started GET "/tasks/5868d9b6-2350-4551-b31c-6b085eccf604" for 172.16.1.106 at 2016-03-19 22:52:36 +0100
2016-03-19T22:52:36 [app] [I] Processing by TasksController#show as */*
2016-03-19T22:52:36 [app] [I] Parameters: {"id"=>"5868d9b6-2350-4551-b31c-6b085eccf604"}
2016-03-19T22:52:36 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:52:36 [app] [I] Rendered tasks/_list.html.erb (0.5ms)
2016-03-19T22:52:36 [app] [I] Completed 200 OK in 8.4ms (Views: 1.2ms | ActiveRecord: 0.8ms)
2016-03-19T22:52:37 [app] [I] Failed to save: Failed to create a compute HIVE (Libvirt) instance blah.inparadise.se: no implicit conversion of nil into String
|
2016-03-19T22:52:37 [app] [I] Rendered hosts/_progress.html.erb (0.2ms)
2016-03-19T22:52:37 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:52:37 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:52:37 [app] [I] Rendered puppetclasses/_selectedClasses.html.erb (0.0ms)
2016-03-19T22:52:37 [app] [I] Rendered puppetclasses/_classes_in_groups.html.erb (0.0ms)
2016-03-19T22:52:37 [app] [I] Rendered puppetclasses/_classes.html.erb (46.2ms)
2016-03-19T22:52:37 [app] [I] Rendered puppetclasses/_class_selection.html.erb (69.8ms)
2016-03-19T22:52:37 [app] [I] Rendered nic/_base_form.html.erb (12.2ms)
2016-03-19T22:52:37 [app] [I] Rendered nic/_virtual_form.html.erb (1.0ms)
2016-03-19T22:52:37 [app] [I] Rendered compute_resources_vms/form/libvirt/_network.html.erb (341.0ms)
2016-03-19T22:52:37 [app] [I] Rendered nic/_provider_specific_form.html.erb (343.2ms)
2016-03-19T22:52:37 [app] [I] Rendered nic/manageds/_managed.html.erb (360.3ms)
2016-03-19T22:52:37 [app] [I] Rendered nic/_base_form.html.erb (26.3ms)
2016-03-19T22:52:37 [app] [I] Rendered nic/_virtual_form.html.erb (2.4ms)
2016-03-19T22:52:37 [app] [I] Started GET "/tasks/5868d9b6-2350-4551-b31c-6b085eccf604" for 172.16.1.106 at 2016-03-19 22:52:37 +0100
2016-03-19T22:52:38 [app] [I] Processing by TasksController#show as */*
2016-03-19T22:52:38 [app] [I] Parameters: {"id"=>"5868d9b6-2350-4551-b31c-6b085eccf604"}
2016-03-19T22:52:38 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:52:38 [app] [I] Rendered tasks/_list.html.erb (0.6ms)
2016-03-19T22:52:38 [app] [I] Completed 200 OK in 11.9ms (Views: 1.7ms | ActiveRecord: 1.0ms)
2016-03-19T22:52:38 [app] [I] Rendered compute_resources_vms/form/libvirt/_network.html.erb (389.5ms)
2016-03-19T22:52:38 [app] [I] Rendered nic/_provider_specific_form.html.erb (391.6ms)
2016-03-19T22:52:38 [app] [I] Rendered nic/manageds/_managed.html.erb (425.9ms)
2016-03-19T22:52:38 [app] [I] Rendered hosts/_interfaces.html.erb (791.0ms)
2016-03-19T22:52:38 [app] [I] Rendered compute_resources_vms/form/libvirt/_base.html.erb (148.7ms)
2016-03-19T22:52:38 [app] [I] Rendered compute_resources_vms/form/libvirt/_volume.html.erb (217.3ms)
2016-03-19T22:52:39 [app] [I] Rendered compute_resources_vms/form/libvirt/_volume.html.erb (217.5ms)
2016-03-19T22:52:39 [app] [I] Rendered compute_resources_vms/form/_volumes.html.erb (498.0ms)
2016-03-19T22:52:39 [app] [I] Rendered hosts/_compute_detail.html.erb (648.2ms)
2016-03-19T22:52:39 [app] [I] Rendered hosts/_compute.html.erb (879.6ms)
2016-03-19T22:52:39 [app] [I] Rendered common/os_selection/_architecture.html.erb (4.7ms)
2016-03-19T22:52:39 [app] [I] Rendered common/os_selection/_operatingsystem.html.erb (8.5ms)
2016-03-19T22:52:39 [app] [I] Rendered hosts/_operating_system.html.erb (20.5ms)
2016-03-19T22:52:39 [app] [I] Rendered hosts/_unattended.html.erb (901.0ms)
2016-03-19T22:52:39 [app] [I] Rendered puppetclasses/_class_parameters.html.erb (0.0ms)
2016-03-19T22:52:39 [app] [I] Rendered puppetclasses/_classes_parameters.html.erb (7.2ms)
2016-03-19T22:52:39 [app] [I] Rendered common_parameters/_puppetclass_parameter.html.erb (2.2ms)
2016-03-19T22:52:39 [app] [I] Rendered common_parameters/_puppetclasses_parameters.html.erb (5.1ms)
2016-03-19T22:52:39 [app] [I] Rendered common_parameters/_inherited_parameters.html.erb (0.1ms)
2016-03-19T22:52:39 [app] [I] Rendered common_parameters/_parameter.html.erb (1.6ms)
2016-03-19T22:52:39 [app] [I] Rendered common_parameters/_parameters.html.erb (3.7ms)
2016-03-19T22:52:39 [app] [D] Setting current location thread-local variable to none
2016-03-19T22:52:39 [app] [D] Setting current organization thread-local variable to none
2016-03-19T22:52:39 [app] [I] Rendered hosts/_form.html.erb (1843.5ms)
2016-03-19T22:52:39 [app] [I] Rendered hosts/new.html.erb within layouts/application (1844.4ms)
2016-03-19T22:52:39 [app] [I] Rendered home/_user_dropdown.html.erb (1.5ms)
2016-03-19T22:52:39 [app] [I] Read fragment views/tabs_and_title_records-4 0.2ms
2016-03-19T22:52:39 [app] [I] Rendered home/_topbar.html.erb (3.0ms)
2016-03-19T22:52:39 [app] [I] Rendered layouts/base.html.erb (5.2ms)
2016-03-19T22:52:39 [app] [I] Completed 200 OK in 6302.5ms (Views: 1844.0ms | ActiveRecord: 18.7ms)
2016-03-19T22:52:40 [app] [I] Started GET "/tasks/5868d9b6-2350-4551-b31c-6b085eccf604" for 172.16.1.106 at 2016-03-19 22:52:40 +0100
2016-03-19T22:52:40 [app] [I] Processing by TasksController#show as */*
2016-03-19T22:52:40 [app] [I] Parameters: {"id"=>"5868d9b6-2350-4551-b31c-6b085eccf604"}
2016-03-19T22:52:40 [app] [D] Setting current user thread-local variable to karliadm
2016-03-19T22:52:40 [app] [I] Rendered tasks/_list.html.erb (0.9ms)
2016-03-19T22:52:40 [app] [I] Completed 200 OK in 16.6ms (Views: 2.5ms | ActiveRecord: 1.2ms)
(1-1/3)