hello Andy,
i have deleted all the database record and then i re added all the nodes
but still i experience error in capturing the image, please find the
attachment that show your the logs
please your help,
thank you very much
|31723| Add Local VCL User Account
|31723| Set Local VCL User Account Password
|31723| Test RPC-XML Access
2014-04-28 23:02:52|31723|utils.pm:setup_get_array_choice(9769)|choices argument:
|31723| VCL Base Module
|31723| VCL Image State Module
|31723| Windows OS Module
2014-04-28 23:02:52|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:02:52
2014-04-28 23:02:57|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:02:57
2014-04-28 23:03:02|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:03:02
2014-04-28 23:03:08|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:03:07
2014-04-28 23:03:13|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:03:13
2014-04-28 23:03:15|31723|utils.pm:setup_get_array_choice(9769)|choices argument:
|31723| Capture Base Image
2014-04-28 23:03:17|31723|utils.pm:get_management_node_id(8364)|management_node_id environment variable not set
2014-04-28 23:03:17|31723|utils.pm:get_management_node_id(8370)|get_managementnode_info(): 1
2014-04-28 23:03:18|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:03:18
2014-04-28 23:03:23|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:03:23
2014-04-28 23:03:28|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:03:28
2014-04-28 23:03:33|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:03:33
2014-04-28 23:03:34|31723|utils.pm:get_computer_ids(7962)|found computers matching identifier: 192.168.134.12, IDs: 7
2014-04-28 23:03:34|31723|utils.pm:get_management_node_info(5456)|retrieving current management node info for 'mgmt.encs.concordia.ca' from database, cached data is stale: 65 seconds old
2014-04-28 23:03:34|31723|utils.pm:get_management_node_info(5603)|retrieved management node info: 'mgmt.encs.concordia.ca' (mgmt)
2014-04-28 23:03:34|31723|utils.pm:setup_get_array_choice(9769)|choices argument:
|31723| Generic Linux (VMware)
|31723| OSX Snow Leopard (VMware)
|31723| Ubuntu (VMware)
|31723| Windows 2003 Server (VMware)
|31723| Windows 7 (VMware)
|31723| Windows Server 2008 (VMware)
|31723| Windows Vista (VMware)
|31723| Windows XP (VMware)
2014-04-28 23:03:38|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:03:38
2014-04-28 23:03:43|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:03:43
2014-04-28 23:03:49|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:03:48
2014-04-28 23:03:54|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:03:54
2014-04-28 23:03:59|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:03:59
2014-04-28 23:04:04|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:04:04
2014-04-28 23:04:09|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:04:09
2014-04-28 23:04:14|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:04:14
2014-04-28 23:04:19|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:04:19
2014-04-28 23:04:24|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:04:24
2014-04-28 23:04:29|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:04:29
2014-04-28 23:04:35|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:04:34
2014-04-28 23:04:38|31723|utils.pm:setup_get_array_choice(9769)|choices argument:
|31723| x86
|31723| x86_64
2014-04-28 23:04:40|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:04:40
2014-04-28 23:04:41|31723|utils.pm:setup_get_array_choice(9769)|choices argument:
|31723| Yes
|31723| No
2014-04-28 23:04:45|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:04:45
2014-04-28 23:04:50|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:04:50
2014-04-28 23:04:55|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:04:55
2014-04-28 23:05:00|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:05:00
2014-04-28 23:05:05|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:05:05
2014-04-28 23:05:09|31723|utils.pm:insert_request(8043)|inserted new reload request into request table, request id=4
2014-04-28 23:05:09|31723|utils.pm:insert_request(8074)|inserted new reload request into reservation table, reservation id=4
2014-04-28 23:05:10|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:05:10
2014-04-28 23:05:10|31981|4:4|image|utils.pm:reservation_being_processed(8634)|computerloadlog 'begin' entry does NOT exist for reservation 4
2014-04-28 23:05:11|31981|4:4|image|utils.pm:run_command(8706)|executed command: pgrep -fl 'vcld [0-9]+:4 ', pid: 31928, exit status: 1, output:
2014-04-28 23:05:11|31981|4:4|image|utils.pm:is_management_node_process_running(8939)|process is NOT running, identifier: 'vcld [0-9]+:4 '
2014-04-28 23:05:11|31981|4:4|image|utils.pm:reservation_being_processed(8655)|reservation is NOT currently being processed
2014-04-28 23:05:11|31981|4:4|image|vcld:main(282)|reservation 4 is NOT already being processed
2014-04-28 23:05:11|31981|4:4|image|utils.pm:get_imagemeta_info(4576)|retrieved imagemeta info:
|31981|4:4|image| : {
|31981|4:4|image| : "architecture" => "",
|31981|4:4|image| : "checkuser" => 1,
|31981|4:4|image| : "id" => 2,
|31981|4:4|image| : "postoption" => "",
|31981|4:4|image| : "rootaccess" => 1,
|31981|4:4|image| : "subimages" => 0,
|31981|4:4|image| : "sysprep" => 0,
|31981|4:4|image| : "usergroupid" => ""
|31981|4:4|image| : }
2014-04-28 23:05:11|31981|4:4|image|utils.pm:get_management_node_info(5456)|retrieving current management node info for 'mgmt.encs.concordia.ca' from database, cached data is stale: 486 seconds old
2014-04-28 23:05:11|31981|4:4|image|utils.pm:get_management_node_info(5603)|retrieved management node info: 'mgmt.encs.concordia.ca' (mgmt)
2014-04-28 23:05:11|31981|4:4|image|utils.pm:get_connect_method_info(10059)|attempting to retrieve connect method info:
|31981|4:4|image| imagerevision: 3 - vmwarewin2003-flake3-v0
|31981|4:4|image| OS: 37 - vmwarewin2003
|31981|4:4|image| OS type: 1 - windows
2014-04-28 23:05:11|31981|4:4|image|utils.pm:get_connect_method_info(10123)|RDP: connectmethodid=2, OStypeid=1, OSid=NULL, imagerevisionid=NULL, disabled=0
2014-04-28 23:05:11|31981|4:4|image|vcld:main(287)|retrieved request information from database
2014-04-28 23:05:11|31981|4:4|image|DataStructure.pm:is_parent_reservation(1004)|returning true: parent reservation ID for this request: 4
2014-04-28 23:05:11|31981|4:4|image|utils.pm:update_request_state(1545)|request 4 state updated to: pending, laststate to: image
2014-04-28 23:05:11|31981|4:4|image|utils.pm:insertloadlog(3665)|inserted computer=7, begin, beginning to process, state is image
2014-04-28 23:05:11|31981|4:4|image|vcld:make_new_child(518)|loaded VCL::image module
2014-04-28 23:05:11|31981|4:4|image|vcld:make_new_child(542)|current number of forked kids: 1
2014-04-28 23:05:11|31946|4:4|image|vcld:make_new_child(558)|vcld environment variable set to 0 for this process
2014-04-28 23:05:12|31946|4:4|image|DataStructure.pm:_initialize(643)|image ID argument was specified: noimage, DataStructure object will contain image information for the production imagerevision of this image
2014-04-28 23:05:12|31946|4:4|image|DataStructure.pm:_initialize(674)|retrieved data for imagerevision ID: 1
2014-04-28 23:05:12|31946|4:4|image|DataStructure.pm:_initialize(690)|retrieved data for image ID: 1
2014-04-28 23:05:17|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:05:16
2014-04-28 23:05:18|31946|4:4|image|Module.pm:create_mn_os_object(376)|VCL::Module::OS::Linux::ManagementNode module loaded
2014-04-28 23:05:18|31946|4:4|image|Module.pm:new(196)|VCL::Module::OS::Linux::ManagementNode object created for image noimage, address: 3f7cad8
2014-04-28 23:05:18|31946|4:4|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{0}{computer}{hostname}, data identifier: computer_hostname, data:
|31946|4:4|image| : "mgmt.encs.concordia.ca"
2014-04-28 23:05:18|31946|4:4|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{0}{computer}{NODENAME}, data identifier: computer_node_name, data:
|31946|4:4|image| : "mgmt"
2014-04-28 23:05:18|31946|4:4|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{0}{computer}{SHORTNAME}, data identifier: computer_short_name, data:
|31946|4:4|image| : "mgmt"
2014-04-28 23:05:18|31946|4:4|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{0}{computer}{IPaddress}, data identifier: computer_ip_address, data:
|31946|4:4|image| : "132.205.19.5"
2014-04-28 23:05:18|31946|4:4|image|Module.pm:create_mn_os_object(381)|VCL::Module::OS::Linux::ManagementNode OS object created, address: 3f7cad8
2014-04-28 23:05:18|31946|4:4|image|Module.pm:new(192)|VCL::image object created for state image, address: 3a10ae8
2014-04-28 23:05:18|31946|4:4|image|State.pm:initialize(88)|obtained a database handle for this state process, stored as $ENV{dbh}
2014-04-28 23:05:18|31946|4:4|image|State.pm:check_image_os(790)|no corrections need to be made to image OS: vmwarewin2003
2014-04-28 23:05:18|31946|4:4|image|DataStructure.pm:is_parent_reservation(1004)|returning true: parent reservation ID for this request: 4
2014-04-28 23:05:18|31946|4:4|image|utils.pm:rename_vcld_process(6752)|reservation count: 1
2014-04-28 23:05:18|31946|4:4|image|utils.pm:rename_vcld_process(6771)|PARENTIMAGE: 1
2014-04-28 23:05:18|31946|4:4|image|utils.pm:rename_vcld_process(6772)|SUBIMAGE: 0
2014-04-28 23:05:18|31946|4:4|image|utils.pm:rename_vcld_process(6790)|renamed process to 'vcld 4:4 image flake>flare vmwarewin2003-flake3-v0 admin'
2014-04-28 23:05:18|31946|4:4|image|DataStructure.pm:is_parent_reservation(1004)|returning true: parent reservation ID for this request: 4
2014-04-28 23:05:18|31946|4:4|image|DataStructure.pm:is_parent_reservation(1004)|returning true: parent reservation ID for this request: 4
2014-04-28 23:05:19|31946|4:4|image|Module.pm:create_os_object(308)|VCL::Module::OS::Windows::Version_5::2003 module loaded
2014-04-28 23:05:19|31946|4:4|image|Module.pm:new(196)|VCL::Module::OS::Windows::Version_5::2003 object created for image vmwarewin2003-flake3-v0, address: 3f7f198
2014-04-28 23:05:19|31946|4:4|image|Windows.pm:initialize(214)|beginning Windows module initialization
2014-04-28 23:05:19|31946|4:4|image|Windows.pm:initialize(224)|Windows module initialization complete
2014-04-28 23:05:19|31946|4:4|image|Module.pm:create_os_object(314)|VCL::Module::OS::Windows::Version_5::2003 OS object created, address: 3f7f198
2014-04-28 23:05:19|31946|4:4|image|State.pm:initialize(125)|computer is a VM, attempting to create VM host OS object
2014-04-28 23:05:19|31946|4:4|image|DataStructure.pm:_initialize(619)|computer ID argument was specified, retrieving data for computer ID: 6
2014-04-28 23:05:19|31946|4:4|image|DataStructure.pm:_initialize(643)|image ID argument was specified: 1, DataStructure object will contain image information for the production imagerevision of this image
2014-04-28 23:05:19|31946|4:4|image|DataStructure.pm:_initialize(674)|retrieved data for imagerevision ID: 1
2014-04-28 23:05:19|31946|4:4|image|DataStructure.pm:_initialize(690)|retrieved data for image ID: 1
2014-04-28 23:05:19|31946|4:4|image|Module.pm:create_vmhost_os_object(454)|attempting to load VM host OS module: VCL::Module::OS::Linux::UnixLab (image: 1)
2014-04-28 23:05:19|31946|4:4|image|Module.pm:create_vmhost_os_object(460)|VM host OS module loaded: VCL::Module::OS::Linux::UnixLab
2014-04-28 23:05:19|31946|4:4|image|Module.pm:new(196)|VCL::Module::OS::Linux::UnixLab object created for image noimage, address: 3f7f510
2014-04-28 23:05:19|31946|4:4|image|Module.pm:create_vmhost_os_object(465)|VCL::Module::OS::Linux::UnixLab OS object created, address: 3f7f510
2014-04-28 23:05:19|31946|4:4|image|Module.pm:create_provisioning_object(519)|VCL::Module::Provisioning::libvirt module loaded
2014-04-28 23:05:19|31946|4:4|image|Module.pm:new(200)|VCL::Module::Provisioning::libvirt object created for computer flake, address: 3f9c008
2014-04-28 23:05:19|31946|4:4|image|libvirt.pm:initialize(99)|libvirt driver module directory path: /usr/local/vcl/bin/../lib/VCL/Module/Provisioning/libvirt
2014-04-28 23:05:19|31946|4:4|image|OS.pm:find_files(2917)|attempting to find files on mgmt, base directory path: '/usr/local/vcl/bin/../lib/VCL/Module/Provisioning/libvirt/', pattern: *.pm, command: /usr/bin/find "/usr/local/vcl/bin/../lib/VCL/Module/Provisioning/libvirt/" -type f -iname "*.pm"
2014-04-28 23:05:19|31946|4:4|image|OS.pm:find_files(2945)|files found: 1, base directory: '/usr/local/vcl/bin/../lib/VCL/Module/Provisioning/libvirt/', pattern: '*.pm'
|31946|4:4|image| command: '/usr/bin/find "/usr/local/vcl/bin/../lib/VCL/Module/Provisioning/libvirt/" -type f -iname "*.pm"'
2014-04-28 23:05:19|31946|4:4|image|Module.pm:new(200)|VCL::Module::Provisioning::libvirt::KVM object created for computer flake, address: 476ed50
2014-04-28 23:05:19|31946|4:4|image|utils.pm:run_ssh_command(4902)|executing SSH command on flare:
|31946|4:4|image| /usr/bin/ssh -i /etc/vcl/vcl.key -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x flare 'which virsh' 2>&1
2014-04-28 23:05:20|31946|4:4|image|utils.pm:run_ssh_command(5020)|run_ssh_command output:
|31946|4:4|image| /usr/bin/virsh
2014-04-28 23:05:20|31946|4:4|image|utils.pm:run_ssh_command(5034)|SSH command executed on flare, returning (0, "/usr/bin/virsh")
2014-04-28 23:05:20|31946|4:4|image|KVM.pm:initialize(104)|verified 'virsh' command is available on flare
2014-04-28 23:05:20|31946|4:4|image|utils.pm:run_ssh_command(4902)|executing SSH command on flare:
|31946|4:4|image| /usr/bin/ssh -i /etc/vcl/vcl.key -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x flare 'which qemu-img' 2>&1
2014-04-28 23:05:21|31946|4:4|image|utils.pm:run_ssh_command(5020)|run_ssh_command output:
|31946|4:4|image| /usr/bin/qemu-img
2014-04-28 23:05:21|31946|4:4|image|utils.pm:run_ssh_command(5034)|SSH command executed on flare, returning (0, "/usr/bin/qemu-img")
2014-04-28 23:05:21|31946|4:4|image|KVM.pm:initialize(104)|verified 'qemu-img' command is available on flare
2014-04-28 23:05:21|31946|4:4|image|utils.pm:run_ssh_command(4902)|executing SSH command on flare:
|31946|4:4|image| /usr/bin/ssh -i /etc/vcl/vcl.key -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x flare 'which virt-win-reg' 2>&1
2014-04-28 23:05:21|31946|4:4|image|utils.pm:run_ssh_command(5020)|run_ssh_command output:
|31946|4:4|image| /usr/bin/virt-win-reg
2014-04-28 23:05:21|31946|4:4|image|utils.pm:run_ssh_command(5034)|SSH command executed on flare, returning (0, "/usr/bin/virt-win-reg")
2014-04-28 23:05:21|31946|4:4|image|KVM.pm:initialize(104)|verified 'virt-win-reg' command is available on flare
2014-04-28 23:05:21|31946|4:4|image|KVM.pm:initialize(113)|KVM driver module successfully initialized to control flare
2014-04-28 23:05:21|31946|4:4|image|libvirt.pm:initialize(119)|libvirt KVM driver object created and initialized to control flare
2014-04-28 23:05:21|31946|4:4|image|libvirt.pm:initialize(139)|VCL::Module::Provisioning::libvirt provisioning module initialized
2014-04-28 23:05:21|31946|4:4|image|Module.pm:create_provisioning_object(525)|VCL::Module::Provisioning::libvirt provisioner object created for flake, address: 3f9c008
2014-04-28 23:05:21|31946|4:4|image|State.pm:initialize(154)|returning 1
2014-04-28 23:05:21|31946|4:4|image|vcld:make_new_child(568)|VCL::image object created and initialized
2014-04-28 23:05:21|31946|4:4|image|OS.pm:find_files(2917)|attempting to find files on flare, base directory path: '/var/lib/libvirt/images/', pattern: vmwarewin2003-flake3-v0.*, command: /usr/bin/find "/var/lib/libvirt/images/" -type f -iname "vmwarewin2003-flake3-v0.*"
2014-04-28 23:05:21|31946|4:4|image|OS.pm:find_files(2945)|files found: 0, base directory: '/var/lib/libvirt/images/', pattern: 'vmwarewin2003-flake3-v0.*'
|31946|4:4|image| command: '/usr/bin/find "/var/lib/libvirt/images/" -type f -iname "vmwarewin2003-flake3-v0.*"'
2014-04-28 23:05:21|31946|4:4|image|libvirt.pm:get_master_image_file_path(1173)|constructed master image file path: /var/lib/libvirt/images/vmwarewin2003-flake3-v0.qcow2
2014-04-28 23:05:21|31946|4:4|image|Module.pm:new(203)|VCL::Module::Semaphore object created, address: 4724720
2014-04-28 23:05:21|31946|4:4|image|Module.pm:code_loop_timeout(887)|attempting to open lockfile, maximum of 7200 seconds
2014-04-28 23:05:21|31946|4:4|image|Semaphore.pm:open_lockfile(175)|opened and obtained an exclusive lock on file: /tmp/master-vmwarewin2003-flake3-v0.semaphore
2014-04-28 23:05:21|31946|4:4|image|Semaphore.pm:open_lockfile(182)|wrote to file: /tmp/master-vmwarewin2003-flake3-v0.semaphore, contents:
|31946|4:4|image| '31946 vcld 4:4 image flake>flare vmwarewin2003-flake3-v0 admin'
2014-04-28 23:05:21|31946|4:4|image|Module.pm:code_loop_timeout(900)|attempting to open lockfile, code returned true
2014-04-28 23:05:21|31946|4:4|image|Module.pm:get_semaphore(993)|created 'master:vmwarewin2003-flake3-v0' Semaphore object, memory address: 4724720
2014-04-28 23:05:22|31946|4:4|image|utils.pm:run_ssh_command(4902)|executing SSH command on flare:
|31946|4:4|image| /usr/bin/ssh -i /etc/vcl/vcl.key -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x flare 'stat /var/lib/libvirt/images/vmwarewin2003\-flake3\-v0\.qcow2' 2>&1
2014-04-28 23:05:22|31946|4:4|image|utils.pm:run_ssh_command(5020)|run_ssh_command output:
|31946|4:4|image| stat: cannot stat `/var/lib/libvirt/images/vmwarewin2003-flake3-v0.qcow2': No such file or directory
2014-04-28 23:05:22|31946|4:4|image|utils.pm:run_ssh_command(5030)|SSH command executed on flare, command:
|31946|4:4|image| /usr/bin/ssh -i /etc/vcl/vcl.key -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x flare 'stat /var/lib/libvirt/images/vmwarewin2003\-flake3\-v0\.qcow2' 2>&1
|31946|4:4|image| returning (1, "stat: cannot stat `/var/lib/li...")
2014-04-28 23:05:22|31946|4:4|image|libvirt.pm:find_repository_image_file_paths(2263)|repository path is not configured in the VM host profile for flare
2014-04-28 23:05:22|31946|4:4|image|libvirt.pm:does_image_exist(542)|vmwarewin2003-flake3-v0 image does not exist flare
2014-04-28 23:05:22|31946|4:4|image|Semaphore.pm:release_lockfile(280)|deleted file: /tmp/master-vmwarewin2003-flake3-v0.semaphore
2014-04-28 23:05:22|31946|4:4|image|Semaphore.pm:DESTROY(377)|destroyed Semaphore object, memory address: 4724720
2014-04-28 23:05:22|31946|4:4|image|image.pm:process(145)|image vmwarewin2003-flake3-v0 does not exist in the repository
2014-04-28 23:05:22|31946|4:4|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{4}{image}{lastupdate}, data identifier: image_lastupdate, data:
|31946|4:4|image| : "2014-04-28 23:05:22"
2014-04-28 23:05:22|31946|4:4|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{4}{imagerevision}{datecreated}, data identifier: imagerevision_date_created, data:
|31946|4:4|image| : "2014-04-28 23:05:22"
2014-04-28 23:05:22|31946|4:4|image|image.pm:process(161)|calling provisioning module's capture() subroutine
2014-04-28 23:05:22|31946|4:4|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{4}{imagerevision}{imagename}, data identifier: image_name, data:
|31946|4:4|image| : "win2003-flake-3-v0"
2014-04-28 23:05:22|31946|4:4|image|OS.pm:find_files(2917)|attempting to find files on flare, base directory path: '/var/lib/libvirt/images/', pattern: win2003-flake-3-v0.*, command: /usr/bin/find "/var/lib/libvirt/images/" -type f -iname "win2003-flake-3-v0.*"
2014-04-28 23:05:22|31946|4:4|image|OS.pm:find_files(2945)|files found: 0, base directory: '/var/lib/libvirt/images/', pattern: 'win2003-flake-3-v0.*'
|31946|4:4|image| command: '/usr/bin/find "/var/lib/libvirt/images/" -type f -iname "win2003-flake-3-v0.*"'
2014-04-28 23:05:22|31946|4:4|image|libvirt.pm:get_master_image_file_path(1173)|constructed master image file path: /var/lib/libvirt/images/win2003-flake-3-v0.qcow2
2014-04-28 23:05:22|31946|4:4|image|DataStructure.pm:_automethod(847)|data structure updated, hash path: $self->request_data->{reservation}{4}{image}{imagemeta}{sysprep}, data identifier: imagemeta_sysprep, data:
|31946|4:4|image| : 0
|31946|4:4|image| ---- WARNING ----
|31946|4:4|image| 2014-04-28 23:05:22|31946|4:4|image|utils.pm:run_ssh_command(5006)|attempt 1/3: failed to execute SSH command on flake: 'uname -a', exit status: 255, output:
|31946|4:4|image| ssh output (uname -a): ssh: connect to host flake port 22: Connection refused
|31946|4:4|image| ( 0) utils.pm, run_ssh_command (line: 5006)
|31946|4:4|image| (-1) OS.pm, execute (line: 1992)
|31946|4:4|image| (-2) OS.pm, get_os_type (line: 2282)
|31946|4:4|image| (-3) libvirt.pm, get_active_domain_name (line: 942)
|31946|4:4|image| (-4) libvirt.pm, get_domain_name (line: 899)
|31946|4:4|image| (-5) libvirt.pm, capture (line: 326)
2014-04-28 23:05:22|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:05:22
|31946|4:4|image| ---- WARNING ----
|31946|4:4|image| 2014-04-28 23:05:24|31946|4:4|image|utils.pm:run_ssh_command(5006)|attempt 2/3: failed to execute SSH command on flake: 'uname -a', exit status: 255, output:
|31946|4:4|image| ssh output (uname -a): ssh: connect to host flake port 22: Connection refused
|31946|4:4|image| ( 0) utils.pm, run_ssh_command (line: 5006)
|31946|4:4|image| (-1) OS.pm, execute (line: 1992)
|31946|4:4|image| (-2) OS.pm, get_os_type (line: 2282)
|31946|4:4|image| (-3) libvirt.pm, get_active_domain_name (line: 942)
|31946|4:4|image| (-4) libvirt.pm, get_domain_name (line: 899)
|31946|4:4|image| (-5) libvirt.pm, capture (line: 326)
2014-04-28 23:05:27|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:05:27
|31946|4:4|image| ---- WARNING ----
|31946|4:4|image| 2014-04-28 23:05:28|31946|4:4|image|utils.pm:run_ssh_command(5006)|attempt 3/3: failed to execute SSH command on flake: 'uname -a', exit status: 255, output:
|31946|4:4|image| ssh output (uname -a): ssh: connect to host flake port 22: Connection refused
|31946|4:4|image| ( 0) utils.pm, run_ssh_command (line: 5006)
|31946|4:4|image| (-1) OS.pm, execute (line: 1992)
|31946|4:4|image| (-2) OS.pm, get_os_type (line: 2282)
|31946|4:4|image| (-3) libvirt.pm, get_active_domain_name (line: 942)
|31946|4:4|image| (-4) libvirt.pm, get_domain_name (line: 899)
|31946|4:4|image| (-5) libvirt.pm, capture (line: 326)
|31946|4:4|image| ---- WARNING ----
|31946|4:4|image| 2014-04-28 23:05:28|31946|4:4|image|OS.pm:execute(2000)|failed to run command on flake: uname -a
|31946|4:4|image| ( 0) OS.pm, execute (line: 2000)
|31946|4:4|image| (-1) OS.pm, get_os_type (line: 2282)
|31946|4:4|image| (-2) libvirt.pm, get_active_domain_name (line: 942)
|31946|4:4|image| (-3) libvirt.pm, get_domain_name (line: 899)
|31946|4:4|image| (-4) libvirt.pm, capture (line: 326)
|31946|4:4|image| (-5) image.pm, process (line: 162)
|31946|4:4|image| ---- WARNING ----
|31946|4:4|image| 2014-04-28 23:05:28|31946|4:4|image|OS.pm:get_os_type(2284)|failed to run command to determine OS type currently installed on flake
|31946|4:4|image| ( 0) OS.pm, get_os_type (line: 2284)
|31946|4:4|image| (-1) libvirt.pm, get_active_domain_name (line: 942)
|31946|4:4|image| (-2) libvirt.pm, get_domain_name (line: 899)
|31946|4:4|image| (-3) libvirt.pm, capture (line: 326)
|31946|4:4|image| (-4) image.pm, process (line: 162)
|31946|4:4|image| (-5) vcld, make_new_child (line: 571)
|31946|4:4|image| ---- WARNING ----
|31946|4:4|image| 2014-04-28 23:05:28|31946|4:4|image|libvirt.pm:get_active_domain_name(944)|unable to determine active domain, OS type currently installed on flake could not be determined
|31946|4:4|image| ( 0) libvirt.pm, get_active_domain_name (line: 944)
|31946|4:4|image| (-1) libvirt.pm, get_domain_name (line: 899)
|31946|4:4|image| (-2) libvirt.pm, capture (line: 326)
|31946|4:4|image| (-3) image.pm, process (line: 162)
|31946|4:4|image| (-4) vcld, make_new_child (line: 571)
|31946|4:4|image| (-5) vcld, main (line: 350)
|31946|4:4|image| ---- WARNING ----
|31946|4:4|image| 2014-04-28 23:05:28|31946|4:4|image|libvirt.pm:get_domain_name(905)|unable to determine name of domain to be captured
|31946|4:4|image| ( 0) libvirt.pm, get_domain_name (line: 905)
|31946|4:4|image| (-1) libvirt.pm, capture (line: 326)
|31946|4:4|image| (-2) image.pm, process (line: 162)
|31946|4:4|image| (-3) vcld, make_new_child (line: 571)
|31946|4:4|image| (-4) vcld, main (line: 350)
|31946|4:4|image| ---- WARNING ----
|31946|4:4|image| 2014-04-28 23:05:28|31946|4:4|image|libvirt.pm:capture(328)|unable to capture image on flare, domain name could not be determined
|31946|4:4|image| ( 0) libvirt.pm, capture (line: 328)
|31946|4:4|image| (-1) image.pm, process (line: 162)
|31946|4:4|image| (-2) vcld, make_new_child (line: 571)
|31946|4:4|image| (-3) vcld, main (line: 350)
|31946|4:4|image| ---- WARNING ----
|31946|4:4|image| 2014-04-28 23:05:28|31946|4:4|image|image.pm:process(166)|vmwarewin2003-flake3-v0 image failed to be captured by provisioning module
|31946|4:4|image| ( 0) image.pm, process (line: 166)
|31946|4:4|image| (-1) vcld, make_new_child (line: 571)
|31946|4:4|image| (-2) vcld, main (line: 350)
|31946|4:4|image| ---- CRITICAL ----
|31946|4:4|image| 2014-04-28 23:05:28|31946|4:4|image|image.pm:reservation_failed(387)|win2003-flake-3-v0 image creation failed
|31946|4:4|image| ( 0) image.pm, reservation_failed (line: 387)
|31946|4:4|image| (-1) image.pm, process (line: 167)
|31946|4:4|image| (-2) vcld, make_new_child (line: 571)
|31946|4:4|image| (-3) vcld, main (line: 350)
2014-04-28 23:05:29|31946|4:4|image|utils.pm:mail(1266)|SUCCESS -- Sending mail To: root@localhost, VCL -- NOTICE DELAY Image Creation flake
2014-04-28 23:05:29|31946|4:4|image|utils.pm:update_request_state(1545)|request 4 state updated to: maintenance, laststate to: image
2014-04-28 23:05:29|31946|4:4|image|image.pm:reservation_failed(441)|request state set to maintenance, laststate to image
2014-04-28 23:05:29|31946|4:4|image|utils.pm:update_computer_state(1587)|computer 7 state updated to: maintenance
2014-04-28 23:05:29|31946|4:4|image|image.pm:reservation_failed(449)|flake state set to maintenance
2014-04-28 23:05:29|31946|4:4|image|image.pm:reservation_failed(456)|exiting
2014-04-28 23:05:30|31946|4:4|image|utils.pm:delete_computerloadlog_reservation(6396)|removing computerloadlog entries matching loadstate = begin
2014-04-28 23:05:30|31946|4:4|image|utils.pm:delete_computerloadlog_reservation(6443)|deleted rows from computerloadlog for reservation id=4
2014-04-28 23:05:30|31946|4:4|image|State.pm:DESTROY(929)|VCL::image process duration: 12 seconds
2014-04-28 23:05:33|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:05:32
2014-04-28 23:05:35|31981|vcld:REAPER(721)|VCL process exited for reservation 4, PID: 31946, signal: CHLD
2014-04-28 23:05:35|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:05:35
2014-04-28 23:05:40|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:05:40
2014-04-28 23:05:45|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:05:45
2014-04-28 23:05:50|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:05:50
2014-04-28 23:05:55|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:05:55
2014-04-28 23:06:00|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:06:00
2014-04-28 23:06:05|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:06:05
2014-04-28 23:06:10|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:06:10
2014-04-28 23:06:15|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:06:15
2014-04-28 23:06:20|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:06:20
2014-04-28 23:06:25|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:06:25
2014-04-28 23:06:31|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:06:31
2014-04-28 23:06:36|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:06:36
2014-04-28 23:06:41|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:06:41
2014-04-28 23:06:46|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:06:46
2014-04-28 23:06:51|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:06:51
2014-04-28 23:06:56|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:06:56
2014-04-28 23:07:01|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:07:01
2014-04-28 23:07:06|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:07:06
2014-04-28 23:07:11|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:07:11
2014-04-28 23:07:16|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:07:16
2014-04-28 23:07:21|31981|vcld:main(167)|lastcheckin time updated for management node 1: 2014-04-28 23:07:21
