@@ -445,16 +445,22 @@ def _clone_vm(pool_name, provider, dns_plugin, request_id = nil, pool_alias = ni
445445 start = Time . now
446446 provider . create_vm ( pool_name , new_vmname )
447447 finish = format ( '%<time>.2f' , time : Time . now - start )
448+ $logger. log ( 's' , "[+] [#{ pool_name } ] '#{ new_vmname } ' cloned in #{ finish } seconds" )
449+ $metrics. timing ( "clone.#{ pool_name } " , finish )
450+
451+ $logger. log ( 'd' , "[~] [#{ pool_name } ] Obtaining IP for '#{ new_vmname } '" )
452+ ip_start = Time . now
453+ ip = provider . get_vm_ip_address ( new_vmname , pool_name )
454+ ip_finish = format ( '%<time>.2f' , time : Time . now - ip_start )
455+ $logger. log ( 's' , "[+] [#{ pool_name } ] Obtained IP for '#{ new_vmname } ' in #{ ip_finish } seconds" )
448456
449457 @redis . with_metrics do |redis |
450458 redis . pipelined do |pipeline |
451459 pipeline . hset ( "vmpooler__clone__#{ Date . today } " , "#{ pool_name } :#{ new_vmname } " , finish )
452460 pipeline . hset ( "vmpooler__vm__#{ new_vmname } " , 'clone_time' , finish )
461+ pipeline . hset ( "vmpooler__vm__#{ new_vmname } " , 'ip' , ip )
453462 end
454- end
455- $logger. log ( 's' , "[+] [#{ pool_name } ] '#{ new_vmname } ' cloned in #{ finish } seconds" )
456-
457- $metrics. timing ( "clone.#{ pool_name } " , finish )
463+ end
458464
459465 dns_plugin_class_name = get_dns_plugin_class_name_for_pool ( pool_name )
460466 dns_plugin . create_or_replace_record ( new_vmname ) unless dns_plugin_class_name == 'dynamic-dns'
0 commit comments