10:06:25.295 [3828.6472] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpVMutil.exe 0 0 _NONE_ 
10:06:25.295 [3828.6472] virt_test_log: log function is called
10:06:25.295 [3828.6472] <2> bpVMutil main: cmd = 0 ESXMount = 0 ESXserver = _NONE_, flags = 0
10:06:25.295 [3828.6472] <2> bpVMutil main: Hyper-V GET Names requested
10:06:25.311 [3828.6472] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: SPFCUBICON2
10:06:25.311 [3828.6472] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:06:25.311 [3828.6472] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: S1-SYS-MA-01
10:06:25.311 [3828.6472] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:06:25.311 [3828.6472] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.384: AZ Service Host: s1-sys-ma-01m
10:06:25.311 [3828.6472] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.385: AZ Service Port: 0 0 0x00000000
10:06:25.311 [3828.6472] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.65309 TO 172.24.79.27.13720 fd = 484
10:06:26.169 [3828.6472] checkBackupRegEntry: looking registry for excludeVMservers
10:06:26.169 [3828.6472]  RegOpenKey failed with 2
10:06:26.169 [3828.6472] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:06:26.169 [3828.6472] <2> bpVMutil main: Saving IP-SYS-SM-02M guests to C:\Windows\TEMP\VMutl-03828449194785295472000000000-a06472
10:06:26.169 [3828.6472] <2> get_VM_names: get_VM_names: read for IP-SYS-SM-02M
10:06:26.169 [3828.6472] get_id_lock: wait lock timeout value: 30 minutes, 10 interval seconds
10:06:26.169 [3828.6472] fis_lock: entering ...
10:06:26.169 [3828.6472] rw_lock: leaving ...
10:06:26.169 [3828.6472] WRITE LOCK VMcache_lock fd = 484
10:06:26.169 [3828.6472] <2> get_VM_names: get_id_lock returned 0
10:06:26.169 [3828.6472]  read cache file = D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\vcbnames_IP-SYS-SM-02M_Any
10:06:26.169 [3828.6472] read_guests returned 0 and 0
10:06:26.169 [3828.6472] UNLOCK fd = 484
10:06:26.169 [3828.6472] removing D:\Program Files\Veritas\NetBackup\VMcache_lock
10:06:26.169 [3828.6472] <2> get_VM_names: release_id_lock returned 0
10:06:26.169 [3828.6472] <2> Cache for IP-SYS-SM-02M complete: bpVMutil
10:06:26.169 [3828.6472] <2> bpVMutil main: save_guests returned 0 for IP-SYS-SM-02M
10:06:26.169 [3828.6472] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:06:26.169 [3828.6472] <2> Ending cached = 1, sched 0: bpVMutil
10:06:26.169 [3828.6472] <2> bpVMutil main: EXIT STATUS 0: the requested operation was successfully completed
10:16:29.292 [7464.6136] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpVMutil.exe 0 0 _NONE_ 
10:16:29.292 [7464.6136] virt_test_log: log function is called
10:16:29.292 [7464.6136] <2> bpVMutil main: cmd = 0 ESXMount = 0 ESXserver = _NONE_, flags = 0
10:16:29.292 [7464.6136] <2> bpVMutil main: Hyper-V GET Names requested
10:16:29.292 [7464.6136] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: SPFCUBICON2
10:16:29.292 [7464.6136] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:16:29.292 [7464.6136] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: S1-SYS-MA-01
10:16:29.292 [7464.6136] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:16:29.292 [7464.6136] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.384: AZ Service Host: s1-sys-ma-01m
10:16:29.292 [7464.6136] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.385: AZ Service Port: 0 0 0x00000000
10:16:29.292 [7464.6136] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.49450 TO 172.24.79.27.13720 fd = 484
10:16:30.181 [7464.6136] checkBackupRegEntry: looking registry for excludeVMservers
10:16:30.181 [7464.6136]  RegOpenKey failed with 2
10:16:30.181 [7464.6136] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:16:30.181 [7464.6136] <2> bpVMutil main: Saving IP-SYS-SM-02M guests to C:\Windows\TEMP\VMutl-07464449195389292136000000000-a06136
10:16:30.181 [7464.6136] <2> get_VM_names: get_VM_names: read for IP-SYS-SM-02M
10:16:30.181 [7464.6136] get_id_lock: wait lock timeout value: 30 minutes, 10 interval seconds
10:16:30.181 [7464.6136] fis_lock: entering ...
10:16:30.181 [7464.6136] rw_lock: leaving ...
10:16:30.181 [7464.6136] WRITE LOCK VMcache_lock fd = 484
10:16:30.181 [7464.6136] <2> get_VM_names: get_id_lock returned 0
10:16:30.181 [7464.6136]  read cache file = D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\vcbnames_IP-SYS-SM-02M_Any
10:16:30.181 [7464.6136] read_guests returned 0 and 0
10:16:30.181 [7464.6136] UNLOCK fd = 484
10:16:30.181 [7464.6136] removing D:\Program Files\Veritas\NetBackup\VMcache_lock
10:16:30.181 [7464.6136] <2> get_VM_names: release_id_lock returned 0
10:16:30.181 [7464.6136] <2> Cache for IP-SYS-SM-02M complete: bpVMutil
10:16:30.181 [7464.6136] <2> bpVMutil main: save_guests returned 0 for IP-SYS-SM-02M
10:16:30.181 [7464.6136] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:16:30.181 [7464.6136] <2> Ending cached = 1, sched 0: bpVMutil
10:16:30.181 [7464.6136] <2> bpVMutil main: EXIT STATUS 0: the requested operation was successfully completed
10:26:12.977 [3572.4796] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpVMutil.exe 2816 ip-sys-04m.spfcubicon2.spf.gov.sg IP-SYS-SM-02M 
10:26:12.977 [3572.4796] virt_test_log: log function is called
10:26:12.977 [3572.4796] <2> bpVMutil main: cmd = 11 ESXMount = ip-sys-04m.spfcubicon2.spf.gov.sg ESXserver = IP-SYS-SM-02M, flags = 0
10:26:12.977 [3572.4796] get_id_lock: wait lock timeout value: 15 minutes, 5 interval seconds
10:26:12.977 [3572.4796] fis_lock: entering ...
10:26:12.977 [3572.4796] rw_lock: leaving ...
10:26:12.977 [3572.4796] WRITE LOCK ip-sys-04m.spfcubicon2.spf.gov.sg_lock fd = 280
10:26:12.977 [3572.4796] generateServerFiles: get_id_lock returned 0
10:26:12.977 [3572.4796] generateServerFiles: using file D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\IP-SYS-SM-02M_resp_ip-sys-04m.spfcubicon2.spf.gov.sg.xml
10:26:12.977 [3572.4796] find_virtual_server_cred: Looking for: IP-SYS-SM-02M
10:26:12.993 [3572.4796] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: SPFCUBICON2
10:26:12.993 [3572.4796] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:26:12.993 [3572.4796] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: S1-SYS-MA-01
10:26:12.993 [3572.4796] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:26:12.993 [3572.4796] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.384: AZ Service Host: s1-sys-ma-01m
10:26:12.993 [3572.4796] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.385: AZ Service Port: 0 0 0x00000000
10:26:12.993 [3572.4796] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50027 TO 172.24.79.27.13720 fd = 488
10:26:13.929 [3572.4796] checkBackupRegEntry: looking registry for excludeVMservers
10:26:13.929 [3572.4796]  RegOpenKey failed with 2
10:26:13.929 [3572.4796] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:26:13.929 [3572.4796] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:13.929 [3572.4796] generateServerFiles: D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\IP-SYS-SM-02M_resp_ip-sys-04m.spfcubicon2.spf.gov.sg.xml over one hour old, rebuilding
10:26:13.929 [3572.4796] listResourcePools: Listing Resource Pools on IP-SYS-SM-02M for ip-sys-04m.spfcubicon2.spf.gov.sg
10:26:13.929 [3572.4796] checkBackupRegEntry: looking registry for connecttimeout
10:26:13.929 [3572.4796]  RegOpenKey failed with 2
10:26:13.929 [3572.4796] vSphereConnect: ConnectTimeout: 300 secs
10:26:13.929 [3572.4796] vSphereConnect: connecting to https://IP-SYS-SM-02M/sdk
10:26:13.929 [3572.4796] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:14.506 [3572.4796] checkBackupRegEntry: looking registry for jobtimeout
10:26:14.506 [3572.4796]  RegOpenKey failed with 2
10:26:14.506 [3572.4796] checkBackupRegEntry: looking registry for poweroptimeout
10:26:14.506 [3572.4796]  RegOpenKey failed with 2
10:26:14.506 [3572.4796] checkBackupRegEntry: looking registry for snapshottimeout
10:26:14.506 [3572.4796]  RegOpenKey failed with 2
10:26:14.506 [3572.4796] checkBackupRegEntry: looking registry for registertimeout
10:26:14.506 [3572.4796]  RegOpenKey failed with 2
10:26:14.506 [3572.4796] checkBackupRegEntry: looking registry for browsetimeout
10:26:14.506 [3572.4796]  RegOpenKey failed with 2
10:26:14.506 [3572.4796] SetTimeoutsAndLogging: JobTimeout: 900 secs
10:26:14.506 [3572.4796] SetTimeoutsAndLogging: PowerOpTimeout: 900 secs
10:26:14.506 [3572.4796] SetTimeoutsAndLogging: SnapshotTimeout: 900 secs
10:26:14.506 [3572.4796] SetTimeoutsAndLogging: RegisterTimeout: 180 secs
10:26:14.506 [3572.4796] SetTimeoutsAndLogging: BrowseTimeout: 180 secs
10:26:14.506 [3572.4796] checkBackupRegEntry: looking registry for vmcloglevel
10:26:14.506 [3572.4796]  RegOpenKey failed with 2
10:26:14.506 [3572.4796] SetTimeoutsAndLogging: vmcloglevel=0
10:26:14.568 [3572.4796] vmwareLogger: Login: key            = 52bb7a97-29fd-105d-60ce-cf6b41acd9a2

