PDA

View Full Version : Windows Client Passes AMCHECK But...



DocSavage
February 21st, 2007, 01:21 AM
I have set up amanda on our Fedora Core 6 machine and am able to back up correctly on there. I installed the Windows Client on our 2003 server and it passes the amcheck. I thought I had achieved success, however when I try to do an amdump i get a timeout on trying to estimate the size of the dump on the windows server and nothing gets written to the vtape from the windows server. Has anyone run across this type of behaviour?

-Andy

P.S. Here is what amdump is returning...


galinda.qdtechnology.com /cygdrive/c/amanda_pickup lev 0 FAILED [disk /cygdrive/c/amanda_pickup, all estimate timed out]
planner: ERROR Request to galinda.qdtechnology.com failed: timeout waiting for REP

paddy
February 21st, 2007, 10:04 AM
What version of Windows client are you using?

Please check sendsize logs on the Windows client directory C:/Cygwin/tmp/amanda/

Please post the contents of sendsize logs if you need help in figuring out the problem.

Thanks,
Paddy

DocSavage
February 22nd, 2007, 03:18 AM
Here is the sendsize debug file. I see there is an error about an illegal seek - but i dont know how to interpret it. It appears that gnutar gets the correct files sizes....


endsize: debug 1 pid 5284 ruid 3199 euid 3199: start at Thu Feb 22 00:45:05 2007
sendsize: version 2.5.1p3
Reading conf file "/etc/amanda/amanda-client.conf".
Reading conf file "/etc/amanda/DailySet1/amanda-client.conf".
sendsize: debug 1 pid 5284 ruid 3199 euid 3199: rename at Thu Feb 22 00:45:05 2007
sendsize[5284]: time 0.218: waiting for any estimate child: 1 running
sendsize[3480]: time 0.250: calculating for amname /cygdrive/c/amanda_pickup, dirname /cygdrive/c/amanda_pickup, spindle -1
sendsize[3480]: time 0.250: getting size via gnutar for /cygdrive/c/amanda_pickup level 0
sendsize[3480]: time 0.265: spawning /usr/lib/amanda/runtar in pipeline
sendsize[3480]: argument list: runtar DailySet1 /bin/tar.exe --create --file /dev/null --directory /cygdrive/c/amanda_pickup --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/galinda.qdtechnology.com_cygdrive_c_amanda_pickup_ 0.new --sparse --ignore-failed-read --totals .
sendsize[3480]: time 5880.021: Total bytes written: 5678561280 (5.3GiB, 944KiB/s)
sendsize[3480]: time 5880.036: .....
sendsize[3480]: estimate time for /cygdrive/c/amanda_pickup level 0: 5879.771
sendsize[3480]: estimate size for /cygdrive/c/amanda_pickup level 0: 5545470 KB
sendsize[3480]: time 5880.068: waiting for runtar "/cygdrive/c/amanda_pickup" child
sendsize[3480]: time 5880.177: after runtar /cygdrive/c/amanda_pickup wait
gnutar_calc_estimates: warning - seek failed: Illegal seek
sendsize[3480]: time 5880.177: getting size via gnutar for /cygdrive/c/amanda_pickup level 1
sendsize[3480]: gnutar: error opening /var/lib/amanda/gnutar-lists/galinda.qdtechnology.com_cygdrive_c_amanda_pickup_ 0: No such file or directory
sendsize[3480]: time 5880.177: spawning /usr/lib/amanda/runtar in pipeline
sendsize[3480]: argument list: runtar DailySet1 /bin/tar.exe --create --file /dev/null --directory /cygdrive/c/amanda_pickup --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/galinda.qdtechnology.com_cygdrive_c_amanda_pickup_ 1.new --sparse --ignore-failed-read --totals .
sendsize[3480]: time 9525.814: Total bytes written: 5678561280 (5.3GiB, 1.5MiB/s)
sendsize[3480]: time 9525.814: .....
sendsize[3480]: estimate time for /cygdrive/c/amanda_pickup level 1: 3645.637
sendsize[3480]: estimate size for /cygdrive/c/amanda_pickup level 1: 5545470 KB
sendsize[3480]: time 9525.861: waiting for runtar "/cygdrive/c/amanda_pickup" child
sendsize[3480]: time 9525.955: after runtar /cygdrive/c/amanda_pickup wait
gnutar_calc_estimates: warning - seek failed: Illegal seek
sendsize[3480]: time 9525.955: done with amname /cygdrive/c/amanda_pickup dirname /cygdrive/c/amanda_pickup spindle -1
sendsize[5284]: time 9525.971: child 3480 terminated normally
sendsize: time 9525.971: pid 5284 finish time Thu Feb 22 03:23:51 2007

