未解决
1 Rookie
•
15 消息
0
680
avamar备份虚拟机,其中一台报错无法创建快照
avamar备份虚拟机,其中一台报错无法创建快照,
Summary
Log Files
Log #1:/usr/local/avamarclient/var/MOD-1667446305496-b37c2ab43917bc9ca9edeeace741fab796bcaaaf-3016-vmimagew-xmlstats.log
Log #2:avvcbimage log 2022-11-03 11:37:20 CST [19.1.100-38 Linux-x86_64] (3 errors)
Log #3:MOD-1667446305496 log 2022-11-03 11:37:20 CST [19.1.100-38 Linux-x86_64]
Errors and Exceptions
2022-11-03 11:37:40 avvcbimage FATAL <0000>: [IMG0002] The datastore information from VMX '[Vplex-Unity400-1.8-4.5T] Queueing/Queueing.vmx' will not permit a restore or backup. (Log #2)
2022-11-03 11:37:40 avvcbimage Error <0000>: [IMG0009] Create Snapshot: snapshot creation or pre/post snapshot script failed. (Log #2)
2022-11-03 11:37:40 avvcbimage Error <0000>: [IMG0009] Create Snapshot: snapshot creation/pre-script/post-script failed. (Log #2)
*********** /usr/local/avamarclient/var/MOD-1667446305496-b37c2ab43917bc9ca9edeeace741fab796bcaaaf-3016-vmimagew-xmlstats.log ***********
Log #1: /usr/local/avamarclient/var/MOD-1667446305496-b37c2ab43917bc9ca9edeeace741fab796bcaaaf-3016-vmimagew-xmlstats.log
192.168.80.53 6.7.0 17137327 avamarproxy-proxy-3 19.1.100-38 192.168.80.46 6.7.3 build-17499825
*********** /usr/local/avamarclient/var/MOD-1667446305496-b37c2ab43917bc9ca9edeeace741fab796bcaaaf-3016-vmimagew.log ***********
Log #2: avvcbimage log 2022-11-03 11:37:20 CST [19.1.100-38 Linux-x86_64]
2022-11-03 11:37:20 avvcbimage Info <5008>: Logging to /usr/local/avamarclient/var/MOD-1667446305496-b37c2ab43917bc9ca9edeeace741fab796bcaaaf-3016-vmimagew.log 2022-11-03 11:37:20 avvcbimage Info <5174>: - Reading /usr/local/avamarclient/var/avvcbimage.cmd 2022-11-03 11:37:20 avvcbimage Info <5174>: - Reading /usr/local/avamarclient/var/avvcbimageAll.cmd 2022-11-03 11:37:20 avvcbimage Info <6673>: CTL listening on port 54430 2022-11-03 11:37:20 avvcbimage Info <10684>: Setting ctl message version to 3 (from 1) 2022-11-03 11:37:20 avvcbimage Info <16136>: Setting ctl max message size to 268435456 2022-11-03 11:37:20 avvcbimage Info <40636>: SSL Certificate Thumbprint: 1F:27:81:D5:87:D6:B0:1D:AD:12:7F:9B:3B:B7:A4:CB:FD:E4:41:C4 2022-11-03 11:37:20 avvcbimage Info <42665>: setParent(): sGuestName= sProxyHostName=avamarproxy svCentername=192.168.80.53 sGuestVmName=Queueing.30.27 sVmxPath=[Vplex-Unity400-1.8-4.5T] Queueing/Queueing.vmx 2022-11-03 11:37:20 avvcbimage Info <43574>: vcApiVersion 6.5 2022-11-03 11:37:20 avvcbimage Info <43575>: VSphereHTTP::vcVersion65 will be true 2022-11-03 11:37:20 avvcbimage Info <42137>: job type = 4 2022-11-03 11:37:20 avvcbimage Info <18664>: Login(https://192.168.80.53:443/sdk) Datacenter: 'A-A-Datacenter' 2022-11-03 11:37:20 avvcbimage Info <19728>: - connected to 'VirtualCenter' - version: 'VMware vCenter Server 6.7.0 build-17137327', apiVersion:'6.7.3' 2022-11-03 11:37:20 avvcbimage Info <42146>: Login by loginWS test is OK. 2022-11-03 11:37:30 avvcbimage Info <40726>: VM Config Info: VMX version 4(F), ctk enabled(T), fault tolerant (F), Guest OS Microsoft Windows Server 2008 R2 (64-bit), Cloud UUID = NoUUID. version=13 2022-11-03 11:37:30 avvcbimage Info <40727>: VM Guest Info: GuestOS Family = windowsGuest, Guest Full Name = Microsoft Windows Server 2008 R2 (64-bit), Guest OS State = running, Tools Running = guestToolsRunning, Tools Version Status = guestToolsCurrent 2022-11-03 11:37:30 avvcbimage Info <14625>: WorkOrder Disk Info: Prior Disk '2000': file(base):'[Vplex-Unity400-1.8-4.5T] Queueing/Queueing.vmdk', backItUp=1 snapshot file:'[Vplex-Unity400-1.8-4.5T] Queueing/Queueing.vmdk' prior size(KB):0, current size(KB):314572800, match=0 prior change block ID:'' Datastore:'Vplex-Unity400-1.8-4.5T' Directly Accessible=1 2022-11-03 11:37:30 avvcbimage Info <40637>: Proxy Host Name=192.168.80.19(6.7.3 build-17499825) 2022-11-03 11:37:30 avvcbimage Info <40638>: VM Host Name=192.168.80.46(6.7.3 build-17499825) 2022-11-03 11:37:30 avvcbimage Info <19547>: Using Simultaneous Workorders method 2022-11-03 11:37:30 avvcbimage Info <19652>: Updated cache lookup 2022-11-03 11:37:30 avvcbimage Info <19587>: Create cache lock file succeeded 2022-11-03 11:37:30 avvcbimage Info <19588>: usLockFile: /usr/local/avamarclient/bin/var3.lck 2022-11-03 11:37:37 avvcbimage Info <11979>: proxy VM DNS name is: 192.168.80.62,avamarproxy,localhost,ipv6-localhost,ipv6-loopback,::1,fe80::250:56ff:fe8a:2501 2022-11-03 11:37:37 avvcbimage Info <18672>: proxy VM Instance name is: avamarproxy-proxy-3 2022-11-03 11:37:37 avvcbimage Info <17793>: proxy VM Moref is: vm-45847 2022-11-03 11:37:37 avvcbimage Info <16010>: vCenter 'A-A-Datacenter' is 192.168.80.53 2022-11-03 11:37:37 avvcbimage Info <11981>: VM's host is 192.168.80.46 2022-11-03 11:37:37 avvcbimage Info <11982>: VM's primary storage location is [Vplex-Unity400-1.8-4.5T] Queueing/Queueing.vmx 2022-11-03 11:37:37 avvcbimage Info <11983>: VM's vCenter ID is vm-178 2022-11-03 11:37:37 avvcbimage Info <19658>: change block file: VmImage3_changefilemap.tmp 2022-11-03 11:37:37 avvcbimage Info <11984>: VM's backup account on 192.168.80.55 is /192.168.80.53/ContainerClients/Queueing.30.27_C2AR7BAcMQ2WCIPPqg83JA 2022-11-03 11:37:37 avvcbimage Info <11986>: Changed block tracking is engaged for this VM 2022-11-03 11:37:37 avvcbimage Info <11988>: A reference to a valid prior backup is not available so this will be a full level zero backup. 2022-11-03 11:37:37 avvcbimage Info <19549>: metadata tmp dir: /usr/local/avamarclient/var/vmware/3/temp 2022-11-03 11:37:37 avvcbimage Info <40640>: create tmpDir: /usr, failed (-1) 2022-11-03 11:37:37 avvcbimage Info <40640>: create tmpDir: /usr/local, failed (-1) 2022-11-03 11:37:37 avvcbimage Info <40640>: create tmpDir: /usr/local/avamarclient, failed (-1) 2022-11-03 11:37:37 avvcbimage Info <40640>: create tmpDir: /usr/local/avamarclient/var, failed (-1) 2022-11-03 11:37:37 avvcbimage Info <40640>: create tmpDir: /usr/local/avamarclient/var/vmware, failed (-1) 2022-11-03 11:37:37 avvcbimage Info <40640>: create tmpDir: /usr/local/avamarclient/var/vmware/3, failed (-1) 2022-11-03 11:37:37 avvcbimage Info <40640>: create tmpDir: /usr/local/avamarclient/var/vmware/3/temp, failed (-1) 2022-11-03 11:37:37 avvcbimage Info <40654>: isExitOK()=0 2022-11-03 11:37:37 avvcbimage Info <17823>: Body- abortrecommended(f) 2022-11-03 11:37:37 avvcbimage Info <19660>: targetlist contains2022-11-03 11:37:37 avvcbimage Info <5174>: - Reading /usr/local/avamarclient/var/avvcbimage.cmd 2022-11-03 11:37:37 avvcbimage Info <5174>: - Reading /usr/local/avamarclient/var/avvcbimageAll.cmd 2022-11-03 11:37:37 avvcbimage Info <7084>: target[0]=[Vplex-Unity400-1.8-4.5T] Queueing/Queueing.vmdk 2022-11-03 11:37:37 avvcbimage Info <40658>: vmparams (192.168.80.53) 2022-11-03 11:37:37 avvcbimage Info <40654>: isExitOK()=0 2022-11-03 11:37:37 avvcbimage Info <42660>: Value of runflags.m_allDisksIncluded is true 2022-11-03 11:37:37 avvcbimage Info <18664>: Login(https://192.168.80.53:443/sdk) Datacenter: 'A-A-Datacenter' 2022-11-03 11:37:37 avvcbimage Info <19728>: - connected to 'VirtualCenter' - version: 'VMware vCenter Server 6.7.0 build-17137327', apiVersion:'6.7.3' 2022-11-03 11:37:37 avvcbimage Info <42146>: Login by loginWS test is OK. 2022-11-03 11:37:37 avvcbimage Info <16011>: Running cleanup of old backups. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:OBJLIB-LIB: Objlib initialized. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:VixDiskLib: Attempting to locate advanced transport module in "/usr/lib/vmware-vix-disklib". 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:VixDiskLib: Advanced transport plugin /usr/lib/vmware-vix-disklib/lib64/libdiskLibPlugin.so was successfully loaded into vixDiskLib. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:VixDiskLib: Enabling advanced transport modes. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:VixDiskLib: Initialize transport modes. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:2022-11-03T11:37:37.777+08:00 info -[7F4AD9735700] [Originator@6876 sub=ThreadPool] Entering fair thread loop 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:2022-11-03T11:37:37.777+08:00 info -[7F4ACEFFD700] [Originator@6876 sub=vm:DictionaryLoad: Cannot open file "/etc/vmware/vsphereFeatures/vsphereFeatures.cfg] : No such file or directory. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:2022-11-03T11:37:37.777+08:00 info -[7F4ACEFFD700] [Originator@6876 sub=vm:[msg.dictionary.load.openFailed] Cannot open file "/etc/vmware/vsphereFeatures/vsphereFeatures.cfg] : No such file or directory. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:2022-11-03T11:37:37.777+08:00 info -[7F4ACEFFD700] [Originator@6876 sub=vm:FeatureStateLib: Error while loading feature config file: /etc/vmware/vsphereFeatures/vsphereFeatures.cfg] using default feature state values. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:VixDiskLib: Available transport modes: file:san:hotadd:nbdssl:nbd. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:VMware VixDiskLib (6.5) Release build-6195444 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:DictionaryLoad: Cannot open file "/usr/lib/vmware/settings": No such file or directory. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:[msg.dictionary.load.openFailed] Cannot open file "/usr/lib/vmware/settings": No such file or directory. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:PREF Optional preferences file not found at /usr/lib/vmware/settings. Using default values. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:DictionaryLoad: Cannot open file "/usr/lib/vmware/config": No such file or directory. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:[msg.dictionary.load.openFailed] Cannot open file "/usr/lib/vmware/config": No such file or directory. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:PREF Optional preferences file not found at /usr/lib/vmware/config. Using default values. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:DictionaryLoad: Cannot open file "/root/.vmware/config": No such file or directory. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:[msg.dictionary.load.openFailed] Cannot open file "/root/.vmware/config": No such file or directory. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:PREF Optional preferences file not found at /root/.vmware/config. Using default values. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:DictionaryLoad: Cannot open file "/root/.vmware/preferences": No such file or directory. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:[msg.dictionary.load.openFailed] Cannot open file "/root/.vmware/preferences": No such file or directory. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:PREF Optional preferences file not found at /root/.vmware/preferences. Using default values. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:lib/ssl: OpenSSL using FIPS_drbg for RAND 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:lib/ssl: protocol list tls1.2 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:lib/ssl: protocol list tls1.2 (openssl flags 0x17000000) 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:lib/ssl: cipher list !aNULL:kECDH+AESGCM:ECDH+AESGCM:RSA+AESGCM:kECDH+AES:ECDH+AES:RSA+AES 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:VixDiskLibVim: VixDiskLibVim_Init: Initialization is completed. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:VixDiskLib: VixDiskLib_Cleanup: Remove previous mount points and clean up . 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:VixDiskLib: VixDiskLib_Connect: Establish connection. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:VixDiskLib: Resolve host. 2022-11-03 11:37:37 avvcbimage Info <16041>: VDDK:VixDiskLib: VixDiskLib_Disconnect: Disconnect. 2022-11-03 11:37:37 avvcbimage Info <16048>: VixDiskLib_Cleanup() found no prior mounts to cleanup. 2022-11-03 11:37:37 avvcbimage Info <18664>: Login(https://192.168.80.53:443/sdk) Datacenter: 'A-A-Datacenter' 2022-11-03 11:37:37 avvcbimage Info <19728>: - connected to 'VirtualCenter' - version: 'VMware vCenter Server 6.7.0 build-17137327', apiVersion:'6.7.3' 2022-11-03 11:37:37 avvcbimage Info <42146>: Login by loginWS test is OK. 2022-11-03 11:37:37 avvcbimage Info <42215>: SnapshotManager: initializing at 192.168.80.55:8543, vCenter 192.168.80.53(btso@vsphere.local), datacenter(/A-A-Datacenter), httpsTimeout_ms(3600000), maxDeleteRetries(-1) 2022-11-03 11:37:38 avvcbimage Info <42191>: GET: curl_easy_getinfo succeeded with httpcode(200) 2022-11-03 11:37:38 avvcbimage Info <44033>: AvSnapMgr::getData Succeeded httpCode: 200 2022-11-03 11:37:38 avvcbimage Info <42162>: SnapshotManager version:19.1.0.38 2022-11-03 11:37:38 avvcbimage Info <40642>: Using SnapShot Manager - with fallback to proxy calling vSphere API functions. 2022-11-03 11:37:38 avvcbimage Info <18648>: The snapshot '' could not be removed. 2022-11-03 11:37:38 avvcbimage Info <44037>: No same VM disk(s) had attached to proxy: vm-45847.No need to reconfig proxy 2022-11-03 11:37:38 avvcbimage Info <40751>: Datastore Vplex-Unity400-1.8-4.5T 2022-11-03 11:37:38 avvcbimage Info <14664>: no snapshots are present on this VM. 2022-11-03 11:37:38 avvcbimage Info <42225>: (CURL) Logging into datacenter path of 'folder?dcPath=A-A-Datacenter', A-A-Datacenter => A-A-Datacenter 2022-11-03 11:37:38 avvcbimage Info <42226>: (Legacy) Logging into datacenter path of 'folder?dcPath=A-A-Datacenter', A-A-Datacenter => A%2DA%2DDatacenter 2022-11-03 11:37:38 avvcbimage Info <41153>: Using CURL Method 2022-11-03 11:37:38 avvcbimage Info <41154>: Re-using vCS Session Cookie 2022-11-03 11:37:38 avvcbimage Info <41153>: Using CURL Method 2022-11-03 11:37:38 avvcbimage Info <41154>: Re-using vCS Session Cookie 2022-11-03 11:37:39 avvcbimage Info <14673>: All Datastore Info: datastore:'FC_Share5 ' capacity=3873523630080 free=3479955308544 datastore:'FC_share1 ' capacity=2198754820096 free=1774673985536 datastore:'FC_share2 ' capacity=2050578448384 free=1997352730624 datastore:'FC_share3 ' capacity=1470757863424 free=1418084745216 datastore:'FC_share4 ' capacity=1578132045824 free=1577105489920 datastore:'Vplex-HUS110-2.5T ' capacity=2748510633984 free=974624129024 datastore:'Vplex-HUS110-2.7T ' capacity=3003524317184 free=1252699144192 datastore:'Vplex-HUS110-4.9T ' capacity=5387231166464 free=2415214460928 datastore:'Vplex-Unity400-1.8-4.5T ' capacity=4947533889536 free=2033307353088 datastore:'Vplex-Unity400-1.8-4T ' capacity=4397778075648 free=2290258804736 datastore:'Vplex-Unity400-1.8-5.5T ' capacity=6047045517312 free=3057300537344 datastore:'Vplex-Unity400-1.8-5T ' capacity=5497289703424 free=2266599784448 datastore:'Vplex-Unity400-1.8-6TB ' capacity=6596801331200 free=3465928507392 datastore:'Vplex-Unity400-11Tssd-DB3 ' capacity=12094359470080 free=5651431424000 datastore:'Vplex-Unity400-3.9T-DB1 ' capacity=4294698860544 free=1945732382720 datastore:'Vplex-Unity400-4.59T ' capacity=5046318137344 free=1942662152192 datastore:'Vplex-Unity400-4T-DB2 ' capacity=4402073042944 free=4400491790336 datastore:'datastore12 ' capacity=99321118720 free=98301902848 datastore:'datastore13 ' capacity=290984034304 free=145993236480 datastore:'datastore14 ' capacity=290984034304 free=145994285056 datastore:'datastore17 ' capacity=290984034304 free=263665483776 datastore:'datastore18 ' capacity=290984034304 free=268590645248 datastore:'datastore19 ' capacity=290984034304 free=263648706560 datastore:'datastore20 ' capacity=290984034304 free=263650803712 datastore:'datastore41 ' capacity=290984034304 free=268522487808 datastore:'datastore42 ' capacity=290984034304 free=268703891456 datastore:'datastore43 ' capacity=290984034304 free=268707037184 datastore:'datastore44 ' capacity=290984034304 free=266484056064 datastore:'datastore45 ' capacity=590826438656 free=563534102528 datastore:'datastore46 ' capacity=590826438656 free=563550879744 datastore:'datastore47 ' capacity=590826438656 free=563515228160 datastore:'datastore48 ' capacity=590826438656 free=563550879744 datastore:'datastore49 ' capacity=290984034304 free=268689211392 datastore:'datastore50 ' capacity=290984034304 free=268705988608 datastore:'datastore51 ' capacity=290984034304 free=268670337024 datastore:'datastore52 ' capacity=290984034304 free=268688162816 2022-11-03 11:37:39 avvcbimage Info <14675>: All VM Info (10/10): VM:'Caiji/ ' last modified on '11-Oct-2022 15:50' VM:'DB-WeiChatMiniPrograms/ ' last modified on '12-Oct-2022 14:07' VM:'M&C/ ' last modified on '11-Oct-2022 16:22' VM:'PACS Database/ ' last modified on '13-Oct-2021 12:40' VM:'Queueing/ ' last modified on '03-Nov-2022 03:36' VM:'RGSZYY_DC2/ ' last modified on '11-Oct-2022 15:59' VM:'VCSA7.01/ ' last modified on '26-Oct-2022 11:59' VM:'WUZI/ ' last modified on '11-Oct-2022 15:37' VM:'Yonyou.30.34/ ' last modified on '11-Oct-2022 16:07' VM:'server_2016_standard/ ' last modified on '13-Sep-2022 08:38' 2022-11-03 11:37:39 avvcbimage Info <42204>: The download path of VM 'Queueing/' is (CURL) '/folder/Queueing?dcPath=A-A-Datacenter&dsName=Vplex%252dUnity400%252d1%252e8%252d4%252e5T' or (LEGACY) '/folder/Queueing?dcPath=A%252dA%252dDatacenter&dsName=Vplex%252dUnity400%252d1%252e8%252d4%252e5T' 2022-11-03 11:37:39 avvcbimage Info <41153>: Using CURL Method 2022-11-03 11:37:39 avvcbimage Info <41154>: Re-using vCS Session Cookie 2022-11-03 11:37:39 avvcbimage Info <14677>: All VM file Info: File:'Queueing-000002-sesparse.vmdk ' last modified on '14-Dec-2021 14:07' size=1288704000 File:'Queueing-000002.vmdk ' last modified on '14-Dec-2021 14:28' size=313 File:'Queueing-02f3f7de.vswp ' last modified on '27-Sep-2020 07:44' size=17179869184 File:'Queueing-4bb0307c.hlog ' last modified on '05-Mar-2021 15:54' size=92 File:'Queueing-aux.xml ' last modified on '03-Nov-2022 03:36' size=13 File:'Queueing-ctk.vmdk ' last modified on '03-Nov-2022 03:36' size=4915712 File:'Queueing-flat.vmdk ' last modified on '03-Nov-2022 03:37' size=322122547200 File:'Queueing.db ' last modified on '03-Mar-2021 05:06' size=9216 File:'Queueing.db-journal ' last modified on '03-Mar-2021 05:06' size=3608 File:'Queueing.nvram ' last modified on '09-Oct-2022 17:00' size=8684 File:'Queueing.vmdk ' last modified on '03-Nov-2022 03:36' size=711 File:'Queueing.vmsd ' last modified on '03-Nov-2022 03:36' size=45 File:'Queueing.vmx ' last modified on '03-Nov-2022 03:36' size=4032 File:'Queueing.vmx.lck ' last modified on '05-Mar-2021 15:54' size=0 File:'Queueing.vmxf ' last modified on '02-Nov-2022 03:03' size=150 File:'Queueing.vmx~ ' last modified on '03-Nov-2022 03:36' size=4039 File:'vmware-48.log ' last modified on '27-Sep-2020 07:44' size=251342 File:'vmware-49.log ' last modified on '27-Sep-2020 07:44' size=250684 File:'vmware-50.log ' last modified on '21-Oct-2020 11:33' size=977494 File:'vmware-51.log ' last modified on '21-Oct-2020 11:47' size=250734 File:'vmware-52.log ' last modified on '05-Mar-2021 10:36' size=6195620 File:'vmware-53.log ' last modified on '05-Mar-2021 15:54' size=306518 File:'vmware.log ' last modified on '03-Nov-2022 03:36' size=13083115 File:'vmx-Queueing-49543134-1.vswp ' last modified on '05-Mar-2021 15:53' size=90177536 2022-11-03 11:37:39 avvcbimage Info <19704>: DataStore Storage Info:Vplex-Unity400-1.8-4.5T capacity=4947533889536 free=2033307353088 2022-11-03 11:37:39 avvcbimage Info <19716>: DS Capacity=4947533889536 FreeSpace=2033307353088 / HD committed=78353110215 unCommitted=261060821703 unShared=61061726208 2022-11-03 11:37:39 avvcbimage Info <41153>: Using CURL Method 2022-11-03 11:37:39 avvcbimage Info <41154>: Re-using vCS Session Cookie 2022-11-03 11:37:39 avvcbimage Info <41153>: Using CURL Method 2022-11-03 11:37:39 avvcbimage Info <41154>: Re-using vCS Session Cookie 2022-11-03 11:37:40 avvcbimage Info <42216>: Evaluating Datastores for orphaned snapshots using legacy method. 2022-11-03 11:37:40 avvcbimage Info <16001>: Found 1 disk(s), 0 snapshots, and 1 snapshot files, on the VMs datastore. 2022-11-03 11:37:40 avvcbimage Warning <18652>: Too many extra snapshot files (1) were found on this Windows VMs datastore. This can cause a problem for the backup or restore. 2022-11-03 11:37:40 avvcbimage Info <18678>: Extracted Queueing-flat.vmdk base disk from the VMX file 2022-11-03 11:37:40 avvcbimage FATAL <0000>: [IMG0002] The datastore information from VMX '[Vplex-Unity400-1.8-4.5T] Queueing/Queueing.vmx' will not permit a restore or backup. 2022-11-03 11:37:40 avvcbimage Info <9772>: Starting graceful (staged) termination, Too many pre-existing snapshots on the datastore will not permit a restore or backup. (wrap-up stage) 2022-11-03 11:37:40 avvcbimage Error <0000>: [IMG0009] Create Snapshot: snapshot creation or pre/post snapshot script failed. 2022-11-03 11:37:40 avvcbimage Error <0000>: [IMG0009] Create Snapshot: snapshot creation/pre-script/post-script failed. 2022-11-03 11:37:40 avvcbimage Info <40654>: isExitOK()=206 2022-11-03 11:37:40 avvcbimage Info <40659>: snapshot created:false NOMC:false ChangeBlTrackingAvail:true UsingChBl:true, ExitOK:false, cancelled:false, fatal: true 2022-11-03 11:37:40 avvcbimage Info <18664>: Login(https://192.168.80.53:443/sdk) Datacenter: 'A-A-Datacenter' 2022-11-03 11:37:40 avvcbimage Info <19728>: - connected to 'VirtualCenter' - version: 'VMware vCenter Server 6.7.0 build-17137327', apiVersion:'6.7.3' 2022-11-03 11:37:40 avvcbimage Info <42146>: Login by loginWS test is OK. 2022-11-03 11:37:40 avvcbimage Info <18664>: Login(https://192.168.80.53:443/sdk) Datacenter: 'A-A-Datacenter' 2022-11-03 11:37:40 avvcbimage Info <19728>: - connected to 'VirtualCenter' - version: 'VMware vCenter Server 6.7.0 build-17137327', apiVersion:'6.7.3' 2022-11-03 11:37:40 avvcbimage Info <42146>: Login by loginWS test is OK. 2022-11-03 11:37:40 avvcbimage Info <9666>: Available transport modes are file:san:hotadd:nbdssl:nbd 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:VixDiskLib: VixDiskLib_PrepareForAccess: Prepare to access disk. 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:VixDiskLib: VixDiskLib_Connect: Establish connection. 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:VixDiskLib: Resolve host. 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:VixDiskLibVim: VixDiskLibVim_AllowVMotion: Disable VMotion. 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:DictionaryLoad: Cannot open file "/usr/lib/vmware/settings": No such file or directory. 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:[msg.dictionary.load.openFailed] Cannot open file "/usr/lib/vmware/settings": No such file or directory. 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:PREF Optional preferences file not found at /usr/lib/vmware/settings. Using default values. 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:DictionaryLoad: Cannot open file "/usr/lib/vmware/config": No such file or directory. 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:[msg.dictionary.load.openFailed] Cannot open file "/usr/lib/vmware/config": No such file or directory. 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:PREF Optional preferences file not found at /usr/lib/vmware/config. Using default values. 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:DictionaryLoad: Cannot open file "/root/.vmware/config": No such file or directory. 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:[msg.dictionary.load.openFailed] Cannot open file "/root/.vmware/config": No such file or directory. 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:PREF Optional preferences file not found at /root/.vmware/config. Using default values. 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:DictionaryLoad: Cannot open file "/root/.vmware/preferences": No such file or directory. 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:[msg.dictionary.load.openFailed] Cannot open file "/root/.vmware/preferences": No such file or directory. 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:PREF Optional preferences file not found at /root/.vmware/preferences. Using default values. 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:lib/ssl: OpenSSL using FIPS_drbg for RAND 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:lib/ssl: protocol list tls1.2 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:lib/ssl: protocol list tls1.2 (openssl flags 0x17000000) 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:lib/ssl: cipher list !aNULL:kECDH+AESGCM:ECDH+AESGCM:RSA+AESGCM:kECDH+AES:ECDH+AES:RSA+AES 2022-11-03 11:37:40 avvcbimage Info <16041>: VDDK:VixDiskLibVim: VixDiskLibVim_FreeNfcTicket: Free NFC ticket. 2022-11-03 11:37:40 avvcbimage Info <17818>: VixDiskLib vMotion reservation successfully enabled by 'EMC Avamar VM Backup Proxy' 2022-11-03 11:37:40 avvcbimage Info <40654>: isExitOK()=206 2022-11-03 11:37:40 avvcbimage Info <18664>: Login(https://192.168.80.53:443/sdk) Datacenter: 'A-A-Datacenter' 2022-11-03 11:37:40 avvcbimage Info <19728>: - connected to 'VirtualCenter' - version: 'VMware vCenter Server 6.7.0 build-17137327', apiVersion:'6.7.3' 2022-11-03 11:37:40 avvcbimage Info <42146>: Login by loginWS test is OK. 2022-11-03 11:37:40 avvcbimage Info <43003>: backup Failed, only set annotation to LastBackupStatus 2022-11-03 11:37:40 avvcbimage Info <43109>: AvSnapMgr::addSetAnnotation, vm-178 2022-11-03 11:37:40 avvcbimage Info <42182>: SnapshotManager: POST to /snapshotmanager/vm/vm-178/action/markAnnotations, payload({"url":"https://192.168.80.53/sdk","tags":{"LastBackupStatus-com.dellemc.avamar":"Failed: 2022-11-03 03:37:40 UTC"}}) 2022-11-03 11:37:41 avvcbimage Info <42183>: AvSnapMgr::postData Succeeded 2022-11-03 11:37:41 avvcbimage Info <42185>: POST: curl_easy_getinfo - passed. HTTPCode: 200 2022-11-03 11:37:41 avvcbimage Info <42183>: AvSnapMgr::postData Succeeded 2022-11-03 11:37:41 avvcbimage Info <43110>: avSnapMgr::addSetAnnotation returned: HTTP/1.1 200 Content-Length: 0 Date: Thu, 03 Nov 2022 03:32:42 GMT Connection: close Server: Avamar 2022-11-03 11:37:41 avvcbimage Info <43206>: SnapshotManager: addSetAnnotation successful for vm vm-178 2022-11-03 11:37:41 avvcbimage Info <43005>: addSetAnnotation Successful 2022-11-03 11:37:41 avvcbimage Info <40660>: vcbimage_progress::terminate 2022-11-03 11:37:41 avvcbimage Info <16038>: Final summary, cancelled/aborted 0, snapview 0, exitcode 206: plugin error 06 2022-11-03 11:37:46 avvcbimage Info <16041>: VDDK:VixDiskLib: VixDiskLib_EndAccess: Disk access completed. 2022-11-03 11:37:46 avvcbimage Info <16041>: VDDK:VixDiskLib: VixDiskLib_Connect: Establish connection. 2022-11-03 11:37:46 avvcbimage Info <16041>: VDDK:VixDiskLib: Resolve host. 2022-11-03 11:37:46 avvcbimage Info <16041>: VDDK:VixDiskLibVim: VixDiskLibVim_AllowVMotion: Enable VMotion. 2022-11-03 11:37:46 avvcbimage Info <16041>: VDDK:VixDiskLibVim: VixDiskLibVim_FreeNfcTicket: Free NFC ticket. 2022-11-03 11:37:46 avvcbimage Info <17819>: VixDiskLib vMotion reservation successfully released
END avvcbimage log 2022-11-03 11:37:46 CST (3 warnings, 3 errors, 1 fatal error)
*********** /usr/local/avamarclient/var/MOD-1667446305496-b37c2ab43917bc9ca9edeeace741fab796bcaaaf-3016-vmimagew.alg ***********
Log #3: MOD-1667446305496 log 2022-11-03 11:37:20 CST [19.1.100-38 Linux-x86_64]
2022-11-03 11:37:20 MOD-1667446305496 Info <0000>: Workorder : MOD-1667446305496 2022-11-03 11:37:20 MOD-1667446305496 Info <0000>: Plugin ID : 3016-vmimagew 2022-11-03 11:37:20 MOD-1667446305496 Info <0000>: bindir : /usr/local/avamarclient/bin 2022-11-03 11:37:20 MOD-1667446305496 Info <0000>: vardir : /usr/local/avamarclient/var 2022-11-03 11:37:20 MOD-1667446305496 Info <0000>: Initiator : 2022-11-03 11:37:20 MOD-1667446305496 Info <0000>: Executable : /usr/local/avamarclient/bin/avvcbimage 2022-11-03 11:37:20 MOD-1667446305496 Info <0000>: Args : avvcbimage --sysdir="/usr/local/avamarclient/etc" --bindir="/usr/local/avamarclient/bin" --vardir="/usr/local/avamarclient/var" --ctlcallport="57831" --ctlinterface="3016-MOD-1667446305496-b37c2ab43917bc9ca9edeeace741fab796bcaaaf" --logtimeformat="default" --logtimezone="default" --logfile="/usr/local/avamarclient/var/MOD-1667446305496-b37c2ab43917bc9ca9edeeace741fab796bcaaaf-3016-vmimagew.log" --ctlusessl="true" 2022-11-03 11:37:20 MOD-1667446305496 Info <0000>: Order :2022-11-03 11:37:47 MOD-1667446305496 Warning <0000>: Workorder "MOD-1667446305496" non-zero exit status 'code 206: plugin error 06' 2022-11-03 11:37:47 MOD-1667446305496 Info <0000>:
LeoLi
Moderator
Moderator
•
5.5K 消息
0
2022年11月3日 23:00
您好!
这个问题和您之前发的下面的问题是同一个问题是吗?问题已经解决了是吧。
https://www.dell.com/community/%E6%95%B0%E6%8D%AE%E4%BF%9D%E6%8A%A4%E8%AE%A8%E8%AE%BA%E5%8C%BA/all-virtual-machines-backup-failed%E6%89%80%E6%9C%89%E8%99%9A%E6%8B%9F%E6%9C%BA%E5%A4%87%E4%BB%BD%E9%83%BD%E6%8F%90%E7%A4%BA%E6%97%A0%E6%B3%95%E5%88%9B%E5%BB%BA%E5%BF%AB%E7%85%A7/m-p/8291819#M7005
dangdang_togo
1 Rookie
1 Rookie
•
15 消息
0
2022年11月8日 02:00
不是同一个问题,之前是所有虚拟机都无法备份,现在只有一台
LeoLi
Moderator
Moderator
•
5.5K 消息
0
2022年11月9日 01:00
您好!
请参考看下面的内容来解决:
VMWare Image Backups Fail (Code 10056) >> avvcbimage Error <9759>: createSnapshot: Snapshot creation failed
Unable to perform VMware client Image backups and they are failing with Code 10056.
The Snapshot create task fails with the following errors:
Issue #1
avvcbimage Warning <16002>: Too many extra snapshot files (3) were found on the VMs datastore. This can cause a problem for the backup or restore.
avvcbimage FATAL <16018>: The datastore information from VMX '[MLUN] Datastore/TestMachine.vmx' will not permit a restore or backup.
avvcbimage Info <0000>: Starting graceful (staged) termination, Too many pre-existing snapshots on the datastore will not permit a restore or backup. (wrap-up stage)
avvcbimage Error <9759>: createSnapshot: snapshot creation failed
The log shows Avamar did not detect there is Snapshot for this VM initially:However, there are Snapshot files on the Datastore:
2013-01-31 23:37:54 avvcbimage Info <14677>: All VM file Info:File:'CCHA-00SV0D0SAN-000003-ctk.vmdk ' last modified on '07-Dec-2011 15:58' size=3932672
File:'CCHA-00SV0D0SAN-000003-delta.vmdk ' last modified on '07-Dec-2011 15:57' size=134344704
File:'CCHA-00SV0D0SAN-000003.vmdk ' last modified on '07-Dec-2011 15:55' size=408
2015-02-20 13:47:23 avvcbimage Info <14665>: 30 snapshots are present on this VM:
level=1, children=1, ref='snapshot-4223', 'snapshot1':desc='na'
...
*level=30, children=0, ref='snapshot-4252', 'snapshot30':desc='na
2015-02-20 13:47:23 avvcbimage FATAL <0000>: [IMG0019] The pre-existing snapshots from VMX '[MLUN] Datastore/TestMachine.vmx does not permit a backup.
System: VMWare
If there are Snapshot files in the Virtual Machine directory and vCenter reported less then was discovered by the scan then the backup fails.
VMware ESI will only permit up to 32 before the Snapshot task fails with: Create Virtual Machine Sapshot task fails with a VMware error of: "Snapshot hierarchy is too deep". As described above we need to take a new "Avamar" snapshot (bring Snapshot count to 31). And the end of the backup we need to delete this Snapshot. This delete action causes ESX to create a hidden esx Snapshot called consolidated helper (snapshot count would be 32) and could fail due to the ESX "Snapshot hierarchy is too deep" issue. For this reason the Avamar proxy code will NOT allow Snapshots of a VM that has existing 30 snapshots.
Snapshot files must be removed from the Virtual Machines Datastore Directory.
Before removing the files there are some checks that must be completed.
For Issue #2.
In VMware, delete the existing Snapshot. VMware does not recommend using Snapshots as your only backup strategy (For example: use product like Avamar ). Have more than a small set of short term Snapshots that introduces latency and high stun times during Snapshot operations.Ideally a VM should not have Snapshot.