I started getting this error last nigt.
Retrieving file list VCB error: Other error encountered: vim.fault.TaskInProgress An error occurred, cleaning up...
I have done several backups in the past and 4 other vms on the host backed up just fine.
the one that failed is a microsoft Domain controler.
I looked in the archives and other places on line but did not find a reference to task in progress.
Thanks.
-
adamfalk
- Novice
- Posts: 3
- Liked: never
- Joined: Aug 21, 2009 6:22 pm
- Full Name: Adam Falk
-
Gostev
- Chief Product Officer
- Posts: 31804
- Liked: 7298 times
- Joined: Jan 01, 2006 1:01 am
- Location: Baar, Switzerland
- Contact:
Re: VCB error task in progress
Adam, I do not recall seeing this error reported before either. Could you please send log files from Help > Support information for investigation? Thank you.
-
adamfalk
- Novice
- Posts: 3
- Liked: never
- Joined: Aug 21, 2009 6:22 pm
- Full Name: Adam Falk
Re: VCB error task in progress
I hate to be ignorant, but i do not see a place to upload a zip file of the logs, and I assume that you do not expect me to cut and past, they are large logs.
-
adamfalk
- Novice
- Posts: 3
- Liked: never
- Joined: Aug 21, 2009 6:22 pm
- Full Name: Adam Falk
Re: VCB error task in progress
[21.08.2009 10:32:16] <01> Info Log has been started by ARLIE\adam user (Interactive)
[21.08.2009 10:32:16] <01> Info Logging level is 4
[21.08.2009 10:32:16] <01> Info Module: C:\Program Files\Veeam\Backup and FastSCP\VeeamManager.exe version: 3.1.0.278
[21.08.2009 10:32:16] <01> Info Current culture: en-US (UI: en-US)
[21.08.2009 10:32:16] <01> Info OS: Microsoft Windows NT 6.0.6001 Service Pack 1
[21.08.2009 10:32:17] <01> Info CPU: Intel(R) Xeon(TM) CPU 3.00GHz
[21.08.2009 10:32:17] <01> Info Memory: 4.00 GB
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection 2, Intel(R) PRO/1000 MT Network Connection, Ethernet, Down; Unicast IPs: 169.254.174.98; Gateway IPs: 0.0.0.0;
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection, Marvell Yukon 88E8050 PCI-E ASF Gigabit Ethernet Controller, Ethernet, Up; Unicast IPs: 192.168.0.20; Gateway IPs: 192.168.0.254;
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection 3, VMware Virtual Ethernet Adapter for VMnet1, Ethernet, Up; Unicast IPs: 192.168.187.1; Gateway IPs: 0.0.0.0;
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection 4, VMware Virtual Ethernet Adapter for VMnet8, Ethernet, Up; Unicast IPs: 192.168.112.1; Gateway IPs: 0.0.0.0;
[21.08.2009 10:32:18] <01> Info Network: Loopback Pseudo-Interface 1, Software Loopback Interface 1, Loopback, Up; Unicast IPs: ::1; 127.0.0.1;
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection* 8, isatap.{879D604D-ED9D-4800-9582-85387B4FCFC5}, Tunnel, Down; Unicast IPs: fe80::5efe:192.168.0.20%15;
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection* 9, isatap.{61F23CFC-3334-499C-8670-B4A2AD36D472}, Tunnel, Down; Unicast IPs: fe80::5efe:192.168.112.1%16;
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection* 11, Teredo Tunneling Pseudo-Interface, Tunnel, Down; Unicast IPs: fe80:
7f:fffe%12;
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection* 12, isatap.{BE210E3D-1369-4BDD-BCCC-D140FCFDAC50}, Tunnel, Down;
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection* 13, isatap.{29E8617D-72F3-4FE6-AFC3-FD548AC000E9}, Tunnel, Down; Unicast IPs: fe80::5efe:192.168.187.1%18;
[21.08.2009 10:32:18] <01> Info --------------------------------------------------
[21.08.2009 10:32:18] <01> Info Creating job session, jobID {175b508a-baa9-400e-bc4b-06217051b1c6}, jobName "temp dc"
[21.08.2009 10:32:18] <01> Info Job session {f17df613-dc3b-4aef-b54e-1409e899652a} has been created
[21.08.2009 10:32:18] <01> Info Options: <BackupJobOptions xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:xsd="http://www.w3.org/2001/XMLSchema"><Reta ... ostCommand /></BackupJobOptions>
[21.08.2009 10:32:18] <01> Info VSS options: <CVssOptions xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:xsd="http://www.w3.org/2001/XMLSchema"><Enab ... VssOptions>
[21.08.2009 10:32:18] <01> Info Retry mode: False
[21.08.2009 10:32:18] <01> Info Job task: host "192.168.0.28", object "Arlie-TempDC", type "VM", viType "VirtualMachine"
[21.08.2009 10:32:18] <01> Info Effective VSS options: <?xml version="1.0" encoding="utf-16"?><CVssOptions xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:xsd="http://www.w3.org/2001/XMLSchema"><Enab ... VssOptions>
[21.08.2009 10:32:18] <01> Info Source: VCB
[21.08.2009 10:32:18] <01> Info Method: VCB Backup
[21.08.2009 10:32:18] <01> Info Job operation: Verifying license
[21.08.2009 10:32:18] <01> Info Job operation: Checking backup version
[21.08.2009 10:32:18] <01> Info Job operation: Analyzing object "Arlie-TempDC" (16), host "192.168.0.28"
[21.08.2009 10:32:18] <01> Info [Soap] Outgoing connection '192.168.0.28:443:Adam:False::0:1'.
[21.08.2009 10:32:18] <01> Info [Soap] Creating new connection '192.168.0.28:443:Adam:False::0:1'.
[21.08.2009 10:32:18] <01> Info [Soap] Logging on to "192.168.0.28", port 443, user "Adam", proxy srv: port:0, serviceType: public
[21.08.2009 10:32:20] <01> Info [Soap] Skip SSL error with certificate: RemoteCertificateNameMismatch, RemoteCertificateChainErrors
[21.08.2009 10:32:21] <01> Info [Soap] Host content info: host "192.168.0.28", type "embeddedEsx", version "4.0.0", build "175625", apiVersion "4.0", hostTime "8/21/2009 5:32:20 PM"
[21.08.2009 10:32:21] <01> Info [Soap] Loading 'ha-folder-root:Folder' hierarchy
[21.08.2009 10:32:23] <01> Info [Soap] Loaded 14 hierarchy objects from '192.168.0.28', 1 Datacenter(s), 1 HostSystem(s), 4 VirtualMachine(s), 3 Datastore(s), 1 ResourcePool(s).
[21.08.2009 10:32:23] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is disposing.
[21.08.2009 10:32:23] <01> Info Job operation: Calculating job size
[21.08.2009 10:32:23] <01> Info Total size of 1 objects is 20.00 GB
[21.08.2009 10:32:23] <01> Info Job operation: Creating DB records for backup
[21.08.2009 10:32:23] <01> Info Current backup id: {75d36a63-af67-4336-807e-0c732b597a69}, Previous backup id: {null}
[21.08.2009 10:32:23] <01> Info Job operation: Processing object "Arlie-TempDC" (1 of 1)
[21.08.2009 10:32:23] <01> Info Creating task session, sessionID {f17df613-dc3b-4aef-b54e-1409e899652a}
[21.08.2009 10:32:23] <01> Info Task session {60aa149f-518e-4256-b00e-cea372a58adc} has been created
[21.08.2009 10:32:23] <01> Info -----------------------------------------------------
[21.08.2009 10:32:23] <01> Info Current object "Arlie-TempDC", target host "My Computer", dir "D:\backups\veeam\", file "temp dc"
[21.08.2009 10:32:23] <01> Info Task operation: Validating object
[21.08.2009 10:32:23] <01> Info [Soap] Outgoing connection '192.168.0.28:443:Adam:False::0:1'.
[21.08.2009 10:32:23] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is provided from the cache.
[21.08.2009 10:32:23] <01> Info [Soap] LogUserEvent, ref 16, type VirtualMachine, message "Source: Veeam Backup\nAction: Job "temp dc"\nOperation: Started\nStatus: Success\nID: 60aa149f-518e-4256-b00e-cea372a58adc\nDescription: "
[21.08.2009 10:32:23] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is disposing.
[21.08.2009 10:32:23] <01> Info [Soap] Outgoing connection '192.168.0.28:443:Adam:False::0:1'.
[21.08.2009 10:32:23] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is provided from the cache.
[21.08.2009 10:32:23] <01> Info [Soap] Loading '16:VirtualMachine' hierarchy
[21.08.2009 10:32:23] <01> Info [Soap] Loaded 6 hierarchy objects from '192.168.0.28', 0 Datacenter(s), 1 HostSystem(s), 1 VirtualMachine(s), 3 Datastore(s), 0 ResourcePool(s).
[21.08.2009 10:32:23] <01> Info VM information: name "Arlie-TempDC", ref "16", uuid "564dd975-367b-b744-79dc-42d36a0e7f66", host "esxi01.arlie.local", resourcePool "ha-root-pool", connectionState "connected", powerState "poweredOn", template "False"
[21.08.2009 10:32:23] <01> Info VMX file: "[ESXi01-VMFS] temp dc01/temp dc01.vmx"
[21.08.2009 10:32:23] <01> Info Config file: "[ESXi01-VMFS] temp dc01/temp dc01.vmsd"
[21.08.2009 10:32:23] <01> Info Config file: "[ESXi01-VMFS] temp dc01/temp dc01.nvram"
[21.08.2009 10:32:23] <01> Info Config file: "[ESXi01-VMFS] temp dc01/temp dc01.vmxf"
[21.08.2009 10:32:23] <01> Info Log file: "[ESXi01-VMFS] temp dc01/vmware-7.log"
[21.08.2009 10:32:23] <01> Info Log file: "[ESXi01-VMFS] temp dc01/vmware-8.log"
[21.08.2009 10:32:23] <01> Info Log file: "[ESXi01-VMFS] temp dc01/vmware-9.log"
[21.08.2009 10:32:23] <01> Info Log file: "[ESXi01-VMFS] temp dc01/vmware-4.log"
[21.08.2009 10:32:23] <01> Info Log file: "[ESXi01-VMFS] temp dc01/vmware-5.log"
[21.08.2009 10:32:23] <01> Info Log file: "[ESXi01-VMFS] temp dc01/vmware-6.log"
[21.08.2009 10:32:23] <01> Info Log file: "[ESXi01-VMFS] temp dc01/vmware.log"
[21.08.2009 10:32:23] <01> Info Disk: label "Hard disk 1", path "[ESXi01-VMFS] temp dc01/temp dc01.vmdk", capacity 20.00 GB, mode "persistent"
[21.08.2009 10:32:23] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is disposing.
[21.08.2009 10:32:23] <01> Info Task operation: Checking license
[21.08.2009 10:32:24] <01> Info [Soap] Outgoing connection '192.168.0.28:443:Adam:False::0:1'.
[21.08.2009 10:32:24] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is provided from the cache.
[21.08.2009 10:32:24] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is disposing.
[21.08.2009 10:32:24] <01> Info Task operation: Connecting
[21.08.2009 10:32:24] <01> Info Creating file commander for the host "192.168.0.28" (allowNfc: True)
[21.08.2009 10:32:24] <01> Info [Soap] Outgoing connection '192.168.0.28:443:Adam:False::0:1'.
[21.08.2009 10:32:24] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is provided from the cache.
[21.08.2009 10:32:24] <01> Info Creating file commander for the host "My Computer" (allowNfc: True)
[21.08.2009 10:32:24] <01> Info Task operation: Searching for last backup
[21.08.2009 10:32:24] <01> Info Task operation: Checking VM running state
[21.08.2009 10:32:24] <01> Info Task operation: Searching for last successful backup
[21.08.2009 10:32:24] <01> Info Found no last backup
[21.08.2009 10:32:24] <01> Info lastSuccessfulOIB: {null}, lastOIB: {null}
[21.08.2009 10:32:24] <01> Info Preparation stage has been completed
[21.08.2009 10:32:24] <01> Info Now: lastBackup = null and prevSuccesBackup=null
[21.08.2009 10:32:24] <01> Info Task operation: Retrieving backup file name
[21.08.2009 10:32:24] <01> Info Backup file: "D:\backups\veeam\temp dc.vbk"
[21.08.2009 10:32:24] <01> Info Make sure that this machine can be backed up
[21.08.2009 10:32:24] <01> Info Backup stage
[21.08.2009 10:32:24] <01> Info Backup has been started to "D:\backups\veeam\temp dc.vbk"
[21.08.2009 10:32:24] <01> Info Task operation: Checking target path and creating directories if needed
[21.08.2009 10:32:24] <01> Info Task operation: Building path for backup
[21.08.2009 10:32:24] <01> Info [Win] Creating dir "D:\backups\veeam"
[21.08.2009 10:32:24] <01> Info There is no successful backup
[21.08.2009 10:32:24] <01> Info Task operation: Creating DB record for VM
[21.08.2009 10:32:24] <01> Info Creating ObjectInBackup, object {c17e2b91-47b8-4f8d-b4a0-c31adb770eab}, backup {75d36a63-af67-4336-807e-0c732b597a69}, filename "D:\backups\veeam\temp dc.vbk", insideDir "dd9ed54a-a447-4a35-80fe-87442df30208 (16)"
[21.08.2009 10:32:24] <01> Info Task operation: Starting VM backup process
[21.08.2009 10:32:24] <01> Info Retrieving local info
[21.08.2009 10:32:24] <01> Info Drive "C:\", type "Fixed", volume label "", file system "NTFS", total space 58.59 GB, free space 26.52 GB, free space to current user 26.52 GB
[21.08.2009 10:32:24] <01> Info Drive "D:\", type "Fixed", volume label "Storage", file system "NTFS", total space 399.61 GB, free space 293.05 GB, free space to current user 293.05 GB
[21.08.2009 10:32:24] <01> Info Retrieving target info
[21.08.2009 10:32:24] <01> Info Drive "C:\", type "Fixed", volume label "", file system "NTFS", total space 58.59 GB, free space 26.52 GB, free space to current user 26.52 GB
[21.08.2009 10:32:24] <01> Info Drive "D:\", type "Fixed", volume label "Storage", file system "NTFS", total space 399.61 GB, free space 293.05 GB, free space to current user 293.05 GB
[21.08.2009 10:32:24] <01> Info Starting client agent on localhost, startPort 2500, endPort 2600, serverPort 0
[21.08.2009 10:32:24] <01> Info Win agent started, pid 2284, args -lflush,"C:\Users\adam\AppData\Local\Veeam\Backup\VeeamAgent_temp_dc_cli.log"
[21.08.2009 10:32:25] <08> Info (Client) Service output: LOG:C:\Users\adam\AppData\Local\Veeam\Backup\VeeamAgent_temp_dc_cli.log\n
[21.08.2009 10:32:25] <08> Info (Client) Service: ready state
[21.08.2009 10:32:25] <08> Info (Client) Service output: >\n
[21.08.2009 10:32:25] <01> Info Agent command: "text2tar\nveeamfs:4:dd9ed54a-a447-4a35-80fe-87442df30208 (16)/summary.xml@D:\backups\veeam\temp dc.vbk\n<?xml version="1.0" encoding="utf-16"?><BackupSummary xmlns="http://tempuri.org/BackupSummary.xsd">< ... _name>temp dc</job_name><datestart>2009-08-21T10:32:24.6193244-07:00</datestart><dateend>9999-12-31T23:59:59.9999999-08:00</dateend><objects_count>1</objects_count><db_session_id>f17df613-dc3b-4aef-b54e-1409e899652a</db_session_id><db_backup_id>75d36a63-af67-4336-807e-0c732b597a69</db_backup_id><db_job_id>175b508a-baa9-400e-bc4b-06217051b1c6</db_job_id><filename>D:\backups\veeam\temp dc.vbk</filename><version>0</version><previous_filename></previous_filename><result>false</result><remarks></remarks></Summary><Object><id>0</id><job_id>0</job_id><object_type>1</object_type><object_name>Arlie-TempDC</object_name><object_id>16</object_id><result>true</result><errors></errors><host_name>192.168.0.28</host_name><storage_name>Arlie-TempDC</storage_name><resourcepool_name>ha-root-pool</resourcepool_name><host_id>dd9ed54a-a447-4a35-80fe-87442df30208</host_id><corrupted>true</corrupted></Object></BackupSummary>\n.\n"
[21.08.2009 10:32:27] <08> Info (Client) Service output: >\n
[21.08.2009 10:32:27] <01> Info Task operation: Initializing source
[21.08.2009 10:32:27] <01> Info [Soap] Outgoing connection '192.168.0.28:443:Adam:False::0:1'.
[21.08.2009 10:32:27] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is provided from the cache.
[21.08.2009 10:32:27] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is disposing.
[21.08.2009 10:32:27] <01> Info Task operation: Retrieving file list
[21.08.2009 10:32:28] <01> Info Starting filter for IO write redirection
[21.08.2009 10:32:28] <01> Info Starting agents, server "localhost", client "localhost"
[21.08.2009 10:32:28] <01> Info Starting server agent on localhost, startPort 2500, endPort 2600
[21.08.2009 10:32:28] <01> Info Win agent started, pid 3644, args -s2500-2600 -u7d809356-3eaf-4ffb-b5e3-7c9c3752fa07 -pbbf443d7-1dd8-47bc-a8d3-b36b3f595e64 -lflush,"C:\Users\adam\AppData\Local\Veeam\Backup\VeeamAgent_temp_dc_srv.log"
[21.08.2009 10:32:28] <10> Info (Server) Service output: LOG:C:\Users\adam\AppData\Local\Veeam\Backup\VeeamAgent_temp_dc_srv.log\n
[21.08.2009 10:32:28] <11> Info (Server) Service: ready state
[21.08.2009 10:32:28] <11> Info (Server) Service output: Listening port:2500\n
[21.08.2009 10:32:28] <01> Info Starting client agent on localhost, startPort 2500, endPort 2600, serverPort 2500
[21.08.2009 10:32:28] <01> Info Win agent started, pid 6008, args -lflush,"C:\Users\adam\AppData\Local\Veeam\Backup\VeeamAgent_temp_dc_cli.log"
[21.08.2009 10:32:29] <13> Info (Client) Service output: LOG:C:\Users\adam\AppData\Local\Veeam\Backup\VeeamAgent_temp_dc_cli.1.log\n
[21.08.2009 10:32:29] <13> Info (Client) Service output: >\n
[21.08.2009 10:32:30] <14> Info (Client) Service: ready state
[21.08.2009 10:32:30] <01> Info Connecting to "Arlie-Backup", port 2500, outgoingPort -1, user"7d809356-3eaf-4ffb-b5e3-7c9c3752fa07"
[21.08.2009 10:32:31] <14> Info (Client) Service output: >\n
[21.08.2009 10:32:31] <01> Info Agent command: "hookBegin\nC:\Users\adam\AppData\Local\Temp\veeamvcb51db11cb-330b-46e7-9362-7ee42ca96971\n"
[21.08.2009 10:32:32] <14> Info (Client) Service output: >\n
[21.08.2009 10:32:32] <01> Info VSSFreezer: Validating Veeam VSS availability for the VM
[21.08.2009 10:32:32] <01> Info VSSFreezer: Initializing VSS guest agent connection
[8/21/2009 10:32:32 AM]Info ==================================================================================
[8/21/2009 10:32:32 AM]Info Guest agent control version info
[8/21/2009 10:32:32 AM]Info {
[8/21/2009 10:32:32 AM]Info Module name: [VeeamGuestAgentCtrl.dll].
[8/21/2009 10:32:32 AM]Info Module path: [C:\Program Files\Veeam\Backup and FastSCP\VeeamGuestAgentCtrl.dll].
[8/21/2009 10:32:32 AM]Info Module version: [3.1.0.278].
[8/21/2009 10:32:32 AM]Info }
[21.08.2009 10:32:32] <01> Info VSSFreezer: Connecting to "192.168.0.30", user "arlie\adam"
[8/21/2009 10:32:32 AM]Info Connecting to a remote host. Host: [192.168.0.30]. Specified account: [arlie\adam].
[8/21/2009 10:32:32 AM]Info Connecting to a remote host. Host: [192.168.0.30]. Specified account: [arlie\adam].Ok.
[21.08.2009 10:32:32] <01> Info VSSFreezer: Collecting guest metrics
[8/21/2009 10:32:32 AM]Info Collecting guest metrics.
[8/21/2009 10:32:32 AM]Info sv101_type: [0x84102b]
[8/21/2009 10:32:32 AM]Info Target host is a server.
[8/21/2009 10:32:32 AM]Info Guest OS version: [5.2]
[8/21/2009 10:32:32 AM]Info Collecting information about the guest's platform type.
[8/21/2009 10:32:32 AM]Info PROCESSOR_ARCHITECTURE: [x86]
[8/21/2009 10:32:32 AM]Info PROCESSOR_ARCHITEW6432: []
[8/21/2009 10:32:32 AM]Info Guest OS platform: [X86].
[8/21/2009 10:32:32 AM]Info Collecting information about the guest's platform type.Ok.
[8/21/2009 10:32:32 AM]Info Guest OS: [Windows 2003 Server].
[8/21/2009 10:32:32 AM]Info Collecting guest metrics.Ok.
[21.08.2009 10:32:32] <01> Info VSSFreezer: Guest operation system: type EVeeamOs2003, platform EVeeamX86, version 5.2
[21.08.2009 10:32:32] <01> Info VSSFreezer: Installing agent
[8/21/2009 10:32:32 AM]Info Installing agent.
[8/21/2009 10:32:33 AM]Info The guest agent service will be registered with the following service account: [].
[8/21/2009 10:32:39 AM]Info Installing agent.Ok.
[21.08.2009 10:32:39] <01> Info VSSFreezer: Freezing
[8/21/2009 10:32:39 AM]Info Getting a binding handle for a RPC endpoint with the RPC interface specified. RPC interface UID: [{fa8573fc-2445-4ef5-82f1-30e34e7a07c7}]. Version major: [1]. Version minor: [0].
[8/21/2009 10:32:39 AM]Info Settings authentication information for the binding handle to the remote endpoint mapper.
[8/21/2009 10:32:39 AM]Info Settings authentication information for the binding handle to the remote endpoint mapper.Ok.
[8/21/2009 10:32:39 AM]Info String representation of the suitable binding handle: [ncadg_ip_udp:192.168.0.30[3006]]
[8/21/2009 10:32:39 AM]Info String representation of the suitable binding handle: [ncacn_np:192.168.0.30[\\pipe\\8775133D398F8C11]]
[8/21/2009 10:32:39 AM]Info String representation of the suitable binding handle: [ncacn_ip_tcp:192.168.0.30[3005]]
[8/21/2009 10:32:39 AM]Info Found binding handle: [ncacn_ip_tcp:192.168.0.30[3005]].
[8/21/2009 10:32:39 AM]Info Getting a binding handle for a RPC endpoint with the RPC interface specified. RPC interface UID: [{fa8573fc-2445-4ef5-82f1-30e34e7a07c7}]. Version major: [1]. Version minor: [0].Ok.
[21.08.2009 10:33:19] <01> Info VSSFreezer: Frozen in 40sec
[21.08.2009 10:33:19] <01> Info Starting VCBMounter
[21.08.2009 10:33:19] <15> Info Looking for VCB Framework
[21.08.2009 10:33:19] <15> Info VCB Framework has been found in "C:\Program Files\VMware\VMware Consolidated Backup Framework", version 1.0.4948.0
[21.08.2009 10:33:19] <15> Info VCB command: C:\Program Files\VMware\VMware Consolidated Backup Framework\vcbMounter.exe -h 192.168.0.28 -u Adam -p ****** -a moref:16 -r "C:\Users\adam\AppData\Local\Temp\veeamvcb51db11cb-330b-46e7-9362-7ee42ca96971" -t fullvm -m san -M 1 -F 1 -L 3 -Q 0
[21.08.2009 10:33:19] <15> Info VCB Started
[21.08.2009 10:33:19] <01> Info Waiting for VCB catalog
[21.08.2009 10:33:19] <01> Info Agent command: "queryCatalog\n4604\n"
[21.08.2009 10:33:22] <05> Info vcbMounter: [2009-08-21 10:33:22.125 'App' 5648 info] Current working directory: C:\Program Files\VMware\VMware Consolidated Backup Framework
[21.08.2009 10:33:22] <05> Info vcbMounter: [2009-08-21 10:33:22.127 'BaseLibs' 5648 info] HOSTINFO: Seeing Intel CPU, numCoresPerCPU 1 numThreadsPerCore 2.
[21.08.2009 10:33:22] <05> Info vcbMounter: [2009-08-21 10:33:22.127 'BaseLibs' 5648 info] HOSTINFO: This machine has 1 physical CPUS, 1 total cores, and 2 logical CPUs.
[21.08.2009 10:33:24] <05> Info vcbMounter: [2009-08-21 10:33:24.563 'BaseLibs' 5648 info] Using system libcrypto, version 90709F
[21.08.2009 10:33:24] <05> Info vcbMounter: [2009-08-21 10:33:24.741 'BaseLibs' 5648 warning] SSLVerifyCertAgainstSystemStore: Subject mismatch: localhost.localdomain vs 192.168.0.28
[21.08.2009 10:33:24] <05> Info vcbMounter: [2009-08-21 10:33:24.742 'BaseLibs' 5648 warning] SSLVerifyCertAgainstSystemStore: The remote host certificate has these problems:
[21.08.2009 10:33:24] <05> Info vcbMounter:
[21.08.2009 10:33:24] <05> Info vcbMounter: * The host name used for the connection does not match the subject name on the host certificate
[21.08.2009 10:33:24] <05> Info vcbMounter:
[21.08.2009 10:33:24] <05> Info vcbMounter: * A certificate in the host's chain is based on an untrusted root.
[21.08.2009 10:33:24] <05> Info vcbMounter: [2009-08-21 10:33:24.742 'BaseLibs' 5648 warning] SSLVerifyIsEnabled: failed to read registry value. Assuming verification is disabled. LastError = 0
[21.08.2009 10:33:24] <05> Info vcbMounter: [2009-08-21 10:33:24.742 'BaseLibs' 5648 warning] SSLVerifyCertAgainstSystemStore: Certificate verification is disabled, so connection will proceed despite the error
[21.08.2009 10:33:28] <05> Info vcbMounter: [2009-08-21 10:33:28.752 'vcbMounter' 5648 error] Error: Other error encountered: vim.fault.TaskInProgress
[21.08.2009 10:33:28] <05> Info vcbMounter: [2009-08-21 10:33:28.752 'vcbMounter' 5648 error] An error occurred, cleaning up...
[21.08.2009 10:33:28] <16> Info vcbMounter: Deleted directory C:\Users\adam\AppData\Local\Temp\veeamvcb51db11cb-330b-46e7-9362-7ee42ca96971
[21.08.2009 10:33:30] <15> Info VCB Ended
[21.08.2009 10:33:30] <13> Info (Client) Service error: Vcb process is terminated
[21.08.2009 10:33:30] <10> Info (Server) Service error: End of file
[21.08.2009 10:33:30] <10> Info (Server) Service error: --tr:Cannot read data from the socket. Requested data size: [1].
[21.08.2009 10:33:30] <17> Info (Server) Service: closed
[21.08.2009 10:33:30] <13> Info (Client) Service error: Waiting for catalog data has failed.Vcb process is terminated
[21.08.2009 10:33:30] <13> Info (Client) Service error: Waiting for catalog data has failed.
[21.08.2009 10:33:30] <10> Info (Client) Service error: --tr:Client failed to process the command. Command: [queryCatalog].
[21.08.2009 10:33:30] <17> Info (Client) Service: closed
[21.08.2009 10:33:30] <01> Info VSSFreezer: Unfreezing
[21.08.2009 10:33:33] <01> Info VSSFreezer: Uninstalling agent
[21.08.2009 10:33:36] <01> Info VSSFreezer: Disconnecting
[21.08.2009 10:32:16] <01> Info Logging level is 4
[21.08.2009 10:32:16] <01> Info Module: C:\Program Files\Veeam\Backup and FastSCP\VeeamManager.exe version: 3.1.0.278
[21.08.2009 10:32:16] <01> Info Current culture: en-US (UI: en-US)
[21.08.2009 10:32:16] <01> Info OS: Microsoft Windows NT 6.0.6001 Service Pack 1
[21.08.2009 10:32:17] <01> Info CPU: Intel(R) Xeon(TM) CPU 3.00GHz
[21.08.2009 10:32:17] <01> Info Memory: 4.00 GB
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection 2, Intel(R) PRO/1000 MT Network Connection, Ethernet, Down; Unicast IPs: 169.254.174.98; Gateway IPs: 0.0.0.0;
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection, Marvell Yukon 88E8050 PCI-E ASF Gigabit Ethernet Controller, Ethernet, Up; Unicast IPs: 192.168.0.20; Gateway IPs: 192.168.0.254;
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection 3, VMware Virtual Ethernet Adapter for VMnet1, Ethernet, Up; Unicast IPs: 192.168.187.1; Gateway IPs: 0.0.0.0;
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection 4, VMware Virtual Ethernet Adapter for VMnet8, Ethernet, Up; Unicast IPs: 192.168.112.1; Gateway IPs: 0.0.0.0;
[21.08.2009 10:32:18] <01> Info Network: Loopback Pseudo-Interface 1, Software Loopback Interface 1, Loopback, Up; Unicast IPs: ::1; 127.0.0.1;
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection* 8, isatap.{879D604D-ED9D-4800-9582-85387B4FCFC5}, Tunnel, Down; Unicast IPs: fe80::5efe:192.168.0.20%15;
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection* 9, isatap.{61F23CFC-3334-499C-8670-B4A2AD36D472}, Tunnel, Down; Unicast IPs: fe80::5efe:192.168.112.1%16;
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection* 11, Teredo Tunneling Pseudo-Interface, Tunnel, Down; Unicast IPs: fe80:
7f:fffe%12;[21.08.2009 10:32:18] <01> Info Network: Local Area Connection* 12, isatap.{BE210E3D-1369-4BDD-BCCC-D140FCFDAC50}, Tunnel, Down;
[21.08.2009 10:32:18] <01> Info Network: Local Area Connection* 13, isatap.{29E8617D-72F3-4FE6-AFC3-FD548AC000E9}, Tunnel, Down; Unicast IPs: fe80::5efe:192.168.187.1%18;
[21.08.2009 10:32:18] <01> Info --------------------------------------------------
[21.08.2009 10:32:18] <01> Info Creating job session, jobID {175b508a-baa9-400e-bc4b-06217051b1c6}, jobName "temp dc"
[21.08.2009 10:32:18] <01> Info Job session {f17df613-dc3b-4aef-b54e-1409e899652a} has been created
[21.08.2009 10:32:18] <01> Info Options: <BackupJobOptions xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:xsd="http://www.w3.org/2001/XMLSchema"><Reta ... ostCommand /></BackupJobOptions>
[21.08.2009 10:32:18] <01> Info VSS options: <CVssOptions xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:xsd="http://www.w3.org/2001/XMLSchema"><Enab ... VssOptions>
[21.08.2009 10:32:18] <01> Info Retry mode: False
[21.08.2009 10:32:18] <01> Info Job task: host "192.168.0.28", object "Arlie-TempDC", type "VM", viType "VirtualMachine"
[21.08.2009 10:32:18] <01> Info Effective VSS options: <?xml version="1.0" encoding="utf-16"?><CVssOptions xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:xsd="http://www.w3.org/2001/XMLSchema"><Enab ... VssOptions>
[21.08.2009 10:32:18] <01> Info Source: VCB
[21.08.2009 10:32:18] <01> Info Method: VCB Backup
[21.08.2009 10:32:18] <01> Info Job operation: Verifying license
[21.08.2009 10:32:18] <01> Info Job operation: Checking backup version
[21.08.2009 10:32:18] <01> Info Job operation: Analyzing object "Arlie-TempDC" (16), host "192.168.0.28"
[21.08.2009 10:32:18] <01> Info [Soap] Outgoing connection '192.168.0.28:443:Adam:False::0:1'.
[21.08.2009 10:32:18] <01> Info [Soap] Creating new connection '192.168.0.28:443:Adam:False::0:1'.
[21.08.2009 10:32:18] <01> Info [Soap] Logging on to "192.168.0.28", port 443, user "Adam", proxy srv: port:0, serviceType: public
[21.08.2009 10:32:20] <01> Info [Soap] Skip SSL error with certificate: RemoteCertificateNameMismatch, RemoteCertificateChainErrors
[21.08.2009 10:32:21] <01> Info [Soap] Host content info: host "192.168.0.28", type "embeddedEsx", version "4.0.0", build "175625", apiVersion "4.0", hostTime "8/21/2009 5:32:20 PM"
[21.08.2009 10:32:21] <01> Info [Soap] Loading 'ha-folder-root:Folder' hierarchy
[21.08.2009 10:32:23] <01> Info [Soap] Loaded 14 hierarchy objects from '192.168.0.28', 1 Datacenter(s), 1 HostSystem(s), 4 VirtualMachine(s), 3 Datastore(s), 1 ResourcePool(s).
[21.08.2009 10:32:23] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is disposing.
[21.08.2009 10:32:23] <01> Info Job operation: Calculating job size
[21.08.2009 10:32:23] <01> Info Total size of 1 objects is 20.00 GB
[21.08.2009 10:32:23] <01> Info Job operation: Creating DB records for backup
[21.08.2009 10:32:23] <01> Info Current backup id: {75d36a63-af67-4336-807e-0c732b597a69}, Previous backup id: {null}
[21.08.2009 10:32:23] <01> Info Job operation: Processing object "Arlie-TempDC" (1 of 1)
[21.08.2009 10:32:23] <01> Info Creating task session, sessionID {f17df613-dc3b-4aef-b54e-1409e899652a}
[21.08.2009 10:32:23] <01> Info Task session {60aa149f-518e-4256-b00e-cea372a58adc} has been created
[21.08.2009 10:32:23] <01> Info -----------------------------------------------------
[21.08.2009 10:32:23] <01> Info Current object "Arlie-TempDC", target host "My Computer", dir "D:\backups\veeam\", file "temp dc"
[21.08.2009 10:32:23] <01> Info Task operation: Validating object
[21.08.2009 10:32:23] <01> Info [Soap] Outgoing connection '192.168.0.28:443:Adam:False::0:1'.
[21.08.2009 10:32:23] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is provided from the cache.
[21.08.2009 10:32:23] <01> Info [Soap] LogUserEvent, ref 16, type VirtualMachine, message "Source: Veeam Backup\nAction: Job "temp dc"\nOperation: Started\nStatus: Success\nID: 60aa149f-518e-4256-b00e-cea372a58adc\nDescription: "
[21.08.2009 10:32:23] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is disposing.
[21.08.2009 10:32:23] <01> Info [Soap] Outgoing connection '192.168.0.28:443:Adam:False::0:1'.
[21.08.2009 10:32:23] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is provided from the cache.
[21.08.2009 10:32:23] <01> Info [Soap] Loading '16:VirtualMachine' hierarchy
[21.08.2009 10:32:23] <01> Info [Soap] Loaded 6 hierarchy objects from '192.168.0.28', 0 Datacenter(s), 1 HostSystem(s), 1 VirtualMachine(s), 3 Datastore(s), 0 ResourcePool(s).
[21.08.2009 10:32:23] <01> Info VM information: name "Arlie-TempDC", ref "16", uuid "564dd975-367b-b744-79dc-42d36a0e7f66", host "esxi01.arlie.local", resourcePool "ha-root-pool", connectionState "connected", powerState "poweredOn", template "False"
[21.08.2009 10:32:23] <01> Info VMX file: "[ESXi01-VMFS] temp dc01/temp dc01.vmx"
[21.08.2009 10:32:23] <01> Info Config file: "[ESXi01-VMFS] temp dc01/temp dc01.vmsd"
[21.08.2009 10:32:23] <01> Info Config file: "[ESXi01-VMFS] temp dc01/temp dc01.nvram"
[21.08.2009 10:32:23] <01> Info Config file: "[ESXi01-VMFS] temp dc01/temp dc01.vmxf"
[21.08.2009 10:32:23] <01> Info Log file: "[ESXi01-VMFS] temp dc01/vmware-7.log"
[21.08.2009 10:32:23] <01> Info Log file: "[ESXi01-VMFS] temp dc01/vmware-8.log"
[21.08.2009 10:32:23] <01> Info Log file: "[ESXi01-VMFS] temp dc01/vmware-9.log"
[21.08.2009 10:32:23] <01> Info Log file: "[ESXi01-VMFS] temp dc01/vmware-4.log"
[21.08.2009 10:32:23] <01> Info Log file: "[ESXi01-VMFS] temp dc01/vmware-5.log"
[21.08.2009 10:32:23] <01> Info Log file: "[ESXi01-VMFS] temp dc01/vmware-6.log"
[21.08.2009 10:32:23] <01> Info Log file: "[ESXi01-VMFS] temp dc01/vmware.log"
[21.08.2009 10:32:23] <01> Info Disk: label "Hard disk 1", path "[ESXi01-VMFS] temp dc01/temp dc01.vmdk", capacity 20.00 GB, mode "persistent"
[21.08.2009 10:32:23] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is disposing.
[21.08.2009 10:32:23] <01> Info Task operation: Checking license
[21.08.2009 10:32:24] <01> Info [Soap] Outgoing connection '192.168.0.28:443:Adam:False::0:1'.
[21.08.2009 10:32:24] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is provided from the cache.
[21.08.2009 10:32:24] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is disposing.
[21.08.2009 10:32:24] <01> Info Task operation: Connecting
[21.08.2009 10:32:24] <01> Info Creating file commander for the host "192.168.0.28" (allowNfc: True)
[21.08.2009 10:32:24] <01> Info [Soap] Outgoing connection '192.168.0.28:443:Adam:False::0:1'.
[21.08.2009 10:32:24] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is provided from the cache.
[21.08.2009 10:32:24] <01> Info Creating file commander for the host "My Computer" (allowNfc: True)
[21.08.2009 10:32:24] <01> Info Task operation: Searching for last backup
[21.08.2009 10:32:24] <01> Info Task operation: Checking VM running state
[21.08.2009 10:32:24] <01> Info Task operation: Searching for last successful backup
[21.08.2009 10:32:24] <01> Info Found no last backup
[21.08.2009 10:32:24] <01> Info lastSuccessfulOIB: {null}, lastOIB: {null}
[21.08.2009 10:32:24] <01> Info Preparation stage has been completed
[21.08.2009 10:32:24] <01> Info Now: lastBackup = null and prevSuccesBackup=null
[21.08.2009 10:32:24] <01> Info Task operation: Retrieving backup file name
[21.08.2009 10:32:24] <01> Info Backup file: "D:\backups\veeam\temp dc.vbk"
[21.08.2009 10:32:24] <01> Info Make sure that this machine can be backed up
[21.08.2009 10:32:24] <01> Info Backup stage
[21.08.2009 10:32:24] <01> Info Backup has been started to "D:\backups\veeam\temp dc.vbk"
[21.08.2009 10:32:24] <01> Info Task operation: Checking target path and creating directories if needed
[21.08.2009 10:32:24] <01> Info Task operation: Building path for backup
[21.08.2009 10:32:24] <01> Info [Win] Creating dir "D:\backups\veeam"
[21.08.2009 10:32:24] <01> Info There is no successful backup
[21.08.2009 10:32:24] <01> Info Task operation: Creating DB record for VM
[21.08.2009 10:32:24] <01> Info Creating ObjectInBackup, object {c17e2b91-47b8-4f8d-b4a0-c31adb770eab}, backup {75d36a63-af67-4336-807e-0c732b597a69}, filename "D:\backups\veeam\temp dc.vbk", insideDir "dd9ed54a-a447-4a35-80fe-87442df30208 (16)"
[21.08.2009 10:32:24] <01> Info Task operation: Starting VM backup process
[21.08.2009 10:32:24] <01> Info Retrieving local info
[21.08.2009 10:32:24] <01> Info Drive "C:\", type "Fixed", volume label "", file system "NTFS", total space 58.59 GB, free space 26.52 GB, free space to current user 26.52 GB
[21.08.2009 10:32:24] <01> Info Drive "D:\", type "Fixed", volume label "Storage", file system "NTFS", total space 399.61 GB, free space 293.05 GB, free space to current user 293.05 GB
[21.08.2009 10:32:24] <01> Info Retrieving target info
[21.08.2009 10:32:24] <01> Info Drive "C:\", type "Fixed", volume label "", file system "NTFS", total space 58.59 GB, free space 26.52 GB, free space to current user 26.52 GB
[21.08.2009 10:32:24] <01> Info Drive "D:\", type "Fixed", volume label "Storage", file system "NTFS", total space 399.61 GB, free space 293.05 GB, free space to current user 293.05 GB
[21.08.2009 10:32:24] <01> Info Starting client agent on localhost, startPort 2500, endPort 2600, serverPort 0
[21.08.2009 10:32:24] <01> Info Win agent started, pid 2284, args -lflush,"C:\Users\adam\AppData\Local\Veeam\Backup\VeeamAgent_temp_dc_cli.log"
[21.08.2009 10:32:25] <08> Info (Client) Service output: LOG:C:\Users\adam\AppData\Local\Veeam\Backup\VeeamAgent_temp_dc_cli.log\n
[21.08.2009 10:32:25] <08> Info (Client) Service: ready state
[21.08.2009 10:32:25] <08> Info (Client) Service output: >\n
[21.08.2009 10:32:25] <01> Info Agent command: "text2tar\nveeamfs:4:dd9ed54a-a447-4a35-80fe-87442df30208 (16)/summary.xml@D:\backups\veeam\temp dc.vbk\n<?xml version="1.0" encoding="utf-16"?><BackupSummary xmlns="http://tempuri.org/BackupSummary.xsd">< ... _name>temp dc</job_name><datestart>2009-08-21T10:32:24.6193244-07:00</datestart><dateend>9999-12-31T23:59:59.9999999-08:00</dateend><objects_count>1</objects_count><db_session_id>f17df613-dc3b-4aef-b54e-1409e899652a</db_session_id><db_backup_id>75d36a63-af67-4336-807e-0c732b597a69</db_backup_id><db_job_id>175b508a-baa9-400e-bc4b-06217051b1c6</db_job_id><filename>D:\backups\veeam\temp dc.vbk</filename><version>0</version><previous_filename></previous_filename><result>false</result><remarks></remarks></Summary><Object><id>0</id><job_id>0</job_id><object_type>1</object_type><object_name>Arlie-TempDC</object_name><object_id>16</object_id><result>true</result><errors></errors><host_name>192.168.0.28</host_name><storage_name>Arlie-TempDC</storage_name><resourcepool_name>ha-root-pool</resourcepool_name><host_id>dd9ed54a-a447-4a35-80fe-87442df30208</host_id><corrupted>true</corrupted></Object></BackupSummary>\n.\n"
[21.08.2009 10:32:27] <08> Info (Client) Service output: >\n
[21.08.2009 10:32:27] <01> Info Task operation: Initializing source
[21.08.2009 10:32:27] <01> Info [Soap] Outgoing connection '192.168.0.28:443:Adam:False::0:1'.
[21.08.2009 10:32:27] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is provided from the cache.
[21.08.2009 10:32:27] <01> Info [Soap] Connection '192.168.0.28:443:Adam:False::0:1' is disposing.
[21.08.2009 10:32:27] <01> Info Task operation: Retrieving file list
[21.08.2009 10:32:28] <01> Info Starting filter for IO write redirection
[21.08.2009 10:32:28] <01> Info Starting agents, server "localhost", client "localhost"
[21.08.2009 10:32:28] <01> Info Starting server agent on localhost, startPort 2500, endPort 2600
[21.08.2009 10:32:28] <01> Info Win agent started, pid 3644, args -s2500-2600 -u7d809356-3eaf-4ffb-b5e3-7c9c3752fa07 -pbbf443d7-1dd8-47bc-a8d3-b36b3f595e64 -lflush,"C:\Users\adam\AppData\Local\Veeam\Backup\VeeamAgent_temp_dc_srv.log"
[21.08.2009 10:32:28] <10> Info (Server) Service output: LOG:C:\Users\adam\AppData\Local\Veeam\Backup\VeeamAgent_temp_dc_srv.log\n
[21.08.2009 10:32:28] <11> Info (Server) Service: ready state
[21.08.2009 10:32:28] <11> Info (Server) Service output: Listening port:2500\n
[21.08.2009 10:32:28] <01> Info Starting client agent on localhost, startPort 2500, endPort 2600, serverPort 2500
[21.08.2009 10:32:28] <01> Info Win agent started, pid 6008, args -lflush,"C:\Users\adam\AppData\Local\Veeam\Backup\VeeamAgent_temp_dc_cli.log"
[21.08.2009 10:32:29] <13> Info (Client) Service output: LOG:C:\Users\adam\AppData\Local\Veeam\Backup\VeeamAgent_temp_dc_cli.1.log\n
[21.08.2009 10:32:29] <13> Info (Client) Service output: >\n
[21.08.2009 10:32:30] <14> Info (Client) Service: ready state
[21.08.2009 10:32:30] <01> Info Connecting to "Arlie-Backup", port 2500, outgoingPort -1, user"7d809356-3eaf-4ffb-b5e3-7c9c3752fa07"
[21.08.2009 10:32:31] <14> Info (Client) Service output: >\n
[21.08.2009 10:32:31] <01> Info Agent command: "hookBegin\nC:\Users\adam\AppData\Local\Temp\veeamvcb51db11cb-330b-46e7-9362-7ee42ca96971\n"
[21.08.2009 10:32:32] <14> Info (Client) Service output: >\n
[21.08.2009 10:32:32] <01> Info VSSFreezer: Validating Veeam VSS availability for the VM
[21.08.2009 10:32:32] <01> Info VSSFreezer: Initializing VSS guest agent connection
[8/21/2009 10:32:32 AM]Info ==================================================================================
[8/21/2009 10:32:32 AM]Info Guest agent control version info
[8/21/2009 10:32:32 AM]Info {
[8/21/2009 10:32:32 AM]Info Module name: [VeeamGuestAgentCtrl.dll].
[8/21/2009 10:32:32 AM]Info Module path: [C:\Program Files\Veeam\Backup and FastSCP\VeeamGuestAgentCtrl.dll].
[8/21/2009 10:32:32 AM]Info Module version: [3.1.0.278].
[8/21/2009 10:32:32 AM]Info }
[21.08.2009 10:32:32] <01> Info VSSFreezer: Connecting to "192.168.0.30", user "arlie\adam"
[8/21/2009 10:32:32 AM]Info Connecting to a remote host. Host: [192.168.0.30]. Specified account: [arlie\adam].
[8/21/2009 10:32:32 AM]Info Connecting to a remote host. Host: [192.168.0.30]. Specified account: [arlie\adam].Ok.
[21.08.2009 10:32:32] <01> Info VSSFreezer: Collecting guest metrics
[8/21/2009 10:32:32 AM]Info Collecting guest metrics.
[8/21/2009 10:32:32 AM]Info sv101_type: [0x84102b]
[8/21/2009 10:32:32 AM]Info Target host is a server.
[8/21/2009 10:32:32 AM]Info Guest OS version: [5.2]
[8/21/2009 10:32:32 AM]Info Collecting information about the guest's platform type.
[8/21/2009 10:32:32 AM]Info PROCESSOR_ARCHITECTURE: [x86]
[8/21/2009 10:32:32 AM]Info PROCESSOR_ARCHITEW6432: []
[8/21/2009 10:32:32 AM]Info Guest OS platform: [X86].
[8/21/2009 10:32:32 AM]Info Collecting information about the guest's platform type.Ok.
[8/21/2009 10:32:32 AM]Info Guest OS: [Windows 2003 Server].
[8/21/2009 10:32:32 AM]Info Collecting guest metrics.Ok.
[21.08.2009 10:32:32] <01> Info VSSFreezer: Guest operation system: type EVeeamOs2003, platform EVeeamX86, version 5.2
[21.08.2009 10:32:32] <01> Info VSSFreezer: Installing agent
[8/21/2009 10:32:32 AM]Info Installing agent.
[8/21/2009 10:32:33 AM]Info The guest agent service will be registered with the following service account: [].
[8/21/2009 10:32:39 AM]Info Installing agent.Ok.
[21.08.2009 10:32:39] <01> Info VSSFreezer: Freezing
[8/21/2009 10:32:39 AM]Info Getting a binding handle for a RPC endpoint with the RPC interface specified. RPC interface UID: [{fa8573fc-2445-4ef5-82f1-30e34e7a07c7}]. Version major: [1]. Version minor: [0].
[8/21/2009 10:32:39 AM]Info Settings authentication information for the binding handle to the remote endpoint mapper.
[8/21/2009 10:32:39 AM]Info Settings authentication information for the binding handle to the remote endpoint mapper.Ok.
[8/21/2009 10:32:39 AM]Info String representation of the suitable binding handle: [ncadg_ip_udp:192.168.0.30[3006]]
[8/21/2009 10:32:39 AM]Info String representation of the suitable binding handle: [ncacn_np:192.168.0.30[\\pipe\\8775133D398F8C11]]
[8/21/2009 10:32:39 AM]Info String representation of the suitable binding handle: [ncacn_ip_tcp:192.168.0.30[3005]]
[8/21/2009 10:32:39 AM]Info Found binding handle: [ncacn_ip_tcp:192.168.0.30[3005]].
[8/21/2009 10:32:39 AM]Info Getting a binding handle for a RPC endpoint with the RPC interface specified. RPC interface UID: [{fa8573fc-2445-4ef5-82f1-30e34e7a07c7}]. Version major: [1]. Version minor: [0].Ok.
[21.08.2009 10:33:19] <01> Info VSSFreezer: Frozen in 40sec
[21.08.2009 10:33:19] <01> Info Starting VCBMounter
[21.08.2009 10:33:19] <15> Info Looking for VCB Framework
[21.08.2009 10:33:19] <15> Info VCB Framework has been found in "C:\Program Files\VMware\VMware Consolidated Backup Framework", version 1.0.4948.0
[21.08.2009 10:33:19] <15> Info VCB command: C:\Program Files\VMware\VMware Consolidated Backup Framework\vcbMounter.exe -h 192.168.0.28 -u Adam -p ****** -a moref:16 -r "C:\Users\adam\AppData\Local\Temp\veeamvcb51db11cb-330b-46e7-9362-7ee42ca96971" -t fullvm -m san -M 1 -F 1 -L 3 -Q 0
[21.08.2009 10:33:19] <15> Info VCB Started
[21.08.2009 10:33:19] <01> Info Waiting for VCB catalog
[21.08.2009 10:33:19] <01> Info Agent command: "queryCatalog\n4604\n"
[21.08.2009 10:33:22] <05> Info vcbMounter: [2009-08-21 10:33:22.125 'App' 5648 info] Current working directory: C:\Program Files\VMware\VMware Consolidated Backup Framework
[21.08.2009 10:33:22] <05> Info vcbMounter: [2009-08-21 10:33:22.127 'BaseLibs' 5648 info] HOSTINFO: Seeing Intel CPU, numCoresPerCPU 1 numThreadsPerCore 2.
[21.08.2009 10:33:22] <05> Info vcbMounter: [2009-08-21 10:33:22.127 'BaseLibs' 5648 info] HOSTINFO: This machine has 1 physical CPUS, 1 total cores, and 2 logical CPUs.
[21.08.2009 10:33:24] <05> Info vcbMounter: [2009-08-21 10:33:24.563 'BaseLibs' 5648 info] Using system libcrypto, version 90709F
[21.08.2009 10:33:24] <05> Info vcbMounter: [2009-08-21 10:33:24.741 'BaseLibs' 5648 warning] SSLVerifyCertAgainstSystemStore: Subject mismatch: localhost.localdomain vs 192.168.0.28
[21.08.2009 10:33:24] <05> Info vcbMounter: [2009-08-21 10:33:24.742 'BaseLibs' 5648 warning] SSLVerifyCertAgainstSystemStore: The remote host certificate has these problems:
[21.08.2009 10:33:24] <05> Info vcbMounter:
[21.08.2009 10:33:24] <05> Info vcbMounter: * The host name used for the connection does not match the subject name on the host certificate
[21.08.2009 10:33:24] <05> Info vcbMounter:
[21.08.2009 10:33:24] <05> Info vcbMounter: * A certificate in the host's chain is based on an untrusted root.
[21.08.2009 10:33:24] <05> Info vcbMounter: [2009-08-21 10:33:24.742 'BaseLibs' 5648 warning] SSLVerifyIsEnabled: failed to read registry value. Assuming verification is disabled. LastError = 0
[21.08.2009 10:33:24] <05> Info vcbMounter: [2009-08-21 10:33:24.742 'BaseLibs' 5648 warning] SSLVerifyCertAgainstSystemStore: Certificate verification is disabled, so connection will proceed despite the error
[21.08.2009 10:33:28] <05> Info vcbMounter: [2009-08-21 10:33:28.752 'vcbMounter' 5648 error] Error: Other error encountered: vim.fault.TaskInProgress
[21.08.2009 10:33:28] <05> Info vcbMounter: [2009-08-21 10:33:28.752 'vcbMounter' 5648 error] An error occurred, cleaning up...
[21.08.2009 10:33:28] <16> Info vcbMounter: Deleted directory C:\Users\adam\AppData\Local\Temp\veeamvcb51db11cb-330b-46e7-9362-7ee42ca96971
[21.08.2009 10:33:30] <15> Info VCB Ended
[21.08.2009 10:33:30] <13> Info (Client) Service error: Vcb process is terminated
[21.08.2009 10:33:30] <10> Info (Server) Service error: End of file
[21.08.2009 10:33:30] <10> Info (Server) Service error: --tr:Cannot read data from the socket. Requested data size: [1].
[21.08.2009 10:33:30] <17> Info (Server) Service: closed
[21.08.2009 10:33:30] <13> Info (Client) Service error: Waiting for catalog data has failed.Vcb process is terminated
[21.08.2009 10:33:30] <13> Info (Client) Service error: Waiting for catalog data has failed.
[21.08.2009 10:33:30] <10> Info (Client) Service error: --tr:Client failed to process the command. Command: [queryCatalog].
[21.08.2009 10:33:30] <17> Info (Client) Service: closed
[21.08.2009 10:33:30] <01> Info VSSFreezer: Unfreezing
[21.08.2009 10:33:33] <01> Info VSSFreezer: Uninstalling agent
[21.08.2009 10:33:36] <01> Info VSSFreezer: Disconnecting
-
Gostev
- Chief Product Officer
- Posts: 31804
- Liked: 7298 times
- Joined: Jan 01, 2006 1:01 am
- Location: Baar, Switzerland
- Contact:
Re: VCB error task in progress
Adam, sorry for confusion, I meant to ask you to send all logs to support@veeam.com, this would also automatically open the support ticket for you for the tracking purposes.adamfalk wrote:I hate to be ignorant, but i do not see a place to upload a zip file of the logs, and I assume that you do not expect me to cut and past, they are large logs.
Who is online
Users browsing this forum: Bing [Bot] and 93 guests