DocSavage
February 27th, 2007, 05:34 AM
Well I finally gave up and started using SAMBA. Not the way I wanted to do it but I just cant get the windows client to work!

Has anybody gotten it to?

-A

ktill
February 27th, 2007, 07:58 AM
>sendsize[3480]: gnutar: error opening >/var/lib/amanda/gnutar-lists/galinda.qdtechnology.com_cygdrive_c_amanda_pickup_ 0: No >such file or directory

is there a directory on the server called /var/lib/amanda/gnutar-lists? If so, can amandabackup user read/write to it?

MercuryShadow
June 9th, 2008, 07:54 AM
I had a similar error in my backup summaries, and it turns out that it was a configuration issue on the server side. There were no errors in the sendsize log file, aside from a few errors regarding file paths being too long. Turns out I had specified "root" instead of "amandaroot" as the username in /etc/amandahosts on the server for my windows hosts.

MercuryShadow
June 10th, 2008, 12:40 PM
Actually, I spoke too soon... still getting the error.

ndbarss
June 18th, 2008, 10:29 AM
Are you still having this problem?

I changed our "etimeout" to something like 3600 to scan our 150GB fileserver, and it still timed out.

Then I broke the server up into multiple DLEs in addition to the extended timeout and it seemed to work better. For example, instead of using:

f:/some/file

I used:

f:/some/file/here
f:/some/file/there
f:/some/file/fun

Not sure if that is a good idea, but it worked, though it is incredibly slow. I just wanted to see if you had figured out anything further.

Nathan

garaemon
September 17th, 2009, 03:50 AM
Hi!

I have a similar problem. my case is that the log backup returns the error:
planner: ERROR Request to 172.20.0.223 failed: timeout waiting for REP

I changed the etimeout but it did not solve the problem. I have two machines, one has windows XP (in spanish) and other is Windows 2000 (in English) and backup only works on Windows 2000.

The logFile is:

Zmanda Windows Client Version 2.6
Zmanda Windows Client, Copyright 2006-2007 Zmanda Inc.
-------------------------------Log file header--------------------------------
Process Id: 3948

-------------------------------Log file debug info----------------------------
3948:2776:12:38:499:Start of 'Zmanda Client for Windows' Installation--------------------------------
3948:2776:12:38:499:[Installation::SearchForOpenPort]:Found unbound port : 3307 for ZWC-MySQL Installation
3948:2776:12:38:499:[Installation::WriteToMySQLIni]:Wrote port information onto MySQL.INI
3948:2776:12:38:779:[Installation::CreateAmandaMySQLService]:Installation of ZWC-MySQL Service succeeded !!
3948:2776:12:38:889:[Installation::CreateAmandaMySQLService]:Installation of ZWC-MySQL Service succeeded !!
3948:2776:12:38:110:[Installation::CreateMySQLUser]:Successfully created the 'amandabackup' user for ZWC-MySQL
3948:2776:12:38:157:Catalog:GetStatementHandle: sql specific error
3948:2776:12:38:157:Catalog: IsConnected: Reconnecting
3948:2776:12:38:157:Catalog: IsConnected: Freeing connection handles
3948:2776:12:38:235:Catalog: IsConnected: Reconnecting to database
3948:2776:12:38:235:Catalog: IsConnected: Connected to database
3948:2776:12:38:235:Catalog:DropSchema: SQL specific error
3948:2776:12:38:235:[MySQL][ODBC 5.1 Driver][mysqld-5.0.41-community-nt]Can't drop database 'zwccatalog'; database doesn't exist
3948:2776:12:38:390:[Installation::CreateMySQLUser]:Successfully deleted previously existing 'amandabackup' ZWC user
3948:2776:12:38:515:[Installation::CreateNewUser]:Successfully created the 'amandabackup' user for ZWC
3948:2776:12:38:546:[Installation::AddUserToGroup]:Successfully added user : amandabackup to group : Operadores de copia
3948:2776:12:38:577:[Installation::AddUserToGroup]:Successfully added user : amandabackup to group : Administradores
3948:2776:12:38:609:[Installation::AddUserToGroup]:Successfully added user : amandabackup to group : Usuarios
3948:2776:12:38:765:[Installation::AssignServiceLogonRights]:Successfully assigned the LogonAsService right to user : 'amandabackup'
3948:2776:12:38:14:[Installation::CreateZWCService]:Installation of "ZWCService" Service succeeded !!
3948:2776:12:38:380:[Installation::CreateAmandaMySQLService]:Started the ZWCService Service successfully !
3948:2776:12:38:396:[Installation::WriteEncryptedPasswords]:Writing of encrytped password to registry succeeded!
3948:2776:12:38:396:End Of 'Zmanda Client for Windows' Installation----------------------------------
-----------
Process Id: 2460

