Standalone backup agents for Linux, Mac, AIX & Solaris workloads on-premises or in the public cloud
Post Reply
delmarthome
Novice
Posts: 7
Liked: 2 times
Joined: Aug 23, 2016 8:40 pm
Full Name: Delmar Jose Thome
Contact:

Cannot allocate memory

Post by delmarthome »

Hi,

I received the error "Failed to create volume snapshot; Cannot allocate memory".
In the log file I've found "Checking common snapshot data file exist. Data host device [/dev/dm-2], file [/u01/veeamsnapshotdata.0], size 11803684864. Failed."

What can I do to solve the problem?
PTide
Product Manager
Posts: 6408
Liked: 724 times
Joined: May 19, 2015 1:46 pm
Contact:

Re: Cannot allocate memory

Post by PTide »

Hi,

What's the system that you are using (virtual/physical, OS) and how much RAM was free when the backup job had started? Please provide /var/log/veeam directory archive so we can review the logs. Also please include dmesg -T, lsblk -af, fdisk -l outputs.

Thanks
delmarthome
Novice
Posts: 7
Liked: 2 times
Joined: Aug 23, 2016 8:40 pm
Full Name: Delmar Jose Thome
Contact:

Re: Cannot allocate memory

Post by delmarthome »

Hi,

This is a vvmware virtual machine:
ESXi 5.5.0
CentOS 6.5, 12Gb RAM, 6Gb Free, 12 Gb swap

veeamsvc.log

Code: Select all

