[AG-TECH] Status of AG Connector plugin?

Mike.W.Daley at cs.cardiff.ac.uk Mike.W.Daley at cs.cardiff.ac.uk
Fri Dec 4 14:52:16 CST 2009


Hi Tom
I have attached my log file
Mike




Quoting Jeremy Mann <jeremymann at gmail.com>:

> On Fri, Dec 4, 2009 at 11:12 AM, Thomas Uram <turam at mcs.anl.gov> wrote:
>> I expect you'll find some info about the error in the VenueClient.log file,
>> in the Access Grid directory.
>>
>> Linux: ~/.AccessGrid3/Logs
>> Windows: documents and settings\username\application data\accessgrid3\logs
>
> This is where it "hangs" in the VenueClient log:
>
> VenueClientController VenueClientController.py:1646 DEBUG THere is no
> personal venues file to load.
>
>
>
> --
> Jeremy Mann
> jeremy at biochem.uthscsa.edu
>
> University of Texas Health Science Center
> Bioinformatics Core Facility
> http://www.bioinformatics.uthscsa.edu
> Phone: (210) 567-2672
>



Michael Daley M.Phil MBCS GCFA

----------------------------------------------------------------
This message was sent using IMP, the Internet Messaging Program.

-------------- next part --------------
12/04/09 20:20:48 5228 ServiceDiscovery     servicediscovery.py:62 INFO  Required bonjour libs not found; service discovery disabled
12/04/09 20:20:48 5228 Toolkit     config.py:630 ERROR Failed to determine local IP address, using 127.0.0.1
Traceback (most recent call last):
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Config.py", line 624, in _SetHostnameToLocalIP
    self.hostname = self.GetLocalIPAddress()
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Platform\win32\Config.py", line 451, in GetLocalIPAddress
    if parts[0] == "0.0.0.0":
IndexError: list index out of range
12/04/09 20:20:48 5228 Toolkit     toolkit.py:116 DEBUG Initializing AG Toolkit version 3.1 
12/04/09 20:20:48 5228 Toolkit     toolkit.py:117 INFO  Command and arguments: ['C:\\Program Files\\AGTk-3\\bin\\VenueClient3.py']
12/04/09 20:20:48 5228 VenueClient     preferences.py:188 DEBUG Preferences.LoadPreferences: open file
12/04/09 20:20:48 5228 Toolkit     toolkit.py:166 INFO  Logfile Name: VenueClient.log
12/04/09 20:20:48 5228 Toolkit     venueclient3.py:137 INFO  wx version is: 2.6.3.3
12/04/09 20:20:48 5228 VenueClient     venueclient.py:529 DEBUG __StartWebService: service manager: http://127.0.0.1:11000/ServiceManager
12/04/09 20:20:48 5228 VenueClient     venueclient.py:543 DEBUG __StartWebService: node service: http://127.0.0.1:11000/NodeService
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:640 INFO  NodeService.GetConfigurations
12/04/09 20:20:48 5228 VenueClient     venueclient.py:270 DEBUG Loading node configuration: <AccessGrid.Descriptions.NodeConfigDescription instance at 0x05306710>
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:379 INFO  NodeService.LoadConfiguration
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:398 INFO  Trying to load node configuration from: C:\Program Files\AGTk-3\Config\nodeConfig\quincy09
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:406 DEBUG section: node
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   servicemanagers : servicemanager0
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:406 DEBUG section: serviceconfig0
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Use site id : On
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Silence Suppression : Off
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Start Priority : 5
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Output Gain : 50
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Talk : Off
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Input Gain : 50
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:406 DEBUG section: servicemanager0
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   url : 
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   builtin : 1
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   services : service0 service1 service2 service3
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   name : 
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:406 DEBUG section: serviceconfig1
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Start Priority : 7
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Thumbnail Columns : 2
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Processor usage : All
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:406 DEBUG section: service3
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   packageName : VideoProducerService.zip
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   resource : resource3
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   serviceConfig : serviceconfig3
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:406 DEBUG section: service2
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   packageName : VideoProducerService.zip
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   resource : resource2
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   serviceConfig : serviceconfig2
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:406 DEBUG section: service1
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   packageName : VideoConsumerService.zip
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   serviceConfig : serviceconfig1
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:406 DEBUG section: service0
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   packageName : AudioService.zip
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   serviceConfig : serviceconfig0
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:406 DEBUG section: serviceconfig3
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Frame Rate : 24
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Encoding : h261
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Start Priority : 5
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Standard : PAL
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Bandwidth : 800
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Processor usage : All
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Port : external-in
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Quality : 75
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Stream Name : SPC 610NC Laptop Camera
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:406 DEBUG section: serviceconfig2
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Frame Rate : 24
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Encoding : h261
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Start Priority : 5
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Standard : PAL
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Bandwidth : 800
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Processor usage : All
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Port : external-in
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Quality : 75
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   Stream Name : Sony Visual Communication Camera
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:406 DEBUG section: resource3
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   name : SPC 610NC Laptop Camera
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:406 DEBUG section: resource2
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:408 DEBUG   name : Sony Visual Communication Camera
12/04/09 20:20:48 5228 NodeService     agnodeservice.py:483 DEBUG using builtin service manager at http://127.0.0.1:11000/ServiceManager
12/04/09 20:20:49 5228 NodeService     agnodeservice.py:518 ERROR Exception adding service AudioService.zip
Traceback (most recent call last):
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGNodeService.py", line 515, in LoadConfiguration
    prefs.GetProfile())
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\interfaces\AGServiceManager_client.py", line 60, in AddServiceByName
    response = self.binding.Receive(AddServiceByNameResponse.typecode)
  File "C:\Python24\lib\site-packages\ZSI\client.py", line 504, in Receive
    return _Binding.Receive(self, replytype, **kw)
  File "C:\Python24\lib\site-packages\ZSI\client.py", line 432, in Receive
    raise FaultException(msg)
FaultException: Processing Failure
exceptions:IOError
[Errno 9] Bad file descriptor
[trace: C:\Python24\lib\site-packages\ZSI\ServiceContainer.py:108:_Dispatch
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\interfaces\AGServiceManager_interface.py:960:soap_AddServiceByName
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGServiceManager.py:97:AddServiceByName
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGServiceManager.py:147:AddService
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGServiceManager.py:413:__ExecuteService
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Platform\win32\ProcessManager.py:75:StartProcess
C:\Python24\lib\logging\__init__.py:955:debug
C:\Python24\lib\logging\__init__.py:1079:_log
C:\Python24\lib\logging\__init__.py:1089:handle
C:\Python24\lib\logging\__init__.py:1126:callHandlers
C:\Python24\lib\logging\__init__.py:642:handle
C:\Python24\lib\logging\handlers.py:930:emit
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Log.py:436:flush
C:\Python24\lib\logging\__init__.py:642:handle
C:\Python24\lib\logging\handlers.py:79:emit
C:\Python24\lib\logging\__init__.py:693:handleError
C:\Python24\lib\traceback.py:125:print_exception
C:\Python24\lib\traceback.py:67:print_tb
C:\Python24\lib\traceback.py:13:_print]
12/04/09 20:20:50 5228 NodeService     agnodeservice.py:518 ERROR Exception adding service VideoProducerService.zip
Traceback (most recent call last):
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGNodeService.py", line 515, in LoadConfiguration
    prefs.GetProfile())
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\interfaces\AGServiceManager_client.py", line 60, in AddServiceByName
    response = self.binding.Receive(AddServiceByNameResponse.typecode)
  File "C:\Python24\lib\site-packages\ZSI\client.py", line 504, in Receive
    return _Binding.Receive(self, replytype, **kw)
  File "C:\Python24\lib\site-packages\ZSI\client.py", line 432, in Receive
    raise FaultException(msg)
FaultException: Processing Failure
exceptions:IOError
[Errno 9] Bad file descriptor
[trace: C:\Python24\lib\site-packages\ZSI\ServiceContainer.py:108:_Dispatch
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\interfaces\AGServiceManager_interface.py:960:soap_AddServiceByName
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGServiceManager.py:97:AddServiceByName
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGServiceManager.py:147:AddService
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGServiceManager.py:378:__ExecuteService
C:\Python24\lib\logging\__init__.py:955:debug
C:\Python24\lib\logging\__init__.py:1079:_log
C:\Python24\lib\logging\__init__.py:1089:handle
C:\Python24\lib\logging\__init__.py:1126:callHandlers
C:\Python24\lib\logging\__init__.py:642:handle
C:\Python24\lib\logging\handlers.py:930:emit
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Log.py:436:flush
C:\Python24\lib\logging\__init__.py:642:handle
C:\Python24\lib\logging\handlers.py:79:emit
C:\Python24\lib\logging\__init__.py:693:handleError
C:\Python24\lib\traceback.py:125:print_exception
C:\Python24\lib\traceback.py:70:print_tb
C:\Python24\lib\traceback.py:13:_print]
12/04/09 20:20:51 5228 VenueClient     venueclient.py:276 ERROR Error loading node configuration: None
Traceback (most recent call last):
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\VenueClient.py", line 271, in __init__
    self.nodeService.LoadConfiguration(nodeConfig)
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGNodeService.py", line 522, in LoadConfiguration
    raise Exception(exceptionText)
Exception: Couldn't add service AudioService.zipCouldn't add service VideoProducerService.zip
12/04/09 20:20:51 5228 VenueClient     venueclient.py:365 DEBUG get bridges from registry
12/04/09 20:20:51 5228 VenueClient     venueclient.py:371 DEBUG Trying bridge registry: http://www.accessgrid.org/registry/peers.txt
12/04/09 20:20:51 5228 VenueClient     preferences.py:188 DEBUG Preferences.LoadPreferences: open file
12/04/09 20:20:53 5228 VenueClient     venueclient.py:371 DEBUG Trying bridge registry: http://www.ap-accessgrid.org/registry/peers.txt
12/04/09 20:20:53 5228 VenueClient     preferences.py:188 DEBUG Preferences.LoadPreferences: open file
12/04/09 20:20:56 5228 VenueClient     venueclient.py:397 DEBUG connect to bridge
12/04/09 20:20:56 5228 VenueClient     venueclient.py:401 DEBUG exiting loadbridges
12/04/09 20:24:45 3272 ServiceDiscovery     servicediscovery.py:62 INFO  Required bonjour libs not found; service discovery disabled
12/04/09 20:24:45 3272 Toolkit     config.py:630 ERROR Failed to determine local IP address, using 127.0.0.1
Traceback (most recent call last):
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Config.py", line 624, in _SetHostnameToLocalIP
    self.hostname = self.GetLocalIPAddress()
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Platform\win32\Config.py", line 451, in GetLocalIPAddress
    if parts[0] == "0.0.0.0":
