Comment 8 for bug 1338573

Revision history for this message
Vladimir Sharshov (vsharshov) wrote :

Discovery logs and compare with success installation:

Correct (Astute):

[407] Casting message to fuel: {"method"=>"deploy_resp", "args"=>{"task_uuid"=>"eb13f757-7c91-4869-bd78-410e7f481750", "nodes"=>[{"uid"=>"2", "progress"=>34, "status"=>"deploying", "role"=>"compute"}]}}
[407] Casting message to fuel: {"method"=>"deploy_resp", "args"=>{"task_uuid"=>"eb13f757-7c91-4869-bd78-410e7f481750", "status"=>"ready", "progress"=>100}}

Incorrect (Astute):

422] Casting message to fuel: {"method"=>"deploy_resp", "args"=>{"task_uuid"=>"1f2906a7-0fff-423e-91b6-6686b54cb1a8", "nodes"=>[{"uid"=>"2", "progress"=>34, "status"=>"deploying", "role"=>"compute"}]}}
[422] Casting message to fuel: {"method"=>"deploy_resp", "args"=>{"task_uuid"=>"1f2906a7-0fff-423e-91b6-6686b54cb1a8", "status"=>"ready", "progress"=>100}}

Result: no differents.

Correct (Nailgun):

2014-07-10 08:56:03.961 INFO [7fc90c241700] (receiver) RPC method deploy_resp received: {"status": "ready", "progress": 100, "task_uuid": "eb13f757-7c91-4869-bd78-410e7f481750"}
/usr/lib64/python2.6/site-packages/sqlalchemy/sql/expression.py:1927: SAWarning: The IN-predicate on "nodes.id" was invoked with an empty sequence. This results in a contradiction, which nonetheless can be expensive to evaluate. Consider alternative strategies for improved performance.
  return self._in_impl(operators.in_op, operators.notin_op, other)
2014-07-10 08:56:04.005 DEBUG [7fc90c241700] (receiver) Controller is found, node_id=1, getting it's IP addresses
2014-07-10 08:56:04.039 INFO [7fc90c241700] (notification) Notification: topic: done message: Deployment of environment 'test' is done. Access the OpenStack dashboard (Horizon) at http://172.16.0.2/ or via internal network at http://10.20.0.3/
2014-07-10 08:56:04.039 DEBUG [7fc90c241700] (helpers) Updating task: eb13f757-7c91-4869-bd78-410e7f481750
2014-07-10 08:56:04.052 INFO [7fc90c241700] (helpers) Task eb13f757-7c91-4869-bd78-410e7f481750 (deployment) status is set to ready
2014-07-10 08:56:04.053 INFO [7fc90c241700] (helpers) Task eb13f757-7c91-4869-bd78-410e7f481750 (deployment) progress is set to 100
2014-07-10 08:56:04.053 INFO [7fc90c241700] (helpers) Task eb13f757-7c91-4869-bd78-410e7f481750 (deployment) message is set to Deployment of environment 'test' is done. Access the OpenStack dashboard (Horizon) at http://172.16.0.2/ or via internal network at http://10.20.0.3/
2014-07-10 08:56:04.073 DEBUG [7fc90c241700] (helpers) Updating cluster status: eb13f757-7c91-4869-bd78-410e7f481750 cluster_id: 1 status: ready
2014-07-10 08:56:04.101 DEBUG [7fc90c241700] (helpers) Updating parent task: 00f16f66-0825-480c-a362-80dd3cbe93a2.
2014-07-10 08:56:04.137 DEBUG [7fc90c241700] (helpers) Updating cluster (test (id=1, mode=multinode)) status: from deployment to operational

Incorrect (Nailgun):

2014-07-07 13:17:13.990 INFO [7f376e415700] (receiver) RPC method deploy_resp received: {"status": "ready", "progress": 100, "task_uuid": "1f2906a7-0fff-423e-91b6-6686b54cb1a8"}
/usr/lib64/python2.6/site-packages/sqlalchemy/sql/expression.py:1927: SAWarning: The IN-predicate on "nodes.id" was invoked with an empty sequence. This results in a contradiction, which nonetheless can be expensive to evaluate. Consider alternative strategies for improved performance.
  return self._in_impl(operators.in_op, operators.notin_op, other)
2014-07-07 13:17:14.094 DEBUG [7f376e415700] (receiver) Controller is found, node_id=1, getting it's IP addresses
2014-07-07 13:17:14.124 INFO [7f376e415700] (notification) Notification: topic: done message: Deployment of environment 'Tbilisi' is done. Access the OpenStack dashboard (Horizon) at http://172.18.164.198/ or via internal network at http://10.20.204.3/
2014-07-07 13:17:14.125 DEBUG [7f376e415700] (helpers) Updating task: 1f2906a7-0fff-423e-91b6-6686b54cb1a8
2014-07-07 13:17:14.145 INFO [7f376e415700] (helpers) Task 1f2906a7-0fff-423e-91b6-6686b54cb1a8 (deployment) status is set to ready
2014-07-07 13:17:14.146 INFO [7f376e415700] (helpers) Task 1f2906a7-0fff-423e-91b6-6686b54cb1a8 (deployment) progress is set to 100
2014-07-07 13:17:14.147 INFO [7f376e415700] (helpers) Task 1f2906a7-0fff-423e-91b6-6686b54cb1a8 (deployment) message is set to Deployment of environment 'Tbilisi' is done. Access the OpenStack dashboard (Horizon) at http://172.18.164.198/ or via internal network at http://10.20.204.3/
2014-07-07 13:17:14.218 DEBUG [7f376e415700] (helpers) Updating cluster status: 1f2906a7-0fff-423e-91b6-6686b54cb1a8 cluster_id: 2 status: ready
2014-07-07 13:17:14.263 DEBUG [7f376e415700] (helpers) Updating parent task: ff81523c-b8fd-4185-8a32-3d9577d4f0e6.
2014-07-07 13:17:14.381 DEBUG [7f376e415700] (helpers) Updating cluster (Tbilisi (id=2, mode=multinode)) status: from deployment to operational

Result: no differents.

I guess this error was affect only UI part, because of all part of backend show 100% same result with success and fail results.

Egor Kotko (ykotko), if you try to force a page refresh using F5 or app menu, do you see same page?