10:26:14.568 [3572.4796] dovSphereLogin: login for IP-SYS-SM-02M completed with flags 70 and status 0
10:26:14.568 [3572.4796] vmwareLogger: BuildDatabases: Building databases

10:26:15.364 [3572.4796] vmwareLogger: BuildDatabases: Building databases

10:26:15.364 [3572.4796] vmwareLogger: BuildDatabases: Building databases

10:26:15.411 [3572.4796] generateServerFiles: D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\IP-SYS-SM-02M_datas_ip-sys-04m.spfcubicon2.spf.gov.sg.xml over one hour old, rebuilding
10:26:15.411 [3572.4796] listDatastores: Listing datastores on IP-SYS-SM-02M for ip-sys-04m.spfcubicon2.spf.gov.sg
10:26:15.411 [3572.4796] getDatastoresList: Creating List of datastores on IP-SYS-SM-02M for ip-sys-04m.spfcubicon2.spf.gov.sg
10:26:15.411 [3572.4796] dovSphereLogin: server IP-SYS-SM-02M already logged in
10:26:15.442 [3572.4796] generateServerFiles: D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\IP-SYS-SM-02M_net_ip-sys-04m.spfcubicon2.spf.gov.sg.xml over one hour old, rebuilding
10:26:15.442 [3572.4796] checkBackupRegEntry: looking registry for disableFQDN
10:26:15.442 [3572.4796]  RegOpenKey failed with 2
10:26:15.442 [3572.4796] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6669: 0: fopen() failed: 2 0x00000002
10:26:15.442 [3572.4796] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6670: 0: fopen() failed: D:\Program Files\Veritas\NetBackup\var\host_cache\1b9\a81afdb9+0,1,2,0,0,0+ip-sys-04m.spfcubicon2.spf.gov.sg.txt
10:26:15.457 [3572.4796] get_fqdn_hostname: fqdn host ip-sys-04m.spfcubicon2.spf.gov.sg
10:26:15.457 [3572.4796] listNetworks: Listing networks on IP-SYS-SM-02M for ip-sys-04m.spfcubicon2.spf.gov.sg
10:26:15.457 [3572.4796] dovSphereLogin: server IP-SYS-SM-02M already logged in
10:26:15.504 [3572.4796] UNLOCK fd = 280
10:26:15.504 [3572.4796] removing D:\Program Files\Veritas\NetBackup\ip-sys-04m.spfcubicon2.spf.gov.sg_lock
10:26:15.504 [3572.4796] generateServerFiles: release_id_lock returned 0
10:26:15.520 [3572.4796] vmwareLogger: Logout: returned 0

10:26:15.535 [3572.4796] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:15.535 [3572.4796] find_virtual_server_cred: Looking for: IP-SYS-SM-02M
10:26:15.535 [3572.4796] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50034 TO 172.24.79.27.13720 fd = 1600
10:26:16.550 [3572.4796] checkBackupRegEntry: looking registry for excludeVMservers
10:26:16.550 [3572.4796]  RegOpenKey failed with 2
10:26:16.550 [3572.4796] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:26:16.550 [3572.4796] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:16.550 [3572.4796] get_id_lock: wait lock timeout value: 30 minutes, 10 interval seconds
10:26:16.550 [3572.4796] fis_lock: entering ...
10:26:16.550 [3572.4796] rw_lock: leaving ...
10:26:16.550 [3572.4796] WRITE LOCK ip-sys-04m.spfcubicon2.spf.gov.sg_lock fd = 1600
10:26:16.550 [3572.4796] <2> browseResourcePools: get_id_lock returned 0
10:26:16.550 [3572.4796] <2> browseResourcePools: fileCOPY of D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\IP-SYS-SM-02M_resp_ip-sys-04m.spfcubicon2.spf.gov.sg.xml to C:\Windows\TEMP\VMutl-03572449195975535796000000002-a04796 returned 0
10:26:16.550 [3572.4796] UNLOCK fd = 1600
10:26:16.550 [3572.4796] removing D:\Program Files\Veritas\NetBackup\ip-sys-04m.spfcubicon2.spf.gov.sg_lock
10:26:16.550 [3572.4796] <2> browseResourcePools: release_id_lock returned 0
10:26:16.550 [3572.4796] <2> bpVMutil main: EXIT STATUS 0: the requested operation was successfully completed
10:26:20.231 [7584.7960] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpVMutil.exe 3072 ip-sys-04m.spfcubicon2.spf.gov.sg IP-SYS-SM-02M 
10:26:20.231 [7584.7960] virt_test_log: log function is called
10:26:20.231 [7584.7960] <2> bpVMutil main: cmd = 12 ESXMount = ip-sys-04m.spfcubicon2.spf.gov.sg ESXserver = IP-SYS-SM-02M, flags = 0
10:26:20.231 [7584.7960] get_id_lock: wait lock timeout value: 15 minutes, 5 interval seconds
10:26:20.231 [7584.7960] fis_lock: entering ...
10:26:20.247 [7584.7960] rw_lock: leaving ...
10:26:20.247 [7584.7960] WRITE LOCK ip-sys-04m.spfcubicon2.spf.gov.sg_lock fd = 280
10:26:20.247 [7584.7960] generateServerFiles: get_id_lock returned 0
10:26:20.247 [7584.7960] generateServerFiles: using file D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\IP-SYS-SM-02M_resp_ip-sys-04m.spfcubicon2.spf.gov.sg.xml
10:26:20.247 [7584.7960] find_virtual_server_cred: Looking for: IP-SYS-SM-02M
10:26:20.247 [7584.7960] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: SPFCUBICON2
10:26:20.247 [7584.7960] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:26:20.247 [7584.7960] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: S1-SYS-MA-01
10:26:20.247 [7584.7960] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:26:20.247 [7584.7960] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.384: AZ Service Host: s1-sys-ma-01m
10:26:20.247 [7584.7960] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.385: AZ Service Port: 0 0 0x00000000
10:26:20.247 [7584.7960] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50046 TO 172.24.79.27.13720 fd = 488
10:26:21.058 [7584.7960] checkBackupRegEntry: looking registry for excludeVMservers
10:26:21.058 [7584.7960]  RegOpenKey failed with 2
10:26:21.058 [7584.7960] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:26:21.058 [7584.7960] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:21.074 [7584.7960] UNLOCK fd = 280
10:26:21.074 [7584.7960] removing D:\Program Files\Veritas\NetBackup\ip-sys-04m.spfcubicon2.spf.gov.sg_lock
10:26:21.074 [7584.7960] generateServerFiles: release_id_lock returned 0
10:26:21.074 [7584.7960] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:21.074 [7584.7960] find_virtual_server_cred: Looking for: IP-SYS-SM-02M
10:26:21.074 [7584.7960] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50053 TO 172.24.79.27.13720 fd = 488
10:26:21.963 [7584.7960] checkBackupRegEntry: looking registry for excludeVMservers
10:26:21.963 [7584.7960]  RegOpenKey failed with 2
10:26:21.963 [7584.7960] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:26:21.963 [7584.7960] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:21.963 [7584.7960] get_id_lock: wait lock timeout value: 30 minutes, 10 interval seconds
10:26:21.963 [7584.7960] fis_lock: entering ...
10:26:21.963 [7584.7960] rw_lock: leaving ...
10:26:21.963 [7584.7960] WRITE LOCK ip-sys-04m.spfcubicon2.spf.gov.sg_lock fd = 488
10:26:21.963 [7584.7960] <2> browseDatastores: get_id_lock returned 0
10:26:21.963 [7584.7960] <2> browseDatastores: fileCOPY of D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\IP-SYS-SM-02M_datas_ip-sys-04m.spfcubicon2.spf.gov.sg.xml to C:\Windows\TEMP\VMutl-07584449195981074960000000001-a07960 returned 0
10:26:21.963 [7584.7960] UNLOCK fd = 488
10:26:21.963 [7584.7960] removing D:\Program Files\Veritas\NetBackup\ip-sys-04m.spfcubicon2.spf.gov.sg_lock
10:26:21.963 [7584.7960] <2> browseDatastores: release_id_lock returned 0
10:26:21.963 [7584.7960] <2> bpVMutil main: EXIT STATUS 0: the requested operation was successfully completed
10:26:27.002 [3256.8028] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpVMutil.exe 1792 _NONE_ IP-SYS-SM-02M 
10:26:27.002 [3256.8028] virt_test_log: log function is called
10:26:27.002 [3256.8028] <2> bpVMutil main: cmd = 7 ESXMount = _NONE_ ESXserver = IP-SYS-SM-02M, flags = 0
10:26:27.002 [3256.8028] find_virtual_server_cred: Looking for: IP-SYS-SM-02M
10:26:27.002 [3256.8028] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: SPFCUBICON2
10:26:27.002 [3256.8028] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:26:27.002 [3256.8028] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: S1-SYS-MA-01
10:26:27.002 [3256.8028] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:26:27.002 [3256.8028] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.384: AZ Service Host: s1-sys-ma-01m
10:26:27.002 [3256.8028] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.385: AZ Service Port: 0 0 0x00000000
10:26:27.002 [3256.8028] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50080 TO 172.24.79.27.13720 fd = 484
10:26:27.969 [3256.8028] checkBackupRegEntry: looking registry for excludeVMservers
10:26:27.969 [3256.8028]  RegOpenKey failed with 2
10:26:27.969 [3256.8028] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:26:27.969 [3256.8028] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:27.969 [3256.8028] getvSphereViewTree: Getting Virtual Center view tree for IP-SYS-SM-02M
10:26:27.969 [3256.8028] checkBackupRegEntry: looking registry for connecttimeout
10:26:27.969 [3256.8028]  RegOpenKey failed with 2
10:26:27.969 [3256.8028] vSphereConnect: ConnectTimeout: 300 secs
10:26:27.969 [3256.8028] vSphereConnect: connecting to https://IP-SYS-SM-02M/sdk
10:26:27.969 [3256.8028] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:28.593 [3256.8028] checkBackupRegEntry: looking registry for jobtimeout
10:26:28.593 [3256.8028]  RegOpenKey failed with 2
10:26:28.593 [3256.8028] checkBackupRegEntry: looking registry for poweroptimeout
10:26:28.593 [3256.8028]  RegOpenKey failed with 2
10:26:28.593 [3256.8028] checkBackupRegEntry: looking registry for snapshottimeout
10:26:28.593 [3256.8028]  RegOpenKey failed with 2
10:26:28.593 [3256.8028] checkBackupRegEntry: looking registry for registertimeout
10:26:28.593 [3256.8028]  RegOpenKey failed with 2
10:26:28.593 [3256.8028] checkBackupRegEntry: looking registry for browsetimeout
10:26:28.593 [3256.8028]  RegOpenKey failed with 2
10:26:28.593 [3256.8028] SetTimeoutsAndLogging: JobTimeout: 900 secs
10:26:28.593 [3256.8028] SetTimeoutsAndLogging: PowerOpTimeout: 900 secs
10:26:28.593 [3256.8028] SetTimeoutsAndLogging: SnapshotTimeout: 900 secs
10:26:28.593 [3256.8028] SetTimeoutsAndLogging: RegisterTimeout: 180 secs
10:26:28.593 [3256.8028] SetTimeoutsAndLogging: BrowseTimeout: 180 secs
10:26:28.593 [3256.8028] checkBackupRegEntry: looking registry for vmcloglevel
10:26:28.593 [3256.8028]  RegOpenKey failed with 2
10:26:28.593 [3256.8028] SetTimeoutsAndLogging: vmcloglevel=0
10:26:28.655 [3256.8028] vmwareLogger: Login: key            = 521b0dbc-9d89-2d85-51f0-bb2897f182bb