------------------------------------------------------------
[24.08.2016 10:59:59] <139652762142560> cli    | Initializing new log filter.
[24.08.2016 10:59:59] <139652762142560>        | ====================================================================================
[24.08.2016 10:59:59] <139652762142560> lpb    | {
[24.08.2016 10:59:59] <139652762142560> lpb    |   Veeam Agent for Linux BETA (expires in 8 days): veeamservice.
[24.08.2016 10:59:59] <139652762142560> lpb    |   Version: 1.0.0.499
[24.08.2016 10:59:59] <139652762142560> lpb    |   PID: 11541
[24.08.2016 10:59:59] <139652762142560> lpb    |   uname
[24.08.2016 10:59:59] <139652762142560> lpb    |     sysname : Linux
[24.08.2016 10:59:59] <139652762142560> lpb    |     release : 2.6.32-431.17.1.el6.x86_64
[24.08.2016 10:59:59] <139652762142560> lpb    |     version : #1 SMP Wed May 7 23:32:49 UTC 2014
[24.08.2016 10:59:59] <139652762142560> lpb    |     machine : x86_64
[24.08.2016 10:59:59] <139652762142560> lpb    |   cpuinfo
[24.08.2016 10:59:59] <139652762142560> lpb    |     processor  : 0
[24.08.2016 10:59:59] <139652762142560> lpb    |     vendor_id  : GenuineIntel
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu family : 6
[24.08.2016 10:59:59] <139652762142560> lpb    |     model              : 62
[24.08.2016 10:59:59] <139652762142560> lpb    |     model name : Intel(R) Xeon(R) CPU E5-2407 v2 @ 2.40GHz
[24.08.2016 10:59:59] <139652762142560> lpb    |     stepping   : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu MHz            : 2400.000
[24.08.2016 10:59:59] <139652762142560> lpb    |     cache size : 10240 KB
[24.08.2016 10:59:59] <139652762142560> lpb    |     physical id        : 0
[24.08.2016 10:59:59] <139652762142560> lpb    |     siblings   : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     core id            : 0
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu cores  : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     apicid             : 0
[24.08.2016 10:59:59] <139652762142560> lpb    |     initial apicid     : 0
[24.08.2016 10:59:59] <139652762142560> lpb    |     fpu                : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     fpu_exception      : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpuid level        : 13
[24.08.2016 10:59:59] <139652762142560> lpb    |     wp         : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     flags              : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts mmx fxsr sse sse2 ss ht syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts xtopology tsc_reliable nonstop_tsc aperfmperf unfair_spinlock pni pclmulqdq ssse3 cx16 sse4_1 sse4_2 popcnt aes xsave avx hypervisor lahf_lm arat xsaveopt pln pts dts
[24.08.2016 10:59:59] <139652762142560> lpb    |     bogomips   : 4800.00
[24.08.2016 10:59:59] <139652762142560> lpb    |     clflush size       : 64
[24.08.2016 10:59:59] <139652762142560> lpb    |     cache_alignment    : 64
[24.08.2016 10:59:59] <139652762142560> lpb    |     address sizes      : 40 bits physical, 48 bits virtual
[24.08.2016 10:59:59] <139652762142560> lpb    |     power management:
[24.08.2016 10:59:59] <139652762142560> lpb    |     processor  : 1
[24.08.2016 10:59:59] <139652762142560> lpb    |     vendor_id  : GenuineIntel
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu family : 6
[24.08.2016 10:59:59] <139652762142560> lpb    |     model              : 62
[24.08.2016 10:59:59] <139652762142560> lpb    |     model name : Intel(R) Xeon(R) CPU E5-2407 v2 @ 2.40GHz
[24.08.2016 10:59:59] <139652762142560> lpb    |     stepping   : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu MHz            : 2400.000
[24.08.2016 10:59:59] <139652762142560> lpb    |     cache size : 10240 KB
[24.08.2016 10:59:59] <139652762142560> lpb    |     physical id        : 0
[24.08.2016 10:59:59] <139652762142560> lpb    |     siblings   : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     core id            : 1
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu cores  : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     apicid             : 1
[24.08.2016 10:59:59] <139652762142560> lpb    |     initial apicid     : 1
[24.08.2016 10:59:59] <139652762142560> lpb    |     fpu                : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     fpu_exception      : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpuid level        : 13
[24.08.2016 10:59:59] <139652762142560> lpb    |     wp         : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     flags              : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts mmx fxsr sse sse2 ss ht syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts xtopology tsc_reliable nonstop_tsc aperfmperf unfair_spinlock pni pclmulqdq ssse3 cx16 sse4_1 sse4_2 popcnt aes xsave avx hypervisor lahf_lm arat xsaveopt pln pts dts
[24.08.2016 10:59:59] <139652762142560> lpb    |     bogomips   : 4800.00
[24.08.2016 10:59:59] <139652762142560> lpb    |     clflush size       : 64
[24.08.2016 10:59:59] <139652762142560> lpb    |     cache_alignment    : 64
[24.08.2016 10:59:59] <139652762142560> lpb    |     address sizes      : 40 bits physical, 48 bits virtual
[24.08.2016 10:59:59] <139652762142560> lpb    |     power management:
[24.08.2016 10:59:59] <139652762142560> lpb    |     processor  : 2
[24.08.2016 10:59:59] <139652762142560> lpb    |     vendor_id  : GenuineIntel
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu family : 6
[24.08.2016 10:59:59] <139652762142560> lpb    |     model              : 62
[24.08.2016 10:59:59] <139652762142560> lpb    |     model name : Intel(R) Xeon(R) CPU E5-2407 v2 @ 2.40GHz
[24.08.2016 10:59:59] <139652762142560> lpb    |     stepping   : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu MHz            : 2400.000
[24.08.2016 10:59:59] <139652762142560> lpb    |     cache size : 10240 KB
[24.08.2016 10:59:59] <139652762142560> lpb    |     physical id        : 0
[24.08.2016 10:59:59] <139652762142560> lpb    |     siblings   : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     core id            : 2
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu cores  : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     apicid             : 2
[24.08.2016 10:59:59] <139652762142560> lpb    |     initial apicid     : 2
[24.08.2016 10:59:59] <139652762142560> lpb    |     fpu                : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     fpu_exception      : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpuid level        : 13
[24.08.2016 10:59:59] <139652762142560> lpb    |     wp         : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     flags              : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts mmx fxsr sse sse2 ss ht syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts xtopology tsc_reliable nonstop_tsc aperfmperf unfair_spinlock pni pclmulqdq ssse3 cx16 sse4_1 sse4_2 popcnt aes xsave avx hypervisor lahf_lm arat xsaveopt pln pts dts
[24.08.2016 10:59:59] <139652762142560> lpb    |     bogomips   : 4800.00
[24.08.2016 10:59:59] <139652762142560> lpb    |     clflush size       : 64
[24.08.2016 10:59:59] <139652762142560> lpb    |     cache_alignment    : 64
[24.08.2016 10:59:59] <139652762142560> lpb    |     address sizes      : 40 bits physical, 48 bits virtual
[24.08.2016 10:59:59] <139652762142560> lpb    |     power management:
[24.08.2016 10:59:59] <139652762142560> lpb    |     processor  : 3
[24.08.2016 10:59:59] <139652762142560> lpb    |     vendor_id  : GenuineIntel
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu family : 6
[24.08.2016 10:59:59] <139652762142560> lpb    |     model              : 62
[24.08.2016 10:59:59] <139652762142560> lpb    |     model name : Intel(R) Xeon(R) CPU E5-2407 v2 @ 2.40GHz
[24.08.2016 10:59:59] <139652762142560> lpb    |     stepping   : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu MHz            : 2400.000
[24.08.2016 10:59:59] <139652762142560> lpb    |     cache size : 10240 KB
[24.08.2016 10:59:59] <139652762142560> lpb    |     physical id        : 0
[24.08.2016 10:59:59] <139652762142560> lpb    |     siblings   : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     core id            : 3
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu cores  : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     apicid             : 3
[24.08.2016 10:59:59] <139652762142560> lpb    |     initial apicid     : 3
[24.08.2016 10:59:59] <139652762142560> lpb    |     fpu                : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     fpu_exception      : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpuid level        : 13
[24.08.2016 10:59:59] <139652762142560> lpb    |     wp         : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     flags              : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts mmx fxsr sse sse2 ss ht syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts xtopology tsc_reliable nonstop_tsc aperfmperf unfair_spinlock pni pclmulqdq ssse3 cx16 sse4_1 sse4_2 popcnt aes xsave avx hypervisor lahf_lm arat xsaveopt pln pts dts
[24.08.2016 10:59:59] <139652762142560> lpb    |     bogomips   : 4800.00
[24.08.2016 10:59:59] <139652762142560> lpb    |     clflush size       : 64
[24.08.2016 10:59:59] <139652762142560> lpb    |     cache_alignment    : 64
[24.08.2016 10:59:59] <139652762142560> lpb    |     address sizes      : 40 bits physical, 48 bits virtual
[24.08.2016 10:59:59] <139652762142560> lpb    |     power management:
[24.08.2016 10:59:59] <139652762142560> lpb    |     processor  : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     vendor_id  : GenuineIntel
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu family : 6
[24.08.2016 10:59:59] <139652762142560> lpb    |     model              : 62
[24.08.2016 10:59:59] <139652762142560> lpb    |     model name : Intel(R) Xeon(R) CPU E5-2407 v2 @ 2.40GHz
[24.08.2016 10:59:59] <139652762142560> lpb    |     stepping   : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu MHz            : 2400.000
[24.08.2016 10:59:59] <139652762142560> lpb    |     cache size : 10240 KB
[24.08.2016 10:59:59] <139652762142560> lpb    |     physical id        : 1
[24.08.2016 10:59:59] <139652762142560> lpb    |     siblings   : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     core id            : 0
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu cores  : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     apicid             : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     initial apicid     : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     fpu                : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     fpu_exception      : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpuid level        : 13
[24.08.2016 10:59:59] <139652762142560> lpb    |     wp         : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     flags              : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts mmx fxsr sse sse2 ss ht syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts xtopology tsc_reliable nonstop_tsc aperfmperf unfair_spinlock pni pclmulqdq ssse3 cx16 sse4_1 sse4_2 popcnt aes xsave avx hypervisor lahf_lm arat xsaveopt pln pts dts
[24.08.2016 10:59:59] <139652762142560> lpb    |     bogomips   : 4800.00
[24.08.2016 10:59:59] <139652762142560> lpb    |     clflush size       : 64
[24.08.2016 10:59:59] <139652762142560> lpb    |     cache_alignment    : 64
[24.08.2016 10:59:59] <139652762142560> lpb    |     address sizes      : 40 bits physical, 48 bits virtual
[24.08.2016 10:59:59] <139652762142560> lpb    |     power management:
[24.08.2016 10:59:59] <139652762142560> lpb    |     processor  : 5
[24.08.2016 10:59:59] <139652762142560> lpb    |     vendor_id  : GenuineIntel
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu family : 6
[24.08.2016 10:59:59] <139652762142560> lpb    |     model              : 62
[24.08.2016 10:59:59] <139652762142560> lpb    |     model name : Intel(R) Xeon(R) CPU E5-2407 v2 @ 2.40GHz
[24.08.2016 10:59:59] <139652762142560> lpb    |     stepping   : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu MHz            : 2400.000
[24.08.2016 10:59:59] <139652762142560> lpb    |     cache size : 10240 KB
[24.08.2016 10:59:59] <139652762142560> lpb    |     physical id        : 1
[24.08.2016 10:59:59] <139652762142560> lpb    |     siblings   : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     core id            : 1
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu cores  : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     apicid             : 5
[24.08.2016 10:59:59] <139652762142560> lpb    |     initial apicid     : 5
[24.08.2016 10:59:59] <139652762142560> lpb    |     fpu                : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     fpu_exception      : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpuid level        : 13
[24.08.2016 10:59:59] <139652762142560> lpb    |     wp         : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     flags              : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts mmx fxsr sse sse2 ss ht syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts xtopology tsc_reliable nonstop_tsc aperfmperf unfair_spinlock pni pclmulqdq ssse3 cx16 sse4_1 sse4_2 popcnt aes xsave avx hypervisor lahf_lm arat xsaveopt pln pts dts
[24.08.2016 10:59:59] <139652762142560> lpb    |     bogomips   : 4800.00
[24.08.2016 10:59:59] <139652762142560> lpb    |     clflush size       : 64
[24.08.2016 10:59:59] <139652762142560> lpb    |     cache_alignment    : 64
[24.08.2016 10:59:59] <139652762142560> lpb    |     address sizes      : 40 bits physical, 48 bits virtual
[24.08.2016 10:59:59] <139652762142560> lpb    |     power management:
[24.08.2016 10:59:59] <139652762142560> lpb    |     processor  : 6
[24.08.2016 10:59:59] <139652762142560> lpb    |     vendor_id  : GenuineIntel
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu family : 6
[24.08.2016 10:59:59] <139652762142560> lpb    |     model              : 62
[24.08.2016 10:59:59] <139652762142560> lpb    |     model name : Intel(R) Xeon(R) CPU E5-2407 v2 @ 2.40GHz
[24.08.2016 10:59:59] <139652762142560> lpb    |     stepping   : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu MHz            : 2400.000
[24.08.2016 10:59:59] <139652762142560> lpb    |     cache size : 10240 KB
[24.08.2016 10:59:59] <139652762142560> lpb    |     physical id        : 1
[24.08.2016 10:59:59] <139652762142560> lpb    |     siblings   : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     core id            : 2
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu cores  : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     apicid             : 6
[24.08.2016 10:59:59] <139652762142560> lpb    |     initial apicid     : 6
[24.08.2016 10:59:59] <139652762142560> lpb    |     fpu                : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     fpu_exception      : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpuid level        : 13
[24.08.2016 10:59:59] <139652762142560> lpb    |     wp         : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     flags              : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts mmx fxsr sse sse2 ss ht syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts xtopology tsc_reliable nonstop_tsc aperfmperf unfair_spinlock pni pclmulqdq ssse3 cx16 sse4_1 sse4_2 popcnt aes xsave avx hypervisor lahf_lm arat xsaveopt pln pts dts
[24.08.2016 10:59:59] <139652762142560> lpb    |     bogomips   : 4800.00
[24.08.2016 10:59:59] <139652762142560> lpb    |     clflush size       : 64
[24.08.2016 10:59:59] <139652762142560> lpb    |     cache_alignment    : 64
[24.08.2016 10:59:59] <139652762142560> lpb    |     address sizes      : 40 bits physical, 48 bits virtual
[24.08.2016 10:59:59] <139652762142560> lpb    |     power management:
[24.08.2016 10:59:59] <139652762142560> lpb    |     processor  : 7
[24.08.2016 10:59:59] <139652762142560> lpb    |     vendor_id  : GenuineIntel
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu family : 6
[24.08.2016 10:59:59] <139652762142560> lpb    |     model              : 62
[24.08.2016 10:59:59] <139652762142560> lpb    |     model name : Intel(R) Xeon(R) CPU E5-2407 v2 @ 2.40GHz
[24.08.2016 10:59:59] <139652762142560> lpb    |     stepping   : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu MHz            : 2400.000
[24.08.2016 10:59:59] <139652762142560> lpb    |     cache size : 10240 KB
[24.08.2016 10:59:59] <139652762142560> lpb    |     physical id        : 1
[24.08.2016 10:59:59] <139652762142560> lpb    |     siblings   : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     core id            : 3
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpu cores  : 4
[24.08.2016 10:59:59] <139652762142560> lpb    |     apicid             : 7
[24.08.2016 10:59:59] <139652762142560> lpb    |     initial apicid     : 7
[24.08.2016 10:59:59] <139652762142560> lpb    |     fpu                : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     fpu_exception      : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     cpuid level        : 13
[24.08.2016 10:59:59] <139652762142560> lpb    |     wp         : yes
[24.08.2016 10:59:59] <139652762142560> lpb    |     flags              : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts mmx fxsr sse sse2 ss ht syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts xtopology tsc_reliable nonstop_tsc aperfmperf unfair_spinlock pni pclmulqdq ssse3 cx16 sse4_1 sse4_2 popcnt aes xsave avx hypervisor lahf_lm arat xsaveopt pln pts dts
[24.08.2016 10:59:59] <139652762142560> lpb    |     bogomips   : 4800.00
[24.08.2016 10:59:59] <139652762142560> lpb    |     clflush size       : 64
[24.08.2016 10:59:59] <139652762142560> lpb    |     cache_alignment    : 64
[24.08.2016 10:59:59] <139652762142560> lpb    |     address sizes      : 40 bits physical, 48 bits virtual
[24.08.2016 10:59:59] <139652762142560> lpb    |     power management:
[24.08.2016 10:59:59] <139652762142560> lpb    |   meminfo
[24.08.2016 10:59:59] <139652762142560> lpb    |     MemTotal:       12197836 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     MemFree:         5408848 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Buffers:           23588 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Cached:          6049432 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     SwapCached:         2320 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Active:          3687784 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Inactive:        2702056 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Active(anon):    3593604 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Inactive(anon):  1138640 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Active(file):      94180 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Inactive(file):  1563416 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Unevictable:           0 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Mlocked:               0 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     SwapTotal:      12582904 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     SwapFree:       12234040 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Dirty:                76 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Writeback:             0 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     AnonPages:        314892 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Mapped:          3470228 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Shmem:           4415420 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Slab:             170892 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     SReclaimable:     127808 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     SUnreclaim:        43084 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     KernelStack:        3032 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     PageTables:        99648 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     NFS_Unstable:          0 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Bounce:                0 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     WritebackTmp:          0 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     CommitLimit:    18681820 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     Committed_AS:    5819272 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     VmallocTotal:   34359738367 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     VmallocUsed:      174716 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     VmallocChunk:   34359560236 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     HardwareCorrupted:     0 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     AnonHugePages:         0 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     HugePages_Total:       0
[24.08.2016 10:59:59] <139652762142560> lpb    |     HugePages_Free:        0
[24.08.2016 10:59:59] <139652762142560> lpb    |     HugePages_Rsvd:        0
[24.08.2016 10:59:59] <139652762142560> lpb    |     HugePages_Surp:        0
[24.08.2016 10:59:59] <139652762142560> lpb    |     Hugepagesize:       2048 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     DirectMap4k:       10240 kB
[24.08.2016 10:59:59] <139652762142560> lpb    |     DirectMap2M:    12572672 kB
[24.08.2016 10:59:59] <139652762142560> lpb    | }
[24.08.2016 10:59:59] <139652762142560> lpbsvc | Main thread has started.
[24.08.2016 10:59:59] <139652762142560> lpbcore| SQL user version: 2.
[24.08.2016 10:59:59] <139652762142560> lpbcore| Cleaning orphaned sessions.
[24.08.2016 10:59:59] <139652762142560> lpbsvc | Starting server.
[24.08.2016 10:59:59] <139652759983872>        | Thread started. Thread id: 139652759983872, parent id: 139652762142560, role: VRPC server thread
[24.08.2016 10:59:59] <139652759983872> net    | Veeam RPC server started.
[24.08.2016 10:59:59] <139652759983872> net    | Selected vRPC port: '3500'.
[24.08.2016 10:59:59] <139652759983872> net    | Listening vRPC port '3500'.
[24.08.2016 10:59:59] <139652762142560> lpbsvc | Server started on port [3500].
[24.08.2016 11:00:31] <139652759983872> net    |   Accepted incoming vRPC connection from '127.0.0.1:44214'.
[24.08.2016 11:00:31] <139652673566464>        | Thread started. Thread id: 139652673566464, parent id: 139652759983872, role: Client processor thread (127.0.0.1:44214)
[24.08.2016 11:00:31] <139652673566464> net    | Client connected...
[24.08.2016 11:00:31] <139652673566464> net    | Received reconnect options: [disabled].
[24.08.2016 11:00:31] <139652673566464>        | Thread finished. Role: 'Client processor thread (127.0.0.1:44214)'.
[24.08.2016 11:00:31] <139652663076608>        | Thread started. Thread id: 139652663076608, parent id: 139652673566464, role: peer 127.0.0.1:44214
[24.08.2016 11:00:31] <139652663076608> lpbcore| Starting proxystub protocol dispatch loop.
[24.08.2016 11:00:31] <139652759983872> net    |   Accepted incoming vRPC connection from '127.0.0.1:44215'.
[24.08.2016 11:00:31] <139652652586752>        | Thread started. Thread id: 139652652586752, parent id: 139652759983872, role: Client processor thread (127.0.0.1:44215)
[24.08.2016 11:00:31] <139652652586752> net    | Client connected...
[24.08.2016 11:00:31] <139652652586752> net    | Received reconnect options: [disabled].
[24.08.2016 11:00:31] <139652652586752> lpbcore| Starting new LPB session.
[24.08.2016 11:00:31] <139652652586752> lpbcore| Starting new LPB session. ok.
[24.08.2016 11:00:31] <139652673566464>        | Thread started. Thread id: 139652673566464, parent id: 139652652586752, role: (async) LPB database session (127.0.0.1:44215)
[24.08.2016 11:00:31] <139652652586752>        | Thread finished. Role: 'Client processor thread (127.0.0.1:44215)'.
[24.08.2016 11:00:31] <139652759983872> net    |   Accepted incoming vRPC connection from '127.0.0.1:44216'.
[24.08.2016 11:00:31] <139652642096896>        | Thread started. Thread id: 139652642096896, parent id: 139652759983872, role: Client processor thread (127.0.0.1:44216)
[24.08.2016 11:00:31] <139652642096896> net    | Client connected...
[24.08.2016 11:00:31] <139652642096896> net    | Received reconnect options: [disabled].
[24.08.2016 11:00:31] <139652642096896> lpbcore| Starting new LPB session.
[24.08.2016 11:00:31] <139652642096896> lpbcore| Starting new LPB session. ok.
[24.08.2016 11:00:31] <139652642096896>        | Thread finished. Role: 'Client processor thread (127.0.0.1:44216)'.
[24.08.2016 11:00:31] <139652652586752>        | Thread started. Thread id: 139652652586752, parent id: 139652642096896, role: (async) LPB database session (127.0.0.1:44216)
[24.08.2016 11:00:31] <139652759983872> net    |   Accepted incoming vRPC connection from '127.0.0.1:44217'.
[24.08.2016 11:00:31] <139652631607040>        | Thread started. Thread id: 139652631607040, parent id: 139652759983872, role: Client processor thread (127.0.0.1:44217)
[24.08.2016 11:00:31] <139652631607040> net    | Client connected...
[24.08.2016 11:00:31] <139652631607040> net    | Received reconnect options: [disabled].
[24.08.2016 11:00:31] <139652631607040> lpbcore| Starting new LPB session.
[24.08.2016 11:00:31] <139652631607040> lpbcore| Starting new LPB session. ok.
[24.08.2016 11:00:31] <139652631607040>        | Thread finished. Role: 'Client processor thread (127.0.0.1:44217)'.
[24.08.2016 11:00:31] <139652642096896>        | Thread started. Thread id: 139652642096896, parent id: 139652631607040, role: (async) LPBConfig session (127.0.0.1:44217)
[24.08.2016 11:00:31] <139652642096896> lpbcore| LpbCfgSession: Tcp loop.
[24.08.2016 11:00:34] <139652631607040> cli    | Thread [JobManManagerInspector].
[24.08.2016 11:00:34] <139652621117184> cli    | Thread [JobManManagerTerminator].
[24.08.2016 11:00:34] <139652663076608> lpbcore|   Job execution service: starting worker (manager) process.
[24.08.2016 11:00:34] <139652663076608> lpbcore|     Starting manager process. Session UUID: [{6b001b25-7bf9-426a-b3af-9fc4f409ac52}]. Logs path: [/var/log/veeam/Backup/Oracle_BackupJob/Session_{6b001b25-7bf9-426a-b3af-9fc4f409ac52}/Job.log]
[24.08.2016 11:00:34] <139652663076608> lpbcore|     JobMan has started. PID: [11567].
[24.08.2016 11:00:34] <139652663076608> lpbcore|     Manager started with PID [11567]. Waiting connection.
[24.08.2016 11:00:34] <139652270913280>        | Thread started. Thread id: 139652270913280, parent id: 139652663076608, role: Manager process [11567] shutdown handler.
[24.08.2016 11:00:35] <139652759983872> net    |   Accepted incoming vRPC connection from '127.0.0.1:44218'.
[24.08.2016 11:00:35] <139652260423424>        | Thread started. Thread id: 139652260423424, parent id: 139652759983872, role: Client processor thread (127.0.0.1:44218)
[24.08.2016 11:00:35] <139652260423424> net    | Client connected...
[24.08.2016 11:00:35] <139652260423424> net    | Received reconnect options: [disabled].
[24.08.2016 11:00:35] <139652249933568>        | Thread started. Thread id: 139652249933568, parent id: 139652260423424, role: peer 127.0.0.1:44218
[24.08.2016 11:00:35] <139652260423424>        | Thread finished. Role: 'Client processor thread (127.0.0.1:44218)'.
[24.08.2016 11:00:35] <139652249933568> lpbcore| Starting proxystub protocol dispatch loop.
[24.08.2016 11:00:35] <139652759983872> net    |   Accepted incoming vRPC connection from '127.0.0.1:44219'.
[24.08.2016 11:00:35] <139652239443712>        | Thread started. Thread id: 139652239443712, parent id: 139652759983872, role: Client processor thread (127.0.0.1:44219)
[24.08.2016 11:00:35] <139652239443712> net    | Client connected...
[24.08.2016 11:00:35] <139652239443712> net    | Received reconnect options: [disabled].
[24.08.2016 11:00:35] <139652239443712> lpbcore| Starting new LPB session.
[24.08.2016 11:00:35] <139652239443712> lpbcore| Starting new LPB session. ok.
[24.08.2016 11:00:35] <139652239443712>        | Thread finished. Role: 'Client processor thread (127.0.0.1:44219)'.
[24.08.2016 11:00:35] <139652260423424>        | Thread started. Thread id: 139652260423424, parent id: 139652239443712, role: (async) LPB database session (127.0.0.1:44219)
[24.08.2016 11:00:35] <139652759983872> net    |   Accepted incoming vRPC connection from '127.0.0.1:44220'.
[24.08.2016 11:00:35] <139652228953856>        | Thread started. Thread id: 139652228953856, parent id: 139652759983872, role: Client processor thread (127.0.0.1:44220)
[24.08.2016 11:00:35] <139652228953856> net    | Client connected...
[24.08.2016 11:00:35] <139652228953856> net    | Received reconnect options: [disabled].
[24.08.2016 11:00:35] <139652228953856>        | Thread finished. Role: 'Client processor thread (127.0.0.1:44220)'.
[24.08.2016 11:00:35] <139652663076608> lpbcore|   Sending command [StartBackupJob] to manager with PID [11567]. Job ID: [11567]
[24.08.2016 11:00:35] <139652759983872> net    |   Accepted incoming vRPC connection from '127.0.0.1:44221'.
[24.08.2016 11:00:35] <139652239443712>        | Thread started. Thread id: 139652239443712, parent id: 139652759983872, role: Client processor thread (127.0.0.1:44221)
[24.08.2016 11:00:35] <139652239443712> net    | Client connected...
[24.08.2016 11:00:35] <139652239443712> net    | Received reconnect options: [disabled].
[24.08.2016 11:00:35] <139652239443712> lpbcore| Starting new LPB session.
[24.08.2016 11:00:35] <139652239443712> lpbcore| Starting new LPB session. ok.
[24.08.2016 11:00:35] <139652239443712>        | Thread finished. Role: 'Client processor thread (127.0.0.1:44221)'.
[24.08.2016 11:00:35] <139652228953856>        | Thread started. Thread id: 139652228953856, parent id: 139652239443712, role: (async) LPB database session (127.0.0.1:44221)
[24.08.2016 11:00:35] <139652759983872> net    |   Accepted incoming vRPC connection from '127.0.0.1:44222'.
[24.08.2016 11:00:35] <139652218464000>        | Thread started. Thread id: 139652218464000, parent id: 139652759983872, role: Client processor thread (127.0.0.1:44222)
[24.08.2016 11:00:35] <139652218464000> net    | Client connected...
[24.08.2016 11:00:35] <139652218464000> net    | Received reconnect options: [disabled].
[24.08.2016 11:00:35] <139652218464000> lpbcore| Starting new LPB session.
[24.08.2016 11:00:35] <139652218464000> lpbcore| Starting new LPB session. ok.
[24.08.2016 11:00:35] <139652218464000>        | Thread finished. Role: 'Client processor thread (127.0.0.1:44222)'.
[24.08.2016 11:00:35] <139652239443712>        | Thread started. Thread id: 139652239443712, parent id: 139652218464000, role: (async) LPB database session (127.0.0.1:44222)
[24.08.2016 11:00:35] <139652249933568> lpbcore|   Executing custom script: [mount]. Arguments: [-t nfs -o rw,soft,timeo=100 srv-ad:/F /tmp/veeam/srv-ad:F]
[24.08.2016 11:00:35] <139651790661376>        | Thread started. Thread id: 139651790661376, parent id: 139652249933568, role: script error accum
[24.08.2016 11:00:35] <139651801151232>        | Thread started. Thread id: 139651801151232, parent id: 139652249933568, role: script output redirector
[24.08.2016 11:00:40] <139651801151232>        | Thread finished. Role: 'script output redirector'.
[24.08.2016 11:00:40] <139651790661376>        | Thread finished. Role: 'script error accum'.
[24.08.2016 11:00:40] <139652249933568> lpbcore|   Executing custom script: [mount]. Arguments: [-t nfs -o rw,soft,timeo=100 srv-ad:/F /tmp/veeam/srv-ad:F] ok.
[24.08.2016 11:00:41] <139652249933568> lpbcore|   Snapshot service: creating snapshot.
[24.08.2016 11:00:41] <139652249933568> lpbcore| ERR |No medium found
[24.08.2016 11:00:41] <139652249933568> lpbcore| >>  |Failed to probe device [/dev/sr0].
[24.08.2016 11:00:41] <139652249933568> lpbcore| >>  |An exception was thrown from thread [1388304128].
[24.08.2016 11:00:41] <139652249933568> lpbcore|   Enumerating LVM volume groups...
[24.08.2016 11:00:41] <139652249933568> lpbcore|     LVM volume group: [vg_oracle].
[24.08.2016 11:00:41] <139652249933568> lpbcore|     Enumerating logical volumes for LVM volume group: [vg_oracle].
[24.08.2016 11:00:41] <139652249933568> lpbcore|     LVM volume group: [vg_srvoracle].
[24.08.2016 11:00:41] <139652249933568> lpbcore|     Enumerating logical volumes for LVM volume group: [vg_srvoracle].
[24.08.2016 11:00:41] <139652249933568> lpbcore|   [2] LVM volume groups were detected.
[24.08.2016 11:00:41] <139652249933568> vsnap  |   Checking whether veeamsnap kernel module is loaded.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     Module load information: veeamsnap             241881  0 .
[24.08.2016 11:00:41] <139652249933568> vsnap  |   Module is loaded.
[24.08.2016 11:00:41] <139652249933568> vsnap  |   Obtaining driver major number.
[24.08.2016 11:00:41] <139652249933568> vsnap  |   Major number is [248].
[24.08.2016 11:00:41] <139652249933568> vsnap  |   Opening VeeamSnap control.
[24.08.2016 11:00:41] <139652249933568> vsnap  |   Obtaining disk tracking information.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     arg=0x000x7f0352bfc4b0
[24.08.2016 11:00:41] <139652249933568> vsnap  |     size=12
[24.08.2016 11:00:41] <139652249933568> vsnap  |     Disks under tracking: 7 devices.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     dev id: 8:1.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     snapNumber: 1.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     cbtMapSize: 2000.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     GenerationId: {c23e9fe3-c8c4-13be-0e9b-879764eb80d8}
[24.08.2016 11:00:41] <139652249933568> vsnap  |     dev id: 253:2.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     snapNumber: 1.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     cbtMapSize: 1228768.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     GenerationId: {e9339d69-95fb-32ae-6f9a-59b9a4a60690}
[24.08.2016 11:00:41] <139652249933568> vsnap  |     dev id: 253:0.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     snapNumber: 1.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     cbtMapSize: 63520.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     GenerationId: {d8a63e97-72ac-cd95-7ec7-ad1200c4b934}
[24.08.2016 11:00:41] <139652249933568> vsnap  |     dev id: 253:1.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     snapNumber: 1.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     cbtMapSize: 49152.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     GenerationId: {4ed105b8-7ddc-f2df-1652-bb2afa368e84}
[24.08.2016 11:00:41] <139652249933568> vsnap  |     dev id: 253:3.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     snapNumber: 1.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     cbtMapSize: 16384.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     GenerationId: {16d226c7-ae1c-ed66-8a42-f76dcbc336dc}
[24.08.2016 11:00:41] <139652249933568> vsnap  |     dev id: 253:4.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     snapNumber: 1.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     cbtMapSize: 32768.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     GenerationId: {9cbab6b9-95f0-9e4e-0be2-37904bbcfa74}
[24.08.2016 11:00:41] <139652249933568> vsnap  |     dev id: 253:5.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     snapNumber: 1.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     cbtMapSize: 40960.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     GenerationId: {34e5c8d8-d4cd-da10-9f51-7a85bb5a50e5}
[24.08.2016 11:00:41] <139652249933568> lpbcore|   Creating machine snapshot using VeeamSnap kernel module.
[24.08.2016 11:00:41] <139652249933568> lpbcore|     Adding device [8:1] to snapshot set.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     Adding device [8:1] under tracking.
[24.08.2016 11:00:41] <139652249933568> vsnap  | WARN|Re-adding tracking for device [8:1].
[24.08.2016 11:00:41] <139652249933568> lpbcore|     Adding device [253:2] to snapshot set.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     Adding device [253:2] under tracking.
[24.08.2016 11:00:41] <139652249933568> vsnap  | WARN|Re-adding tracking for device [253:2].
[24.08.2016 11:00:41] <139652249933568> lpbcore|     Adding device [253:0] to snapshot set.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     Adding device [253:0] under tracking.
[24.08.2016 11:00:41] <139652249933568> vsnap  | WARN|Re-adding tracking for device [253:0].
[24.08.2016 11:00:41] <139652249933568> lpbcore|     Adding device [253:1] to snapshot set.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     Adding device [253:1] under tracking.
[24.08.2016 11:00:41] <139652249933568> vsnap  | WARN|Re-adding tracking for device [253:1].
[24.08.2016 11:00:41] <139652249933568> lpbcore|     Adding device [253:3] to snapshot set.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     Adding device [253:3] under tracking.
[24.08.2016 11:00:41] <139652249933568> vsnap  | WARN|Re-adding tracking for device [253:3].
[24.08.2016 11:00:41] <139652249933568> lpbcore|     Adding device [253:4] to snapshot set.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     Adding device [253:4] under tracking.
[24.08.2016 11:00:41] <139652249933568> vsnap  | WARN|Re-adding tracking for device [253:4].
[24.08.2016 11:00:41] <139652249933568> lpbcore|     Adding device [253:5] to snapshot set.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     Adding device [253:5] under tracking.
[24.08.2016 11:00:41] <139652249933568> vsnap  | WARN|Re-adding tracking for device [253:5].
[24.08.2016 11:00:41] <139652249933568> lpbcore|     Detecting snapshot storage parameters.
[24.08.2016 11:00:41] <139652249933568> lpbcore|       Calculating required snapshot storage size.
[24.08.2016 11:00:41] <139652249933568> lpbcore|         Snapshot storage parameters: free ratio [0.1], used ratio [0.05], minimum size [536870912], maximum size [21474836480].
[24.08.2016 11:00:41] <139652249933568> lpbcore|         Total used space (volumes in snapshot): [236010097664].
[24.08.2016 11:00:41] <139652249933568> lpbcore|         Total free space (volumes in snapshot): [121198908416].
[24.08.2016 11:00:41] <139652249933568> lpbcore|       Required snapshot storage size: [11800505344].
[24.08.2016 11:00:41] <139652249933568> lpbcore|       Searching for the best candidate to store snapshot data.
[24.08.2016 11:00:41] <139652249933568> lpbcore|         Found supported filesystem on device [/dev/sda1] mounted at [/boot]. FS type: [ext4]. Free space: [386827264] bytes.
[24.08.2016 11:00:41] <139652249933568> lpbcore|         Found supported filesystem on device [/dev/dm-2] mounted at [/u01]. FS type: [ext3]. Free space: [96632139776] bytes.
[24.08.2016 11:00:41] <139652249933568> lpbcore|         Found supported filesystem on device [/dev/dm-0] mounted at [/]. FS type: [ext4]. Free space: [5362315264] bytes.
[24.08.2016 11:00:41] <139652249933568> lpbcore|         Found supported filesystem on device [/dev/dm-3] mounted at [/tmp]. FS type: [ext4]. Free space: [3870126080] bytes.
[24.08.2016 11:00:41] <139652249933568> lpbcore|         Found supported filesystem on device [/dev/dm-4] mounted at [/var]. FS type: [ext4]. Free space: [6917922816] bytes.
[24.08.2016 11:00:41] <139652249933568> lpbcore|         Found supported filesystem on device [/dev/dm-5] mounted at [/usr]. FS type: [ext4]. Free space: [8029577216] bytes.
[24.08.2016 11:00:41] <139652249933568> lpbcore|     Snapshot data will be stored in [/u01] on device [/dev/dm-2]. Storage size: [11800505344].
[24.08.2016 11:00:41] <139652249933568> vsnap  |     Opening VeeamSnap control.
[24.08.2016 11:00:41] <139652249933568> vsnap  |     Checking common snapshot data file exist. Data host device [/dev/dm-2], file [/u01/veeamsnapshotdata.0], size 11800506368.
[24.08.2016 11:00:41] <139652249933568> vsnap  |       Snapshot control: creating snapshot data file
[24.08.2016 11:00:41] <139652249933568> vsnap  |         Snapshot device 253:2
[24.08.2016 11:00:41] <139652249933568> vsnap  |         Initializing snapshot allocation algorithms for '/dev/dm-2'
[24.08.2016 11:00:41] <139652249933568> lpbcore| ERR |No medium found
[24.08.2016 11:00:41] <139652249933568> lpbcore| >>  |Failed to probe device [/dev/sr0].
[24.08.2016 11:00:41] <139652249933568> lpbcore| >>  |An exception was thrown from thread [1388304128].
[24.08.2016 11:00:41] <139652249933568> lpbcore|           Enumerating LVM volume groups...
[24.08.2016 11:00:41] <139652249933568> lpbcore|             LVM volume group: [vg_oracle].
[24.08.2016 11:00:41] <139652249933568> lpbcore|             Enumerating logical volumes for LVM volume group: [vg_oracle].
[24.08.2016 11:00:41] <139652249933568> lpbcore|             LVM volume group: [vg_srvoracle].
[24.08.2016 11:00:41] <139652249933568> lpbcore|             Enumerating logical volumes for LVM volume group: [vg_srvoracle].
[24.08.2016 11:00:41] <139652249933568> lpbcore|           [2] LVM volume groups were detected.
[24.08.2016 11:00:41] <139652249933568> extfs  |           Super block reading.
[24.08.2016 11:00:41] <139652249933568> extfs  |           Super block was read.
[24.08.2016 11:00:41] <139652249933568> vsnap  |         Supported algorithms: 4
[24.08.2016 11:00:41] <139652249933568> vsnap  |         Creating snapshot file (path: /u01/veeamsnapshotdata.0, length: 11800506368)
[24.08.2016 11:00:41] <139652249933568> vsnap  |           Initializing snapshot file with 'fiemap' (flags: 1)
[24.08.2016 11:00:41] <139652249933568> vsnap  |           Allocating new snapshot file (path: /u01/veeamsnapshotdata.0, size: 11800506368)
[24.08.2016 11:00:41] <139652249933568> vsnap  |           Snapshot data clean.
[24.08.2016 11:00:41] <139652249933568> vsnap  |             devId: 0:0.
[24.08.2016 11:00:41] <139652249933568> vsnap  |             Snapshot data already cleaned for device=0:0.
[24.08.2016 11:00:50] <139652249933568> vsnap  |           Allocating snapshot file (path: /u01/veeamsnapshotdata.0, size: 11800506368)
[24.08.2016 11:00:50] <139652249933568>        | WARN|Unable to preallocate disk using vmkfstools v4.
[24.08.2016 11:00:50] <139652249933568>        | WARN|Unable to preallocate disk space using ioctl.
[24.08.2016 11:00:50] <139652249933568>        |             [11,800,506,368] bytes were preallocated for the file [/u01/veeamsnapshotdata.0].
[24.08.2016 11:00:50] <139652249933568> vsnap  |             1%
...
[24.08.2016 11:03:57] <139652249933568> vsnap  |             100%
[24.08.2016 11:04:41] <139652249933568> vsnap  |           Allocating snapshot file (path: /u01/veeamsnapshotdata.0, size: 11800506368) ok.
[24.08.2016 11:04:42] <139652249933568> vsnap  |           Validating snapshotdata file content (size: 11800506368, extents: 373550)
[24.08.2016 11:04:42] <139652249933568> vsnap  |             Opening direct device.
[24.08.2016 11:04:44] <139652249933568> vsnap  |             1%
...
[24.08.2016 11:06:31] <139652249933568> vsnap  |             100%
[24.08.2016 11:06:31] <139652249933568> vsnap  |             Closing direct device.
[24.08.2016 11:06:31] <139652249933568> vsnap  |           Validating snapshotdata file content (size: 11800506368, extents: 373550) ok.
[24.08.2016 11:06:31] <139652249933568> vsnap  |         Snapshot file successfully created with 'fiemap'
[24.08.2016 11:06:31] <139652249933568> vsnap  |       Snapshot device 253:2
[24.08.2016 11:06:31] <139652249933568> vsnap  |       Try to make snapshot data info for all devices on snapshot device 253:2.
[24.08.2016 11:06:31] <139652249933568> vsnap  |     Checking common snapshot data file exist. Data host device [/dev/dm-2], file [/u01/veeamsnapshotdata.0], size 11800506368. Failed.
[24.08.2016 11:06:31] <139652249933568> vsnap  |     Closing VeeamSnap control.
[24.08.2016 11:06:31] <139652249933568> lpbcore|   Creating machine snapshot using VeeamSnap kernel module. Failed.
[24.08.2016 11:06:31] <139652249933568> vsnap  |   Opening VeeamSnap control.
[24.08.2016 11:06:31] <139652249933568> vsnap  |   Closing VeeamSnap control.
[24.08.2016 11:06:31] <139652249933568> vsnap  |   Closing VeeamSnap control.
[24.08.2016 11:06:31] <139652249933568> lpbcore| ERR |Cannot allocate memory
[24.08.2016 11:06:31] <139652249933568> lpbcore| >>  |Failed to execute IOCTL_SNAPSHOT_COMMON_DATAINFO.
[24.08.2016 11:06:31] <139652249933568> lpbcore| >>  |--tr:SnapshotDataMakeCommonFile failed.
[24.08.2016 11:06:31] <139652249933568> lpbcore| >>  |--tr:CreateSnapshotStorageCommon failed.
[24.08.2016 11:06:31] <139652249933568> lpbcore| >>  |--tr:Failed to create machine snapshot.
[24.08.2016 11:06:31] <139652249933568> lpbcore| >>  |--tr:Unable to create snapshot for session [{6b001b25-7bf9-426a-b3af-9fc4f409ac52}].
[24.08.2016 11:06:31] <139652249933568> lpbcore| >>  |--tr:Failed to execute method [0] for class [N10lpbcorelib11interaction9proxystub21CResourcesServiceStubE].
[24.08.2016 11:06:31] <139652249933568> lpbcore| >>  |An exception was thrown from thread [1388304128].
[24.08.2016 11:06:33] <139652759983872> net    |   Accepted incoming vRPC connection from '127.0.0.1:44367'.
[24.08.2016 11:06:33] <139651801151232>        | Thread started. Thread id: 139651801151232, parent id: 139652759983872, role: Client processor thread (127.0.0.1:44367)
[24.08.2016 11:06:33] <139651801151232> net    | Client connected...
[24.08.2016 11:06:33] <139651801151232> net    | Received reconnect options: [disabled].
[24.08.2016 11:06:33] <139651801151232> lpbcore| Starting new LPB session.
[24.08.2016 11:06:33] <139651801151232> lpbcore| Starting new LPB session. ok.
[24.08.2016 11:06:33] <139651801151232>        | Thread finished. Role: 'Client processor thread (127.0.0.1:44367)'.
[24.08.2016 11:06:33] <139651790661376>        | Thread started. Thread id: 139651790661376, parent id: 139651801151232, role: (async) LPBConfig session (127.0.0.1:44367)
[24.08.2016 11:06:33] <139651790661376> lpbcore| LpbCfgSession: Tcp loop.
[24.08.2016 11:06:33] <139651790661376> lpbcore|   LpbCfgSession: Session is finished.
[24.08.2016 11:06:33] <139651790661376> lpbcore|     Session ID: [{6b001b25-7bf9-426a-b3af-9fc4f409ac52}].
[24.08.2016 11:06:33] <139651790661376> lpbcore|     LpbCfgSession: Finding Manager [{6b001b25-7bf9-426a-b3af-9fc4f409ac52}].
[24.08.2016 11:06:33] <139651790661376> lpbcore|       Manager [{6b001b25-7bf9-426a-b3af-9fc4f409ac52}] is found and active.
[24.08.2016 11:06:33] <139651790661376> lpbcore|     LpbCfgSession: Finding Manager [{6b001b25-7bf9-426a-b3af-9fc4f409ac52}]. ok.
[24.08.2016 11:06:33] <139651790661376> lpbcore|   LpbCfgSession: Session is finished. ok.
[24.08.2016 11:06:33] <139651790661376> lpbcore|   LpbCfgSession: Disconnecting.
[24.08.2016 11:06:33] <139651790661376>        |     Closing socket device.
[24.08.2016 11:06:33] <139651790661376> lpbcore|   LpbCfgSession: Disconnecting. ok.
[24.08.2016 11:06:33] <139651790661376> lpbcore| LpbCfgSession: Tcp loop. ok.
[24.08.2016 11:06:33] <139651790661376>        | Thread finished. Role: '(async) LPBConfig session (127.0.0.1:44367)'.
[24.08.2016 11:06:33] <139652228953856>        | Closing socket device.
[24.08.2016 11:06:33] <139652239443712>        | Closing socket device.
[24.08.2016 11:06:33] <139652228953856>        | Thread finished. Role: '(async) LPB database session (127.0.0.1:44221)'.
[24.08.2016 11:06:33] <139652239443712>        | Thread finished. Role: '(async) LPB database session (127.0.0.1:44222)'.
[24.08.2016 11:06:33] <139652260423424>        | Closing socket device.
[24.08.2016 11:06:33] <139652260423424>        | Thread finished. Role: '(async) LPB database session (127.0.0.1:44219)'.
[24.08.2016 11:06:33] <139652249933568> lpbcore| Starting proxystub protocol dispatch loop. ok.
[24.08.2016 11:06:33] <139652249933568>        | Closing socket device.
[24.08.2016 11:06:33] <139652249933568>        | Thread finished. Role: 'peer 127.0.0.1:44218'.
[24.08.2016 11:06:33] <139652270913280> lpbcore| Executing custom script: [umount]. Arguments: [-l /tmp/veeam/srv-ad:F]
[24.08.2016 11:06:33] <139651780171520>        | Thread started. Thread id: 139651780171520, parent id: 139652270913280, role: script error accum
[24.08.2016 11:06:33] <139652218464000>        | Thread started. Thread id: 139652218464000, parent id: 139652270913280, role: script output redirector
[24.08.2016 11:06:33] <139652218464000>        | Thread finished. Role: 'script output redirector'.
[24.08.2016 11:06:33] <139651780171520>        | Thread finished. Role: 'script error accum'.
[24.08.2016 11:06:33] <139652270913280> lpbcore| Executing custom script: [umount]. Arguments: [-l /tmp/veeam/srv-ad:F] ok.
[24.08.2016 11:06:33] <139652270913280> lpbcore| Manager process [11567] has been shutdown.
[24.08.2016 11:06:33] <139652270913280>        | Thread finished. Role: 'Manager process [11567] shutdown handler.'.
[24.08.2016 11:06:34] <139652631607040> lpbcore|   Job manager process with PID [11567] is terminating.
[24.08.2016 11:07:04] <139652621117184> lpbcore|   Terminating job manager process with PID [11567].
[24.08.2016 11:07:04] <139652621117184>        |   Closing socket device.
[24.08.2016 11:07:57] <139652663076608> lpbcore| Starting proxystub protocol dispatch loop. ok.
[24.08.2016 11:07:57] <139652663076608>        | Closing socket device.
[24.08.2016 11:07:57] <139652663076608>        | Thread finished. Role: 'peer 127.0.0.1:44214'.
[24.08.2016 11:07:57] <139652673566464>        | Closing socket device.
[24.08.2016 11:07:57] <139652673566464>        | Thread finished. Role: '(async) LPB database session (127.0.0.1:44215)'.
[24.08.2016 11:07:58] <139652642096896> lpbcore| LpbCfgSession: Tcp loop. Failed.
[24.08.2016 11:07:58] <139652642096896> lpbcore| ERR |LpbCfgSession failed.
[24.08.2016 11:07:58] <139652642096896> lpbcore| >>  |read: End of file
[24.08.2016 11:07:58] <139652642096896> lpbcore| >>  |--tr:Cannot read data from the socket. Requested data size: [4].
[24.08.2016 11:07:58] <139652642096896> lpbcore| >>  |An exception was thrown from thread [1780467456].
[24.08.2016 11:07:58] <139652642096896>        | Thread finished. Role: '(async) LPBConfig session (127.0.0.1:44217)'.
[24.08.2016 11:07:58] <139652652586752> lpbcore| ERR |LpbDbSession failed.
[24.08.2016 11:07:58] <139652652586752> lpbcore| >>  |read: End of file
[24.08.2016 11:07:58] <139652652586752> lpbcore| >>  |--tr:Cannot read data from the socket. Requested data size: [4].
[24.08.2016 11:07:58] <139652652586752> lpbcore| >>  |An exception was thrown from thread [1790957312].
[24.08.2016 11:07:58] <139652652586752>        | Thread finished. Role: '(async) LPB database session (127.0.0.1:44216)'.
------------------------------------------------------