IndexError: list index out of range
12/04/09 20:24:45 3272 Toolkit     toolkit.py:116 DEBUG Initializing AG Toolkit version 3.1 
12/04/09 20:24:45 3272 Toolkit     toolkit.py:117 INFO  Command and arguments: ['C:\\Program Files\\AGTk-3\\bin\\VenueClient3.py']
12/04/09 20:24:45 3272 VenueClient     preferences.py:188 DEBUG Preferences.LoadPreferences: open file
12/04/09 20:24:45 3272 Toolkit     toolkit.py:166 INFO  Logfile Name: VenueClient.log
12/04/09 20:24:45 3272 Toolkit     venueclient3.py:137 INFO  wx version is: 2.6.3.3
12/04/09 20:24:45 3272 VenueClient     venueclient.py:529 DEBUG __StartWebService: service manager: http://127.0.0.1:11000/ServiceManager
12/04/09 20:24:45 3272 VenueClient     venueclient.py:543 DEBUG __StartWebService: node service: http://127.0.0.1:11000/NodeService
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:640 INFO  NodeService.GetConfigurations
12/04/09 20:24:45 3272 VenueClient     venueclient.py:270 DEBUG Loading node configuration: <AccessGrid.Descriptions.NodeConfigDescription instance at 0x0522DF30>
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:379 INFO  NodeService.LoadConfiguration
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:398 INFO  Trying to load node configuration from: C:\Program Files\AGTk-3\Config\nodeConfig\quincy09
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:406 DEBUG section: node
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   servicemanagers : servicemanager0
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:406 DEBUG section: serviceconfig0
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Use site id : On
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Silence Suppression : Off
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Start Priority : 5
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Output Gain : 50
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Talk : Off
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Input Gain : 50
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:406 DEBUG section: servicemanager0
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   url : 
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   builtin : 1
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   services : service0 service1 service2 service3
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   name : 
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:406 DEBUG section: serviceconfig1
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Start Priority : 7
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Thumbnail Columns : 2
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Processor usage : All
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:406 DEBUG section: service3
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   packageName : VideoProducerService.zip
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   resource : resource3
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   serviceConfig : serviceconfig3
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:406 DEBUG section: service2
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   packageName : VideoProducerService.zip
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   resource : resource2
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   serviceConfig : serviceconfig2
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:406 DEBUG section: service1
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   packageName : VideoConsumerService.zip
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   serviceConfig : serviceconfig1
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:406 DEBUG section: service0
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   packageName : AudioService.zip
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   serviceConfig : serviceconfig0
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:406 DEBUG section: serviceconfig3
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Frame Rate : 24
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Encoding : h261
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Start Priority : 5
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Standard : PAL
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Bandwidth : 800
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Processor usage : All
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Port : external-in
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Quality : 75
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Stream Name : SPC 610NC Laptop Camera
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:406 DEBUG section: serviceconfig2
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Frame Rate : 24
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Encoding : h261
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Start Priority : 5
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Standard : PAL
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Bandwidth : 800
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Processor usage : All
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Port : external-in
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Quality : 75
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   Stream Name : Sony Visual Communication Camera
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:406 DEBUG section: resource3
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   name : SPC 610NC Laptop Camera
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:406 DEBUG section: resource2
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:408 DEBUG   name : Sony Visual Communication Camera
12/04/09 20:24:45 3272 NodeService     agnodeservice.py:483 DEBUG using builtin service manager at http://127.0.0.1:11000/ServiceManager
12/04/09 20:24:46 3272 NodeService     agnodeservice.py:518 ERROR Exception adding service AudioService.zip
Traceback (most recent call last):
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGNodeService.py", line 515, in LoadConfiguration
    prefs.GetProfile())
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\interfaces\AGServiceManager_client.py", line 60, in AddServiceByName
    response = self.binding.Receive(AddServiceByNameResponse.typecode)
  File "C:\Python24\lib\site-packages\ZSI\client.py", line 504, in Receive
    return _Binding.Receive(self, replytype, **kw)
  File "C:\Python24\lib\site-packages\ZSI\client.py", line 432, in Receive
    raise FaultException(msg)
FaultException: Processing Failure
exceptions:IOError
[Errno 9] Bad file descriptor
[trace: C:\Python24\lib\site-packages\ZSI\ServiceContainer.py:108:_Dispatch
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\interfaces\AGServiceManager_interface.py:960:soap_AddServiceByName
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGServiceManager.py:97:AddServiceByName
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGServiceManager.py:147:AddService
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGServiceManager.py:378:__ExecuteService
C:\Python24\lib\logging\__init__.py:955:debug
C:\Python24\lib\logging\__init__.py:1079:_log
C:\Python24\lib\logging\__init__.py:1089:handle
C:\Python24\lib\logging\__init__.py:1126:callHandlers
C:\Python24\lib\logging\__init__.py:642:handle
C:\Python24\lib\logging\handlers.py:930:emit
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Log.py:436:flush
C:\Python24\lib\logging\__init__.py:642:handle
C:\Python24\lib\logging\handlers.py:79:emit
C:\Python24\lib\logging\__init__.py:693:handleError
C:\Python24\lib\traceback.py:125:print_exception
C:\Python24\lib\traceback.py:70:print_tb
C:\Python24\lib\traceback.py:13:_print]
12/04/09 20:24:47 3272 NodeService     agnodeservice.py:518 ERROR Exception adding service VideoProducerService.zip
Traceback (most recent call last):
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGNodeService.py", line 515, in LoadConfiguration
    prefs.GetProfile())
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\interfaces\AGServiceManager_client.py", line 60, in AddServiceByName
    response = self.binding.Receive(AddServiceByNameResponse.typecode)
  File "C:\Python24\lib\site-packages\ZSI\client.py", line 504, in Receive
    return _Binding.Receive(self, replytype, **kw)
  File "C:\Python24\lib\site-packages\ZSI\client.py", line 432, in Receive
    raise FaultException(msg)
FaultException: Processing Failure
exceptions:IOError
[Errno 9] Bad file descriptor
[trace: C:\Python24\lib\site-packages\ZSI\ServiceContainer.py:108:_Dispatch
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\interfaces\AGServiceManager_interface.py:960:soap_AddServiceByName
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGServiceManager.py:97:AddServiceByName
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGServiceManager.py:147:AddService
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGServiceManager.py:378:__ExecuteService
C:\Python24\lib\logging\__init__.py:955:debug
C:\Python24\lib\logging\__init__.py:1079:_log
C:\Python24\lib\logging\__init__.py:1089:handle
C:\Python24\lib\logging\__init__.py:1126:callHandlers
C:\Python24\lib\logging\__init__.py:642:handle
C:\Python24\lib\logging\handlers.py:930:emit
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Log.py:436:flush
C:\Python24\lib\logging\__init__.py:642:handle
C:\Python24\lib\logging\handlers.py:79:emit
C:\Python24\lib\logging\__init__.py:693:handleError
C:\Python24\lib\traceback.py:125:print_exception
C:\Python24\lib\traceback.py:70:print_tb
C:\Python24\lib\traceback.py:13:_print]
12/04/09 20:24:48 3272 VenueClient     venueclient.py:276 ERROR Error loading node configuration: None
Traceback (most recent call last):
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\VenueClient.py", line 271, in __init__
    self.nodeService.LoadConfiguration(nodeConfig)
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGNodeService.py", line 522, in LoadConfiguration
    raise Exception(exceptionText)
