Hi Tom
I have attached my log file
Mike



Quoting Jeremy Mann <[email protected]>:

> On Fri, Dec 4, 2009 at 11:12 AM, Thomas Uram <[email protected]> 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
> [email protected]
>
> 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.

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 '[email protected]/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 [email protected]/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='[email protected]/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='[email protected]'>
        <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='[email protected]' 
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='[email protected]/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='[email protected]/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='[email protected]/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='[email protected]/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='[email protected]/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='[email protected]/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='[email protected]/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='[email protected]/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='[email protected]/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='[email protected]/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='[email protected]/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='[email protected]/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='[email protected]/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='[email protected]/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

Reply via email to