Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 1480530 Details for
Bug 1624840
Unable to update Cloud Volume using CFME 5.10 with OSP 14
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
production.log
production.log (text/plain), 149.98 KB, created by
Danylo Kholodov
on 2018-09-03 12:20:07 UTC
(
hide
)
Description:
production.log
Filename:
MIME Type:
Creator:
Danylo Kholodov
Created:
2018-09-03 12:20:07 UTC
Size:
149.98 KB
patch
obsolete
>[root@ibm-p9z-18-fsp log]# tail -1000 production.log >[----] I, [2018-09-03T07:41:51.021343 #12675:15b52d8] INFO -- : Parameters: {"c_id"=>"39", "provider"=>{}} >[----] I, [2018-09-03T07:41:51.159477 #12675:15b52d8] INFO -- : Completed 200 OK in 138ms (Views: 0.2ms | ActiveRecord: 10.0ms) >[----] I, [2018-09-03T07:41:52.167699 #12675:15b3e4c] INFO -- : Started GET "/api/providers/39" for 127.0.0.1 at 2018-09-03 07:41:52 -0400 >[----] I, [2018-09-03T07:41:52.171308 #12675:15b3e4c] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:41:52.171468 #12675:15b3e4c] INFO -- : Parameters: {"c_id"=>"39", "provider"=>{}} >[----] I, [2018-09-03T07:41:52.305932 #12675:15b3e4c] INFO -- : Completed 200 OK in 134ms (Views: 0.2ms | ActiveRecord: 10.3ms) >[----] I, [2018-09-03T07:41:53.315182 #12675:15b59cc] INFO -- : Started GET "/api/providers/39" for 127.0.0.1 at 2018-09-03 07:41:53 -0400 >[----] I, [2018-09-03T07:41:53.319569 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:41:53.319738 #12675:15b59cc] INFO -- : Parameters: {"c_id"=>"39", "provider"=>{}} >[----] I, [2018-09-03T07:41:53.442327 #12675:15b59cc] INFO -- : Completed 404 Not Found in 123ms (Views: 0.2ms | ActiveRecord: 8.9ms) >[----] I, [2018-09-03T07:41:53.448325 #12675:15b59cc] INFO -- : Started GET "/api/settings" for 127.0.0.1 at 2018-09-03 07:41:53 -0400 >[----] I, [2018-09-03T07:41:53.451790 #12675:15b59cc] INFO -- : Processing by Api::SettingsController#index as JSON >[----] I, [2018-09-03T07:41:53.451915 #12675:15b59cc] INFO -- : Parameters: {"setting"=>{}} >[----] I, [2018-09-03T07:41:53.614564 #12675:15b59cc] INFO -- : Completed 200 OK in 162ms (Views: 0.2ms | ActiveRecord: 8.7ms) >[----] I, [2018-09-03T07:41:53.623284 #12675:15b59cc] INFO -- : Started GET "/api/providers?expand=resources" for 127.0.0.1 at 2018-09-03 07:41:53 -0400 >[----] I, [2018-09-03T07:41:53.627222 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:41:53.627299 #12675:15b59cc] INFO -- : Parameters: {"expand"=>"resources", "provider"=>{}} >[----] I, [2018-09-03T07:41:53.793192 #12675:15b59cc] INFO -- : Completed 200 OK in 166ms (Views: 0.2ms | ActiveRecord: 10.5ms) >[----] I, [2018-09-03T07:41:53.803384 #12675:15b59cc] INFO -- : Started POST "/api/providers" for 127.0.0.1 at 2018-09-03 07:41:53 -0400 >[----] I, [2018-09-03T07:41:53.808122 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#create as JSON >[----] I, [2018-09-03T07:41:53.808275 #12675:15b59cc] INFO -- : Parameters: {"resource"=>{"name"=>"uc_ci", "type"=>"ManageIQ::Providers::Openstack::InfraManager", "hostname"=>"192.168.24.2", "connection_configurations"=>[{"endpoint"=>{"security_protocol"=>"ssl", "role"=>"default", "port"=>13000}}], "uid_ems"=>"default", "credentials"=>[{"password"=>"[FILTERED]", "userid"=>"admin"}, {"auth_type"=>"ssh_keypair", "userid"=>"heat-admin", "auth_key"=>"-----BEGIN RSA PRIVATE KEY-----\nMIIEpAIBAAKCAQEA7fOaij7qpR0JTceZ7YVCq8esrp4LMqO/7vyL7AHWc/1jvj3b cxmOG6aU+/pYrxeOIKu59nRJCjO0sizjEpoAUHdy/SgmelgDUmk01w0COyetbK98 S2pELmwBjb/lwVh4lYtkRo1FwDkBz+ZkfsqNl0s098i/qj2BAX18cZj80+kOVzyG IjcmPiQfLncH69K3Va86O+n1jEOzuq6viBbPIE9D6Y3RNmzImxPop3IPSuAKLBPJ OHQb11j5Vkp57PtnMjdeTlOyMOuJfIt0VQDfnw/lfltsKrqBMs0PxRq8O9kyNODe pA/bKsAEMWlaebINXiMNC088t6UJJfgHUvOYhQIDAQABAoIBAQDBLEaFfNZY/N2U uzoHTWv2sL9Lcxp8HduvVG7zRfqnZ0oWI3mIU3K+l9nTRkXAv0qu0zrBDV5OXfOP wkkFzOf8azx6sKGsKM9pk+dFvlXVWDy+QyiQwNBhL2WDggovkHc0UFkzvXSmvKDv +oYTOLUB4hg6IEsZFZ0pbIoeXqjCDpzFndjg34mXkklRlmqBvIyuajiSXN5F410Y kdSdqPdMdSiLbQmRkoQJlWlVJoPz871IZPTNua7sxBQ8spabYlEeasnYKR1Udjy2 zD1pQPI9ysaDZCzY5PbmqiG5sMy+BL9BiiCqSG012G5Y5LanrDiQ4c6M+ZUwJV9u 1Yc3HBQBAoGBAPnaz8F0+58rlfs38828mI+FZhV77/QYI0Ch4xaAiHFmvKbbusNX ytzjaPYw0ruUGly8smzjvMT2g3suPr7/XHy6dEmHFoXVSiaj4xaayWRa2QLaBSJd tle2sDzs8d+vgy8zNNNFAimm9S5eljd0S8o6q/+YoWcJq9hdlylLwvdFAoGBAPPN 2E6CHjP9J00c6TcG/KaYr/bTFOPljwu+F0Ud0SDSUlz/jhasDUVeM8blVHisyN3S EQTwKNpl8uCtKVW9LCVDS8n9opOqnu+mZmgh9tWY4CiJEiJ5iONaCn+4Blcsp1Kj ZSrlN3/nu36mI3SRQE6CDRBXQHvismwtTaY1rJBBAoGBAMFIPfOAFa7aPtnqPNZp EPPl1ETGbae8jHjRQyUDJYnkWggSbvXykmIyId5hgoPNXF9lMfn+audLdAb0jWGf leJ/Mro2oHvDtesSWPgBoc3aYYpNMi+VoxtTnh5jhfDui2MWD8Vn2vZxy7WlQjt/ 4al9ndTKNAHLf36PWAKAM0CpAoGANUd8x9tjolTma9GvnZjnSbkAa15BqHQYSKao CzCy7QtJeI1YjdfXppspkYO4bA7GbsNjeMLLCr9QzozJBbWrmhEU1PWau86DoTeq FYOmn5MjGXiq/+GzaovY04Rxzkcx4btXdoJVGJyZutmv0d+UQBPnqvSGYQxvlXhp CbOdHIECgYB1I2r1W9x15LDnceR9ew8E9QgmKN+Vtu2HrpvXnOra7Uw5zgppp2o2 h5aTdRHVJk36P2HhvdRRU7BiPDqIgyVEwoBVg6OGmh8Ge4TsOooXGHbit0T5mFg7 1cibny2+mQQzTRUIKcC61sLMEZ/tFoghQcT8+yuXV1i3CwJjeluTFA==\n-----END RSA PRIVATE KEY-----"}], "ipaddress"=>"192.168.24.2", "api_version"=>"v3"}, "provider"=>{}} >[----] I, [2018-09-03T07:41:54.011321 #12675:15b59cc] INFO -- : Completed 200 OK in 203ms (Views: 0.1ms | ActiveRecord: 25.9ms) >[----] I, [2018-09-03T07:41:54.020482 #12675:15b59cc] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22uc_ci%22" for 127.0.0.1 at 2018-09-03 07:41:54 -0400 >[----] I, [2018-09-03T07:41:54.023943 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:41:54.024113 #12675:15b59cc] INFO -- : Parameters: {"filter"=>["name = \"uc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:41:54.151023 #12675:15b59cc] INFO -- : Completed 200 OK in 127ms (Views: 0.1ms | ActiveRecord: 9.0ms) >[----] I, [2018-09-03T07:41:54.156683 #12675:15b59cc] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:41:54 -0400 >[----] I, [2018-09-03T07:41:54.159580 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:41:54.159699 #12675:15b59cc] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:41:54.291331 #12675:15b59cc] INFO -- : Completed 200 OK in 132ms (Views: 0.1ms | ActiveRecord: 10.4ms) >[----] I, [2018-09-03T07:41:54.299290 #12675:15b59cc] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:41:54 -0400 >[----] I, [2018-09-03T07:41:54.302592 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:41:54.302739 #12675:15b59cc] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:41:54.436265 #12675:15b59cc] INFO -- : Completed 200 OK in 133ms (Views: 0.1ms | ActiveRecord: 10.7ms) >[----] I, [2018-09-03T07:41:54.443252 #12675:15b59cc] INFO -- : Started OPTIONS "/api/providers" for 127.0.0.1 at 2018-09-03 07:41:54 -0400 >[----] I, [2018-09-03T07:41:54.447612 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#options as JSON >[----] I, [2018-09-03T07:41:54.447693 #12675:15b59cc] INFO -- : Parameters: {"provider"=>{}} >[----] I, [2018-09-03T07:41:54.456417 #12675:15b59cc] INFO -- : Completed 200 OK in 9ms (Views: 3.7ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:42:54.533432 #12675:15b2e0c] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22uc_ci%22" for 127.0.0.1 at 2018-09-03 07:42:54 -0400 >[----] I, [2018-09-03T07:42:54.538414 #12675:15b2e0c] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:42:54.538572 #12675:15b2e0c] INFO -- : Parameters: {"filter"=>["name = \"uc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:42:54.677240 #12675:15b2e0c] INFO -- : Completed 200 OK in 139ms (Views: 0.2ms | ActiveRecord: 11.5ms) >[----] I, [2018-09-03T07:42:54.685251 #12675:15b2e0c] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:42:54 -0400 >[----] I, [2018-09-03T07:42:54.688377 #12675:15b2e0c] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:42:54.688460 #12675:15b2e0c] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:42:54.822603 #12675:15b2e0c] INFO -- : Completed 200 OK in 134ms (Views: 0.1ms | ActiveRecord: 10.6ms) >[----] I, [2018-09-03T07:42:54.829059 #12675:15b2e0c] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:42:54 -0400 >[----] I, [2018-09-03T07:42:54.832251 #12675:15b2e0c] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:42:54.832351 #12675:15b2e0c] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:42:54.970797 #12675:15b2e0c] INFO -- : Completed 200 OK in 138ms (Views: 0.1ms | ActiveRecord: 15.0ms) >[----] I, [2018-09-03T07:43:55.021109 #12675:15b28e4] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22uc_ci%22" for 127.0.0.1 at 2018-09-03 07:43:55 -0400 >[----] I, [2018-09-03T07:43:55.025133 #12675:15b28e4] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:43:55.025219 #12675:15b28e4] INFO -- : Parameters: {"filter"=>["name = \"uc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:43:55.156027 #12675:15b28e4] INFO -- : Completed 200 OK in 131ms (Views: 0.1ms | ActiveRecord: 10.1ms) >[----] I, [2018-09-03T07:43:55.162933 #12675:15b28e4] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:43:55 -0400 >[----] I, [2018-09-03T07:43:55.166432 #12675:15b28e4] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:43:55.166572 #12675:15b28e4] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:43:55.302582 #12675:15b28e4] INFO -- : Completed 200 OK in 136ms (Views: 0.1ms | ActiveRecord: 9.2ms) >[----] I, [2018-09-03T07:43:55.309171 #12675:15b28e4] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:43:55 -0400 >[----] I, [2018-09-03T07:43:55.312109 #12675:15b28e4] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:43:55.312191 #12675:15b28e4] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:43:55.438973 #12675:15b28e4] INFO -- : Completed 200 OK in 127ms (Views: 0.2ms | ActiveRecord: 9.3ms) >[----] I, [2018-09-03T07:44:55.495933 #12675:15b52d8] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22uc_ci%22" for 127.0.0.1 at 2018-09-03 07:44:55 -0400 >[----] I, [2018-09-03T07:44:55.499118 #12675:15b52d8] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:44:55.499211 #12675:15b52d8] INFO -- : Parameters: {"filter"=>["name = \"uc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:44:55.624510 #12675:15b52d8] INFO -- : Completed 200 OK in 125ms (Views: 0.1ms | ActiveRecord: 9.4ms) >[----] I, [2018-09-03T07:44:55.631943 #12675:15b52d8] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:44:55 -0400 >[----] I, [2018-09-03T07:44:55.635333 #12675:15b52d8] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:44:55.635410 #12675:15b52d8] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:44:55.761954 #12675:15b52d8] INFO -- : Completed 200 OK in 126ms (Views: 0.2ms | ActiveRecord: 8.9ms) >[----] I, [2018-09-03T07:44:55.768485 #12675:15b52d8] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:44:55 -0400 >[----] I, [2018-09-03T07:44:55.771484 #12675:15b52d8] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:44:55.771546 #12675:15b52d8] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:44:55.901545 #12675:15b52d8] INFO -- : Completed 200 OK in 130ms (Views: 0.2ms | ActiveRecord: 10.5ms) >[----] I, [2018-09-03T07:45:55.970856 #12675:15b3e4c] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22uc_ci%22" for 127.0.0.1 at 2018-09-03 07:45:55 -0400 >[----] I, [2018-09-03T07:45:55.974344 #12675:15b3e4c] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:45:55.974428 #12675:15b3e4c] INFO -- : Parameters: {"filter"=>["name = \"uc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:45:56.103576 #12675:15b3e4c] INFO -- : Completed 200 OK in 129ms (Views: 0.1ms | ActiveRecord: 10.0ms) >[----] I, [2018-09-03T07:45:56.109843 #12675:15b3e4c] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:45:56 -0400 >[----] I, [2018-09-03T07:45:56.112670 #12675:15b3e4c] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:45:56.112739 #12675:15b3e4c] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:45:56.246414 #12675:15b3e4c] INFO -- : Completed 200 OK in 134ms (Views: 0.2ms | ActiveRecord: 10.7ms) >[----] I, [2018-09-03T07:45:56.253132 #12675:15b3e4c] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:45:56 -0400 >[----] I, [2018-09-03T07:45:56.256563 #12675:15b3e4c] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:45:56.256687 #12675:15b3e4c] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:45:56.391746 #12675:15b3e4c] INFO -- : Completed 200 OK in 135ms (Views: 0.2ms | ActiveRecord: 11.1ms) >[----] I, [2018-09-03T07:46:56.459711 #12675:15b59cc] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22uc_ci%22" for 127.0.0.1 at 2018-09-03 07:46:56 -0400 >[----] I, [2018-09-03T07:46:56.463723 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:46:56.463812 #12675:15b59cc] INFO -- : Parameters: {"filter"=>["name = \"uc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:46:56.590742 #12675:15b59cc] INFO -- : Completed 200 OK in 127ms (Views: 0.1ms | ActiveRecord: 10.0ms) >[----] I, [2018-09-03T07:46:56.598687 #12675:15b59cc] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:46:56 -0400 >[----] I, [2018-09-03T07:46:56.602242 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:46:56.602329 #12675:15b59cc] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:46:56.733563 #12675:15b59cc] INFO -- : Completed 200 OK in 131ms (Views: 0.1ms | ActiveRecord: 9.9ms) >[----] I, [2018-09-03T07:46:56.741719 #12675:15b59cc] INFO -- : Started POST "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:46:56 -0400 >[----] I, [2018-09-03T07:46:56.744828 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#update as JSON >[----] I, [2018-09-03T07:46:56.744908 #12675:15b59cc] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:46:56.886320 #12675:15b59cc] INFO -- : Completed 200 OK in 141ms (Views: 0.2ms | ActiveRecord: 13.7ms) >[----] I, [2018-09-03T07:46:56.894975 #12675:15b59cc] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22uc_ci%22" for 127.0.0.1 at 2018-09-03 07:46:56 -0400 >[----] I, [2018-09-03T07:46:56.898976 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:46:56.899161 #12675:15b59cc] INFO -- : Parameters: {"filter"=>["name = \"uc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:46:57.029307 #12675:15b59cc] INFO -- : Completed 200 OK in 130ms (Views: 0.1ms | ActiveRecord: 9.8ms) >[----] I, [2018-09-03T07:46:57.036537 #12675:15b59cc] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:46:57 -0400 >[----] I, [2018-09-03T07:46:57.041325 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:46:57.041476 #12675:15b59cc] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:46:57.180671 #12675:15b59cc] INFO -- : Completed 200 OK in 139ms (Views: 0.2ms | ActiveRecord: 11.7ms) >[----] I, [2018-09-03T07:46:57.188086 #12675:15b59cc] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:46:57 -0400 >[----] I, [2018-09-03T07:46:57.191077 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:46:57.191164 #12675:15b59cc] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:46:57.327106 #12675:15b59cc] INFO -- : Completed 200 OK in 136ms (Views: 0.2ms | ActiveRecord: 11.4ms) >[----] I, [2018-09-03T07:47:57.396425 #12675:15b2e0c] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22uc_ci%22" for 127.0.0.1 at 2018-09-03 07:47:57 -0400 >[----] I, [2018-09-03T07:47:57.400947 #12675:15b2e0c] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:47:57.401107 #12675:15b2e0c] INFO -- : Parameters: {"filter"=>["name = \"uc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:47:57.533771 #12675:15b2e0c] INFO -- : Completed 200 OK in 133ms (Views: 0.1ms | ActiveRecord: 11.1ms) >[----] I, [2018-09-03T07:47:57.541822 #12675:15b2e0c] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:47:57 -0400 >[----] I, [2018-09-03T07:47:57.545123 #12675:15b2e0c] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:47:57.545203 #12675:15b2e0c] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:47:57.684678 #12675:15b2e0c] INFO -- : Completed 200 OK in 139ms (Views: 0.2ms | ActiveRecord: 12.4ms) >[----] I, [2018-09-03T07:47:57.692177 #12675:15b2e0c] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:47:57 -0400 >[----] I, [2018-09-03T07:47:57.696473 #12675:15b2e0c] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:47:57.696587 #12675:15b2e0c] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:47:57.837227 #12675:15b2e0c] INFO -- : Completed 200 OK in 140ms (Views: 0.2ms | ActiveRecord: 11.4ms) >[----] I, [2018-09-03T07:48:57.905959 #12675:15b28e4] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22uc_ci%22" for 127.0.0.1 at 2018-09-03 07:48:57 -0400 >[----] I, [2018-09-03T07:48:57.910116 #12675:15b28e4] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:48:57.910237 #12675:15b28e4] INFO -- : Parameters: {"filter"=>["name = \"uc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:48:58.044103 #12675:15b28e4] INFO -- : Completed 200 OK in 134ms (Views: 0.1ms | ActiveRecord: 11.1ms) >[----] I, [2018-09-03T07:48:58.052125 #12675:15b28e4] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:48:58 -0400 >[----] I, [2018-09-03T07:48:58.056036 #12675:15b28e4] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:48:58.056196 #12675:15b28e4] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:48:58.192062 #12675:15b28e4] INFO -- : Completed 200 OK in 136ms (Views: 0.2ms | ActiveRecord: 10.9ms) >[----] I, [2018-09-03T07:48:58.199382 #12675:15b28e4] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:48:58 -0400 >[----] I, [2018-09-03T07:48:58.202339 #12675:15b28e4] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:48:58.202413 #12675:15b28e4] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:48:58.336012 #12675:15b28e4] INFO -- : Completed 200 OK in 133ms (Views: 0.2ms | ActiveRecord: 10.5ms) >[----] I, [2018-09-03T07:49:58.364383 #12675:15b52d8] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22uc_ci%22" for 127.0.0.1 at 2018-09-03 07:49:58 -0400 >[----] I, [2018-09-03T07:49:58.368084 #12675:15b52d8] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:49:58.368174 #12675:15b52d8] INFO -- : Parameters: {"filter"=>["name = \"uc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:49:58.496947 #12675:15b52d8] INFO -- : Completed 200 OK in 129ms (Views: 0.1ms | ActiveRecord: 9.3ms) >[----] I, [2018-09-03T07:49:58.503969 #12675:15b52d8] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:49:58 -0400 >[----] I, [2018-09-03T07:49:58.506831 #12675:15b52d8] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:49:58.506916 #12675:15b52d8] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:49:58.649168 #12675:15b52d8] INFO -- : Completed 200 OK in 142ms (Views: 0.2ms | ActiveRecord: 11.9ms) >[----] I, [2018-09-03T07:49:58.657139 #12675:15b52d8] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:49:58 -0400 >[----] I, [2018-09-03T07:49:58.661968 #12675:15b52d8] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:49:58.662100 #12675:15b52d8] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:49:58.795418 #12675:15b52d8] INFO -- : Completed 200 OK in 133ms (Views: 0.1ms | ActiveRecord: 10.1ms) >[----] I, [2018-09-03T07:50:58.863486 #12675:15b3e4c] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22uc_ci%22" for 127.0.0.1 at 2018-09-03 07:50:58 -0400 >[----] I, [2018-09-03T07:50:58.866725 #12675:15b3e4c] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:50:58.866819 #12675:15b3e4c] INFO -- : Parameters: {"filter"=>["name = \"uc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:50:58.995133 #12675:15b3e4c] INFO -- : Completed 200 OK in 128ms (Views: 0.1ms | ActiveRecord: 9.7ms) >[----] I, [2018-09-03T07:50:59.001875 #12675:15b3e4c] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:50:59 -0400 >[----] I, [2018-09-03T07:50:59.004902 #12675:15b3e4c] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:50:59.004994 #12675:15b3e4c] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:50:59.133003 #12675:15b3e4c] INFO -- : Completed 200 OK in 128ms (Views: 0.2ms | ActiveRecord: 9.5ms) >[----] I, [2018-09-03T07:50:59.139298 #12675:15b3e4c] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:50:59 -0400 >[----] I, [2018-09-03T07:50:59.142575 #12675:15b3e4c] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:50:59.142664 #12675:15b3e4c] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:50:59.279198 #12675:15b3e4c] INFO -- : Completed 200 OK in 136ms (Views: 0.1ms | ActiveRecord: 10.8ms) >[----] I, [2018-09-03T07:51:59.352073 #12675:15b59cc] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22uc_ci%22" for 127.0.0.1 at 2018-09-03 07:51:59 -0400 >[----] I, [2018-09-03T07:51:59.355227 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:51:59.355330 #12675:15b59cc] INFO -- : Parameters: {"filter"=>["name = \"uc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:51:59.496838 #12675:15b59cc] INFO -- : Completed 200 OK in 141ms (Views: 0.1ms | ActiveRecord: 10.1ms) >[----] I, [2018-09-03T07:51:59.503321 #12675:15b59cc] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:51:59 -0400 >[----] I, [2018-09-03T07:51:59.506641 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:51:59.506786 #12675:15b59cc] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:51:59.642628 #12675:15b59cc] INFO -- : Completed 200 OK in 136ms (Views: 0.2ms | ActiveRecord: 11.1ms) >[----] I, [2018-09-03T07:51:59.652326 #12675:15b59cc] INFO -- : Started POST "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:51:59 -0400 >[----] I, [2018-09-03T07:51:59.655558 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#update as JSON >[----] I, [2018-09-03T07:51:59.655647 #12675:15b59cc] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:51:59.798562 #12675:15b59cc] INFO -- : Completed 200 OK in 143ms (Views: 0.1ms | ActiveRecord: 15.5ms) >[----] I, [2018-09-03T07:51:59.808902 #12675:15b59cc] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22uc_ci%22" for 127.0.0.1 at 2018-09-03 07:51:59 -0400 >[----] I, [2018-09-03T07:51:59.812061 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:51:59.812200 #12675:15b59cc] INFO -- : Parameters: {"filter"=>["name = \"uc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:51:59.936979 #12675:15b59cc] INFO -- : Completed 200 OK in 125ms (Views: 0.2ms | ActiveRecord: 9.2ms) >[----] I, [2018-09-03T07:51:59.943210 #12675:15b59cc] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:51:59 -0400 >[----] I, [2018-09-03T07:51:59.947967 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:51:59.948082 #12675:15b59cc] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:52:00.083904 #12675:15b59cc] INFO -- : Completed 200 OK in 136ms (Views: 0.2ms | ActiveRecord: 11.4ms) >[----] I, [2018-09-03T07:52:00.090994 #12675:15b59cc] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:52:00 -0400 >[----] I, [2018-09-03T07:52:00.094026 #12675:15b59cc] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:52:00.094123 #12675:15b59cc] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:52:00.232698 #12675:15b59cc] INFO -- : Completed 200 OK in 138ms (Views: 0.2ms | ActiveRecord: 10.4ms) >[----] I, [2018-09-03T07:53:00.284640 #12675:15b2e0c] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22uc_ci%22" for 127.0.0.1 at 2018-09-03 07:53:00 -0400 >[----] I, [2018-09-03T07:53:00.288202 #12675:15b2e0c] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:53:00.288289 #12675:15b2e0c] INFO -- : Parameters: {"filter"=>["name = \"uc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:53:00.420981 #12675:15b2e0c] INFO -- : Completed 200 OK in 133ms (Views: 0.2ms | ActiveRecord: 10.1ms) >[----] I, [2018-09-03T07:53:00.428267 #12675:15b2e0c] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:53:00 -0400 >[----] I, [2018-09-03T07:53:00.431674 #12675:15b2e0c] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:53:00.431802 #12675:15b2e0c] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:53:00.560629 #12675:15b2e0c] INFO -- : Completed 200 OK in 129ms (Views: 0.1ms | ActiveRecord: 9.5ms) >[----] I, [2018-09-03T07:53:00.756455 #12675:15b2e0c] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22uc_ci%22" for 127.0.0.1 at 2018-09-03 07:53:00 -0400 >[----] I, [2018-09-03T07:53:00.760636 #12675:15b2e0c] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:53:00.760743 #12675:15b2e0c] INFO -- : Parameters: {"filter"=>["name = \"uc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:53:00.885845 #12675:15b2e0c] INFO -- : Completed 200 OK in 125ms (Views: 0.2ms | ActiveRecord: 9.2ms) >[----] I, [2018-09-03T07:53:00.892032 #12675:15b2e0c] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:53:00 -0400 >[----] I, [2018-09-03T07:53:00.895959 #12675:15b2e0c] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:53:00.896057 #12675:15b2e0c] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:53:01.024499 #12675:15b2e0c] INFO -- : Completed 200 OK in 128ms (Views: 0.1ms | ActiveRecord: 9.3ms) >[----] I, [2018-09-03T07:53:01.031184 #12675:15b2e0c] INFO -- : Started GET "/api/providers/41" for 127.0.0.1 at 2018-09-03 07:53:01 -0400 >[----] I, [2018-09-03T07:53:01.034267 #12675:15b2e0c] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:53:01.034388 #12675:15b2e0c] INFO -- : Parameters: {"c_id"=>"41", "provider"=>{}} >[----] I, [2018-09-03T07:53:01.160898 #12675:15b2e0c] INFO -- : Completed 200 OK in 126ms (Views: 0.2ms | ActiveRecord: 9.2ms) >[----] I, [2018-09-03T07:53:01.167365 #12675:15b2e0c] INFO -- : Started GET "/api/providers?expand=resources" for 127.0.0.1 at 2018-09-03 07:53:01 -0400 >[----] I, [2018-09-03T07:53:01.170890 #12675:15b2e0c] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:53:01.170988 #12675:15b2e0c] INFO -- : Parameters: {"expand"=>"resources", "provider"=>{}} >[----] I, [2018-09-03T07:53:01.337701 #12675:15b2e0c] INFO -- : Completed 200 OK in 167ms (Views: 0.1ms | ActiveRecord: 14.4ms) >[----] I, [2018-09-03T07:53:01.348315 #12675:15b2e0c] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:53:01 -0400 >[----] I, [2018-09-03T07:53:01.351645 #12675:15b2e0c] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:53:01.351777 #12675:15b2e0c] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:53:01.480090 #12675:15b2e0c] INFO -- : Completed 200 OK in 128ms (Views: 0.2ms | ActiveRecord: 10.7ms) >[----] I, [2018-09-03T07:53:01.486672 #12675:15b2e0c] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:53:01 -0400 >[----] I, [2018-09-03T07:53:01.489805 #12675:15b2e0c] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:53:01.489877 #12675:15b2e0c] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:53:01.611685 #12675:15b2e0c] INFO -- : Completed 200 OK in 122ms (Views: 0.1ms | ActiveRecord: 8.2ms) >[----] I, [2018-09-03T07:53:02.496136 #12665:44bc554] INFO -- : Started GET "/" for 127.0.0.1 at 2018-09-03 07:53:02 -0400 >[----] I, [2018-09-03T07:53:02.500571 #12665:44bc554] INFO -- : Processing by DashboardController#login as HTML >[----] I, [2018-09-03T07:53:02.518035 #12665:44bc554] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/login.html.haml within layouts/login >[----] I, [2018-09-03T07:53:02.518951 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_spinner.html.haml (0.5ms) >[----] I, [2018-09-03T07:53:02.519212 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_flash_msg.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:02.519664 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_login_more.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:02.523208 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/login.html.haml within layouts/login (5.0ms) >[----] I, [2018-09-03T07:53:02.523447 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_doctype.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:02.525105 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_i18n_js.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:02.526461 #12665:44bc554] INFO -- : Completed 200 OK in 26ms (Views: 7.6ms | ActiveRecord: 3.7ms) >[----] I, [2018-09-03T07:53:04.928861 #12675:15b28e4] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:53:04 -0400 >[----] I, [2018-09-03T07:53:04.932146 #12675:15b28e4] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:53:04.932271 #12675:15b28e4] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:53:05.056912 #12675:15b28e4] INFO -- : Completed 200 OK in 125ms (Views: 0.2ms | ActiveRecord: 8.8ms) >[----] I, [2018-09-03T07:53:05.062949 #12675:15b28e4] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:53:05 -0400 >[----] I, [2018-09-03T07:53:05.066514 #12675:15b28e4] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:53:05.066606 #12675:15b28e4] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:53:05.198952 #12675:15b28e4] INFO -- : Completed 200 OK in 132ms (Views: 0.2ms | ActiveRecord: 9.9ms) >[----] I, [2018-09-03T07:53:06.593000 #12675:15b52d8] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:53:06 -0400 >[----] I, [2018-09-03T07:53:06.596346 #12675:15b52d8] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:53:06.596438 #12675:15b52d8] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:53:06.733559 #12675:15b52d8] INFO -- : Completed 200 OK in 137ms (Views: 0.2ms | ActiveRecord: 15.4ms) >[----] I, [2018-09-03T07:53:06.740747 #12675:15b52d8] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:53:06 -0400 >[----] I, [2018-09-03T07:53:06.744727 #12675:15b52d8] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:53:06.744894 #12675:15b52d8] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:53:06.869196 #12675:15b52d8] INFO -- : Completed 200 OK in 124ms (Views: 0.1ms | ActiveRecord: 8.4ms) >[----] I, [2018-09-03T07:53:07.187772 #12675:15b3e4c] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:53:07 -0400 >[----] I, [2018-09-03T07:53:07.192094 #12675:15b3e4c] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:53:07.192165 #12675:15b3e4c] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:53:07.319677 #12675:15b3e4c] INFO -- : Completed 200 OK in 127ms (Views: 0.2ms | ActiveRecord: 10.1ms) >[----] I, [2018-09-03T07:53:07.327235 #12675:15b3e4c] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:53:07 -0400 >[----] I, [2018-09-03T07:53:07.330936 #12675:15b3e4c] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:53:07.331096 #12675:15b3e4c] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:53:07.462889 #12675:15b3e4c] INFO -- : Completed 200 OK in 132ms (Views: 0.1ms | ActiveRecord: 9.1ms) >[----] I, [2018-09-03T07:53:07.470528 #12675:15b3e4c] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:53:07 -0400 >[----] I, [2018-09-03T07:53:07.475813 #12675:15b3e4c] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:53:07.475994 #12675:15b3e4c] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:53:07.610350 #12675:15b3e4c] INFO -- : Completed 200 OK in 134ms (Views: 0.1ms | ActiveRecord: 9.6ms) >[----] I, [2018-09-03T07:53:07.616552 #12675:15b3e4c] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:53:07 -0400 >[----] I, [2018-09-03T07:53:07.619854 #12675:15b3e4c] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:53:07.619927 #12675:15b3e4c] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:53:07.742708 #12675:15b3e4c] INFO -- : Completed 200 OK in 123ms (Views: 0.2ms | ActiveRecord: 8.4ms) >[----] I, [2018-09-03T07:53:07.930850 #12675:15b3e4c] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:53:07 -0400 >[----] I, [2018-09-03T07:53:07.934021 #12675:15b3e4c] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:53:07.934117 #12675:15b3e4c] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:53:08.053387 #12675:15b3e4c] INFO -- : Completed 200 OK in 119ms (Views: 0.1ms | ActiveRecord: 8.3ms) >[----] I, [2018-09-03T07:53:08.060138 #12675:15b3e4c] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:53:08 -0400 >[----] I, [2018-09-03T07:53:08.063472 #12675:15b3e4c] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:53:08.063603 #12675:15b3e4c] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:53:08.191131 #12675:15b3e4c] INFO -- : Completed 200 OK in 127ms (Views: 0.2ms | ActiveRecord: 8.8ms) >[----] I, [2018-09-03T07:53:08.812245 #12675:15b59cc] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:53:08 -0400 >[----] I, [2018-09-03T07:53:08.815557 #12675:15b59cc] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:53:08.815645 #12675:15b59cc] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:53:08.950079 #12675:15b59cc] INFO -- : Completed 200 OK in 134ms (Views: 0.2ms | ActiveRecord: 9.9ms) >[----] I, [2018-09-03T07:53:08.956814 #12675:15b59cc] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:53:08 -0400 >[----] I, [2018-09-03T07:53:08.961163 #12675:15b59cc] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:53:08.961281 #12675:15b59cc] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:53:09.090369 #12675:15b59cc] INFO -- : Completed 200 OK in 129ms (Views: 0.1ms | ActiveRecord: 9.2ms) >[----] I, [2018-09-03T07:53:11.661748 #12675:15b2e0c] INFO -- : Started GET "/api/auth?requester_type=ui" for 127.0.0.1 at 2018-09-03 07:53:11 -0400 >[----] I, [2018-09-03T07:53:11.664997 #12675:15b2e0c] INFO -- : Processing by Api::AuthController#show as JSON >[----] I, [2018-09-03T07:53:11.665063 #12675:15b2e0c] INFO -- : Parameters: {"requester_type"=>"ui"} >[----] I, [2018-09-03T07:53:11.782561 #12675:15b2e0c] INFO -- : Completed 200 OK in 117ms (Views: 0.1ms | ActiveRecord: 7.4ms) >[----] I, [2018-09-03T07:53:11.790751 #12675:15b2e0c] INFO -- : Started GET "/api/auth?requester_type=ws" for 127.0.0.1 at 2018-09-03 07:53:11 -0400 >[----] I, [2018-09-03T07:53:11.793970 #12675:15b2e0c] INFO -- : Processing by Api::AuthController#show as JSON >[----] I, [2018-09-03T07:53:11.794035 #12675:15b2e0c] INFO -- : Parameters: {"requester_type"=>"ws"} >[----] I, [2018-09-03T07:53:11.810687 #12675:15b2e0c] INFO -- : Completed 200 OK in 17ms (Views: 0.2ms | ActiveRecord: 2.3ms) >[----] I, [2018-09-03T07:53:11.831831 #12665:44c1be4] INFO -- : Started POST "/dashboard/authenticate?button=login" for 127.0.0.1 at 2018-09-03 07:53:11 -0400 >[----] I, [2018-09-03T07:53:11.835215 #12665:44c1be4] INFO -- : Processing by DashboardController#authenticate as JS >[----] I, [2018-09-03T07:53:11.835358 #12665:44c1be4] INFO -- : Parameters: {"user_name"=>"admin", "user_password"=>"[FILTERED]", "browser_name"=>"Chrome", "browser_version"=>"49", "browser_os"=>"Linux", "user_TZO"=>"0", "button"=>"login"} >[----] I, [2018-09-03T07:53:11.964040 #12665:44c1be4] INFO -- : Completed 200 OK in 129ms (Views: 0.4ms | ActiveRecord: 9.5ms) >[----] I, [2018-09-03T07:53:11.978452 #12665:44c1be4] INFO -- : Started GET "/dashboard/show" for 127.0.0.1 at 2018-09-03 07:53:11 -0400 >[----] I, [2018-09-03T07:53:11.981321 #12665:44c1be4] INFO -- : Processing by DashboardController#show as HTML >[----] I, [2018-09-03T07:53:12.005136 #12665:44c1be4] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/show.html.haml within layouts/application >[----] I, [2018-09-03T07:53:12.005415 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_flash_msg.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:12.006423 #12665:44c1be4] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget.html.haml >[----] I, [2018-09-03T07:53:12.021825 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget.html.haml (15.3ms) >[----] I, [2018-09-03T07:53:12.023093 #12665:44c1be4] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget.html.haml >[----] I, [2018-09-03T07:53:12.039353 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget.html.haml (16.1ms) >[----] I, [2018-09-03T07:53:12.040841 #12665:44c1be4] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget.html.haml >[----] I, [2018-09-03T07:53:12.057357 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget.html.haml (16.4ms) >[----] I, [2018-09-03T07:53:12.058735 #12665:44c1be4] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget.html.haml >[----] I, [2018-09-03T07:53:12.075095 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget.html.haml (16.3ms) >[----] I, [2018-09-03T07:53:12.076333 #12665:44c1be4] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget.html.haml >[----] I, [2018-09-03T07:53:12.091835 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget.html.haml (15.4ms) >[----] I, [2018-09-03T07:53:12.093255 #12665:44c1be4] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget.html.haml >[----] I, [2018-09-03T07:53:12.108651 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget.html.haml (15.3ms) >[----] I, [2018-09-03T07:53:12.109860 #12665:44c1be4] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget.html.haml >[----] I, [2018-09-03T07:53:12.125186 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget.html.haml (15.2ms) >[----] I, [2018-09-03T07:53:12.126335 #12665:44c1be4] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget.html.haml >[----] I, [2018-09-03T07:53:12.141472 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget.html.haml (15.0ms) >[----] I, [2018-09-03T07:53:12.141661 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/show.html.haml within layouts/application (136.4ms) >[----] I, [2018-09-03T07:53:12.141984 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_doctype.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:12.143003 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/stylesheets/_template50.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:12.144204 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_i18n_js.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:12.145720 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_about_modal.html.haml (1.2ms) >[----] I, [2018-09-03T07:53:12.149057 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_user_options.html.haml (2.6ms) >[----] I, [2018-09-03T07:53:12.149305 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_notifications_drawer.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:12.149484 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_toast_list.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:12.149667 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_spinner.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:12.149823 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_lightbox_panel.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:12.149906 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_header.html.haml (5.5ms) >[----] I, [2018-09-03T07:53:12.167833 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_vertical_navbar.html.haml (17.6ms) >[----] I, [2018-09-03T07:53:12.168875 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widgets_menu.html.haml (0.2ms) >[----] I, [2018-09-03T07:53:12.168991 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_dropdownbar.html.haml (0.5ms) >[----] I, [2018-09-03T07:53:12.170118 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/angular/_toolbar.html.haml (2.0ms) >[----] I, [2018-09-03T07:53:12.170296 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_tabs.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:12.170392 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_center_div_dashboard_no_listnav.html.haml (20.3ms) >[----] I, [2018-09-03T07:53:12.170526 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_content.html.haml (20.5ms) >[----] I, [2018-09-03T07:53:12.170836 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_adv_search.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:12.170940 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_footer.html.haml (0.2ms) >[----] I, [2018-09-03T07:53:12.171878 #12665:44c1be4] INFO -- : Completed 200 OK in 190ms (Views: 147.2ms | ActiveRecord: 23.4ms) >[----] I, [2018-09-03T07:53:14.522850 #12675:15b28e4] INFO -- : Started GET "/api/notifications?expand=resources&attributes=details&sort_by=id&sort_order=desc&limit=100" for 127.0.0.1 at 2018-09-03 07:53:14 -0400 >[----] I, [2018-09-03T07:53:14.526289 #12675:15b28e4] INFO -- : Processing by Api::NotificationsController#index as JSON >[----] I, [2018-09-03T07:53:14.526389 #12675:15b28e4] INFO -- : Parameters: {"expand"=>"resources", "attributes"=>"details", "sort_by"=>"id", "sort_order"=>"desc", "limit"=>"100"} >[----] I, [2018-09-03T07:53:14.540195 #12665:44bc39c] INFO -- : Started GET "/static/notification_drawer/notification-drawer.html" for 127.0.0.1 at 2018-09-03 07:53:14 -0400 >[----] I, [2018-09-03T07:53:14.543085 #12665:44bc39c] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:14.543202 #12665:44bc39c] INFO -- : Parameters: {"id"=>"notification_drawer/notification-drawer.html"} >[----] I, [2018-09-03T07:53:14.544006 #12665:44bc39c] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-drawer.html.haml >[----] I, [2018-09-03T07:53:14.544270 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-drawer.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:14.544504 #12665:44bc39c] INFO -- : Completed 200 OK in 1ms (Views: 0.8ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:14.546886 #12675:15b28e4] INFO -- : Completed 200 OK in 20ms (Views: 0.1ms | ActiveRecord: 3.2ms) >[----] I, [2018-09-03T07:53:14.784802 #12665:44bc108] INFO -- : Started GET "/static/dropdown-menu.html.haml" for 127.0.0.1 at 2018-09-03 07:53:14 -0400 >[----] I, [2018-09-03T07:53:14.788532 #12665:44bc108] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:14.788690 #12665:44bc108] INFO -- : Parameters: {"id"=>"dropdown-menu.html.haml"} >[----] I, [2018-09-03T07:53:14.789276 #12665:44bc108] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/dropdown-menu.html.haml >[----] I, [2018-09-03T07:53:14.789431 #12665:44bc108] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/dropdown-menu.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:14.789798 #12665:44bc108] INFO -- : Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:14.795996 #12665:44bc108] INFO -- : Started GET "/dashboard/widget_chart_data//7" for 127.0.0.1 at 2018-09-03 07:53:14 -0400 >[----] I, [2018-09-03T07:53:14.800121 #12665:44bc108] INFO -- : Processing by DashboardController#widget_chart_data as HTML >[----] I, [2018-09-03T07:53:14.800246 #12665:44bc108] INFO -- : Parameters: {"id"=>"7"} >[----] I, [2018-09-03T07:53:14.830743 #12665:44bc554] INFO -- : Started GET "/static/dropdown-menu.html.haml" for 127.0.0.1 at 2018-09-03 07:53:14 -0400 >[----] I, [2018-09-03T07:53:14.834939 #12665:44bc554] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:14.835443 #12665:44c152c] INFO -- : Started GET "/dashboard/widget_report_data//21" for 127.0.0.1 at 2018-09-03 07:53:14 -0400 >[----] I, [2018-09-03T07:53:14.841314 #12665:44bc108] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget_chart.html.haml (4.9ms) >[----] I, [2018-09-03T07:53:14.841535 #12665:44bc554] INFO -- : Parameters: {"id"=>"dropdown-menu.html.haml"} >[----] I, [2018-09-03T07:53:14.847210 #12665:44bc554] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/dropdown-menu.html.haml >[----] I, [2018-09-03T07:53:14.846418 #12665:44bc108] INFO -- : Completed 200 OK in 46ms (Views: 0.5ms | ActiveRecord: 8.5ms) >[----] I, [2018-09-03T07:53:14.844201 #12665:44c152c] INFO -- : Processing by DashboardController#widget_report_data as HTML >[----] I, [2018-09-03T07:53:14.849143 #12665:44c152c] INFO -- : Parameters: {"id"=>"21"} >[----] I, [2018-09-03T07:53:14.847458 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/dropdown-menu.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:14.850070 #12665:44bc554] INFO -- : Completed 200 OK in 3ms (Views: 3.0ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:14.866630 #12665:44bc554] INFO -- : Started GET "/static/dropdown-menu.html.haml" for 127.0.0.1 at 2018-09-03 07:53:14 -0400 >[----] I, [2018-09-03T07:53:14.870893 #12665:44bc554] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:14.871069 #12665:44bc554] INFO -- : Parameters: {"id"=>"dropdown-menu.html.haml"} >[----] I, [2018-09-03T07:53:14.871509 #12665:44bc108] INFO -- : Started GET "/dashboard/widget_report_data//19" for 127.0.0.1 at 2018-09-03 07:53:14 -0400 >[----] I, [2018-09-03T07:53:14.874143 #12665:44bc554] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/dropdown-menu.html.haml >[----] I, [2018-09-03T07:53:14.878358 #12665:44bc108] INFO -- : Processing by DashboardController#widget_report_data as HTML >[----] I, [2018-09-03T07:53:14.880371 #12665:44c152c] INFO -- : Completed 200 OK in 31ms (Views: 0.5ms | ActiveRecord: 12.6ms) >[----] I, [2018-09-03T07:53:14.880639 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/dropdown-menu.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:14.880775 #12665:44bc108] INFO -- : Parameters: {"id"=>"19"} >[----] I, [2018-09-03T07:53:14.882129 #12665:44bc554] INFO -- : Completed 200 OK in 8ms (Views: 8.1ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:14.903541 #12665:44bc108] INFO -- : Completed 200 OK in 20ms (Views: 0.3ms | ActiveRecord: 3.8ms) >[----] I, [2018-09-03T07:53:14.932936 #12665:44bc554] INFO -- : Started GET "/dashboard/widget_report_data//18" for 127.0.0.1 at 2018-09-03 07:53:14 -0400 >[----] I, [2018-09-03T07:53:14.937097 #12665:44bc554] INFO -- : Processing by DashboardController#widget_report_data as HTML >[----] I, [2018-09-03T07:53:14.937224 #12665:44bc554] INFO -- : Parameters: {"id"=>"18"} >[----] I, [2018-09-03T07:53:14.943392 #12665:44bc108] INFO -- : Started GET "/static/dropdown-menu.html.haml" for 127.0.0.1 at 2018-09-03 07:53:14 -0400 >[----] I, [2018-09-03T07:53:14.946330 #12665:44bc108] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:14.946433 #12665:44bc108] INFO -- : Parameters: {"id"=>"dropdown-menu.html.haml"} >[----] I, [2018-09-03T07:53:14.947141 #12665:44bc108] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/dropdown-menu.html.haml >[----] I, [2018-09-03T07:53:14.947441 #12665:44bc108] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/dropdown-menu.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:14.949698 #12665:44bc108] INFO -- : Completed 200 OK in 3ms (Views: 2.8ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:14.968051 #12665:44bc554] INFO -- : Completed 200 OK in 31ms (Views: 0.3ms | ActiveRecord: 10.1ms) >[----] I, [2018-09-03T07:53:14.970116 #12665:44bc108] INFO -- : Started GET "/static/dropdown-menu.html.haml" for 127.0.0.1 at 2018-09-03 07:53:14 -0400 >[----] I, [2018-09-03T07:53:14.974187 #12665:44bc108] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:14.974291 #12665:44bc108] INFO -- : Parameters: {"id"=>"dropdown-menu.html.haml"} >[----] I, [2018-09-03T07:53:14.975123 #12665:44bc108] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/dropdown-menu.html.haml >[----] I, [2018-09-03T07:53:14.975405 #12665:44bc108] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/dropdown-menu.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:14.975789 #12665:44bc108] INFO -- : Completed 200 OK in 1ms (Views: 0.9ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:14.976154 #12665:44bc554] INFO -- : Started GET "/dashboard/widget_chart_data//8" for 127.0.0.1 at 2018-09-03 07:53:14 -0400 >[----] I, [2018-09-03T07:53:14.979264 #12665:44bc554] INFO -- : Processing by DashboardController#widget_chart_data as HTML >[----] I, [2018-09-03T07:53:14.979347 #12665:44bc554] INFO -- : Parameters: {"id"=>"8"} >[----] I, [2018-09-03T07:53:15.000699 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget_chart.html.haml (2.4ms) >[----] I, [2018-09-03T07:53:15.001298 #12665:44bc108] INFO -- : Started GET "/static/dropdown-menu.html.haml" for 127.0.0.1 at 2018-09-03 07:53:15 -0400 >[----] I, [2018-09-03T07:53:15.001648 #12665:44c152c] INFO -- : Started GET "/dashboard/widget_chart_data//1" for 127.0.0.1 at 2018-09-03 07:53:15 -0400 >[----] I, [2018-09-03T07:53:15.003070 #12665:44bc554] INFO -- : Completed 200 OK in 24ms (Views: 0.3ms | ActiveRecord: 3.9ms) >[----] I, [2018-09-03T07:53:15.006057 #12665:44bc108] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:15.008078 #12665:44c152c] INFO -- : Processing by DashboardController#widget_chart_data as HTML >[----] I, [2018-09-03T07:53:15.009433 #12665:44bc108] INFO -- : Parameters: {"id"=>"dropdown-menu.html.haml"} >[----] I, [2018-09-03T07:53:15.009547 #12665:44c152c] INFO -- : Parameters: {"id"=>"1"} >[----] I, [2018-09-03T07:53:15.010782 #12665:44bc108] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/dropdown-menu.html.haml >[----] I, [2018-09-03T07:53:15.016278 #12665:44bc108] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/dropdown-menu.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:15.016505 #12665:44bc108] INFO -- : Completed 200 OK in 6ms (Views: 6.0ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:15.011872 #12665:44bc554] INFO -- : Started GET "/static/notification_drawer/notification-heading.html" for 127.0.0.1 at 2018-09-03 07:53:15 -0400 >[----] I, [2018-09-03T07:53:15.019375 #12665:44bc554] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:15.019445 #12665:44bc554] INFO -- : Parameters: {"id"=>"notification_drawer/notification-heading.html"} >[----] I, [2018-09-03T07:53:15.019905 #12665:44bc554] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-heading.html.haml >[----] I, [2018-09-03T07:53:15.020040 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-heading.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:15.020194 #12665:44bc554] INFO -- : Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:15.024862 #12665:44c1be4] INFO -- : Started GET "/static/notification_drawer/notification-subheading.html" for 127.0.0.1 at 2018-09-03 07:53:15 -0400 >[----] I, [2018-09-03T07:53:15.027020 #12665:44c1be4] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:15.027084 #12665:44c1be4] INFO -- : Parameters: {"id"=>"notification_drawer/notification-subheading.html"} >[----] I, [2018-09-03T07:53:15.027555 #12665:44c1be4] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-subheading.html.haml >[----] I, [2018-09-03T07:53:15.027700 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-subheading.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:15.027854 #12665:44c1be4] INFO -- : Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:15.046436 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/dashboard/_widget_chart.html.haml (2.4ms) >[----] I, [2018-09-03T07:53:15.047817 #12665:44c152c] INFO -- : Completed 200 OK in 37ms (Views: 0.3ms | ActiveRecord: 17.2ms) >[----] I, [2018-09-03T07:53:15.448538 #12665:44bc39c] INFO -- : Started GET "/static/dropdown-menu.html.haml" for 127.0.0.1 at 2018-09-03 07:53:15 -0400 >[----] I, [2018-09-03T07:53:15.451335 #12665:44bc39c] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:15.451420 #12665:44bc39c] INFO -- : Parameters: {"id"=>"dropdown-menu.html.haml"} >[----] I, [2018-09-03T07:53:15.452085 #12665:44bc39c] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/dropdown-menu.html.haml >[----] I, [2018-09-03T07:53:15.452240 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/dropdown-menu.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:15.452453 #12665:44bc39c] INFO -- : Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:15.454288 #12665:44bc108] INFO -- : Started GET "/dashboard/widget_report_data//23" for 127.0.0.1 at 2018-09-03 07:53:15 -0400 >[----] I, [2018-09-03T07:53:15.456593 #12665:44bc108] INFO -- : Processing by DashboardController#widget_report_data as HTML >[----] I, [2018-09-03T07:53:15.456687 #12665:44bc108] INFO -- : Parameters: {"id"=>"23"} >[----] I, [2018-09-03T07:53:15.473375 #12665:44bc39c] INFO -- : Started GET "/static/dropdown-menu.html.haml" for 127.0.0.1 at 2018-09-03 07:53:15 -0400 >[----] I, [2018-09-03T07:53:15.477470 #12665:44bc39c] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:15.477591 #12665:44bc39c] INFO -- : Parameters: {"id"=>"dropdown-menu.html.haml"} >[----] I, [2018-09-03T07:53:15.478093 #12665:44bc39c] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/dropdown-menu.html.haml >[----] I, [2018-09-03T07:53:15.478250 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/dropdown-menu.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:15.478471 #12665:44bc39c] INFO -- : Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:15.482282 #12665:44bc554] INFO -- : Started GET "/dashboard/widget_report_data//20" for 127.0.0.1 at 2018-09-03 07:53:15 -0400 >[----] I, [2018-09-03T07:53:15.484802 #12665:44bc554] INFO -- : Processing by DashboardController#widget_report_data as HTML >[----] I, [2018-09-03T07:53:15.484878 #12665:44bc554] INFO -- : Parameters: {"id"=>"20"} >[----] I, [2018-09-03T07:53:15.489451 #12665:44bc108] INFO -- : Completed 200 OK in 33ms (Views: 0.6ms | ActiveRecord: 11.5ms) >[----] I, [2018-09-03T07:53:15.514069 #12665:44bc554] INFO -- : Completed 200 OK in 29ms (Views: 0.6ms | ActiveRecord: 4.9ms) >[----] I, [2018-09-03T07:53:19.389939 #12675:15b52d8] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:53:19 -0400 >[----] I, [2018-09-03T07:53:19.394566 #12675:15b52d8] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:53:19.394668 #12675:15b52d8] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:53:19.520613 #12675:15b52d8] INFO -- : Completed 200 OK in 126ms (Views: 0.2ms | ActiveRecord: 9.3ms) >[----] I, [2018-09-03T07:53:19.527887 #12675:15b52d8] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:53:19 -0400 >[----] I, [2018-09-03T07:53:19.532221 #12675:15b52d8] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:53:19.532303 #12675:15b52d8] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:53:19.661791 #12675:15b52d8] INFO -- : Completed 200 OK in 129ms (Views: 0.2ms | ActiveRecord: 8.5ms) >[----] I, [2018-09-03T07:53:21.952702 #12665:44c1be4] INFO -- : Started GET "/cloud_volume" for 127.0.0.1 at 2018-09-03 07:53:21 -0400 >[----] I, [2018-09-03T07:53:21.955624 #12665:44c1be4] INFO -- : Processing by CloudVolumeController#index as HTML >[----] I, [2018-09-03T07:53:21.968432 #12665:44c1be4] INFO -- : Redirected to https://192.168.24.200/cloud_volume/show_list >[----] I, [2018-09-03T07:53:21.969296 #12665:44c1be4] INFO -- : Completed 302 Found in 14ms (ActiveRecord: 1.9ms) >[----] I, [2018-09-03T07:53:21.980469 #12665:44c1be4] INFO -- : Started GET "/cloud_volume/show_list" for 127.0.0.1 at 2018-09-03 07:53:21 -0400 >[----] I, [2018-09-03T07:53:21.983176 #12665:44c1be4] INFO -- : Processing by CloudVolumeController#show_list as HTML >[----] I, [2018-09-03T07:53:21.999623 #12665:44c1be4] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/cloud_volume/show_list.html.haml within layouts/application >[----] I, [2018-09-03T07:53:22.000184 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_flash_msg.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:22.000514 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_info_msg.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:22.001524 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/angular/_gtl.html.haml (1.5ms) >[----] I, [2018-09-03T07:53:22.001619 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_gtl.html.haml (1.7ms) >[----] I, [2018-09-03T07:53:22.001687 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/cloud_volume/show_list.html.haml within layouts/application (1.9ms) >[----] I, [2018-09-03T07:53:22.001872 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_doctype.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:22.002711 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/stylesheets/_template50.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:22.003816 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_i18n_js.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:22.005213 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_about_modal.html.haml (1.1ms) >[----] I, [2018-09-03T07:53:22.008923 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_user_options.html.haml (2.9ms) >[----] I, [2018-09-03T07:53:22.009175 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_notifications_drawer.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:22.009332 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_toast_list.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:22.009493 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_spinner.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:22.009646 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_lightbox_panel.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:22.009730 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_header.html.haml (5.7ms) >[----] I, [2018-09-03T07:53:22.021342 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_vertical_navbar.html.haml (11.2ms) >[----] I, [2018-09-03T07:53:22.038719 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/angular/_toolbar.html.haml (17.1ms) >[----] I, [2018-09-03T07:53:22.039026 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_breadcrumbs.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:22.039263 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_tabs.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:22.040244 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_user_input_filter.html.haml (0.6ms) >[----] I, [2018-09-03T07:53:22.040337 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_quick_search.html.haml (0.8ms) >[----] I, [2018-09-03T07:53:22.040893 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_searchbar.html.haml (1.5ms) >[----] I, [2018-09-03T07:53:22.041320 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_info_msg.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:22.041390 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/listnav/_show_list.html.haml (0.2ms) >[----] I, [2018-09-03T07:53:22.041447 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_listnav.html.haml (0.4ms) >[----] I, [2018-09-03T07:53:22.041532 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_center_div_with_listnav.html.haml (31.5ms) >[----] I, [2018-09-03T07:53:22.041660 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_content.html.haml (31.8ms) >[----] I, [2018-09-03T07:53:22.044210 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_flash_msg.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:22.045861 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/exp_atom/_editor.html.haml (0.6ms) >[----] I, [2018-09-03T07:53:22.045949 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_exp_editor.html.haml (1.9ms) >[----] I, [2018-09-03T07:53:22.046021 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_adv_search_body.html.haml (4.0ms) >[----] I, [2018-09-03T07:53:22.048817 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_adv_search_footer.html.haml (2.6ms) >[----] I, [2018-09-03T07:53:22.048906 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_adv_search.html.haml (7.0ms) >[----] I, [2018-09-03T07:53:22.048996 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_footer.html.haml (7.2ms) >[----] I, [2018-09-03T07:53:22.050744 #12665:44c1be4] INFO -- : Completed 200 OK in 67ms (Views: 47.0ms | ActiveRecord: 5.9ms) >[----] I, [2018-09-03T07:53:23.084058 #12675:15b3e4c] INFO -- : Started GET "/api/notifications?expand=resources&attributes=details&sort_by=id&sort_order=desc&limit=100" for 127.0.0.1 at 2018-09-03 07:53:23 -0400 >[----] I, [2018-09-03T07:53:23.085560 #12665:44c152c] INFO -- : Started GET "/static/notification_drawer/notification-drawer.html" for 127.0.0.1 at 2018-09-03 07:53:23 -0400 >[----] I, [2018-09-03T07:53:23.088456 #12665:44c152c] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:23.088550 #12665:44c152c] INFO -- : Parameters: {"id"=>"notification_drawer/notification-drawer.html"} >[----] I, [2018-09-03T07:53:23.088663 #12675:15b3e4c] INFO -- : Processing by Api::NotificationsController#index as JSON >[----] I, [2018-09-03T07:53:23.088776 #12675:15b3e4c] INFO -- : Parameters: {"expand"=>"resources", "attributes"=>"details", "sort_by"=>"id", "sort_order"=>"desc", "limit"=>"100"} >[----] I, [2018-09-03T07:53:23.089182 #12665:44c152c] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-drawer.html.haml >[----] I, [2018-09-03T07:53:23.089415 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-drawer.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:23.089652 #12665:44c152c] INFO -- : Completed 200 OK in 1ms (Views: 0.7ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:23.112431 #12675:15b3e4c] INFO -- : Completed 200 OK in 23ms (Views: 0.1ms | ActiveRecord: 5.3ms) >[----] I, [2018-09-03T07:53:23.388237 #12665:44bc39c] INFO -- : Started GET "/static/notification_drawer/notification-heading.html" for 127.0.0.1 at 2018-09-03 07:53:23 -0400 >[----] I, [2018-09-03T07:53:23.391278 #12665:44bc39c] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:23.391400 #12665:44bc39c] INFO -- : Parameters: {"id"=>"notification_drawer/notification-heading.html"} >[----] I, [2018-09-03T07:53:23.391928 #12665:44bc39c] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-heading.html.haml >[----] I, [2018-09-03T07:53:23.392069 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-heading.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:23.392241 #12665:44bc39c] INFO -- : Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:23.396120 #12665:44bc108] INFO -- : Started GET "/static/notification_drawer/notification-subheading.html" for 127.0.0.1 at 2018-09-03 07:53:23 -0400 >[----] I, [2018-09-03T07:53:23.399004 #12665:44bc108] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:23.399318 #12665:44bc108] INFO -- : Parameters: {"id"=>"notification_drawer/notification-subheading.html"} >[----] I, [2018-09-03T07:53:23.400386 #12665:44bc108] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-subheading.html.haml >[----] I, [2018-09-03T07:53:23.400590 #12665:44bc108] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-subheading.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:23.400840 #12665:44bc108] INFO -- : Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:23.591008 #12665:44bc108] INFO -- : Started POST "/cloud_volume/report_data" for 127.0.0.1 at 2018-09-03 07:53:23 -0400 >[----] I, [2018-09-03T07:53:23.595341 #12665:44bc108] INFO -- : Processing by CloudVolumeController#report_data as HTML >[----] I, [2018-09-03T07:53:23.595582 #12665:44bc108] INFO -- : Parameters: {"model_name"=>"CloudVolume", "model"=>"CloudVolume", "additional_options"=>{"named_scope"=>nil, "gtl_dbname"=>nil, "model"=>"CloudVolume", "match_via_descendants"=>nil, "parent_id"=>nil, "parent_class_name"=>nil, "parent_method"=>nil, "association"=>nil, "view_suffix"=>nil, "row_button"=>nil, "menu_click"=>nil, "sb_controller"=>nil, "embedded"=>nil, "showlinks"=>nil, "policy_sim"=>nil, "in_a_form"=>nil, "lastaction"=>"show_list", "display"=>nil, "gtl_type"=>"list", "supported_features_filter"=>nil, "clickable"=>nil, "no_checkboxes"=>nil}, "cloud_volume"=>{}} >[----] I, [2018-09-03T07:53:23.676240 #12665:44bc108] INFO -- : Completed 200 OK in 81ms (Views: 6.6ms | ActiveRecord: 9.8ms) >[----] I, [2018-09-03T07:53:29.336315 #12675:15b59cc] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:53:29 -0400 >[----] I, [2018-09-03T07:53:29.340026 #12675:15b59cc] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:53:29.340150 #12675:15b59cc] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:53:29.470861 #12675:15b59cc] INFO -- : Completed 200 OK in 131ms (Views: 0.1ms | ActiveRecord: 16.0ms) >[----] I, [2018-09-03T07:53:29.479167 #12675:15b59cc] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:53:29 -0400 >[----] I, [2018-09-03T07:53:29.482335 #12675:15b59cc] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:53:29.482441 #12675:15b59cc] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:53:29.608242 #12675:15b59cc] INFO -- : Completed 200 OK in 126ms (Views: 0.1ms | ActiveRecord: 8.3ms) >[----] I, [2018-09-03T07:53:32.609840 #12665:44bc554] INFO -- : Started POST "/cloud_volume/button?pressed=cloud_volume_new" for 127.0.0.1 at 2018-09-03 07:53:32 -0400 >[----] I, [2018-09-03T07:53:32.612600 #12665:44bc554] INFO -- : Processing by CloudVolumeController#button as JS >[----] I, [2018-09-03T07:53:32.612668 #12665:44bc554] INFO -- : Parameters: {"pressed"=>"cloud_volume_new"} >[----] I, [2018-09-03T07:53:32.627762 #12665:44bc554] INFO -- : Completed 200 OK in 15ms (Views: 0.6ms | ActiveRecord: 1.9ms) >[----] I, [2018-09-03T07:53:32.645041 #12665:44bc554] INFO -- : Started GET "/cloud_volume/new" for 127.0.0.1 at 2018-09-03 07:53:32 -0400 >[----] I, [2018-09-03T07:53:32.648081 #12665:44bc554] INFO -- : Processing by CloudVolumeController#new as HTML >[----] I, [2018-09-03T07:53:32.660652 #12665:44bc554] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/cloud_volume/new.html.haml within layouts/application >[----] I, [2018-09-03T07:53:32.660987 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_flash_msg.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:32.661734 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/angular/_generic_form_buttons.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:32.661871 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/cloud_volume/_common_new_edit.html.haml (0.7ms) >[----] I, [2018-09-03T07:53:32.661947 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/cloud_volume/new.html.haml within layouts/application (1.2ms) >[----] I, [2018-09-03T07:53:32.662120 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_doctype.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:32.662978 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/stylesheets/_template50.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:32.664031 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_i18n_js.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:32.665520 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_about_modal.html.haml (1.2ms) >[----] I, [2018-09-03T07:53:32.668571 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_user_options.html.haml (2.2ms) >[----] I, [2018-09-03T07:53:32.668783 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_notifications_drawer.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:32.668970 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_toast_list.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:32.669152 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_spinner.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:32.669315 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_lightbox_panel.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:32.669381 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_header.html.haml (5.2ms) >[----] I, [2018-09-03T07:53:32.680219 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_vertical_navbar.html.haml (10.5ms) >[----] I, [2018-09-03T07:53:32.680448 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_breadcrumbs.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:32.680610 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_tabs.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:32.680694 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_center_div_no_listnav.html.haml (11.1ms) >[----] I, [2018-09-03T07:53:32.680784 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_content.html.haml (11.3ms) >[----] I, [2018-09-03T07:53:32.681023 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_adv_search.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:32.681104 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_footer.html.haml (0.2ms) >[----] I, [2018-09-03T07:53:32.682790 #12665:44bc554] INFO -- : Completed 200 OK in 35ms (Views: 20.8ms | ActiveRecord: 2.0ms) >[----] I, [2018-09-03T07:53:33.388372 #12665:44c1be4] INFO -- : Started GET "/static/notification_drawer/notification-drawer.html" for 127.0.0.1 at 2018-09-03 07:53:33 -0400 >[----] I, [2018-09-03T07:53:33.389412 #12675:15b2e0c] INFO -- : Started GET "/api/notifications?expand=resources&attributes=details&sort_by=id&sort_order=desc&limit=100" for 127.0.0.1 at 2018-09-03 07:53:33 -0400 >[----] I, [2018-09-03T07:53:33.391245 #12665:44c1be4] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:33.391341 #12665:44c1be4] INFO -- : Parameters: {"id"=>"notification_drawer/notification-drawer.html"} >[----] I, [2018-09-03T07:53:33.391957 #12665:44c1be4] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-drawer.html.haml >[----] I, [2018-09-03T07:53:33.392188 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-drawer.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:33.392396 #12665:44c1be4] INFO -- : Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:33.394277 #12675:15b2e0c] INFO -- : Processing by Api::NotificationsController#index as JSON >[----] I, [2018-09-03T07:53:33.394440 #12675:15b2e0c] INFO -- : Parameters: {"expand"=>"resources", "attributes"=>"details", "sort_by"=>"id", "sort_order"=>"desc", "limit"=>"100"} >[----] I, [2018-09-03T07:53:33.418444 #12675:15b2e0c] INFO -- : Completed 200 OK in 24ms (Views: 0.2ms | ActiveRecord: 5.1ms) >[----] I, [2018-09-03T07:53:33.512220 #12675:15b2e0c] INFO -- : Started GET "/api/providers?expand=resources&attributes=id,name,supports_block_storage&filter[]=supports_block_storage=true" for 127.0.0.1 at 2018-09-03 07:53:33 -0400 >[----] I, [2018-09-03T07:53:33.515746 #12675:15b2e0c] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:53:33.515846 #12675:15b2e0c] INFO -- : Parameters: {"expand"=>"resources", "attributes"=>"id,name,supports_block_storage", "filter"=>["supports_block_storage=true"]} >[----] I, [2018-09-03T07:53:33.558748 #12675:15b2e0c] INFO -- : Completed 200 OK in 43ms (Views: 0.2ms | ActiveRecord: 5.8ms) >[----] I, [2018-09-03T07:53:33.663815 #12665:44c152c] INFO -- : Started GET "/static/notification_drawer/notification-heading.html" for 127.0.0.1 at 2018-09-03 07:53:33 -0400 >[----] I, [2018-09-03T07:53:33.666853 #12665:44c152c] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:33.666936 #12665:44c152c] INFO -- : Parameters: {"id"=>"notification_drawer/notification-heading.html"} >[----] I, [2018-09-03T07:53:33.667457 #12665:44c152c] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-heading.html.haml >[----] I, [2018-09-03T07:53:33.667635 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-heading.html.haml (0.0ms) >[----] I, [2018-09-03T07:53:33.667827 #12665:44c152c] INFO -- : Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:33.669445 #12665:44bc39c] INFO -- : Started GET "/static/notification_drawer/notification-subheading.html" for 127.0.0.1 at 2018-09-03 07:53:33 -0400 >[----] I, [2018-09-03T07:53:33.676132 #12665:44bc39c] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:53:33.676738 #12665:44bc39c] INFO -- : Parameters: {"id"=>"notification_drawer/notification-subheading.html"} >[----] I, [2018-09-03T07:53:33.677503 #12665:44bc39c] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-subheading.html.haml >[----] I, [2018-09-03T07:53:33.677764 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-subheading.html.haml (0.1ms) >[----] I, [2018-09-03T07:53:33.678096 #12665:44bc39c] INFO -- : Completed 200 OK in 1ms (Views: 0.8ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:53:38.563462 #12675:15b28e4] INFO -- : Started GET "/api/providers/21?attributes=type,parent_manager.availability_zones,parent_manager.cloud_tenants,parent_manager.cloud_volume_snapshots" for 127.0.0.1 at 2018-09-03 07:53:38 -0400 >[----] I, [2018-09-03T07:53:38.581219 #12675:15b28e4] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:53:38.581349 #12675:15b28e4] INFO -- : Parameters: {"attributes"=>"type,parent_manager.availability_zones,parent_manager.cloud_tenants,parent_manager.cloud_volume_snapshots", "c_id"=>"21"} >[----] I, [2018-09-03T07:53:38.651868 #12675:15b28e4] INFO -- : Completed 200 OK in 70ms (Views: 0.2ms | ActiveRecord: 11.2ms) >[----] I, [2018-09-03T07:53:53.400663 #12665:44bc108] INFO -- : Started POST "/cloud_volume/create/new?button=add" for 127.0.0.1 at 2018-09-03 07:53:53 -0400 >[----] I, [2018-09-03T07:53:53.405078 #12665:44bc108] INFO -- : Processing by CloudVolumeController#create as JS >[----] I, [2018-09-03T07:53:53.405196 #12665:44bc108] INFO -- : Parameters: {"name"=>"vyeHcRZQcV", "aws_encryption"=>"false", "incremental"=>"false", "force"=>"false", "storage_manager_id"=>"21", "emstype"=>"ManageIQ::Providers::Openstack::StorageManager::CinderManager", "size"=>"1", "cloud_tenant_id"=>"2", "button"=>"add", "id"=>"new"} >[----] I, [2018-09-03T07:53:53.469337 #12665:44bc108] INFO -- : Completed 200 OK in 64ms (Views: 0.9ms | ActiveRecord: 6.8ms) >[----] I, [2018-09-03T07:53:54.728877 #12665:44bc554] INFO -- : Started POST "/cloud_volume/wait_for_task?escape=false&task_id=594" for 127.0.0.1 at 2018-09-03 07:53:54 -0400 >[----] I, [2018-09-03T07:53:54.733312 #12665:44bc554] INFO -- : Processing by CloudVolumeController#wait_for_task as JS >[----] I, [2018-09-03T07:53:54.733465 #12665:44bc554] INFO -- : Parameters: {"authenticity_token"=>"0BCFfECuZZ/TuhNP73tr6a1P5NKfvpFwMe9KKMF40v6eTXBfGpf7X85gRWORSJ7DONfRyOGxresmIJ8yJ+uvFA==", "escape"=>"false", "task_id"=>"594"} >[----] I, [2018-09-03T07:53:54.751687 #12665:44bc554] INFO -- : Completed 200 OK in 18ms (Views: 0.8ms | ActiveRecord: 2.4ms) >[----] I, [2018-09-03T07:53:56.260891 #12665:44c1be4] INFO -- : Started POST "/cloud_volume/wait_for_task?escape=false&task_id=594" for 127.0.0.1 at 2018-09-03 07:53:56 -0400 >[----] I, [2018-09-03T07:53:56.263849 #12665:44c1be4] INFO -- : Processing by CloudVolumeController#wait_for_task as JS >[----] I, [2018-09-03T07:53:56.263926 #12665:44c1be4] INFO -- : Parameters: {"authenticity_token"=>"egXgnFlEPM+IADpYXpdEIdvRO0KDyutgHAqG7N8weAM0WBW/A32iD5XabHQgpLELTkkOWP3F1/sLxVP2OaMF6Q==", "escape"=>"false", "task_id"=>"594"} >[----] I, [2018-09-03T07:53:56.282421 #12665:44c1be4] INFO -- : Completed 200 OK in 18ms (Views: 0.9ms | ActiveRecord: 2.6ms) >[----] I, [2018-09-03T07:53:58.043855 #12665:44c152c] INFO -- : Started POST "/cloud_volume/wait_for_task?escape=false&task_id=594" for 127.0.0.1 at 2018-09-03 07:53:58 -0400 >[----] I, [2018-09-03T07:53:58.047498 #12665:44c152c] INFO -- : Processing by CloudVolumeController#wait_for_task as JS >[----] I, [2018-09-03T07:53:58.047612 #12665:44c152c] INFO -- : Parameters: {"authenticity_token"=>"tmoPz+aW51HapHoyXR2OItxREoON8LJp3+LT+Vlpmzv4N/rsvK95kcd+LB4jLnsIScknmfP/jvLILQbjv/rm0Q==", "escape"=>"false", "task_id"=>"594"} >[----] I, [2018-09-03T07:53:58.064590 #12665:44c152c] INFO -- : Completed 200 OK in 17ms (Views: 0.8ms | ActiveRecord: 2.4ms) >[----] I, [2018-09-03T07:53:58.261430 #3400:11e0f7c] INFO -- : [ActionCable] Broadcasting to notifications_1: {:level=>"success", :created_at=>Mon, 03 Sep 2018 11:53:58 UTC +00:00, :text=>"Creating Volume %{volume_name} completed successfully.", :bindings=>{:volume_name=>{:text=>"vyeHcRZQcV"}}, :id=>"42"} >[----] I, [2018-09-03T07:54:00.073200 #12665:44bc39c] INFO -- : Started POST "/cloud_volume/wait_for_task?escape=false&task_id=594" for 127.0.0.1 at 2018-09-03 07:54:00 -0400 >[----] I, [2018-09-03T07:54:00.076090 #12665:44bc39c] INFO -- : Processing by CloudVolumeController#wait_for_task as JS >[----] I, [2018-09-03T07:54:00.076175 #12665:44bc39c] INFO -- : Parameters: {"authenticity_token"=>"Pl0/3DMzbnj2+GmbZ3/EPaErfucNTIvxBwuoH7lkXutwAMr/aQrwuOsiP7cZTDEXNLNL/XNDt2oQxH0FX/cjAQ==", "escape"=>"false", "task_id"=>"594"} >[----] I, [2018-09-03T07:54:00.097993 #12665:44bc39c] INFO -- : Completed 200 OK in 22ms (Views: 0.6ms | ActiveRecord: 3.2ms) >[----] I, [2018-09-03T07:54:00.110457 #12665:44bc39c] INFO -- : Started GET "/cloud_volume/show_list" for 127.0.0.1 at 2018-09-03 07:54:00 -0400 >[----] I, [2018-09-03T07:54:00.113296 #12665:44bc39c] INFO -- : Processing by CloudVolumeController#show_list as HTML >[----] I, [2018-09-03T07:54:00.132375 #12665:44bc39c] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/cloud_volume/show_list.html.haml within layouts/application >[----] I, [2018-09-03T07:54:00.132984 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_flash_msg.html.haml (0.1ms) >[----] I, [2018-09-03T07:54:00.133302 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_info_msg.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:00.134486 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/angular/_gtl.html.haml (1.7ms) >[----] I, [2018-09-03T07:54:00.134629 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_gtl.html.haml (2.0ms) >[----] I, [2018-09-03T07:54:00.134716 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/cloud_volume/show_list.html.haml within layouts/application (2.2ms) >[----] I, [2018-09-03T07:54:00.134885 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_doctype.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:00.135811 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/stylesheets/_template50.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:00.136974 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_i18n_js.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:00.138420 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_about_modal.html.haml (1.1ms) >[----] I, [2018-09-03T07:54:00.142053 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_user_options.html.haml (2.9ms) >[----] I, [2018-09-03T07:54:00.142311 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_notifications_drawer.html.haml (0.1ms) >[----] I, [2018-09-03T07:54:00.142507 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_toast_list.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:00.142694 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_spinner.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:00.142863 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_lightbox_panel.html.haml (0.1ms) >[----] I, [2018-09-03T07:54:00.142954 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_header.html.haml (5.7ms) >[----] I, [2018-09-03T07:54:00.157031 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_vertical_navbar.html.haml (13.7ms) >[----] I, [2018-09-03T07:54:00.179924 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/angular/_toolbar.html.haml (22.5ms) >[----] I, [2018-09-03T07:54:00.180363 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_breadcrumbs.html.haml (0.1ms) >[----] I, [2018-09-03T07:54:00.180664 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_tabs.html.haml (0.1ms) >[----] I, [2018-09-03T07:54:00.181807 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_user_input_filter.html.haml (0.7ms) >[----] I, [2018-09-03T07:54:00.181931 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_quick_search.html.haml (0.9ms) >[----] I, [2018-09-03T07:54:00.182602 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_searchbar.html.haml (1.8ms) >[----] I, [2018-09-03T07:54:00.183127 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_info_msg.html.haml (0.1ms) >[----] I, [2018-09-03T07:54:00.183230 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/listnav/_show_list.html.haml (0.3ms) >[----] I, [2018-09-03T07:54:00.183296 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_listnav.html.haml (0.5ms) >[----] I, [2018-09-03T07:54:00.183355 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_center_div_with_listnav.html.haml (40.2ms) >[----] I, [2018-09-03T07:54:00.183520 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_content.html.haml (40.4ms) >[----] I, [2018-09-03T07:54:00.186546 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_flash_msg.html.haml (0.1ms) >[----] I, [2018-09-03T07:54:00.188483 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/exp_atom/_editor.html.haml (0.7ms) >[----] I, [2018-09-03T07:54:00.188607 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_exp_editor.html.haml (2.3ms) >[----] I, [2018-09-03T07:54:00.188677 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_adv_search_body.html.haml (4.7ms) >[----] I, [2018-09-03T07:54:00.191479 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_adv_search_footer.html.haml (2.7ms) >[----] I, [2018-09-03T07:54:00.191616 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_adv_search.html.haml (7.8ms) >[----] I, [2018-09-03T07:54:00.191710 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_footer.html.haml (8.0ms) >[----] I, [2018-09-03T07:54:00.193253 #12665:44bc39c] INFO -- : Completed 200 OK in 80ms (Views: 56.5ms | ActiveRecord: 6.4ms) >[----] I, [2018-09-03T07:54:00.823382 #12675:15b52d8] INFO -- : Started GET "/api/notifications?expand=resources&attributes=details&sort_by=id&sort_order=desc&limit=100" for 127.0.0.1 at 2018-09-03 07:54:00 -0400 >[----] I, [2018-09-03T07:54:00.827860 #12675:15b52d8] INFO -- : Processing by Api::NotificationsController#index as JSON >[----] I, [2018-09-03T07:54:00.828035 #12675:15b52d8] INFO -- : Parameters: {"expand"=>"resources", "attributes"=>"details", "sort_by"=>"id", "sort_order"=>"desc", "limit"=>"100"} >[----] I, [2018-09-03T07:54:00.841153 #12665:44bc108] INFO -- : Started GET "/static/notification_drawer/notification-drawer.html" for 127.0.0.1 at 2018-09-03 07:54:00 -0400 >[----] I, [2018-09-03T07:54:00.845022 #12665:44bc108] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:54:00.845162 #12665:44bc108] INFO -- : Parameters: {"id"=>"notification_drawer/notification-drawer.html"} >[----] I, [2018-09-03T07:54:00.846024 #12665:44bc108] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-drawer.html.haml >[----] I, [2018-09-03T07:54:00.846307 #12665:44bc108] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-drawer.html.haml (0.1ms) >[----] I, [2018-09-03T07:54:00.846645 #12665:44bc108] INFO -- : Completed 200 OK in 1ms (Views: 0.9ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:54:00.876457 #12675:15b52d8] INFO -- : Completed 200 OK in 48ms (Views: 0.2ms | ActiveRecord: 16.7ms) >[----] I, [2018-09-03T07:54:01.063381 #12665:44bc554] INFO -- : Started GET "/static/notification_drawer/notification-heading.html" for 127.0.0.1 at 2018-09-03 07:54:01 -0400 >[----] I, [2018-09-03T07:54:01.066644 #12665:44bc554] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:54:01.066742 #12665:44bc554] INFO -- : Parameters: {"id"=>"notification_drawer/notification-heading.html"} >[----] I, [2018-09-03T07:54:01.067265 #12665:44bc554] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-heading.html.haml >[----] I, [2018-09-03T07:54:01.067443 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-heading.html.haml (0.1ms) >[----] I, [2018-09-03T07:54:01.067722 #12665:44bc554] INFO -- : Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:54:01.073977 #12665:44c1be4] INFO -- : Started GET "/static/notification_drawer/notification-subheading.html" for 127.0.0.1 at 2018-09-03 07:54:01 -0400 >[----] I, [2018-09-03T07:54:01.076427 #12665:44c1be4] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:54:01.076520 #12665:44c1be4] INFO -- : Parameters: {"id"=>"notification_drawer/notification-subheading.html"} >[----] I, [2018-09-03T07:54:01.077002 #12665:44c1be4] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-subheading.html.haml >[----] I, [2018-09-03T07:54:01.077137 #12665:44c1be4] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-subheading.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:01.077306 #12665:44c1be4] INFO -- : Completed 200 OK in 1ms (Views: 0.4ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:54:01.145108 #12665:44c1be4] INFO -- : Started POST "/cloud_volume/report_data" for 127.0.0.1 at 2018-09-03 07:54:01 -0400 >[----] I, [2018-09-03T07:54:01.149714 #12665:44c1be4] INFO -- : Processing by CloudVolumeController#report_data as HTML >[----] I, [2018-09-03T07:54:01.149919 #12665:44c1be4] INFO -- : Parameters: {"model_name"=>"CloudVolume", "model"=>"CloudVolume", "additional_options"=>{"named_scope"=>nil, "gtl_dbname"=>nil, "model"=>"CloudVolume", "match_via_descendants"=>nil, "parent_id"=>nil, "parent_class_name"=>nil, "parent_method"=>nil, "association"=>nil, "view_suffix"=>nil, "row_button"=>nil, "menu_click"=>nil, "sb_controller"=>nil, "embedded"=>nil, "showlinks"=>nil, "policy_sim"=>nil, "in_a_form"=>nil, "lastaction"=>"show_list", "display"=>nil, "gtl_type"=>"list", "supported_features_filter"=>nil, "clickable"=>nil, "no_checkboxes"=>nil}, "cloud_volume"=>{}} >[----] I, [2018-09-03T07:54:01.205083 #12665:44c1be4] INFO -- : Completed 200 OK in 55ms (Views: 1.3ms | ActiveRecord: 6.2ms) >[----] I, [2018-09-03T07:54:01.564635 #12675:15b3e4c] INFO -- : Started POST "/api/notifications" for 127.0.0.1 at 2018-09-03 07:54:01 -0400 >[----] I, [2018-09-03T07:54:01.568560 #12675:15b3e4c] INFO -- : Processing by Api::NotificationsController#update as JSON >[----] I, [2018-09-03T07:54:01.595630 #12675:15b3e4c] INFO -- : Completed 200 OK in 27ms (Views: 0.2ms | ActiveRecord: 4.2ms) >[----] I, [2018-09-03T07:54:05.487334 #12675:15b59cc] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:54:05 -0400 >[----] I, [2018-09-03T07:54:05.490304 #12675:15b59cc] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:54:05.490459 #12675:15b59cc] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:54:05.610332 #12675:15b59cc] INFO -- : Completed 200 OK in 120ms (Views: 0.1ms | ActiveRecord: 8.5ms) >[----] I, [2018-09-03T07:54:05.616829 #12675:15b59cc] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:54:05 -0400 >[----] I, [2018-09-03T07:54:05.620100 #12675:15b59cc] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:54:05.620198 #12675:15b59cc] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:54:05.737546 #12675:15b59cc] INFO -- : Completed 200 OK in 117ms (Views: 0.1ms | ActiveRecord: 7.6ms) >[----] I, [2018-09-03T07:54:06.049059 #12675:15b2e0c] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:54:06 -0400 >[----] I, [2018-09-03T07:54:06.052838 #12675:15b2e0c] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:54:06.052913 #12675:15b2e0c] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:54:06.178802 #12675:15b2e0c] INFO -- : Completed 200 OK in 126ms (Views: 0.2ms | ActiveRecord: 9.5ms) >[----] I, [2018-09-03T07:54:06.184914 #12675:15b2e0c] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:54:06 -0400 >[----] I, [2018-09-03T07:54:06.188134 #12675:15b2e0c] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:54:06.188199 #12675:15b2e0c] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:54:06.319939 #12675:15b2e0c] INFO -- : Completed 200 OK in 132ms (Views: 0.1ms | ActiveRecord: 11.4ms) >[----] I, [2018-09-03T07:54:07.840590 #12675:15b28e4] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:54:07 -0400 >[----] I, [2018-09-03T07:54:07.843883 #12675:15b28e4] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:54:07.843959 #12675:15b28e4] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:54:07.971832 #12675:15b28e4] INFO -- : Completed 200 OK in 128ms (Views: 0.1ms | ActiveRecord: 9.2ms) >[----] I, [2018-09-03T07:54:07.977939 #12675:15b28e4] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:54:07 -0400 >[----] I, [2018-09-03T07:54:07.981011 #12675:15b28e4] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:54:07.981107 #12675:15b28e4] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:54:08.103068 #12675:15b28e4] INFO -- : Completed 200 OK in 122ms (Views: 0.2ms | ActiveRecord: 8.8ms) >[----] I, [2018-09-03T07:54:59.112734 #12675:15b52d8] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22oc_ci%22" for 127.0.0.1 at 2018-09-03 07:54:59 -0400 >[----] I, [2018-09-03T07:54:59.115959 #12675:15b52d8] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:54:59.116084 #12675:15b52d8] INFO -- : Parameters: {"filter"=>["name = \"oc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:54:59.243824 #12675:15b52d8] INFO -- : Completed 200 OK in 128ms (Views: 0.2ms | ActiveRecord: 9.1ms) >[----] I, [2018-09-03T07:54:59.250966 #12675:15b52d8] INFO -- : Started GET "/api/providers/23" for 127.0.0.1 at 2018-09-03 07:54:59 -0400 >[----] I, [2018-09-03T07:54:59.254685 #12675:15b52d8] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:54:59.254803 #12675:15b52d8] INFO -- : Parameters: {"c_id"=>"23", "provider"=>{}} >[----] I, [2018-09-03T07:54:59.386101 #12675:15b52d8] INFO -- : Completed 200 OK in 131ms (Views: 0.3ms | ActiveRecord: 10.4ms) >[----] I, [2018-09-03T07:54:59.395048 #12675:15b52d8] INFO -- : Started POST "/api/providers/23" for 127.0.0.1 at 2018-09-03 07:54:59 -0400 >[----] I, [2018-09-03T07:54:59.398628 #12675:15b52d8] INFO -- : Processing by Api::ProvidersController#update as JSON >[----] I, [2018-09-03T07:54:59.398765 #12675:15b52d8] INFO -- : Parameters: {"c_id"=>"23", "provider"=>{}} >[----] I, [2018-09-03T07:54:59.541704 #12675:15b52d8] INFO -- : Completed 200 OK in 143ms (Views: 0.2ms | ActiveRecord: 15.0ms) >[----] I, [2018-09-03T07:54:59.562083 #12665:44c152c] INFO -- : Started GET "/cloud_volume/show_list" for 127.0.0.1 at 2018-09-03 07:54:59 -0400 >[----] I, [2018-09-03T07:54:59.565042 #12665:44c152c] INFO -- : Processing by CloudVolumeController#show_list as HTML >[----] I, [2018-09-03T07:54:59.582196 #12665:44c152c] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/cloud_volume/show_list.html.haml within layouts/application >[----] I, [2018-09-03T07:54:59.582822 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_flash_msg.html.haml (0.1ms) >[----] I, [2018-09-03T07:54:59.583125 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_info_msg.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:59.584382 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/angular/_gtl.html.haml (1.8ms) >[----] I, [2018-09-03T07:54:59.584467 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_gtl.html.haml (2.0ms) >[----] I, [2018-09-03T07:54:59.584568 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/cloud_volume/show_list.html.haml within layouts/application (2.2ms) >[----] I, [2018-09-03T07:54:59.584770 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_doctype.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:59.585778 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/stylesheets/_template50.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:59.586874 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_i18n_js.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:59.588794 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_about_modal.html.haml (1.6ms) >[----] I, [2018-09-03T07:54:59.593056 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_user_options.html.haml (3.3ms) >[----] I, [2018-09-03T07:54:59.593353 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_notifications_drawer.html.haml (0.1ms) >[----] I, [2018-09-03T07:54:59.593511 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_toast_list.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:59.593693 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_spinner.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:59.593873 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_lightbox_panel.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:59.593948 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_header.html.haml (6.9ms) >[----] I, [2018-09-03T07:54:59.610457 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_vertical_navbar.html.haml (16.0ms) >[----] I, [2018-09-03T07:54:59.630847 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/angular/_toolbar.html.haml (20.0ms) >[----] I, [2018-09-03T07:54:59.631179 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_breadcrumbs.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:59.631364 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_tabs.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:59.632751 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_user_input_filter.html.haml (0.7ms) >[----] I, [2018-09-03T07:54:59.632865 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_quick_search.html.haml (1.0ms) >[----] I, [2018-09-03T07:54:59.633673 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_searchbar.html.haml (2.1ms) >[----] I, [2018-09-03T07:54:59.634326 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_info_msg.html.haml (0.1ms) >[----] I, [2018-09-03T07:54:59.634442 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/listnav/_show_list.html.haml (0.3ms) >[----] I, [2018-09-03T07:54:59.634566 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_listnav.html.haml (0.6ms) >[----] I, [2018-09-03T07:54:59.634638 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_center_div_with_listnav.html.haml (40.3ms) >[----] I, [2018-09-03T07:54:59.634757 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_content.html.haml (40.6ms) >[----] I, [2018-09-03T07:54:59.638067 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_flash_msg.html.haml (0.0ms) >[----] I, [2018-09-03T07:54:59.640833 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/exp_atom/_editor.html.haml (1.1ms) >[----] I, [2018-09-03T07:54:59.640974 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_exp_editor.html.haml (3.1ms) >[----] I, [2018-09-03T07:54:59.641124 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_adv_search_body.html.haml (5.9ms) >[----] I, [2018-09-03T07:54:59.644793 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_adv_search_footer.html.haml (3.4ms) >[----] I, [2018-09-03T07:54:59.644898 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_adv_search.html.haml (9.8ms) >[----] I, [2018-09-03T07:54:59.644993 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_footer.html.haml (10.1ms) >[----] I, [2018-09-03T07:54:59.646938 #12665:44c152c] INFO -- : Completed 200 OK in 82ms (Views: 59.5ms | ActiveRecord: 6.5ms) >[----] I, [2018-09-03T07:55:01.573724 #12675:15b3e4c] INFO -- : Started GET "/api/notifications?expand=resources&attributes=details&sort_by=id&sort_order=desc&limit=100" for 127.0.0.1 at 2018-09-03 07:55:01 -0400 >[----] I, [2018-09-03T07:55:01.578090 #12675:15b3e4c] INFO -- : Processing by Api::NotificationsController#index as JSON >[----] I, [2018-09-03T07:55:01.578170 #12675:15b3e4c] INFO -- : Parameters: {"expand"=>"resources", "attributes"=>"details", "sort_by"=>"id", "sort_order"=>"desc", "limit"=>"100"} >[----] I, [2018-09-03T07:55:01.581563 #12665:44bc39c] INFO -- : Started GET "/static/notification_drawer/notification-drawer.html" for 127.0.0.1 at 2018-09-03 07:55:01 -0400 >[----] I, [2018-09-03T07:55:01.584369 #12665:44bc39c] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:55:01.584445 #12665:44bc39c] INFO -- : Parameters: {"id"=>"notification_drawer/notification-drawer.html"} >[----] I, [2018-09-03T07:55:01.585099 #12665:44bc39c] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-drawer.html.haml >[----] I, [2018-09-03T07:55:01.585297 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-drawer.html.haml (0.1ms) >[----] I, [2018-09-03T07:55:01.585505 #12665:44bc39c] INFO -- : Completed 200 OK in 1ms (Views: 0.7ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:55:01.599509 #12675:15b3e4c] INFO -- : Completed 200 OK in 21ms (Views: 0.2ms | ActiveRecord: 3.1ms) >[----] I, [2018-09-03T07:55:01.822228 #12665:44bc108] INFO -- : Started GET "/static/notification_drawer/notification-heading.html" for 127.0.0.1 at 2018-09-03 07:55:01 -0400 >[----] I, [2018-09-03T07:55:01.826042 #12665:44bc108] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:55:01.826132 #12665:44bc108] INFO -- : Parameters: {"id"=>"notification_drawer/notification-heading.html"} >[----] I, [2018-09-03T07:55:01.826821 #12665:44bc108] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-heading.html.haml >[----] I, [2018-09-03T07:55:01.827006 #12665:44bc108] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-heading.html.haml (0.1ms) >[----] I, [2018-09-03T07:55:01.827221 #12665:44bc108] INFO -- : Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:55:01.828484 #12665:44bc554] INFO -- : Started GET "/static/notification_drawer/notification-subheading.html" for 127.0.0.1 at 2018-09-03 07:55:01 -0400 >[----] I, [2018-09-03T07:55:01.831426 #12665:44bc554] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:55:01.831862 #12665:44bc554] INFO -- : Parameters: {"id"=>"notification_drawer/notification-subheading.html"} >[----] I, [2018-09-03T07:55:01.832597 #12665:44bc554] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-subheading.html.haml >[----] I, [2018-09-03T07:55:01.835555 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-subheading.html.haml (0.1ms) >[----] I, [2018-09-03T07:55:01.835827 #12665:44bc554] INFO -- : Completed 200 OK in 4ms (Views: 3.4ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:55:02.049577 #12665:44c1be4] INFO -- : Started POST "/cloud_volume/report_data" for 127.0.0.1 at 2018-09-03 07:55:02 -0400 >[----] I, [2018-09-03T07:55:02.054335 #12665:44c1be4] INFO -- : Processing by CloudVolumeController#report_data as HTML >[----] I, [2018-09-03T07:55:02.054478 #12665:44c1be4] INFO -- : Parameters: {"model_name"=>"CloudVolume", "model"=>"CloudVolume", "additional_options"=>{"named_scope"=>nil, "gtl_dbname"=>nil, "model"=>"CloudVolume", "match_via_descendants"=>nil, "parent_id"=>nil, "parent_class_name"=>nil, "parent_method"=>nil, "association"=>nil, "view_suffix"=>nil, "row_button"=>nil, "menu_click"=>nil, "sb_controller"=>nil, "embedded"=>nil, "showlinks"=>nil, "policy_sim"=>nil, "in_a_form"=>nil, "lastaction"=>"show_list", "display"=>nil, "gtl_type"=>"list", "supported_features_filter"=>nil, "clickable"=>nil, "no_checkboxes"=>nil}, "cloud_volume"=>{}} >[----] I, [2018-09-03T07:55:02.083350 #12675:15b59cc] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:55:02 -0400 >[----] I, [2018-09-03T07:55:02.087746 #12675:15b59cc] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:55:02.087850 #12675:15b59cc] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:55:02.125620 #12665:44c1be4] INFO -- : Completed 200 OK in 71ms (Views: 1.6ms | ActiveRecord: 9.9ms) >[----] I, [2018-09-03T07:55:02.222447 #12675:15b59cc] INFO -- : Completed 200 OK in 134ms (Views: 0.2ms | ActiveRecord: 10.0ms) >[----] I, [2018-09-03T07:55:02.229813 #12675:15b59cc] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:55:02 -0400 >[----] I, [2018-09-03T07:55:02.235084 #12675:15b59cc] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:55:02.235250 #12675:15b59cc] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:55:02.369776 #12675:15b59cc] INFO -- : Completed 200 OK in 134ms (Views: 0.2ms | ActiveRecord: 10.9ms) >[----] I, [2018-09-03T07:55:03.890440 #12675:15b2e0c] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:55:03 -0400 >[----] I, [2018-09-03T07:55:03.894956 #12675:15b2e0c] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:55:03.895089 #12675:15b2e0c] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:55:04.034616 #12675:15b2e0c] INFO -- : Completed 200 OK in 139ms (Views: 0.2ms | ActiveRecord: 11.3ms) >[----] I, [2018-09-03T07:55:04.040960 #12675:15b2e0c] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:55:04 -0400 >[----] I, [2018-09-03T07:55:04.044176 #12675:15b2e0c] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:55:04.044282 #12675:15b2e0c] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:55:04.175985 #12675:15b2e0c] INFO -- : Completed 200 OK in 132ms (Views: 0.1ms | ActiveRecord: 9.1ms) >[----] I, [2018-09-03T07:55:05.816994 #12675:15b28e4] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:55:05 -0400 >[----] I, [2018-09-03T07:55:05.820164 #12675:15b28e4] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:55:05.820247 #12675:15b28e4] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:55:05.945189 #12675:15b28e4] INFO -- : Completed 200 OK in 125ms (Views: 0.1ms | ActiveRecord: 9.1ms) >[----] I, [2018-09-03T07:55:05.951121 #12675:15b28e4] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:55:05 -0400 >[----] I, [2018-09-03T07:55:05.953975 #12675:15b28e4] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:55:05.954055 #12675:15b28e4] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:55:06.089678 #12675:15b28e4] INFO -- : Completed 200 OK in 136ms (Views: 0.2ms | ActiveRecord: 8.7ms) >[----] W, [2018-09-03T07:55:24.164938 #22951:11e0f7c] WARN -- : Q-task_id([595]) Scoped order and limit are ignored, it's forced to be batch order and batch size. >[----] I, [2018-09-03T07:55:57.172298 #12675:15b52d8] INFO -- : Started GET "/api/providers?filter%5B%5D=name+%3D+%22oc_ci%22" for 127.0.0.1 at 2018-09-03 07:55:57 -0400 >[----] I, [2018-09-03T07:55:57.175820 #12675:15b52d8] INFO -- : Processing by Api::ProvidersController#index as JSON >[----] I, [2018-09-03T07:55:57.175961 #12675:15b52d8] INFO -- : Parameters: {"filter"=>["name = \"oc_ci\""], "provider"=>{}} >[----] I, [2018-09-03T07:55:57.306798 #12675:15b52d8] INFO -- : Completed 200 OK in 131ms (Views: 0.2ms | ActiveRecord: 9.7ms) >[----] I, [2018-09-03T07:55:57.315220 #12675:15b52d8] INFO -- : Started GET "/api/providers/23" for 127.0.0.1 at 2018-09-03 07:55:57 -0400 >[----] I, [2018-09-03T07:55:57.318631 #12675:15b52d8] INFO -- : Processing by Api::ProvidersController#show as JSON >[----] I, [2018-09-03T07:55:57.318721 #12675:15b52d8] INFO -- : Parameters: {"c_id"=>"23", "provider"=>{}} >[----] I, [2018-09-03T07:55:57.459025 #12675:15b52d8] INFO -- : Completed 200 OK in 140ms (Views: 0.1ms | ActiveRecord: 13.3ms) >[----] I, [2018-09-03T07:55:57.465976 #12675:15b52d8] INFO -- : Started POST "/api/providers/23" for 127.0.0.1 at 2018-09-03 07:55:57 -0400 >[----] I, [2018-09-03T07:55:57.470599 #12675:15b52d8] INFO -- : Processing by Api::ProvidersController#update as JSON >[----] I, [2018-09-03T07:55:57.470759 #12675:15b52d8] INFO -- : Parameters: {"c_id"=>"23", "provider"=>{}} >[----] I, [2018-09-03T07:55:57.616091 #12675:15b52d8] INFO -- : Completed 200 OK in 145ms (Views: 0.2ms | ActiveRecord: 13.8ms) >[----] I, [2018-09-03T07:55:57.634578 #12665:44c152c] INFO -- : Started GET "/cloud_volume/show_list" for 127.0.0.1 at 2018-09-03 07:55:57 -0400 >[----] I, [2018-09-03T07:55:57.637852 #12665:44c152c] INFO -- : Processing by CloudVolumeController#show_list as HTML >[----] I, [2018-09-03T07:55:57.659080 #12665:44c152c] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/cloud_volume/show_list.html.haml within layouts/application >[----] I, [2018-09-03T07:55:57.659734 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_flash_msg.html.haml (0.0ms) >[----] I, [2018-09-03T07:55:57.660104 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_info_msg.html.haml (0.0ms) >[----] I, [2018-09-03T07:55:57.661145 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/angular/_gtl.html.haml (1.7ms) >[----] I, [2018-09-03T07:55:57.661227 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_gtl.html.haml (1.8ms) >[----] I, [2018-09-03T07:55:57.661294 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/cloud_volume/show_list.html.haml within layouts/application (2.0ms) >[----] I, [2018-09-03T07:55:57.661470 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_doctype.html.haml (0.0ms) >[----] I, [2018-09-03T07:55:57.662448 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/stylesheets/_template50.html.haml (0.0ms) >[----] I, [2018-09-03T07:55:57.663628 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_i18n_js.html.haml (0.0ms) >[----] I, [2018-09-03T07:55:57.665151 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_about_modal.html.haml (1.2ms) >[----] I, [2018-09-03T07:55:57.669327 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_user_options.html.haml (3.3ms) >[----] I, [2018-09-03T07:55:57.669622 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_notifications_drawer.html.haml (0.1ms) >[----] I, [2018-09-03T07:55:57.669791 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_toast_list.html.haml (0.0ms) >[----] I, [2018-09-03T07:55:57.669969 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_spinner.html.haml (0.0ms) >[----] I, [2018-09-03T07:55:57.670126 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_lightbox_panel.html.haml (0.0ms) >[----] I, [2018-09-03T07:55:57.670220 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_header.html.haml (6.4ms) >[----] I, [2018-09-03T07:55:57.682851 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_vertical_navbar.html.haml (12.2ms) >[----] I, [2018-09-03T07:55:57.705132 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/angular/_toolbar.html.haml (21.9ms) >[----] I, [2018-09-03T07:55:57.705598 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_breadcrumbs.html.haml (0.1ms) >[----] I, [2018-09-03T07:55:57.705791 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_tabs.html.haml (0.0ms) >[----] I, [2018-09-03T07:55:57.706997 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_user_input_filter.html.haml (0.8ms) >[----] I, [2018-09-03T07:55:57.707080 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_quick_search.html.haml (1.0ms) >[----] I, [2018-09-03T07:55:57.707724 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_searchbar.html.haml (1.8ms) >[----] I, [2018-09-03T07:55:57.708205 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_info_msg.html.haml (0.1ms) >[----] I, [2018-09-03T07:55:57.708282 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/listnav/_show_list.html.haml (0.2ms) >[----] I, [2018-09-03T07:55:57.708341 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_listnav.html.haml (0.4ms) >[----] I, [2018-09-03T07:55:57.708397 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_center_div_with_listnav.html.haml (37.9ms) >[----] I, [2018-09-03T07:55:57.708568 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_content.html.haml (38.2ms) >[----] I, [2018-09-03T07:55:57.711755 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_flash_msg.html.haml (0.1ms) >[----] I, [2018-09-03T07:55:57.713546 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/exp_atom/_editor.html.haml (0.7ms) >[----] I, [2018-09-03T07:55:57.713672 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_exp_editor.html.haml (2.1ms) >[----] I, [2018-09-03T07:55:57.713768 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_adv_search_body.html.haml (4.8ms) >[----] I, [2018-09-03T07:55:57.716939 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_adv_search_footer.html.haml (3.0ms) >[----] I, [2018-09-03T07:55:57.717032 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_adv_search.html.haml (8.2ms) >[----] I, [2018-09-03T07:55:57.717125 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_footer.html.haml (8.4ms) >[----] I, [2018-09-03T07:55:57.719009 #12665:44c152c] INFO -- : Completed 200 OK in 81ms (Views: 54.5ms | ActiveRecord: 7.6ms) >[----] I, [2018-09-03T07:55:59.622303 #12675:15b3e4c] INFO -- : Started GET "/api/notifications?expand=resources&attributes=details&sort_by=id&sort_order=desc&limit=100" for 127.0.0.1 at 2018-09-03 07:55:59 -0400 >[----] I, [2018-09-03T07:55:59.626359 #12675:15b3e4c] INFO -- : Processing by Api::NotificationsController#index as JSON >[----] I, [2018-09-03T07:55:59.626435 #12675:15b3e4c] INFO -- : Parameters: {"expand"=>"resources", "attributes"=>"details", "sort_by"=>"id", "sort_order"=>"desc", "limit"=>"100"} >[----] I, [2018-09-03T07:55:59.632268 #12665:44bc39c] INFO -- : Started GET "/static/notification_drawer/notification-drawer.html" for 127.0.0.1 at 2018-09-03 07:55:59 -0400 >[----] I, [2018-09-03T07:55:59.637186 #12665:44bc39c] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:55:59.637316 #12665:44bc39c] INFO -- : Parameters: {"id"=>"notification_drawer/notification-drawer.html"} >[----] I, [2018-09-03T07:55:59.638286 #12665:44bc39c] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-drawer.html.haml >[----] I, [2018-09-03T07:55:59.638560 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-drawer.html.haml (0.2ms) >[----] I, [2018-09-03T07:55:59.638789 #12665:44bc39c] INFO -- : Completed 200 OK in 1ms (Views: 0.9ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:55:59.653243 #12675:15b3e4c] INFO -- : Completed 200 OK in 27ms (Views: 0.1ms | ActiveRecord: 7.4ms) >[----] I, [2018-09-03T07:55:59.912967 #12665:44bc554] INFO -- : Started GET "/static/notification_drawer/notification-subheading.html" for 127.0.0.1 at 2018-09-03 07:55:59 -0400 >[----] I, [2018-09-03T07:55:59.917074 #12665:44bc554] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:55:59.917177 #12665:44bc554] INFO -- : Parameters: {"id"=>"notification_drawer/notification-subheading.html"} >[----] I, [2018-09-03T07:55:59.917735 #12665:44bc554] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-subheading.html.haml >[----] I, [2018-09-03T07:55:59.917883 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-subheading.html.haml (0.0ms) >[----] I, [2018-09-03T07:55:59.918083 #12665:44bc554] INFO -- : Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:55:59.913449 #12665:44bc108] INFO -- : Started GET "/static/notification_drawer/notification-heading.html" for 127.0.0.1 at 2018-09-03 07:55:59 -0400 >[----] I, [2018-09-03T07:55:59.921107 #12665:44bc108] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:55:59.921191 #12665:44bc108] INFO -- : Parameters: {"id"=>"notification_drawer/notification-heading.html"} >[----] I, [2018-09-03T07:55:59.921691 #12665:44bc108] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-heading.html.haml >[----] I, [2018-09-03T07:55:59.922014 #12665:44bc108] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-heading.html.haml (0.1ms) >[----] I, [2018-09-03T07:55:59.922208 #12665:44bc108] INFO -- : Completed 200 OK in 1ms (Views: 0.7ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:56:00.146678 #12665:44c1be4] INFO -- : Started POST "/cloud_volume/report_data" for 127.0.0.1 at 2018-09-03 07:56:00 -0400 >[----] I, [2018-09-03T07:56:00.151021 #12665:44c1be4] INFO -- : Processing by CloudVolumeController#report_data as HTML >[----] I, [2018-09-03T07:56:00.151176 #12665:44c1be4] INFO -- : Parameters: {"model_name"=>"CloudVolume", "model"=>"CloudVolume", "additional_options"=>{"named_scope"=>nil, "gtl_dbname"=>nil, "model"=>"CloudVolume", "match_via_descendants"=>nil, "parent_id"=>nil, "parent_class_name"=>nil, "parent_method"=>nil, "association"=>nil, "view_suffix"=>nil, "row_button"=>nil, "menu_click"=>nil, "sb_controller"=>nil, "embedded"=>nil, "showlinks"=>nil, "policy_sim"=>nil, "in_a_form"=>nil, "lastaction"=>"show_list", "display"=>nil, "gtl_type"=>"list", "supported_features_filter"=>nil, "clickable"=>nil, "no_checkboxes"=>nil}, "cloud_volume"=>{}} >[----] I, [2018-09-03T07:56:00.176992 #12675:15b59cc] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:56:00 -0400 >[----] I, [2018-09-03T07:56:00.181198 #12675:15b59cc] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:56:00.181383 #12675:15b59cc] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:56:00.215255 #12665:44c1be4] INFO -- : Completed 200 OK in 64ms (Views: 1.7ms | ActiveRecord: 8.2ms) >[----] I, [2018-09-03T07:56:00.326173 #12675:15b59cc] INFO -- : Completed 200 OK in 145ms (Views: 0.1ms | ActiveRecord: 10.7ms) >[----] I, [2018-09-03T07:56:00.332741 #12675:15b59cc] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:56:00 -0400 >[----] I, [2018-09-03T07:56:00.335875 #12675:15b59cc] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:56:00.335968 #12675:15b59cc] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:56:00.461918 #12675:15b59cc] INFO -- : Completed 200 OK in 126ms (Views: 0.1ms | ActiveRecord: 8.4ms) >[----] I, [2018-09-03T07:56:01.990524 #12675:15b2e0c] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:56:01 -0400 >[----] I, [2018-09-03T07:56:01.994351 #12675:15b2e0c] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:56:01.994465 #12675:15b2e0c] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:56:02.119891 #12675:15b2e0c] INFO -- : Completed 200 OK in 125ms (Views: 0.1ms | ActiveRecord: 9.4ms) >[----] I, [2018-09-03T07:56:02.126399 #12675:15b2e0c] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:56:02 -0400 >[----] I, [2018-09-03T07:56:02.129307 #12675:15b2e0c] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:56:02.129408 #12675:15b2e0c] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:56:02.258582 #12675:15b2e0c] INFO -- : Completed 200 OK in 129ms (Views: 0.1ms | ActiveRecord: 9.2ms) >[----] I, [2018-09-03T07:56:03.891694 #12675:15b28e4] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 07:56:03 -0400 >[----] I, [2018-09-03T07:56:03.895616 #12675:15b28e4] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T07:56:03.895704 #12675:15b28e4] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T07:56:04.026575 #12675:15b28e4] INFO -- : Completed 200 OK in 131ms (Views: 0.1ms | ActiveRecord: 13.4ms) >[----] I, [2018-09-03T07:56:04.033951 #12675:15b28e4] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 07:56:04 -0400 >[----] I, [2018-09-03T07:56:04.038293 #12675:15b28e4] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T07:56:04.038420 #12675:15b28e4] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T07:56:04.172052 #12675:15b28e4] INFO -- : Completed 200 OK in 133ms (Views: 0.2ms | ActiveRecord: 9.4ms) >[----] I, [2018-09-03T07:56:05.349144 #12665:44c152c] INFO -- : Started GET "/cloud_volume/show/12" for 127.0.0.1 at 2018-09-03 07:56:05 -0400 >[----] I, [2018-09-03T07:56:05.353314 #12665:44c152c] INFO -- : Processing by CloudVolumeController#show as HTML >[----] I, [2018-09-03T07:56:05.353491 #12665:44c152c] INFO -- : Parameters: {"id"=>"12"} >[----] I, [2018-09-03T07:56:05.386763 #12665:44c152c] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/cloud_volume/show.html.haml within layouts/application >[----] I, [2018-09-03T07:56:05.387242 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_flash_msg.html.haml (0.0ms) >[----] I, [2018-09-03T07:56:05.417867 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_textual_groups_generic.html.haml (30.7ms) >[----] I, [2018-09-03T07:56:05.418082 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/cloud_volume/show.html.haml within layouts/application (31.1ms) >[----] I, [2018-09-03T07:56:05.418340 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_doctype.html.haml (0.0ms) >[----] I, [2018-09-03T07:56:05.419319 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/stylesheets/_template50.html.haml (0.0ms) >[----] I, [2018-09-03T07:56:05.420567 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_i18n_js.html.haml (0.1ms) >[----] I, [2018-09-03T07:56:05.422169 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_about_modal.html.haml (1.3ms) >[----] I, [2018-09-03T07:56:05.426492 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_user_options.html.haml (3.5ms) >[----] I, [2018-09-03T07:56:05.426780 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_notifications_drawer.html.haml (0.1ms) >[----] I, [2018-09-03T07:56:05.426958 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_toast_list.html.haml (0.0ms) >[----] I, [2018-09-03T07:56:05.427146 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_spinner.html.haml (0.0ms) >[----] I, [2018-09-03T07:56:05.427307 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_lightbox_panel.html.haml (0.0ms) >[----] I, [2018-09-03T07:56:05.427446 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_header.html.haml (6.7ms) >[----] I, [2018-09-03T07:56:05.440425 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_vertical_navbar.html.haml (12.5ms) >[----] I, [2018-09-03T07:56:05.455717 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/angular/_toolbar.html.haml (14.9ms) >[----] I, [2018-09-03T07:56:05.456205 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_breadcrumbs.html.haml (0.1ms) >[----] I, [2018-09-03T07:56:05.456399 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_tabs.html.haml (0.1ms) >[----] I, [2018-09-03T07:56:05.456645 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_searchbar.html.haml (0.1ms) >[----] I, [2018-09-03T07:56:05.459224 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/shared/_quadicon.html.haml (1.9ms) >[----] I, [2018-09-03T07:56:05.461474 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/listnav/_cloud_volume.html.haml (4.5ms) >[----] I, [2018-09-03T07:56:05.461590 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_listnav.html.haml (4.8ms) >[----] I, [2018-09-03T07:56:05.461694 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_center_div_with_listnav.html.haml (33.9ms) >[----] I, [2018-09-03T07:56:05.461825 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_content.html.haml (34.2ms) >[----] I, [2018-09-03T07:56:05.462238 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_adv_search.html.haml (0.0ms) >[----] I, [2018-09-03T07:56:05.462333 #12665:44c152c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/layouts/_footer.html.haml (0.3ms) >[----] I, [2018-09-03T07:56:05.464202 #12665:44c152c] INFO -- : Completed 200 OK in 111ms (Views: 71.7ms | ActiveRecord: 12.7ms) >[----] I, [2018-09-03T07:56:06.062587 #12675:15b52d8] INFO -- : Started GET "/api/notifications?expand=resources&attributes=details&sort_by=id&sort_order=desc&limit=100" for 127.0.0.1 at 2018-09-03 07:56:06 -0400 >[----] I, [2018-09-03T07:56:06.066820 #12675:15b52d8] INFO -- : Processing by Api::NotificationsController#index as JSON >[----] I, [2018-09-03T07:56:06.066928 #12675:15b52d8] INFO -- : Parameters: {"expand"=>"resources", "attributes"=>"details", "sort_by"=>"id", "sort_order"=>"desc", "limit"=>"100"} >[----] I, [2018-09-03T07:56:06.071156 #12665:44bc39c] INFO -- : Started GET "/static/notification_drawer/notification-drawer.html" for 127.0.0.1 at 2018-09-03 07:56:06 -0400 >[----] I, [2018-09-03T07:56:06.074497 #12665:44bc39c] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:56:06.074594 #12665:44bc39c] INFO -- : Parameters: {"id"=>"notification_drawer/notification-drawer.html"} >[----] I, [2018-09-03T07:56:06.075296 #12665:44bc39c] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-drawer.html.haml >[----] I, [2018-09-03T07:56:06.075530 #12665:44bc39c] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-drawer.html.haml (0.1ms) >[----] I, [2018-09-03T07:56:06.075763 #12665:44bc39c] INFO -- : Completed 200 OK in 1ms (Views: 0.7ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:56:06.093148 #12675:15b52d8] INFO -- : Completed 200 OK in 26ms (Views: 0.1ms | ActiveRecord: 7.2ms) >[----] I, [2018-09-03T07:56:06.365500 #12665:44bc554] INFO -- : Started GET "/static/notification_drawer/notification-heading.html" for 127.0.0.1 at 2018-09-03 07:56:06 -0400 >[----] I, [2018-09-03T07:56:06.369207 #12665:44bc554] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:56:06.369324 #12665:44bc554] INFO -- : Parameters: {"id"=>"notification_drawer/notification-heading.html"} >[----] I, [2018-09-03T07:56:06.369965 #12665:44bc554] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-heading.html.haml >[----] I, [2018-09-03T07:56:06.370150 #12665:44bc554] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-heading.html.haml (0.1ms) >[----] I, [2018-09-03T07:56:06.370438 #12665:44bc554] INFO -- : Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms) >[----] I, [2018-09-03T07:56:06.373783 #12665:44bc108] INFO -- : Started GET "/static/notification_drawer/notification-subheading.html" for 127.0.0.1 at 2018-09-03 07:56:06 -0400 >[----] I, [2018-09-03T07:56:06.377022 #12665:44bc108] INFO -- : Processing by StaticController#show as HTML >[----] I, [2018-09-03T07:56:06.377119 #12665:44bc108] INFO -- : Parameters: {"id"=>"notification_drawer/notification-subheading.html"} >[----] I, [2018-09-03T07:56:06.377768 #12665:44bc108] INFO -- : Rendering /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-subheading.html.haml >[----] I, [2018-09-03T07:56:06.378050 #12665:44bc108] INFO -- : Rendered /opt/rh/cfme-gemset/bundler/gems/cfme-ui-classic-8294aa96df09/app/views/static/notification_drawer/notification-subheading.html.haml (0.1ms) >[----] I, [2018-09-03T07:56:06.378616 #12665:44bc108] INFO -- : Completed 200 OK in 1ms (Views: 1.1ms | ActiveRecord: 0.0ms) >[----] W, [2018-09-03T07:56:18.182357 #22951:11e0f7c] WARN -- : Q-task_id([596]) Scoped order and limit are ignored, it's forced to be batch order and batch size. >[----] I, [2018-09-03T07:57:06.017760 #12675:15b3e4c] INFO -- : Started GET "/api" for 127.0.0.1 at 2018-09-03 07:57:06 -0400 >[----] I, [2018-09-03T07:57:06.021458 #12675:15b3e4c] INFO -- : Processing by Api::ApiController#index as JSON >[----] I, [2018-09-03T07:57:06.058291 #12675:15b3e4c] INFO -- : Completed 200 OK in 37ms (Views: 0.2ms | ActiveRecord: 4.2ms) >[----] I, [2018-09-03T07:58:06.019263 #12675:15b59cc] INFO -- : Started GET "/api" for 127.0.0.1 at 2018-09-03 07:58:06 -0400 >[----] I, [2018-09-03T07:58:06.023554 #12675:15b59cc] INFO -- : Processing by Api::ApiController#index as JSON >[----] I, [2018-09-03T07:58:06.060703 #12675:15b59cc] INFO -- : Completed 200 OK in 37ms (Views: 0.1ms | ActiveRecord: 3.0ms) >[----] I, [2018-09-03T07:59:06.015965 #12675:15b2e0c] INFO -- : Started GET "/api" for 127.0.0.1 at 2018-09-03 07:59:06 -0400 >[----] I, [2018-09-03T07:59:06.018958 #12675:15b2e0c] INFO -- : Processing by Api::ApiController#index as JSON >[----] I, [2018-09-03T07:59:06.055011 #12675:15b2e0c] INFO -- : Completed 200 OK in 36ms (Views: 0.1ms | ActiveRecord: 3.3ms) >[----] I, [2018-09-03T08:00:06.016091 #12675:15b28e4] INFO -- : Started GET "/api" for 127.0.0.1 at 2018-09-03 08:00:06 -0400 >[----] I, [2018-09-03T08:00:06.019018 #12675:15b28e4] INFO -- : Processing by Api::ApiController#index as JSON >[----] I, [2018-09-03T08:00:06.051051 #12675:15b28e4] INFO -- : Completed 200 OK in 32ms (Views: 0.1ms | ActiveRecord: 3.1ms) >[----] I, [2018-09-03T08:01:06.016989 #12675:15b52d8] INFO -- : Started GET "/api" for 127.0.0.1 at 2018-09-03 08:01:06 -0400 >[----] I, [2018-09-03T08:01:06.020185 #12675:15b52d8] INFO -- : Processing by Api::ApiController#index as JSON >[----] I, [2018-09-03T08:01:06.057635 #12675:15b52d8] INFO -- : Completed 200 OK in 37ms (Views: 0.2ms | ActiveRecord: 5.5ms) >[----] I, [2018-09-03T08:02:06.015521 #12675:15b3e4c] INFO -- : Started GET "/api" for 127.0.0.1 at 2018-09-03 08:02:06 -0400 >[----] I, [2018-09-03T08:02:06.018412 #12675:15b3e4c] INFO -- : Processing by Api::ApiController#index as JSON >[----] I, [2018-09-03T08:02:06.051823 #12675:15b3e4c] INFO -- : Completed 200 OK in 33ms (Views: 0.2ms | ActiveRecord: 3.4ms) >[----] I, [2018-09-03T08:03:06.016475 #12675:15b59cc] INFO -- : Started GET "/api" for 127.0.0.1 at 2018-09-03 08:03:06 -0400 >[----] I, [2018-09-03T08:03:06.019253 #12675:15b59cc] INFO -- : Processing by Api::ApiController#index as JSON >[----] I, [2018-09-03T08:03:06.051816 #12675:15b59cc] INFO -- : Completed 200 OK in 32ms (Views: 0.1ms | ActiveRecord: 4.0ms) >[----] I, [2018-09-03T08:04:06.015824 #12675:15b2e0c] INFO -- : Started GET "/api" for 127.0.0.1 at 2018-09-03 08:04:06 -0400 >[----] I, [2018-09-03T08:04:06.018729 #12675:15b2e0c] INFO -- : Processing by Api::ApiController#index as JSON >[----] I, [2018-09-03T08:04:06.047128 #12675:15b2e0c] INFO -- : Completed 200 OK in 28ms (Views: 0.1ms | ActiveRecord: 2.9ms) >[----] I, [2018-09-03T08:05:06.017045 #12675:15b28e4] INFO -- : Started GET "/api" for 127.0.0.1 at 2018-09-03 08:05:06 -0400 >[----] I, [2018-09-03T08:05:06.019926 #12675:15b28e4] INFO -- : Processing by Api::ApiController#index as JSON >[----] I, [2018-09-03T08:05:06.053097 #12675:15b28e4] INFO -- : Completed 200 OK in 33ms (Views: 0.2ms | ActiveRecord: 3.2ms) >[----] I, [2018-09-03T08:06:06.016711 #12675:15b52d8] INFO -- : Started GET "/api" for 127.0.0.1 at 2018-09-03 08:06:06 -0400 >[----] I, [2018-09-03T08:06:06.019504 #12675:15b52d8] INFO -- : Processing by Api::ApiController#index as JSON >[----] I, [2018-09-03T08:06:06.047771 #12675:15b52d8] INFO -- : Completed 200 OK in 28ms (Views: 0.2ms | ActiveRecord: 2.9ms) >[----] I, [2018-09-03T08:07:06.014714 #12675:15b3e4c] INFO -- : Started GET "/api" for 127.0.0.1 at 2018-09-03 08:07:06 -0400 >[----] I, [2018-09-03T08:07:06.018599 #12675:15b3e4c] INFO -- : Processing by Api::ApiController#index as JSON >[----] I, [2018-09-03T08:07:06.048045 #12675:15b3e4c] INFO -- : Completed 200 OK in 29ms (Views: 0.2ms | ActiveRecord: 2.9ms) >[----] I, [2018-09-03T08:08:06.015238 #12675:15b59cc] INFO -- : Started GET "/api" for 127.0.0.1 at 2018-09-03 08:08:06 -0400 >[----] I, [2018-09-03T08:08:06.019888 #12675:15b59cc] INFO -- : Processing by Api::ApiController#index as JSON >[----] I, [2018-09-03T08:08:06.057151 #12675:15b59cc] INFO -- : Completed 200 OK in 37ms (Views: 0.2ms | ActiveRecord: 4.5ms) >[----] I, [2018-09-03T08:09:06.017293 #12675:15b2e0c] INFO -- : Started GET "/api" for 127.0.0.1 at 2018-09-03 08:09:06 -0400 >[----] I, [2018-09-03T08:09:06.022559 #12675:15b2e0c] INFO -- : Processing by Api::ApiController#index as JSON >[----] I, [2018-09-03T08:09:06.071090 #12675:15b2e0c] INFO -- : Completed 200 OK in 48ms (Views: 0.2ms | ActiveRecord: 4.4ms) >[----] I, [2018-09-03T08:10:06.016745 #12675:15b28e4] INFO -- : Started GET "/api" for 127.0.0.1 at 2018-09-03 08:10:06 -0400 >[----] I, [2018-09-03T08:10:06.020652 #12675:15b28e4] INFO -- : Processing by Api::ApiController#index as JSON >[----] I, [2018-09-03T08:10:06.059279 #12675:15b28e4] INFO -- : Completed 200 OK in 38ms (Views: 0.2ms | ActiveRecord: 3.7ms) >[----] I, [2018-09-03T08:10:56.210458 #12675:15b52d8] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 08:10:56 -0400 >[----] I, [2018-09-03T08:10:56.213593 #12675:15b52d8] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T08:10:56.213686 #12675:15b52d8] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T08:10:56.333132 #12675:15b52d8] INFO -- : Completed 200 OK in 119ms (Views: 0.1ms | ActiveRecord: 8.6ms) >[----] I, [2018-09-03T08:10:56.341019 #12675:15b52d8] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 08:10:56 -0400 >[----] I, [2018-09-03T08:10:56.344347 #12675:15b52d8] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T08:10:56.344435 #12675:15b52d8] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T08:10:56.465876 #12675:15b52d8] INFO -- : Completed 200 OK in 121ms (Views: 0.1ms | ActiveRecord: 8.0ms) >[----] I, [2018-09-03T08:10:58.229633 #12675:15b3e4c] INFO -- : Started GET "/api/servers?filter%5B%5D=is_master+%3D+True" for 127.0.0.1 at 2018-09-03 08:10:58 -0400 >[----] I, [2018-09-03T08:10:58.234045 #12675:15b3e4c] INFO -- : Processing by Api::ServersController#index as JSON >[----] I, [2018-09-03T08:10:58.234227 #12675:15b3e4c] INFO -- : Parameters: {"filter"=>["is_master = True"], "server"=>{}} >[----] I, [2018-09-03T08:10:58.356378 #12675:15b3e4c] INFO -- : Completed 200 OK in 122ms (Views: 0.1ms | ActiveRecord: 8.5ms) >[----] I, [2018-09-03T08:10:58.362580 #12675:15b3e4c] INFO -- : Started GET "/api/servers/1" for 127.0.0.1 at 2018-09-03 08:10:58 -0400 >[----] I, [2018-09-03T08:10:58.365821 #12675:15b3e4c] INFO -- : Processing by Api::ServersController#show as JSON >[----] I, [2018-09-03T08:10:58.365939 #12675:15b3e4c] INFO -- : Parameters: {"c_id"=>"1", "server"=>{}} >[----] I, [2018-09-03T08:10:58.487553 #12675:15b3e4c] INFO -- : Completed 200 OK in 122ms (Views: 0.2ms | ActiveRecord: 8.4ms) >[----] I, [2018-09-03T08:11:06.015582 #12675:15b59cc] INFO -- : Started GET "/api" for 127.0.0.1 at 2018-09-03 08:11:06 -0400 >[----] I, [2018-09-03T08:11:06.018652 #12675:15b59cc] INFO -- : Processing by Api::ApiController#index as JSON >[----] I, [2018-09-03T08:11:06.054467 #12675:15b59cc] INFO -- : Completed 200 OK in 36ms (Views: 0.2ms | ActiveRecord: 3.6ms) >
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=1480530">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 1624840
:
1480529
| 1480530 |
1480531