Exception: Couldn't add service AudioService.zipCouldn't add service VideoProducerService.zip
12/04/09 20:24:48 3272 VenueClient     venueclient.py:365 DEBUG get bridges from registry
12/04/09 20:24:48 3272 VenueClient     venueclient.py:371 DEBUG Trying bridge registry: http://www.accessgrid.org/registry/peers.txt
12/04/09 20:24:48 3272 VenueClient     preferences.py:188 DEBUG Preferences.LoadPreferences: open file
12/04/09 20:24:49 3272 VenueClient     venueclient.py:371 DEBUG Trying bridge registry: http://www.ap-accessgrid.org/registry/peers.txt
12/04/09 20:24:49 3272 VenueClient     preferences.py:188 DEBUG Preferences.LoadPreferences: open file
12/04/09 20:24:55 3272 VenueClient     venueclient.py:397 DEBUG connect to bridge
12/04/09 20:24:55 3272 VenueClient     venueclient.py:401 DEBUG exiting loadbridges
12/04/09 20:24:55 3272 NodeService     agnodeservice.py:289 INFO  NodeService.SetServiceEnabledByMediaType
12/04/09 20:24:55 3272 NodeService     agnodeservice.py:258 INFO  NodeService.GetServices
12/04/09 20:24:55 3272 NodeService     agnodeservice.py:258 INFO  NodeService.GetServices
12/04/09 20:24:55 3272 NodeService     agnodeservice.py:258 INFO  NodeService.GetServices
12/04/09 20:24:55 3272 NodeService     agnodeservice.py:266 ERROR Exception in AGNodeService.GetServices.
Traceback (most recent call last):
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGNodeService.py", line 262, in GetServices
    serviceSubset = AGServiceManagerIW(
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\interfaces\AGServiceManager_client.py", line 112, in GetServices
    response = self.binding.Receive(GetServicesResponse.typecode)
  File "C:\Python24\lib\site-packages\ZSI\client.py", line 504, in Receive
    return _Binding.Receive(self, replytype, **kw)
  File "C:\Python24\lib\site-packages\ZSI\client.py", line 432, in Receive
    raise FaultException(msg)
FaultException: Processing Failure
exceptions:IOError
[Errno 9] Bad file descriptor
[trace: C:\Python24\lib\site-packages\ZSI\ServiceContainer.py:108:_Dispatch
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\interfaces\AGServiceManager_interface.py:1024:soap_GetServices
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\AGServiceManager.py:220:GetServices
C:\Python24\lib\logging\__init__.py:969:info
C:\Python24\lib\logging\__init__.py:1079:_log
C:\Python24\lib\logging\__init__.py:1089:handle
C:\Python24\lib\logging\__init__.py:1126:callHandlers
C:\Python24\lib\logging\__init__.py:642:handle
C:\Python24\lib\logging\handlers.py:930:emit
C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Log.py:436:flush
C:\Python24\lib\logging\__init__.py:642:handle
C:\Python24\lib\logging\handlers.py:79:emit
C:\Python24\lib\logging\__init__.py:693:handleError
C:\Python24\lib\traceback.py:125:print_exception
C:\Python24\lib\traceback.py:70:print_tb
C:\Python24\lib\traceback.py:13:_print]
12/04/09 20:24:55 3272 NodeService     agnodeservice.py:640 INFO  NodeService.GetConfigurations
12/04/09 20:24:55 3272 CertificateManager     certificatemanager.py:212 DEBUG Opened repository C:\Users\Mike\AppData\Roaming\AccessGrid3\Config\certRepo
12/04/09 20:24:55 3272 Toolkit     toolkit.py:472 INFO  Initialized certificate manager.
12/04/09 20:24:56 3272 CertificateManager     certificatemanager.py:575 DEBUG Configuring standard environment
12/04/09 20:24:56 3272 CertificateManager     certificatemanager.py:782 WARNING No identity certs found
12/04/09 20:24:56 3272 CertificateManager     certificatemanager.py:790 WARNING No default identity certificate found
12/04/09 20:24:56 3272 CertificateManager     certificatemanager.py:627 DEBUG No default identity found
12/04/09 20:24:56 3272 NodeService     agnodeservice.py:640 INFO  NodeService.GetConfigurations
12/04/09 20:25:01 3272 VenueClient     preferences.py:188 DEBUG Preferences.LoadPreferences: open file
12/04/09 20:25:18 3272 VenueClient     venueclientui.py:2141 INFO  Entering venue at https://sam.ag.manchester.ac.uk:8000/Venues/default
12/04/09 20:25:18 3272 VenueClientController     venueclientcontroller.py:457 DEBUG VenueClientController calling Venue.EnterVenue
12/04/09 20:25:18 3272 VenueClient     venueclient.py:1283 DEBUG EnterVenue; url=https://sam.ag.manchester.ac.uk:8000/Venues/default type=<type 'str'>
12/04/09 20:25:18 3272 NodeService     agnodeservice.py:663 INFO  NodeService.GetCapabilities
12/04/09 20:25:18 3272 NodeService     agnodeservice.py:258 INFO  NodeService.GetServices
12/04/09 20:25:18 3272 VenueClient     venueclient.py:1295 DEBUG Capability on VenueEnter: consumer, video, c0a802010cfc55efc614045ac10cf67f, H261, 90000, 1, None, None
12/04/09 20:25:18 3272 VenueClient     venueclient.py:1295 DEBUG Capability on VenueEnter: producer, video, c0a8020113b855efc6690539b5102956, H261, 90000, 1, None, None
12/04/09 20:25:18 3272 VenueClient     venueclient.py:1295 DEBUG Capability on VenueEnter: producer, Beacon, 1, ANY, 0, 1, None, None
12/04/09 20:25:18 3272 VenueClient     venueclient.py:1295 DEBUG Capability on VenueEnter: consumer, Beacon, 1, ANY, 0, 1, None, None
12/04/09 20:25:18 3272 VenueClient     venueclient.py:1320 DEBUG calling __EnterVenue
12/04/09 20:25:18 3272 VenueClient     venueclient.py:1021 DEBUG EnterVenue: Invoke Venue.Enter
12/04/09 20:25:20 3272 VenueClient     venueclient.py:1023 DEBUG after Venue.Enter
12/04/09 20:25:20 3272 VenueClient     venueclient.py:1025 DEBUG EnterVenue: Invoke Venue.getstate
12/04/09 20:25:20 3272 VenueClient     venueclient.py:1027 DEBUG EnterVenue: done Venue.getstate
12/04/09 20:25:21 3272 VenueClient     venueclient.py:1061 DEBUG Setting isInVenue flag.
12/04/09 20:25:21 3272 VenueClient     venueclient.py:1322 DEBUG after __EnterVenue
12/04/09 20:25:21 3272 VenueClient     venueclientui.py:3182 DEBUG bin.VenueClient::EnterVenue: Enter venue with url: https://sam.ag.manchester.ac.uk:8000/Venues/default
12/04/09 20:25:21 5576 VenueClient     venueclient.py:593 DEBUG Calling Heartbeat, time now: 1259958321
12/04/09 20:25:21 3272 VenueClient     venueclientui.py:3249 DEBUG Load Venue data store data
12/04/09 20:25:21 3272 VenueClient     venueclientui.py:3251 DEBUG Amount of entries: 1
12/04/09 20:25:21 3272 VenueClient     venueclientui.py:3303 DEBUG Entered venue
12/04/09 20:25:21 3272 VenueClientController     venueclientcontroller.py:459 DEBUG VenueClientController after Venue.EnterVenue
12/04/09 20:25:21 3272 VenueClient     venueclientui.py:4096 DEBUG ContentListPanel.AddDir: profile.type = Directory
12/04/09 20:25:21 3272 VenueClient     venueclientui.py:4097 DEBUG Id of DirDecsription c096b84d5e271d5587fa65ddf86f0db082 
12/04/09 20:25:21 3272 VenueClient     venueclientui.py:4100 DEBUG ContentListPanel.AddDir: This is venue data
12/04/09 20:25:21 3272 VenueClient     venueclientui.py:4101 DEBUG Search for parent of directory: presentacion
12/04/09 20:25:21 3272 VenueClient     venueclientui.py:4800 DEBUG Parent TreeItem is Data root!
12/04/09 20:25:21 3272 VenueClient     venueclientui.py:4104 DEBUG Twig to add presentacion -dir to is
12/04/09 20:25:21 3272 VenueClient     venueclientui.py:3570 INFO  AddConnections:  displayMode = exits
12/04/09 20:25:21 5576 VenueClient     venueclient.py:617 DEBUG Next Heartbeat needed within 36s
12/04/09 20:25:21 5576 VenueClient     venueclient.py:627 DEBUG heartBeatCounter = 1
12/04/09 20:25:21 5576 JabberClient     jabberclient.py:38 INFO  Connecting to Jabber Server 'jabber.mcs.anl.gov' ...
12/04/09 20:25:22 3272 GroupMsgClient     groupmsgclient.py:112 INFO  Connected.  Connection ID: c096b84d5e271d55efd288482c763c8882
12/04/09 20:25:22 3272 EventClient     insecurevenueeventclient.py:60 INFO  BaseVenueEventClient made connection.
12/04/09 20:25:24 5576 bajjer.io     io.py:103 DEBUG RECV: <?xml version='1.0'?><stream:stream xmlns:stream='http://etherx.jabber.org/streams' id='4B197067' xmlns='jabber:client' from='jabber.mcs.anl.gov'>
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:64 DEBUG feed parser initialized
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:71 DEBUG start <{http://etherx.jabber.org/streams}stream> level=0
12/04/09 20:25:24 5576 JabberClient     jabberclient.py:194 INFO  Registering the user 'c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default' in jabber server ...
12/04/09 20:25:24 5576 bajjer.io     io.py:125 DEBUG SEND: <iq type="get"><query xmlns="jabber:iq:register" /></iq>
12/04/09 20:25:24 5576 bajjer.io     io.py:167 DEBUG RECV: <iq type='result'><query xmlns='jabber:iq:register'><password/><password/>
        <instructions>Choose a username and password to register with this server.</instructions>
        <name/>
        <email/>
      <username/></query></iq>
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}iq> level=1
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:iq:register}query> level=2
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:iq:register}password> level=3
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:iq:register}password> level=3
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:iq:register}password> level=3
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:iq:register}password> level=3
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:iq:register}instructions> level=3
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:iq:register}instructions> level=3
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:iq:register}name> level=3
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:iq:register}name> level=3
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:iq:register}email> level=3
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:iq:register}email> level=3
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:iq:register}username> level=3
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:iq:register}username> level=3
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:iq:register}query> level=2
12/04/09 20:25:24 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}iq> level=1
12/04/09 20:25:24 5576 bajjer.io     io.py:125 DEBUG SEND: <iq type="set"><query xmlns="jabber:iq:register"><username>c096b84d5e271d55efd288482c763c8882</username><password>c096b84d5e271d55efd288482c763c8882</password></query></iq>
12/04/09 20:25:25 5576 bajjer.io     io.py:167 DEBUG RECV: <iq type='result'/>
12/04/09 20:25:25 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}iq> level=1
12/04/09 20:25:25 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}iq> level=1
12/04/09 20:25:26 5576 JabberClient     jabberclient.py:203 INFO  Attempting to log in as c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default ...
12/04/09 20:25:26 5576 bajjer.io     io.py:125 DEBUG SEND: <iq type="get"><query xmlns="jabber:iq:auth"><username>c096b84d5e271d55efd288482c763c8882</username></query></iq>
12/04/09 20:25:26 5576 bajjer.io     io.py:167 DEBUG RECV: <iq type='result'><query xmlns='jabber:iq:auth'><username>c096b84d5e271d55efd288482c763c8882</username><digest/><password/><resource/></query></iq>
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}iq> level=1
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:iq:auth}query> level=2
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:iq:auth}username> level=3
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:iq:auth}username> level=3
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:iq:auth}digest> level=3
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:iq:auth}digest> level=3
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:iq:auth}password> level=3
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:iq:auth}password> level=3
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:iq:auth}resource> level=3
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:iq:auth}resource> level=3
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:iq:auth}query> level=2
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}iq> level=1
12/04/09 20:25:26 5576 bajjer.stream     stream.py:326 DEBUG Recv: <gov.lbl.dsd.bajjer.stanza.Iq object at 0x053C6710>
12/04/09 20:25:26 5576 bajjer.stream     stream.py:342 DEBUG performing 'digest' authentication
12/04/09 20:25:26 5576 bajjer.io     io.py:125 DEBUG SEND: <iq type="set"><query xmlns="jabber:iq:auth"><resource>default</resource><username>c096b84d5e271d55efd288482c763c8882</username><digest>e74c83f80c8313bffa8b3258dc3bef54365b03e2</digest></query></iq>
12/04/09 20:25:26 5576 bajjer.io     io.py:167 DEBUG RECV: <iq type='result' id='pthsock_client_auth_ID'/>
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}iq> level=1
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}iq> level=1
12/04/09 20:25:26 5576 bajjer.stream     stream.py:351 DEBUG Authorization succeeded
12/04/09 20:25:26 5576 bajjer.io     io.py:125 DEBUG SEND: <iq from="c096b84d5e271d55efd288482c763c8882" type="get"><query xmlns="jabber:iq:roster" /></iq>
12/04/09 20:25:26 5576 bajjer.io     io.py:167 DEBUG RECV: <iq from='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default' type='result'><query xmlns='jabber:iq:roster'/></iq>
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}iq> level=1
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:iq:roster}query> level=2
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:iq:roster}query> level=2
12/04/09 20:25:26 5576 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}iq> level=1
12/04/09 20:25:26 5576 bajjer.io     io.py:125 DEBUG SEND: <presence />
12/04/09 20:25:26 5576 JabberClient     jabberclient.py:82 DEBUG Sending the presence to 'AGSC-Venue-Server-Lobby(195.194.48.209)@conference.mcs.anl.gov/Quincy' of type 'available'...
12/04/09 20:25:26 5576 bajjer.io     io.py:125 DEBUG SEND: <presence to="AGSC-Venue-Server-Lobby(195.194.48.209)@conference.mcs.anl.gov/Quincy" type="available"><x /></presence>
12/04/09 20:25:26 5576 VenueClient     venueclient.py:1468 DEBUG UpdateNodeService: Method UpdateNodeService called
12/04/09 20:25:26 5576 VenueClient     venueclient.py:1520 INFO  In UpdateStream: transport=multicast
12/04/09 20:25:26 5576 VenueClient     venueclient.py:1520 INFO  In UpdateStream: transport=multicast
12/04/09 20:25:26 5576 VenueClient     venueclient.py:1500 DEBUG Checking preference and starting beacon
12/04/09 20:25:26 5576 VenueClient     venueclient.py:1508 DEBUG Starting beacon
12/04/09 20:25:26 5576 VenueClient     venueclient.py:1186 INFO  VenueClient.StartBeacon: Address 224.2.142.125/49464
12/04/09 20:25:26 5576 RTPBeacon     rtpbeacon.py:278 DEBUG Called Start.
12/04/09 20:25:26 5576 VenueClient     venueclient.py:1476 DEBUG Setting node service streams
12/04/09 20:25:26 5576 NodeService     agnodeservice.py:329 INFO  NodeService.SetStreams
12/04/09 20:25:26 5576 NodeService     agnodeservice.py:258 INFO  NodeService.GetServices
12/04/09 20:25:26 5576 NodeService     agnodeservice.py:342 DEBUG Starting service VideoProducerService (priority 5)
12/04/09 20:25:26 5576 NodeService     agnodeservice.py:701 INFO  NodeService.__SendStreamsToService
12/04/09 20:25:26 5576 NodeService     agnodeservice.py:705 DEBUG service capabilities: [producer, video, c0a8020113b855efc6690539b5102956, H261, 90000, 1, None, None]
12/04/09 20:25:26 5576 NodeService     agnodeservice.py:707 DEBUG streamDescriptions: StreamDescription: {'description': None, 'any': [], 'encryptionKey': None, 'uri': None, 'encryptionFlag': 0, 'capability': [producer, video, c096b8721a3855c3f792012451320bac, H261, 90000, 1, None, None], 'static': False, 'location': multicast 224.2.134.92 54928 127, 'id': u'c096b84d5e271d55c40072e290df8acacf', 'networkLocations': [multicast 224.2.134.92 54928 127], 'name': u'AGSC Venue Server Lobby'}
12/04/09 20:25:26 5576 NodeService     agnodeservice.py:721 INFO  Sending stream (type=[producer, video, c096b8721a3855c3f792012451320bac, H261, 90000, 1, None, None]) to service: http://127.0.0.1:55121/Services/VideoProducerService.c0a8020113b855efc68d3baabb4f6400
12/04/09 20:25:26 5576 NodeService     agnodeservice.py:707 DEBUG streamDescriptions: StreamDescription: {'description': None, 'any': [], 'encryptionKey': None, 'uri': None, 'encryptionFlag': 0, 'capability': [producer, Beacon, 1, ANY, 0, 1, None, None, consumer, Beacon, 1, ANY, 0, 1, None, None], 'static': False, 'location': multicast 224.2.142.125 49464 127, 'id': u'c096b84d5e271d55efd2dc6aeff54f5348', 'networkLocations': [multicast 224.2.142.125 49464 127], 'name': u'AGSC Venue Server Lobby'}
12/04/09 20:25:26 5576 NodeService     agnodeservice.py:726 DEBUG No stream match! Sending no new streams!
12/04/09 20:25:26 5576 NodeService     agnodeservice.py:342 DEBUG Starting service VideoConsumerService (priority 7)
12/04/09 20:25:26 5576 NodeService     agnodeservice.py:701 INFO  NodeService.__SendStreamsToService
12/04/09 20:25:26 5576 NodeService     agnodeservice.py:705 DEBUG service capabilities: [consumer, video, c0a802010cfc55efc614045ac10cf67f, H261, 90000, 1, None, None]
12/04/09 20:25:26 5576 NodeService     agnodeservice.py:707 DEBUG streamDescriptions: StreamDescription: {'description': None, 'any': [], 'encryptionKey': None, 'uri': None, 'encryptionFlag': 0, 'capability': [producer, video, c096b8721a3855c3f792012451320bac, H261, 90000, 1, None, None], 'static': False, 'location': multicast 224.2.134.92 54928 127, 'id': u'c096b84d5e271d55c40072e290df8acacf', 'networkLocations': [multicast 224.2.134.92 54928 127], 'name': u'AGSC Venue Server Lobby'}
12/04/09 20:25:26 5576 NodeService     agnodeservice.py:721 INFO  Sending stream (type=[producer, video, c096b8721a3855c3f792012451320bac, H261, 90000, 1, None, None]) to service: http://127.0.0.1:51348/Services/VideoConsumerService.c0a802010cfc55efc62de4db1818f716
12/04/09 20:25:26 5576 NodeService     agnodeservice.py:707 DEBUG streamDescriptions: StreamDescription: {'description': None, 'any': [], 'encryptionKey': None, 'uri': None, 'encryptionFlag': 0, 'capability': [producer, Beacon, 1, ANY, 0, 1, None, None, consumer, Beacon, 1, ANY, 0, 1, None, None], 'static': False, 'location': multicast 224.2.142.125 49464 127, 'id': u'c096b84d5e271d55efd2dc6aeff54f5348', 'networkLocations': [multicast 224.2.142.125 49464 127], 'name': u'AGSC Venue Server Lobby'}
12/04/09 20:25:26 5576 NodeService     agnodeservice.py:726 DEBUG No stream match! Sending no new streams!
12/04/09 20:25:26 5576 VenueClient     venueclient.py:1141 DEBUG Updating client profile cache.
12/04/09 20:25:26 3060 bajjer.io     io.py:167 DEBUG RECV: <message from='jabber.mcs.anl.gov' to='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov'>
        <subject>Welcome!</subject>
        <body>Welcome to the Access Grid Jabber server @ jabber.mcs.anl.gov -- we hope you enjoy this service! For information about how to use Jabber, visit the Jabber User&apos;s Guide at http://jabbermanual.jabberstudio.org/</body>
      <x xmlns='jabber:x:delay' from='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov' stamp='20091204T20:26:15'>Offline Storage</x></message>