lsblk -af

Code: Select all

------------------------------------------------------
NAME   FSTYPE      LABEL UUID                                   MOUNTPOINT
loop0
loop1
loop2
loop3
loop4
loop5
loop6
loop7
sr0
sda
ââsda1 ext4              e94c4161-a826-4144-b16b-16249b27fa1e   /boot
ââsda2 LVM2_member       BoXkRU-m2rP-Ql5f-dHN4-nPiN-fj6W-PJH8Dw
  ââvg_srvoracle-lv_root (dm-0)
       ext4              ee927726-e028-48ee-ba0f-91e4fadc210b   /
  ââvg_srvoracle-lv_swap (dm-1)
       swap              5a543545-8ad1-4083-b646-a0e12e27a742   [SWAP]
  ââvg_srvoracle-lv_tmp (dm-3)
       ext4              1865774c-c698-4e98-ae64-9611f796fc7b   /tmp
  ââvg_srvoracle-lv_var (dm-4)
       ext4              b4c51ba9-bd74-4e93-902a-7cfaa5d61893   /var
  ââvg_srvoracle-lv_usr (dm-5)
       ext4              3713c457-78d1-40ed-a57b-2a179cb64864   /usr
sdb
ââsdb1 LVM2_member       NrA6wR-40m3-qctx-joS0-BBds-jsRD-9r0xBw
  ââvg_oracle-lv_oracle (dm-2)
       ext3              90e149ba-11ee-4800-a97b-5539c7c0ffea   /u01