10:26:28.655 [3256.8028] dovSphereLogin: login for IP-SYS-SM-02M completed with flags 70 and status 0
10:26:28.655 [3256.8028] vmwareLogger: BuildDatabases: Building databases

10:26:29.357 [3256.8028] vmwareLogger: BuildDatabases: Building databases

10:26:29.420 [3256.8028] vmwareLogger: Logout: returned 0

10:26:29.435 [3256.8028] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:29.435 [3256.8028] <2> bpVMutil main: EXIT STATUS 0: the requested operation was successfully completed
10:26:33.257 [7508.7200] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpVMutil.exe 0 0 _NONE_ 
10:26:33.257 [7508.7200] virt_test_log: log function is called
10:26:33.257 [7508.7200] <2> bpVMutil main: cmd = 0 ESXMount = 0 ESXserver = _NONE_, flags = 0
10:26:33.257 [7508.7200] <2> bpVMutil main: Hyper-V GET Names requested
10:26:33.273 [7508.7200] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: SPFCUBICON2
10:26:33.273 [7508.7200] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:26:33.273 [7508.7200] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: S1-SYS-MA-01
10:26:33.273 [7508.7200] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:26:33.273 [7508.7200] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.384: AZ Service Host: s1-sys-ma-01m
10:26:33.273 [7508.7200] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.385: AZ Service Port: 0 0 0x00000000
10:26:33.273 [7508.7200] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50104 TO 172.24.79.27.13720 fd = 488
10:26:34.287 [7508.7200] checkBackupRegEntry: looking registry for excludeVMservers
10:26:34.287 [7508.7200]  RegOpenKey failed with 2
10:26:34.287 [7508.7200] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:26:34.287 [7508.7200] <2> bpVMutil main: Saving IP-SYS-SM-02M guests to C:\Windows\TEMP\VMutl-07508449195993257200000000000-a07200
10:26:34.287 [7508.7200] <2> get_VM_names: get_VM_names: read for IP-SYS-SM-02M
10:26:34.287 [7508.7200] get_id_lock: wait lock timeout value: 30 minutes, 10 interval seconds
10:26:34.287 [7508.7200] fis_lock: entering ...
10:26:34.287 [7508.7200] rw_lock: leaving ...
10:26:34.287 [7508.7200] WRITE LOCK VMcache_lock fd = 488
10:26:34.287 [7508.7200] <2> get_VM_names: get_id_lock returned 0
10:26:34.287 [7508.7200]  read cache file = D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\vcbnames_IP-SYS-SM-02M_Any
10:26:34.287 [7508.7200] read_guests returned 0 and 0
10:26:34.287 [7508.7200] UNLOCK fd = 488
10:26:34.287 [7508.7200] removing D:\Program Files\Veritas\NetBackup\VMcache_lock
10:26:34.287 [7508.7200] <2> get_VM_names: release_id_lock returned 0
10:26:34.287 [7508.7200] <2> Cache for IP-SYS-SM-02M complete: bpVMutil
10:26:34.287 [7508.7200] <2> bpVMutil main: save_guests returned 0 for IP-SYS-SM-02M
10:26:34.287 [7508.7200] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:34.287 [7508.7200] <2> Ending cached = 1, sched 0: bpVMutil
10:26:34.287 [7508.7200] <2> bpVMutil main: EXIT STATUS 0: the requested operation was successfully completed
10:26:37.797 [6696.4016] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpVMutil.exe 2816 ip-sys-02m.spfcubicon2.spf.gov.sg IP-SYS-SM-02M 
10:26:37.797 [6696.4016] virt_test_log: log function is called
10:26:37.797 [6696.4016] <2> bpVMutil main: cmd = 11 ESXMount = ip-sys-02m.spfcubicon2.spf.gov.sg ESXserver = IP-SYS-SM-02M, flags = 0
10:26:37.797 [6696.4016] get_id_lock: wait lock timeout value: 15 minutes, 5 interval seconds
10:26:37.797 [6696.4016] fis_lock: entering ...
10:26:37.797 [6696.4016] rw_lock: leaving ...
10:26:37.797 [6696.4016] WRITE LOCK ip-sys-02m.spfcubicon2.spf.gov.sg_lock fd = 280
10:26:37.797 [6696.4016] generateServerFiles: get_id_lock returned 0
10:26:37.797 [6696.4016] generateServerFiles: using file D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\IP-SYS-SM-02M_resp_ip-sys-02m.spfcubicon2.spf.gov.sg.xml
10:26:37.797 [6696.4016] find_virtual_server_cred: Looking for: IP-SYS-SM-02M
10:26:37.813 [6696.4016] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: SPFCUBICON2
10:26:37.813 [6696.4016] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:26:37.813 [6696.4016] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: S1-SYS-MA-01
10:26:37.813 [6696.4016] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:26:37.813 [6696.4016] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.384: AZ Service Host: s1-sys-ma-01m
10:26:37.813 [6696.4016] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.385: AZ Service Port: 0 0 0x00000000
10:26:37.813 [6696.4016] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50117 TO 172.24.79.27.13720 fd = 488
10:26:38.686 [6696.4016] checkBackupRegEntry: looking registry for excludeVMservers
10:26:38.686 [6696.4016]  RegOpenKey failed with 2
10:26:38.686 [6696.4016] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:26:38.686 [6696.4016] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:38.686 [6696.4016] generateServerFiles: D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\IP-SYS-SM-02M_resp_ip-sys-02m.spfcubicon2.spf.gov.sg.xml over one hour old, rebuilding
10:26:38.686 [6696.4016] listResourcePools: Listing Resource Pools on IP-SYS-SM-02M for ip-sys-02m.spfcubicon2.spf.gov.sg
10:26:38.686 [6696.4016] checkBackupRegEntry: looking registry for connecttimeout
10:26:38.686 [6696.4016]  RegOpenKey failed with 2
10:26:38.686 [6696.4016] vSphereConnect: ConnectTimeout: 300 secs
10:26:38.686 [6696.4016] vSphereConnect: connecting to https://IP-SYS-SM-02M/sdk
10:26:38.686 [6696.4016] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:39.388 [6696.4016] checkBackupRegEntry: looking registry for jobtimeout
10:26:39.388 [6696.4016]  RegOpenKey failed with 2
10:26:39.388 [6696.4016] checkBackupRegEntry: looking registry for poweroptimeout
10:26:39.388 [6696.4016]  RegOpenKey failed with 2
10:26:39.388 [6696.4016] checkBackupRegEntry: looking registry for snapshottimeout
10:26:39.388 [6696.4016]  RegOpenKey failed with 2
10:26:39.388 [6696.4016] checkBackupRegEntry: looking registry for registertimeout
10:26:39.388 [6696.4016]  RegOpenKey failed with 2
10:26:39.388 [6696.4016] checkBackupRegEntry: looking registry for browsetimeout
10:26:39.388 [6696.4016]  RegOpenKey failed with 2
10:26:39.388 [6696.4016] SetTimeoutsAndLogging: JobTimeout: 900 secs
10:26:39.388 [6696.4016] SetTimeoutsAndLogging: PowerOpTimeout: 900 secs
10:26:39.388 [6696.4016] SetTimeoutsAndLogging: SnapshotTimeout: 900 secs
10:26:39.388 [6696.4016] SetTimeoutsAndLogging: RegisterTimeout: 180 secs
10:26:39.388 [6696.4016] SetTimeoutsAndLogging: BrowseTimeout: 180 secs
10:26:39.388 [6696.4016] checkBackupRegEntry: looking registry for vmcloglevel
10:26:39.388 [6696.4016]  RegOpenKey failed with 2
10:26:39.388 [6696.4016] SetTimeoutsAndLogging: vmcloglevel=0
10:26:39.435 [6696.4016] vmwareLogger: Login: key            = 52815196-7bea-6ece-d3a2-8aae820ef417

