===> +++++++++++++++++++++++++++++++++++++++++++++++++++++ ===> + TestSuite of the gLite-WMS command line interface ===> + Description: test glite-wms-job-delegate-proxy command ===> +++++++++++++++++++++++++++++++++++++++++++++++++++++ ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/example.jdl... ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf... ===> Initializing proxy file ... ===> DEBUG ===> Your identity: /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle verify OK Creating temporary proxy ............................... Done Contacting lxbra2309.cern.ch:15001 [/C=CH/O=CERN/OU=GRID/CN=host/lxb2309.cern.ch] "dteam" Failed Error: Could not establish authenticated connection with the server. GSS Major Status: Unexpected Gatekeeper or Service Name GSS Minor Status Error Chain: globus_gss_assist: Error during context initialization globus_gsi_gssapi: Authorization denied: The name of the remote entity (/DC=ch/DC=cern/OU=computers/CN=lxbra2309.cern.ch), and the expected name for the remote entity (/C=CH/O=CERN/OU=GRID/CN=host/lxb2309.cern.ch) do not match Trying next server for dteam. Creating temporary proxy ................................. Done Contacting lcg-voms.cern.ch:15004 [/DC=ch/DC=cern/OU=computers/CN=lcg-voms.cern.ch] "dteam" Done Creating proxy ..................................... Done Your proxy is valid until Tue Oct 19 16:09:13 2010 ===> >>> Check if command glite-wms-job-delegate-proxy exists ===> ===> [ 16:09:13 ] $ which glite-wms-job-delegate-proxy ===> DEBUG ===> /opt/glite/bin/glite-wms-job-delegate-proxy ===> -> Command success ===> >>> ===> >>> Test --version option ===> ===> [ 16:09:13 ] $ glite-wms-job-delegate-proxy --version ===> DEBUG ===> WMS User Interface version 1.3.1 Copyright (C) 2005 by DATAMAT SpA Connecting to the service https://devel18.cnaf.infn.it:7443/glite_wms_wmproxy_server WMProxy Version: 2.9.9 ===> -> Command success ===> Check the output command ===> ===> [ 16:09:13 ] $ grep "WMS User Interface version" ===> DEBUG ===> WMS User Interface version 1.3.1 ===> -> Command success ===> >>> We are testing WMS User Interface version 1.3.1 ===> >>> ===> >>> Test option --autm-delegation ===> ===> [ 16:09:13 ] $ glite-wms-job-delegate-proxy --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf --output /home/ale/UI/test/wms-cli-test_20101018160907/output.txt --autm-delegation ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ================== glite-wms-job-delegate-proxy Success ================== Your proxy has been successfully delegated to the WMProxy: https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server with the delegation identifier: Nu0n6B_d6RF05f-RQQ9Wng The DelegateProxy result has been saved in the following file: /home/ale/UI/test/wms-cli-test_20101018160907/output.txt ========================================================================== ===> -> Command success ===> Delegation Id: Nu0n6B_d6RF05f-RQQ9Wng ===> Verify the delegation ===> ===> [ 16:09:14 ] $ glite-wms-job-info -d Nu0n6B_d6RF05f-RQQ9Wng --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ======================= glite-wms-job-info Success ======================= Your proxy delegated to the endpoint https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server with delegationID Nu0n6B_d6RF05f-RQQ9Wng: Subject : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy/CN=proxy Issuer : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy Identity : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy Type : proxy Strength : 512 StartDate : 18 Oct 2010 - 16:09:14 Expiration : 19 Oct 2010 - 16:08:14 Timeleft : 23 hours 58 min 59 sec === VO dteam extension information === VO : dteam Subject : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle Issuer : /DC=ch/DC=cern/OU=computers/CN=lcg-voms.cern.ch URI : lcg-voms.cern.ch:15004 Attribute : /dteam/Role=NULL/Capability=NULL StartTime : 18 Oct 2010 - 16:09:12 Expiration : 19 Oct 2010 - 16:09:12 Timeleft : 23 hours 59 min 57 sec ========================================================================== ===> -> Command success ===> Try a submit ===> ===> [ 16:09:15 ] $ glite-wms-job-submit -d Nu0n6B_d6RF05f-RQQ9Wng --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf --output /home/ale/UI/test/wms-cli-test_20101018160907/job.id /home/ale/UI/test/wms-cli-test_20101018160907/example.jdl ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ====================== glite-wms-job-submit Success ====================== The job has been successfully submitted to the WMProxy Your job identifier is: https://devel17.cnaf.infn.it:9000/sdiwO-MKpFskCj2w5qLyOQ The job identifier has been saved in the following file: /home/ale/UI/test/wms-cli-test_20101018160907/job.id ========================================================================== ===> -> Command success ===> JOBID: https://devel17.cnaf.infn.it:9000/sdiwO-MKpFskCj2w5qLyOQ ===> Cancel the unused job ===> ===> [ 16:09:18 ] $ glite-wms-job-cancel --noint https://devel17.cnaf.infn.it:9000/sdiwO-MKpFskCj2w5qLyOQ ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ============================= glite-wms-job-cancel Success ============================= The cancellation request has been successfully submitted for the following job(s): - https://devel17.cnaf.infn.it:9000/sdiwO-MKpFskCj2w5qLyOQ ======================================================================================== ===> -> Command success ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/job.id... ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/output.txt... ===> >>> ===> >>> Test option -d ===> ===> [ 16:09:19 ] $ glite-wms-job-delegate-proxy --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf -d deleg-4059 ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ================== glite-wms-job-delegate-proxy Success ================== Your proxy has been successfully delegated to the WMProxy: https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server with the delegation identifier: deleg-4059 ========================================================================== ===> -> Command success ===> Verify the delegation ===> ===> [ 16:09:20 ] $ glite-wms-job-info -d deleg-4059 --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ======================= glite-wms-job-info Success ======================= Your proxy delegated to the endpoint https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server with delegationID deleg-4059: Subject : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy/CN=proxy Issuer : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy Identity : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy Type : proxy Strength : 512 StartDate : 18 Oct 2010 - 16:09:20 Expiration : 19 Oct 2010 - 16:08:20 Timeleft : 23 hours 58 min 59 sec === VO dteam extension information === VO : dteam Subject : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle Issuer : /DC=ch/DC=cern/OU=computers/CN=lcg-voms.cern.ch URI : lcg-voms.cern.ch:15004 Attribute : /dteam/Role=NULL/Capability=NULL StartTime : 18 Oct 2010 - 16:09:12 Expiration : 19 Oct 2010 - 16:09:12 Timeleft : 23 hours 59 min 51 sec ========================================================================== ===> -> Command success ===> Try a submit ===> ===> [ 16:09:21 ] $ glite-wms-job-submit -d deleg-4059 --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf --output /home/ale/UI/test/wms-cli-test_20101018160907/job.id /home/ale/UI/test/wms-cli-test_20101018160907/example.jdl ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ====================== glite-wms-job-submit Success ====================== The job has been successfully submitted to the WMProxy Your job identifier is: https://devel17.cnaf.infn.it:9000/OxVh57EqHe4awnvnVDiJRg The job identifier has been saved in the following file: /home/ale/UI/test/wms-cli-test_20101018160907/job.id ========================================================================== ===> -> Command success ===> JOBID: https://devel17.cnaf.infn.it:9000/OxVh57EqHe4awnvnVDiJRg ===> Cancel the unused job ===> ===> [ 16:09:23 ] $ glite-wms-job-cancel --noint https://devel17.cnaf.infn.it:9000/OxVh57EqHe4awnvnVDiJRg ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ============================= glite-wms-job-cancel Success ============================= The cancellation request has been successfully submitted for the following job(s): - https://devel17.cnaf.infn.it:9000/OxVh57EqHe4awnvnVDiJRg ======================================================================================== ===> -> Command success ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/job.id... ===> >>> ===> >>> Test option --logfile ===> ===> [ 16:09:24 ] $ glite-wms-job-delegate-proxy --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf --logfile /home/ale/UI/test/wms-cli-test_20101018160907/log.txt -d deleg-4059 ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ================== glite-wms-job-delegate-proxy Success ================== Your proxy has been successfully delegated to the WMProxy: https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server with the delegation identifier: deleg-4059 ========================================================================== *** Log file created *** Possible Errors and Debug messages have been printed in the following file: /home/ale/UI/test/wms-cli-test_20101018160907/log.txt ===> -> Command success ===> Check logfile ===> ===> [ 16:09:25 ] $ cat /home/ale/UI/test/wms-cli-test_20101018160907/log.txt ===> DEBUG ===> ------------------------------------------ ******************************************************************************** WMS User Interface - version 1.3.1 - Log File ******************************************************************************** ------------------------------------------ 18 Oct 2010, 16:09:24 -I- PID: 4113 (Info) - Function: glite-wms-job-delegate-proxy ------------------------------------------ 18 Oct 2010, 16:09:24 -I- PID: 4113 (Info) - Options: --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf; --logfile /home/ale/UI/test/wms-cli-test_20101018160907/log.txt; --delegationid deleg-4059; ------------------------------------------ 18 Oct 2010, 16:09:24 -I- PID: 4113 (Debug) - Vo read from proxy certificate extension ------------------------------------------ 18 Oct 2010, 16:09:24 -I- PID: 4113 (Debug) - VirtualOrganisation value : dteam ----------------------------------------- 18 Oct 2010, 16:09:24 -I- PID: 4113 (Debug) - Delegation ID: deleg-4059 ----------------------------------------- 18 Oct 2010, 16:09:24 -I- PID: 4113 (Debug) - Getting Endpoint URL from configuration file ----------------------------------------- 18 Oct 2010, 16:09:24 -I- PID: 4113 (Info) - Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ----------------------------------------- 18 Oct 2010, 16:09:24 -I- PID: 4113 (Debug) - Calling the WMProxy getVersion service ----------------------------------------- 18 Oct 2010, 16:09:24 -I- PID: 4113 (Debug) - WMProxy: major version[2] - minor version[9] - release version[9] ----------------------------------------- 18 Oct 2010, 16:09:24 -I- PID: 4113 (Debug) - getVersion - Version successfully retrieved : 2.9.9 ----------------------------------------- 18 Oct 2010, 16:09:24 -I- PID: 4113 (Debug) - WMProxy Version: 2.9.9 ----------------------------------------- 18 Oct 2010, 16:09:24 -I- PID: 4113 (Debug) - Sending Proxy Request to https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ----------------------------------------- 18 Oct 2010, 16:09:24 -I- PID: 4113 (Debug) - Calling the WMProxy delegationns__getProxyReq service ----------------------------------------- 18 Oct 2010, 16:09:25 -I- PID: 4113 (Debug) - delegationns__getProxyReq - The proxy has been successfully retrieved ----------------------------------------- 18 Oct 2010, 16:09:25 -I- PID: 4113 (Debug) - Calling the WMProxy delegationns__putProxyReq service ----------------------------------------- 18 Oct 2010, 16:09:25 -I- PID: 4113 (Debug) - delegationns__putProxyReq - The proxy has been successfully delegated with the identifier: deleg-4059 ----------------------------------------- 18 Oct 2010, 16:09:25 -I- PID: 4113 (Debug) - The proxy has been successfully delegated with the identifier: deleg-4059 ===> -> Command success ===> Verify the delegation ===> ===> [ 16:09:25 ] $ glite-wms-job-info -d deleg-4059 --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ======================= glite-wms-job-info Success ======================= Your proxy delegated to the endpoint https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server with delegationID deleg-4059: Subject : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy/CN=proxy Issuer : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy Identity : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy Type : proxy Strength : 512 StartDate : 18 Oct 2010 - 16:09:25 Expiration : 19 Oct 2010 - 16:08:25 Timeleft : 23 hours 59 min === VO dteam extension information === VO : dteam Subject : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle Issuer : /DC=ch/DC=cern/OU=computers/CN=lcg-voms.cern.ch URI : lcg-voms.cern.ch:15004 Attribute : /dteam/Role=NULL/Capability=NULL StartTime : 18 Oct 2010 - 16:09:12 Expiration : 19 Oct 2010 - 16:09:12 Timeleft : 23 hours 59 min 47 sec ========================================================================== ===> -> Command success ===> Try a submit ===> ===> [ 16:09:25 ] $ glite-wms-job-submit -d deleg-4059 --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf --output /home/ale/UI/test/wms-cli-test_20101018160907/job.id /home/ale/UI/test/wms-cli-test_20101018160907/example.jdl ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ====================== glite-wms-job-submit Success ====================== The job has been successfully submitted to the WMProxy Your job identifier is: https://devel17.cnaf.infn.it:9000/t-HdMutXuIDmth4yfujdYA The job identifier has been saved in the following file: /home/ale/UI/test/wms-cli-test_20101018160907/job.id ========================================================================== ===> -> Command success ===> JOBID: https://devel17.cnaf.infn.it:9000/t-HdMutXuIDmth4yfujdYA ===> Cancel the unused job ===> ===> [ 16:09:30 ] $ glite-wms-job-cancel --noint https://devel17.cnaf.infn.it:9000/t-HdMutXuIDmth4yfujdYA ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ============================= glite-wms-job-cancel Success ============================= The cancellation request has been successfully submitted for the following job(s): - https://devel17.cnaf.infn.it:9000/t-HdMutXuIDmth4yfujdYA ======================================================================================== ===> -> Command success ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/job.id... ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/log.txt... ===> >>> ===> >>> Test option --endpoint ===> ===> [ 16:09:31 ] $ glite-wms-job-delegate-proxy --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf --endpoint https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server -d deleg-4059 --output /home/ale/UI/test/wms-cli-test_20101018160907/output.txt ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ================== glite-wms-job-delegate-proxy Success ================== Your proxy has been successfully delegated to the WMProxy: https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server with the delegation identifier: deleg-4059 The DelegateProxy result has been saved in the following file: /home/ale/UI/test/wms-cli-test_20101018160907/output.txt ========================================================================== ===> -> Command success ===> Verify the delegation ===> ===> [ 16:09:32 ] $ glite-wms-job-info -d deleg-4059 --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ======================= glite-wms-job-info Success ======================= Your proxy delegated to the endpoint https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server with delegationID deleg-4059: Subject : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy/CN=proxy Issuer : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy Identity : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy Type : proxy Strength : 512 StartDate : 18 Oct 2010 - 16:09:32 Expiration : 19 Oct 2010 - 16:08:32 Timeleft : 23 hours 59 min === VO dteam extension information === VO : dteam Subject : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle Issuer : /DC=ch/DC=cern/OU=computers/CN=lcg-voms.cern.ch URI : lcg-voms.cern.ch:15004 Attribute : /dteam/Role=NULL/Capability=NULL StartTime : 18 Oct 2010 - 16:09:12 Expiration : 19 Oct 2010 - 16:09:12 Timeleft : 23 hours 59 min 40 sec ========================================================================== ===> -> Command success ===> Try a submit ===> ===> [ 16:09:32 ] $ glite-wms-job-submit -d deleg-4059 --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf --output /home/ale/UI/test/wms-cli-test_20101018160907/job.id /home/ale/UI/test/wms-cli-test_20101018160907/example.jdl ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ====================== glite-wms-job-submit Success ====================== The job has been successfully submitted to the WMProxy Your job identifier is: https://devel17.cnaf.infn.it:9000/R5b6YZekzFoQq2eQ7Fouow The job identifier has been saved in the following file: /home/ale/UI/test/wms-cli-test_20101018160907/job.id ========================================================================== ===> -> Command success ===> JOBID: https://devel17.cnaf.infn.it:9000/R5b6YZekzFoQq2eQ7Fouow ===> Cancel the unused job ===> ===> [ 16:09:35 ] $ glite-wms-job-cancel --noint https://devel17.cnaf.infn.it:9000/R5b6YZekzFoQq2eQ7Fouow ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ============================= glite-wms-job-cancel Success ============================= The cancellation request has been successfully submitted for the following job(s): - https://devel17.cnaf.infn.it:9000/R5b6YZekzFoQq2eQ7Fouow ======================================================================================== ===> -> Command success ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/job.id... ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/output.txt... ===> >>> ===> >>> Test all options together ===> ===> [ 16:09:38 ] $ glite-wms-job-delegate-proxy --noint --output /home/ale/UI/test/wms-cli-test_20101018160907/output.txt --logfile /home/ale/UI/test/wms-cli-test_20101018160907/log.txt --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf --endpoint https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server -d deleg-4059 ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ================== glite-wms-job-delegate-proxy Success ================== Your proxy has been successfully delegated to the WMProxy: https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server with the delegation identifier: deleg-4059 The DelegateProxy result has been saved in the following file: /home/ale/UI/test/wms-cli-test_20101018160907/output.txt ========================================================================== *** Log file created *** Possible Errors and Debug messages have been printed in the following file: /home/ale/UI/test/wms-cli-test_20101018160907/log.txt ===> -> Command success ===> Check the output ===> ===> [ 16:09:39 ] $ cat /home/ale/UI/test/wms-cli-test_20101018160907/output.txt ===> DEBUG ===> glite-wms-job-delegate-proxy (18 Oct 2010, 16:09:39 ) ========================================================================= WMProxy: https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server delegation ID: deleg-4059 ===> -> Command success ===> Check the logfile ===> ===> [ 16:09:39 ] $ cat /home/ale/UI/test/wms-cli-test_20101018160907/log.txt ===> DEBUG ===> ------------------------------------------ ******************************************************************************** WMS User Interface - version 1.3.1 - Log File ******************************************************************************** ------------------------------------------ 18 Oct 2010, 16:09:38 -I- PID: 4173 (Info) - Function: glite-wms-job-delegate-proxy ------------------------------------------ 18 Oct 2010, 16:09:38 -I- PID: 4173 (Info) - Options: --noint; --output /home/ale/UI/test/wms-cli-test_20101018160907/output.txt; --logfile /home/ale/UI/test/wms-cli-test_20101018160907/log.txt; --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf; --endpoint https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server; --delegationid deleg-4059; ------------------------------------------ 18 Oct 2010, 16:09:38 -I- PID: 4173 (Debug) - Vo read from proxy certificate extension ------------------------------------------ 18 Oct 2010, 16:09:38 -I- PID: 4173 (Debug) - VirtualOrganisation value : dteam ----------------------------------------- 18 Oct 2010, 16:09:38 -I- PID: 4173 (Debug) - Delegation ID: deleg-4059 ----------------------------------------- 18 Oct 2010, 16:09:38 -I- PID: 4173 (Debug) - EndPoint URL from user option: https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ----------------------------------------- 18 Oct 2010, 16:09:38 -I- PID: 4173 (Info) - Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ----------------------------------------- 18 Oct 2010, 16:09:38 -I- PID: 4173 (Debug) - Calling the WMProxy getVersion service ----------------------------------------- 18 Oct 2010, 16:09:38 -I- PID: 4173 (Debug) - WMProxy: major version[2] - minor version[9] - release version[9] ----------------------------------------- 18 Oct 2010, 16:09:38 -I- PID: 4173 (Debug) - getVersion - Version successfully retrieved : 2.9.9 ----------------------------------------- 18 Oct 2010, 16:09:38 -I- PID: 4173 (Debug) - WMProxy Version: 2.9.9 ----------------------------------------- 18 Oct 2010, 16:09:38 -I- PID: 4173 (Debug) - Sending Proxy Request to https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ----------------------------------------- 18 Oct 2010, 16:09:38 -I- PID: 4173 (Debug) - Calling the WMProxy delegationns__getProxyReq service ----------------------------------------- 18 Oct 2010, 16:09:39 -I- PID: 4173 (Debug) - delegationns__getProxyReq - The proxy has been successfully retrieved ----------------------------------------- 18 Oct 2010, 16:09:39 -I- PID: 4173 (Debug) - Calling the WMProxy delegationns__putProxyReq service ----------------------------------------- 18 Oct 2010, 16:09:39 -I- PID: 4173 (Debug) - delegationns__putProxyReq - The proxy has been successfully delegated with the identifier: deleg-4059 ----------------------------------------- 18 Oct 2010, 16:09:39 -I- PID: 4173 (Debug) - The proxy has been successfully delegated with the identifier: deleg-4059 ----------------------------------------- 18 Oct 2010, 16:09:39 -I- PID: 4173 (Debug) - The DelegateProxy result has been saved in the output file /home/ale/UI/test/wms-cli-test_20101018160907/output.txt ===> -> Command success ===> Verify the delegation ===> ===> [ 16:09:39 ] $ glite-wms-job-info -d deleg-4059 --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ======================= glite-wms-job-info Success ======================= Your proxy delegated to the endpoint https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server with delegationID deleg-4059: Subject : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy/CN=proxy Issuer : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy Identity : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy Type : proxy Strength : 512 StartDate : 18 Oct 2010 - 16:09:39 Expiration : 19 Oct 2010 - 16:08:39 Timeleft : 23 hours 59 min === VO dteam extension information === VO : dteam Subject : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle Issuer : /DC=ch/DC=cern/OU=computers/CN=lcg-voms.cern.ch URI : lcg-voms.cern.ch:15004 Attribute : /dteam/Role=NULL/Capability=NULL StartTime : 18 Oct 2010 - 16:09:12 Expiration : 19 Oct 2010 - 16:09:12 Timeleft : 23 hours 59 min 33 sec ========================================================================== ===> -> Command success ===> Try a submit ===> ===> [ 16:09:39 ] $ glite-wms-job-submit -d deleg-4059 --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf --output /home/ale/UI/test/wms-cli-test_20101018160907/job.id /home/ale/UI/test/wms-cli-test_20101018160907/example.jdl ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ====================== glite-wms-job-submit Success ====================== The job has been successfully submitted to the WMProxy Your job identifier is: https://devel17.cnaf.infn.it:9000/9dEY0dBxXWlpcRbjlaNaLA The job identifier has been saved in the following file: /home/ale/UI/test/wms-cli-test_20101018160907/job.id ========================================================================== ===> -> Command success ===> JOBID: https://devel17.cnaf.infn.it:9000/9dEY0dBxXWlpcRbjlaNaLA ===> Cancel the unused job ===> ===> [ 16:09:44 ] $ glite-wms-job-cancel --noint https://devel17.cnaf.infn.it:9000/9dEY0dBxXWlpcRbjlaNaLA ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ============================= glite-wms-job-cancel Success ============================= The cancellation request has been successfully submitted for the following job(s): - https://devel17.cnaf.infn.it:9000/9dEY0dBxXWlpcRbjlaNaLA ======================================================================================== ===> -> Command success ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/job.id... ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/log.txt... ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/output.txt... ===> Initializing proxy file ... ===> DEBUG ===> Your identity: /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle verify OK Creating temporary proxy .......................................................................... Done Contacting lcg-voms.cern.ch:15004 [/DC=ch/DC=cern/OU=computers/CN=lcg-voms.cern.ch] "dteam" Done Creating proxy .............................................................. Done Your proxy is valid until Mon Oct 18 16:19:47 2010 ===> >>> ===> >>> Try to delegate with a short proxy and check the validity ===> ===> [ 16:09:47 ] $ glite-wms-job-delegate-proxy --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf -d deleg-4059 ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ================== glite-wms-job-delegate-proxy Success ================== Your proxy has been successfully delegated to the WMProxy: https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server with the delegation identifier: deleg-4059 ========================================================================== ===> -> Command success ===> Check the delegation timeleft value ===> ===> [ 16:09:48 ] $ glite-wms-job-info -d deleg-4059 --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf --output /home/ale/UI/test/wms-cli-test_20101018160907/output.txt ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ======================= glite-wms-job-info Success ======================= Your proxy delegated to the endpoint https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server with delegationID deleg-4059: Subject : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy/CN=proxy Issuer : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy Identity : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy Type : proxy Strength : 512 StartDate : 18 Oct 2010 - 16:09:48 Expiration : 18 Oct 2010 - 16:18:48 Timeleft : 09 min === VO dteam extension information === VO : dteam Subject : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle Issuer : /DC=ch/DC=cern/OU=computers/CN=lcg-voms.cern.ch URI : lcg-voms.cern.ch:15004 Attribute : /dteam/Role=NULL/Capability=NULL StartTime : 18 Oct 2010 - 16:09:46 Expiration : 18 Oct 2010 - 16:19:46 Timeleft : 09 min 58 sec ========================================================================== The DelegateProxy result has been saved in the following file: /home/ale/UI/test/wms-cli-test_20101018160907/output.txt ===> -> Command success ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/output.txt... ===> Verify the delegation ===> ===> [ 16:09:48 ] $ glite-wms-job-info -d deleg-4059 --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ======================= glite-wms-job-info Success ======================= Your proxy delegated to the endpoint https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server with delegationID deleg-4059: Subject : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy/CN=proxy Issuer : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy Identity : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy Type : proxy Strength : 512 StartDate : 18 Oct 2010 - 16:09:48 Expiration : 18 Oct 2010 - 16:18:48 Timeleft : 08 min 59 sec === VO dteam extension information === VO : dteam Subject : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle Issuer : /DC=ch/DC=cern/OU=computers/CN=lcg-voms.cern.ch URI : lcg-voms.cern.ch:15004 Attribute : /dteam/Role=NULL/Capability=NULL StartTime : 18 Oct 2010 - 16:09:46 Expiration : 18 Oct 2010 - 16:19:46 Timeleft : 09 min 57 sec ========================================================================== ===> -> Command success ===> Try a submit ===> ===> [ 16:09:49 ] $ glite-wms-job-submit -d deleg-4059 --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf --output /home/ale/UI/test/wms-cli-test_20101018160907/job.id /home/ale/UI/test/wms-cli-test_20101018160907/example.jdl ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ====================== glite-wms-job-submit Success ====================== The job has been successfully submitted to the WMProxy Your job identifier is: https://devel17.cnaf.infn.it:9000/oJ1l4gOJk8uGkIYXrfb4-A The job identifier has been saved in the following file: /home/ale/UI/test/wms-cli-test_20101018160907/job.id ========================================================================== ===> -> Command success ===> JOBID: https://devel17.cnaf.infn.it:9000/oJ1l4gOJk8uGkIYXrfb4-A ===> Cancel the unused job ===> ===> [ 16:09:51 ] $ glite-wms-job-cancel --noint https://devel17.cnaf.infn.it:9000/oJ1l4gOJk8uGkIYXrfb4-A ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ============================= glite-wms-job-cancel Success ============================= The cancellation request has been successfully submitted for the following job(s): - https://devel17.cnaf.infn.it:9000/oJ1l4gOJk8uGkIYXrfb4-A ======================================================================================== ===> -> Command success ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/job.id... ===> Initializing proxy file ... ===> DEBUG ===> Your identity: /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle verify OK Creating temporary proxy .................................. Done Contacting lcg-voms.cern.ch:15004 [/DC=ch/DC=cern/OU=computers/CN=lcg-voms.cern.ch] "dteam" Done Creating proxy .............................................................................................. Done Your proxy is valid until Mon Oct 18 16:10:54 2010 ===> >>> ===> >>> Works with expiring proxy... ===> ===> [ 16:09:54 ] $ glite-wms-job-delegate-proxy --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf -d deleg-4059 ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ================== glite-wms-job-delegate-proxy Success ================== Your proxy has been successfully delegated to the WMProxy: https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server with the delegation identifier: deleg-4059 ========================================================================== ===> -> Command success ===> Wait until proxy expired... ===> ===> [ 16:09:58 ] $ sleep 60 ===> DEBUG ===> ===> -> Command success ===> >>> Try to delegate with an expired proxy ===> ===> [ 16:10:58 ] $ glite-wms-job-delegate-proxy --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf --autm-delegation ===> DEBUG ===> Error - Proxy validity Error Your proxy credential has expired ===> -> Command successfully failed ===> Initializing proxy file ... ===> DEBUG ===> Your identity: /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle verify OK Creating temporary proxy ...................................................... Done Contacting lcg-voms.cern.ch:15004 [/DC=ch/DC=cern/OU=computers/CN=lcg-voms.cern.ch] "dteam" Done Creating proxy ........................ Done Your proxy is valid until Tue Oct 19 16:10:59 2010 ===> Check if the old delegation is expired ===> ===> [ 16:10:59 ] $ glite-wms-job-info --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf -d deleg-4059 ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server ======================= glite-wms-job-info Success ======================= Your proxy delegated to the endpoint https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server with delegationID deleg-4059: Subject : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy/CN=proxy Issuer : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy Identity : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle/CN=proxy Type : proxy Strength : 512 StartDate : 18 Oct 2010 - 16:09:58 Expiration : 18 Oct 2010 - 16:09:58 === VO dteam extension information === VO : dteam Subject : /C=IT/O=INFN/OU=Personal Certificate/L=Padova/CN=Alessio Gianelle Issuer : /DC=ch/DC=cern/OU=computers/CN=lcg-voms.cern.ch URI : lcg-voms.cern.ch:15004 Attribute : /dteam/Role=NULL/Capability=NULL StartTime : 18 Oct 2010 - 16:09:53 Expiration : 18 Oct 2010 - 16:10:53 ========================================================================== ===> ===> Started: 16:09:07 ===> Ended : 16:11:00 ===> ===> >>> TEST FAILED <<< ===> >>> failure reason: glite-wms-job-info failed <<< ===> ===> Test directory /home/ale/UI/test/wms-cli-test_20101018160907 ===> has not been cleaned for debug purpose ===> >>> Try to submit with an expired delegation ===> ===> [ 16:11:00 ] $ glite-wms-job-submit -d deleg-4059 --config /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf --logfile /home/ale/UI/test/wms-cli-test_20101018160907/log.txt /home/ale/UI/test/wms-cli-test_20101018160907/example.jdl ===> DEBUG ===> Connecting to the service https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server Warning - Unable to submit the job to the service: https://cream-45.pd.infn.it:7443/glite_wms_wmproxy_server Proxy exception: The delegated Proxy has expired Method: jobSubmit Error - Operation failed Unable to find any endpoint where to perform service request Possible Errors and Debug messages have been printed in the following file: /home/ale/UI/test/wms-cli-test_20101018160907/log.txt ===> -> Command successfully failed ===> Check the output of the command ===> ===> [ 16:11:02 ] $ grep "The delegated Proxy has expired" /home/ale/UI/test/wms-cli-test_20101018160907/log.txt ===> DEBUG ===> Proxy exception: The delegated Proxy has expired ===> -> Command success ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/log.txt... ===> ===> Cleaning up /home/ale/UI/test/wms-cli-test_20101018160907 ... ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/output.txt... ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/log.txt... ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/job.id... ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/example.jdl... ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/wms.conf... ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/file.tmp... ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/jobOutput/std.out... ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/jobOutput/std.err... ===> DEBUG ===> Remove file /home/ale/UI/test/wms-cli-test_20101018160907/jobOutput/out.txt... ===> DEBUG ===> Remove /home/ale/UI/test/wms-cli-test_20101018160907/jobOutput directory ... ===> DEBUG ===> Remove /home/ale/UI/test/wms-cli-test_20101018160907 directory ... ===> ===> Started: 16:09:07 ===> Ended : 16:11:02 ===> ===> === test PASSED ===