SRM-Tester 2.2.1.2.e5 Mon Nov 17 11:40:39 PST 2008 SRM-Clients and BeStMan Copyright(c) 2007-2008, Lawrence Berkeley National Laboratory. All rights reserved. Support at SRM@LBL.GOV and documents at http://datagrid.lbl.gov/bestman ARGS[i]-drive ARGS[i]-conf ARGS[i]-localpublish SRM-TESTER: SRM Tester Configuration ServicePath=srm://head01.aglt2.org:8443/srm/managerv2 Doing operation=ping,put,get,bringonline,push,pull,gsiftp,ls,mv,srmrm,mkdir,rmdir,reserve,getspacemeta,getspacetokens,release,gettransferprotocols Remote Target Location=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 Move Target Location=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.mv.0.8701747482760221 Remove Target Location=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.mv.0.8701747482760221 Browse Target Location=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 Dir Location=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.testdir0.8701747482760221 PushModeUsed=false PushModeGiven=false CleanUpAll=false ReleaseFile=true Overwrite=true BrowseAfterPut=true RetryTimeAllowed=600 StatusWaitTime=30 source for Get=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 target for Get=file:////tmp/srmtester2.aglt2.data source for Put=file:////testdata3/T/test.data source for GsiFTP Copy=gsiftp://datagrid.lbl.gov//testdata/T/test.data source for Copy=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 PullCopySrms=AGLT2@srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data,UNL@srm://srm.unl.edu:8443/srm/managerv2?SFN=/pnfs/unl.edu/data2/srmv2_test/srmtester2.unl.data PushCopySource=AGLT2@disable=false,UNL@disable=false PullCopySource=AGLT2@disable=false,UNL@disable=false gucScriptPath=/data3/srm-tester-2/default-srmtester/bin/g-urlcopy.sh DailyTest=false NumSites=0 RetryTimeAllowed=600 StatusWaitTime=30 DCAU=true DriverOn=false remotesfn=?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data UseDriverOn=true Using proxy file /home/srmtester/.gridtest/asim/asim_proxy ++++++++++++++++++++++++++++++++++++++++++++ R E P O R T ++++++++++++++++++++++++++++++++++++++++++++ SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3539865 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode SRM-TESTER: Wed Feb 04 09:05:19 PST 2009 Calling Ping request ... SRM-TESTER: ... Output from SRM... Wed Feb 04 09:05:23 PST 2009 =================================== Ping versionInfo=v2.2 Extra information Key=backend_type Value=dCache Key=backend_version Value=0.0.0 SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3539861 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode SRM-TESTER: Wed Feb 04 09:05:23 PST 2009 Calling Put request ... SRM-TESTER: ...Input parameters ... ============================================ SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 FileSize=16 Protocols=gsiftp,http,https,ftp, Overwrite=true DCAU=true BufferSize=1048576 ============================================ SRM-TESTER: ...Output from SRM ...Wed Feb 04 09:05:24 PST 2009 ========================================== request.token : -2133726323 request.state : SRM_REQUEST_QUEUED request.explanation at Wed Feb 04 12:05:24 EST 2009 state Pending : created request.remainingTotalRequestTime : null SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 Status=SRM_REQUEST_INPROGRESS Explanation= at Wed Feb 04 12:05:24 EST 2009 state AsyncWait : calling Storage.prepareToPut() ............ =========================================== SRM-TESTER: Calling PutStatus Wed Feb 04 09:05:54 PST 2009 SRM-TESTER: Wed Feb 04 09:05:54 PST 2009 Sending PutStatus for requestToken=-2133726323 SRM-TESTER: SURL srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 Explanation= at Wed Feb 04 12:05:24 EST 2009 state Pending : created Status from SRM (srmStatusOfPutRequest) SRM_SUCCESS Explanation from SRM (srmStatusOfPutRequest) at Wed Feb 04 12:05:24 EST 2009 state Pending : created . FileStatus code=SRM_SPACE_AVAILABLE Explanation= at Wed Feb 04 12:05:54 EST 2009 state Ready : execution succeeded SRM-TESTER: Wed Feb 04 09:05:55 PST 2009 TURL returned=gsiftp://umfs09.aglt2.org:2811//pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 SRM-TESTER: Wed Feb 04 09:05:55 PST 2009 Starting GSIFTP SRM-TESTER: From=file:////testdata3/T/test.data SRM-TESTER: To=gsiftp://umfs09.aglt2.org:2811//pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 SRM-TESTER: Wed Feb 04 09:06:01 PST 2009 GSIFTP completed. SRM-TESTER: Wed Feb 04 09:06:01 PST 2009 Calling PutDone ... SRM-TESTER: ...Input parameters ... ============================= ========================= RequestToken=-2133726323 SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 ========================= SRM-TESTER: ...Output from SRM...Wed Feb 04 09:06:02 PST 2009 =========================== SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 status=SRM_SUCCESS explanation=Done =========================== SRM-TESTER: Wed Feb 04 09:06:02 PST 2009 Calling SrmLs ... SRM-TESTER: ....Input parameters ... ============================================ SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 ============================================ SRM-TESTER: ...Output from SRM...Wed Feb 04 09:06:03 PST 2009 request.token : null request.state : SRM_SUCCESS SURL : /pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 Bytes : 16 FileType : FILE StorageType : PERMANENT Status : SRM_SUCCESS OwnerPermission : 789088 LifetimeLeft : -1 Last accessed: =Wed Feb 04 09:05:58 PST 2009 =========================================== FileStatus code=SRM_SUCCESS Explanation=null SURL : /pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 Bytes : 16 FileType : FILE StorageType : PERMANENT Status : SRM_SUCCESS OwnerPermission : 789088 LifetimeLeft : -1 Last accessed: =Wed Feb 04 09:05:58 PST 2009 SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3539816 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode SRM-TESTER: Wed Feb 04 09:06:08 PST 2009 Calling Get request ... SRM-TESTER: ...Input paramters ... ============================================ SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 Protocols=gsiftp,http,https,ftp, DCAU=true BufferSize=1048576 ========================================== SRM-TESTER: ...Output from SRM...Wed Feb 04 09:06:09 PST 2009 ========================================== request.token : -2133726313 request.state : SRM_REQUEST_QUEUED request.remainingTotalRequestTime : null SRM-TESTER: SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 Status=SRM_REQUEST_INPROGRESS Explanation=Running ............ =========================================== SRM-TESTER: Calling Get Status Wed Feb 04 09:06:39 PST 2009 SRM-TESTER: Wed Feb 04 09:06:39 PST 2009 Calling GetStatus for requestToken=-2133726313 SRM-TESTER: SURL srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 Status from SRM (srmStatusOfGetRequest) SRM_SUCCESS Explanation= .Status SRM_FILE_PINNED FileStatus code=SRM_FILE_PINNED Explanation=Ready SRM-TESTER: TURL returned=gsiftp://umfs09.aglt2.org:2811//pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 SRM-TESTER: copying the file to file:////tmp/srmtester2.aglt2.data SRM-TESTER: Wed Feb 04 09:06:41 PST 2009 Starting GSIFTP SRM-TESTER: From=gsiftp://umfs09.aglt2.org:2811//pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 SRM-TESTER: To=file:////tmp/srmtester2.aglt2.data SRM-TESTER: Wed Feb 04 09:06:44 PST 2009 GSIFTP completed. SRM-TESTER: Get succeeded, file copied to the target location SRM-TESTER: Wed Feb 04 09:06:44 PST 2009 Calling ReleaseFile(Get) ... SRM-TESTER: ...Input parameters... RequestToken=-2133726313 SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 ExpectedResult=SRM_RELEASED ============================ SRM-TESTER: ...Output from SRM...Wed Feb 04 09:06:45 PST 2009 status=SRM_SUCCESS explanation=null details=SRM_SUCCESS details explanation=Done ================================= SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3539779 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode SRM-TESTER: Wed Feb 04 09:06:45 PST 2009 Calling BringOnline request ... SRM-TESTER: ...Input parameters... ============================================ SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 Protocols=gsiftp,http,https,ftp, DCAU=true BufferSize=1048576 ============================================ SRM-TESTER: ...Output from SRM...Wed Feb 04 09:06:46 PST 2009 request.token : -2133726311 request.state : SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:06:45 EST 2009 state Pending : created request.remainingTotalRequestTime : null SRM-TESTER: SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 Status=SRM_REQUEST_QUEUED Explanation=TQueued ............ =========================================== SRM-TESTER: Calling BringOnline Status Wed Feb 04 09:07:16 PST 2009 SRM-TESTER: Wed Feb 04 09:07:16 PST 2009 Calling BringOnlineStatus for requestToken=-2133726311 SRM-TESTER: SURL srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 SRM-TESTER: Status from SRM (srmStatusOfBringOnlineRequest) SRM_FAILURE Explanation= at Wed Feb 04 12:07:16 EST 2009 state Failed : at Wed Feb 04 12:06:45 EST 2009 state Pending : created RequestFileStatus#-2133726310 failed with error:[ at Wed Feb 04 12:06:46 EST 2009 state Failed : lifetime =0 <=0] . FileStatus code=SRM_FAILURE Explanation=FAILED: at Wed Feb 04 12:06:46 EST 2009 state Failed : lifetime =0 <=0 BringOnline File request failed. ################## SRM-TESTER: Testing -srmtester failed ################## Notes from serverFAILED: at Wed Feb 04 12:06:46 EST 2009 state Failed : lifetime =0 <=0 SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3539747 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode SRM-TESTER: Wed Feb 04 09:07:17 PST 2009 Calling Copy request ... Mode=Push SRM-TESTER: ServicePath=srm://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: ...Input parameters... ============================================ SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 TURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.AGLT2.0.7158453593442118 Overwrite=true DCAU=true BufferSize=1048576 SRM-TESTER: ...Output from SRM...Wed Feb 04 09:07:17 PST 2009 request.token : -2133726309 request.state : SRM_REQUEST_QUEUED SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 TURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.AGLT2.0.7158453593442118 Status=SRM_REQUEST_QUEUED Explanation=Pending ............ =========================================== SRM-TESTER: Calling Copy Status Wed Feb 04 09:07:47 PST 2009 SRM-TESTER: Wed Feb 04 09:07:47 PST 2009 Calling CopyStatus for requestToken=-2133726309 Status from SRM (srmStatusOfCopyRequest) SRM_SUCCESS Explanation= at Wed Feb 04 12:07:21 EST 2009 state Done : all files requests have completed . FileStatus code=SRM_SUCCESS Explanation=Done SRM-TESTER: SourceSURL srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 SRM-TESTER: TransferSURL srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.AGLT2.0.7158453593442118 Connecting to ServicePath ... Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 timeleft=3539716 ++++ Setting setDelegationGSIProperties +++ +++ Setting Full delegation mode +++ SRM-TESTER: Wed Feb 04 09:07:48 PST 2009 Calling SrmLs ... SRM-TESTER: ....Input parameters ... ============================================ SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.AGLT2.0.7158453593442118 ============================================ SRM-TESTER: ...Output from SRM...Wed Feb 04 09:07:53 PST 2009 request.token : null request.state : SRM_SUCCESS SURL : /pnfs/aglt2.org/data/srmtester2.aglt2.data.AGLT2.0.7158453593442118 Bytes : 16 FileType : FILE StorageType : PERMANENT Status : SRM_SUCCESS OwnerPermission : 789088 LifetimeLeft : -1 Last accessed: =Wed Feb 04 09:07:18 PST 2009 =========================================== FileStatus code=SRM_SUCCESS Explanation=null SURL : /pnfs/aglt2.org/data/srmtester2.aglt2.data.AGLT2.0.7158453593442118 Bytes : 16 FileType : FILE StorageType : PERMANENT Status : SRM_SUCCESS OwnerPermission : 789088 LifetimeLeft : -1 Last accessed: =Wed Feb 04 09:07:18 PST 2009 SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3539706 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode SRM-TESTER: Wed Feb 04 09:07:58 PST 2009 Calling Copy request ... Mode=Push SRM-TESTER: ServicePath=srm://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: ...Input parameters... ============================================ SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 TURL=srm://srm.unl.edu:8443/srm/managerv2?SFN=/pnfs/unl.edu/data2/srmv2_test/srmtester2.unl.data.UNL.0.07179845435805698 Overwrite=true DCAU=true BufferSize=1048576 SRM-TESTER: ...Output from SRM...Wed Feb 04 09:07:58 PST 2009 request.token : -2133726307 request.state : SRM_REQUEST_QUEUED SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 TURL=srm://srm.unl.edu:8443/srm/managerv2?SFN=/pnfs/unl.edu/data2/srmv2_test/srmtester2.unl.data.UNL.0.07179845435805698 Status=SRM_REQUEST_QUEUED Explanation=Pending ............ =========================================== SRM-TESTER: Calling Copy Status Wed Feb 04 09:08:28 PST 2009 SRM-TESTER: Wed Feb 04 09:08:29 PST 2009 Calling CopyStatus for requestToken=-2133726307 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:08:59 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:09:30 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:10:01 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:10:32 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:11:03 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:11:34 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:12:05 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:12:36 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:13:06 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:13:37 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:14:08 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:14:39 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:15:09 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:15:44 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:16:18 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:16:48 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:17:19 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..SRM-TESTER: Calling Copy Status Wed Feb 04 09:17:49 PST 2009 Status from SRM (srmStatusOfCopyRequest) SRM_REQUEST_QUEUED Explanation= at Wed Feb 04 12:08:03 EST 2009 state AsyncWait : waiting for files to complete ..Check status exceeds max retry SRM-TESTER: copy request failed SRM-TESTER: Testing -srmtester failed Explanation Pending Connecting with copyServicePath ... SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3539084 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode Connecting with copyServicePath ... SRM-TESTER: Connecting to url httpg://srm.unl.edu:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3539084 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode SRM-TESTER: Wed Feb 04 09:18:20 PST 2009 Calling Copy request ... SRM-TESTER: ServicePath=srm://srm.unl.edu:8443/srm/managerv2 SRM-TESTER: ...Input parameters... ============================================ SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 TURL=srm://srm.unl.edu:8443/srm/managerv2?SFN=/pnfs/unl.edu/data2/srmv2_test/srmtester2.unl.data.UNL.0.897153626588219 Overwrite=true DCAU=true BufferSize=1048576 SRM-TESTER: ...Output from SRM...Wed Feb 04 09:18:21 PST 2009 request.token : -2142668027 request.state : SRM_REQUEST_QUEUED SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 TURL=srm://srm.unl.edu:8443/srm/managerv2?SFN=/pnfs/unl.edu/data2/srmv2_test/srmtester2.unl.data.UNL.0.897153626588219 Status=SRM_REQUEST_QUEUED Explanation=Pending ............ =========================================== SRM-TESTER: Calling Copy Status Wed Feb 04 09:18:51 PST 2009 SRM-TESTER: Wed Feb 04 09:18:51 PST 2009 Calling CopyStatus for requestToken=-2142668027 Status from SRM (srmStatusOfCopyRequest) SRM_SUCCESS . FileStatus code=SRM_SUCCESS Explanation=Done SRM-TESTER: SourceSURL srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 SRM-TESTER: TransferSURL srm://srm.unl.edu:8443/srm/managerv2?SFN=/pnfs/unl.edu/data2/srmv2_test/srmtester2.unl.data.UNL.0.897153626588219 SRM-TESTER: Wed Feb 04 09:18:52 PST 2009 Calling SrmLs ... SRM-TESTER: ....Input parameters ... ============================================ SURL=srm://srm.unl.edu:8443/srm/managerv2?SFN=/pnfs/unl.edu/data2/srmv2_test/srmtester2.unl.data.UNL.0.897153626588219 ============================================ SRM-TESTER: ...Output from SRM...Wed Feb 04 09:18:53 PST 2009 request.token : null request.state : SRM_SUCCESS SURL : /pnfs/unl.edu/data2/srmv2_test/srmtester2.unl.data.UNL.0.897153626588219 Bytes : 16 FileType : FILE StorageType : PERMANENT Status : SRM_SUCCESS OwnerPermission : 1403 LifetimeLeft : -1 Last accessed: =Wed Feb 04 09:18:38 PST 2009 =========================================== FileStatus code=SRM_SUCCESS Explanation=null SURL : /pnfs/unl.edu/data2/srmv2_test/srmtester2.unl.data.UNL.0.897153626588219 Bytes : 16 FileType : FILE StorageType : PERMANENT Status : SRM_SUCCESS OwnerPermission : 1403 LifetimeLeft : -1 Last accessed: =Wed Feb 04 09:18:38 PST 2009 SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3539046 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode SRM-TESTER: Wed Feb 04 09:18:58 PST 2009 Calling Copy request ... SRM-TESTER: ServicePath= SRM-TESTER: ...Input parameters... ============================================ SURL=gsiftp://datagrid.lbl.gov//testdata/T/test.data TURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.gsiftp.0.2615252358742416 Overwrite=true DCAU=true BufferSize=1048576 SRM-TESTER: ...Output from SRM...Wed Feb 04 09:18:58 PST 2009 request.token : -2133726213 request.state : SRM_REQUEST_QUEUED SURL=gsiftp://datagrid.lbl.gov//testdata/T/test.data TURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.gsiftp.0.2615252358742416 Status=SRM_REQUEST_QUEUED Explanation=Pending ............ =========================================== SRM-TESTER: Calling Copy Status Wed Feb 04 09:19:28 PST 2009 SRM-TESTER: Wed Feb 04 09:19:28 PST 2009 Calling CopyStatus for requestToken=-2133726213 Status from SRM (srmStatusOfCopyRequest) SRM_SUCCESS Explanation= at Wed Feb 04 12:19:11 EST 2009 state Done : all files requests have completed . FileStatus code=SRM_SUCCESS Explanation=Done SRM-TESTER: SourceSURL gsiftp://datagrid.lbl.gov//testdata/T/test.data SRM-TESTER: TransferSURL srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.gsiftp.0.2615252358742416 SRM-TESTER: Wed Feb 04 09:19:29 PST 2009 Calling SrmLs ... SRM-TESTER: ....Input parameters ... ============================================ SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.gsiftp.0.2615252358742416 ============================================ SRM-TESTER: ...Output from SRM...Wed Feb 04 09:19:30 PST 2009 request.token : null request.state : SRM_SUCCESS SURL : /pnfs/aglt2.org/data/srmtester2.aglt2.data.gsiftp.0.2615252358742416 Bytes : 16 FileType : FILE StorageType : PERMANENT Status : SRM_SUCCESS OwnerPermission : 789088 LifetimeLeft : -1 Last accessed: =Wed Feb 04 09:19:05 PST 2009 =========================================== FileStatus code=SRM_SUCCESS Explanation=null SURL : /pnfs/aglt2.org/data/srmtester2.aglt2.data.gsiftp.0.2615252358742416 Bytes : 16 FileType : FILE StorageType : PERMANENT Status : SRM_SUCCESS OwnerPermission : 789088 LifetimeLeft : -1 Last accessed: =Wed Feb 04 09:19:05 PST 2009 SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3539009 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode SRM-TESTER: Wed Feb 04 09:19:35 PST 2009 Calling SrmLs ... SRM-TESTER: ....Input parameters ... ============================================ SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 ============================================ SRM-TESTER: ...Output from SRM...Wed Feb 04 09:19:36 PST 2009 request.token : null request.state : SRM_SUCCESS SURL : /pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 Bytes : 16 FileType : FILE StorageType : PERMANENT Status : SRM_SUCCESS OwnerPermission : 789088 LifetimeLeft : -1 Last accessed: =Wed Feb 04 09:05:58 PST 2009 =========================================== FileStatus code=SRM_SUCCESS Explanation=null SURL : /pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 Bytes : 16 FileType : FILE StorageType : PERMANENT Status : SRM_SUCCESS OwnerPermission : 789088 LifetimeLeft : -1 Last accessed: =Wed Feb 04 09:05:58 PST 2009 SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3539003 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode ++++ Sending "Mv" request +++++ SRM-TESTER: ....Input parameters ... ============================================ From SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.0.8701747482760221 To SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.mv.0.8701747482760221 ========================================== request.state : SRM_SUCCESS request.explanation success SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3539002 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode ++++ Sending "SrmRm" request +++++ SRM-TESTER: ....Input parameters ... ============================================ SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.mv.0.8701747482760221 ========================================== request.state : SRM_SUCCESS request.explanation successfully removed files Surl : srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.mv.0.8701747482760221 FileStatus : SRM_SUCCESS FileStatus Explanation : null SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3539001 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode ++++ Sending "Mkdir" request +++++ SRM-TESTER: ....Input parameters ... ============================================ SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.testdir0.8701747482760221 ========================================== request.state : SRM_SUCCESS request.explanation success SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3538999 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode ++++ Sending "Rmdir" request +++++ SRM-TESTER: ....Input parameters ... ============================================ SURL=srm://head01.aglt2.org:8443/srm/managerv2?SFN=/pnfs/aglt2.org/data/srmtester2.aglt2.data.testdir0.8701747482760221 ========================================== request.state : SRM_SUCCESS request.explanation success SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3538998 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode Sending ReserveSpace ... StartTime=Wed Feb 04 09:19:46 PST 2009 =========================================== TotalSpace=1000 GuaranteedSpace=1000 LifeTime=10000 =========================================== =========================================== SpaceRequest Status Code=SRM_REQUEST_INPROGRESS SpaceRequest Explanation= at Wed Feb 04 12:19:46 EST 2009 state TQueued : put on the thread queue Request token=-2133726203 StatusOfReserveSpaceRequest Status Code=SRM_SUCCESS StatusOfReserveSpaceRequest Explanation= TargetSpaceToken=4138115 =========================================== EndTime=Wed Feb 04 09:20:17 PST 2009 SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3538967 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode Sending GetSpaceMetaData ... StartTime=Wed Feb 04 09:20:17 PST 2009 =================================== SpaceToken=4138115 =================================== =================================== StatusCode=SRM_SUCCESS Explanation=OK =================================== EndTime=Wed Feb 04 09:20:19 PST 2009 SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3538965 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode Sending GetSpaceTokens request ... StartTime=Wed Feb 04 09:20:19 PST 2009 STATUS=SRM_SUCCESS spacetoken.size=1 (0)space.token=4138115 SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3538965 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode Sending ReleaseSpace ... StartTime=Wed Feb 04 09:20:19 PST 2009 =================================== SpaceToken=4138115 =================================== =================================== StatusCode=SRM_SUCCESS Explanation=Space released =================================== EndTime=Wed Feb 04 09:20:20 PST 2009 SRM-TESTER: Connecting with regular servicepath SRM-TESTER: Connecting to url httpg://head01.aglt2.org:8443/srm/managerv2 SRM-TESTER: Issues=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622 SRM-TESTER: Subject=DC=org,DC=doegrids,OU=People,CN=Alexander Sim 546622,CN=proxy SRM-TESTER: Identity=/DC=org/DC=doegrids/OU=People/CN=Alexander Sim 546622 SRM-TESTER: timeleft=3538964 SRM-TESTER: Setting setDelegationGSIProperties SRM-TESTER: Setting Full delegation mode Sending GetTransferProtocols request ... StartTime=Wed Feb 04 09:20:20 PST 2009 GetTransferProtocols.Status=SRM_SUCCESS (0)gsiftp (1)gsidcap (2)dcap ========================================= Summary ========================================= ServicePath:srm://head01.aglt2.org:8443/srm/managerv2 Date:Wed Feb 04 09:20:24 PST 2009 srmPing : Ok srmGetTransferProtocols : Ok srmPrepareToPut : Ok srmStatusOfPutRequest : Ok gsiftp-put: Ok srmPutDone : Ok srmPrepareToGet : Ok srmStatusOfGetRequest : Ok gsiftp-get: Ok srmReleaseFiles : Ok srmBringOnline : at Wed Feb 04 12:07:16 EST 2009 state Failed : at Wed Feb 04 12:06:45 EST 2009 state Pending : created RequestFileStatus#-2133726310 failed with error:[ at Wed Feb 04 12:06:46 EST 2009 state Failed : lifetime =0 <=0] srmStatusOfBringOnlineRequest : Failed srmReserveSpace : Failed srmGetSpaceMetadata : Ok srmGetSpaceTokens : Ok srmReleaseSpace : Ok srmMv : Ok srmMkdir : Ok srmRmdir : Ok srmLs : Ok srmRm : Ok srmCopy-pull-UNL:Ok srmStatusOfCopyRequest-pull-UNL:Ok srmCopy-pull-gsiftp:Ok srmStatusOfCopyRequest-pull-gsiftp:Ok srmCopy-push-UNL:Ok srmStatusOfCopyRequest-push-UNL:TimedOut srmCopy-push-AGLT2:Ok srmStatusOfCopyRequest-push-AGLT2:Ok