10:26:39.435 [6696.4016] dovSphereLogin: login for IP-SYS-SM-02M completed with flags 70 and status 0
10:26:39.435 [6696.4016] vmwareLogger: BuildDatabases: Building databases

10:26:40.246 [6696.4016] vmwareLogger: BuildDatabases: Building databases

10:26:40.262 [6696.4016] vmwareLogger: BuildDatabases: Building databases

10:26:40.293 [6696.4016] generateServerFiles: D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\IP-SYS-SM-02M_datas_ip-sys-02m.spfcubicon2.spf.gov.sg.xml over one hour old, rebuilding
10:26:40.293 [6696.4016] listDatastores: Listing datastores on IP-SYS-SM-02M for ip-sys-02m.spfcubicon2.spf.gov.sg
10:26:40.293 [6696.4016] getDatastoresList: Creating List of datastores on IP-SYS-SM-02M for ip-sys-02m.spfcubicon2.spf.gov.sg
10:26:40.293 [6696.4016] dovSphereLogin: server IP-SYS-SM-02M already logged in
10:26:40.340 [6696.4016] generateServerFiles: D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\IP-SYS-SM-02M_net_ip-sys-02m.spfcubicon2.spf.gov.sg.xml over one hour old, rebuilding
10:26:40.340 [6696.4016] checkBackupRegEntry: looking registry for disableFQDN
10:26:40.340 [6696.4016]  RegOpenKey failed with 2
10:26:40.340 [6696.4016] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6669: 0: fopen() failed: 2 0x00000002
10:26:40.340 [6696.4016] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6670: 0: fopen() failed: D:\Program Files\Veritas\NetBackup\var\host_cache\16b\e1f10d6b+0,1,2,0,0,0+ip-sys-02m.spfcubicon2.spf.gov.sg.txt
10:26:40.340 [6696.4016] get_fqdn_hostname: fqdn host ip-sys-02m.spfcubicon2.spf.gov.sg
10:26:40.340 [6696.4016] listNetworks: Listing networks on IP-SYS-SM-02M for ip-sys-02m.spfcubicon2.spf.gov.sg
10:26:40.340 [6696.4016] dovSphereLogin: server IP-SYS-SM-02M already logged in
10:26:40.402 [6696.4016] UNLOCK fd = 280
10:26:40.402 [6696.4016] removing D:\Program Files\Veritas\NetBackup\ip-sys-02m.spfcubicon2.spf.gov.sg_lock
10:26:40.402 [6696.4016] generateServerFiles: release_id_lock returned 0
10:26:40.402 [6696.4016] vmwareLogger: Logout: returned 0

10:26:40.434 [6696.4016] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:40.434 [6696.4016] find_virtual_server_cred: Looking for: IP-SYS-SM-02M
10:26:40.434 [6696.4016] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50124 TO 172.24.79.27.13720 fd = 1600
10:26:41.494 [6696.4016] checkBackupRegEntry: looking registry for excludeVMservers
10:26:41.494 [6696.4016]  RegOpenKey failed with 2
10:26:41.494 [6696.4016] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:26:41.494 [6696.4016] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:41.494 [6696.4016] get_id_lock: wait lock timeout value: 30 minutes, 10 interval seconds
10:26:41.494 [6696.4016] fis_lock: entering ...
10:26:41.494 [6696.4016] rw_lock: leaving ...
10:26:41.494 [6696.4016] WRITE LOCK ip-sys-02m.spfcubicon2.spf.gov.sg_lock fd = 1600
10:26:41.494 [6696.4016] <2> browseResourcePools: get_id_lock returned 0
10:26:41.494 [6696.4016] <2> browseResourcePools: fileCOPY of D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\IP-SYS-SM-02M_resp_ip-sys-02m.spfcubicon2.spf.gov.sg.xml to C:\Windows\TEMP\VMutl-06696449196000434016000000002-a04016 returned 0
10:26:41.494 [6696.4016] UNLOCK fd = 1600
10:26:41.494 [6696.4016] removing D:\Program Files\Veritas\NetBackup\ip-sys-02m.spfcubicon2.spf.gov.sg_lock
10:26:41.494 [6696.4016] <2> browseResourcePools: release_id_lock returned 0
10:26:41.494 [6696.4016] <2> bpVMutil main: EXIT STATUS 0: the requested operation was successfully completed
10:26:45.316 [5476.7280] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpVMutil.exe 3072 ip-sys-02m.spfcubicon2.spf.gov.sg IP-SYS-SM-02M 
10:26:45.316 [5476.7280] virt_test_log: log function is called
10:26:45.316 [5476.7280] <2> bpVMutil main: cmd = 12 ESXMount = ip-sys-02m.spfcubicon2.spf.gov.sg ESXserver = IP-SYS-SM-02M, flags = 0
10:26:45.316 [5476.7280] get_id_lock: wait lock timeout value: 15 minutes, 5 interval seconds
10:26:45.316 [5476.7280] fis_lock: entering ...
10:26:45.316 [5476.7280] rw_lock: leaving ...
10:26:45.316 [5476.7280] WRITE LOCK ip-sys-02m.spfcubicon2.spf.gov.sg_lock fd = 280
10:26:45.316 [5476.7280] generateServerFiles: get_id_lock returned 0
10:26:45.316 [5476.7280] generateServerFiles: using file D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\IP-SYS-SM-02M_resp_ip-sys-02m.spfcubicon2.spf.gov.sg.xml
10:26:45.316 [5476.7280] find_virtual_server_cred: Looking for: IP-SYS-SM-02M
10:26:45.316 [5476.7280] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: SPFCUBICON2
10:26:45.316 [5476.7280] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:26:45.316 [5476.7280] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: S1-SYS-MA-01
10:26:45.316 [5476.7280] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:26:45.316 [5476.7280] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.384: AZ Service Host: s1-sys-ma-01m
10:26:45.316 [5476.7280] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.385: AZ Service Port: 0 0 0x00000000
10:26:45.316 [5476.7280] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50136 TO 172.24.79.27.13720 fd = 488
10:26:46.284 [5476.7280] checkBackupRegEntry: looking registry for excludeVMservers
10:26:46.284 [5476.7280]  RegOpenKey failed with 2
10:26:46.284 [5476.7280] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:26:46.284 [5476.7280] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:46.299 [5476.7280] UNLOCK fd = 280
10:26:46.299 [5476.7280] removing D:\Program Files\Veritas\NetBackup\ip-sys-02m.spfcubicon2.spf.gov.sg_lock
10:26:46.299 [5476.7280] generateServerFiles: release_id_lock returned 0
10:26:46.299 [5476.7280] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:46.299 [5476.7280] find_virtual_server_cred: Looking for: IP-SYS-SM-02M
10:26:46.299 [5476.7280] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50142 TO 172.24.79.27.13720 fd = 488
10:26:47.173 [5476.7280] checkBackupRegEntry: looking registry for excludeVMservers
10:26:47.173 [5476.7280]  RegOpenKey failed with 2
10:26:47.173 [5476.7280] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:26:47.173 [5476.7280] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:47.173 [5476.7280] get_id_lock: wait lock timeout value: 30 minutes, 10 interval seconds
10:26:47.173 [5476.7280] fis_lock: entering ...
10:26:47.173 [5476.7280] rw_lock: leaving ...
10:26:47.173 [5476.7280] WRITE LOCK ip-sys-02m.spfcubicon2.spf.gov.sg_lock fd = 488
10:26:47.173 [5476.7280] <2> browseDatastores: get_id_lock returned 0
10:26:47.173 [5476.7280] <2> browseDatastores: fileCOPY of D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\IP-SYS-SM-02M_datas_ip-sys-02m.spfcubicon2.spf.gov.sg.xml to C:\Windows\TEMP\VMutl-05476449196006299280000000001-a07280 returned 0
10:26:47.173 [5476.7280] UNLOCK fd = 488
10:26:47.173 [5476.7280] removing D:\Program Files\Veritas\NetBackup\ip-sys-02m.spfcubicon2.spf.gov.sg_lock
10:26:47.173 [5476.7280] <2> browseDatastores: release_id_lock returned 0
10:26:47.173 [5476.7280] <2> bpVMutil main: EXIT STATUS 0: the requested operation was successfully completed
10:26:52.383 [6944.3724] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpVMutil.exe 4864 /SYS Zone IP-SYS-SM-02M 
10:26:52.383 [6944.3724] virt_test_log: log function is called
10:26:52.383 [6944.3724] <2> bpVMutil main: cmd = 19 ESXMount = /SYS Zone ESXserver = IP-SYS-SM-02M, flags = 0
10:26:52.383 [6944.3724] find_virtual_server_cred: Looking for: IP-SYS-SM-02M
10:26:52.383 [6944.3724] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: SPFCUBICON2
10:26:52.383 [6944.3724] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:26:52.383 [6944.3724] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: S1-SYS-MA-01
10:26:52.383 [6944.3724] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:26:52.383 [6944.3724] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.384: AZ Service Host: s1-sys-ma-01m
10:26:52.383 [6944.3724] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.385: AZ Service Port: 0 0 0x00000000
10:26:52.399 [6944.3724] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50157 TO 172.24.79.27.13720 fd = 484
10:26:53.460 [6944.3724] checkBackupRegEntry: looking registry for excludeVMservers
10:26:53.460 [6944.3724]  RegOpenKey failed with 2
10:26:53.460 [6944.3724] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:26:53.460 [6944.3724] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:53.460 [6944.3724] getVmFolderTree: Getting VM Folder tree for IP-SYS-SM-02M
10:26:53.460 [6944.3724] checkBackupRegEntry: looking registry for connecttimeout
10:26:53.460 [6944.3724]  RegOpenKey failed with 2
10:26:53.460 [6944.3724] vSphereConnect: ConnectTimeout: 300 secs
10:26:53.460 [6944.3724] vSphereConnect: connecting to https://IP-SYS-SM-02M/sdk
10:26:53.460 [6944.3724] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:54.209 [6944.3724] checkBackupRegEntry: looking registry for jobtimeout
10:26:54.240 [6944.3724]  RegOpenKey failed with 2
10:26:54.240 [6944.3724] checkBackupRegEntry: looking registry for poweroptimeout
10:26:54.240 [6944.3724]  RegOpenKey failed with 2
10:26:54.240 [6944.3724] checkBackupRegEntry: looking registry for snapshottimeout
10:26:54.240 [6944.3724]  RegOpenKey failed with 2
10:26:54.240 [6944.3724] checkBackupRegEntry: looking registry for registertimeout
10:26:54.240 [6944.3724]  RegOpenKey failed with 2
10:26:54.240 [6944.3724] checkBackupRegEntry: looking registry for browsetimeout
10:26:54.240 [6944.3724]  RegOpenKey failed with 2
10:26:54.240 [6944.3724] SetTimeoutsAndLogging: JobTimeout: 900 secs
10:26:54.240 [6944.3724] SetTimeoutsAndLogging: PowerOpTimeout: 900 secs
10:26:54.240 [6944.3724] SetTimeoutsAndLogging: SnapshotTimeout: 900 secs
10:26:54.240 [6944.3724] SetTimeoutsAndLogging: RegisterTimeout: 180 secs
10:26:54.240 [6944.3724] SetTimeoutsAndLogging: BrowseTimeout: 180 secs
10:26:54.240 [6944.3724] checkBackupRegEntry: looking registry for vmcloglevel
10:26:54.240 [6944.3724]  RegOpenKey failed with 2
10:26:54.240 [6944.3724] SetTimeoutsAndLogging: vmcloglevel=0
10:26:54.287 [6944.3724] vmwareLogger: Login: key            = 52c24581-5f48-5c7e-2dfa-54164031dccc