--------------------------------------------
fdsik -l

Code: Select all

--------------------------------------------
Disk /dev/sda: 53.7 GB, 53687091200 bytes
255 heads, 63 sectors/track, 6527 cylinders
Units = cylinders of 16065 * 512 = 8225280 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk identifier: 0x00086b63

   Device Boot      Start         End      Blocks   Id  System
/dev/sda1   *           1          64      512000   83  Linux
Partition 1 does not end on cylinder boundary.
/dev/sda2              64        6528    51915776   8e  Linux LVM

Disk /dev/sdb: 322.1 GB, 322122547200 bytes
255 heads, 63 sectors/track, 39162 cylinders
Units = cylinders of 16065 * 512 = 8225280 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk identifier: 0x7b6dc55a

   Device Boot      Start         End      Blocks   Id  System
/dev/sdb1               1       39162   314568733+  8e  Linux LVM

Disk /dev/mapper/vg_srvoracle-lv_root: 16.7 GB, 16651386880 bytes
255 heads, 63 sectors/track, 2024 cylinders
Units = cylinders of 16065 * 512 = 8225280 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk identifier: 0x00000000


Disk /dev/mapper/vg_srvoracle-lv_swap: 12.9 GB, 12884901888 bytes
255 heads, 63 sectors/track, 1566 cylinders
Units = cylinders of 16065 * 512 = 8225280 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk identifier: 0x00000000


