AW: [condor-users] Transfer-File-Problems?

["Thomas Bauer" <tombauer@xxxxxxxxxxxxxxxxxxx>]

Ok, now the correct log-file...

1/19 18:46:06 ******************************************************
1/19 18:46:06 ** condor_shadow (CONDOR_SHADOW) STARTING UP
1/19 18:46:06 ** $CondorVersion: 6.6.0 Nov 24 2003 $
1/19 18:46:06 ** $CondorPlatform: INTEL-WINNT40 $
1/19 18:46:06 ** PID = 776
1/19 18:46:06 ******************************************************
1/19 18:46:06 Using config file: C:\Condor\condor_config
1/19 18:46:06 Using local config files: C:\Condor\condor_config.local
1/19 18:46:06 DaemonCore: Command Socket at <128.176.208.220:1405>
1/19 18:46:07 Getting JobAd from schedd manually.
1/19 18:46:07 Success in retreving JobAd.
1/19 18:46:07 Initializing a VANILLA shadow
1/19 18:46:07 (29.0) (776): init_user_ids: want user 'ytombaue@nwznet',
current is '(null)@(null)'
1/19 18:46:07 (29.0) (776): Found credential for user 'ytombaue'
1/19 18:46:07 (29.0) (776): LogonUser completed.
1/19 18:46:07 (29.0) (776): TokenCache contents:
ytombaue@NWZNET
1/19 18:46:07 (29.0) (776): UserLog = C:\BATCH\trapez.log
1/19 18:46:07 (29.0) (776): *** Reserved Swap = 5120
1/19 18:46:07 (29.0) (776): *** Free Swap = 2178680
1/19 18:46:07 (29.0) (776): init_user_ids: want user 'ytombaue@nwznet',
current is 'ytombaue@nwznet'
1/19 18:46:07 (29.0) (776): init_user_ids: Already have handle for
ytombaue@nwznet, so returning.
1/19 18:46:07 (29.0) (776): TokenCache contents:
ytombaue@NWZNET
1/19 18:46:07 (29.0) (776): Entering DCStartd::activateClaim()
1/19 18:46:07 (29.0) (776): DCStartd::activateClaim: successfully sent
command, reply is: 1
1/19 18:46:07 (29.0) (776): Request to run on <128.176.198.38:1047> was
ACCEPTED
1/19 18:46:07 (29.0) (776): Resource <128.176.198.38:1047> changing state
from PRE to STARTUP
1/19 18:46:07 (29.0) (776): DaemonCore: in SendAliveToParent()
1/19 18:46:07 (29.0) (776): DaemonCore: attempting to connect to
'<128.176.208.220:1343>'
1/19 18:46:08 (29.0) (776): entering FileTransfer::Init
1/19 18:46:08 (29.0) (776): entering FileTransfer::SimpleInit
1/19 18:46:08 (29.0) (776): perm::init() starting up for account (ytombaue)
domain (nwznet)
1/19 18:46:08 (29.0) (776): perm::init: Found Account Name ytombaue
1/19 18:46:08 (29.0) (776): TokenCache contents:
ytombaue@NWZNET
1/19 18:46:08 (29.0) (776): TokenCache contents:
ytombaue@NWZNET
1/19 18:46:08 (29.0) (776): TokenCache contents:
ytombaue@NWZNET
1/19 18:46:08 (29.0) (776): perm::init() starting up for account (ytombaue)
domain (nwznet)
1/19 18:46:08 (29.0) (776): perm::init: Found Account Name ytombaue
1/19 18:46:08 (29.0) (776): perm::init() starting up for account (ytombaue)
domain (nwznet)
1/19 18:46:08 (29.0) (776): perm::init: Found Account Name ytombaue
1/19 18:46:09 (29.0) (776): entering FileTransfer::HandleCommands
1/19 18:46:09 (29.0) (776): FileTransfer::HandleCommands read
transkey=1#400c17e0694c1036
1/19 18:46:09 (29.0) (776): TokenCache contents:
ytombaue@NWZNET
1/19 18:46:09 (29.0) (776): TokenCache contents:
ytombaue@NWZNET
1/19 18:46:09 (29.0) (776): entering FileTransfer::Upload
1/19 18:46:09 (29.0) (776): entering FileTransfer::DoUpload
1/19 18:46:09 (29.0) (776): DoUpload: send file
C:\Condor\spool\cluster29.ickpt.subproc0
1/19 18:46:09 (29.0) (776): ReliSock: put_file: done with transfer (errno =
2)
1/19 18:46:09 (29.0) (776): ReliSock: put_file: sent 528441 bytes
1/19 18:46:09 (29.0) (776): DoUpload: exiting at 1386
1/19 18:46:09 (29.0) (776): TokenCache contents:
ytombaue@NWZNET
1/19 18:46:11 (29.0) (776): perm::init() starting up for account (ytombaue)
domain (nwznet)
1/19 18:46:11 (29.0) (776): perm::init: Found Account Name ytombaue
1/19 18:46:11 (29.0) (776): Resource PCFT83.nwznet.uni-muenster.de changing
state from STARTUP to EXECUTING
1/19 18:46:19 (29.0) (776): Inside RemoteResource::updateFromStarter()
1/19 18:46:36 (29.0) (776): entering FileTransfer::HandleCommands
1/19 18:46:36 (29.0) (776): FileTransfer::HandleCommands read
transkey=1#400c17e0694c1036
1/19 18:46:36 (29.0) (776): entering FileTransfer::Download
1/19 18:46:36 (29.0) (776): entering FileTransfer::DoDownload sync=0
1/19 18:46:36 (29.0) (776): Calling Perm::userInAce() for ytombaue\nwznet
1/19 18:46:36 (29.0) (776): perm::UserInAce: Checking VORDEFINIERT\Users
1/19 18:46:36 (29.0) (776): in perm::userInLocalGroup() looking at group
'Users'
1/19 18:46:36 (29.0) (776): Calling Perm::userInAce() for ytombaue\nwznet
1/19 18:46:36 (29.0) (776): perm::UserInAce: Checking
VORDEFINIERT\Administrators
1/19 18:46:36 (29.0) (776): in perm::userInLocalGroup() looking at group
'Administrators'
1/19 18:46:36 (29.0) (776): Calling Perm::userInAce() for ytombaue\nwznet
1/19 18:46:36 (29.0) (776): perm::UserInAce: Checking \ERSTELLER-BESITZER
1/19 18:46:36 (29.0) (776): in perm::userInLocalGroup() looking at group
'ERSTELLER-BESITZER'
1/19 18:46:36 (29.0) (776): Calling Perm::userInAce() for ytombaue\nwznet
1/19 18:46:36 (29.0) (776): perm::UserInAce: Checking NT-AUTORITAT\SYSTEM
1/19 18:46:36 (29.0) (776): in perm::userInLocalGroup() looking at group
'SYSTEM'
1/19 18:46:36 (29.0) (776): Calling Perm::userInAce() for ytombaue\nwznet
1/19 18:46:36 (29.0) (776): perm::UserInAce: Checking VORDEFINIERT\Users
1/19 18:46:36 (29.0) (776): in perm::userInLocalGroup() looking at group
'Users'
1/19 18:46:36 (29.0) (776): DoDownload: Permission denied to write file
C:\BATCH\fort.19!
1/19 18:46:36 (29.0) (776): DoDownload: exiting at 1090
1/19 18:46:36 (29.0) (776): perm::init() starting up for account (ytombaue)
domain (nwznet)
1/19 18:46:36 (29.0) (776): perm::init: Found Account Name ytombaue
1/19 18:46:36 (29.0) (776): ERROR "Can no longer talk to condor_starter on
execute machine (128.176.198.38)" at line 141 in file
..\src\condor_shadow.V6.1\NTreceivers.C
1/19 18:46:37 ******************************************************
1/19 18:46:37 ** condor_shadow (CONDOR_SHADOW) STARTING UP
1/19 18:46:37 ** $CondorVersion: 6.6.0 Nov 24 2003 $
1/19 18:46:37 ** $CondorPlatform: INTEL-WINNT40 $
1/19 18:46:37 ** PID = 2132
1/19 18:46:37 ******************************************************
1/19 18:46:37 Using config file: C:\Condor\condor_config
1/19 18:46:37 Using local config files: C:\Condor\condor_config.local
1/19 18:46:37 DaemonCore: Command Socket at <128.176.208.220:1428>
1/19 18:46:38 Getting JobAd from schedd manually.
1/19 18:46:38 Success in retreving JobAd.
1/19 18:46:38 Initializing a VANILLA shadow
1/19 18:46:38 (29.0) (2132): init_user_ids: want user 'ytombaue@nwznet',
current is '(null)@(null)'
1/19 18:46:38 (29.0) (2132): Found credential for user 'ytombaue'
1/19 18:46:38 (29.0) (2132): LogonUser completed.
1/19 18:46:38 (29.0) (2132): TokenCache contents:
ytombaue@NWZNET
1/19 18:46:38 (29.0) (2132): UserLog = C:\BATCH\trapez.log
1/19 18:46:38 (29.0) (2132): *** Reserved Swap = 5120
1/19 18:46:38 (29.0) (2132): *** Free Swap = 2178604
1/19 18:46:38 (29.0) (2132): init_user_ids: want user 'ytombaue@nwznet',
current is 'ytombaue@nwznet'
1/19 18:46:38 (29.0) (2132): init_user_ids: Already have handle for
ytombaue@nwznet, so returning.
1/19 18:46:38 (29.0) (2132): TokenCache contents:
ytombaue@NWZNET
1/19 18:46:38 (29.0) (2132): Entering DCStartd::activateClaim()
1/19 18:46:39 (29.0) (2132): DCStartd::activateClaim: successfully sent
command, reply is: 1
1/19 18:46:39 (29.0) (2132): Request to run on <128.176.198.38:1047> was
ACCEPTED
1/19 18:46:39 (29.0) (2132): Resource <128.176.198.38:1047> changing state
from PRE to STARTUP
1/19 18:46:39 (29.0) (2132): DaemonCore: in SendAliveToParent()
1/19 18:46:39 (29.0) (2132): DaemonCore: attempting to connect to
'<128.176.208.220:1343>'
1/19 18:46:39 (29.0) (2132): entering FileTransfer::Init
1/19 18:46:39 (29.0) (2132): entering FileTransfer::SimpleInit
1/19 18:46:39 (29.0) (2132): perm::init() starting up for account (ytombaue)
domain (nwznet)
1/19 18:46:40 (29.0) (2132): perm::init: Found Account Name ytombaue
1/19 18:46:40 (29.0) (2132): FileTransfer::Init():
mkdir(C:\Condor\spool\cluster29.proc0.subproc0) failed, errno: 17
1/19 18:46:40 (29.0) (2132): FileTransfer::Init():
mkdir(C:\Condor\spool\cluster29.proc0.subproc0.tmp) failed, errno: 17
1/19 18:46:40 (29.0) (2132): TokenCache contents:
ytombaue@NWZNET
1/19 18:46:40 (29.0) (2132): TokenCache contents:
ytombaue@NWZNET
1/19 18:46:40 (29.0) (2132): TokenCache contents:
ytombaue@NWZNET
1/19 18:46:40 (29.0) (2132): perm::init() starting up for account (ytombaue)
domain (nwznet)
1/19 18:46:40 (29.0) (2132): perm::init: Found Account Name ytombaue
1/19 18:46:40 (29.0) (2132): perm::init() starting up for account (ytombaue)
domain (nwznet)
1/19 18:46:40 (29.0) (2132): perm::init: Found Account Name ytombaue
1/19 18:46:40 (29.0) (2132): entering FileTransfer::HandleCommands
1/19 18:46:41 (29.0) (2132): FileTransfer::HandleCommands read
transkey=1#400c17ff80216d5
1/19 18:46:41 (29.0) (2132): TokenCache contents:
ytombaue@NWZNET
1/19 18:46:41 (29.0) (2132): TokenCache contents:
ytombaue@NWZNET
1/19 18:46:41 (29.0) (2132): entering FileTransfer::Upload
1/19 18:46:41 (29.0) (2132): entering FileTransfer::DoUpload
1/19 18:46:41 (29.0) (2132): DoUpload: send file
C:\Condor\spool\cluster29.ickpt.subproc0
1/19 18:46:41 (29.0) (2132): ReliSock: put_file: done with transfer (errno =
2)
1/19 18:46:41 (29.0) (2132): ReliSock: put_file: sent 528441 bytes
1/19 18:46:41 (29.0) (2132): DoUpload: exiting at 1386
1/19 18:46:41 (29.0) (2132): TokenCache contents:
ytombaue@NWZNET
1/19 18:46:42 (29.0) (2132): In handleJobRemoval(), sig 10
1/19 18:46:42 (29.0) (2132): setting exit reason on
PCFT83.nwznet.uni-muenster.de to 102
1/19 18:46:42 (29.0) (2132): Resource PCFT83.nwznet.uni-muenster.de changing
state from STARTUP to FINISHED
1/19 18:46:42 (29.0) (2132): Entering DCStartd::deactivateClaim(forceful)
1/19 18:46:42 (29.0) (2132): DCStartd::deactivateClaim: successfully sent
command
1/19 18:46:42 (29.0) (2132): Killed starter (fast) at <128.176.198.38:1047>
1/19 18:46:42 (29.0) (2132): perm::init() starting up for account (ytombaue)
domain (nwznet)
1/19 18:46:42 (29.0) (2132): perm::init: Found Account Name ytombaue
1/19 18:46:42 (29.0) (2132): Resource PCFT83.nwznet.uni-muenster.de changing
state from FINISHED to EXECUTING
1/19 18:46:47 (29.0) (2132): perm::init() starting up for account (ytombaue)
domain (nwznet)
1/19 18:46:47 (29.0) (2132): perm::init: Found Account Name ytombaue
1/19 18:46:47 (29.0) (2132): Inside RemoteResource::updateFromStarter()
1/19 18:46:47 (29.0) (2132): Inside RemoteResource::resourceExit()
1/19 18:46:47 (29.0) (2132): setting exit reason on
PCFT83.nwznet.uni-muenster.de to 107
1/19 18:46:47 (29.0) (2132): Resource PCFT83.nwznet.uni-muenster.de changing
state from EXECUTING to FINISHED
1/19 18:46:47 (29.0) (2132): Job 29.0 is being evicted
1/19 18:46:47 (29.0) (2132): Entering BaseShadow::updateJobInQueue
1/19 18:46:47 (29.0) (2132): sspi_client_auth() entered
1/19 18:46:47 (29.0) (2132): sspi_client_auth() looping
1/19 18:46:47 (29.0) (2132): sspi_client_auth() exiting
1/19 18:46:47 (29.0) (2132): Updating Job Queue:
SetAttribute(LastVacateTime, 1074534407)
1/19 18:46:47 (29.0) (2132): Updating Job Queue: SetAttribute(BytesSent,
0.000000)
1/19 18:46:47 (29.0) (2132): Updating Job Queue: SetAttribute(BytesRecvd,
528441.000000)
1/19 18:46:47 (29.0) (2132): **** condor_shadow (condor_SHADOW) EXITING WITH
STATUS 102