10:26:54.287 [6944.3724] dovSphereLogin: login for IP-SYS-SM-02M completed with flags 70 and status 0
10:26:54.287 [6944.3724] vmwareLogger: BuildDatabases: Building databases

10:26:55.285 [6944.3724] vmwareLogger: Logout: returned 0

10:26:55.316 [6944.3724] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:26:55.316 [6944.3724] <2> bpVMutil main: EXIT STATUS 0: the requested operation was successfully completed
10:27:09.606 [6304.7312] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpVMutil.exe 3328 <VirtualMachine><Name>S1-APP-SM-01-testrestore</Name></VirtualMachine>

 IP-SYS-SM-02M 
10:27:09.606 [6304.7312] virt_test_log: log function is called
10:27:09.606 [6304.7312] <2> bpVMutil main: cmd = 13 ESXMount = <VirtualMachine><Name>S1-APP-SM-01-testrestore</Name></VirtualMachine>

 ESXserver = IP-SYS-SM-02M, flags = 0
10:27:09.606 [6304.7312] find_virtual_server_cred: Looking for: IP-SYS-SM-02M
10:27:09.622 [6304.7312] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: SPFCUBICON2
10:27:09.622 [6304.7312] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:27:09.622 [6304.7312] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: S1-SYS-MA-01
10:27:09.622 [6304.7312] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:27:09.622 [6304.7312] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.384: AZ Service Host: s1-sys-ma-01m
10:27:09.622 [6304.7312] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.385: AZ Service Port: 0 0 0x00000000
10:27:09.622 [6304.7312] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50200 TO 172.24.79.27.13720 fd = 480
10:27:10.464 [6304.7312] checkBackupRegEntry: looking registry for excludeVMservers
10:27:10.464 [6304.7312]  RegOpenKey failed with 2
10:27:10.464 [6304.7312] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:27:10.464 [6304.7312] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:27:10.464 [6304.7312] listMatchingVM: Listing matching VMs on IP-SYS-SM-02M
10:27:10.464 [6304.7312] find_vm_by_displayname: Looking for S1-APP-SM-01-testrestore
10:27:10.464 [6304.7312] checkBackupRegEntry: looking registry for connecttimeout
10:27:10.464 [6304.7312]  RegOpenKey failed with 2
10:27:10.464 [6304.7312] vSphereConnect: ConnectTimeout: 300 secs
10:27:10.464 [6304.7312] vSphereConnect: connecting to https://IP-SYS-SM-02M/sdk
10:27:10.464 [6304.7312] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:27:11.416 [6304.7312] checkBackupRegEntry: looking registry for jobtimeout
10:27:11.416 [6304.7312]  RegOpenKey failed with 2
10:27:11.416 [6304.7312] checkBackupRegEntry: looking registry for poweroptimeout
10:27:11.416 [6304.7312]  RegOpenKey failed with 2
10:27:11.416 [6304.7312] checkBackupRegEntry: looking registry for snapshottimeout
10:27:11.416 [6304.7312]  RegOpenKey failed with 2
10:27:11.416 [6304.7312] checkBackupRegEntry: looking registry for registertimeout
10:27:11.416 [6304.7312]  RegOpenKey failed with 2
10:27:11.416 [6304.7312] checkBackupRegEntry: looking registry for browsetimeout
10:27:11.416 [6304.7312]  RegOpenKey failed with 2
10:27:11.416 [6304.7312] SetTimeoutsAndLogging: JobTimeout: 900 secs
10:27:11.416 [6304.7312] SetTimeoutsAndLogging: PowerOpTimeout: 900 secs
10:27:11.416 [6304.7312] SetTimeoutsAndLogging: SnapshotTimeout: 900 secs
10:27:11.416 [6304.7312] SetTimeoutsAndLogging: RegisterTimeout: 180 secs
10:27:11.416 [6304.7312] SetTimeoutsAndLogging: BrowseTimeout: 180 secs
10:27:11.416 [6304.7312] checkBackupRegEntry: looking registry for vmcloglevel
10:27:11.416 [6304.7312]  RegOpenKey failed with 2
10:27:11.416 [6304.7312] SetTimeoutsAndLogging: vmcloglevel=0
10:27:11.478 [6304.7312] vmwareLogger: Login: key            = 52dd298e-ba73-5b1f-95e9-424e21f04a72

10:27:11.478 [6304.7312] dovSphereLogin: login for IP-SYS-SM-02M completed with flags 70 and status 0
10:27:11.478 [6304.7312] vmwareLogger: BuildDatabases: Building databases

10:27:12.180 [6304.7312] vmwareLogger: RetrieveDcMorAndObjMorFromPath: SYM_VMC_ERROR:  FAILED_TO_GET_MOB

10:27:12.180 [6304.7312] vmwareLogger: GetObjDetails: SYM_VMC_ERROR:  FAILED_TO_GET_MOB

