03/18/05 09:58:57 -151080192 ProxyGen __init__.py:988 DEBUG Using GT24 proxy code 03/18/05 09:58:58 -151080192 Toolkit __init__.py:988 DEBUG Initializing AG Toolkit version 2.3 03/18/05 09:58:58 -151080192 Toolkit __init__.py:988 INFO Logfile Name: BridgeServer.log 03/18/05 09:58:59 -151080192 Toolkit __init__.py:988 INFO Service init: have profile None 03/18/05 09:58:59 -151080192 Platform __init__.py:988 DEBUG retrieved local IP address 164.54.100.13 03/18/05 09:58:59 -151080192 CertificateManager __init__.py:988 DEBUG Opened repository /aguser/.AccessGrid/Config/certRepo 03/18/05 09:58:59 -151080192 Toolkit __init__.py:988 INFO Initialized cert mgmt. 03/18/05 09:58:59 -151080192 Toolkit __init__.py:988 INFO Loaded profile and configured with it. 03/18/05 09:58:59 -151080192 CertificateManager __init__.py:988 DEBUG Configuring standard environment 03/18/05 09:58:59 -151080192 CertificateManager __init__.py:988 DEBUG Using default identity /O=Access Grid/OU=agdev-ca.mcs.anl.gov/OU=aps.anl.gov/CN=Brian Tieman 03/18/05 09:58:59 -151080192 CertificateManager __init__.py:988 DEBUG Initializing environment with proxy cert for /O=Access Grid/OU=agdev-ca.mcs.anl.gov/OU=aps.anl.gov/CN=Brian Tieman 03/18/05 09:58:59 -151080192 CertificateManager __init__.py:988 DEBUG Looking for identity (unused) None 03/18/05 09:58:59 -151080192 CertificateManager __init__.py:988 DEBUG Configuring for user proxy issued from /O=Access Grid/OU=agdev-ca.mcs.anl.gov/OU=aps.anl.gov/CN=Brian Tieman 03/18/05 09:58:59 -151080192 CertificateManager __init__.py:988 DEBUG Proxy /tmp/x509up_u501 will expire 03/22/05 10:05:55 03/18/05 09:58:59 -151080192 CertificateManager __init__.py:988 DEBUG done, success=1 03/18/05 09:58:59 -151080192 Toolkit __init__.py:988 INFO Initialized Globus. 03/18/05 09:58:59 -151080192 Toolkit __init__.py:988 INFO Service Initialization Complete. 03/18/05 09:58:59 -151080192 Toolkit __init__.py:988 INFO AddVenueServer: url = https://pcdiag11.xor.aps.anl.gov:8000/VenueServer 03/18/05 09:58:59 -151080192 Toolkit __init__.py:988 DEBUG Initializing AG Toolkit version 2.3 03/18/05 09:59:05 -151080192 Toolkit __init__.py:988 INFO AddVenue: url = https://164.54.100.13:8000/Venues/0000010216f41e4b00a400360064000d967 03/18/05 09:59:19 -151080192 EventClient __init__.py:988 DEBUG Have callback handle _78aa9409_p_callbackStruct 03/18/05 09:59:30 -151080192 Toolkit __init__.py:988 INFO AddVenue: url = https://164.54.100.13:8000/Venues/000001025fe422fe00a400360064000d30b 03/18/05 09:59:30 -211141712 Toolkit __init__.py:988 INFO Method Venue.RunQueueThread called 03/18/05 09:59:56 -151080192 EventClient __init__.py:988 DEBUG Have callback handle _00568f09_p_callbackStruct 03/18/05 10:00:04 -151080192 Toolkit __init__.py:988 INFO AddVenue: url = https://164.54.100.13:8000/Venues/000001025fe4f27c00a400360064000dfa4 03/18/05 10:00:04 -242611280 Toolkit __init__.py:988 INFO Method Venue.RunQueueThread called 03/18/05 10:00:33 -151080192 EventClient __init__.py:988 DEBUG Have callback handle _80e69809_p_callbackStruct 03/18/05 10:00:45 -151080192 Toolkit __init__.py:988 INFO AddVenue: url = https://164.54.100.13:8000/Venues/000001025fe5645700a400360064000db90 03/18/05 10:00:45 -274080848 Toolkit __init__.py:988 INFO Method Venue.RunQueueThread called 03/18/05 10:00:45 -274080848 Toolkit __init__.py:988 INFO Method Venue.AddBridge called 03/18/05 10:00:45 -274080848 Toolkit __init__.py:988 INFO Method CreateBridge called 03/18/05 10:00:45 -274080848 Toolkit __init__.py:988 INFO Allocated port = 30002 03/18/05 10:00:45 -274080848 Toolkit __init__.py:988 INFO - creating new bridge 03/18/05 10:00:45 -274080848 Toolkit __init__.py:988 INFO Method Bridge.Start called 03/18/05 10:00:45 -274080848 Toolkit __init__.py:988 INFO Starting bridge: 03/18/05 10:00:45 -274080848 Toolkit __init__.py:988 INFO [maddr,mport,mttl] = 224.2.150.90 52847 127 03/18/05 10:00:45 -274080848 Toolkit __init__.py:988 INFO [uaddr,uport] = pcdiag11 30002 03/18/05 10:00:45 -274080848 Toolkit __init__.py:988 INFO Starting bridge: /usr/bin/QuickBridge ['-g', '224.2.150.90', '-m', '52847', '-u', '30002'] 03/18/05 10:00:58 -158688336 EventClient __init__.py:988 DEBUG Read Callback called arg=None handle=_f84f9d09_p_globus_io_handle_t result=(0, None) n=4 03/18/05 10:00:58 -158688336 EventClient __init__.py:988 DEBUG Freeing callback _80e69809_p_callbackStruct within callback 03/18/05 10:00:58 -158688336 EventClient __init__.py:988 DEBUG Have new callback handle _80e69809_p_callbackStruct 03/18/05 10:00:58 -158688336 EventClient __init__.py:988 DEBUG Read Callback called arg=None handle=_f84f9d09_p_globus_io_handle_t result=(0, None) n=1209 03/18/05 10:00:58 -158688336 EventClient __init__.py:988 DEBUG finally read enough data, wait=1209 buflen=1209 03/18/05 10:00:58 -158688336 EventClient __init__.py:988 DEBUG handleData returns 03/18/05 10:00:58 -158688336 EventClient __init__.py:988 DEBUG Freeing callback _80e69809_p_callbackStruct within callback 03/18/05 10:00:58 -158688336 EventClient __init__.py:988 DEBUG Have new callback handle _80e69809_p_callbackStruct 03/18/05 10:01:02 -274080848 Toolkit __init__.py:988 INFO Method Venue.AddBridge called 03/18/05 10:01:02 -274080848 Toolkit __init__.py:988 INFO Method CreateBridge called 03/18/05 10:01:02 -274080848 Toolkit __init__.py:988 INFO Allocated port = 30008 03/18/05 10:01:02 -274080848 Toolkit __init__.py:988 INFO - creating new bridge 03/18/05 10:01:02 -274080848 Toolkit __init__.py:988 INFO Method Bridge.Start called 03/18/05 10:01:02 -274080848 Toolkit __init__.py:988 INFO Starting bridge: 03/18/05 10:01:02 -274080848 Toolkit __init__.py:988 INFO [maddr,mport,mttl] = 224.2.133.108 56389 127 03/18/05 10:01:02 -274080848 Toolkit __init__.py:988 INFO [uaddr,uport] = pcdiag11 30008 03/18/05 10:01:02 -274080848 Toolkit __init__.py:988 INFO Starting bridge: /usr/bin/QuickBridge ['-g', '224.2.133.108', '-m', '56389', '-u', '30008'] 03/18/05 10:01:13 -158688336 EventClient __init__.py:988 DEBUG Read Callback called arg=None handle=_f84f9d09_p_globus_io_handle_t result=(0, None) n=4 03/18/05 10:01:13 -158688336 EventClient __init__.py:988 DEBUG Freeing callback _80e69809_p_callbackStruct within callback 03/18/05 10:01:13 -158688336 EventClient __init__.py:988 DEBUG Have new callback handle _80e69809_p_callbackStruct 03/18/05 10:01:13 -158688336 EventClient __init__.py:988 DEBUG Read Callback called arg=None handle=_f84f9d09_p_globus_io_handle_t result=(0, None) n=1210 03/18/05 10:01:13 -158688336 EventClient __init__.py:988 DEBUG finally read enough data, wait=1210 buflen=1210 03/18/05 10:01:13 -158688336 EventClient __init__.py:988 DEBUG handleData returns 03/18/05 10:01:13 -158688336 EventClient __init__.py:988 DEBUG Freeing callback _80e69809_p_callbackStruct within callback 03/18/05 10:01:13 -158688336 EventClient __init__.py:988 DEBUG Have new callback handle _80e69809_p_callbackStruct 03/18/05 10:01:31 -151080192 EventClient __init__.py:988 DEBUG Have callback handle _40dd9809_p_callbackStruct 03/18/05 10:01:42 -305550416 Toolkit __init__.py:988 INFO Method Venue.RunQueueThread called 03/18/05 10:07:36 -151080192 Toolkit __init__.py:988 INFO Method BridgeServer.Shutdown called 03/18/05 10:07:36 -151080192 Toolkit __init__.py:988 INFO Method BridgeServer.RemoveVenues called 03/18/05 10:07:36 -151080192 Toolkit __init__.py:988 INFO Method Venue.Shutdown called 03/18/05 10:07:36 -151080192 Toolkit __init__.py:988 INFO - Send stop message to bridges 03/18/05 10:07:36 -151080192 Toolkit __init__.py:988 INFO - Stopping event client 03/18/05 10:07:36 -151080192 EventClient __init__.py:988 DEBUG Cancel pending callbacks 03/18/05 10:07:36 -158688336 EventClient __init__.py:988 DEBUG Read Callback called arg=None handle=_68bf9d09_p_globus_io_handle_t result=(48, 'an I/O operation was cancelled') n=0 03/18/05 10:07:36 -158688336 EventClient __init__.py:988 DEBUG readCallback gets failure in result: 48 an I/O operation was cancelled 03/18/05 10:07:37 -253101136 Toolkit __init__.py:988 INFO Heartbeat thread exiting (1) 03/18/05 10:07:37 -151080192 EventClient __init__.py:988 DEBUG Free callback _00568f09_p_callbackStruct 03/18/05 10:07:37 -151080192 EventClient __init__.py:988 DEBUG EventClient.Stop: closing socket 03/18/05 10:07:37 -151080192 EventClient __init__.py:988 DEBUG EventClient.Stop: closing queue 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO - Wait for bridges to shutdown 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO - Send stop message to queue processor 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO Shutdown exiting 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO Method Venue.Shutdown called 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO - Send stop message to bridges 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO - Stopping event client 03/18/05 10:07:37 -151080192 EventClient __init__.py:988 DEBUG Cancel pending callbacks 03/18/05 10:07:37 -242611280 Toolkit __init__.py:988 INFO RunQueueThread exiting 03/18/05 10:07:37 -190162000 EventClient __init__.py:988 DEBUG Read Callback called arg=None handle=_489b8d09_p_globus_io_handle_t result=(50, 'an I/O operation was cancelled') n=0 03/18/05 10:07:37 -190162000 EventClient __init__.py:988 DEBUG readCallback gets failure in result: 50 an I/O operation was cancelled 03/18/05 10:07:37 -151080192 EventClient __init__.py:988 DEBUG Free callback _78aa9409_p_callbackStruct 03/18/05 10:07:37 -151080192 EventClient __init__.py:988 DEBUG EventClient.Stop: closing socket 03/18/05 10:07:37 -151080192 EventClient __init__.py:988 DEBUG EventClient.Stop: closing queue 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO - Wait for bridges to shutdown 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO - Send stop message to queue processor 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO Shutdown exiting 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO Method Venue.Shutdown called 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO - Send stop message to bridges 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO - Stopping event client 03/18/05 10:07:37 -151080192 EventClient __init__.py:988 DEBUG Cancel pending callbacks 03/18/05 10:07:37 -211141712 Toolkit __init__.py:988 INFO RunQueueThread exiting 03/18/05 10:07:37 -221631568 Toolkit __init__.py:988 INFO Heartbeat thread exiting (1) 03/18/05 10:07:37 -316040272 Toolkit __init__.py:988 INFO Heartbeat thread exiting (1) 03/18/05 10:07:37 -169178192 EventClient __init__.py:988 DEBUG Read Callback called arg=None handle=_28949e09_p_globus_io_handle_t result=(52, 'an I/O operation was cancelled') n=0 03/18/05 10:07:37 -169178192 EventClient __init__.py:988 DEBUG readCallback gets failure in result: 52 an I/O operation was cancelled 03/18/05 10:07:37 -151080192 EventClient __init__.py:988 DEBUG Free callback _40dd9809_p_callbackStruct 03/18/05 10:07:37 -151080192 EventClient __init__.py:988 DEBUG EventClient.Stop: closing socket 03/18/05 10:07:37 -151080192 EventClient __init__.py:988 DEBUG EventClient.Stop: closing queue 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO - Wait for bridges to shutdown 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO - Send stop message to queue processor 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO Shutdown exiting 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO Method Venue.Shutdown called 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO - Send stop message to bridges 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO Method Venue.RemoveBridge called 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO streamId = 00000102b64bc71800a400360064000dac9 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO removed 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO Method DestroyBridge called 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO - Refcount zero; stopping and deleting bridge 03/18/05 10:07:37 -284570704 Toolkit __init__.py:988 INFO Heartbeat thread exiting (1) 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO Method Bridge.Stop called 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO Method Venue.RemoveBridge called 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO streamId = 00000102b64bc71c00a400360064000d146 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO removed 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO Method DestroyBridge called 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO - Refcount zero; stopping and deleting bridge 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO Method Bridge.Stop called 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO - Stopping event client 03/18/05 10:07:37 -151080192 EventClient __init__.py:988 DEBUG Cancel pending callbacks 03/18/05 10:07:37 -305550416 Toolkit __init__.py:988 INFO RunQueueThread exiting 03/18/05 10:07:37 -158688336 EventClient __init__.py:988 DEBUG Read Callback called arg=None handle=_f84f9d09_p_globus_io_handle_t result=(54, 'an I/O operation was cancelled') n=0 03/18/05 10:07:37 -158688336 EventClient __init__.py:988 DEBUG readCallback gets failure in result: 54 an I/O operation was cancelled 03/18/05 10:07:37 -151080192 EventClient __init__.py:988 DEBUG Free callback _80e69809_p_callbackStruct 03/18/05 10:07:37 -151080192 EventClient __init__.py:988 DEBUG EventClient.Stop: closing socket 03/18/05 10:07:37 -151080192 EventClient __init__.py:988 DEBUG EventClient.Stop: closing queue 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO - Wait for bridges to shutdown 03/18/05 10:07:37 -151080192 Toolkit __init__.py:988 INFO - Send stop message to queue processor 03/18/05 10:07:38 -151080192 Toolkit __init__.py:988 INFO Shutdown exiting 03/18/05 10:07:38 -274080848 Toolkit __init__.py:988 INFO RunQueueThread exiting