Disk /dev/mapper/vg_oracle-lv_oracle: 322.1 GB, 322114158592 bytes
255 heads, 63 sectors/track, 39161 cylinders
Units = cylinders of 16065 * 512 = 8225280 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk identifier: 0x00000000


Disk /dev/mapper/vg_srvoracle-lv_tmp: 4294 MB, 4294967296 bytes
255 heads, 63 sectors/track, 522 cylinders
Units = cylinders of 16065 * 512 = 8225280 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk identifier: 0x00000000


Disk /dev/mapper/vg_srvoracle-lv_var: 8589 MB, 8589934592 bytes
255 heads, 63 sectors/track, 1044 cylinders
Units = cylinders of 16065 * 512 = 8225280 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk identifier: 0x00000000


Disk /dev/mapper/vg_srvoracle-lv_usr: 10.7 GB, 10737418240 bytes
255 heads, 63 sectors/track, 1305 cylinders
Units = cylinders of 16065 * 512 = 8225280 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk identifier: 0x00000000
-----------------------------------------------------
The dmseg result is too long, so it´s not here.

Thanks.
delmarthome
Novice
Posts: 7
Liked: 2 times
Joined: Aug 23, 2016 8:40 pm
Full Name: Delmar Jose Thome
Contact:

Re: Cannot allocate memory

Post by delmarthome »

Hi PTide,

Any news about this problem?
nielsengelen
Product Manager
Posts: 5619
Liked: 1177 times
Joined: Jul 15, 2013 11:09 am
Full Name: Niels Engelen
Contact:

Re: Cannot allocate memory

Post by nielsengelen »

BETA2 is now available via http://www.veeam.com/beta/val

Please install that one (remove the previous one) and try again to see if the issue has been resolved.
Personal blog: https://foonet.be
GitHub: https://github.com/nielsengelen
delmarthome
Novice
Posts: 7
Liked: 2 times
Joined: Aug 23, 2016 8:40 pm
Full Name: Delmar Jose Thome
Contact:

Re: Cannot allocate memory

Post by delmarthome » 1 person likes this post

Hi everybody,

I think the problem was solved on Beta 2.

Thanks!
Post Reply

Who is online

Users browsing this forum: No registered users and 13 guests