10:27:12.180 [6304.7312] find_vm_by_displayname: vmcGetObjDetails failed with status 18
10:27:12.180 [6304.7312] <2> bpVMutil main: EXIT STATUS 227: no entity was found
10:27:25.955 [4756.2464] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpVMutil.exe 4352 ip-sys-02m.spfcubicon2.spf.gov.sg IP-SYS-SM-02M 
10:27:25.955 [4756.2464] virt_test_log: log function is called
10:27:25.955 [4756.2464] <2> bpVMutil main: cmd = 17 ESXMount = ip-sys-02m.spfcubicon2.spf.gov.sg ESXserver = IP-SYS-SM-02M, flags = 0
10:27:25.955 [4756.2464] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: SPFCUBICON2
10:27:25.955 [4756.2464] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:27:25.955 [4756.2464] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: S1-SYS-MA-01
10:27:25.955 [4756.2464] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:27:25.955 [4756.2464] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.384: AZ Service Host: s1-sys-ma-01m
10:27:25.955 [4756.2464] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.385: AZ Service Port: 0 0 0x00000000
10:27:25.971 [4756.2464] get_fqdn_hostname: fqdn host ip-sys-02m.spfcubicon2.spf.gov.sg
10:27:25.971 [4756.2464] get_id_lock: wait lock timeout value: 15 minutes, 5 interval seconds
10:27:25.971 [4756.2464] fis_lock: entering ...
10:27:25.971 [4756.2464] rw_lock: leaving ...
10:27:25.971 [4756.2464] WRITE LOCK ip-sys-02m.spfcubicon2.spf.gov.sg_lock fd = 476
10:27:25.971 [4756.2464] generateServerFiles: get_id_lock returned 0
10:27:25.971 [4756.2464] generateServerFiles: using file D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\IP-SYS-SM-02M_resp_ip-sys-02m.spfcubicon2.spf.gov.sg.xml
10:27:25.971 [4756.2464] find_virtual_server_cred: Looking for: IP-SYS-SM-02M
10:27:25.971 [4756.2464] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50231 TO 172.24.79.27.13720 fd = 488
10:27:26.954 [4756.2464] checkBackupRegEntry: looking registry for excludeVMservers
10:27:26.954 [4756.2464]  RegOpenKey failed with 2
10:27:26.954 [4756.2464] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:27:26.954 [4756.2464] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:27:26.954 [4756.2464] UNLOCK fd = 476
10:27:26.954 [4756.2464] removing D:\Program Files\Veritas\NetBackup\ip-sys-02m.spfcubicon2.spf.gov.sg_lock
10:27:26.954 [4756.2464] generateServerFiles: release_id_lock returned 0
10:27:26.954 [4756.2464] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:27:26.954 [4756.2464] find_virtual_server_cred: Looking for: IP-SYS-SM-02M
10:27:26.954 [4756.2464] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50238 TO 172.24.79.27.13720 fd = 488
10:27:28.014 [4756.2464] checkBackupRegEntry: looking registry for excludeVMservers
10:27:28.014 [4756.2464]  RegOpenKey failed with 2
10:27:28.014 [4756.2464] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:27:28.014 [4756.2464] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:27:28.014 [4756.2464] get_id_lock: wait lock timeout value: 30 minutes, 10 interval seconds
10:27:28.014 [4756.2464] fis_lock: entering ...
10:27:28.014 [4756.2464] rw_lock: leaving ...
10:27:28.014 [4756.2464] WRITE LOCK ip-sys-02m.spfcubicon2.spf.gov.sg_lock fd = 488
10:27:28.014 [4756.2464] <2> browseNetworks: get_id_lock returned 0
10:27:28.014 [4756.2464] <2> browseNetworks: fileCOPY of D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\IP-SYS-SM-02M_net_ip-sys-02m.spfcubicon2.spf.gov.sg.xml to C:\Windows\TEMP\VMutl-04756449196046954464000000001-a02464 returned 0
10:27:28.014 [4756.2464] UNLOCK fd = 488
10:27:28.014 [4756.2464] removing D:\Program Files\Veritas\NetBackup\ip-sys-02m.spfcubicon2.spf.gov.sg_lock
10:27:28.014 [4756.2464] <2> browseNetworks: release_id_lock returned 0
10:27:28.014 [4756.2464] <2> bpVMutil main: EXIT STATUS 0: the requested operation was successfully completed
10:27:35.737 [5756.7232] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpVMutil.exe 1024 C:\Windows\TEMP\VMval-04652449196055690084000000001-a07084 IP-SYS-SM-02M 
10:27:35.737 [5756.7232] virt_test_log: log function is called
10:27:35.737 [5756.7232] <2> bpVMutil main: cmd = 4 ESXMount = C:\Windows\TEMP\VMval-04652449196055690084000000001-a07084 ESXserver = IP-SYS-SM-02M, flags = 0
10:27:35.737 [5756.7232] <2> dumpXMLFile: <?xml version="1.0" encoding="UTF-8"?>
<VMRestoreConfiguration><VMRestoreConfigurationElement Keyword="VmRestoreType" V
10:27:35.737 [5756.7232] <2> dumpXMLFile: alue="vStorage"/><VMRestoreConfigurationElement Keyword="VmDisplayName" Value="S1-APP-SM-01-testrestore"/><VMRestoreCon
10:27:35.737 [5756.7232] <2> dumpXMLFile: figurationElement Keyword="VmUuid" Value="42118027-a998-7f3d-b607-36227a119eeb"/><VMRestoreConfigurationElement Keyword
10:27:35.737 [5756.7232] <2> dumpXMLFile: ="VmHost" Value="ip-sys-02m.spfcubicon2.spf.gov.sg"/><VMRestoreConfigurationElement Keyword="Datacenter" Value="/SYS Zo
10:27:35.737 [5756.7232] <2> dumpXMLFile: ne"/><VMRestoreConfigurationElement Keyword="Folder" Value="/(DC)SYS Zone(DC)/vm"/><VMRestoreConfigurationElement Keywo
10:27:35.737 [5756.7232] <2> dumpXMLFile: rd="Datastore" Value="IP-SYS-02-DS3"/><VMRestoreConfigurationElement Keyword="ResourcePool" Value="/SYS Zone/host/ip-sy
10:27:35.737 [5756.7232] <2> dumpXMLFile: s-02m.spfcubicon2.spf.gov.sg/Resources"/><VMRestoreConfigurationElement Keyword="VmOverwrite" Value="false"/><VMRestore
10:27:35.737 [5756.7232] <2> dumpXMLFile: ConfigurationElement Keyword="VmIdentityRestore" Value="false"/><Datastore Name="IP-SYS-02-DS3" RequiredMbytes="660480"
10:27:35.737 [5756.7232] <2> dumpXMLFile: /></VMRestoreConfiguration>

10:27:35.737 [5756.7232] vm_test_restore: ESXhost = ip-sys-02m.spfcubicon2.spf.gov.sg.
10:27:35.737 [5756.7232] find_virtual_server_cred: Looking for: IP-SYS-SM-02M
10:27:35.737 [5756.7232] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: SPFCUBICON2
10:27:35.737 [5756.7232] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:27:35.737 [5756.7232] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: S1-SYS-MA-01
10:27:35.737 [5756.7232] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:27:35.752 [5756.7232] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.384: AZ Service Host: s1-sys-ma-01m
10:27:35.752 [5756.7232] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.385: AZ Service Port: 0 0 0x00000000
10:27:35.752 [5756.7232] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50257 TO 172.24.79.27.13720 fd = 548
10:27:36.719 [5756.7232] checkBackupRegEntry: looking registry for excludeVMservers
10:27:36.719 [5756.7232]  RegOpenKey failed with 2
10:27:36.719 [5756.7232] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:27:36.719 [5756.7232] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:27:36.719 [5756.7232] getDatastoresList: Creating List of datastores on IP-SYS-SM-02M for ip-sys-02m.spfcubicon2.spf.gov.sg
10:27:36.719 [5756.7232] checkBackupRegEntry: looking registry for connecttimeout
10:27:36.719 [5756.7232]  RegOpenKey failed with 2
10:27:36.719 [5756.7232] vSphereConnect: ConnectTimeout: 300 secs
10:27:36.719 [5756.7232] vSphereConnect: connecting to https://IP-SYS-SM-02M/sdk
10:27:36.719 [5756.7232] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:27:37.624 [5756.7232] checkBackupRegEntry: looking registry for jobtimeout
10:27:37.624 [5756.7232]  RegOpenKey failed with 2
10:27:37.624 [5756.7232] checkBackupRegEntry: looking registry for poweroptimeout
10:27:37.624 [5756.7232]  RegOpenKey failed with 2
10:27:37.624 [5756.7232] checkBackupRegEntry: looking registry for snapshottimeout
10:27:37.624 [5756.7232]  RegOpenKey failed with 2
10:27:37.624 [5756.7232] checkBackupRegEntry: looking registry for registertimeout
10:27:37.624 [5756.7232]  RegOpenKey failed with 2
10:27:37.624 [5756.7232] checkBackupRegEntry: looking registry for browsetimeout
10:27:37.624 [5756.7232]  RegOpenKey failed with 2
10:27:37.624 [5756.7232] SetTimeoutsAndLogging: JobTimeout: 900 secs
10:27:37.624 [5756.7232] SetTimeoutsAndLogging: PowerOpTimeout: 900 secs
10:27:37.624 [5756.7232] SetTimeoutsAndLogging: SnapshotTimeout: 900 secs
10:27:37.624 [5756.7232] SetTimeoutsAndLogging: RegisterTimeout: 180 secs
10:27:37.624 [5756.7232] SetTimeoutsAndLogging: BrowseTimeout: 180 secs
10:27:37.624 [5756.7232] checkBackupRegEntry: looking registry for vmcloglevel
10:27:37.624 [5756.7232]  RegOpenKey failed with 2
10:27:37.624 [5756.7232] SetTimeoutsAndLogging: vmcloglevel=0
10:27:37.671 [5756.7232] vmwareLogger: Login: key            = 525c0dbf-10c7-6f8c-4a11-b429d6b339b9

10:27:37.671 [5756.7232] dovSphereLogin: login for IP-SYS-SM-02M completed with flags 70 and status 0
10:27:37.718 [5756.7232] vmwareLogger: BuildDatabases: Building databases

10:27:38.420 [5756.7232] vmwareLogger: BuildDatabases: Building databases

10:27:38.966 [5756.7232] vmwareLogger: WaitForTaskCompleteEx: reached Error!

10:27:38.966 [5756.7232] vmwareLogger: WaitForTaskCompleteEx: File [IP-SYS-02-DS3]S1-APP-SM-01-testrestore was not found <183>

10:27:38.966 [5756.7232] vmwareLogger: WaitForTaskCompleteEx: SYM_VMC_ERROR:  TASK_REACHED_ERROR_STATE

10:27:38.966 [5756.7232] validateOverwrite: Validate datastore path - host = ip-sys-02m.spfcubicon2.spf.gov.sg, dcpath = [IP-SYS-02-DS3]S1-APP-SM-01-testrestore.
10:27:39.512 [5756.7232] vmwareLogger: WaitForTaskCompleteEx: reached Error!

10:27:39.512 [5756.7232] vmwareLogger: WaitForTaskCompleteEx: File [IP-SYS-02-DS3]S1-APP-SM-01-testrestore was not found <183>

10:27:39.512 [5756.7232] vmwareLogger: WaitForTaskCompleteEx: SYM_VMC_ERROR:  TASK_REACHED_ERROR_STATE

10:27:39.512 [5756.7232] validateOverwrite: Validate datastore path - host = ip-sys-02m.spfcubicon2.spf.gov.sg, dcpath = [IP-SYS-02-DS3]S1-APP-SM-01-testrestore.
10:27:39.512 [5756.7232] validateOverwrite: bVmXdirExists = 0.
10:27:39.512 [5756.7232] find_vm_by_displayname: Looking for S1-APP-SM-01-testrestore
10:27:39.512 [5756.7232] dovSphereLogin: server IP-SYS-SM-02M already logged in
10:27:39.512 [5756.7232] vmwareLogger: RetrieveDcMorAndObjMorFromPath: SYM_VMC_ERROR:  FAILED_TO_GET_MOB

10:27:39.512 [5756.7232] vmwareLogger: GetObjDetails: SYM_VMC_ERROR:  FAILED_TO_GET_MOB

