ERROR: Only some of the VMs backed up

[penguin02007]

I have been getting this perky message. Regardless all the vms are able to back up?

Looks like it is failing on a 'blank' vm name at the very end.

`2016-10-04 05:05:00 -- info: ============================== ghettoVCB LOG START ==============================

2016-10-04 05:05:00 -- debug: Succesfully acquired lock directory - /tmp/ghettoVCB.work

2016-10-04 05:05:00 -- debug: HOST VERSION: VMware ESXi 5.5.0 build-2403361
2016-10-04 05:05:00 -- debug: HOST LEVEL: VMware ESXi 5.5.0 Update 2
2016-10-04 05:05:00 -- debug: HOSTNAME: hsesx2

2016-10-04 05:05:00 -- info: CONFIG - USING GLOBAL GHETTOVCB CONFIGURATION FILE = /vmfs/volumes/hsesx2_ds/ghettoVCB-master/conf
2016-10-04 05:05:00 -- info: CONFIG - VERSION = 2013_26_11_2
2016-10-04 05:05:00 -- info: CONFIG - GHETTOVCB_PID = 2676260
2016-10-04 05:05:00 -- info: CONFIG - VM_BACKUP_VOLUME = /vmfs/volumes/hsesx2_backup/hsesx2_vms
2016-10-04 05:05:00 -- info: CONFIG - VM_BACKUP_ROTATION_COUNT = 5
2016-10-04 05:05:00 -- info: CONFIG - VM_BACKUP_DIR_NAMING_CONVENTION = 2016-10-04_05-04-59
2016-10-04 05:05:00 -- info: CONFIG - DISK_BACKUP_FORMAT = thin
2016-10-04 05:05:00 -- info: CONFIG - POWER_VM_DOWN_BEFORE_BACKUP = 0
2016-10-04 05:05:00 -- info: CONFIG - ENABLE_HARD_POWER_OFF = 0
2016-10-04 05:05:00 -- info: CONFIG - ITER_TO_WAIT_SHUTDOWN = 3
2016-10-04 05:05:00 -- info: CONFIG - POWER_DOWN_TIMEOUT = 5
2016-10-04 05:05:00 -- info: CONFIG - SNAPSHOT_TIMEOUT = 15
2016-10-04 05:05:00 -- info: CONFIG - LOG_LEVEL = debug
2016-10-04 05:05:00 -- info: CONFIG - BACKUP_LOG_OUTPUT = /tmp/ghettoVCB-2016-10-04_05-04-59-2676260.log
2016-10-04 05:05:00 -- info: CONFIG - ENABLE_COMPRESSION = 0
2016-10-04 05:05:00 -- info: CONFIG - VM_SNAPSHOT_MEMORY = 0
2016-10-04 05:05:00 -- info: CONFIG - VM_SNAPSHOT_QUIESCE = 0
2016-10-04 05:05:00 -- info: CONFIG - ALLOW_VMS_WITH_SNAPSHOTS_TO_BE_BACKEDUP = 0
2016-10-04 05:05:00 -- info: CONFIG - VMDK_FILES_TO_BACKUP = all
2016-10-04 05:05:00 -- info: CONFIG - VM_SHUTDOWN_ORDER =
2016-10-04 05:05:00 -- info: CONFIG - VM_STARTUP_ORDER = =
2016-10-04 05:05:00 -- info: CONFIG - RSYNC_LINK = 0
2016-10-04 05:05:00 -- info: CONFIG - EMAIL_LOG = 1
2016-10-04 05:05:00 -- info: CONFIG - EMAIL_SERVER = smtp.hstf.local
2016-10-04 05:05:00 -- info: CONFIG - EMAIL_SERVER_PORT = 25
2016-10-04 05:05:00 -- info: CONFIG - EMAIL_DELAY_INTERVAL = 1
2016-10-04 05:05:00 -- info: CONFIG - EMAIL_FROM = root@hsesx2
2016-10-04 05:05:00 -- info: CONFIG - EMAIL_TO = [email protected]
2016-10-04 05:05:00 -- info: CONFIG - WORKDIR_DEBUG = 0
2016-10-04 05:05:00 -- info:
2016-10-04 05:05:07 -- debug: Storage Information before backup:
2016-10-04 05:05:07 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:05:07 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:05:07 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:05:07 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:05:07 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:05:07 -- debug:
2016-10-04 05:05:07 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:05:07 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:05:07 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:05:07 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:05:07 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:05:07 -- debug:
2016-10-04 05:05:07 -- debug: getVMDKs() - HSSEPM1_1.vmdk###50:HSSEPM1.vmdk###40:
2016-10-04 05:05:08 -- info: Initiate backup for HSSEPM1
2016-10-04 05:05:08 -- info: Creating Snapshot "ghettoVCB-snapshot-2016-10-04" for HSSEPM1
2016-10-04 05:05:10 -- debug: Waiting for snapshot "ghettoVCB-snapshot-2016-10-04" to be created
2016-10-04 05:05:10 -- debug: Snapshot timeout set to: 900 seconds
2016-10-04 05:05:11 -- debug: findVMDK() - Searching for VMDK: "HSSEPM1_1.vmdk" to backup
2016-10-04 05:05:11 -- debug: /sbin/vmkfstools -i "/vmfs/volumes/hsesx2_ds/HSSEPM1/HSSEPM1_1.vmdk" -a "lsilogic" -d "thin" "/vmfs/volumes/hsesx2_backup/hsesx2_vms/HSSEPM1/HSSEPM1-2016-10-04_05-04-59/HSSEPM1_1.vmdk"
2016-10-04 05:08:34 -- debug: findVMDK() - Searching for VMDK: "HSSEPM1.vmdk" to backup
2016-10-04 05:08:34 -- debug: /sbin/vmkfstools -i "/vmfs/volumes/hsesx2_ds/HSSEPM1/HSSEPM1.vmdk" -a "lsilogic" -d "thin" "/vmfs/volumes/hsesx2_backup/hsesx2_vms/HSSEPM1/HSSEPM1-2016-10-04_05-04-59/HSSEPM1.vmdk"
2016-10-04 05:12:26 -- info: Removing snapshot from HSSEPM1 ...
2016-10-04 05:12:26 -- debug: Removing /vmfs/volumes/hsesx2_backup/hsesx2_vms/HSSEPM1/HSSEPM1-2016-09-29_05-05-01
2016-10-04 05:12:26 -- info: Backup Duration: 7.30 Minutes
2016-10-04 05:12:26 -- info: Successfully completed backup for HSSEPM1!

2016-10-04 05:12:31 -- debug: Storage Information after backup:
2016-10-04 05:12:31 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:12:31 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:12:31 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:12:31 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:12:31 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:12:31 -- debug:
2016-10-04 05:12:31 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:12:31 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:12:31 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:12:31 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:12:31 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:12:31 -- debug:
2016-10-04 05:12:36 -- debug: Storage Information before backup:
2016-10-04 05:12:36 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:12:36 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:12:36 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:12:36 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:12:36 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:12:36 -- debug:
2016-10-04 05:12:36 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:12:36 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:12:36 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:12:36 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:12:36 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:12:36 -- debug:
2016-10-04 05:12:37 -- debug: getVMDKs() - HSSPLUNK1.vmdk###16:
2016-10-04 05:12:37 -- info: Initiate backup for HSSPLUNK1
2016-10-04 05:12:38 -- info: Creating Snapshot "ghettoVCB-snapshot-2016-10-04" for HSSPLUNK1
2016-10-04 05:12:39 -- debug: Waiting for snapshot "ghettoVCB-snapshot-2016-10-04" to be created
2016-10-04 05:12:39 -- debug: Snapshot timeout set to: 900 seconds
2016-10-04 05:12:40 -- debug: findVMDK() - Searching for VMDK: "HSSPLUNK1.vmdk" to backup
2016-10-04 05:12:40 -- debug: /sbin/vmkfstools -i "/vmfs/volumes/hsesx2_ds/HSSPLUNK1/HSSPLUNK1.vmdk" -a "lsilogic" -d "thin" "/vmfs/volumes/hsesx2_backup/hsesx2_vms/HSSPLUNK1/HSSPLUNK1-2016-10-04_05-04-59/HSSPLUNK1.vmdk"
2016-10-04 05:13:03 -- info: Removing snapshot from HSSPLUNK1 ...
2016-10-04 05:13:03 -- debug: Removing /vmfs/volumes/hsesx2_backup/hsesx2_vms/HSSPLUNK1/HSSPLUNK1-2016-09-29_05-05-01
2016-10-04 05:13:03 -- info: Backup Duration: 26 Seconds
2016-10-04 05:13:03 -- info: Successfully completed backup for HSSPLUNK1!

2016-10-04 05:13:08 -- debug: Storage Information after backup:
2016-10-04 05:13:08 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:13:08 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:13:08 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:13:08 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:13:08 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:13:08 -- debug:
2016-10-04 05:13:08 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:13:08 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:13:08 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:13:08 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:13:08 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:13:08 -- debug:
2016-10-04 05:13:13 -- debug: Storage Information before backup:
2016-10-04 05:13:13 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:13:13 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:13:13 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:13:13 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:13:13 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:13:13 -- debug:
2016-10-04 05:13:13 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:13:13 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:13:13 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:13:13 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:13:13 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:13:13 -- debug:
2016-10-04 05:13:14 -- debug: getVMDKs() - HSAP3P_1.vmdk###50:HSAP3P.vmdk###16:
2016-10-04 05:13:15 -- info: Initiate backup for HSAP3P
2016-10-04 05:13:15 -- info: Creating Snapshot "ghettoVCB-snapshot-2016-10-04" for HSAP3P
2016-10-04 05:13:17 -- debug: Waiting for snapshot "ghettoVCB-snapshot-2016-10-04" to be created
2016-10-04 05:13:17 -- debug: Snapshot timeout set to: 900 seconds
2016-10-04 05:13:17 -- debug: findVMDK() - Searching for VMDK: "HSAP3P_1.vmdk" to backup
2016-10-04 05:13:17 -- debug: /sbin/vmkfstools -i "/vmfs/volumes/hsesx2_ds/HSAP3P/HSAP3P_1.vmdk" -a "lsilogic" -d "thin" "/vmfs/volumes/hsesx2_backup/hsesx2_vms/HSAP3P/HSAP3P-2016-10-04_05-04-59/HSAP3P_1.vmdk"
2016-10-04 05:17:07 -- debug: findVMDK() - Searching for VMDK: "HSAP3P.vmdk" to backup
2016-10-04 05:17:07 -- debug: /sbin/vmkfstools -i "/vmfs/volumes/hsesx2_ds/HSAP3P/HSAP3P.vmdk" -a "lsilogic" -d "thin" "/vmfs/volumes/hsesx2_backup/hsesx2_vms/HSAP3P/HSAP3P-2016-10-04_05-04-59/HSAP3P.vmdk"
2016-10-04 05:17:35 -- info: Removing snapshot from HSAP3P ...
2016-10-04 05:17:35 -- debug: Removing /vmfs/volumes/hsesx2_backup/hsesx2_vms/HSAP3P/HSAP3P-2016-09-29_05-05-01
2016-10-04 05:17:36 -- info: Backup Duration: 4.35 Minutes
2016-10-04 05:17:36 -- info: Successfully completed backup for HSAP3P!

2016-10-04 05:17:41 -- debug: Storage Information after backup:
2016-10-04 05:17:41 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:17:41 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:17:41 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:17:41 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:17:41 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:17:41 -- debug:
2016-10-04 05:17:41 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:17:41 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:17:41 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:17:41 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:17:41 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:17:41 -- debug:
2016-10-04 05:17:46 -- debug: Storage Information before backup:
2016-10-04 05:17:46 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:17:46 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:17:46 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:17:46 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:17:46 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:17:46 -- debug:
2016-10-04 05:17:46 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:17:46 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:17:46 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:17:46 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:17:46 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:17:46 -- debug:
2016-10-04 05:17:46 -- debug: getVMDKs() - HSTERM2.vmdk###50:
2016-10-04 05:17:47 -- info: Initiate backup for HSTERM2
2016-10-04 05:17:47 -- info: Creating Snapshot "ghettoVCB-snapshot-2016-10-04" for HSTERM2
2016-10-04 05:17:48 -- debug: Waiting for snapshot "ghettoVCB-snapshot-2016-10-04" to be created
2016-10-04 05:17:48 -- debug: Snapshot timeout set to: 900 seconds
2016-10-04 05:17:49 -- debug: findVMDK() - Searching for VMDK: "HSTERM2.vmdk" to backup
2016-10-04 05:17:49 -- debug: /sbin/vmkfstools -i "/vmfs/volumes/hsesx2_ds/HSTERM2/HSTERM2.vmdk" -a "lsilogic" -d "thin" "/vmfs/volumes/hsesx2_backup/hsesx2_vms/HSTERM2/HSTERM2-2016-10-04_05-04-59/HSTERM2.vmdk"
2016-10-04 05:22:15 -- info: Removing snapshot from HSTERM2 ...
2016-10-04 05:22:15 -- debug: Removing /vmfs/volumes/hsesx2_backup/hsesx2_vms/HSTERM2/HSTERM2-2016-09-29_05-05-01
2016-10-04 05:22:15 -- info: Backup Duration: 4.47 Minutes
2016-10-04 05:22:15 -- info: Successfully completed backup for HSTERM2!

2016-10-04 05:22:20 -- debug: Storage Information after backup:
2016-10-04 05:22:20 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:22:20 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:22:20 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:22:20 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:22:20 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:22:20 -- debug:
2016-10-04 05:22:20 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:22:20 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:22:20 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:22:20 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:22:20 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:22:20 -- debug:
2016-10-04 05:22:25 -- debug: Storage Information before backup:
2016-10-04 05:22:25 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:22:25 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:22:25 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:22:25 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:22:25 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:22:25 -- debug:
2016-10-04 05:22:25 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:22:25 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:22:25 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:22:25 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:22:25 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:22:25 -- debug:
2016-10-04 05:22:26 -- debug: getVMDKs() - HSNPS2.vmdk###40:
2016-10-04 05:22:26 -- info: Initiate backup for HSNPS2
2016-10-04 05:22:26 -- info: Creating Snapshot "ghettoVCB-snapshot-2016-10-04" for HSNPS2
2016-10-04 05:22:28 -- debug: Waiting for snapshot "ghettoVCB-snapshot-2016-10-04" to be created
2016-10-04 05:22:28 -- debug: Snapshot timeout set to: 900 seconds
2016-10-04 05:22:29 -- debug: findVMDK() - Searching for VMDK: "HSNPS2.vmdk" to backup
2016-10-04 05:22:29 -- debug: /sbin/vmkfstools -i "/vmfs/volumes/hsesx2_ds/HSNPS2/HSNPS2.vmdk" -a "lsilogic" -d "thin" "/vmfs/volumes/hsesx2_backup/hsesx2_vms/HSNPS2/HSNPS2-2016-10-04_05-04-59/HSNPS2.vmdk"
2016-10-04 05:25:06 -- info: Removing snapshot from HSNPS2 ...
2016-10-04 05:25:06 -- debug: Removing /vmfs/volumes/hsesx2_backup/hsesx2_vms/HSNPS2/HSNPS2-2016-09-29_05-05-01
2016-10-04 05:25:06 -- info: Backup Duration: 2.67 Minutes
2016-10-04 05:25:06 -- info: Successfully completed backup for HSNPS2!

2016-10-04 05:25:11 -- debug: Storage Information after backup:
2016-10-04 05:25:11 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:25:11 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:25:11 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:25:11 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:25:11 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:25:11 -- debug:
2016-10-04 05:25:11 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:25:11 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:25:11 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:25:11 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:25:11 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:25:11 -- debug:
2016-10-04 05:25:16 -- debug: Storage Information before backup:
2016-10-04 05:25:16 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:25:16 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:25:16 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:25:16 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:25:16 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:25:16 -- debug:
2016-10-04 05:25:16 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:25:16 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:25:16 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:25:16 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:25:16 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:25:16 -- debug:
2016-10-04 05:25:16 -- debug: getVMDKs() - HSAP2P_1.vmdk###25:HSAP2P.vmdk###40:
2016-10-04 05:25:17 -- info: Initiate backup for HSAP2P
2016-10-04 05:25:17 -- info: Creating Snapshot "ghettoVCB-snapshot-2016-10-04" for HSAP2P
2016-10-04 05:25:18 -- debug: Waiting for snapshot "ghettoVCB-snapshot-2016-10-04" to be created
2016-10-04 05:25:18 -- debug: Snapshot timeout set to: 900 seconds
2016-10-04 05:25:19 -- debug: findVMDK() - Searching for VMDK: "HSAP2P_1.vmdk" to backup
2016-10-04 05:25:19 -- debug: /sbin/vmkfstools -i "/vmfs/volumes/hsesx2_ds/HSAP2P/HSAP2P_1.vmdk" -a "lsilogic" -d "thin" "/vmfs/volumes/hsesx2_backup/hsesx2_vms/HSAP2P/HSAP2P-2016-10-04_05-04-59/HSAP2P_1.vmdk"
2016-10-04 05:25:30 -- debug: findVMDK() - Searching for VMDK: "HSAP2P.vmdk" to backup
2016-10-04 05:25:30 -- debug: /sbin/vmkfstools -i "/vmfs/volumes/hsesx2_ds/HSAP2P/HSAP2P.vmdk" -a "lsilogic" -d "thin" "/vmfs/volumes/hsesx2_backup/hsesx2_vms/HSAP2P/HSAP2P-2016-10-04_05-04-59/HSAP2P.vmdk"
2016-10-04 05:26:53 -- info: Removing snapshot from HSAP2P ...
2016-10-04 05:26:53 -- debug: Removing /vmfs/volumes/hsesx2_backup/hsesx2_vms/HSAP2P/HSAP2P-2016-09-29_05-05-01
2016-10-04 05:26:53 -- info: Backup Duration: 1.60 Minutes
2016-10-04 05:26:53 -- info: Successfully completed backup for HSAP2P!

2016-10-04 05:26:58 -- debug: Storage Information after backup:
2016-10-04 05:26:58 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:26:58 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:26:58 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:26:58 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:26:58 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:26:58 -- debug:
2016-10-04 05:26:58 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:26:58 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:26:58 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:26:58 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:26:58 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:26:58 -- debug:
2016-10-04 05:27:03 -- debug: Storage Information before backup:
2016-10-04 05:27:03 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:27:03 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:27:03 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:27:03 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:27:03 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:27:03 -- debug:
2016-10-04 05:27:03 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:27:03 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:27:03 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:27:03 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:27:03 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:27:03 -- debug:
2016-10-04 05:27:03 -- debug: getVMDKs() - HSNAGIOS1.vmdk###10:
2016-10-04 05:27:04 -- info: Initiate backup for HSNAGIOS1
2016-10-04 05:27:04 -- debug: findVMDK() - Searching for VMDK: "HSNAGIOS1.vmdk" to backup
2016-10-04 05:27:04 -- debug: /sbin/vmkfstools -i "/vmfs/volumes/hsesx2_ds/HSNAGIOS1/HSNAGIOS1.vmdk" -a "lsilogic" -d "thin" "/vmfs/volumes/hsesx2_backup/hsesx2_vms/HSNAGIOS1/HSNAGIOS1-2016-10-04_05-04-59/HSNAGIOS1.vmdk"
2016-10-04 05:29:20 -- debug: Removing /vmfs/volumes/hsesx2_backup/hsesx2_vms/HSNAGIOS1/HSNAGIOS1-2016-09-29_05-05-01
2016-10-04 05:29:20 -- info: Backup Duration: 2.27 Minutes
2016-10-04 05:29:20 -- info: Successfully completed backup for HSNAGIOS1!

2016-10-04 05:29:25 -- debug: Storage Information after backup:
2016-10-04 05:29:25 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:29:25 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:29:25 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:29:25 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:29:25 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:29:25 -- debug:
2016-10-04 05:29:25 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:29:25 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:29:25 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:29:25 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:29:25 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:29:25 -- debug:
2016-10-04 05:29:30 -- debug: Storage Information before backup:
2016-10-04 05:29:30 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:29:30 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:29:30 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:29:30 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:29:30 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:29:30 -- debug:
2016-10-04 05:29:30 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:29:30 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:29:30 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:29:30 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:29:30 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:29:30 -- debug:
2016-10-04 05:29:30 -- info: Snapshot found for RDDC1, backup will not take place

2016-10-04 05:29:35 -- debug: Storage Information before backup:
2016-10-04 05:29:35 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:29:35 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:29:35 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:29:35 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:29:35 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:29:35 -- debug:
2016-10-04 05:29:35 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:29:35 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:29:35 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:29:35 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:29:35 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:29:35 -- debug:
2016-10-04 05:29:36 -- debug: getVMDKs() - RDDC2.vmdk###40:
2016-10-04 05:29:37 -- info: Initiate backup for RDDC2
2016-10-04 05:29:37 -- debug: findVMDK() - Searching for VMDK: "RDDC2.vmdk" to backup
2016-10-04 05:29:37 -- debug: /sbin/vmkfstools -i "/vmfs/volumes/hsesx2_ds/RDDC2/RDDC2.vmdk" -a "lsilogic" -d "thin" "/vmfs/volumes/hsesx2_backup/hsesx2_vms/RDDC2/RDDC2-2016-10-04_05-04-59/RDDC2.vmdk"
2016-10-04 05:30:49 -- debug: Removing /vmfs/volumes/hsesx2_backup/hsesx2_vms/RDDC2/RDDC2-2016-09-29_05-05-01
2016-10-04 05:30:49 -- info: Backup Duration: 1.20 Minutes
2016-10-04 05:30:49 -- info: Successfully completed backup for RDDC2!

2016-10-04 05:30:54 -- debug: Storage Information after backup:
2016-10-04 05:30:54 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:30:54 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:30:54 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:30:54 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:30:54 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:30:54 -- debug:
2016-10-04 05:30:54 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:30:54 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:30:54 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:30:54 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:30:54 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:30:54 -- debug:
2016-10-04 05:30:59 -- debug: Storage Information before backup:
2016-10-04 05:30:59 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:30:59 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:30:59 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:30:59 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:30:59 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:30:59 -- debug:
2016-10-04 05:30:59 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:30:59 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:30:59 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:30:59 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:30:59 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:30:59 -- debug:
2016-10-04 05:30:59 -- debug: getVMDKs() - HSZBXN1.vmdk###30:
2016-10-04 05:31:00 -- info: Initiate backup for HSZBXN1
2016-10-04 05:31:00 -- info: Creating Snapshot "ghettoVCB-snapshot-2016-10-04" for HSZBXN1
2016-10-04 05:31:02 -- debug: Waiting for snapshot "ghettoVCB-snapshot-2016-10-04" to be created
2016-10-04 05:31:02 -- debug: Snapshot timeout set to: 900 seconds
2016-10-04 05:31:03 -- debug: findVMDK() - Searching for VMDK: "HSZBXN1.vmdk" to backup
2016-10-04 05:31:03 -- debug: /sbin/vmkfstools -i "/vmfs/volumes/hsesx2_ds/HSZBXN1/HSZBXN1.vmdk" -a "lsilogic" -d "thin" "/vmfs/volumes/hsesx2_backup/hsesx2_vms/HSZBXN1/HSZBXN1-2016-10-04_05-04-59/HSZBXN1.vmdk"
2016-10-04 05:37:21 -- info: Removing snapshot from HSZBXN1 ...
2016-10-04 05:37:21 -- debug: Removing /vmfs/volumes/hsesx2_backup/hsesx2_vms/HSZBXN1/HSZBXN1-2016-09-29_05-05-01
2016-10-04 05:37:21 -- info: Backup Duration: 6.35 Minutes
2016-10-04 05:37:21 -- info: Successfully completed backup for HSZBXN1!

2016-10-04 05:37:26 -- debug: Storage Information after backup:
2016-10-04 05:37:26 -- debug: SRC_DATASTORE: hsesx2_ds
2016-10-04 05:37:26 -- debug: SRC_DATASTORE_CAPACITY: 63488.0
698.0 GB
2016-10-04 05:37:26 -- debug: SRC_DATASTORE_FREE: 502.5 GB
2016-10-04 05:37:26 -- debug: SRC_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:37:26 -- debug: SRC_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:37:26 -- debug:
2016-10-04 05:37:26 -- debug: DST_DATASTORE: hsesx2_backup
2016-10-04 05:37:26 -- debug: DST_DATASTORE_CAPACITY: 63488.0
931.2 GB
2016-10-04 05:37:26 -- debug: DST_DATASTORE_FREE: 673.1 GB
2016-10-04 05:37:26 -- debug: DST_DATASTORE_BLOCKSIZE: 1
2016-10-04 05:37:26 -- debug: DST_DATASTORE_MAX_FILE_SIZE: 256 GB
2016-10-04 05:37:26 -- debug:
2016-10-04 05:37:26 -- debug: VM Startup Order: =

2016-10-04 05:37:26 -- info: Powering on initiated for =
2016-10-04 05:37:28 -- info: VM is powerdOn
2016-10-04 05:37:28 -- info: ###### Final status: ERROR: Only some of the VMs backed up! ######

2016-10-04 05:37:28 -- debug: Succesfully removed lock directory - /tmp/ghettoVCB.work

2016-10-04 05:37:28 -- info: ============================== ghettoVCB LOG END ================================`