ObjectNotFound: Task with UUID=44bb1435-3860-4924-bf8c-4d2b56988d47 is not found in DB

[Anastasia Palkina]

"build_id": "2014-08-14_02-01-17",
"ostf_sha": "d2a894d228c1f3c22595a77f04b1e00d09d8e463",
"build_number": "436",
"auth_required": true,
"api": "1.0",
"nailgun_sha": "b5bdd19c2dbeb26ce3bd88270d09f5e7541a3aea",
"production": "docker",
"fuelmain_sha": "9f327045cdd72d406d89063393a499635be5e3d4",
"astute_sha": "b52910642d6de941444901b0f20e95ebbcb2b2e9",
"feature_groups": ["mirantis"],
"release": "5.1",
"fuellib_sha": "4b085bfbf7be973f0aa29d9d5e4f3ebd5bf789a1"

1. Create new environment (CentOS, simple mode)
2. Choose GRE neutron
3. Choose both Ceph and installing ceilometer
4. Add controller, compute and 3 ceph
5. Start deployment
6. There are errors in "RPC Consumer" log:

2014-08-14 14:55:44 ERROR

[7fc383d23700] (receiverd) Traceback (most recent call last):
  File "/usr/lib/python2.6/site-packages/nailgun/rpc/receiverd.py", line 52, in consume_msg
    callback(**body["args"])
  File "/usr/lib/python2.6/site-packages/nailgun/rpc/receiver.py", line 205, in deploy_resp
    fail_if_not_found=True,
  File "/usr/lib/python2.6/site-packages/nailgun/objects/task.py", line 91, in get_by_uuid
    "Task with UUID={0} is not found in DB".format(uuid)
ObjectNotFound: Task with UUID=44bb1435-3860-4924-bf8c-4d2b56988d47 is not found in DB

Also there is message in "Web backend" log:
2014-08-14 11:59:24 DEBUG

[7f0813742740] (task) DeploymentTask.message(task=44bb1435-3860-4924-bf8c-4d2b56988d47)
/usr/lib64/python2.6/site-packages/sqlalchemy/sql/expression.py:1927: SAWarning: The IN-predicate on "roles.name" 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)

And also there are errors in astute.log:

2014-08-14 14:55:44 ERR

[372] 44bb1435-3860-4924-bf8c-4d2b56988d47: cmd: ruby -r 'yaml' -e 'y = YAML.load_file("/etc/astute.yaml"); y["nodes"] = YAML.load_file("/tmp/astute.yaml"); File.open("/etc/astute.yaml", "w") { |f| f.write y.to_yaml }'; puppet apply --logdest syslog --debug -e '$settings=parseyaml($::astute_settings_yaml) $nodes_hash=$settings["nodes"] class {"l23network::hosts_file": nodes => $nodes_hash }'
                                               mcollective error: 44bb1435-3860-4924-bf8c-4d2b56988d47: MCollective agents '4' didn't respond within the allotted time.

2014-08-14 14:55:44 ERR

[372] MCollective agents '4' didn't respond within the allotted time.

[Vladimir Sharshov (vsharshov)]

Deploy error similar to https://bugs.launchpad.net/mos/+bug/1342068.

Looks like env was deleted in time when it still deploying. In common case this is not error or problem, because env was deleted without stop deployment and such error expected.

Received error:

2014-08-14 13:34:51.962 DEBUG [7fc383d23700] (task) Updating cluster status: 44bb1435-3860-4924-bf8c-4d2b56988d47 cluster_id: 1 status: running

2014-08-14 13:55:55.827 INFO [7fc383d23700] (receiver) RPC method deploy_resp received: {"task_uuid": "44bb1435-3860-4924-bf8c-4d2b56988d47", "nodes": [{"status": "error", "progress": 100, "error_type": "deploy", "role": "ceph-osd", "uid": "3"}]}
2014-08-14 13:55:55.877 ERROR [7fc383d23700] (receiverd) Traceback (most recent call last):
  File "/usr/lib/python2.6/site-packages/nailgun/rpc/receiverd.py", line 52, in consume_msg
    callback(**body["args"])
  File "/usr/lib/python2.6/site-packages/nailgun/rpc/receiver.py", line 205, in deploy_resp
    fail_if_not_found=True,
  File "/usr/lib/python2.6/site-packages/nailgun/objects/task.py", line 91, in get_by_uuid
    "Task with UUID={0} is not found in DB".format(uuid)
ObjectNotFound: Task with UUID=44bb1435-3860-4924-bf8c-4d2b56988d47 is not found in DB

But

2014-08-14 13:54:31.818 INFO [7fc383d23700] (receiver) RPC method remove_cluster_resp received: {"status": "ready", "progress": 100, "task_uuid": "79684b87-2193-497c-8947-706a1d49eeca", "nodes": [{"uid": "4"}, {"uid": "1"}, {"uid": "3"}, {"uid": "2"}]}
2014-08-14 13:54:31.820 INFO [7fc383d23700] (receiver) RPC method remove_nodes_resp received: {"status": "ready", "progress": 100, "task_uuid": "79684b87-2193-497c-8947-706a1d49eeca", "nodes": [{"uid": "4"}, {"uid": "1"}, {"uid": "3"}, {"uid": "2"}]}
2014-08-14 13:54:32.102 INFO [7fc383d23700] (notification) Notification: topic: done message: Successfully removed 4 node(s)
2014-08-14 13:54:32.102 DEBUG [7fc383d23700] (task) Updating task: 79684b87-2193-497c-8947-706a1d49eeca
2014-08-14 13:54:32.106 DEBUG [7fc383d23700] (task) Updating cluster status: 79684b87-2193-497c-8947-706a1d49eeca cluster_id: 1 status: ready
2014-08-14 13:54:32.113 DEBUG [7fc383d23700] (receiver) Removing environment itself
2014-08-14 13:54:32.386 INFO [7fc383d23700] (notification) Notification: topic: done message: Environment 'test_ubuntu_ha_vlanseg_ceph' and all its nodes are deleted

[Kostiantyn Danylov (kdanylov)]

> Deploy error similar to https://bugs.launchpad.net/mos/+bug/1342068.

How exactly it similar?
Bug 1342068 described excessive CPU consumption by the logging module.