12/04/09 20:25:26 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}message> level=1
12/04/09 20:25:26 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}subject> level=2
12/04/09 20:25:26 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}subject> level=2
12/04/09 20:25:26 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}body> level=2
12/04/09 20:25:26 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}body> level=2
12/04/09 20:25:26 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:x:delay}x> level=2
12/04/09 20:25:26 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:x:delay}x> level=2
12/04/09 20:25:26 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}message> level=1
12/04/09 20:25:26 3060 JabberClient     jabberclient.py:151 DEBUG jabber.mcs.anl.go: Welcome to the Access Grid Jabber server @ jabber.mcs.anl.gov -- we hope you enjoy this service! For information about how to use Jabber, visit the Jabber User's Guide at http://jabbermanual.jabberstudio.org/
12/04/09 20:25:27 3060 bajjer.io     io.py:167 DEBUG RECV: <presence to='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default' from='agsc-venue-server-lobby(195.194.48.209)@conference.mcs.anl.gov/Quincy'><x/><x xmlns='http://jabber.org/protocol/muc#user'><item jid='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default' affiliation='owner' role='moderator'/></x></presence>
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}presence> level=1
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}x> level=2
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}x> level=2
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:71 DEBUG start <{http://jabber.org/protocol/muc#user}x> level=2
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:71 DEBUG start <{http://jabber.org/protocol/muc#user}item> level=3
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:85 DEBUG end <{http://jabber.org/protocol/muc#user}item> level=3
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:85 DEBUG end <{http://jabber.org/protocol/muc#user}x> level=2
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}presence> level=1
12/04/09 20:25:27 3060 JabberClient     jabberclient.py:171 DEBUG agsc-venue-server-lobby(195.194.48.209)@conference.mcs.anl.gov/Quincy is available (None / None)
12/04/09 20:25:27 3060 VenueClient     venueclientui.py:2836 DEBUG   add user: Quincy
12/04/09 20:25:27 3060 bajjer.io     io.py:167 DEBUG RECV: <message type='groupchat' to='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default' from='agsc-venue-server-lobby(195.194.48.209)@conference.mcs.anl.gov'><body>agsc-venue-server-lobby(195.194.48.209)</body></message>
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}message> level=1
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}body> level=2
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}body> level=2
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}message> level=1
12/04/09 20:25:27 3060 JabberClient     jabberclient.py:151 DEBUG agsc-venue-server-lobby(195.194.48.209)@conference.mcs.anl.go: agsc-venue-server-lobby(195.194.48.209)
12/04/09 20:25:27 3060 bajjer.io     io.py:167 DEBUG RECV: <message type='groupchat' to='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default' from='agsc-venue-server-lobby(195.194.48.209)@conference.mcs.anl.gov'><body>This room supports the MUC protocol.</body></message>
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}message> level=1
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}body> level=2
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}body> level=2
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}message> level=1
12/04/09 20:25:27 3060 bajjer.io     io.py:167 DEBUG RECV: <message type='groupchat' from='agsc-venue-server-lobby(195.194.48.209)@conference.mcs.anl.gov' to='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default'><body>Quincy has joined this session</body></message>
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}message> level=1
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}body> level=2
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}body> level=2
12/04/09 20:25:27 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}message> level=1
12/04/09 20:25:27 3060 JabberClient     jabberclient.py:151 DEBUG agsc-venue-server-lobby(195.194.48.209)@conference.mcs.anl.go: This room supports the MUC protocol.
12/04/09 20:25:27 3060 JabberClient     jabberclient.py:151 DEBUG agsc-venue-server-lobby(195.194.48.209)@conference.mcs.anl.go: Quincy has joined this session
12/04/09 20:25:57 5164 VenueClient     venueclient.py:593 DEBUG Calling Heartbeat, time now: 1259958357
12/04/09 20:25:58 5164 VenueClient     venueclient.py:617 DEBUG Next Heartbeat needed within 36s
12/04/09 20:25:58 5164 VenueClient     venueclient.py:627 DEBUG heartBeatCounter = 2
12/04/09 20:26:01 3272 VenueClient     venueclientui.py:1274 DEBUG In VenueClientController.AddDataCB
12/04/09 20:26:01 3272 VenueClient     venueclientui.py:1280 DEBUG VenueClientUI.AddDataCB: Trying to upload to 'ftps://195.194.48.209:8006/000001024523a7de00c0009600b80046218'
12/04/09 20:26:21 3272 VenueClient     venueclientui.py:1367 DEBUG AddDataCB: URI of parent is 
12/04/09 20:26:21 3272 VenueClientController     venueclientcontroller.py:820 DEBUG In VenueClientController.UploadVenueFiles
12/04/09 20:26:21 3272 VenueClientController     venueclientcontroller.py:821 DEBUG   fileList = [u'C:\\Users\\Mike\\Downloads\\UMTP_AGTk_3_1.zip']
12/04/09 20:26:21 3272 VenueClientController     venueclientcontroller.py:847 DEBUG Serverpath: 
12/04/09 20:26:21 3272 VenueClientController     venueclientcontroller.py:856 DEBUG Have args, creating thread, url: ftps://195.194.48.209:8006/000001024523a7de00c0009600b80046218, files: [u'C:\\Users\\Mike\\Downloads\\UMTP_AGTk_3_1.zip']
12/04/09 20:26:21 3272 VenueClientController     venueclientcontroller.py:861 DEBUG Started thread
12/04/09 20:26:21 3820 VenueClientController     venueclientcontroller.py:943 DEBUG Upload: getting identity
12/04/09 20:26:21 3820 VenueClientController     venueclientcontroller.py:948 DEBUG Got identity None
12/04/09 20:26:21 3820 VenueClientController     venueclientcontroller.py:949 DEBUG get_ident_and_upload: Upload URL ftps://195.194.48.209:8006/000001024523a7de00c0009600b80046218
12/04/09 20:26:21 3820 VenueClientController     venueclientcontroller.py:952 DEBUG Get_ident_and_upload: Word is: ftps: 
12/04/09 20:26:21 3820 VenueClientController     venueclientcontroller.py:952 DEBUG Get_ident_and_upload: Word is:  
12/04/09 20:26:21 3820 VenueClientController     venueclientcontroller.py:952 DEBUG Get_ident_and_upload: Word is: 195.194.48.209:8006 
12/04/09 20:26:21 3820 VenueClientController     venueclientcontroller.py:952 DEBUG Get_ident_and_upload: Word is: 000001024523a7de00c0009600b80046218 
12/04/09 20:26:21 3820 DataStore     datastore.py:1268 INFO  UploadFiles: ftps://195.194.48.209:8006/000001024523a7de00c0009600b80046218 [u'C:\\Users\\Mike\\Downloads\\UMTP_AGTk_3_1.zip']
12/04/09 20:26:21 3820 DataStore     datastore.py:1272 DEBUG UploadFiles: ftps://195.194.48.209:8006/000001024523a7de00c0009600b80046218 C:\Users\Mike\Downloads\UMTP_AGTk_3_1.zip [u'C:\\Users\\Mike\\Downloads\\UMTP_AGTk_3_1.zip']
12/04/09 20:26:21 3820 FTPSClient     ftpsclient.py:141 DEBUG Entered FTPSUploadFile: localfile=C:\Users\Mike\Downloads\UMTP_AGTk_3_1.zip url=ftps://195.194.48.209:8006/000001024523a7de00c0009600b80046218
12/04/09 20:26:22 3820 FTPSClient     ftpsclient.py:193 ERROR Error in FTPSUploadFile
Traceback (most recent call last):
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\FTPSClient.py", line 174, in FTPSUploadFile
    lin = f.storbinary('stor %s' % remotefile, fl)
  File "C:\Python24\lib\ftplib.py", line 415, in storbinary
    conn = self.transfercmd(cmd)
  File "C:\Python24\lib\ftplib.py", line 345, in transfercmd
    return self.ntransfercmd(cmd, rest)[0]
  File "C:\Python24\lib\site-packages\M2Crypto\ftpslib.py", line 86, in ntransfercmd
    conn, size = FTP.ntransfercmd(self, cmd, rest)
  File "C:\Python24\lib\ftplib.py", line 327, in ntransfercmd
    resp = self.sendcmd(cmd)
  File "C:\Python24\lib\ftplib.py", line 241, in sendcmd
    return self.getresp()
  File "C:\Python24\lib\ftplib.py", line 216, in getresp
    raise error_perm, resp