Thomas Bauer
--------------------------------------------
Westfaelische Wilhelms-Universitaet Muenster
Institut fuer Festkoerpertheorie
Wilhelm-Klemm-Str. 10
D 48149 Muenster
++49 (251) 8339040
--------------------------------------------

-----Ursprungliche Nachricht-----
Von: owner-condor-users@xxxxxxxxxxx
[mailto:owner-condor-users@xxxxxxxxxxx]Im Auftrag von Colin Stolley
Gesendet: Montag, 19. Januar 2004 18:06
An: condor-users@xxxxxxxxxxx
Betreff: Re: [condor-users] Transfer-File-Problems?


> PS: What do you mean with "It would be especially helpful if your
> SHADOW_DEBUG setting
> had D_FULLDEBUG turned on."
> Should I place into the condor_config of every machine "SHADOW_DEBUG =
> D_FULLDEBUG" ?

Sorry for not being clear. Actually only the submit machine needs
this setting, and the best way to add D_FULLDEBUG to SHADOW_DEBUG
(without wiping out other debug levels you might have set) is to add
the following line:

SHADOW_DEBUG = $(SHADOW_DEBUG) D_FULLDEBUG

As I suspected, your log isn't much help in determining why the
permissions checks failed without D_FULLDEBUG. If you could add the above
line to your config file and send off a snippet, that would help a lot.

Colin
Condor Support Information:
http://www.cs.wisc.edu/condor/condor-support/
To Unsubscribe, send mail to majordomo@xxxxxxxxxxx with
unsubscribe condor-users <your_email_address>


Condor Support Information:
http://www.cs.wisc.edu/condor/condor-support/
To Unsubscribe, send mail to majordomo@xxxxxxxxxxx with
unsubscribe condor-users <your_email_address>