10:27:39.512 [5756.7232] find_vm_by_displayname: vmcGetObjDetails failed with status 18
10:27:39.512 [5756.7232] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6669: 0: fopen() failed: 2 0x00000002
10:27:39.512 [5756.7232] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6670: 0: fopen() failed: D:\Program Files\Veritas\NetBackup\var\host_cache\179\c1be1579+0,1,2,0,0,0+ip-sys-sm-02m.txt
10:27:39.512 [5756.7232] checkBackupRegEntry: looking registry for connecttimeout
10:27:39.512 [5756.7232]  RegOpenKey failed with 2
10:27:39.512 [5756.7232] vSphereConnect: ConnectTimeout: 300 secs
10:27:39.512 [5756.7232] vSphereConnect: connecting to https://IP-SYS-SM-02M/sdk
10:27:39.512 [5756.7232] vmwareLogger: Logout: returned 0

10:27:39.559 [5756.7232] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:27:39.574 [5756.7232] checkBackupRegEntry: looking registry for jobtimeout
10:27:39.574 [5756.7232]  RegOpenKey failed with 2
10:27:39.574 [5756.7232] checkBackupRegEntry: looking registry for poweroptimeout
10:27:39.574 [5756.7232]  RegOpenKey failed with 2
10:27:39.574 [5756.7232] checkBackupRegEntry: looking registry for snapshottimeout
10:27:39.574 [5756.7232]  RegOpenKey failed with 2
10:27:39.574 [5756.7232] checkBackupRegEntry: looking registry for registertimeout
10:27:39.574 [5756.7232]  RegOpenKey failed with 2
10:27:39.574 [5756.7232] checkBackupRegEntry: looking registry for browsetimeout
10:27:39.574 [5756.7232]  RegOpenKey failed with 2
10:27:39.574 [5756.7232] SetTimeoutsAndLogging: JobTimeout: 900 secs
10:27:39.574 [5756.7232] SetTimeoutsAndLogging: PowerOpTimeout: 900 secs
10:27:39.574 [5756.7232] SetTimeoutsAndLogging: SnapshotTimeout: 900 secs
10:27:39.574 [5756.7232] SetTimeoutsAndLogging: RegisterTimeout: 180 secs
10:27:39.574 [5756.7232] SetTimeoutsAndLogging: BrowseTimeout: 180 secs
10:27:39.574 [5756.7232] checkBackupRegEntry: looking registry for vmcloglevel
10:27:39.574 [5756.7232]  RegOpenKey failed with 2
10:27:39.574 [5756.7232] SetTimeoutsAndLogging: vmcloglevel=0
10:27:39.637 [5756.7232] vmwareLogger: Login: key            = 52771ecf-90a3-4557-b872-a6447f643c9b

10:27:39.637 [5756.7232] vmwareLogger: Logout: returned 0

10:27:39.637 [5756.7232] <2> cmn_test_VM_cred: Verified credentials with VIX API
10:27:39.668 [5756.7232] vm_test_restore: Done Saved XML to C:\Windows\TEMP\VMutl-05756449196055737232000000000-a07232
10:27:39.668 [5756.7232] <2> bpVMutil main: EXIT STATUS 0: the requested operation was successfully completed
10:33:17.165 [7832.7192] <2> logparams: D:\Program Files\Veritas\NetBackup\bin\bpVMutil.exe 1728 4:ip-sys-04m.spfcubicon2.spf.gov.sg:42118027-a998-7f3d-b607-36227a119eeb:S1-APP-SM-01:S1-APP-SM-01_1449130802 IP-SYS-SM-02M 
10:33:17.165 [7832.7192] virt_test_log: log function is called
10:33:17.165 [7832.7192] <2> bpVMutil main: cmd = 6 ESXMount = 4:ip-sys-04m.spfcubicon2.spf.gov.sg:42118027-a998-7f3d-b607-36227a119eeb:S1-APP-SM-01:S1-APP-SM-01_1449130802 ESXserver = IP-SYS-SM-02M, flags = 192
10:33:17.165 [7832.7192] <2> bpVMutil main: VMuuid 42118027-a998-7f3d-b607-36227a119eeb VMesxhost ip-sys-04m.spfcubicon2.spf.gov.sg VMDisplayName S1-APP-SM-01 VMBackupID S1-APP-SM-01_1449130802 transport mode 4
10:33:17.165 [7832.7192] <2> bpVMutil main: XML file is D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\VMXml_S1-APP-SM-01_1449130802.xml
10:33:17.165 [7832.7192] <2> bpVMutil main: Vix file is D:\Program Files\Veritas\NetBackup\online_util\fi_cntl\VMVix_S1-APP-SM-01_1449130802.xml
10:33:17.165 [7832.7192] <2> bpVMutil main: Reading XML...
10:33:17.165 [7832.7192] <2> bpVMutil main: XML read 27002 bytes.
10:33:17.165 [7832.7192] <2> bpVMutil main: renameString read 262 bytes.
10:33:17.165 [7832.7192] <2> bpVMutil main: renameString esxhost=ip-sys-02m.spfcubicon2.spf.gov.sg;datacenter=/SYS Zone;datastore=IP-SYS-02-DS3;vmname=S1-APP-SM-01-testrestore;resourcepool=/SYS Zone/host/ip-sys-02m.spfcubicon2.spf.gov.sg/Resources;folder=/(DC)SYS Zone(DC)/vm;diskdatastores=IP-SYS-02-DS3/IP-SYS-02-DS3
10:33:17.165 [7832.7192] find_virtual_server_cred: Looking for: IP-SYS-SM-02M
10:33:17.165 [7832.7192] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: SPFCUBICON2
10:33:17.165 [7832.7192] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:33:17.165 [7832.7192] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.261: AT Domain Name: S1-SYS-MA-01
10:33:17.165 [7832.7192] <2> nbconf_create_authentication_domain_rec: ../../libvlibs/nbconf_private.c.262: AT Domain Type: 4 4 0x00000004
10:33:17.165 [7832.7192] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.384: AZ Service Host: s1-sys-ma-01m
10:33:17.165 [7832.7192] <2> nbconf_create_authorization_service_rec: ../../libvlibs/nbconf_private.c.385: AZ Service Port: 0 0 0x00000000
10:33:17.180 [7832.7192] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50617 TO 172.24.79.27.13720 fd = 484
10:33:18.101 [7832.7192] checkBackupRegEntry: looking registry for excludeVMservers
10:33:18.101 [7832.7192]  RegOpenKey failed with 2
10:33:18.101 [7832.7192] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:33:18.101 [7832.7192] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:33:18.101 [7832.7192] find_vm_by_displayname: Looking for S1-APP-SM-01-testrestore
10:33:18.101 [7832.7192] checkBackupRegEntry: looking registry for connecttimeout
10:33:18.101 [7832.7192]  RegOpenKey failed with 2
10:33:18.101 [7832.7192] vSphereConnect: ConnectTimeout: 300 secs
10:33:18.101 [7832.7192] vSphereConnect: connecting to https://IP-SYS-SM-02M/sdk
10:33:18.101 [7832.7192] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:33:18.662 [7832.7192] checkBackupRegEntry: looking registry for jobtimeout
10:33:18.662 [7832.7192]  RegOpenKey failed with 2
10:33:18.662 [7832.7192] checkBackupRegEntry: looking registry for poweroptimeout
10:33:18.662 [7832.7192]  RegOpenKey failed with 2
10:33:18.662 [7832.7192] checkBackupRegEntry: looking registry for snapshottimeout
10:33:18.662 [7832.7192]  RegOpenKey failed with 2
10:33:18.662 [7832.7192] checkBackupRegEntry: looking registry for registertimeout
10:33:18.662 [7832.7192]  RegOpenKey failed with 2
10:33:18.662 [7832.7192] checkBackupRegEntry: looking registry for browsetimeout
10:33:18.662 [7832.7192]  RegOpenKey failed with 2
10:33:18.662 [7832.7192] SetTimeoutsAndLogging: JobTimeout: 900 secs
10:33:18.662 [7832.7192] SetTimeoutsAndLogging: PowerOpTimeout: 900 secs
10:33:18.662 [7832.7192] SetTimeoutsAndLogging: SnapshotTimeout: 900 secs
10:33:18.662 [7832.7192] SetTimeoutsAndLogging: RegisterTimeout: 180 secs
10:33:18.662 [7832.7192] SetTimeoutsAndLogging: BrowseTimeout: 180 secs
10:33:18.662 [7832.7192] checkBackupRegEntry: looking registry for vmcloglevel
10:33:18.662 [7832.7192]  RegOpenKey failed with 2
10:33:18.662 [7832.7192] SetTimeoutsAndLogging: vmcloglevel=0
10:33:18.725 [7832.7192] vmwareLogger: Login: key            = 520a599d-cc06-2001-2e6c-34471d46e890

10:33:18.725 [7832.7192] dovSphereLogin: login for IP-SYS-SM-02M completed with flags 70 and status 0
10:33:18.725 [7832.7192] vmwareLogger: BuildDatabases: Building databases

10:33:19.474 [7832.7192] vmwareLogger: RetrieveDcMorAndObjMorFromPath: SYM_VMC_ERROR:  FAILED_TO_GET_MOB

10:33:19.474 [7832.7192] vmwareLogger: GetObjDetails: SYM_VMC_ERROR:  FAILED_TO_GET_MOB