error_perm: 553 could not open file for writing: <exceptions.IOError instance at 0x2aaaaf092a28>
12/04/09 20:26:34 4944 VenueClient     venueclient.py:593 DEBUG Calling Heartbeat, time now: 1259958394
12/04/09 20:26:34 4944 VenueClient     venueclient.py:617 DEBUG Next Heartbeat needed within 36s
12/04/09 20:26:34 4944 VenueClient     venueclient.py:627 DEBUG heartBeatCounter = 3
12/04/09 20:26:39 3272 VenueClient     venueclientui.py:2141 INFO  Entering venue at https://195.194.48.209:8000/Venues/000000fe537894c400c0009600b80046edc
12/04/09 20:26:39 3272 VenueClient     venueclient.py:1410 INFO  ExitVenue
12/04/09 20:26:39 3272 VenueClient     venueclient.py:1451 INFO  ExitVenue: Stopping node services
12/04/09 20:26:39 3272 NodeService     agnodeservice.py:301 INFO  NodeService.StopServices
12/04/09 20:26:39 3272 NodeService     agnodeservice.py:329 INFO  NodeService.SetStreams
12/04/09 20:26:39 3272 NodeService     agnodeservice.py:258 INFO  NodeService.GetServices
12/04/09 20:26:39 3272 NodeService     agnodeservice.py:342 DEBUG Starting service VideoProducerService (priority 5)
12/04/09 20:26:39 3272 NodeService     agnodeservice.py:701 INFO  NodeService.__SendStreamsToService
12/04/09 20:26:39 3272 NodeService     agnodeservice.py:705 DEBUG service capabilities: [producer, video, c0a8020113b855efc6690539b5102956, H261, 90000, 1, None, None]
12/04/09 20:26:39 3272 NodeService     agnodeservice.py:342 DEBUG Starting service VideoConsumerService (priority 7)
12/04/09 20:26:39 3272 NodeService     agnodeservice.py:701 INFO  NodeService.__SendStreamsToService
12/04/09 20:26:39 3272 NodeService     agnodeservice.py:705 DEBUG service capabilities: [consumer, video, c0a802010cfc55efc614045ac10cf67f, H261, 90000, 1, None, None]
12/04/09 20:26:39 3272 VenueClient     venueclient.py:1362 DEBUG ExitVenue: cancelling heartbeat timer
12/04/09 20:26:40 3272 VenueClient     venueclient.py:1381 DEBUG ExitVenue: Stop event client obj
12/04/09 20:26:40 3272 VenueClient     venueclient.py:1383 DEBUG ExitVenue: Remove event client reference
12/04/09 20:26:40 3272 VenueClient     venueclient.py:1388 INFO  ExitVenue: Stopping text client
12/04/09 20:26:40 3272 JabberClient     jabberclient.py:82 DEBUG Sending the presence to 'AGSC-Venue-Server-Lobby(195.194.48.209)@conference.mcs.anl.gov/Quincy' of type 'unavailable'...
12/04/09 20:26:40 3272 bajjer.io     io.py:125 DEBUG SEND: <presence to="AGSC-Venue-Server-Lobby(195.194.48.209)@conference.mcs.anl.gov/Quincy" type="unavailable"><x /></presence>
12/04/09 20:26:40 3272 RTPBeacon     rtpbeacon.py:296 DEBUG Called Stop.
12/04/09 20:26:40 3272 VenueClientController     venueclientcontroller.py:457 DEBUG VenueClientController calling Venue.EnterVenue
12/04/09 20:26:40 3272 VenueClient     venueclient.py:1283 DEBUG EnterVenue; url=https://195.194.48.209:8000/Venues/000000fe537894c400c0009600b80046edc type=<type 'str'>
12/04/09 20:26:40 3272 NodeService     agnodeservice.py:663 INFO  NodeService.GetCapabilities
12/04/09 20:26:40 3272 NodeService     agnodeservice.py:258 INFO  NodeService.GetServices
12/04/09 20:26:40 3272 VenueClient     venueclient.py:1295 DEBUG Capability on VenueEnter: consumer, video, c0a802010cfc55efc614045ac10cf67f, H261, 90000, 1, None, None
12/04/09 20:26:40 3272 VenueClient     venueclient.py:1295 DEBUG Capability on VenueEnter: producer, video, c0a8020113b855efc6690539b5102956, H261, 90000, 1, None, None
12/04/09 20:26:40 3272 VenueClient     venueclient.py:1295 DEBUG Capability on VenueEnter: producer, Beacon, 1, ANY, 0, 1, None, None
12/04/09 20:26:40 3272 VenueClient     venueclient.py:1295 DEBUG Capability on VenueEnter: consumer, Beacon, 1, ANY, 0, 1, None, None
12/04/09 20:26:40 3272 VenueClient     venueclient.py:1320 DEBUG calling __EnterVenue
12/04/09 20:26:40 3272 VenueClient     venueclient.py:1021 DEBUG EnterVenue: Invoke Venue.Enter
12/04/09 20:26:40 3800 RTPBeacon     rtpbeacon.py:289 INFO  RTPBeacon.UpdateThread exiting
12/04/09 20:26:40 3060 bajjer.io     io.py:167 DEBUG RECV: <presence to='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default' type='unavailable' from='agsc-venue-server-lobby(195.194.48.209)@conference.mcs.anl.gov/Quincy'><x/><x xmlns='http://jabber.org/protocol/muc#user'><item jid='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default' affiliation='owner' role='none'/></x></presence>
12/04/09 20:26:40 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}presence> level=1
12/04/09 20:26:40 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}x> level=2
12/04/09 20:26:40 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}x> level=2
12/04/09 20:26:40 3060 bajjer.serialize     serialize.py:71 DEBUG start <{http://jabber.org/protocol/muc#user}x> level=2
12/04/09 20:26:40 3060 bajjer.serialize     serialize.py:71 DEBUG start <{http://jabber.org/protocol/muc#user}item> level=3
12/04/09 20:26:40 3060 bajjer.serialize     serialize.py:85 DEBUG end <{http://jabber.org/protocol/muc#user}item> level=3
12/04/09 20:26:40 3060 bajjer.serialize     serialize.py:85 DEBUG end <{http://jabber.org/protocol/muc#user}x> level=2
12/04/09 20:26:40 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}presence> level=1
12/04/09 20:26:40 3060 JabberClient     jabberclient.py:174 DEBUG agsc-venue-server-lobby(195.194.48.209)@conference.mcs.anl.gov/Quincy is unavailable (None / None)
12/04/09 20:26:40 3060 VenueClient     venueclientui.py:2850 DEBUG   remove user: Quincy
12/04/09 20:26:40 3272 VenueClient     venueclient.py:1023 DEBUG after Venue.Enter
12/04/09 20:26:40 3272 VenueClient     venueclient.py:1025 DEBUG EnterVenue: Invoke Venue.getstate
12/04/09 20:26:40 3272 VenueClient     venueclient.py:1027 DEBUG EnterVenue: done Venue.getstate
12/04/09 20:26:41 3272 VenueClient     venueclient.py:1061 DEBUG Setting isInVenue flag.
12/04/09 20:26:41 3272 VenueClient     venueclient.py:1322 DEBUG after __EnterVenue
12/04/09 20:26:41 3272 VenueClient     venueclientui.py:3182 DEBUG bin.VenueClient::EnterVenue: Enter venue with url: https://195.194.48.209:8000/Venues/000000fe537894c400c0009600b80046edc
12/04/09 20:26:41 5148 VenueClient     venueclient.py:593 DEBUG Calling Heartbeat, time now: 1259958401
12/04/09 20:26:41 3272 VenueClient     venueclientui.py:3249 DEBUG Load Venue data store data
12/04/09 20:26:41 3272 VenueClient     venueclientui.py:3251 DEBUG Amount of entries: 1
12/04/09 20:26:41 3272 VenueClient     venueclientui.py:3303 DEBUG Entered venue
12/04/09 20:26:41 3272 VenueClientController     venueclientcontroller.py:459 DEBUG VenueClientController after Venue.EnterVenue
12/04/09 20:26:41 3272 VenueClient     venueclientui.py:4013 DEBUG ContentListPanel.RemoveParticipant: Remove participant
12/04/09 20:26:41 3272 VenueClient     venueclientui.py:4048 DEBUG ContentListPanel.AddData: profile.type = File
12/04/09 20:26:41 3272 VenueClient     venueclientui.py:4049 DEBUG Id of DataDescription: c096b84d5e271d55b902635d8b23188bf6
12/04/09 20:26:41 3272 VenueClient     venueclientui.py:4065 DEBUG ContentListPanel.AddData: This is venue data
12/04/09 20:26:41 3272 VenueClient     venueclientui.py:4800 DEBUG Parent TreeItem is Data root!
12/04/09 20:26:41 3272 VenueClient     venueclientui.py:3570 INFO  AddConnections:  displayMode = exits
12/04/09 20:26:42 5148 VenueClient     venueclient.py:617 DEBUG Next Heartbeat needed within 36s
12/04/09 20:26:42 5148 VenueClient     venueclient.py:627 DEBUG heartBeatCounter = 4
12/04/09 20:26:42 5148 JabberClient     jabberclient.py:82 DEBUG Sending the presence to 'Cardiff-University(195.194.48.209)@conference.mcs.anl.gov/Quincy' of type 'available'...
12/04/09 20:26:42 5148 bajjer.io     io.py:125 DEBUG SEND: <presence to="Cardiff-University(195.194.48.209)@conference.mcs.anl.gov/Quincy" type="available"><x /></presence>
12/04/09 20:26:42 5148 VenueClient     venueclient.py:1468 DEBUG UpdateNodeService: Method UpdateNodeService called
12/04/09 20:26:42 5148 VenueClient     venueclient.py:1520 INFO  In UpdateStream: transport=multicast
12/04/09 20:26:42 5148 VenueClient     venueclient.py:1520 INFO  In UpdateStream: transport=multicast
12/04/09 20:26:42 5148 VenueClient     venueclient.py:1520 INFO  In UpdateStream: transport=multicast
12/04/09 20:26:42 5148 VenueClient     venueclient.py:1500 DEBUG Checking preference and starting beacon
12/04/09 20:26:42 5148 VenueClient     venueclient.py:1504 DEBUG Stopping beacon
12/04/09 20:26:42 5148 RTPBeacon     rtpbeacon.py:296 DEBUG Called Stop.
12/04/09 20:26:42 5148 VenueClient     venueclient.py:1508 DEBUG Starting beacon
12/04/09 20:26:42 5148 VenueClient     venueclient.py:1178 INFO  Beacon being reconfigured, stopping running beacon
12/04/09 20:26:42 5148 RTPBeacon     rtpbeacon.py:296 DEBUG Called Stop.
12/04/09 20:26:42 5148 VenueClient     venueclient.py:1186 INFO  VenueClient.StartBeacon: Address 224.2.128.122/63520
12/04/09 20:26:42 5148 RTPBeacon     rtpbeacon.py:278 DEBUG Called Start.
12/04/09 20:26:42 5148 VenueClient     venueclient.py:1476 DEBUG Setting node service streams
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:329 INFO  NodeService.SetStreams
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:258 INFO  NodeService.GetServices
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:342 DEBUG Starting service VideoProducerService (priority 5)
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:701 INFO  NodeService.__SendStreamsToService
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:705 DEBUG service capabilities: [producer, video, c0a8020113b855efc6690539b5102956, H261, 90000, 1, None, None]
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:707 DEBUG streamDescriptions: StreamDescription: {'description': None, 'any': [], 'encryptionKey': None, 'uri': None, 'encryptionFlag': 0, 'capability': [consumer, video, c096b84d5e271d5587fbeed4c0ec2c2ad2, H261, 90000, 1, None, None, consumer, video, 83fb801f0ccc55b5cf4f01157bdfc12c, H264, 90000, 1, None, None, consumer, video, 83fb801f0ccc55b5cf4f01157bdfc12c, MPEG4, 90000, 1, None, None, consumer, video, 83fb801f0ccc55b5cf4f01157bdfc12c, H261AS, 90000, 1, None, None], 'static': True, 'location': multicast 233.2.171.246 59966 127, 'id': u'c096b84d5e271d5587fbefd4c0ef4aec93', 'networkLocations': [multicast 233.2.171.246 59966 127], 'name': u'Cardiff University'}
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:721 INFO  Sending stream (type=[consumer, video, c096b84d5e271d5587fbeed4c0ec2c2ad2, H261, 90000, 1, None, None, consumer, video, 83fb801f0ccc55b5cf4f01157bdfc12c, H264, 90000, 1, None, None, consumer, video, 83fb801f0ccc55b5cf4f01157bdfc12c, MPEG4, 90000, 1, None, None, consumer, video, 83fb801f0ccc55b5cf4f01157bdfc12c, H261AS, 90000, 1, None, None]) to service: http://127.0.0.1:55121/Services/VideoProducerService.c0a8020113b855efc68d3baabb4f6400
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:707 DEBUG streamDescriptions: StreamDescription: {'description': None, 'any': [], 'encryptionKey': None, 'uri': None, 'encryptionFlag': 0, 'capability': [consumer, audio, c096b84d5e271d5587fbf1d4c0f56333cb, L16, 16000, 1, None, None, consumer, audio, c096b84d5e271d5587fbf1d4c0f56333cb, L16, 8000, 1, None, None, consumer, audio, c096b84d5e271d5587fbf1d4c0f56333cb, L8, 16000, 1, None, None, consumer, audio, c096b84d5e271d5587fbf1d4c0f56333cb, L8, 8000, 1, None, None, consumer, audio, c096b84d5e271d5587fbf1d4c0f56333cb, PCMU, 16000, 1, None, None, consumer, audio, c096b84d5e271d5587fbf1d4c0f56333cb, PCMU, 8000, 1, None, None, consumer, audio, c096b84d5e271d5587fbf1d4c0f56333cb, GSM, 16000, 1, None, None, consumer, audio, c096b84d5e271d5587fbf1d4c0f56333cb, GSM, 8000, 1, None, None], 'static': True, 'location': multicast 233.2.171.246 59968 127, 'id': u'c096b84d5e271d5587fbf2d4c0f8378529', 'networkLocations': [multicast 233.2.171.246 59968 127], 'name': u'Cardiff University'}
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:726 DEBUG No stream match! Sending no new streams!
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:707 DEBUG streamDescriptions: StreamDescription: {'description': None, 'any': [], 'encryptionKey': None, 'uri': None, 'encryptionFlag': 0, 'capability': [producer, Beacon, 1, ANY, 0, 1, None, None, consumer, Beacon, 1, ANY, 0, 1, None, None], 'static': False, 'location': multicast 224.2.128.122 63520 127, 'id': u'c096b84d5e271d55b5de96f2535685696b', 'networkLocations': [multicast 224.2.128.122 63520 127], 'name': u'Cardiff University'}
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:726 DEBUG No stream match! Sending no new streams!
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:342 DEBUG Starting service VideoConsumerService (priority 7)
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:701 INFO  NodeService.__SendStreamsToService
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:705 DEBUG service capabilities: [consumer, video, c0a802010cfc55efc614045ac10cf67f, H261, 90000, 1, None, None]
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:707 DEBUG streamDescriptions: StreamDescription: {'description': None, 'any': [], 'encryptionKey': None, 'uri': None, 'encryptionFlag': 0, 'capability': [consumer, video, c096b84d5e271d5587fbeed4c0ec2c2ad2, H261, 90000, 1, None, None, consumer, video, 83fb801f0ccc55b5cf4f01157bdfc12c, H264, 90000, 1, None, None, consumer, video, 83fb801f0ccc55b5cf4f01157bdfc12c, MPEG4, 90000, 1, None, None, consumer, video, 83fb801f0ccc55b5cf4f01157bdfc12c, H261AS, 90000, 1, None, None], 'static': True, 'location': multicast 233.2.171.246 59966 127, 'id': u'c096b84d5e271d5587fbefd4c0ef4aec93', 'networkLocations': [multicast 233.2.171.246 59966 127], 'name': u'Cardiff University'}
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:721 INFO  Sending stream (type=[consumer, video, c096b84d5e271d5587fbeed4c0ec2c2ad2, H261, 90000, 1, None, None, consumer, video, 83fb801f0ccc55b5cf4f01157bdfc12c, H264, 90000, 1, None, None, consumer, video, 83fb801f0ccc55b5cf4f01157bdfc12c, MPEG4, 90000, 1, None, None, consumer, video, 83fb801f0ccc55b5cf4f01157bdfc12c, H261AS, 90000, 1, None, None]) to service: http://127.0.0.1:51348/Services/VideoConsumerService.c0a802010cfc55efc62de4db1818f716
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:707 DEBUG streamDescriptions: StreamDescription: {'description': None, 'any': [], 'encryptionKey': None, 'uri': None, 'encryptionFlag': 0, 'capability': [consumer, audio, c096b84d5e271d5587fbf1d4c0f56333cb, L16, 16000, 1, None, None, consumer, audio, c096b84d5e271d5587fbf1d4c0f56333cb, L16, 8000, 1, None, None, consumer, audio, c096b84d5e271d5587fbf1d4c0f56333cb, L8, 16000, 1, None, None, consumer, audio, c096b84d5e271d5587fbf1d4c0f56333cb, L8, 8000, 1, None, None, consumer, audio, c096b84d5e271d5587fbf1d4c0f56333cb, PCMU, 16000, 1, None, None, consumer, audio, c096b84d5e271d5587fbf1d4c0f56333cb, PCMU, 8000, 1, None, None, consumer, audio, c096b84d5e271d5587fbf1d4c0f56333cb, GSM, 16000, 1, None, None, consumer, audio, c096b84d5e271d5587fbf1d4c0f56333cb, GSM, 8000, 1, None, None], 'static': True, 'location': multicast 233.2.171.246 59968 127, 'id': u'c096b84d5e271d5587fbf2d4c0f8378529', 'networkLocations': [multicast 233.2.171.246 59968 127], 'name': u'Cardiff University'}
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:726 DEBUG No stream match! Sending no new streams!
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:707 DEBUG streamDescriptions: StreamDescription: {'description': None, 'any': [], 'encryptionKey': None, 'uri': None, 'encryptionFlag': 0, 'capability': [producer, Beacon, 1, ANY, 0, 1, None, None, consumer, Beacon, 1, ANY, 0, 1, None, None], 'static': False, 'location': multicast 224.2.128.122 63520 127, 'id': u'c096b84d5e271d55b5de96f2535685696b', 'networkLocations': [multicast 224.2.128.122 63520 127], 'name': u'Cardiff University'}
12/04/09 20:26:42 5148 NodeService     agnodeservice.py:726 DEBUG No stream match! Sending no new streams!
12/04/09 20:26:42 5148 VenueClient     venueclient.py:1141 DEBUG Updating client profile cache.
12/04/09 20:26:42 3060 bajjer.io     io.py:167 DEBUG RECV: <presence to='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default' from='cardiff-university(195.194.48.209)@conference.mcs.anl.gov/Quincy'><x/><x xmlns='http://jabber.org/protocol/muc#user'><item affiliation='none' role='participant'/></x></presence>
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}presence> level=1
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}x> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}x> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{http://jabber.org/protocol/muc#user}x> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{http://jabber.org/protocol/muc#user}item> level=3
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{http://jabber.org/protocol/muc#user}item> level=3
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{http://jabber.org/protocol/muc#user}x> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}presence> level=1
12/04/09 20:26:42 3060 JabberClient     jabberclient.py:171 DEBUG cardiff-university(195.194.48.209)@conference.mcs.anl.gov/Quincy is available (None / None)
12/04/09 20:26:42 3060 VenueClient     venueclientui.py:2836 DEBUG   add user: Quincy
12/04/09 20:26:42 3272 EventClient     insecurevenueeventclient.py:85 INFO  BaseVenueEventClient lost connection; reason=[Failure instance: Traceback (failure with no frames): twisted.internet.error.ConnectionDone: Connection was closed cleanly.
]
12/04/09 20:26:42 3272 GroupMsgClient     groupmsgclient.py:112 INFO  Connected.  Connection ID: c096b84d5e271d55eff1ea9a075dcb0ead
12/04/09 20:26:42 3272 EventClient     insecurevenueeventclient.py:60 INFO  BaseVenueEventClient made connection.
12/04/09 20:26:42 3060 bajjer.io     io.py:167 DEBUG RECV: <message type='groupchat' to='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default' from='cardiff-university(195.194.48.209)@conference.mcs.anl.gov'><body>cardiff-university(195.194.48.209)</body></message>
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}message> level=1
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}body> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}body> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}message> level=1
12/04/09 20:26:42 3060 JabberClient     jabberclient.py:151 DEBUG cardiff-university(195.194.48.209)@conference.mcs.anl.go: cardiff-university(195.194.48.209)
12/04/09 20:26:42 3060 bajjer.io     io.py:167 DEBUG RECV: <message type='groupchat' to='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default' from='cardiff-university(195.194.48.209)@conference.mcs.anl.gov'><body>This room supports the MUC protocol.</body></message>
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}message> level=1
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}body> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}body> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}message> level=1
12/04/09 20:26:42 3060 bajjer.io     io.py:167 DEBUG RECV: <presence to='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default' from='cardiff-university(195.194.48.209)@conference.mcs.anl.gov/Cardiff_HD'><x/><x xmlns='jabber:x:delay' from='cardiff-university(195.194.48.209)@conference.mcs.anl.gov' stamp='20091204T20:23:47'/><x xmlns='http://jabber.org/protocol/muc#user'><item affiliation='none' role='participant'/></x></presence>
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}presence> level=1
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}x> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}x> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:x:delay}x> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:x:delay}x> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{http://jabber.org/protocol/muc#user}x> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{http://jabber.org/protocol/muc#user}item> level=3
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{http://jabber.org/protocol/muc#user}item> level=3
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{http://jabber.org/protocol/muc#user}x> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}presence> level=1
12/04/09 20:26:42 3060 JabberClient     jabberclient.py:151 DEBUG cardiff-university(195.194.48.209)@conference.mcs.anl.go: This room supports the MUC protocol.
12/04/09 20:26:42 3060 JabberClient     jabberclient.py:171 DEBUG cardiff-university(195.194.48.209)@conference.mcs.anl.gov/Cardiff_HD is available (None / None)
12/04/09 20:26:42 3060 VenueClient     venueclientui.py:2836 DEBUG   add user: Cardiff_HD
12/04/09 20:26:42 3060 bajjer.io     io.py:167 DEBUG RECV: <message to='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default' type='groupchat' from='cardiff-university(195.194.48.209)@conference.mcs.anl.gov/parisAR'><body>hey me and Ian are going for breakfast if you fancy it?</body><x xmlns='jabber:x:delay' from='Cardiff-University(195.194.48.209)@conference.mcs.anl.gov' stamp='20091204T10:33:37'>cardiff-university(195.194.48.209)@conference.mcs.anl.gov</x></message>
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}message> level=1
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}body> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}body> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:x:delay}x> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:x:delay}x> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}message> level=1
12/04/09 20:26:42 3060 bajjer.io     io.py:167 DEBUG RECV: <message to='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default' type='groupchat' from='cardiff-university(195.194.48.209)@conference.mcs.anl.gov/parisAR'><body>Hey were just off now meet us down there if you fancy</body><x xmlns='jabber:x:delay' from='Cardiff-University(195.194.48.209)@conference.mcs.anl.gov' stamp='20091204T10:46:36'>cardiff-university(195.194.48.209)@conference.mcs.anl.gov</x></message>
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}message> level=1
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}body> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}body> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:x:delay}x> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:x:delay}x> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}message> level=1
12/04/09 20:26:42 3060 bajjer.io     io.py:167 DEBUG RECV: <message type='groupchat' from='cardiff-university(195.194.48.209)@conference.mcs.anl.gov' to='c096b84d5e271d55efd288482c763c8882 at jabber.mcs.anl.gov/default'><body>Quincy has joined this session</body></message>
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}message> level=1
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:71 DEBUG start <{jabber:client}body> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}body> level=2
12/04/09 20:26:42 3060 bajjer.serialize     serialize.py:85 DEBUG end <{jabber:client}message> level=1
12/04/09 20:26:42 3060 JabberClient     jabberclient.py:151 DEBUG cardiff-university(195.194.48.209)@conference.mcs.anl.go: Quincy has joined this session
12/04/09 20:26:54 3272 VenueClient     venueclientui.py:1274 DEBUG In VenueClientController.AddDataCB
12/04/09 20:26:54 3272 VenueClient     venueclientui.py:1280 DEBUG VenueClientUI.AddDataCB: Trying to upload to 'ftps://195.194.48.209:8006/000000fe537894c400c0009600b80046edc'
12/04/09 20:27:05 3272 VenueClient     venueclientui.py:1367 DEBUG AddDataCB: URI of parent is 
12/04/09 20:27:05 3272 VenueClientController     venueclientcontroller.py:820 DEBUG In VenueClientController.UploadVenueFiles
12/04/09 20:27:05 3272 VenueClientController     venueclientcontroller.py:821 DEBUG   fileList = [u'C:\\Users\\Mike\\Downloads\\UMTP_AGTk_3_1.zip']
12/04/09 20:27:05 3272 VenueClientController     venueclientcontroller.py:847 DEBUG Serverpath: 
12/04/09 20:27:05 3272 VenueClientController     venueclientcontroller.py:856 DEBUG Have args, creating thread, url: ftps://195.194.48.209:8006/000000fe537894c400c0009600b80046edc, files: [u'C:\\Users\\Mike\\Downloads\\UMTP_AGTk_3_1.zip']
12/04/09 20:27:05 3272 VenueClientController     venueclientcontroller.py:861 DEBUG Started thread
12/04/09 20:27:05 2596 VenueClientController     venueclientcontroller.py:943 DEBUG Upload: getting identity
12/04/09 20:27:05 2596 VenueClientController     venueclientcontroller.py:948 DEBUG Got identity None
12/04/09 20:27:05 2596 VenueClientController     venueclientcontroller.py:949 DEBUG get_ident_and_upload: Upload URL ftps://195.194.48.209:8006/000000fe537894c400c0009600b80046edc
12/04/09 20:27:05 2596 VenueClientController     venueclientcontroller.py:952 DEBUG Get_ident_and_upload: Word is: ftps: 
12/04/09 20:27:05 2596 VenueClientController     venueclientcontroller.py:952 DEBUG Get_ident_and_upload: Word is:  
12/04/09 20:27:05 2596 VenueClientController     venueclientcontroller.py:952 DEBUG Get_ident_and_upload: Word is: 195.194.48.209:8006 
12/04/09 20:27:05 2596 VenueClientController     venueclientcontroller.py:952 DEBUG Get_ident_and_upload: Word is: 000000fe537894c400c0009600b80046edc 
12/04/09 20:27:05 2596 DataStore     datastore.py:1268 INFO  UploadFiles: ftps://195.194.48.209:8006/000000fe537894c400c0009600b80046edc [u'C:\\Users\\Mike\\Downloads\\UMTP_AGTk_3_1.zip']
12/04/09 20:27:05 2596 DataStore     datastore.py:1272 DEBUG UploadFiles: ftps://195.194.48.209:8006/000000fe537894c400c0009600b80046edc C:\Users\Mike\Downloads\UMTP_AGTk_3_1.zip [u'C:\\Users\\Mike\\Downloads\\UMTP_AGTk_3_1.zip']
12/04/09 20:27:05 2596 FTPSClient     ftpsclient.py:141 DEBUG Entered FTPSUploadFile: localfile=C:\Users\Mike\Downloads\UMTP_AGTk_3_1.zip url=ftps://195.194.48.209:8006/000000fe537894c400c0009600b80046edc
12/04/09 20:27:09 2596 FTPSClient     ftpsclient.py:175 DEBUG 226 Transfer complete.
12/04/09 20:27:09 3272 VenueClient     venueclient.py:748 DEBUG AddDataEvent: Got Add Data Event
12/04/09 20:27:09 3272 VenueClient     venueclientui.py:2889 DEBUG EVENT - Add data: UMTP_AGTk_3_1.zip
12/04/09 20:27:09 3272 VenueClient     venueclientui.py:4048 DEBUG ContentListPanel.AddData: profile.type = File
12/04/09 20:27:09 3272 VenueClient     venueclientui.py:4049 DEBUG Id of DataDescription: c096b84d5e271d55effd5f7cd83e50a60e
12/04/09 20:27:09 3272 VenueClient     venueclientui.py:4065 DEBUG ContentListPanel.AddData: This is venue data
12/04/09 20:27:09 3272 VenueClient     venueclientui.py:4800 DEBUG Parent TreeItem is Data root!
12/04/09 20:27:12 3272 Toolkit     config.py:820 DEBUG MimeType: None
12/04/09 20:27:12 3272 Toolkit     config.py:833 DEBUG Extension: zip
12/04/09 20:27:12 3272 Toolkit     config.py:848 DEBUG FileType: CompressedFolder
12/04/09 20:27:12 3272 Toolkit     config.py:857 DEBUG Found 2 commands for filetype CompressedFolder.
12/04/09 20:27:12 3272 Toolkit     config.py:820 DEBUG MimeType: None
12/04/09 20:27:12 3272 Toolkit     config.py:833 DEBUG Extension: zip
12/04/09 20:27:12 3272 Toolkit     config.py:848 DEBUG FileType: CompressedFolder
12/04/09 20:27:12 3272 Toolkit     config.py:857 DEBUG Found 2 commands for filetype CompressedFolder.
12/04/09 20:27:12 3272 VenueClientController     venueclientcontroller.py:1184 DEBUG Save file descriptor: DataDescription: {'status': u'present', 'description': None, 'any': [], 'checksum': None, 'parentId': u'-1', 'uri': u'ftps://195.194.48.209:8006/000000fe537894c400c0009600b80046edc/UMTP_AGTk_3_1.zip', 'name': u'UMTP_AGTk_3_1.zip', 'lastModified': u'Fri, Dec 04, 2009, 20:26:51', 'owner': '', 'hierarchyLevel': -2, 'objectType': u'File', 'type': None, 'id': u'c096b84d5e271d55effd5f7cd83e50a60e', 'size': 153806}, path: C:\Users\Mike\AppData\Local\Temp\UMTP_AGTk_3_1.zip
12/04/09 20:27:12 3272 VenueClientController     venueclientcontroller.py:1198 DEBUG data descriptor is AccessGrid.Descriptions.DataDescription
12/04/09 20:27:12 5320 VenueClientController     venueclientcontroller.py:1243 DEBUG Get ident and download
12/04/09 20:27:12 5320 DataStore     datastore.py:1288 INFO  DownloadFile: url ftps://195.194.48.209:8006/000000fe537894c400c0009600b80046edc/UMTP_AGTk_3_1.zip file C:\Users\Mike\AppData\Local\Temp\UMTP_AGTk_3_1.zip
12/04/09 20:27:12 5320 FTPSClient     ftpsclient.py:38 DEBUG Entered FTPSDownloadFile: url=ftps://195.194.48.209:8006/000000fe537894c400c0009600b80046edc/UMTP_AGTk_3_1.zip destination=C:\Users\Mike\AppData\Local\Temp\UMTP_AGTk_3_1.zip
12/04/09 20:27:14 5320 FTPSClient     ftpsclient.py:91 DEBUG 226 Transfer complete
12/04/09 20:27:14 3272 VenueClientController     venueclientcontroller.py:1518 ERROR Command failed, probably misconfigured.                 Tried to run, C:\Windows\Explorer.exe /idlist,%I, with named arguments {'appCmd': 'Open',
 'appDesc': None,
 'appName': u'UMTP_AGTk_3_1.zip',
 'appUrl': u'ftps://195.194.48.209:8006/000000fe537894c400c0009600b80046edc/UMTP_AGTk_3_1.zip',
 'connectionId': u'c096b84d5e271d55eff1ea9a075dcb0ead',
 'localFilePath': u'C:\\Users\\Mike\\AppData\\Local\\Temp\\UMTP_AGTk_3_1.zip',
 'venueClientUrl': 'http://127.0.0.1:11000/VenueClient',
 'venueUrl': 'https://195.194.48.209:8000/Venues/000000fe537894c400c0009600b80046edc'}
