Foreman GUI long loading times when editing a host

Mmiloudi · October 5, 2015, 1:26pm

Hi everyone,

I am trying to diagnose long loading times when accessing the Edit section
of a given host :

<https://lh3.googleusercontent.com/-0Ot3J-Zcovo/VhJq3ELsR5I/AAAAAAAAAAM/Op9qQGM44Is/s1600/foreman_host_edit.png>

When I try to access the Edit section, I notice up to 50s of delay :

2015-10-05 11:11:24 [I] Started GET "/hosts/server1.example.com/edit" for
192.168.2.27 at 2015-10-05 11:11:24 +0200
2015-10-05 11:11:24 [I] Processing by HostsController#edit as HTML
2015-10-05 11:11:24 [I] Parameters: {"id"=>"server1.example.com"}
2015-10-05 11:11:25 [I] Rendered hosts/_progress.html.erb (1.4ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(134.9ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(50.8ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(35.7ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(45.6ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(42.2ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(36.3ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(37.4ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(38.5ms)
2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb
(422.1ms)
2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb
(57.1ms)
2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb
(65.8ms)
2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb
(49.7ms)
2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb
(55.4ms)
2015-10-05 11:11:27 [I] Rendered
config_groups/_config_groups_selection.html.erb (1190.1ms)
2015-10-05 11:11:27 [I] Rendered puppetclasses/_selectedClasses.html.erb
(0.0ms)
2015-10-05 11:11:27 [I] Rendered
puppetclasses/_classes_in_groups.html.erb (0.0ms)
2015-10-05 11:11:28 [I] Rendered puppetclasses/_classes.html.erb
(1063.7ms)
2015-10-05 11:11:28 [I] Rendered puppetclasses/_class_selection.html.erb
(2585.8ms)
2015-10-05 11:11:28 [I] Successfully decrypted field for
Foreman::Model::Vmware vsphere-srv
2015-10-05 11:11:33 [I] Rendered
compute_resources_vms/form/vmware/_base.html.erb (3664.1ms)
2015-10-05 11:11:39 [I] Rendered
compute_resources_vms/form/vmware/_volume.html.erb (6004.1ms)
2015-10-05 11:11:41 [I] Rendered
compute_resources_vms/form/vmware/_volume.html.erb (2128.7ms)
2015-10-05 11:11:41 [I] Rendered
compute_resources_vms/form/_volumes.html.erb (8536.3ms)

2015-10-05 11:11:41 [I] Rendered hosts/_compute_detail.html.erb
(12209.2ms)2015-10-05 11:11:41 [I] Rendered hosts/_compute.html.erb
(13201.9ms)
2015-10-05 11:11:42 [I] Rendered nic/_base_form.html.erb (88.9ms)
2015-10-05 11:11:42 [I] Rendered nic/_virtual_form.html.erb (5.3ms)
2015-10-05 11:11:42 [I] Rendered
compute_resources_vms/form/vmware/_network.html.erb (541.5ms)
2015-10-05 11:11:42 [I] Rendered nic/_provider_specific_form.html.erb
(548.2ms)
2015-10-05 11:11:42 [I] Rendered nic/manageds/_managed.html.erb (655.8ms)
2015-10-05 11:11:42 [I] Rendered nic/_base_form.html.erb (24.4ms)
2015-10-05 11:11:42 [I] Rendered nic/_virtual_form.html.erb (2.1ms)
2015-10-05 11:11:43 [I] Rendered
compute_resources_vms/form/vmware/_network.html.erb (552.0ms)
2015-10-05 11:11:43 [I] Rendered nic/_provider_specific_form.html.erb
(553.9ms)
2015-10-05 11:11:43 [I] Rendered nic/manageds/_managed.html.erb (588.8ms)
2015-10-05 11:11:43 [I] Rendered hosts/_interfaces.html.erb (1257.6ms)
2015-10-05 11:11:43 [I] Rendered
common/os_selection/_architecture.html.erb (23.4ms)
2015-10-05 11:11:43 [I] Rendered
common/os_selection/_operatingsystem.html.erb (73.3ms)
2015-10-05 11:11:43 [I] Rendered hosts/_operating_system.html.erb
(115.5ms)
2015-10-05 11:11:43 [I] Rendered hosts/_unattended.html.erb (14595.3ms)

2015-10-05 11:11:54 [I] Rendered
puppetclasses/_class_parameters.html.erb (10797.4ms)2015-10-05 11:11:54
[I] Rendered puppetclasses/_classes_parameters.html.erb (10842.1ms)
2015-10-05 11:11:54 [I] Rendered
common_parameters/_inherited_parameters.html.erb (2.7ms)
2015-10-05 11:11:54 [I] Rendered
common_parameters/_puppetclass_parameter.html.erb (32.7ms)
2015-10-05 11:11:54 [I] Rendered
common_parameters/_puppetclass_parameter.html.erb (7.2ms)
2015-10-05 11:11:54 [I] Rendered
common_parameters/_puppetclasses_parameters.html.erb (60.1ms)
2015-10-05 11:11:54 [I] Rendered common_parameters/_parameter.html.erb
(8.4ms)
2015-10-05 11:11:54 [I] Rendered common_parameters/_parameters.html.erb
(56.6ms)

2015-10-05 11:11:54 [I] Rendered hosts/_form.html.erb
(29341.7ms)2015-10-05 11:11:54 [I] Rendered hosts/edit.html.erb within
layouts/application (29359.3ms)
2015-10-05 11:11:54 [I] Rendered home/_user_dropdown.html.erb (9.3ms)
2015-10-05 11:11:54 [I] Read fragment views/tabs_and_title_records-24 2.1ms
2015-10-05 11:11:54 [I] Rendered home/_topbar.html.erb (22.5ms)
2015-10-05 11:11:54 [I] Rendered layouts/base.html.erb (31.8ms)
2015-10-05 11:11:54 [I] Completed 200 OK in 30593.2ms (Views: 28684.9ms |
ActiveRecord: 744.7ms)
2015-10-05 11:11:55 [I] Started GET "/hosts/server1.example.com/edit" for
192.168.2.27 at 2015-10-05 11:11:55 +0200
2015-10-05 11:11:55 [I] Processing by HostsController#edit as HTML
2015-10-05 11:11:55 [I] Parameters: {"id"=>"server1.example.com"}
2015-10-05 11:11:55 [I] Rendered hosts/_progress.html.erb (0.8ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(69.3ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(29.2ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(27.0ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(41.0ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(41.2ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(42.5ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(41.2ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(39.8ms)
2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb
(44.3ms)
2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb
(43.9ms)
2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb
(41.4ms)
2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb
(40.5ms)
2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb
(31.4ms)
2015-10-05 11:11:56 [I] Rendered
config_groups/_config_groups_selection.html.erb (608.2ms)
2015-10-05 11:11:56 [I] Rendered puppetclasses/_selectedClasses.html.erb
(0.0ms)
2015-10-05 11:11:56 [I] Rendered
puppetclasses/_classes_in_groups.html.erb (0.0ms)
2015-10-05 11:11:57 [I] Rendered puppetclasses/_classes.html.erb (871.4ms)
2015-10-05 11:11:57 [I] Rendered puppetclasses/_class_selection.html.erb
(1712.8ms)
2015-10-05 11:11:57 [I] Successfully decrypted field for
Foreman::Model::Vmware vsphere-srv
2015-10-05 11:12:00 [I] Rendered
compute_resources_vms/form/vmware/_base.html.erb (3079.7ms)
2015-10-05 11:12:01 [I] Started PUT "/api/v2/settings/3" for 10.10.10.100
at 2015-10-05 11:12:01 +0200
2015-10-05 11:12:01 [I] Started PUT "/api/v2/settings/12" for 10.10.10.100
at 2015-10-05 11:12:01 +0200
2015-10-05 11:12:01 [I] Processing by Api::V2::SettingsController#update as
JSON
2015-10-05 11:12:01 [I] Processing by Api::V2::SettingsController#update as
JSON
2015-10-05 11:12:01 [I] Parameters: {"setting"=>{"value"=>"[FILTERED]"},
"apiv"=>"v2", "id"=>"12"}
2015-10-05 11:12:01 [I] Parameters: {"setting"=>{"value"=>"[FILTERED]"},
"apiv"=>"v2", "id"=>"3"}
2015-10-05 11:12:01 [I] Authorized user api(api)
2015-10-05 11:12:01 [I] Authorized user api(api)
2015-10-05 11:12:01 [I] Completed 200 OK in 115.5ms (Views: 1.1ms |
ActiveRecord: 0.0ms)
2015-10-05 11:12:01 [I] Completed 200 OK in 120.4ms (Views: 1.0ms |
ActiveRecord: 0.0ms)
2015-10-05 11:12:02 [I] Rendered
compute_resources_vms/form/vmware/_volume.html.erb (2038.7ms)
2015-10-05 11:12:05 [I] Rendered
compute_resources_vms/form/vmware/_volume.html.erb (2286.0ms)
2015-10-05 11:12:05 [I] Rendered
compute_resources_vms/form/_volumes.html.erb (4462.0ms)
2015-10-05 11:12:05 [I] Rendered hosts/_compute_detail.html.erb (7545.2ms)
2015-10-05 11:12:05 [I] Rendered hosts/_compute.html.erb (8004.7ms)
2015-10-05 11:12:05 [I] Rendered nic/_base_form.html.erb (43.3ms)
2015-10-05 11:12:05 [I] Rendered nic/_virtual_form.html.erb (4.3ms)
2015-10-05 11:12:05 [I] Rendered
compute_resources_vms/form/vmware/_network.html.erb (620.7ms)
2015-10-05 11:12:05 [I] Rendered nic/_provider_specific_form.html.erb
(624.9ms)
2015-10-05 11:12:05 [I] Rendered nic/manageds/_managed.html.erb (681.2ms)
2015-10-05 11:12:05 [I] Rendered nic/_base_form.html.erb (26.4ms)
2015-10-05 11:12:05 [I] Rendered nic/_virtual_form.html.erb (1.8ms)
2015-10-05 11:12:06 [I] Rendered
compute_resources_vms/form/vmware/_network.html.erb (575.3ms)
2015-10-05 11:12:06 [I] Rendered nic/_provider_specific_form.html.erb
(577.6ms)
2015-10-05 11:12:06 [I] Rendered nic/manageds/_managed.html.erb (610.3ms)
2015-10-05 11:12:06 [I] Rendered hosts/_interfaces.html.erb (1298.9ms)
2015-10-05 11:12:06 [I] Rendered
common/os_selection/_architecture.html.erb (9.9ms)
2015-10-05 11:12:06 [I] Rendered
common/os_selection/_operatingsystem.html.erb (21.9ms)
2015-10-05 11:12:06 [I] Rendered hosts/_operating_system.html.erb (45.8ms)
2015-10-05 11:12:06 [I] Rendered hosts/_unattended.html.erb (9356.3ms)

2015-10-05 11:12:17 [I] Rendered
puppetclasses/_class_parameters.html.erb (10827.1ms)2015-10-05 11:12:17
[I] Rendered puppetclasses/_classes_parameters.html.erb (10870.3ms)
2015-10-05 11:12:17 [I] Rendered
common_parameters/_inherited_parameters.html.erb (1.0ms)
2015-10-05 11:12:17 [I] Rendered
common_parameters/_puppetclass_parameter.html.erb (21.3ms)
2015-10-05 11:12:17 [I] Rendered
common_parameters/_puppetclass_parameter.html.erb (6.2ms)
2015-10-05 11:12:17 [I] Rendered
common_parameters/_puppetclasses_parameters.html.erb (36.9ms)
2015-10-05 11:12:17 [I] Rendered common_parameters/_parameter.html.erb
(4.1ms)
2015-10-05 11:12:17 [I] Rendered common_parameters/_parameters.html.erb
(9.2ms)

2015-10-05 11:12:17 [I] Rendered hosts/_form.html.erb
(22483.6ms)2015-10-05 11:12:17 [I] Rendered hosts/edit.html.erb within
layouts/application (22489.5ms)
2015-10-05 11:12:17 [I] Rendered home/_user_dropdown.html.erb (7.9ms)
2015-10-05 11:12:17 [I] Read fragment views/tabs_and_title_records-24 2.0ms
2015-10-05 11:12:17 [I] Rendered home/_topbar.html.erb (14.9ms)
2015-10-05 11:12:17 [I] Rendered layouts/base.html.erb (295.0ms)
2015-10-05 11:12:17 [I] Completed 200 OK in 22840.8ms (Views: 22326.3ms |
ActiveRecord: 476.5ms)

My setup is the following :

2 x Puppetmasters (on dedicated servers)
Foreman GUI (on a dedicated server)
Foreman DB (on a dedicated server)
Foreman ENC (on a dedicated server)
Foreman reports (on a dedicated server)

When accessing the API, the response times are very quick :

% time curl -k -H "Content-Type:application/json" -H
"Accept:application/json" https://foreman-srv/api/hosts/1
[…]
0,01s user 0,00s system 3% cpu 0,446 total

I actived verbose output in the production.log file but I cannot see any
error in the ActiveRecord queries.

Do you have any lead on this issue ?

Thanks in advance.

Mmiloudi · October 5, 2015, 2:45pm

The target VM (here, server1.example.com) is indeed a VM.
Is there any reasons why access times would be so slow specifically on a VM
?

···

On Monday, October 5, 2015 at 4:10:20 PM UTC+2, mmil...@gmail.com wrote: > > Hi everyone, > > > I am trying to diagnose long loading times when accessing the Edit section > of a given host : > > > > > > When I try to access the Edit section, I notice up to 50s of delay : > > 2015-10-05 11:11:24 [I] Started GET "/hosts/server1.example.com/edit" for > 192.168.2.27 at 2015-10-05 11:11:24 +0200 > 2015-10-05 11:11:24 [I] Processing by HostsController#edit as HTML > 2015-10-05 11:11:24 [I] Parameters: {"id"=>"server1.example.com"} > 2015-10-05 11:11:25 [I] Rendered hosts/_progress.html.erb (1.4ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (134.9ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (50.8ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (35.7ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (45.6ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (42.2ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (36.3ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (37.4ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (38.5ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (422.1ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (57.1ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (65.8ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (49.7ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (55.4ms) > 2015-10-05 11:11:27 [I] Rendered > config_groups/_config_groups_selection.html.erb (1190.1ms) > 2015-10-05 11:11:27 [I] Rendered puppetclasses/_selectedClasses.html.erb > (0.0ms) > 2015-10-05 11:11:27 [I] Rendered > puppetclasses/_classes_in_groups.html.erb (0.0ms) > 2015-10-05 11:11:28 [I] Rendered puppetclasses/_classes.html.erb > (1063.7ms) > 2015-10-05 11:11:28 [I] Rendered puppetclasses/_class_selection.html.erb > (2585.8ms) > 2015-10-05 11:11:28 [I] Successfully decrypted field for > Foreman::Model::Vmware vsphere-srv > 2015-10-05 11:11:33 [I] Rendered > compute_resources_vms/form/vmware/_base.html.erb (3664.1ms) > 2015-10-05 11:11:39 [I] Rendered > compute_resources_vms/form/vmware/_volume.html.erb (6004.1ms) > 2015-10-05 11:11:41 [I] Rendered > compute_resources_vms/form/vmware/_volume.html.erb (2128.7ms) > 2015-10-05 11:11:41 [I] Rendered > compute_resources_vms/form/_volumes.html.erb (8536.3ms) > > *2015-10-05 11:11:41 [I] Rendered hosts/_compute_detail.html.erb > (12209.2ms)2015-10-05 11:11:41 [I] Rendered hosts/_compute.html.erb > (13201.9ms)* > 2015-10-05 11:11:42 [I] Rendered nic/_base_form.html.erb (88.9ms) > 2015-10-05 11:11:42 [I] Rendered nic/_virtual_form.html.erb (5.3ms) > 2015-10-05 11:11:42 [I] Rendered > compute_resources_vms/form/vmware/_network.html.erb (541.5ms) > 2015-10-05 11:11:42 [I] Rendered nic/_provider_specific_form.html.erb > (548.2ms) > 2015-10-05 11:11:42 [I] Rendered nic/manageds/_managed.html.erb (655.8ms) > 2015-10-05 11:11:42 [I] Rendered nic/_base_form.html.erb (24.4ms) > 2015-10-05 11:11:42 [I] Rendered nic/_virtual_form.html.erb (2.1ms) > 2015-10-05 11:11:43 [I] Rendered > compute_resources_vms/form/vmware/_network.html.erb (552.0ms) > 2015-10-05 11:11:43 [I] Rendered nic/_provider_specific_form.html.erb > (553.9ms) > 2015-10-05 11:11:43 [I] Rendered nic/manageds/_managed.html.erb (588.8ms) > 2015-10-05 11:11:43 [I] Rendered hosts/_interfaces.html.erb (1257.6ms) > 2015-10-05 11:11:43 [I] Rendered > common/os_selection/_architecture.html.erb (23.4ms) > 2015-10-05 11:11:43 [I] Rendered > common/os_selection/_operatingsystem.html.erb (73.3ms) > 2015-10-05 11:11:43 [I] Rendered hosts/_operating_system.html.erb > (115.5ms) > *2015-10-05 11:11:43 [I] Rendered hosts/_unattended.html.erb (14595.3ms)* > > *2015-10-05 11:11:54 [I] Rendered > puppetclasses/_class_parameters.html.erb (10797.4ms)2015-10-05 11:11:54 > [I] Rendered puppetclasses/_classes_parameters.html.erb (10842.1ms)* > 2015-10-05 11:11:54 [I] Rendered > common_parameters/_inherited_parameters.html.erb (2.7ms) > 2015-10-05 11:11:54 [I] Rendered > common_parameters/_puppetclass_parameter.html.erb (32.7ms) > 2015-10-05 11:11:54 [I] Rendered > common_parameters/_puppetclass_parameter.html.erb (7.2ms) > 2015-10-05 11:11:54 [I] Rendered > common_parameters/_puppetclasses_parameters.html.erb (60.1ms) > 2015-10-05 11:11:54 [I] Rendered common_parameters/_parameter.html.erb > (8.4ms) > 2015-10-05 11:11:54 [I] Rendered common_parameters/_parameters.html.erb > (56.6ms) > > *2015-10-05 11:11:54 [I] Rendered hosts/_form.html.erb > (29341.7ms)2015-10-05 11:11:54 [I] Rendered hosts/edit.html.erb within > layouts/application (29359.3ms)* > 2015-10-05 11:11:54 [I] Rendered home/_user_dropdown.html.erb (9.3ms) > 2015-10-05 11:11:54 [I] Read fragment views/tabs_and_title_records-24 2.1ms > 2015-10-05 11:11:54 [I] Rendered home/_topbar.html.erb (22.5ms) > 2015-10-05 11:11:54 [I] Rendered layouts/base.html.erb (31.8ms) > *2015-10-05 11:11:54 [I] Completed 200 OK in 30593.2ms (Views: 28684.9ms | > ActiveRecord: 744.7ms)* > 2015-10-05 11:11:55 [I] Started GET "/hosts/server1.example.com/edit" for > 192.168.2.27 at 2015-10-05 11:11:55 +0200 > 2015-10-05 11:11:55 [I] Processing by HostsController#edit as HTML > 2015-10-05 11:11:55 [I] Parameters: {"id"=>"server1.example.com"} > 2015-10-05 11:11:55 [I] Rendered hosts/_progress.html.erb (0.8ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (69.3ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (29.2ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (27.0ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (41.0ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (41.2ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (42.5ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (41.2ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (39.8ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (44.3ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (43.9ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (41.4ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (40.5ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (31.4ms) > 2015-10-05 11:11:56 [I] Rendered > config_groups/_config_groups_selection.html.erb (608.2ms) > 2015-10-05 11:11:56 [I] Rendered puppetclasses/_selectedClasses.html.erb > (0.0ms) > 2015-10-05 11:11:56 [I] Rendered > puppetclasses/_classes_in_groups.html.erb (0.0ms) > 2015-10-05 11:11:57 [I] Rendered puppetclasses/_classes.html.erb > (871.4ms) > 2015-10-05 11:11:57 [I] Rendered puppetclasses/_class_selection.html.erb > (1712.8ms) > 2015-10-05 11:11:57 [I] Successfully decrypted field for > Foreman::Model::Vmware vsphere-srv > 2015-10-05 11:12:00 [I] Rendered > compute_resources_vms/form/vmware/_base.html.erb (3079.7ms) > 2015-10-05 11:12:01 [I] Started PUT "/api/v2/settings/3" for 10.10.10.100 > at 2015-10-05 11:12:01 +0200 > 2015-10-05 11:12:01 [I] Started PUT "/api/v2/settings/12" for 10.10.10.100 > at 2015-10-05 11:12:01 +0200 > 2015-10-05 11:12:01 [I] Processing by Api::V2::SettingsController#update > as JSON > 2015-10-05 11:12:01 [I] Processing by Api::V2::SettingsController#update > as JSON > 2015-10-05 11:12:01 [I] Parameters: {"setting"=>{"value"=>"[FILTERED]"}, > "apiv"=>"v2", "id"=>"12"} > 2015-10-05 11:12:01 [I] Parameters: {"setting"=>{"value"=>"[FILTERED]"}, > "apiv"=>"v2", "id"=>"3"} > 2015-10-05 11:12:01 [I] Authorized user api(api) > 2015-10-05 11:12:01 [I] Authorized user api(api) > 2015-10-05 11:12:01 [I] Completed 200 OK in 115.5ms (Views: 1.1ms | > ActiveRecord: 0.0ms) > 2015-10-05 11:12:01 [I] Completed 200 OK in 120.4ms (Views: 1.0ms | > ActiveRecord: 0.0ms) > 2015-10-05 11:12:02 [I] Rendered > compute_resources_vms/form/vmware/_volume.html.erb (2038.7ms) > 2015-10-05 11:12:05 [I] Rendered > compute_resources_vms/form/vmware/_volume.html.erb (2286.0ms) > 2015-10-05 11:12:05 [I] Rendered > compute_resources_vms/form/_volumes.html.erb (4462.0ms) > 2015-10-05 11:12:05 [I] Rendered hosts/_compute_detail.html.erb > (7545.2ms) > 2015-10-05 11:12:05 [I] Rendered hosts/_compute.html.erb (8004.7ms) > 2015-10-05 11:12:05 [I] Rendered nic/_base_form.html.erb (43.3ms) > 2015-10-05 11:12:05 [I] Rendered nic/_virtual_form.html.erb (4.3ms) > 2015-10-05 11:12:05 [I] Rendered > compute_resources_vms/form/vmware/_network.html.erb (620.7ms) > 2015-10-05 11:12:05 [I] Rendered nic/_provider_specific_form.html.erb > (624.9ms) > 2015-10-05 11:12:05 [I] Rendered nic/manageds/_managed.html.erb (681.2ms) > 2015-10-05 11:12:05 [I] Rendered nic/_base_form.html.erb (26.4ms) > 2015-10-05 11:12:05 [I] Rendered nic/_virtual_form.html.erb (1.8ms) > 2015-10-05 11:12:06 [I] Rendered > compute_resources_vms/form/vmware/_network.html.erb (575.3ms) > 2015-10-05 11:12:06 [I] Rendered nic/_provider_specific_form.html.erb > (577.6ms) > 2015-10-05 11:12:06 [I] Rendered nic/manageds/_managed.html.erb (610.3ms) > 2015-10-05 11:12:06 [I] Rendered hosts/_interfaces.html.erb (1298.9ms) > 2015-10-05 11:12:06 [I] Rendered > common/os_selection/_architecture.html.erb (9.9ms) > 2015-10-05 11:12:06 [I] Rendered > common/os_selection/_operatingsystem.html.erb (21.9ms) > 2015-10-05 11:12:06 [I] Rendered hosts/_operating_system.html.erb > (45.8ms) > *2015-10-05 11:12:06 [I] Rendered hosts/_unattended.html.erb (9356.3ms)* > > *2015-10-05 11:12:17 [I] Rendered > puppetclasses/_class_parameters.html.erb (10827.1ms)2015-10-05 11:12:17 > [I] Rendered puppetclasses/_classes_parameters.html.erb (10870.3ms)* > 2015-10-05 11:12:17 [I] Rendered > common_parameters/_inherited_parameters.html.erb (1.0ms) > 2015-10-05 11:12:17 [I] Rendered > common_parameters/_puppetclass_parameter.html.erb (21.3ms) > 2015-10-05 11:12:17 [I] Rendered > common_parameters/_puppetclass_parameter.html.erb (6.2ms) > 2015-10-05 11:12:17 [I] Rendered > common_parameters/_puppetclasses_parameters.html.erb (36.9ms) > 2015-10-05 11:12:17 [I] Rendered common_parameters/_parameter.html.erb > (4.1ms) > 2015-10-05 11:12:17 [I] Rendered common_parameters/_parameters.html.erb > (9.2ms) > > *2015-10-05 11:12:17 [I] Rendered hosts/_form.html.erb > (22483.6ms)2015-10-05 11:12:17 [I] Rendered hosts/edit.html.erb within > layouts/application (22489.5ms)* > 2015-10-05 11:12:17 [I] Rendered home/_user_dropdown.html.erb (7.9ms) > 2015-10-05 11:12:17 [I] Read fragment views/tabs_and_title_records-24 2.0ms > 2015-10-05 11:12:17 [I] Rendered home/_topbar.html.erb (14.9ms) > 2015-10-05 11:12:17 [I] Rendered layouts/base.html.erb (295.0ms) > *2015-10-05 11:12:17 [I] Completed 200 OK in 22840.8ms (Views: 22326.3ms | > ActiveRecord: 476.5ms)* > > My setup is the following : > > - 2 x Puppetmasters (on dedicated servers) > - Foreman GUI (on a dedicated server) > - Foreman DB (on a dedicated server) > - Foreman ENC (on a dedicated server) > - Foreman reports (on a dedicated server) > > > When accessing the API, the response times are very quick : > > % time curl -k -H "Content-Type:application/json" -H > "Accept:application/json" https://foreman-srv/api/hosts/1 > [...] > 0,01s user 0,00s system 3% cpu 0,446 total > > I actived verbose output in the production.log file but I cannot see any > error in the ActiveRecord queries. > > Do you have any lead on this issue ? > > Thanks in advance. >

Mmiloudi · October 5, 2015, 2:57pm

I heard that it might be an issue with the way the details about a given VM
are retrieved using the VMware API (i.e. too many informations are loaded).
But, unfortunately, I did not investigate this any further…

···

On Monday, October 5, 2015 at 4:10:20 PM UTC+2, mmil...@gmail.com wrote: > > Hi everyone, > > > I am trying to diagnose long loading times when accessing the Edit section > of a given host : > > > > > > When I try to access the Edit section, I notice up to 50s of delay : > > 2015-10-05 11:11:24 [I] Started GET "/hosts/server1.example.com/edit" for > 192.168.2.27 at 2015-10-05 11:11:24 +0200 > 2015-10-05 11:11:24 [I] Processing by HostsController#edit as HTML > 2015-10-05 11:11:24 [I] Parameters: {"id"=>"server1.example.com"} > 2015-10-05 11:11:25 [I] Rendered hosts/_progress.html.erb (1.4ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (134.9ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (50.8ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (35.7ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (45.6ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (42.2ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (36.3ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (37.4ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (38.5ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (422.1ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (57.1ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (65.8ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (49.7ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (55.4ms) > 2015-10-05 11:11:27 [I] Rendered > config_groups/_config_groups_selection.html.erb (1190.1ms) > 2015-10-05 11:11:27 [I] Rendered puppetclasses/_selectedClasses.html.erb > (0.0ms) > 2015-10-05 11:11:27 [I] Rendered > puppetclasses/_classes_in_groups.html.erb (0.0ms) > 2015-10-05 11:11:28 [I] Rendered puppetclasses/_classes.html.erb > (1063.7ms) > 2015-10-05 11:11:28 [I] Rendered puppetclasses/_class_selection.html.erb > (2585.8ms) > 2015-10-05 11:11:28 [I] Successfully decrypted field for > Foreman::Model::Vmware vsphere-srv > 2015-10-05 11:11:33 [I] Rendered > compute_resources_vms/form/vmware/_base.html.erb (3664.1ms) > 2015-10-05 11:11:39 [I] Rendered > compute_resources_vms/form/vmware/_volume.html.erb (6004.1ms) > 2015-10-05 11:11:41 [I] Rendered > compute_resources_vms/form/vmware/_volume.html.erb (2128.7ms) > 2015-10-05 11:11:41 [I] Rendered > compute_resources_vms/form/_volumes.html.erb (8536.3ms) > > *2015-10-05 11:11:41 [I] Rendered hosts/_compute_detail.html.erb > (12209.2ms)2015-10-05 11:11:41 [I] Rendered hosts/_compute.html.erb > (13201.9ms)* > 2015-10-05 11:11:42 [I] Rendered nic/_base_form.html.erb (88.9ms) > 2015-10-05 11:11:42 [I] Rendered nic/_virtual_form.html.erb (5.3ms) > 2015-10-05 11:11:42 [I] Rendered > compute_resources_vms/form/vmware/_network.html.erb (541.5ms) > 2015-10-05 11:11:42 [I] Rendered nic/_provider_specific_form.html.erb > (548.2ms) > 2015-10-05 11:11:42 [I] Rendered nic/manageds/_managed.html.erb (655.8ms) > 2015-10-05 11:11:42 [I] Rendered nic/_base_form.html.erb (24.4ms) > 2015-10-05 11:11:42 [I] Rendered nic/_virtual_form.html.erb (2.1ms) > 2015-10-05 11:11:43 [I] Rendered > compute_resources_vms/form/vmware/_network.html.erb (552.0ms) > 2015-10-05 11:11:43 [I] Rendered nic/_provider_specific_form.html.erb > (553.9ms) > 2015-10-05 11:11:43 [I] Rendered nic/manageds/_managed.html.erb (588.8ms) > 2015-10-05 11:11:43 [I] Rendered hosts/_interfaces.html.erb (1257.6ms) > 2015-10-05 11:11:43 [I] Rendered > common/os_selection/_architecture.html.erb (23.4ms) > 2015-10-05 11:11:43 [I] Rendered > common/os_selection/_operatingsystem.html.erb (73.3ms) > 2015-10-05 11:11:43 [I] Rendered hosts/_operating_system.html.erb > (115.5ms) > *2015-10-05 11:11:43 [I] Rendered hosts/_unattended.html.erb (14595.3ms)* > > *2015-10-05 11:11:54 [I] Rendered > puppetclasses/_class_parameters.html.erb (10797.4ms)2015-10-05 11:11:54 > [I] Rendered puppetclasses/_classes_parameters.html.erb (10842.1ms)* > 2015-10-05 11:11:54 [I] Rendered > common_parameters/_inherited_parameters.html.erb (2.7ms) > 2015-10-05 11:11:54 [I] Rendered > common_parameters/_puppetclass_parameter.html.erb (32.7ms) > 2015-10-05 11:11:54 [I] Rendered > common_parameters/_puppetclass_parameter.html.erb (7.2ms) > 2015-10-05 11:11:54 [I] Rendered > common_parameters/_puppetclasses_parameters.html.erb (60.1ms) > 2015-10-05 11:11:54 [I] Rendered common_parameters/_parameter.html.erb > (8.4ms) > 2015-10-05 11:11:54 [I] Rendered common_parameters/_parameters.html.erb > (56.6ms) > > *2015-10-05 11:11:54 [I] Rendered hosts/_form.html.erb > (29341.7ms)2015-10-05 11:11:54 [I] Rendered hosts/edit.html.erb within > layouts/application (29359.3ms)* > 2015-10-05 11:11:54 [I] Rendered home/_user_dropdown.html.erb (9.3ms) > 2015-10-05 11:11:54 [I] Read fragment views/tabs_and_title_records-24 2.1ms > 2015-10-05 11:11:54 [I] Rendered home/_topbar.html.erb (22.5ms) > 2015-10-05 11:11:54 [I] Rendered layouts/base.html.erb (31.8ms) > *2015-10-05 11:11:54 [I] Completed 200 OK in 30593.2ms (Views: 28684.9ms | > ActiveRecord: 744.7ms)* > 2015-10-05 11:11:55 [I] Started GET "/hosts/server1.example.com/edit" for > 192.168.2.27 at 2015-10-05 11:11:55 +0200 > 2015-10-05 11:11:55 [I] Processing by HostsController#edit as HTML > 2015-10-05 11:11:55 [I] Parameters: {"id"=>"server1.example.com"} > 2015-10-05 11:11:55 [I] Rendered hosts/_progress.html.erb (0.8ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (69.3ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (29.2ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (27.0ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (41.0ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (41.2ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (42.5ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (41.2ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (39.8ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (44.3ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (43.9ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (41.4ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (40.5ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (31.4ms) > 2015-10-05 11:11:56 [I] Rendered > config_groups/_config_groups_selection.html.erb (608.2ms) > 2015-10-05 11:11:56 [I] Rendered puppetclasses/_selectedClasses.html.erb > (0.0ms) > 2015-10-05 11:11:56 [I] Rendered > puppetclasses/_classes_in_groups.html.erb (0.0ms) > 2015-10-05 11:11:57 [I] Rendered puppetclasses/_classes.html.erb > (871.4ms) > 2015-10-05 11:11:57 [I] Rendered puppetclasses/_class_selection.html.erb > (1712.8ms) > 2015-10-05 11:11:57 [I] Successfully decrypted field for > Foreman::Model::Vmware vsphere-srv > 2015-10-05 11:12:00 [I] Rendered > compute_resources_vms/form/vmware/_base.html.erb (3079.7ms) > 2015-10-05 11:12:01 [I] Started PUT "/api/v2/settings/3" for 10.10.10.100 > at 2015-10-05 11:12:01 +0200 > 2015-10-05 11:12:01 [I] Started PUT "/api/v2/settings/12" for 10.10.10.100 > at 2015-10-05 11:12:01 +0200 > 2015-10-05 11:12:01 [I] Processing by Api::V2::SettingsController#update > as JSON > 2015-10-05 11:12:01 [I] Processing by Api::V2::SettingsController#update > as JSON > 2015-10-05 11:12:01 [I] Parameters: {"setting"=>{"value"=>"[FILTERED]"}, > "apiv"=>"v2", "id"=>"12"} > 2015-10-05 11:12:01 [I] Parameters: {"setting"=>{"value"=>"[FILTERED]"}, > "apiv"=>"v2", "id"=>"3"} > 2015-10-05 11:12:01 [I] Authorized user api(api) > 2015-10-05 11:12:01 [I] Authorized user api(api) > 2015-10-05 11:12:01 [I] Completed 200 OK in 115.5ms (Views: 1.1ms | > ActiveRecord: 0.0ms) > 2015-10-05 11:12:01 [I] Completed 200 OK in 120.4ms (Views: 1.0ms | > ActiveRecord: 0.0ms) > 2015-10-05 11:12:02 [I] Rendered > compute_resources_vms/form/vmware/_volume.html.erb (2038.7ms) > 2015-10-05 11:12:05 [I] Rendered > compute_resources_vms/form/vmware/_volume.html.erb (2286.0ms) > 2015-10-05 11:12:05 [I] Rendered > compute_resources_vms/form/_volumes.html.erb (4462.0ms) > 2015-10-05 11:12:05 [I] Rendered hosts/_compute_detail.html.erb > (7545.2ms) > 2015-10-05 11:12:05 [I] Rendered hosts/_compute.html.erb (8004.7ms) > 2015-10-05 11:12:05 [I] Rendered nic/_base_form.html.erb (43.3ms) > 2015-10-05 11:12:05 [I] Rendered nic/_virtual_form.html.erb (4.3ms) > 2015-10-05 11:12:05 [I] Rendered > compute_resources_vms/form/vmware/_network.html.erb (620.7ms) > 2015-10-05 11:12:05 [I] Rendered nic/_provider_specific_form.html.erb > (624.9ms) > 2015-10-05 11:12:05 [I] Rendered nic/manageds/_managed.html.erb (681.2ms) > 2015-10-05 11:12:05 [I] Rendered nic/_base_form.html.erb (26.4ms) > 2015-10-05 11:12:05 [I] Rendered nic/_virtual_form.html.erb (1.8ms) > 2015-10-05 11:12:06 [I] Rendered > compute_resources_vms/form/vmware/_network.html.erb (575.3ms) > 2015-10-05 11:12:06 [I] Rendered nic/_provider_specific_form.html.erb > (577.6ms) > 2015-10-05 11:12:06 [I] Rendered nic/manageds/_managed.html.erb (610.3ms) > 2015-10-05 11:12:06 [I] Rendered hosts/_interfaces.html.erb (1298.9ms) > 2015-10-05 11:12:06 [I] Rendered > common/os_selection/_architecture.html.erb (9.9ms) > 2015-10-05 11:12:06 [I] Rendered > common/os_selection/_operatingsystem.html.erb (21.9ms) > 2015-10-05 11:12:06 [I] Rendered hosts/_operating_system.html.erb > (45.8ms) > *2015-10-05 11:12:06 [I] Rendered hosts/_unattended.html.erb (9356.3ms)* > > *2015-10-05 11:12:17 [I] Rendered > puppetclasses/_class_parameters.html.erb (10827.1ms)2015-10-05 11:12:17 > [I] Rendered puppetclasses/_classes_parameters.html.erb (10870.3ms)* > 2015-10-05 11:12:17 [I] Rendered > common_parameters/_inherited_parameters.html.erb (1.0ms) > 2015-10-05 11:12:17 [I] Rendered > common_parameters/_puppetclass_parameter.html.erb (21.3ms) > 2015-10-05 11:12:17 [I] Rendered > common_parameters/_puppetclass_parameter.html.erb (6.2ms) > 2015-10-05 11:12:17 [I] Rendered > common_parameters/_puppetclasses_parameters.html.erb (36.9ms) > 2015-10-05 11:12:17 [I] Rendered common_parameters/_parameter.html.erb > (4.1ms) > 2015-10-05 11:12:17 [I] Rendered common_parameters/_parameters.html.erb > (9.2ms) > > *2015-10-05 11:12:17 [I] Rendered hosts/_form.html.erb > (22483.6ms)2015-10-05 11:12:17 [I] Rendered hosts/edit.html.erb within > layouts/application (22489.5ms)* > 2015-10-05 11:12:17 [I] Rendered home/_user_dropdown.html.erb (7.9ms) > 2015-10-05 11:12:17 [I] Read fragment views/tabs_and_title_records-24 2.0ms > 2015-10-05 11:12:17 [I] Rendered home/_topbar.html.erb (14.9ms) > 2015-10-05 11:12:17 [I] Rendered layouts/base.html.erb (295.0ms) > *2015-10-05 11:12:17 [I] Completed 200 OK in 22840.8ms (Views: 22326.3ms | > ActiveRecord: 476.5ms)* > > My setup is the following : > > - 2 x Puppetmasters (on dedicated servers) > - Foreman GUI (on a dedicated server) > - Foreman DB (on a dedicated server) > - Foreman ENC (on a dedicated server) > - Foreman reports (on a dedicated server) > > > When accessing the API, the response times are very quick : > > % time curl -k -H "Content-Type:application/json" -H > "Accept:application/json" https://foreman-srv/api/hosts/1 > [...] > 0,01s user 0,00s system 3% cpu 0,446 total > > I actived verbose output in the production.log file but I cannot see any > error in the ActiveRecord queries. > > Do you have any lead on this issue ? > > Thanks in advance. >

Josh · October 5, 2015, 2:36pm

Is this a VMWare VM by any chance? We see load times in the 30-60second
range when "editing" VMWare hosts, but not bare metal.

···

On Mon, Oct 5, 2015 at 9:26 AM, wrote:

Hi everyone,

I am trying to diagnose long loading times when accessing the Edit section
of a given host :

https://lh3.googleusercontent.com/-0Ot3J-Zcovo/VhJq3ELsR5I/AAAAAAAAAAM/Op9qQGM44Is/s1600/foreman_host_edit.png

When I try to access the Edit section, I notice up to 50s of delay :

2015-10-05 11:11:24 [I] Started GET “/hosts/server1.example.com/edit” for
192.168.2.27 at 2015-10-05 11:11:24 +0200
2015-10-05 11:11:24 [I] Processing by HostsController#edit as HTML
2015-10-05 11:11:24 [I] Parameters: {“id”=>“server1.example.com”}
2015-10-05 11:11:25 [I] Rendered hosts/_progress.html.erb (1.4ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(134.9ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(50.8ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(35.7ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(45.6ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(42.2ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(36.3ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(37.4ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(38.5ms)
2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb
(422.1ms)
2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb
(57.1ms)
2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb
(65.8ms)
2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb
(49.7ms)
2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb
(55.4ms)
2015-10-05 11:11:27 [I] Rendered
config_groups/_config_groups_selection.html.erb (1190.1ms)
2015-10-05 11:11:27 [I] Rendered puppetclasses/_selectedClasses.html.erb
(0.0ms)
2015-10-05 11:11:27 [I] Rendered
puppetclasses/_classes_in_groups.html.erb (0.0ms)
2015-10-05 11:11:28 [I] Rendered puppetclasses/_classes.html.erb
(1063.7ms)
2015-10-05 11:11:28 [I] Rendered puppetclasses/_class_selection.html.erb
(2585.8ms)
2015-10-05 11:11:28 [I] Successfully decrypted field for
Foreman::Model::Vmware vsphere-srv
2015-10-05 11:11:33 [I] Rendered
compute_resources_vms/form/vmware/_base.html.erb (3664.1ms)
2015-10-05 11:11:39 [I] Rendered
compute_resources_vms/form/vmware/_volume.html.erb (6004.1ms)
2015-10-05 11:11:41 [I] Rendered
compute_resources_vms/form/vmware/_volume.html.erb (2128.7ms)
2015-10-05 11:11:41 [I] Rendered
compute_resources_vms/form/_volumes.html.erb (8536.3ms)

2015-10-05 11:11:41 [I] Rendered hosts/_compute_detail.html.erb
(12209.2ms)2015-10-05 11:11:41 [I] Rendered hosts/_compute.html.erb
(13201.9ms)
2015-10-05 11:11:42 [I] Rendered nic/_base_form.html.erb (88.9ms)
2015-10-05 11:11:42 [I] Rendered nic/_virtual_form.html.erb (5.3ms)
2015-10-05 11:11:42 [I] Rendered
compute_resources_vms/form/vmware/_network.html.erb (541.5ms)
2015-10-05 11:11:42 [I] Rendered nic/_provider_specific_form.html.erb
(548.2ms)
2015-10-05 11:11:42 [I] Rendered nic/manageds/_managed.html.erb (655.8ms)
2015-10-05 11:11:42 [I] Rendered nic/_base_form.html.erb (24.4ms)
2015-10-05 11:11:42 [I] Rendered nic/_virtual_form.html.erb (2.1ms)
2015-10-05 11:11:43 [I] Rendered
compute_resources_vms/form/vmware/_network.html.erb (552.0ms)
2015-10-05 11:11:43 [I] Rendered nic/_provider_specific_form.html.erb
(553.9ms)
2015-10-05 11:11:43 [I] Rendered nic/manageds/_managed.html.erb (588.8ms)
2015-10-05 11:11:43 [I] Rendered hosts/_interfaces.html.erb (1257.6ms)
2015-10-05 11:11:43 [I] Rendered
common/os_selection/_architecture.html.erb (23.4ms)
2015-10-05 11:11:43 [I] Rendered
common/os_selection/_operatingsystem.html.erb (73.3ms)
2015-10-05 11:11:43 [I] Rendered hosts/_operating_system.html.erb
(115.5ms)
2015-10-05 11:11:43 [I] Rendered hosts/_unattended.html.erb (14595.3ms)

2015-10-05 11:11:54 [I] Rendered
puppetclasses/_class_parameters.html.erb (10797.4ms)2015-10-05 11:11:54
[I] Rendered puppetclasses/_classes_parameters.html.erb (10842.1ms)
2015-10-05 11:11:54 [I] Rendered
common_parameters/_inherited_parameters.html.erb (2.7ms)
2015-10-05 11:11:54 [I] Rendered
common_parameters/_puppetclass_parameter.html.erb (32.7ms)
2015-10-05 11:11:54 [I] Rendered
common_parameters/_puppetclass_parameter.html.erb (7.2ms)
2015-10-05 11:11:54 [I] Rendered
common_parameters/_puppetclasses_parameters.html.erb (60.1ms)
2015-10-05 11:11:54 [I] Rendered common_parameters/_parameter.html.erb
(8.4ms)
2015-10-05 11:11:54 [I] Rendered common_parameters/_parameters.html.erb
(56.6ms)

2015-10-05 11:11:54 [I] Rendered hosts/_form.html.erb
(29341.7ms)2015-10-05 11:11:54 [I] Rendered hosts/edit.html.erb within
layouts/application (29359.3ms)
2015-10-05 11:11:54 [I] Rendered home/_user_dropdown.html.erb (9.3ms)
2015-10-05 11:11:54 [I] Read fragment views/tabs_and_title_records-24 2.1ms
2015-10-05 11:11:54 [I] Rendered home/_topbar.html.erb (22.5ms)
2015-10-05 11:11:54 [I] Rendered layouts/base.html.erb (31.8ms)
2015-10-05 11:11:54 [I] Completed 200 OK in 30593.2ms (Views: 28684.9ms |
ActiveRecord: 744.7ms)
2015-10-05 11:11:55 [I] Started GET “/hosts/server1.example.com/edit” for
192.168.2.27 at 2015-10-05 11:11:55 +0200
2015-10-05 11:11:55 [I] Processing by HostsController#edit as HTML
2015-10-05 11:11:55 [I] Parameters: {“id”=>“server1.example.com”}
2015-10-05 11:11:55 [I] Rendered hosts/_progress.html.erb (0.8ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(69.3ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(29.2ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(27.0ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(41.0ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(41.2ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(42.5ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(41.2ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(39.8ms)
2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb
(44.3ms)
2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb
(43.9ms)
2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb
(41.4ms)
2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb
(40.5ms)
2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb
(31.4ms)
2015-10-05 11:11:56 [I] Rendered
config_groups/_config_groups_selection.html.erb (608.2ms)
2015-10-05 11:11:56 [I] Rendered puppetclasses/_selectedClasses.html.erb
(0.0ms)
2015-10-05 11:11:56 [I] Rendered
puppetclasses/_classes_in_groups.html.erb (0.0ms)
2015-10-05 11:11:57 [I] Rendered puppetclasses/_classes.html.erb
(871.4ms)
2015-10-05 11:11:57 [I] Rendered puppetclasses/_class_selection.html.erb
(1712.8ms)
2015-10-05 11:11:57 [I] Successfully decrypted field for
Foreman::Model::Vmware vsphere-srv
2015-10-05 11:12:00 [I] Rendered
compute_resources_vms/form/vmware/_base.html.erb (3079.7ms)
2015-10-05 11:12:01 [I] Started PUT “/api/v2/settings/3” for 10.10.10.100
at 2015-10-05 11:12:01 +0200
2015-10-05 11:12:01 [I] Started PUT “/api/v2/settings/12” for 10.10.10.100
at 2015-10-05 11:12:01 +0200
2015-10-05 11:12:01 [I] Processing by Api::V2::SettingsController#update
as JSON
2015-10-05 11:12:01 [I] Processing by Api::V2::SettingsController#update
as JSON
2015-10-05 11:12:01 [I] Parameters: {“setting”=>{“value”=>"[FILTERED]"},
“apiv”=>“v2”, “id”=>“12”}
2015-10-05 11:12:01 [I] Parameters: {“setting”=>{“value”=>"[FILTERED]"},
“apiv”=>“v2”, “id”=>“3”}
2015-10-05 11:12:01 [I] Authorized user api(api)
2015-10-05 11:12:01 [I] Authorized user api(api)
2015-10-05 11:12:01 [I] Completed 200 OK in 115.5ms (Views: 1.1ms |
ActiveRecord: 0.0ms)
2015-10-05 11:12:01 [I] Completed 200 OK in 120.4ms (Views: 1.0ms |
ActiveRecord: 0.0ms)
2015-10-05 11:12:02 [I] Rendered
compute_resources_vms/form/vmware/_volume.html.erb (2038.7ms)
2015-10-05 11:12:05 [I] Rendered
compute_resources_vms/form/vmware/_volume.html.erb (2286.0ms)
2015-10-05 11:12:05 [I] Rendered
compute_resources_vms/form/_volumes.html.erb (4462.0ms)
2015-10-05 11:12:05 [I] Rendered hosts/_compute_detail.html.erb
(7545.2ms)
2015-10-05 11:12:05 [I] Rendered hosts/_compute.html.erb (8004.7ms)
2015-10-05 11:12:05 [I] Rendered nic/_base_form.html.erb (43.3ms)
2015-10-05 11:12:05 [I] Rendered nic/_virtual_form.html.erb (4.3ms)
2015-10-05 11:12:05 [I] Rendered
compute_resources_vms/form/vmware/_network.html.erb (620.7ms)
2015-10-05 11:12:05 [I] Rendered nic/_provider_specific_form.html.erb
(624.9ms)
2015-10-05 11:12:05 [I] Rendered nic/manageds/_managed.html.erb (681.2ms)
2015-10-05 11:12:05 [I] Rendered nic/_base_form.html.erb (26.4ms)
2015-10-05 11:12:05 [I] Rendered nic/_virtual_form.html.erb (1.8ms)
2015-10-05 11:12:06 [I] Rendered
compute_resources_vms/form/vmware/_network.html.erb (575.3ms)
2015-10-05 11:12:06 [I] Rendered nic/_provider_specific_form.html.erb
(577.6ms)
2015-10-05 11:12:06 [I] Rendered nic/manageds/_managed.html.erb (610.3ms)
2015-10-05 11:12:06 [I] Rendered hosts/_interfaces.html.erb (1298.9ms)
2015-10-05 11:12:06 [I] Rendered
common/os_selection/_architecture.html.erb (9.9ms)
2015-10-05 11:12:06 [I] Rendered
common/os_selection/_operatingsystem.html.erb (21.9ms)
2015-10-05 11:12:06 [I] Rendered hosts/_operating_system.html.erb
(45.8ms)
2015-10-05 11:12:06 [I] Rendered hosts/_unattended.html.erb (9356.3ms)

2015-10-05 11:12:17 [I] Rendered
puppetclasses/_class_parameters.html.erb (10827.1ms)2015-10-05 11:12:17
[I] Rendered puppetclasses/_classes_parameters.html.erb (10870.3ms)
2015-10-05 11:12:17 [I] Rendered
common_parameters/_inherited_parameters.html.erb (1.0ms)
2015-10-05 11:12:17 [I] Rendered
common_parameters/_puppetclass_parameter.html.erb (21.3ms)
2015-10-05 11:12:17 [I] Rendered
common_parameters/_puppetclass_parameter.html.erb (6.2ms)
2015-10-05 11:12:17 [I] Rendered
common_parameters/_puppetclasses_parameters.html.erb (36.9ms)
2015-10-05 11:12:17 [I] Rendered common_parameters/_parameter.html.erb
(4.1ms)
2015-10-05 11:12:17 [I] Rendered common_parameters/_parameters.html.erb
(9.2ms)

2015-10-05 11:12:17 [I] Rendered hosts/_form.html.erb
(22483.6ms)2015-10-05 11:12:17 [I] Rendered hosts/edit.html.erb within
layouts/application (22489.5ms)
2015-10-05 11:12:17 [I] Rendered home/_user_dropdown.html.erb (7.9ms)
2015-10-05 11:12:17 [I] Read fragment views/tabs_and_title_records-24 2.0ms
2015-10-05 11:12:17 [I] Rendered home/_topbar.html.erb (14.9ms)
2015-10-05 11:12:17 [I] Rendered layouts/base.html.erb (295.0ms)
2015-10-05 11:12:17 [I] Completed 200 OK in 22840.8ms (Views: 22326.3ms |
ActiveRecord: 476.5ms)

My setup is the following :

2 x Puppetmasters (on dedicated servers)

Foreman GUI (on a dedicated server)

Foreman DB (on a dedicated server)

Foreman ENC (on a dedicated server)

Foreman reports (on a dedicated server)

When accessing the API, the response times are very quick :

% time curl -k -H “Content-Type:application/json” -H
"Accept:application/json" https://foreman-srv/api/hosts/1
[…]
0,01s user 0,00s system 3% cpu 0,446 total

I actived verbose output in the production.log file but I cannot see any
error in the ActiveRecord queries.

Do you have any lead on this issue ?

Thanks in advance.

–
You received this message because you are subscribed to the Google Groups
"Foreman users" group.
To unsubscribe from this group and stop receiving emails from it, send an
email to foreman-users+unsubscribe@googlegroups.com.
To post to this group, send email to foreman-users@googlegroups.com.
Visit this group at http://groups.google.com/group/foreman-users.
For more options, visit https://groups.google.com/d/optout.

Mmiloudi · October 6, 2015, 10:35am

Is there any way to speed up this process (cache some informations…) ? Or
maybe spread the necessary informations in tabs to divide the time needed
to access the API ?

···

On Monday, October 5, 2015 at 4:10:20 PM UTC+2, mmil...@gmail.com wrote: > > Hi everyone, > > > I am trying to diagnose long loading times when accessing the Edit section > of a given host : > > > > > > When I try to access the Edit section, I notice up to 50s of delay : > > 2015-10-05 11:11:24 [I] Started GET "/hosts/server1.example.com/edit" for > 192.168.2.27 at 2015-10-05 11:11:24 +0200 > 2015-10-05 11:11:24 [I] Processing by HostsController#edit as HTML > 2015-10-05 11:11:24 [I] Parameters: {"id"=>"server1.example.com"} > 2015-10-05 11:11:25 [I] Rendered hosts/_progress.html.erb (1.4ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (134.9ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (50.8ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (35.7ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (45.6ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (42.2ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (36.3ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (37.4ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (38.5ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (422.1ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (57.1ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (65.8ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (49.7ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (55.4ms) > 2015-10-05 11:11:27 [I] Rendered > config_groups/_config_groups_selection.html.erb (1190.1ms) > 2015-10-05 11:11:27 [I] Rendered puppetclasses/_selectedClasses.html.erb > (0.0ms) > 2015-10-05 11:11:27 [I] Rendered > puppetclasses/_classes_in_groups.html.erb (0.0ms) > 2015-10-05 11:11:28 [I] Rendered puppetclasses/_classes.html.erb > (1063.7ms) > 2015-10-05 11:11:28 [I] Rendered puppetclasses/_class_selection.html.erb > (2585.8ms) > 2015-10-05 11:11:28 [I] Successfully decrypted field for > Foreman::Model::Vmware vsphere-srv > 2015-10-05 11:11:33 [I] Rendered > compute_resources_vms/form/vmware/_base.html.erb (3664.1ms) > 2015-10-05 11:11:39 [I] Rendered > compute_resources_vms/form/vmware/_volume.html.erb (6004.1ms) > 2015-10-05 11:11:41 [I] Rendered > compute_resources_vms/form/vmware/_volume.html.erb (2128.7ms) > 2015-10-05 11:11:41 [I] Rendered > compute_resources_vms/form/_volumes.html.erb (8536.3ms) > > *2015-10-05 11:11:41 [I] Rendered hosts/_compute_detail.html.erb > (12209.2ms)2015-10-05 11:11:41 [I] Rendered hosts/_compute.html.erb > (13201.9ms)* > 2015-10-05 11:11:42 [I] Rendered nic/_base_form.html.erb (88.9ms) > 2015-10-05 11:11:42 [I] Rendered nic/_virtual_form.html.erb (5.3ms) > 2015-10-05 11:11:42 [I] Rendered > compute_resources_vms/form/vmware/_network.html.erb (541.5ms) > 2015-10-05 11:11:42 [I] Rendered nic/_provider_specific_form.html.erb > (548.2ms) > 2015-10-05 11:11:42 [I] Rendered nic/manageds/_managed.html.erb (655.8ms) > 2015-10-05 11:11:42 [I] Rendered nic/_base_form.html.erb (24.4ms) > 2015-10-05 11:11:42 [I] Rendered nic/_virtual_form.html.erb (2.1ms) > 2015-10-05 11:11:43 [I] Rendered > compute_resources_vms/form/vmware/_network.html.erb (552.0ms) > 2015-10-05 11:11:43 [I] Rendered nic/_provider_specific_form.html.erb > (553.9ms) > 2015-10-05 11:11:43 [I] Rendered nic/manageds/_managed.html.erb (588.8ms) > 2015-10-05 11:11:43 [I] Rendered hosts/_interfaces.html.erb (1257.6ms) > 2015-10-05 11:11:43 [I] Rendered > common/os_selection/_architecture.html.erb (23.4ms) > 2015-10-05 11:11:43 [I] Rendered > common/os_selection/_operatingsystem.html.erb (73.3ms) > 2015-10-05 11:11:43 [I] Rendered hosts/_operating_system.html.erb > (115.5ms) > *2015-10-05 11:11:43 [I] Rendered hosts/_unattended.html.erb (14595.3ms)* > > *2015-10-05 11:11:54 [I] Rendered > puppetclasses/_class_parameters.html.erb (10797.4ms)2015-10-05 11:11:54 > [I] Rendered puppetclasses/_classes_parameters.html.erb (10842.1ms)* > 2015-10-05 11:11:54 [I] Rendered > common_parameters/_inherited_parameters.html.erb (2.7ms) > 2015-10-05 11:11:54 [I] Rendered > common_parameters/_puppetclass_parameter.html.erb (32.7ms) > 2015-10-05 11:11:54 [I] Rendered > common_parameters/_puppetclass_parameter.html.erb (7.2ms) > 2015-10-05 11:11:54 [I] Rendered > common_parameters/_puppetclasses_parameters.html.erb (60.1ms) > 2015-10-05 11:11:54 [I] Rendered common_parameters/_parameter.html.erb > (8.4ms) > 2015-10-05 11:11:54 [I] Rendered common_parameters/_parameters.html.erb > (56.6ms) > > *2015-10-05 11:11:54 [I] Rendered hosts/_form.html.erb > (29341.7ms)2015-10-05 11:11:54 [I] Rendered hosts/edit.html.erb within > layouts/application (29359.3ms)* > 2015-10-05 11:11:54 [I] Rendered home/_user_dropdown.html.erb (9.3ms) > 2015-10-05 11:11:54 [I] Read fragment views/tabs_and_title_records-24 2.1ms > 2015-10-05 11:11:54 [I] Rendered home/_topbar.html.erb (22.5ms) > 2015-10-05 11:11:54 [I] Rendered layouts/base.html.erb (31.8ms) > *2015-10-05 11:11:54 [I] Completed 200 OK in 30593.2ms (Views: 28684.9ms | > ActiveRecord: 744.7ms)* > 2015-10-05 11:11:55 [I] Started GET "/hosts/server1.example.com/edit" for > 192.168.2.27 at 2015-10-05 11:11:55 +0200 > 2015-10-05 11:11:55 [I] Processing by HostsController#edit as HTML > 2015-10-05 11:11:55 [I] Parameters: {"id"=>"server1.example.com"} > 2015-10-05 11:11:55 [I] Rendered hosts/_progress.html.erb (0.8ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (69.3ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (29.2ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (27.0ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (41.0ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (41.2ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (42.5ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (41.2ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (39.8ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (44.3ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (43.9ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (41.4ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (40.5ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (31.4ms) > 2015-10-05 11:11:56 [I] Rendered > config_groups/_config_groups_selection.html.erb (608.2ms) > 2015-10-05 11:11:56 [I] Rendered puppetclasses/_selectedClasses.html.erb > (0.0ms) > 2015-10-05 11:11:56 [I] Rendered > puppetclasses/_classes_in_groups.html.erb (0.0ms) > 2015-10-05 11:11:57 [I] Rendered puppetclasses/_classes.html.erb > (871.4ms) > 2015-10-05 11:11:57 [I] Rendered puppetclasses/_class_selection.html.erb > (1712.8ms) > 2015-10-05 11:11:57 [I] Successfully decrypted field for > Foreman::Model::Vmware vsphere-srv > 2015-10-05 11:12:00 [I] Rendered > compute_resources_vms/form/vmware/_base.html.erb (3079.7ms) > 2015-10-05 11:12:01 [I] Started PUT "/api/v2/settings/3" for 10.10.10.100 > at 2015-10-05 11:12:01 +0200 > 2015-10-05 11:12:01 [I] Started PUT "/api/v2/settings/12" for 10.10.10.100 > at 2015-10-05 11:12:01 +0200 > 2015-10-05 11:12:01 [I] Processing by Api::V2::SettingsController#update > as JSON > 2015-10-05 11:12:01 [I] Processing by Api::V2::SettingsController#update > as JSON > 2015-10-05 11:12:01 [I] Parameters: {"setting"=>{"value"=>"[FILTERED]"}, > "apiv"=>"v2", "id"=>"12"} > 2015-10-05 11:12:01 [I] Parameters: {"setting"=>{"value"=>"[FILTERED]"}, > "apiv"=>"v2", "id"=>"3"} > 2015-10-05 11:12:01 [I] Authorized user api(api) > 2015-10-05 11:12:01 [I] Authorized user api(api) > 2015-10-05 11:12:01 [I] Completed 200 OK in 115.5ms (Views: 1.1ms | > ActiveRecord: 0.0ms) > 2015-10-05 11:12:01 [I] Completed 200 OK in 120.4ms (Views: 1.0ms | > ActiveRecord: 0.0ms) > 2015-10-05 11:12:02 [I] Rendered > compute_resources_vms/form/vmware/_volume.html.erb (2038.7ms) > 2015-10-05 11:12:05 [I] Rendered > compute_resources_vms/form/vmware/_volume.html.erb (2286.0ms) > 2015-10-05 11:12:05 [I] Rendered > compute_resources_vms/form/_volumes.html.erb (4462.0ms) > 2015-10-05 11:12:05 [I] Rendered hosts/_compute_detail.html.erb > (7545.2ms) > 2015-10-05 11:12:05 [I] Rendered hosts/_compute.html.erb (8004.7ms) > 2015-10-05 11:12:05 [I] Rendered nic/_base_form.html.erb (43.3ms) > 2015-10-05 11:12:05 [I] Rendered nic/_virtual_form.html.erb (4.3ms) > 2015-10-05 11:12:05 [I] Rendered > compute_resources_vms/form/vmware/_network.html.erb (620.7ms) > 2015-10-05 11:12:05 [I] Rendered nic/_provider_specific_form.html.erb > (624.9ms) > 2015-10-05 11:12:05 [I] Rendered nic/manageds/_managed.html.erb (681.2ms) > 2015-10-05 11:12:05 [I] Rendered nic/_base_form.html.erb (26.4ms) > 2015-10-05 11:12:05 [I] Rendered nic/_virtual_form.html.erb (1.8ms) > 2015-10-05 11:12:06 [I] Rendered > compute_resources_vms/form/vmware/_network.html.erb (575.3ms) > 2015-10-05 11:12:06 [I] Rendered nic/_provider_specific_form.html.erb > (577.6ms) > 2015-10-05 11:12:06 [I] Rendered nic/manageds/_managed.html.erb (610.3ms) > 2015-10-05 11:12:06 [I] Rendered hosts/_interfaces.html.erb (1298.9ms) > 2015-10-05 11:12:06 [I] Rendered > common/os_selection/_architecture.html.erb (9.9ms) > 2015-10-05 11:12:06 [I] Rendered > common/os_selection/_operatingsystem.html.erb (21.9ms) > 2015-10-05 11:12:06 [I] Rendered hosts/_operating_system.html.erb > (45.8ms) > *2015-10-05 11:12:06 [I] Rendered hosts/_unattended.html.erb (9356.3ms)* > > *2015-10-05 11:12:17 [I] Rendered > puppetclasses/_class_parameters.html.erb (10827.1ms)2015-10-05 11:12:17 > [I] Rendered puppetclasses/_classes_parameters.html.erb (10870.3ms)* > 2015-10-05 11:12:17 [I] Rendered > common_parameters/_inherited_parameters.html.erb (1.0ms) > 2015-10-05 11:12:17 [I] Rendered > common_parameters/_puppetclass_parameter.html.erb (21.3ms) > 2015-10-05 11:12:17 [I] Rendered > common_parameters/_puppetclass_parameter.html.erb (6.2ms) > 2015-10-05 11:12:17 [I] Rendered > common_parameters/_puppetclasses_parameters.html.erb (36.9ms) > 2015-10-05 11:12:17 [I] Rendered common_parameters/_parameter.html.erb > (4.1ms) > 2015-10-05 11:12:17 [I] Rendered common_parameters/_parameters.html.erb > (9.2ms) > > *2015-10-05 11:12:17 [I] Rendered hosts/_form.html.erb > (22483.6ms)2015-10-05 11:12:17 [I] Rendered hosts/edit.html.erb within > layouts/application (22489.5ms)* > 2015-10-05 11:12:17 [I] Rendered home/_user_dropdown.html.erb (7.9ms) > 2015-10-05 11:12:17 [I] Read fragment views/tabs_and_title_records-24 2.0ms > 2015-10-05 11:12:17 [I] Rendered home/_topbar.html.erb (14.9ms) > 2015-10-05 11:12:17 [I] Rendered layouts/base.html.erb (295.0ms) > *2015-10-05 11:12:17 [I] Completed 200 OK in 22840.8ms (Views: 22326.3ms | > ActiveRecord: 476.5ms)* > > My setup is the following : > > - 2 x Puppetmasters (on dedicated servers) > - Foreman GUI (on a dedicated server) > - Foreman DB (on a dedicated server) > - Foreman ENC (on a dedicated server) > - Foreman reports (on a dedicated server) > > > When accessing the API, the response times are very quick : > > % time curl -k -H "Content-Type:application/json" -H > "Accept:application/json" https://foreman-srv/api/hosts/1 > [...] > 0,01s user 0,00s system 3% cpu 0,446 total > > I actived verbose output in the production.log file but I cannot see any > error in the ActiveRecord queries. > > Do you have any lead on this issue ? > > Thanks in advance. >

Mmiloudi · October 22, 2015, 4:43pm

Anyone can give me any hints ?

···

On Monday, October 5, 2015 at 4:10:20 PM UTC+2, mmil...@gmail.com wrote: > > Hi everyone, > > > I am trying to diagnose long loading times when accessing the Edit section > of a given host : > > > > > > When I try to access the Edit section, I notice up to 50s of delay : > > 2015-10-05 11:11:24 [I] Started GET "/hosts/server1.example.com/edit" for > 192.168.2.27 at 2015-10-05 11:11:24 +0200 > 2015-10-05 11:11:24 [I] Processing by HostsController#edit as HTML > 2015-10-05 11:11:24 [I] Parameters: {"id"=>"server1.example.com"} > 2015-10-05 11:11:25 [I] Rendered hosts/_progress.html.erb (1.4ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (134.9ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (50.8ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (35.7ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (45.6ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (42.2ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (36.3ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (37.4ms) > 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb > (38.5ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (422.1ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (57.1ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (65.8ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (49.7ms) > 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb > (55.4ms) > 2015-10-05 11:11:27 [I] Rendered > config_groups/_config_groups_selection.html.erb (1190.1ms) > 2015-10-05 11:11:27 [I] Rendered puppetclasses/_selectedClasses.html.erb > (0.0ms) > 2015-10-05 11:11:27 [I] Rendered > puppetclasses/_classes_in_groups.html.erb (0.0ms) > 2015-10-05 11:11:28 [I] Rendered puppetclasses/_classes.html.erb > (1063.7ms) > 2015-10-05 11:11:28 [I] Rendered puppetclasses/_class_selection.html.erb > (2585.8ms) > 2015-10-05 11:11:28 [I] Successfully decrypted field for > Foreman::Model::Vmware vsphere-srv > 2015-10-05 11:11:33 [I] Rendered > compute_resources_vms/form/vmware/_base.html.erb (3664.1ms) > 2015-10-05 11:11:39 [I] Rendered > compute_resources_vms/form/vmware/_volume.html.erb (6004.1ms) > 2015-10-05 11:11:41 [I] Rendered > compute_resources_vms/form/vmware/_volume.html.erb (2128.7ms) > 2015-10-05 11:11:41 [I] Rendered > compute_resources_vms/form/_volumes.html.erb (8536.3ms) > > *2015-10-05 11:11:41 [I] Rendered hosts/_compute_detail.html.erb > (12209.2ms)2015-10-05 11:11:41 [I] Rendered hosts/_compute.html.erb > (13201.9ms)* > 2015-10-05 11:11:42 [I] Rendered nic/_base_form.html.erb (88.9ms) > 2015-10-05 11:11:42 [I] Rendered nic/_virtual_form.html.erb (5.3ms) > 2015-10-05 11:11:42 [I] Rendered > compute_resources_vms/form/vmware/_network.html.erb (541.5ms) > 2015-10-05 11:11:42 [I] Rendered nic/_provider_specific_form.html.erb > (548.2ms) > 2015-10-05 11:11:42 [I] Rendered nic/manageds/_managed.html.erb (655.8ms) > 2015-10-05 11:11:42 [I] Rendered nic/_base_form.html.erb (24.4ms) > 2015-10-05 11:11:42 [I] Rendered nic/_virtual_form.html.erb (2.1ms) > 2015-10-05 11:11:43 [I] Rendered > compute_resources_vms/form/vmware/_network.html.erb (552.0ms) > 2015-10-05 11:11:43 [I] Rendered nic/_provider_specific_form.html.erb > (553.9ms) > 2015-10-05 11:11:43 [I] Rendered nic/manageds/_managed.html.erb (588.8ms) > 2015-10-05 11:11:43 [I] Rendered hosts/_interfaces.html.erb (1257.6ms) > 2015-10-05 11:11:43 [I] Rendered > common/os_selection/_architecture.html.erb (23.4ms) > 2015-10-05 11:11:43 [I] Rendered > common/os_selection/_operatingsystem.html.erb (73.3ms) > 2015-10-05 11:11:43 [I] Rendered hosts/_operating_system.html.erb > (115.5ms) > *2015-10-05 11:11:43 [I] Rendered hosts/_unattended.html.erb (14595.3ms)* > > *2015-10-05 11:11:54 [I] Rendered > puppetclasses/_class_parameters.html.erb (10797.4ms)2015-10-05 11:11:54 > [I] Rendered puppetclasses/_classes_parameters.html.erb (10842.1ms)* > 2015-10-05 11:11:54 [I] Rendered > common_parameters/_inherited_parameters.html.erb (2.7ms) > 2015-10-05 11:11:54 [I] Rendered > common_parameters/_puppetclass_parameter.html.erb (32.7ms) > 2015-10-05 11:11:54 [I] Rendered > common_parameters/_puppetclass_parameter.html.erb (7.2ms) > 2015-10-05 11:11:54 [I] Rendered > common_parameters/_puppetclasses_parameters.html.erb (60.1ms) > 2015-10-05 11:11:54 [I] Rendered common_parameters/_parameter.html.erb > (8.4ms) > 2015-10-05 11:11:54 [I] Rendered common_parameters/_parameters.html.erb > (56.6ms) > > *2015-10-05 11:11:54 [I] Rendered hosts/_form.html.erb > (29341.7ms)2015-10-05 11:11:54 [I] Rendered hosts/edit.html.erb within > layouts/application (29359.3ms)* > 2015-10-05 11:11:54 [I] Rendered home/_user_dropdown.html.erb (9.3ms) > 2015-10-05 11:11:54 [I] Read fragment views/tabs_and_title_records-24 2.1ms > 2015-10-05 11:11:54 [I] Rendered home/_topbar.html.erb (22.5ms) > 2015-10-05 11:11:54 [I] Rendered layouts/base.html.erb (31.8ms) > *2015-10-05 11:11:54 [I] Completed 200 OK in 30593.2ms (Views: 28684.9ms | > ActiveRecord: 744.7ms)* > 2015-10-05 11:11:55 [I] Started GET "/hosts/server1.example.com/edit" for > 192.168.2.27 at 2015-10-05 11:11:55 +0200 > 2015-10-05 11:11:55 [I] Processing by HostsController#edit as HTML > 2015-10-05 11:11:55 [I] Parameters: {"id"=>"server1.example.com"} > 2015-10-05 11:11:55 [I] Rendered hosts/_progress.html.erb (0.8ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (69.3ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (29.2ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (27.0ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (41.0ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (41.2ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (42.5ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (41.2ms) > 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb > (39.8ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (44.3ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (43.9ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (41.4ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (40.5ms) > 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb > (31.4ms) > 2015-10-05 11:11:56 [I] Rendered > config_groups/_config_groups_selection.html.erb (608.2ms) > 2015-10-05 11:11:56 [I] Rendered puppetclasses/_selectedClasses.html.erb > (0.0ms) > 2015-10-05 11:11:56 [I] Rendered > puppetclasses/_classes_in_groups.html.erb (0.0ms) > 2015-10-05 11:11:57 [I] Rendered puppetclasses/_classes.html.erb > (871.4ms) > 2015-10-05 11:11:57 [I] Rendered puppetclasses/_class_selection.html.erb > (1712.8ms) > 2015-10-05 11:11:57 [I] Successfully decrypted field for > Foreman::Model::Vmware vsphere-srv > 2015-10-05 11:12:00 [I] Rendered > compute_resources_vms/form/vmware/_base.html.erb (3079.7ms) > 2015-10-05 11:12:01 [I] Started PUT "/api/v2/settings/3" for 10.10.10.100 > at 2015-10-05 11:12:01 +0200 > 2015-10-05 11:12:01 [I] Started PUT "/api/v2/settings/12" for 10.10.10.100 > at 2015-10-05 11:12:01 +0200 > 2015-10-05 11:12:01 [I] Processing by Api::V2::SettingsController#update > as JSON > 2015-10-05 11:12:01 [I] Processing by Api::V2::SettingsController#update > as JSON > 2015-10-05 11:12:01 [I] Parameters: {"setting"=>{"value"=>"[FILTERED]"}, > "apiv"=>"v2", "id"=>"12"} > 2015-10-05 11:12:01 [I] Parameters: {"setting"=>{"value"=>"[FILTERED]"}, > "apiv"=>"v2", "id"=>"3"} > 2015-10-05 11:12:01 [I] Authorized user api(api) > 2015-10-05 11:12:01 [I] Authorized user api(api) > 2015-10-05 11:12:01 [I] Completed 200 OK in 115.5ms (Views: 1.1ms | > ActiveRecord: 0.0ms) > 2015-10-05 11:12:01 [I] Completed 200 OK in 120.4ms (Views: 1.0ms | > ActiveRecord: 0.0ms) > 2015-10-05 11:12:02 [I] Rendered > compute_resources_vms/form/vmware/_volume.html.erb (2038.7ms) > 2015-10-05 11:12:05 [I] Rendered > compute_resources_vms/form/vmware/_volume.html.erb (2286.0ms) > 2015-10-05 11:12:05 [I] Rendered > compute_resources_vms/form/_volumes.html.erb (4462.0ms) > 2015-10-05 11:12:05 [I] Rendered hosts/_compute_detail.html.erb > (7545.2ms) > 2015-10-05 11:12:05 [I] Rendered hosts/_compute.html.erb (8004.7ms) > 2015-10-05 11:12:05 [I] Rendered nic/_base_form.html.erb (43.3ms) > 2015-10-05 11:12:05 [I] Rendered nic/_virtual_form.html.erb (4.3ms) > 2015-10-05 11:12:05 [I] Rendered > compute_resources_vms/form/vmware/_network.html.erb (620.7ms) > 2015-10-05 11:12:05 [I] Rendered nic/_provider_specific_form.html.erb > (624.9ms) > 2015-10-05 11:12:05 [I] Rendered nic/manageds/_managed.html.erb (681.2ms) > 2015-10-05 11:12:05 [I] Rendered nic/_base_form.html.erb (26.4ms) > 2015-10-05 11:12:05 [I] Rendered nic/_virtual_form.html.erb (1.8ms) > 2015-10-05 11:12:06 [I] Rendered > compute_resources_vms/form/vmware/_network.html.erb (575.3ms) > 2015-10-05 11:12:06 [I] Rendered nic/_provider_specific_form.html.erb > (577.6ms) > 2015-10-05 11:12:06 [I] Rendered nic/manageds/_managed.html.erb (610.3ms) > 2015-10-05 11:12:06 [I] Rendered hosts/_interfaces.html.erb (1298.9ms) > 2015-10-05 11:12:06 [I] Rendered > common/os_selection/_architecture.html.erb (9.9ms) > 2015-10-05 11:12:06 [I] Rendered > common/os_selection/_operatingsystem.html.erb (21.9ms) > 2015-10-05 11:12:06 [I] Rendered hosts/_operating_system.html.erb > (45.8ms) > *2015-10-05 11:12:06 [I] Rendered hosts/_unattended.html.erb (9356.3ms)* > > *2015-10-05 11:12:17 [I] Rendered > puppetclasses/_class_parameters.html.erb (10827.1ms)2015-10-05 11:12:17 > [I] Rendered puppetclasses/_classes_parameters.html.erb (10870.3ms)* > 2015-10-05 11:12:17 [I] Rendered > common_parameters/_inherited_parameters.html.erb (1.0ms) > 2015-10-05 11:12:17 [I] Rendered > common_parameters/_puppetclass_parameter.html.erb (21.3ms) > 2015-10-05 11:12:17 [I] Rendered > common_parameters/_puppetclass_parameter.html.erb (6.2ms) > 2015-10-05 11:12:17 [I] Rendered > common_parameters/_puppetclasses_parameters.html.erb (36.9ms) > 2015-10-05 11:12:17 [I] Rendered common_parameters/_parameter.html.erb > (4.1ms) > 2015-10-05 11:12:17 [I] Rendered common_parameters/_parameters.html.erb > (9.2ms) > > *2015-10-05 11:12:17 [I] Rendered hosts/_form.html.erb > (22483.6ms)2015-10-05 11:12:17 [I] Rendered hosts/edit.html.erb within > layouts/application (22489.5ms)* > 2015-10-05 11:12:17 [I] Rendered home/_user_dropdown.html.erb (7.9ms) > 2015-10-05 11:12:17 [I] Read fragment views/tabs_and_title_records-24 2.0ms > 2015-10-05 11:12:17 [I] Rendered home/_topbar.html.erb (14.9ms) > 2015-10-05 11:12:17 [I] Rendered layouts/base.html.erb (295.0ms) > *2015-10-05 11:12:17 [I] Completed 200 OK in 22840.8ms (Views: 22326.3ms | > ActiveRecord: 476.5ms)* > > My setup is the following : > > - 2 x Puppetmasters (on dedicated servers) > - Foreman GUI (on a dedicated server) > - Foreman DB (on a dedicated server) > - Foreman ENC (on a dedicated server) > - Foreman reports (on a dedicated server) > > > When accessing the API, the response times are very quick : > > % time curl -k -H "Content-Type:application/json" -H > "Accept:application/json" https://foreman-srv/api/hosts/1 > [...] > 0,01s user 0,00s system 3% cpu 0,446 total > > I actived verbose output in the production.log file but I cannot see any > error in the ActiveRecord queries. > > Do you have any lead on this issue ? > > Thanks in advance. >

Josh · October 5, 2015, 2:53pm

I'm not aware, but it's a problem for us too. Can any developers comment?

···

On Mon, Oct 5, 2015 at 10:45 AM, wrote:

The target VM (here, server1.example.com http://server1.example.com)
is indeed a VM.
Is there any reasons why access times would be so slow specifically on a
VM ?

On Monday, October 5, 2015 at 4:10:20 PM UTC+2, mmil...@gmail.com wrote:

Hi everyone,

I am trying to diagnose long loading times when accessing the Edit
section of a given host :

https://lh3.googleusercontent.com/-0Ot3J-Zcovo/VhJq3ELsR5I/AAAAAAAAAAM/Op9qQGM44Is/s1600/foreman_host_edit.png

When I try to access the Edit section, I notice up to 50s of delay :

2015-10-05 11:11:24 [I] Started GET "/hosts/server1.example.com/edit"
for 192.168.2.27 at 2015-10-05 11:11:24 +0200
2015-10-05 11:11:24 [I] Processing by HostsController#edit as HTML
2015-10-05 11:11:24 [I] Parameters: {“id”=>“server1.example.com”}
2015-10-05 11:11:25 [I] Rendered hosts/_progress.html.erb (1.4ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(134.9ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(50.8ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(35.7ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(45.6ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(42.2ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(36.3ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(37.4ms)
2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb
(38.5ms)
2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb
(422.1ms)
2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb
(57.1ms)
2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb
(65.8ms)
2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb
(49.7ms)
2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb
(55.4ms)
2015-10-05 11:11:27 [I] Rendered
config_groups/_config_groups_selection.html.erb (1190.1ms)
2015-10-05 11:11:27 [I] Rendered
puppetclasses/_selectedClasses.html.erb (0.0ms)
2015-10-05 11:11:27 [I] Rendered
puppetclasses/_classes_in_groups.html.erb (0.0ms)
2015-10-05 11:11:28 [I] Rendered puppetclasses/_classes.html.erb
(1063.7ms)
2015-10-05 11:11:28 [I] Rendered
puppetclasses/_class_selection.html.erb (2585.8ms)
2015-10-05 11:11:28 [I] Successfully decrypted field for
Foreman::Model::Vmware vsphere-srv
2015-10-05 11:11:33 [I] Rendered
compute_resources_vms/form/vmware/_base.html.erb (3664.1ms)
2015-10-05 11:11:39 [I] Rendered
compute_resources_vms/form/vmware/_volume.html.erb (6004.1ms)
2015-10-05 11:11:41 [I] Rendered
compute_resources_vms/form/vmware/_volume.html.erb (2128.7ms)
2015-10-05 11:11:41 [I] Rendered
compute_resources_vms/form/_volumes.html.erb (8536.3ms)

2015-10-05 11:11:41 [I] Rendered hosts/_compute_detail.html.erb
(12209.2ms)2015-10-05 11:11:41 [I] Rendered hosts/_compute.html.erb
(13201.9ms)
2015-10-05 11:11:42 [I] Rendered nic/_base_form.html.erb (88.9ms)
2015-10-05 11:11:42 [I] Rendered nic/_virtual_form.html.erb (5.3ms)
2015-10-05 11:11:42 [I] Rendered
compute_resources_vms/form/vmware/_network.html.erb (541.5ms)
2015-10-05 11:11:42 [I] Rendered nic/_provider_specific_form.html.erb
(548.2ms)
2015-10-05 11:11:42 [I] Rendered nic/manageds/_managed.html.erb
(655.8ms)
2015-10-05 11:11:42 [I] Rendered nic/_base_form.html.erb (24.4ms)
2015-10-05 11:11:42 [I] Rendered nic/_virtual_form.html.erb (2.1ms)
2015-10-05 11:11:43 [I] Rendered
compute_resources_vms/form/vmware/_network.html.erb (552.0ms)
2015-10-05 11:11:43 [I] Rendered nic/_provider_specific_form.html.erb
(553.9ms)
2015-10-05 11:11:43 [I] Rendered nic/manageds/_managed.html.erb
(588.8ms)
2015-10-05 11:11:43 [I] Rendered hosts/_interfaces.html.erb (1257.6ms)
2015-10-05 11:11:43 [I] Rendered
common/os_selection/_architecture.html.erb (23.4ms)
2015-10-05 11:11:43 [I] Rendered
common/os_selection/_operatingsystem.html.erb (73.3ms)
2015-10-05 11:11:43 [I] Rendered hosts/_operating_system.html.erb
(115.5ms)
2015-10-05 11:11:43 [I] Rendered hosts/_unattended.html.erb
(14595.3ms)

2015-10-05 11:11:54 [I] Rendered
puppetclasses/_class_parameters.html.erb (10797.4ms)2015-10-05 11:11:54
[I] Rendered puppetclasses/_classes_parameters.html.erb (10842.1ms)
2015-10-05 11:11:54 [I] Rendered
common_parameters/_inherited_parameters.html.erb (2.7ms)
2015-10-05 11:11:54 [I] Rendered
common_parameters/_puppetclass_parameter.html.erb (32.7ms)
2015-10-05 11:11:54 [I] Rendered
common_parameters/_puppetclass_parameter.html.erb (7.2ms)
2015-10-05 11:11:54 [I] Rendered
common_parameters/_puppetclasses_parameters.html.erb (60.1ms)
2015-10-05 11:11:54 [I] Rendered common_parameters/_parameter.html.erb
(8.4ms)
2015-10-05 11:11:54 [I] Rendered common_parameters/_parameters.html.erb
(56.6ms)

2015-10-05 11:11:54 [I] Rendered hosts/_form.html.erb
(29341.7ms)2015-10-05 11:11:54 [I] Rendered hosts/edit.html.erb within
layouts/application (29359.3ms)
2015-10-05 11:11:54 [I] Rendered home/_user_dropdown.html.erb (9.3ms)
2015-10-05 11:11:54 [I] Read fragment views/tabs_and_title_records-24
2.1ms
2015-10-05 11:11:54 [I] Rendered home/_topbar.html.erb (22.5ms)
2015-10-05 11:11:54 [I] Rendered layouts/base.html.erb (31.8ms)
*2015-10-05 11:11:54 [I] Completed 200 OK in 30593.2ms (Views: 28684.9ms

ActiveRecord: 744.7ms)*
2015-10-05 11:11:55 [I] Started GET “/hosts/server1.example.com/edit"
for 192.168.2.27 at 2015-10-05 11:11:55 +0200
2015-10-05 11:11:55 [I] Processing by HostsController#edit as HTML
2015-10-05 11:11:55 [I] Parameters: {“id”=>“server1.example.com”}
2015-10-05 11:11:55 [I] Rendered hosts/_progress.html.erb (0.8ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(69.3ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(29.2ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(27.0ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(41.0ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(41.2ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(42.5ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(41.2ms)
2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb
(39.8ms)
2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb
(44.3ms)
2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb
(43.9ms)
2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb
(41.4ms)
2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb
(40.5ms)
2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb
(31.4ms)
2015-10-05 11:11:56 [I] Rendered
config_groups/_config_groups_selection.html.erb (608.2ms)
2015-10-05 11:11:56 [I] Rendered
puppetclasses/_selectedClasses.html.erb (0.0ms)
2015-10-05 11:11:56 [I] Rendered
puppetclasses/_classes_in_groups.html.erb (0.0ms)
2015-10-05 11:11:57 [I] Rendered puppetclasses/_classes.html.erb
(871.4ms)
2015-10-05 11:11:57 [I] Rendered
puppetclasses/_class_selection.html.erb (1712.8ms)
2015-10-05 11:11:57 [I] Successfully decrypted field for
Foreman::Model::Vmware vsphere-srv
2015-10-05 11:12:00 [I] Rendered
compute_resources_vms/form/vmware/_base.html.erb (3079.7ms)
2015-10-05 11:12:01 [I] Started PUT “/api/v2/settings/3” for 10.10.10.100
at 2015-10-05 11:12:01 +0200
2015-10-05 11:12:01 [I] Started PUT “/api/v2/settings/12” for
10.10.10.100 at 2015-10-05 11:12:01 +0200
2015-10-05 11:12:01 [I] Processing by Api::V2::SettingsController#update
as JSON
2015-10-05 11:12:01 [I] Processing by Api::V2::SettingsController#update
as JSON
2015-10-05 11:12:01 [I] Parameters:
{“setting”=>{“value”=>”[FILTERED]"}, “apiv”=>“v2”, “id”=>“12”}
2015-10-05 11:12:01 [I] Parameters:
{“setting”=>{“value”=>"[FILTERED]"}, “apiv”=>“v2”, “id”=>“3”}
2015-10-05 11:12:01 [I] Authorized user api(api)
2015-10-05 11:12:01 [I] Authorized user api(api)
2015-10-05 11:12:01 [I] Completed 200 OK in 115.5ms (Views: 1.1ms |
ActiveRecord: 0.0ms)
2015-10-05 11:12:01 [I] Completed 200 OK in 120.4ms (Views: 1.0ms |
ActiveRecord: 0.0ms)
2015-10-05 11:12:02 [I] Rendered
compute_resources_vms/form/vmware/_volume.html.erb (2038.7ms)
2015-10-05 11:12:05 [I] Rendered
compute_resources_vms/form/vmware/_volume.html.erb (2286.0ms)
2015-10-05 11:12:05 [I] Rendered
compute_resources_vms/form/_volumes.html.erb (4462.0ms)
2015-10-05 11:12:05 [I] Rendered hosts/_compute_detail.html.erb
(7545.2ms)
2015-10-05 11:12:05 [I] Rendered hosts/_compute.html.erb (8004.7ms)
2015-10-05 11:12:05 [I] Rendered nic/_base_form.html.erb (43.3ms)
2015-10-05 11:12:05 [I] Rendered nic/_virtual_form.html.erb (4.3ms)
2015-10-05 11:12:05 [I] Rendered
compute_resources_vms/form/vmware/_network.html.erb (620.7ms)
2015-10-05 11:12:05 [I] Rendered nic/_provider_specific_form.html.erb
(624.9ms)
2015-10-05 11:12:05 [I] Rendered nic/manageds/_managed.html.erb
(681.2ms)
2015-10-05 11:12:05 [I] Rendered nic/_base_form.html.erb (26.4ms)
2015-10-05 11:12:05 [I] Rendered nic/_virtual_form.html.erb (1.8ms)
2015-10-05 11:12:06 [I] Rendered
compute_resources_vms/form/vmware/_network.html.erb (575.3ms)
2015-10-05 11:12:06 [I] Rendered nic/_provider_specific_form.html.erb
(577.6ms)
2015-10-05 11:12:06 [I] Rendered nic/manageds/_managed.html.erb
(610.3ms)
2015-10-05 11:12:06 [I] Rendered hosts/_interfaces.html.erb (1298.9ms)
2015-10-05 11:12:06 [I] Rendered
common/os_selection/_architecture.html.erb (9.9ms)
2015-10-05 11:12:06 [I] Rendered
common/os_selection/_operatingsystem.html.erb (21.9ms)
2015-10-05 11:12:06 [I] Rendered hosts/_operating_system.html.erb
(45.8ms)
2015-10-05 11:12:06 [I] Rendered hosts/_unattended.html.erb (9356.3ms)

2015-10-05 11:12:17 [I] Rendered
puppetclasses/_class_parameters.html.erb (10827.1ms)2015-10-05 11:12:17
[I] Rendered puppetclasses/_classes_parameters.html.erb (10870.3ms)
2015-10-05 11:12:17 [I] Rendered
common_parameters/_inherited_parameters.html.erb (1.0ms)
2015-10-05 11:12:17 [I] Rendered
common_parameters/_puppetclass_parameter.html.erb (21.3ms)
2015-10-05 11:12:17 [I] Rendered
common_parameters/_puppetclass_parameter.html.erb (6.2ms)
2015-10-05 11:12:17 [I] Rendered
common_parameters/_puppetclasses_parameters.html.erb (36.9ms)
2015-10-05 11:12:17 [I] Rendered common_parameters/_parameter.html.erb
(4.1ms)
2015-10-05 11:12:17 [I] Rendered common_parameters/_parameters.html.erb
(9.2ms)

2015-10-05 11:12:17 [I] Rendered hosts/_form.html.erb
(22483.6ms)2015-10-05 11:12:17 [I] Rendered hosts/edit.html.erb within
layouts/application (22489.5ms)
2015-10-05 11:12:17 [I] Rendered home/_user_dropdown.html.erb (7.9ms)
2015-10-05 11:12:17 [I] Read fragment views/tabs_and_title_records-24
2.0ms
2015-10-05 11:12:17 [I] Rendered home/_topbar.html.erb (14.9ms)
2015-10-05 11:12:17 [I] Rendered layouts/base.html.erb (295.0ms)
*2015-10-05 11:12:17 [I] Completed 200 OK in 22840.8ms (Views: 22326.3ms

ActiveRecord: 476.5ms)*

My setup is the following :

2 x Puppetmasters (on dedicated servers)

Foreman GUI (on a dedicated server)

Foreman DB (on a dedicated server)

Foreman ENC (on a dedicated server)

Foreman reports (on a dedicated server)

When accessing the API, the response times are very quick :

% time curl -k -H “Content-Type:application/json” -H
"Accept:application/json" https://foreman-srv/api/hosts/1
[…]
0,01s user 0,00s system 3% cpu 0,446 total

I actived verbose output in the production.log file but I cannot see any
error in the ActiveRecord queries.

Do you have any lead on this issue ?

Thanks in advance.

–
You received this message because you are subscribed to the Google Groups
"Foreman users" group.
To unsubscribe from this group and stop receiving emails from it, send an
email to foreman-users+unsubscribe@googlegroups.com.
To post to this group, send email to foreman-users@googlegroups.com.
Visit this group at http://groups.google.com/group/foreman-users.
For more options, visit https://groups.google.com/d/optout.

Dominic_Cleal2 · October 6, 2015, 9:32am

Yes, indeed. The form populates the VM information, dropdowns etc.
under the Virtual Machine tab (even though most are disabled for
editing) and it's especially slow to retrieve information from VMware.

···

On 05/10/15 15:57, mmiloudi@gmail.com wrote: > I heard that it might be an issue with the way the details about a given > VM are retrieved using the VMware API (i.e. too many informations are > loaded). > But, unfortunately, I did not investigate this any further...

–
Dominic Cleal
dominic@cleal.org

Sergio_Traldi · November 12, 2015, 10:54am

Hi,
I have the same problem. we have foreman 1.7.5-1 and about 100 hosts
magaged by foreman. The Database is in a cluster percona of three hosts
dedicated. We have some hosts managed by foreman which are compute nodes of
a openstack cloud. Each host (compute node) has libvit (kvm) installed and
has one ore more bridge and openstack create many vitual interfaces. When I
try to Edit one host in foreman gui, many time it takes about 5 minutes or
more, and in the porduction log I se a lot of:
Rendered nic/manageds/_managed.html.erb (8.9ms)
Rendered nic/_base_form.html.erb (6.7ms)
Rendered nic/_virtual_form.html.erb (0.9ms)
Rendered nic/manageds/_managed.html.erb (8.8ms)
Rendered nic/_base_form.html.erb (6.5ms)
Rendered nic/_virtual_form.html.erb (0.8ms)
Rendered nic/manageds/_managed.html.erb (8.7ms)
Rendered nic/_base_form.html.erb (6.4ms)
Rendered nic/_virtual_form.html.erb (0.8ms)

I would like to choose if wait a lot of time or setting some parameter o
facter to disable the rendering of virtual nic.

Can someone help?
I have a look to bug or performance issue, anda I found:
http://projects.theforeman.org/issues/7259
http://projects.theforeman.org/issues/3145
http://projects.theforeman.org/issues/11657

But one of the bug is for parameter issue, the other is for database
caching, and the last is an aggregate of multiple performance issues.

Do you think I have to open another performance bug (issue)?

Cheers
Sergio

Il giorno giovedì 22 ottobre 2015 18:43:11 UTC+2, mmil...@gmail.com ha
scritto:

···

> > Anyone can give me any hints ? > > On Monday, October 5, 2015 at 4:10:20 PM UTC+2, mmil...@gmail.com wrote: >> >> Hi everyone, >> >> >> I am trying to diagnose long loading times when accessing the Edit >> section of a given host : >> >> >> >> >> >> When I try to access the Edit section, I notice up to 50s of delay : >> >> 2015-10-05 11:11:24 [I] Started GET "/hosts/server1.example.com/edit" >> for 192.168.2.27 at 2015-10-05 11:11:24 +0200 >> 2015-10-05 11:11:24 [I] Processing by HostsController#edit as HTML >> 2015-10-05 11:11:24 [I] Parameters: {"id"=>"server1.example.com"} >> 2015-10-05 11:11:25 [I] Rendered hosts/_progress.html.erb (1.4ms) >> 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb >> (134.9ms) >> 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb >> (50.8ms) >> 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb >> (35.7ms) >> 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb >> (45.6ms) >> 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb >> (42.2ms) >> 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb >> (36.3ms) >> 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb >> (37.4ms) >> 2015-10-05 11:11:26 [I] Rendered config_groups/_config_group.html.erb >> (38.5ms) >> 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb >> (422.1ms) >> 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb >> (57.1ms) >> 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb >> (65.8ms) >> 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb >> (49.7ms) >> 2015-10-05 11:11:27 [I] Rendered config_groups/_config_group.html.erb >> (55.4ms) >> 2015-10-05 11:11:27 [I] Rendered >> config_groups/_config_groups_selection.html.erb (1190.1ms) >> 2015-10-05 11:11:27 [I] Rendered >> puppetclasses/_selectedClasses.html.erb (0.0ms) >> 2015-10-05 11:11:27 [I] Rendered >> puppetclasses/_classes_in_groups.html.erb (0.0ms) >> 2015-10-05 11:11:28 [I] Rendered puppetclasses/_classes.html.erb >> (1063.7ms) >> 2015-10-05 11:11:28 [I] Rendered >> puppetclasses/_class_selection.html.erb (2585.8ms) >> 2015-10-05 11:11:28 [I] Successfully decrypted field for >> Foreman::Model::Vmware vsphere-srv >> 2015-10-05 11:11:33 [I] Rendered >> compute_resources_vms/form/vmware/_base.html.erb (3664.1ms) >> 2015-10-05 11:11:39 [I] Rendered >> compute_resources_vms/form/vmware/_volume.html.erb (6004.1ms) >> 2015-10-05 11:11:41 [I] Rendered >> compute_resources_vms/form/vmware/_volume.html.erb (2128.7ms) >> 2015-10-05 11:11:41 [I] Rendered >> compute_resources_vms/form/_volumes.html.erb (8536.3ms) >> >> *2015-10-05 11:11:41 [I] Rendered hosts/_compute_detail.html.erb >> (12209.2ms)2015-10-05 11:11:41 [I] Rendered hosts/_compute.html.erb >> (13201.9ms)* >> 2015-10-05 11:11:42 [I] Rendered nic/_base_form.html.erb (88.9ms) >> 2015-10-05 11:11:42 [I] Rendered nic/_virtual_form.html.erb (5.3ms) >> 2015-10-05 11:11:42 [I] Rendered >> compute_resources_vms/form/vmware/_network.html.erb (541.5ms) >> 2015-10-05 11:11:42 [I] Rendered nic/_provider_specific_form.html.erb >> (548.2ms) >> 2015-10-05 11:11:42 [I] Rendered nic/manageds/_managed.html.erb >> (655.8ms) >> 2015-10-05 11:11:42 [I] Rendered nic/_base_form.html.erb (24.4ms) >> 2015-10-05 11:11:42 [I] Rendered nic/_virtual_form.html.erb (2.1ms) >> 2015-10-05 11:11:43 [I] Rendered >> compute_resources_vms/form/vmware/_network.html.erb (552.0ms) >> 2015-10-05 11:11:43 [I] Rendered nic/_provider_specific_form.html.erb >> (553.9ms) >> 2015-10-05 11:11:43 [I] Rendered nic/manageds/_managed.html.erb >> (588.8ms) >> 2015-10-05 11:11:43 [I] Rendered hosts/_interfaces.html.erb (1257.6ms) >> 2015-10-05 11:11:43 [I] Rendered >> common/os_selection/_architecture.html.erb (23.4ms) >> 2015-10-05 11:11:43 [I] Rendered >> common/os_selection/_operatingsystem.html.erb (73.3ms) >> 2015-10-05 11:11:43 [I] Rendered hosts/_operating_system.html.erb >> (115.5ms) >> *2015-10-05 11:11:43 [I] Rendered hosts/_unattended.html.erb >> (14595.3ms)* >> >> *2015-10-05 11:11:54 [I] Rendered >> puppetclasses/_class_parameters.html.erb (10797.4ms)2015-10-05 11:11:54 >> [I] Rendered puppetclasses/_classes_parameters.html.erb (10842.1ms)* >> 2015-10-05 11:11:54 [I] Rendered >> common_parameters/_inherited_parameters.html.erb (2.7ms) >> 2015-10-05 11:11:54 [I] Rendered >> common_parameters/_puppetclass_parameter.html.erb (32.7ms) >> 2015-10-05 11:11:54 [I] Rendered >> common_parameters/_puppetclass_parameter.html.erb (7.2ms) >> 2015-10-05 11:11:54 [I] Rendered >> common_parameters/_puppetclasses_parameters.html.erb (60.1ms) >> 2015-10-05 11:11:54 [I] Rendered common_parameters/_parameter.html.erb >> (8.4ms) >> 2015-10-05 11:11:54 [I] Rendered common_parameters/_parameters.html.erb >> (56.6ms) >> >> *2015-10-05 11:11:54 [I] Rendered hosts/_form.html.erb >> (29341.7ms)2015-10-05 11:11:54 [I] Rendered hosts/edit.html.erb within >> layouts/application (29359.3ms)* >> 2015-10-05 11:11:54 [I] Rendered home/_user_dropdown.html.erb (9.3ms) >> 2015-10-05 11:11:54 [I] Read fragment views/tabs_and_title_records-24 >> 2.1ms >> 2015-10-05 11:11:54 [I] Rendered home/_topbar.html.erb (22.5ms) >> 2015-10-05 11:11:54 [I] Rendered layouts/base.html.erb (31.8ms) >> *2015-10-05 11:11:54 [I] Completed 200 OK in 30593.2ms (Views: 28684.9ms >> > ActiveRecord: 744.7ms)* >> 2015-10-05 11:11:55 [I] Started GET "/hosts/server1.example.com/edit" >> for 192.168.2.27 at 2015-10-05 11:11:55 +0200 >> 2015-10-05 11:11:55 [I] Processing by HostsController#edit as HTML >> 2015-10-05 11:11:55 [I] Parameters: {"id"=>"server1.example.com"} >> 2015-10-05 11:11:55 [I] Rendered hosts/_progress.html.erb (0.8ms) >> 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb >> (69.3ms) >> 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb >> (29.2ms) >> 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb >> (27.0ms) >> 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb >> (41.0ms) >> 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb >> (41.2ms) >> 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb >> (42.5ms) >> 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb >> (41.2ms) >> 2015-10-05 11:11:55 [I] Rendered config_groups/_config_group.html.erb >> (39.8ms) >> 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb >> (44.3ms) >> 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb >> (43.9ms) >> 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb >> (41.4ms) >> 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb >> (40.5ms) >> 2015-10-05 11:11:56 [I] Rendered config_groups/_config_group.html.erb >> (31.4ms) >> 2015-10-05 11:11:56 [I] Rendered >> config_groups/_config_groups_selection.html.erb (608.2ms) >> 2015-10-05 11:11:56 [I] Rendered >> puppetclasses/_selectedClasses.html.erb (0.0ms) >> 2015-10-05 11:11:56 [I] Rendered >> puppetclasses/_classes_in_groups.html.erb (0.0ms) >> 2015-10-05 11:11:57 [I] Rendered puppetclasses/_classes.html.erb >> (871.4ms) >> 2015-10-05 11:11:57 [I] Rendered >> puppetclasses/_class_selection.html.erb (1712.8ms) >> 2015-10-05 11:11:57 [I] Successfully decrypted field for >> Foreman::Model::Vmware vsphere-srv >> 2015-10-05 11:12:00 [I] Rendered >> compute_resources_vms/form/vmware/_base.html.erb (3079.7ms) >> 2015-10-05 11:12:01 [I] Started PUT "/api/v2/settings/3" for 10.10.10.100 >> at 2015-10-05 11:12:01 +0200 >> 2015-10-05 11:12:01 [I] Started PUT "/api/v2/settings/12" for >> 10.10.10.100 at 2015-10-05 11:12:01 +0200 >> 2015-10-05 11:12:01 [I] Processing by Api::V2::SettingsController#update >> as JSON >> 2015-10-05 11:12:01 [I] Processing by Api::V2::SettingsController#update >> as JSON >> 2015-10-05 11:12:01 [I] Parameters: >> {"setting"=>{"value"=>"[FILTERED]"}, "apiv"=>"v2", "id"=>"12"} >> 2015-10-05 11:12:01 [I] Parameters: >> {"setting"=>{"value"=>"[FILTERED]"}, "apiv"=>"v2", "id"=>"3"} >> 2015-10-05 11:12:01 [I] Authorized user api(api) >> 2015-10-05 11:12:01 [I] Authorized user api(api) >> 2015-10-05 11:12:01 [I] Completed 200 OK in 115.5ms (Views: 1.1ms | >> ActiveRecord: 0.0ms) >> 2015-10-05 11:12:01 [I] Completed 200 OK in 120.4ms (Views: 1.0ms | >> ActiveRecord: 0.0ms) >> 2015-10-05 11:12:02 [I] Rendered >> compute_resources_vms/form/vmware/_volume.html.erb (2038.7ms) >> 2015-10-05 11:12:05 [I] Rendered >> compute_resources_vms/form/vmware/_volume.html.erb (2286.0ms) >> 2015-10-05 11:12:05 [I] Rendered >> compute_resources_vms/form/_volumes.html.erb (4462.0ms) >> 2015-10-05 11:12:05 [I] Rendered hosts/_compute_detail.html.erb >> (7545.2ms) >> 2015-10-05 11:12:05 [I] Rendered hosts/_compute.html.erb (8004.7ms) >> 2015-10-05 11:12:05 [I] Rendered nic/_base_form.html.erb (43.3ms) >> 2015-10-05 11:12:05 [I] Rendered nic/_virtual_form.html.erb (4.3ms) >> 2015-10-05 11:12:05 [I] Rendered >> compute_resources_vms/form/vmware/_network.html.erb (620.7ms) >> 2015-10-05 11:12:05 [I] Rendered nic/_provider_specific_form.html.erb >> (624.9ms) >> 2015-10-05 11:12:05 [I] Rendered nic/manageds/_managed.html.erb >> (681.2ms) >> 2015-10-05 11:12:05 [I] Rendered nic/_base_form.html.erb (26.4ms) >> 2015-10-05 11:12:05 [I] Rendered nic/_virtual_form.html.erb (1.8ms) >> 2015-10-05 11:12:06 [I] Rendered >> compute_resources_vms/form/vmware/_network.html.erb (575.3ms) >> 2015-10-05 11:12:06 [I] Rendered nic/_provider_specific_form.html.erb >> (577.6ms) >> 2015-10-05 11:12:06 [I] Rendered nic/manageds/_managed.html.erb >> (610.3ms) >> 2015-10-05 11:12:06 [I] Rendered hosts/_interfaces.html.erb (1298.9ms) >> 2015-10-05 11:12:06 [I] Rendered >> common/os_selection/_architecture.html.erb (9.9ms) >> 2015-10-05 11:12:06 [I] Rendered >> common/os_selection/_operatingsystem.html.erb (21.9ms) >> 2015-10-05 11:12:06 [I] Rendered hosts/_operating_system.html.erb >> (45.8ms) >> *2015-10-05 11:12:06 [I] Rendered hosts/_unattended.html.erb (9356.3ms)* >> >> *2015-10-05 11:12:17 [I] Rendered >> puppetclasses/_class_parameters.html.erb (10827.1ms)2015-10-05 11:12:17 >> [I] Rendered puppetclasses/_classes_parameters.html.erb (10870.3ms)* >> 2015-10-05 11:12:17 [I] Rendered >> common_parameters/_inherited_parameters.html.erb (1.0ms) >> 2015-10-05 11:12:17 [I] Rendered >> common_parameters/_puppetclass_parameter.html.erb (21.3ms) >> 2015-10-05 11:12:17 [I] Rendered >> common_parameters/_puppetclass_parameter.html.erb (6.2ms) >> 2015-10-05 11:12:17 [I] Rendered >> common_parameters/_puppetclasses_parameters.html.erb (36.9ms) >> 2015-10-05 11:12:17 [I] Rendered common_parameters/_parameter.html.erb >> (4.1ms) >> 2015-10-05 11:12:17 [I] Rendered common_parameters/_parameters.html.erb >> (9.2ms) >> >> *2015-10-05 11:12:17 [I] Rendered hosts/_form.html.erb >> (22483.6ms)2015-10-05 11:12:17 [I] Rendered hosts/edit.html.erb within >> layouts/application (22489.5ms)* >> 2015-10-05 11:12:17 [I] Rendered home/_user_dropdown.html.erb (7.9ms) >> 2015-10-05 11:12:17 [I] Read fragment views/tabs_and_title_records-24 >> 2.0ms >> 2015-10-05 11:12:17 [I] Rendered home/_topbar.html.erb (14.9ms) >> 2015-10-05 11:12:17 [I] Rendered layouts/base.html.erb (295.0ms) >> *2015-10-05 11:12:17 [I] Completed 200 OK in 22840.8ms (Views: 22326.3ms >> > ActiveRecord: 476.5ms)* >> >> My setup is the following : >> >> - 2 x Puppetmasters (on dedicated servers) >> - Foreman GUI (on a dedicated server) >> - Foreman DB (on a dedicated server) >> - Foreman ENC (on a dedicated server) >> - Foreman reports (on a dedicated server) >> >> >> When accessing the API, the response times are very quick : >> >> % time curl -k -H "Content-Type:application/json" -H >> "Accept:application/json" https://foreman-srv/api/hosts/1 >> [...] >> 0,01s user 0,00s system 3% cpu 0,446 total >> >> I actived verbose output in the production.log file but I cannot see any >> error in the ActiveRecord queries. >> >> Do you have any lead on this issue ? >> >> Thanks in advance. >> >