-------------------------------Log file debug info----------------------------
2460:1032:12:38:401:Catalog:GetStatementHandle: sql specific error
2460:1032:12:38:401:Catalog: IsConnected: Reconnecting
2460:1032:12:38:401:Catalog: IsConnected: Freeing connection handles
2460:1032:12:38:417:Catalog: IsConnected: Reconnecting to database
2460:1032:12:38:417:Catalog: IsConnected: Connected to database
2460:2696:12:40:789:CZWCJobHandler::ExecuteJob : Job Type = 3
2460:2696:12:40:789:CAmandaStateManager : Changing the service state to SRV_SERVER_ACK_WAIT
2460:2696:12:40:898:CZWCJobHandler::ExecuteJob : Job Type = 5
2460:2696:12:40:898:CZWCJobHandler::ExecuteJob : Job Type = 21
2460:2696:12:40:898:CZWCJobHandler::ExecuteJob : Job Type = 1
2460:2696:12:40:898:CZWCJobHandler::ExecuteJob : Job Type = 3
2460:2696:12:40:898:CZWCJobHandler::ExecuteJob : Job Type = 6
2460:2696:12:40:898:ZWC Failure error code: 2
2460:2696:12:40:898:FS: NTFS: GetAttributes: GetAttributes of c:\compartida\SSII
2460:2696:12:40:898:FS: NTFS: GetAttributes: GetAttributes is Sucessful
2460:2696:12:40:898:CZWCJobHandler::ExecuteJob : Job Type = 6
2460:2696:12:40:898:ZWC Failure error code: 2
2460:2696:12:40:898:FS: NTFS: GetAttributes: GetAttributes of c:\compartida\Proyectos
2460:2696:12:40:898:FS: NTFS: GetAttributes: GetAttributes is Sucessful
2460:2696:12:40:898:CZWCJobHandler::ExecuteJob : Job Type = 6
2460:2696:12:40:898:ZWC Failure error code: 2
2460:2696:12:40:898:FS: NTFS: GetAttributes: GetAttributes of c:\compartida\Marketing
2460:2696:12:40:898:FS: NTFS: GetAttributes: GetAttributes is Sucessful
2460:2696:12:40:898:CZWCJobHandler::ExecuteJob : Job Type = 6
2460:2696:12:40:898:ZWC Failure error code: 2
2460:2696:12:40:898:FS: NTFS: GetAttributes: GetAttributes of c:\compartida\I+D+i
2460:2696:12:40:898:FS: NTFS: GetAttributes: GetAttributes is Sucessful
2460:2696:12:40:898:CZWCJobHandler::ExecuteJob : Job Type = 6
2460:2696:12:40:898:ZWC Failure error code: 2
2460:2696:12:40:898:FS: NTFS: GetAttributes: GetAttributes of c:\compartida\FicherosInteresGeneral
2460:2696:12:40:898:FS: NTFS: GetAttributes: GetAttributes is Sucessful
2460:2696:12:40:898:CZWCJobHandler::ExecuteJob : Job Type = 6
2460:2696:12:40:898:ZWC Failure error code: 2
2460:2696:12:40:898:FS: NTFS: GetAttributes: GetAttributes of c:\compartida\Documentacion
2460:2696:12:40:898:FS: NTFS: GetAttributes: GetAttributes is Sucessful
2460:2696:12:40:898:CZWCJobHandler::ExecuteJob : Job Type = 6
2460:2696:12:40:898:ZWC Failure error code: 2
2460:2696:12:40:898:FS: NTFS: GetAttributes: GetAttributes of c:\compartida\Consultoria
2460:2696:12:40:898:FS: NTFS: GetAttributes: GetAttributes is Sucessful
2460:2696:12:40:898:CZWCJobHandler::ExecuteJob : Job Type = 7
2460:2696:12:40:898:CZWCJobHandler::ExecuteJob : Job Type = 8
2460:2696:12:40:898:CAmandaStateManager : Changing the service state to SRV_SERVER_ACK_WAIT
2460:676:12:40:898:Got error while reading from socket
2460:676:12:40:898:ZWC Failure error code: 0
2460:676:12:40:898:RemoveStaleClient : Last error code is
2460:676:12:40:929:Se ha anulado una conexión establecida por el software en su equipo host..

I need help because this backup is important!!