Traceback (most recent call last):
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\VenueClientController.py", line 1513, in StartCmd
    realCommand = command % namedVars
ValueError: unsupported format character 'I' (0x49) at index 33
12/04/09 20:27:15 3272 VenueClient     venueclientui.py:4424 DEBUG looking for mime commands for extension: zip
12/04/09 20:27:15 3272 Toolkit     config.py:820 DEBUG MimeType: None
12/04/09 20:27:15 3272 Toolkit     config.py:833 DEBUG Extension: zip
12/04/09 20:27:15 3272 Toolkit     config.py:848 DEBUG FileType: CompressedFolder
12/04/09 20:27:15 3272 Toolkit     config.py:857 DEBUG Found 2 commands for filetype CompressedFolder.
12/04/09 20:27:15 3272 VenueClient     venueclientui.py:4429 DEBUG Commands: 2 {'Open': u'%SystemRoot%\\Explorer.exe /idlist,%I,%L', 'Find': u'%SystemRoot%\\Explorer.exe'}
12/04/09 20:27:17 3272 Toolkit     config.py:820 DEBUG MimeType: None
12/04/09 20:27:17 3272 Toolkit     config.py:833 DEBUG Extension: zip
12/04/09 20:27:17 3272 Toolkit     config.py:848 DEBUG FileType: CompressedFolder
12/04/09 20:27:17 3272 Toolkit     config.py:857 DEBUG Found 2 commands for filetype CompressedFolder.
12/04/09 20:27:17 3272 VenueClientController     venueclientcontroller.py:1184 DEBUG Save file descriptor: DataDescription: {'status': u'present', 'description': None, 'any': [], 'checksum': None, 'parentId': u'-1', 'uri': u'ftps://195.194.48.209:8006/000000fe537894c400c0009600b80046edc/UMTP_AGTk_3_1.zip', 'name': u'UMTP_AGTk_3_1.zip', 'lastModified': u'Fri, Dec 04, 2009, 20:26:51', 'owner': '', 'hierarchyLevel': -2, 'objectType': u'File', 'type': None, 'id': u'c096b84d5e271d55effd5f7cd83e50a60e', 'size': 153806}, path: C:\Users\Mike\AppData\Local\Temp\UMTP_AGTk_3_1.zip
12/04/09 20:27:17 3272 VenueClientController     venueclientcontroller.py:1198 DEBUG data descriptor is AccessGrid.Descriptions.DataDescription
12/04/09 20:27:17 4440 VenueClientController     venueclientcontroller.py:1243 DEBUG Get ident and download
12/04/09 20:27:17 4440 DataStore     datastore.py:1288 INFO  DownloadFile: url ftps://195.194.48.209:8006/000000fe537894c400c0009600b80046edc/UMTP_AGTk_3_1.zip file C:\Users\Mike\AppData\Local\Temp\UMTP_AGTk_3_1.zip
12/04/09 20:27:17 4440 FTPSClient     ftpsclient.py:38 DEBUG Entered FTPSDownloadFile: url=ftps://195.194.48.209:8006/000000fe537894c400c0009600b80046edc/UMTP_AGTk_3_1.zip destination=C:\Users\Mike\AppData\Local\Temp\UMTP_AGTk_3_1.zip
12/04/09 20:27:18 5924 VenueClient     venueclient.py:593 DEBUG Calling Heartbeat, time now: 1259958438
12/04/09 20:27:18 5924 VenueClient     venueclient.py:617 DEBUG Next Heartbeat needed within 36s
12/04/09 20:27:18 5924 VenueClient     venueclient.py:627 DEBUG heartBeatCounter = 5
12/04/09 20:27:19 4440 FTPSClient     ftpsclient.py:91 DEBUG 226 Transfer complete
12/04/09 20:27:19 3272 VenueClientController     venueclientcontroller.py:1518 ERROR Command failed, probably misconfigured.                 Tried to run, C:\Windows\Explorer.exe /idlist,%I, with named arguments {'appCmd': 'Open',
 'appDesc': None,
 'appName': u'UMTP_AGTk_3_1.zip',
 'appUrl': u'ftps://195.194.48.209:8006/000000fe537894c400c0009600b80046edc/UMTP_AGTk_3_1.zip',
 'connectionId': u'c096b84d5e271d55eff1ea9a075dcb0ead',
 'localFilePath': u'C:\\Users\\Mike\\AppData\\Local\\Temp\\UMTP_AGTk_3_1.zip',
 'venueClientUrl': 'http://127.0.0.1:11000/VenueClient',
 'venueUrl': 'https://195.194.48.209:8000/Venues/000000fe537894c400c0009600b80046edc'}