10:33:19.474 [7832.7192] find_vm_by_displayname: vmcGetObjDetails failed with status 18
10:33:19.474 [7832.7192] <2> find_vm_by_displayname returned 18: bpVMutil
10:33:19.474 [7832.7192] checkBackupRegEntry: looking registry for addRestoreDisks
10:33:19.474 [7832.7192]  RegOpenKey failed with 2
10:33:19.474 [7832.7192] <2> createVMwareVM: createVMwareVM: Create thindisks requested
10:33:19.474 [7832.7192] <2> createVMwareVM: createVMwareVM: Using transport mode 4
10:33:19.474 [7832.7192] <2> createVMwareVM: createVMwareVM: Evaluating renameString esxhost=ip-sys-02m.spfcubicon2.spf.gov.sg;datacenter=/SYS Zone;datastore=IP-SYS-02-DS3;vmname=S1-APP-SM-01-testrestore;resourcepool=/SYS Zone/host/ip-sys-02m.spfcubicon2.spf.gov.sg/Resources;folder=/(DC)SYS Zone(DC)/vm;diskdatastores=IP-SYS-02-DS3/IP-SYS-02-DS3
10:33:19.474 [7832.7192] <2> createVMwareVM: unknown term in rename string datacenter=/SYS Zone
10:33:19.474 [7832.7192] <2> createVMwareVM: Adding diskDataStores 0 = IP-SYS-02-DS3
10:33:19.474 [7832.7192] <2> createVMwareVM: Adding diskDataStores 1 = IP-SYS-02-DS3
10:33:19.474 [7832.7192] find_virtual_server_cred: Looking for: IP-SYS-SM-02M
10:33:19.474 [7832.7192] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50625 TO 172.24.79.27.13720 fd = 1632
10:33:20.394 [7832.7192] checkBackupRegEntry: looking registry for excludeVMservers
10:33:20.394 [7832.7192]  RegOpenKey failed with 2
10:33:20.394 [7832.7192] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:33:20.394 [7832.7192] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:33:20.394 [7832.7192] find_virtual_server_cred: Looking for: ip-sys-02m.spfcubicon2.spf.gov.sg
10:33:20.394 [7832.7192] <2> logconnections: BPRD CONNECT FROM 172.24.79.27.50631 TO 172.24.79.27.13720 fd = 1632
10:33:21.361 [7832.7192] checkBackupRegEntry: looking registry for excludeVMservers
10:33:21.361 [7832.7192]  RegOpenKey failed with 2
10:33:21.361 [7832.7192] format_virtualserver_cred: read 1 IP-SYS-SM-02M root 328b896c7265f9df7fa43be518993659cd9fbf80d7df058671b5b43cf1e97d21bb0100c9f6456d462b4f12de00286e0307d6cde75b80e16e0bccacda9b66497a 20986 0

10:33:21.361 [7832.7192] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:33:21.361 [7832.7192] find_virtual_server_cred: Couldn't find ip-sys-02m.spfcubicon2.spf.gov.sg
10:33:21.361 [7832.7192] <2> createVMwareVM: find credential failed for ESX host ip-sys-02m.spfcubicon2.spf.gov.sg using IP-SYS-SM-02M
10:33:21.361 [7832.7192] checkBackupRegEntry: looking registry for connecttimeout
10:33:21.361 [7832.7192]  RegOpenKey failed with 2
10:33:21.361 [7832.7192] vSphereConnect: ConnectTimeout: 300 secs
10:33:21.361 [7832.7192] vSphereConnect: connecting to https://IP-SYS-SM-02M/sdk
10:33:21.361 [7832.7192] dovSphereDisconnect: disconnect for IP-SYS-SM-02M completed with flags 0 and status 0
10:33:21.361 [7832.7192] checkBackupRegEntry: looking registry for jobtimeout
10:33:21.361 [7832.7192]  RegOpenKey failed with 2
10:33:21.361 [7832.7192] checkBackupRegEntry: looking registry for poweroptimeout
10:33:21.361 [7832.7192]  RegOpenKey failed with 2
10:33:21.361 [7832.7192] checkBackupRegEntry: looking registry for snapshottimeout
10:33:21.361 [7832.7192]  RegOpenKey failed with 2
10:33:21.361 [7832.7192] checkBackupRegEntry: looking registry for registertimeout
10:33:21.361 [7832.7192]  RegOpenKey failed with 2
10:33:21.361 [7832.7192] checkBackupRegEntry: looking registry for browsetimeout
10:33:21.361 [7832.7192]  RegOpenKey failed with 2
10:33:21.361 [7832.7192] SetTimeoutsAndLogging: JobTimeout: 900 secs
10:33:21.361 [7832.7192] SetTimeoutsAndLogging: PowerOpTimeout: 900 secs
10:33:21.361 [7832.7192] SetTimeoutsAndLogging: SnapshotTimeout: 900 secs
10:33:21.361 [7832.7192] SetTimeoutsAndLogging: RegisterTimeout: 180 secs
10:33:21.361 [7832.7192] SetTimeoutsAndLogging: BrowseTimeout: 180 secs
10:33:21.361 [7832.7192] checkBackupRegEntry: looking registry for vmcloglevel
10:33:21.361 [7832.7192]  RegOpenKey failed with 2
10:33:21.361 [7832.7192] SetTimeoutsAndLogging: vmcloglevel=0
10:33:21.424 [7832.7192] vmwareLogger: Login: key            = 52ac174c-a630-402b-54ed-2980bf08ad2f

10:33:21.424 [7832.7192] dovSphereLogin: login for IP-SYS-SM-02M completed with flags 70 and status 0
10:33:21.424 [7832.7192] <2> createVMwareVM: vmcCreateVirtualMachineEx arguments:
10:33:21.424 [7832.7192] <2> createVMwareVM:     Client = IP-SYS-SM-02M
10:33:21.424 [7832.7192] <2> createVMwareVM:     vmName = S1-APP-SM-01-testrestore
10:33:21.424 [7832.7192] <2> createVMwareVM:     hostName = ip-sys-02m.spfcubicon2.spf.gov.sg
10:33:21.424 [7832.7192] <2> createVMwareVM:     datacenterPath = (null)
10:33:21.424 [7832.7192] <2> createVMwareVM:     folder = /(DC)SYS Zone(DC)/vm
10:33:21.424 [7832.7192] <2> createVMwareVM:     resourcePoolPath = /SYS Zone/host/ip-sys-02m.spfcubicon2.spf.gov.sg/Resources
10:33:21.424 [7832.7192] <2> createVMwareVM:     vmDatastoreName = IP-SYS-02-DS3
10:33:21.424 [7832.7192] <2> createVMwareVM:     diskDatastores[0] = IP-SYS-02-DS3
10:33:21.424 [7832.7192] <2> createVMwareVM:     diskDatastores[1] = IP-SYS-02-DS3
10:33:21.424 [7832.7192] <2> createVMwareVM:     version = (null)
10:33:21.424 [7832.7192] <2> createVMwareVM:     bThinProvision = 1
10:33:21.424 [7832.7192] <2> createVMwareVM:     bEagerlyScrub = 0
10:33:21.424 [7832.7192] <2> createVMwareVM:     bWriteThrough = 0
10:33:21.424 [7832.7192] <2> createVMwareVM:     bKeepExistingUuid = 0
10:33:21.424 [7832.7192] <2> createVMwareVM:     bPreserveNics = 1
10:33:21.424 [7832.7192] <2> createVMwareVM:     bStripNetworks = 1
10:33:21.424 [7832.7192] <2> createVMwareVM:     bUseVmDatastoreForDisks = 0
10:33:21.424 [7832.7192] checkBackupRegEntry: looking registry for NoEagerlyScrub
10:33:21.424 [7832.7192]  RegOpenKey failed with 2
10:33:21.424 [7832.7192] <2> createVMwareVM: Setting bEagerlyScrub due to registry setting
10:33:21.424 [7832.7192] checkBackupRegEntry: looking registry for UseDiskControllerPrefix
10:33:21.424 [7832.7192]  RegOpenKey failed with 2
10:33:21.424 [7832.7192] checkBackupRegEntry: looking registry for IgnoreAttachedDevices
10:33:21.424 [7832.7192]  RegOpenKey failed with 2
10:33:21.424 [7832.7192] vmwareLogger: BuildDatabases: Building databases

10:33:22.157 [7832.7192] vmwareLogger: BuildDatabases: Building databases

10:33:22.781 [7832.7192] vmwareLogger: GetResourcePoolPath: /SYS Zone/host/ip-sys-02m.spfcubicon2.spf.gov.sg/Resources

10:33:22.781 [7832.7192] vmwareLogger: CreateVirtualMachine: DRSenabled=false, pod=Null, IsVC=true, version=51

10:33:22.781 [7832.7192] vmwareLogger: IsResourcePoolInAVapp: No

10:33:22.781 [7832.7192] vmwareLogger: CreateVm: entered

10:33:23.296 [7832.7192] vmwareLogger: WaitForTaskCompleteEx: reached Error!

10:33:23.296 [7832.7192] vmwareLogger: WaitForTaskCompleteEx: Invalid configuration for device '1'. <27>

10:33:23.296 [7832.7192] vmwareLogger: WaitForTaskCompleteEx: SYM_VMC_ERROR:  TASK_REACHED_ERROR_STATE

10:33:23.296 [7832.7192] vmwareLogger: CreateVm: SYM_VMC_ERROR:  TASK_REACHED_ERROR_STATE

10:33:23.296 [7832.7192] vmwareLogger: CreateVirtualMachine: SYM_VMC_ERROR:  TASK_REACHED_ERROR_STATE

10:33:23.296 [7832.7192] <2> createVMwareVM: Create virtual machine failed for IP-SYS-SM-02M with 36
10:33:23.296 [7832.7192] <2> bpVMutil main: Create VMware VM failed
10:33:23.296 [7832.7192] checkBackupRegEntry: looking registry for KeepVMXmlFiles
10:33:23.296 [7832.7192]  RegOpenKey failed with 2
10:33:23.296 [7832.7192] <2> bpVMutil main: EXIT STATUS 5: the restore failed to recover the requested files