Scenario:
1. Create cluster
2. Assign compute and virt roles to slave node
3. Upload configuration for one VM
4. Spawn VM
5. Wait till VM become available for allocation
Actual:
TimeoutError: Timeout waiting 2 available nodes, current nodes:
{0}Name: slave-01_compute_virt, status: ready, online: True
Nailgun app log:
'''
2015-12-09 21:33:37.585 INFO [7f33ad74f880] (manager) Trying to start deployment at cluster 'TestVirtRole'
2015-12-09 21:33:37.608 ERROR [7f33ad74f880] (helpers) create_action_log failed: 'spawn_vms'
2015-12-09 21:33:37.619 INFO [7f33ad74f880] (manager) ApplyChangesTask: execute async starting for task 1
2015-12-09 21:33:37.642 ERROR [7f33ad74f880] (helpers) Extracting of actor_id failed
Traceback (most recent call last):
File "/usr/lib/python2.7/site-packages/nailgun/task/helpers.py", line 317, in prepare_action_log_kwargs
actor_id = action_log.actor_id
AttributeError: 'NoneType' object has no attribute 'actor_id'
2015-12-09 21:33:37.693 INFO [7f33ad74f880] (provisioning_serializers) Node slave-01_compute_virt (id=1, mac=64:5f:68:69:46:df) seems booted with bootstrap image
2015-12-09 21:33:37.818 ERROR [7f33ad74f880] (helpers) Extracting of actor_id failed
Traceback (most recent call last):
File "/usr/lib/python2.7/site-packages/nailgun/task/helpers.py", line 317, in prepare_action_log_kwargs
actor_id = action_log.actor_id
AttributeError: 'NoneType' object has no attribute 'actor_id'
'''
Puppet log on node-1:
'''
2015-12-09 21:53:51 +0000 Exec[generate_vms](provider=posix) (debug): Executing '/usr/bin/generate_vms.sh /etc/libvirt/qemu /var/lib/nova'
2015-12-09 21:53:51 +0000 Puppet (debug): Executing '/usr/bin/generate_vms.sh /etc/libvirt/qemu /var/lib/nova'
2015-12-09 21:53:52 +0000 /Stage[main]/Main/Exec[generate_vms]/returns (notice): error: failed to get domain '1_vm'
2015-12-09 21:53:52 +0000 /Stage[main]/Main/Exec[generate_vms]/returns (notice): error: Domain not found: no domain with matching name '1_vm'
2015-12-09 21:53:52 +0000 /Stage[main]/Main/Exec[generate_vms]/returns (notice): Disks for 1_vm, total number 1
2015-12-09 21:53:52 +0000 /Stage[main]/Main/Exec[generate_vms]/returns (notice): Disk id: 1, disk type: file, disk format: qcow2, disk path: /var/lib/nova/1_vm.img, disk size: 60G
2015-12-09 21:53:52 +0000 /Stage[main]/Main/Exec[generate_vms]/returns (notice): Formatting '/var/lib/nova/1_vm.img', fmt=qcow2 size=64424509440 encryption=off cluster_size=65536 lazy_refcounts=off
2015-12-09 21:53:52 +0000 /Stage[main]/Main/Exec[generate_vms]/returns (notice): Domain 1_vm defined from /tmp/tmp.KJmNFJNVXm
2015-12-09 21:53:52 +0000 /Stage[main]/Main/Exec[generate_vms]/returns (notice):
2015-12-09 21:53:52 +0000 /Stage[main]/Main/Exec[generate_vms]/returns (notice): error: Failed to start domain 1_vm
2015-12-09 21:53:52 +0000 /Stage[main]/Main/Exec[generate_vms]/returns (notice): error: internal error: process exited while connecting to monitor: Could not access KVM kernel module: Permission denied
2015-12-09 21:53:52 +0000 /Stage[main]/Main/Exec[generate_vms]/returns (notice): failed to initialize KVM: Permission denied
2015-12-09 21:53:52 +0000 Puppet (err): /usr/bin/generate_vms.sh /etc/libvirt/qemu /var/lib/nova returned 1 instead of one of [0]
/usr/lib/ruby/vendor_ruby/puppet/util/errors.rb:106:in `fail'
/usr/lib/ruby/vendor_ruby/puppet/type/exec.rb:160:in `sync'
/usr/lib/ruby/vendor_ruby/puppet/transaction/resource_harness.rb:204:in `sync'
/usr/lib/ruby/vendor_ruby/puppet/transaction/resource_harness.rb:128:in `sync_if_needed'
/usr/lib/ruby/vendor_ruby/puppet/transaction/resource_harness.rb:89:in `block in perform_changes'
/usr/lib/ruby/vendor_ruby/puppet/transaction/resource_harness.rb:88:in `each'
/usr/lib/ruby/vendor_ruby/puppet/transaction/resource_harness.rb:88:in `perform_changes'
/usr/lib/ruby/vendor_ruby/puppet/transaction/resource_harness.rb:20:in `evaluate'
/usr/lib/ruby/vendor_ruby/puppet/transaction.rb:204:in `apply'
/usr/lib/ruby/vendor_ruby/puppet/transaction.rb:217:in `eval_resource'
/usr/lib/ruby/vendor_ruby/puppet/transaction.rb:147:in `call'
/usr/lib/ruby/vendor_ruby/puppet/transaction.rb:147:in `block (2 levels) in evaluate'
/usr/lib/ruby/vendor_ruby/puppet/util.rb:335:in `block in thinmark'
/usr/lib/ruby/1.9.1/benchmark.rb:295:in `realtime'
/usr/lib/ruby/vendor_ruby/puppet/util.rb:334:in `thinmark'
/usr/lib/ruby/vendor_ruby/puppet/transaction.rb:147:in `block in evaluate'
/usr/lib/ruby/vendor_ruby/puppet/graph/relationship_graph.rb:118:in `traverse'
/usr/lib/ruby/vendor_ruby/puppet/transaction.rb:138:in `evaluate'
/usr/lib/ruby/vendor_ruby/puppet/resource/catalog.rb:169:in `block in apply'
/usr/lib/ruby/vendor_ruby/puppet/util/log.rb:149:in `with_destination'
/usr/lib/ruby/vendor_ruby/puppet/transaction/report.rb:112:in `as_logging_destination'
/usr/lib/ruby/vendor_ruby/puppet/resource/catalog.rb:168:in `apply'
/usr/lib/ruby/vendor_ruby/puppet/configurer.rb:120:in `block in apply_catalog'
/usr/lib/ruby/vendor_ruby/puppet/util.rb:161:in `block in benchmark'
/usr/lib/ruby/1.9.1/benchmark.rb:295:in `realtime'
/usr/lib/ruby/vendor_ruby/puppet/util.rb:160:in `benchmark'
/usr/lib/ruby/vendor_ruby/puppet/configurer.rb:119:in `apply_catalog'
/usr/lib/ruby/vendor_ruby/puppet/configurer.rb:227:in `run_internal'
/usr/lib/ruby/vendor_ruby/puppet/configurer.rb:134:in `block in run'
/usr/lib/ruby/vendor_ruby/puppet/context.rb:64:in `override'
/usr/lib/ruby/vendor_ruby/puppet.rb:244:in `override'
/usr/lib/ruby/vendor_ruby/puppet/configurer.rb:133:in `run'
/usr/lib/ruby/vendor_ruby/puppet/application/apply.rb:302:in `apply_catalog'
/usr/lib/ruby/vendor_ruby/puppet/application/apply.rb:236:in `block in main'
/usr/lib/ruby/vendor_ruby/puppet/context.rb:64:in `override'
/usr/lib/ruby/vendor_ruby/puppet.rb:244:in `override'
/usr/lib/ruby/vendor_ruby/puppet/application/apply.rb:198:in `main'
/usr/lib/ruby/vendor_ruby/puppet/application/apply.rb:159:in `run_command'
/usr/lib/ruby/vendor_ruby/puppet/application.rb:381:in `block (2 levels) in run'
/usr/lib/ruby/vendor_ruby/puppet/application.rb:507:in `plugin_hook'
/usr/lib/ruby/vendor_ruby/puppet/application.rb:381:in `block in run'
/usr/lib/ruby/vendor_ruby/puppet/util.rb:496:in `exit_on_fail'
/usr/lib/ruby/vendor_ruby/puppet/application.rb:381:in `run'
/usr/lib/ruby/vendor_ruby/puppet/util/command_line.rb:146:in `run'
/usr/lib/ruby/vendor_ruby/puppet/util/command_line.rb:92:in `execute'
/usr/bin/puppet:8:in `<main>'
2015-12-09 21:53:52 +0000 /Stage[main]/Main/Exec[generate_vms]/returns (err): change from notrun to 0 failed: /usr/bin/generate_vms.sh /etc/libvirt/qemu /var/lib/nova returned 1 instead of one of [0]
'''
Env: 8.0-277
At the moment the current issue is hidden by the following one: https:/ /bugs.launchpad .net/fuel/ +bug/1526448