Traceback (most recent call last):
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\VenueClientController.py", line 1513, in StartCmd
    realCommand = command % namedVars
ValueError: unsupported format character 'I' (0x49) at index 33
12/04/09 20:27:33 3272 VenueClient     venueclientui.py:4424 DEBUG looking for mime commands for extension: zip
12/04/09 20:27:33 3272 Toolkit     config.py:820 DEBUG MimeType: None
12/04/09 20:27:33 3272 Toolkit     config.py:833 DEBUG Extension: zip
12/04/09 20:27:33 3272 Toolkit     config.py:848 DEBUG FileType: CompressedFolder
12/04/09 20:27:33 3272 Toolkit     config.py:857 DEBUG Found 2 commands for filetype CompressedFolder.
12/04/09 20:27:33 3272 VenueClient     venueclientui.py:4429 DEBUG Commands: 2 {'Open': u'%SystemRoot%\\Explorer.exe /idlist,%I,%L', 'Find': u'%SystemRoot%\\Explorer.exe'}
12/04/09 20:27:51 3272 VenueClient     venueclientui.py:2694 INFO  --------- END VenueClient
12/04/09 20:27:51 3272 VenueClient     venueclient.py:1410 INFO  ExitVenue
12/04/09 20:27:51 3272 VenueClient     venueclient.py:1451 INFO  ExitVenue: Stopping node services
12/04/09 20:27:51 3272 NodeService     agnodeservice.py:301 INFO  NodeService.StopServices
12/04/09 20:27:51 3272 NodeService     agnodeservice.py:329 INFO  NodeService.SetStreams
12/04/09 20:27:51 3272 NodeService     agnodeservice.py:258 INFO  NodeService.GetServices
12/04/09 20:27:51 3272 NodeService     agnodeservice.py:342 DEBUG Starting service VideoProducerService (priority 5)
12/04/09 20:27:51 3272 NodeService     agnodeservice.py:701 INFO  NodeService.__SendStreamsToService
12/04/09 20:27:51 3272 NodeService     agnodeservice.py:705 DEBUG service capabilities: [producer, video, c0a8020113b855efc6690539b5102956, H261, 90000, 1, None, None]
12/04/09 20:27:51 3272 NodeService     agnodeservice.py:342 DEBUG Starting service VideoConsumerService (priority 7)
12/04/09 20:27:51 3272 NodeService     agnodeservice.py:701 INFO  NodeService.__SendStreamsToService
12/04/09 20:27:51 3272 NodeService     agnodeservice.py:705 DEBUG service capabilities: [consumer, video, c0a802010cfc55efc614045ac10cf67f, H261, 90000, 1, None, None]
12/04/09 20:27:51 3272 VenueClient     venueclient.py:1362 DEBUG ExitVenue: cancelling heartbeat timer
12/04/09 20:27:52 3272 VenueClient     venueclient.py:1381 DEBUG ExitVenue: Stop event client obj
12/04/09 20:27:52 3272 VenueClient     venueclient.py:1383 DEBUG ExitVenue: Remove event client reference
12/04/09 20:27:52 3272 VenueClient     venueclient.py:1388 INFO  ExitVenue: Stopping text client
12/04/09 20:27:52 3272 JabberClient     jabberclient.py:82 DEBUG Sending the presence to 'Cardiff-University(195.194.48.209)@conference.mcs.anl.gov/Quincy' of type 'unavailable'...
12/04/09 20:27:52 3272 bajjer.io     io.py:125 DEBUG SEND: <presence to="Cardiff-University(195.194.48.209)@conference.mcs.anl.gov/Quincy" type="unavailable"><x /></presence>
12/04/09 20:27:52 3272 RTPBeacon     rtpbeacon.py:296 DEBUG Called Stop.
12/04/09 20:27:52 3272 ServiceManager     agservicemanager.py:72 INFO  AGServiceManager.Shutdown
12/04/09 20:27:52 3272 ServiceManager     agservicemanager.py:73 INFO  Remove services
12/04/09 20:27:52 3272 ServiceManager     agservicemanager.py:210 INFO  AGServiceManager.RemoveServices
12/04/09 20:27:52 3272 ServiceManager     agservicemanager.py:75 INFO  Stop network interface
12/04/09 20:27:52 3272 RTPBeacon     rtpbeacon.py:296 DEBUG Called Stop.
12/04/09 20:27:52 3272 VenueClient     venueclient.py:1583 INFO  Shutting down jabber client
12/04/09 20:27:52 3272 VenueClient     venueclient.py:1588 INFO  Shutting down bridge ping thread
12/04/09 20:38:51 3420 ServiceDiscovery     servicediscovery.py:62 INFO  Required bonjour libs not found; service discovery disabled
12/04/09 20:38:51 3420 Toolkit     config.py:630 ERROR Failed to determine local IP address, using 127.0.0.1
Traceback (most recent call last):
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Config.py", line 624, in _SetHostnameToLocalIP
    self.hostname = self.GetLocalIPAddress()
  File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Platform\win32\Config.py", line 451, in GetLocalIPAddress
    if parts[0] == "0.0.0.0":
IndexError: list index out of range
12/04/09 20:38:51 3420 Toolkit     toolkit.py:116 DEBUG Initializing AG Toolkit version 3.1 
12/04/09 20:38:51 3420 Toolkit     toolkit.py:117 INFO  Command and arguments: ['C:\\Program Files\\AGTk-3\\bin\\VenueClient3.py']
12/04/09 20:38:51 3420 VenueClient     preferences.py:188 DEBUG Preferences.LoadPreferences: open file
12/04/09 20:38:51 3420 Toolkit     toolkit.py:166 INFO  Logfile Name: VenueClient.log
12/04/09 20:38:51 3420 Toolkit     venueclient3.py:137 INFO  wx version is: 2.6.3.3
12/04/09 20:38:51 3420 VenueClient     venueclient.py:529 DEBUG __StartWebService: service manager: http://127.0.0.1:11000/ServiceManager
12/04/09 20:38:51 3420 VenueClient     venueclient.py:543 DEBUG __StartWebService: node service: http://127.0.0.1:11000/NodeService
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:640 INFO  NodeService.GetConfigurations
12/04/09 20:38:51 3420 VenueClient     venueclient.py:270 DEBUG Loading node configuration: <AccessGrid.Descriptions.NodeConfigDescription instance at 0x051CF4E0>
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:379 INFO  NodeService.LoadConfiguration
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:398 INFO  Trying to load node configuration from: C:\Program Files\AGTk-3\Config\nodeConfig\quincy09
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:406 DEBUG section: node
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   servicemanagers : servicemanager0
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:406 DEBUG section: serviceconfig0
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Use site id : On
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Silence Suppression : Off
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Start Priority : 5
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Output Gain : 50
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Talk : Off
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Input Gain : 50
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:406 DEBUG section: servicemanager0
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   url : 
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   builtin : 1
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   services : service0 service1 service2 service3
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   name : 
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:406 DEBUG section: serviceconfig1
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Start Priority : 7
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Thumbnail Columns : 2
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Processor usage : All
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:406 DEBUG section: service3
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   packageName : VideoProducerService.zip
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   resource : resource3
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   serviceConfig : serviceconfig3
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:406 DEBUG section: service2
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   packageName : VideoProducerService.zip
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   resource : resource2
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   serviceConfig : serviceconfig2
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:406 DEBUG section: service1
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   packageName : VideoConsumerService.zip
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   serviceConfig : serviceconfig1
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:406 DEBUG section: service0
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   packageName : AudioService.zip
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   serviceConfig : serviceconfig0
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:406 DEBUG section: serviceconfig3
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Frame Rate : 24
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Encoding : h261
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Start Priority : 5
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Standard : PAL
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Bandwidth : 800
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Processor usage : All
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Port : external-in
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Quality : 75
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Stream Name : SPC 610NC Laptop Camera
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:406 DEBUG section: serviceconfig2
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Frame Rate : 24
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Encoding : h261
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Start Priority : 5
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Standard : PAL
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Bandwidth : 800
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Processor usage : All
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Port : external-in
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Quality : 75
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   Stream Name : Sony Visual Communication Camera
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:406 DEBUG section: resource3
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   name : SPC 610NC Laptop Camera
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:406 DEBUG section: resource2
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:408 DEBUG   name : Sony Visual Communication Camera
12/04/09 20:38:51 3420 NodeService     agnodeservice.py:483 DEBUG using builtin service manager at http://127.0.0.1:11000/ServiceManager
12/04/09 20:38:51 5676 ServiceManager     agservicemanager.py:210 INFO  AGServiceManager.RemoveServices
12/04/09 20:38:51 5724 ServiceManager     agservicemanager.py:103 INFO  AGServiceManager.AddService
12/04/09 20:38:51 5724 ServiceManager     agservicemanager.py:343 INFO  Installed service version: 3.9, package version: 3.9
12/04/09 20:38:51 5724 ServiceManager     agservicemanager.py:378 DEBUG Executing service AudioService
12/04/09 20:38:51 5724 ServiceManager     agservicemanager.py:410 INFO  Running Service; options: ['C:\\Users\\Mike\\AppData\\Roaming\\AccessGrid3\\local_services\\AudioService\\AudioService.py', '--port', 51565, '--serviceManagerUri', 'http://127.0.0.1:11000/ServiceManager', '--token', 'c0a8020115b455f1100193298429840b']
12/04/09 20:38:51 5724 ProcessManager     processmanager.py:62 DEBUG Creating process: C:\Python24\pythonw.exe C:\Users\Mike\AppData\Roaming\AccessGrid3\local_services\AudioService\AudioService.py --port 51565 --serviceManagerUri http://127.0.0.1:11000/ServiceManager --token c0a8020115b455f1100193298429840b
12/04/09 20:38:51 5724 ProcessManager     processmanager.py:75 DEBUG Create process returns: (<PyHANDLE:8>, <PyHANDLE:1324>, 3292, 1984)
12/04/09 20:38:52 1776 ServiceManager     agservicemanager.py:234 INFO  Service registered: token,url = c0a8020115b455f1100193298429840b,http://127.0.0.1:51565/Services/AudioService.c0a802010cdc55f1104442a798567a0a
12/04/09 20:38:52 5724 ServiceManager     agservicemanager.py:422 INFO  Service registered: http://127.0.0.1:51565/Services/AudioService.c0a802010cdc55f1104442a798567a0a c0a8020115b455f1100193298429840b
12/04/09 20:38:52 5764 ServiceManager     agservicemanager.py:103 INFO  AGServiceManager.AddService
12/04/09 20:38:52 5764 ServiceManager     agservicemanager.py:343 INFO  Installed service version: 3.1, package version: 3.1
12/04/09 20:38:52 5764 ServiceManager     agservicemanager.py:378 DEBUG Executing service VideoConsumerService
12/04/09 20:38:52 5764 ServiceManager     agservicemanager.py:410 INFO  Running Service; options: ['C:\\Users\\Mike\\AppData\\Roaming\\AccessGrid3\\local_services\\VideoConsumerService\\VideoConsumerService.py', '--port', 60437, '--serviceManagerUri', 'http://127.0.0.1:11000/ServiceManager', '--token', 'c0a8020115b455f11054741bb96d3986']
12/04/09 20:38:52 5764 ProcessManager     processmanager.py:62 DEBUG Creating process: C:\Python24\pythonw.exe C:\Users\Mike\AppData\Roaming\AccessGrid3\local_services\VideoConsumerService\VideoConsumerService.py --port 60437 --serviceManagerUri http://127.0.0.1:11000/ServiceManager --token c0a8020115b455f11054741bb96d3986
12/04/09 20:38:52 5764 ProcessManager     processmanager.py:75 DEBUG Create process returns: (<PyHANDLE:1320>, <PyHANDLE:1328>, 3744, 3580)
12/04/09 20:38:53 5352 ServiceManager     agservicemanager.py:234 INFO  Service registered: token,url = c0a8020115b455f11054741bb96d3986,http://127.0.0.1:60437/Services/VideoConsumerService.c0a802010ea055f1109573ab638c1b5b
12/04/09 20:38:53 5764 ServiceManager     agservicemanager.py:422 INFO  Service registered: http://127.0.0.1:60437/Services/VideoConsumerService.c0a802010ea055f1109573ab638c1b5b c0a8020115b455f11054741bb96d3986
12/04/09 20:38:53 4904 ServiceManager     agservicemanager.py:103 INFO  AGServiceManager.AddService
12/04/09 20:38:53 4904 ServiceManager     agservicemanager.py:343 INFO  Installed service version: 3.8, package version: 3.8
12/04/09 20:38:53 4904 ServiceManager     agservicemanager.py:378 DEBUG Executing service VideoProducerService
12/04/09 20:38:53 4904 ServiceManager     agservicemanager.py:410 INFO  Running Service; options: ['C:\\Users\\Mike\\AppData\\Roaming\\AccessGrid3\\local_services\\VideoProducerService\\VideoProducerService.py', '--port', 54994, '--serviceManagerUri', 'http://127.0.0.1:11000/ServiceManager', '--token', 'c0a8020115b455f110a4aad4df21d814']
12/04/09 20:38:53 4904 ProcessManager     processmanager.py:62 DEBUG Creating process: C:\Python24\pythonw.exe C:\Users\Mike\AppData\Roaming\AccessGrid3\local_services\VideoProducerService\VideoProducerService.py --port 54994 --serviceManagerUri http://127.0.0.1:11000/ServiceManager --token c0a8020115b455f110a4aad4df21d814
12/04/09 20:38:53 4904 ProcessManager     processmanager.py:75 DEBUG Create process returns: (<PyHANDLE:1324>, <PyHANDLE:1340>, 5008, 5388)
12/04/09 20:38:53 4432 ServiceManager     agservicemanager.py:234 INFO  Service registered: token,url = c0a8020115b455f110a4aad4df21d814,http://127.0.0.1:54994/Services/VideoProducerService.c0a80201139055f110f023642604c336
12/04/09 20:38:53 4904 ServiceManager     agservicemanager.py:422 INFO  Service registered: http://127.0.0.1:54994/Services/VideoProducerService.c0a80201139055f110f023642604c336 c0a8020115b455f110a4aad4df21d814
12/04/09 20:38:54 5856 ServiceManager     agservicemanager.py:103 INFO  AGServiceManager.AddService
12/04/09 20:38:54 5856 ServiceManager     agservicemanager.py:343 INFO  Installed service version: 3.8, package version: 3.8
12/04/09 20:38:54 5856 ServiceManager     agservicemanager.py:378 DEBUG Executing service VideoProducerService
12/04/09 20:38:54 5856 ServiceManager     agservicemanager.py:410 INFO  Running Service; options: ['C:\\Users\\Mike\\AppData\\Roaming\\AccessGrid3\\local_services\\VideoProducerService\\VideoProducerService.py', '--port', 63878, '--serviceManagerUri', 'http://127.0.0.1:11000/ServiceManager', '--token', 'c0a8020115b455f111014da4de49cfc9']
12/04/09 20:38:54 5856 ProcessManager     processmanager.py:62 DEBUG Creating process: C:\Python24\pythonw.exe C:\Users\Mike\AppData\Roaming\AccessGrid3\local_services\VideoProducerService\VideoProducerService.py --port 63878 --serviceManagerUri http://127.0.0.1:11000/ServiceManager --token c0a8020115b455f111014da4de49cfc9
12/04/09 20:38:54 5856 ProcessManager     processmanager.py:75 DEBUG Create process returns: (<PyHANDLE:1340>, <PyHANDLE:1336>, 2504, 5380)
12/04/09 20:38:54 5144 ServiceManager     agservicemanager.py:234 INFO  Service registered: token,url = c0a8020115b455f111014da4de49cfc9,http://127.0.0.1:63878/Services/VideoProducerService.c0a8020109c855f1114a85b72e3bc59c
12/04/09 20:38:54 5856 ServiceManager     agservicemanager.py:422 INFO  Service registered: http://127.0.0.1:63878/Services/VideoProducerService.c0a8020109c855f1114a85b72e3bc59c c0a8020115b455f111014da4de49cfc9
12/04/09 20:38:54 3420 VenueClient     venueclient.py:365 DEBUG get bridges from registry
12/04/09 20:38:54 3420 VenueClient     venueclient.py:371 DEBUG Trying bridge registry: http://www.accessgrid.org/registry/peers.txt
12/04/09 20:38:54 3420 VenueClient     preferences.py:188 DEBUG Preferences.LoadPreferences: open file
12/04/09 20:38:56 3420 VenueClient     venueclient.py:371 DEBUG Trying bridge registry: http://www.ap-accessgrid.org/registry/peers.txt
12/04/09 20:38:56 3420 VenueClient     preferences.py:188 DEBUG Preferences.LoadPreferences: open file
12/04/09 20:38:59 3420 VenueClient     venueclient.py:397 DEBUG connect to bridge
12/04/09 20:38:59 3420 VenueClient     venueclient.py:401 DEBUG exiting loadbridges


More information about the ag-tech mailing list