04/23/08 10:21:33 2788 Toolkit     config.py:559 DEBUG System hostname of hslAG.blue.jays.creighton.edu is valid
04/23/08 10:21:33 2788 Toolkit     toolkit.py:111 DEBUG Initializing AG Toolkit version 3.0.2
04/23/08 10:21:33 2788 Toolkit     toolkit.py:112 INFO  Command and arguments: ['C:\\Program Files\\AGTk-3\\bin\\VenueClient3.py']
04/23/08 10:21:33 2788 VenueClient     preferences.py:180 DEBUG Preferences.LoadPreferences: open file
04/23/08 10:21:33 2788 Toolkit     toolkit.py:161 INFO  Logfile Name: VenueClient.log
04/23/08 10:21:33 2788 Toolkit     venueclient3.py:125 INFO  wx version is: 2.6.2.1
04/23/08 10:21:33 2788 VenueClient     venueclient.py:375 DEBUG __StartWebService: venueclient: http://hslAG.blue.jays.creighton.edu:11000/VenueClient
04/23/08 10:21:33 2788 VenueClient     venueclient.py:386 DEBUG __StartWebService: service manager: http://hslAG.blue.jays.creighton.edu:11000/ServiceManager
04/23/08 10:21:33 2788 VenueClient     venueclient.py:400 DEBUG __StartWebService: node service: http://hslAG.blue.jays.creighton.edu:11000/NodeService
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:635 INFO  NodeService.GetConfigurations
04/23/08 10:21:33 2788 VenueClient     venueclient.py:175 DEBUG Loading node configuration: <AccessGrid.Descriptions.NodeConfigDescription instance at 0x02FD8BE8>
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:375 INFO  NodeService.LoadConfiguration
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:394 INFO  Trying to load node configuration from: C:\Program Files\AGTk-3\Config\nodeConfig\default
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:402 DEBUG section: node
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   servicemanagers : servicemanager0
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:402 DEBUG section: serviceconfig0
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   Silence Suppression : Off
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   Output Gain : 50
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   Talk : On
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   Input Gain : 50
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:402 DEBUG section: servicemanager0
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   services : service0 service1 service2
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   builtin : 1
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   name : 
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   url : 
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:402 DEBUG section: service2
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   resource : resource2
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   serviceConfig : serviceconfig2
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   packageName : VideoProducerService.zip
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:402 DEBUG section: service1
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   packageName : VideoConsumerService.zip
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:402 DEBUG section: service0
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   packageName : AudioService.zip
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   serviceConfig : serviceconfig0
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:402 DEBUG section: serviceconfig2
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   Frame Rate : 24
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   Encoding : h261
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   Stream Name : Microsoft WDM Image Capture (Win32)
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   Standard : NTSC
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   Bandwidth : 800
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   Quality : 75
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   Port : external-in
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:402 DEBUG section: resource2
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:404 DEBUG   name : Microsoft WDM Image Capture (Win32)
04/23/08 10:21:33 2788 NodeService     agnodeservice.py:479 DEBUG using builtin service manager at http://hslAG.blue.jays.creighton.edu:11000/ServiceManager
04/23/08 10:21:33 3748 ServiceManager     agservicemanager.py:208 INFO  AGServiceManager.RemoveServices
04/23/08 10:21:33 3612 ServiceManager     agservicemanager.py:102 INFO  AGServiceManager.AddService
04/23/08 10:21:33 3612 ServiceManager     agservicemanager.py:339 INFO  Installed service version: 3.4, package version: 3.4
04/23/08 10:21:33 3612 ServiceManager     agservicemanager.py:374 DEBUG Executing service AudioService
04/23/08 10:21:33 3612 ServiceManager     agservicemanager.py:406 INFO  Running Service; options: ['C:\\Documents and Settings\\hslguest\\Application Data\\AccessGrid3\\local_services\\AudioService\\AudioService.py', '--port', 52325, '--serviceManagerUri', 'http://hslAG.blue.jays.creighton.edu:11000/ServiceManager', '--token', '9386c9b10c4025fccf652adecdd62798']
04/23/08 10:21:33 3612 ProcessManager     processmanager.py:62 DEBUG Creating process: C:\Python23\pythonw.exe "C:\Documents and Settings\hslguest\Application Data\AccessGrid3\local_services\AudioService\AudioService.py" --port 52325 --serviceManagerUri http://hslAG.blue.jays.creighton.edu:11000/ServiceManager --token 9386c9b10c4025fccf652adecdd62798
04/23/08 10:21:33 3612 ProcessManager     processmanager.py:75 DEBUG Create process returns: (<PyHANDLE:1264>, <PyHANDLE:1276>, 3708, 1180)
04/23/08 10:21:35 3612 ServiceManager     agservicemanager.py:418 INFO  Service registered: http://hslAG.blue.jays.creighton.edu:52325/Services/AudioService.9386c9b10e7c25fccfe4cb7a1d1b58e8 9386c9b10c4025fccf652adecdd62798
04/23/08 10:21:35 2892 ServiceManager     agservicemanager.py:102 INFO  AGServiceManager.AddService
04/23/08 10:21:35 2892 ServiceManager     agservicemanager.py:339 INFO  Installed service version: 3.02, package version: 3.02
04/23/08 10:21:35 2892 ServiceManager     agservicemanager.py:374 DEBUG Executing service VideoConsumerService
04/23/08 10:21:35 2892 ServiceManager     agservicemanager.py:406 INFO  Running Service; options: ['C:\\Documents and Settings\\hslguest\\Application Data\\AccessGrid3\\local_services\\VideoConsumerService\\VideoConsumerService.py', '--port', 64004, '--serviceManagerUri', 'http://hslAG.blue.jays.creighton.edu:11000/ServiceManager', '--token', '9386c9b10c4025fcd005bdaa19c4eda4']
04/23/08 10:21:35 2892 ProcessManager     processmanager.py:62 DEBUG Creating process: C:\Python23\pythonw.exe "C:\Documents and Settings\hslguest\Application Data\AccessGrid3\local_services\VideoConsumerService\VideoConsumerService.py" --port 64004 --serviceManagerUri http://hslAG.blue.jays.creighton.edu:11000/ServiceManager --token 9386c9b10c4025fcd005bdaa19c4eda4
04/23/08 10:21:35 2892 ProcessManager     processmanager.py:75 DEBUG Create process returns: (<PyHANDLE:1160>, <PyHANDLE:1152>, 3472, 3468)
04/23/08 10:21:36 2892 ServiceManager     agservicemanager.py:418 INFO  Service registered: http://hslAG.blue.jays.creighton.edu:64004/Services/VideoConsumerService.9386c9b10d9025fcd07ad337cf81dfa8 9386c9b10c4025fcd005bdaa19c4eda4
04/23/08 10:21:36 2540 ServiceManager     agservicemanager.py:102 INFO  AGServiceManager.AddService
04/23/08 10:21:36 2540 ServiceManager     agservicemanager.py:339 INFO  Installed service version: 3.3, package version: 3.3
04/23/08 10:21:36 2540 ServiceManager     agservicemanager.py:374 DEBUG Executing service VideoProducerService
04/23/08 10:21:36 2540 ServiceManager     agservicemanager.py:406 INFO  Running Service; options: ['C:\\Documents and Settings\\hslguest\\Application Data\\AccessGrid3\\local_services\\VideoProducerService\\VideoProducerService.py', '--port', 58053, '--serviceManagerUri', 'http://hslAG.blue.jays.creighton.edu:11000/ServiceManager', '--token', '9386c9b10c4025fcd0949b32b9eb8d1b']
04/23/08 10:21:36 2540 ProcessManager     processmanager.py:62 DEBUG Creating process: C:\Python23\pythonw.exe "C:\Documents and Settings\hslguest\Application Data\AccessGrid3\local_services\VideoProducerService\VideoProducerService.py" --port 58053 --serviceManagerUri http://hslAG.blue.jays.creighton.edu:11000/ServiceManager --token 9386c9b10c4025fcd0949b32b9eb8d1b
04/23/08 10:21:36 2540 ProcessManager     processmanager.py:75 DEBUG Create process returns: (<PyHANDLE:1072>, <PyHANDLE:1168>, 3788, 3808)
04/23/08 10:21:38 2540 ServiceManager     agservicemanager.py:418 INFO  Service registered: http://hslAG.blue.jays.creighton.edu:58053/Services/VideoProducerService.9386c9b10ecc25fcd12ebdebccb95fbc 9386c9b10c4025fcd0949b32b9eb8d1b
04/23/08 10:21:38 2788 VenueClient     venueclient.py:242 DEBUG get bridges from registry
04/23/08 10:22:01 2788 RegistryClient     registryclient.py:79 ERROR Exception pinging bridge
Traceback (most recent call last):
  File "C:\Python23\lib\site-packages\AccessGrid3\AccessGrid\Registry\RegistryClient.py", line 66, in PingBridgeService
    startTime = bridgeProxy.Ping(time.time())
  File "C:\Python23\lib\xmlrpclib.py", line 1032, in __call__
    return self.__send(self.__name, args)
  File "C:\Python23\lib\xmlrpclib.py", line 1319, in __request
    verbose=self.__verbose
  File "C:\Python23\lib\xmlrpclib.py", line 1065, in request
    self.send_content(h, request_body)
  File "C:\Python23\lib\xmlrpclib.py", line 1179, in send_content
    connection.endheaders()
  File "C:\Python23\lib\httplib.py", line 715, in endheaders
    self._send_output()
  File "C:\Python23\lib\httplib.py", line 600, in _send_output
    self.send(msg)
  File "C:\Python23\lib\httplib.py", line 567, in send
    self.connect()
  File "C:\Python23\lib\httplib.py", line 551, in connect
    raise socket.error, msg
error: (10060, 'Operation timed out')
04/23/08 10:22:01 2788 RegistryClient     registryclient.py:73 INFO  Using deprecated ping for older bridge interface to oa-ag3-venue.sheridanc.on.ca:20000
04/23/08 10:22:07 2788 VenueClient     venueclient.py:256 DEBUG set bridges in prefs
04/23/08 10:22:07 2788 VenueClient     venueclient.py:265 DEBUG connect to bridge
04/23/08 10:22:11 2788 RegistryClient     registryclient.py:216 INFO  Ping: Host ag1.v19.uthscsa.edu timed out
04/23/08 10:22:11 2788 VenueClient     venueclient.py:269 DEBUG exiting loadbridges
04/23/08 10:22:11 2788 NodeService     agnodeservice.py:289 INFO  NodeService.SetServiceEnabledByMediaType
04/23/08 10:22:11 2788 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
04/23/08 10:22:11 3260 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
04/23/08 10:22:11 2788 NodeService     agnodeservice.py:275 INFO  NodeService.SetServiceEnabled
04/23/08 10:22:11 2788 NodeService     agnodeservice.py:695 INFO  NodeService.__SendStreamsToService
04/23/08 10:22:11 2788 NodeService     agnodeservice.py:699 DEBUG service capabilities: [consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L8, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L8, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, PCMU, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, PCMU, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, GSM, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, GSM, 8000, 1, producer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 16000, 1]
04/23/08 10:22:11 2788 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
04/23/08 10:22:11 3144 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
04/23/08 10:22:12 2788 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
04/23/08 10:22:12 1560 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
04/23/08 10:22:12 2788 NodeService     agnodeservice.py:635 INFO  NodeService.GetConfigurations
04/23/08 10:22:12 2788 NodeService     agnodeservice.py:635 INFO  NodeService.GetConfigurations
04/23/08 10:24:44 2788 VenueClientController     venueclientcontroller.py:435 DEBUG VenueClientController calling Venue.EnterVenue
04/23/08 10:24:44 2788 VenueClient     venueclient.py:1015 DEBUG EnterVenue; url=https://artgridvs.chpc.utah.edu:8000/Venues/9b651c3318475afcfe5d7cd0305c7993
04/23/08 10:24:44 2788 NodeService     agnodeservice.py:658 INFO  NodeService.GetCapabilities
04/23/08 10:24:44 2788 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
04/23/08 10:24:44 3704 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
04/23/08 10:24:45 2788 VenueClient     venueclient.py:1049 DEBUG calling __EnterVenue
04/23/08 10:24:45 2788 VenueClient     venueclient.py:806 DEBUG EnterVenue: Invoke Venue.Enter
04/23/08 10:24:45 2788 VenueClient     venueclient.py:808 DEBUG after Venue.Enter
04/23/08 10:24:45 2788 VenueClient     venueclient.py:810 DEBUG EnterVenue: Invoke Venue.getstate
04/23/08 10:24:45 2788 VenueClient     venueclient.py:812 DEBUG EnterVenue: done Venue.getstate
04/23/08 10:24:46 2788 VenueClient     venueclient.py:835 DEBUG Setting isInVenue flag.
04/23/08 10:24:46 2788 VenueClient     venueclient.py:1051 DEBUG after __EnterVenue
04/23/08 10:24:46 2788 VenueClient     venueclientui.py:2586 DEBUG bin.VenueClient::EnterVenue: Enter venue with url: https://artgridvs.chpc.utah.edu:8000/Venues/9b651c3318475afcfe5d7cd0305c7993
04/23/08 10:24:46 4040 VenueClient     venueclient.py:450 DEBUG Calling Heartbeat, time now: 1208964286
04/23/08 10:24:46 2788 VenueClient     venueclientui.py:2699 DEBUG Entered venue
04/23/08 10:24:46 2788 VenueClientController     venueclientcontroller.py:437 DEBUG VenueClientController after Venue.EnterVenue
04/23/08 10:24:47 2788 VenueClient     venueclientui.py:3333 DEBUG ContentListPanel.AddParticipant:: AddParticipant Creighton University HSL (called from ('C:\\Python23\\Lib\\site-packages\\wx-2.6-msw-unicode\\wx\\_core.py', 13469, '<lambda>', 'lambda event: event.callable(*event.args, **event.kw) )'))
04/23/08 10:24:47 4040 VenueClient     venueclient.py:474 DEBUG Next Heartbeat needed within 36s
04/23/08 10:24:47 4040 JabberClient     jabberclient.py:33 INFO  Connecting to Jabber Server 'jabber.mcs.anl.gov' ...
04/23/08 10:24:47 4040 bajjer.io     io.py:100 DEBUG RECV: <?xml version='1.0'?><stream:stream xmlns:stream='http://etherx.jabber.org/streams' id='480F54E7' xmlns='jabber:client' from='jabber.mcs.anl.gov'>
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:65 DEBUG feed parser initialized
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:72 DEBUG start <{http://etherx.jabber.org/streams}stream> level=0
04/23/08 10:24:47 4040 JabberClient     jabberclient.py:168 INFO  Registering the user '9b651c3353e925fd211c6e490da9fc4e@jabber.mcs.anl.gov/default' in jabber server ...
04/23/08 10:24:47 4040 bajjer.io     io.py:122 DEBUG SEND: <iq type="get"><query xmlns="jabber:iq:register" /></iq>
04/23/08 10:24:47 4040 bajjer.io     io.py:161 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>
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}iq> level=1
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:register}query> level=2
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:register}password> level=3
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:register}password> level=3
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:register}password> level=3
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:register}password> level=3
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:register}instructions> level=3
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:register}instructions> level=3
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:register}name> level=3
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:register}name> level=3
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:register}email> level=3
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:register}email> level=3
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:register}username> level=3
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:register}username> level=3
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:register}query> level=2
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}iq> level=1
04/23/08 10:24:47 4040 bajjer.io     io.py:122 DEBUG SEND: <iq type="set"><query xmlns="jabber:iq:register"><username>9b651c3353e925fd211c6e490da9fc4e</username><password>9b651c3353e925fd211c6e490da9fc4e</password></query></iq>
04/23/08 10:24:47 4040 bajjer.io     io.py:161 DEBUG RECV: <iq type='result'/>
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}iq> level=1
04/23/08 10:24:47 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}iq> level=1
04/23/08 10:24:48 4040 JabberClient     jabberclient.py:177 INFO  Attempting to log in as 9b651c3353e925fd211c6e490da9fc4e@jabber.mcs.anl.gov/default ...
04/23/08 10:24:48 4040 bajjer.io     io.py:122 DEBUG SEND: <iq type="get"><query xmlns="jabber:iq:auth"><username>9b651c3353e925fd211c6e490da9fc4e</username></query></iq>
04/23/08 10:24:48 4040 bajjer.io     io.py:161 DEBUG RECV: <iq type='result'><query xmlns='jabber:iq:auth'><username>9b651c3353e925fd211c6e490da9fc4e</username><digest/><password/><resource/></query></iq>
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}iq> level=1
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:auth}query> level=2
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:auth}username> level=3
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:auth}username> level=3
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:auth}digest> level=3
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:auth}digest> level=3
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:auth}password> level=3
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:auth}password> level=3
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:auth}resource> level=3
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:auth}resource> level=3
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:auth}query> level=2
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}iq> level=1
04/23/08 10:24:48 4040 bajjer.stream     stream.py:326 DEBUG Recv: <gov.lbl.dsd.bajjer.stanza.Iq object at 0x03102EF0>
04/23/08 10:24:48 4040 bajjer.stream     stream.py:342 DEBUG performing 'digest' authentication
04/23/08 10:24:48 4040 bajjer.io     io.py:122 DEBUG SEND: <iq type="set"><query xmlns="jabber:iq:auth"><resource>default</resource><username>9b651c3353e925fd211c6e490da9fc4e</username><digest>edd6d3e8a065a587fca0659fbbdc74b07b7f98a1</digest></query></iq>
04/23/08 10:24:48 4040 bajjer.io     io.py:161 DEBUG RECV: <iq type='result' id='pthsock_client_auth_ID'/>
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}iq> level=1
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}iq> level=1
04/23/08 10:24:48 4040 bajjer.stream     stream.py:351 DEBUG Authorization succeeded
04/23/08 10:24:48 4040 bajjer.io     io.py:122 DEBUG SEND: <iq from="9b651c3353e925fd211c6e490da9fc4e" type="get"><query xmlns="jabber:iq:roster" /></iq>
04/23/08 10:24:48 4040 bajjer.io     io.py:161 DEBUG RECV: <iq from='9b651c3353e925fd211c6e490da9fc4e@jabber.mcs.anl.gov/default' type='result'><query xmlns='jabber:iq:roster'/></iq>
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}iq> level=1
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:roster}query> level=2
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:roster}query> level=2
04/23/08 10:24:48 4040 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}iq> level=1
04/23/08 10:24:48 4040 bajjer.io     io.py:122 DEBUG SEND: <presence />
04/23/08 10:24:48 4040 JabberClient     jabberclient.py:73 DEBUG Sending the presence to 'AG_Art_Lobby(artgridvs.chpc.utah.edu)@conference.mcs.anl.gov/Creighton University HSL' of type 'available'...
04/23/08 10:24:48 4040 bajjer.io     io.py:122 DEBUG SEND: <presence to="AG_Art_Lobby(artgridvs.chpc.utah.edu)@conference.mcs.anl.gov/Creighton University HSL" type="available"><x /></presence>
04/23/08 10:24:48 4040 VenueClient     venueclient.py:959 INFO  VenueClient.StartBeacon: Address 224.2.135.17/57002
04/23/08 10:24:48 4040 RTPBeacon     rtpbeacon.py:278 DEBUG Called Start.
04/23/08 10:24:48 4040 VenueClient     venueclient.py:1231 INFO  In UpdateStream: transport=unicast
04/23/08 10:24:48 2528 bajjer.io     io.py:161 DEBUG RECV: <message from='jabber.mcs.anl.gov' to='9b651c3353e925fd211c6e490da9fc4e@jabber.mcs.anl.gov'>
        <subject>Welcome!</subject>
        <body>Welcome to the Access Grid Jabber server @ jabber.mcs.anl.gov -- we hope you enjoy this service! For information about how to use Jabber, visit the Jabber User&apos;s Guide at http://jabbermanual.jabberstudio.org/</body>
      <x xmlns='jabber:x:delay' from='9b651c3353e925fd211c6e490da9fc4e@jabber.mcs.anl.gov' stamp='20080423T15:25:27'>Offline Storage</x></message>
04/23/08 10:24:48 2528 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}message> level=1
04/23/08 10:24:48 2528 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}subject> level=2
04/23/08 10:24:48 2528 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}subject> level=2
04/23/08 10:24:48 2528 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}body> level=2
04/23/08 10:24:48 2528 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}body> level=2
04/23/08 10:24:48 2528 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:x:delay}x> level=2
04/23/08 10:24:48 2528 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:x:delay}x> level=2
04/23/08 10:24:48 2528 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}message> level=1
04/23/08 10:24:48 2528 JabberClient     jabberclient.py:133 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/
04/23/08 10:24:49 2528 bajjer.io     io.py:161 DEBUG RECV: <presence to='9b651c3353e925fd211c6e490da9fc4e@jabber.mcs.anl.gov/default' from='ag_art_lobby(artgridvs.chpc.utah.edu)@conference.mcs.anl.gov/Creighton University HSL'><x/><x xmlns='http://jabber.org/protocol/muc#user'><item jid='9b651c3353e925fd211c6e490da9fc4e@jabber.mcs.anl.gov/default' affiliation='owner' role='moderator'/></x></presence>
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}presence> level=1
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}x> level=2
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}x> level=2
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:72 DEBUG start <{http://jabber.org/protocol/muc#user}x> level=2
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:72 DEBUG start <{http://jabber.org/protocol/muc#user}item> level=3
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:86 DEBUG end <{http://jabber.org/protocol/muc#user}item> level=3
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:86 DEBUG end <{http://jabber.org/protocol/muc#user}x> level=2
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}presence> level=1
04/23/08 10:24:49 2528 JabberClient     jabberclient.py:145 DEBUG ag_art_lobby(artgridvs.chpc.utah.edu)@conference.mcs.anl.gov/Creighton University HSL is available (None / None)
04/23/08 10:24:49 2528 VenueClient     venueclientui.py:2308 DEBUG   add user: Creighton University HSL
04/23/08 10:24:49 2528 bajjer.io     io.py:161 DEBUG RECV: <message type='groupchat' to='9b651c3353e925fd211c6e490da9fc4e@jabber.mcs.anl.gov/default' from='ag_art_lobby(artgridvs.chpc.utah.edu)@conference.mcs.anl.gov'><body>ag_art_lobby(artgridvs.chpc.utah.edu)</body></message>
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}message> level=1
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}body> level=2
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}body> level=2
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}message> level=1
04/23/08 10:24:49 2528 JabberClient     jabberclient.py:133 DEBUG ag_art_lobby(artgridvs.chpc.utah.edu)@conference.mcs.anl.go: ag_art_lobby(artgridvs.chpc.utah.edu)
04/23/08 10:24:49 2528 bajjer.io     io.py:161 DEBUG RECV: <message type='groupchat' to='9b651c3353e925fd211c6e490da9fc4e@jabber.mcs.anl.gov/default' from='ag_art_lobby(artgridvs.chpc.utah.edu)@conference.mcs.anl.gov'><body>This room supports the MUC protocol.</body></message>
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}message> level=1
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}body> level=2
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}body> level=2
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}message> level=1
04/23/08 10:24:49 2528 bajjer.io     io.py:161 DEBUG RECV: <message type='groupchat' from='ag_art_lobby(artgridvs.chpc.utah.edu)@conference.mcs.anl.gov' to='9b651c3353e925fd211c6e490da9fc4e@jabber.mcs.anl.gov/default'><body>Creighton University HSL has joined this session</body></message>
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}message> level=1
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}body> level=2
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}body> level=2
04/23/08 10:24:49 2528 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}message> level=1
04/23/08 10:24:49 4040 VenueClient     venueclient.py:1260 DEBUG Got location from bridge: unicast agbridge.arsc.edu 50398
04/23/08 10:24:49 4040 VenueClient     venueclient.py:1231 INFO  In UpdateStream: transport=unicast
04/23/08 10:24:49 2528 JabberClient     jabberclient.py:133 DEBUG ag_art_lobby(artgridvs.chpc.utah.edu)@conference.mcs.anl.go: This room supports the MUC protocol.
04/23/08 10:24:49 2528 JabberClient     jabberclient.py:133 DEBUG ag_art_lobby(artgridvs.chpc.utah.edu)@conference.mcs.anl.go: Creighton University HSL has joined this session
04/23/08 10:24:49 4040 VenueClient     venueclient.py:1260 DEBUG Got location from bridge: unicast agbridge.arsc.edu 50182
04/23/08 10:24:49 4040 VenueClient     venueclient.py:1231 INFO  In UpdateStream: transport=unicast
04/23/08 10:24:49 2788 GroupMsgClient     groupmsgclient.py:112 INFO  Connected.  Connection ID: 9b651c3353e925fd211c6e490da9fc4e
04/23/08 10:24:49 2788 EventClient     insecurevenueeventclient.py:60 INFO  BaseVenueEventClient made connection.
04/23/08 10:24:49 4040 VenueClient     venueclient.py:1260 DEBUG Got location from bridge: unicast agbridge.arsc.edu 50582
04/23/08 10:24:49 4040 VenueClient     venueclient.py:1231 INFO  In UpdateStream: transport=unicast
04/23/08 10:24:50 4040 VenueClient     venueclient.py:1260 DEBUG Got location from bridge: unicast agbridge.arsc.edu 50162
04/23/08 10:24:50 4040 RTPBeacon     rtpbeacon.py:296 DEBUG Called Stop.
04/23/08 10:24:50 4040 VenueClient     venueclient.py:951 INFO  Beacon being reconfigured, stopping running beacon
04/23/08 10:24:50 4040 RTPBeacon     rtpbeacon.py:296 DEBUG Called Stop.
04/23/08 10:24:50 4040 VenueClient     venueclient.py:959 INFO  VenueClient.StartBeacon: Address agbridge.arsc.edu/50162
04/23/08 10:24:50 4040 RTPBeacon     rtpbeacon.py:278 DEBUG Called Start.
04/23/08 10:24:50 4040 VenueClient     venueclient.py:1190 DEBUG UpdateNodeService: Method UpdateNodeService called
04/23/08 10:24:50 4040 VenueClient     venueclient.py:1195 DEBUG Setting node service streams
04/23/08 10:24:50 4040 NodeService     agnodeservice.py:328 INFO  NodeService.SetStreams
04/23/08 10:24:50 4040 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
04/23/08 10:24:50 3064 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
04/23/08 10:24:50 4040 NodeService     agnodeservice.py:695 INFO  NodeService.__SendStreamsToService
04/23/08 10:24:50 4040 NodeService     agnodeservice.py:699 DEBUG service capabilities: [consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L8, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L8, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, PCMU, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, PCMU, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, GSM, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, GSM, 8000, 1, producer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 16000, 1]
04/23/08 10:24:50 4040 NodeService     agnodeservice.py:713 INFO  Sending stream (type=[consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, L16, 16000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, L16, 8000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, L8, 16000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, L8, 8000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, PCMU, 16000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, PCMU, 8000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, GSM, 16000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, GSM, 8000, 1]) to service: http://hslAG.blue.jays.creighton.edu:52325/Services/AudioService.9386c9b10e7c25fccfe4cb7a1d1b58e8
04/23/08 10:24:50 1592 RTPBeacon     rtpbeacon.py:289 INFO  RTPBeacon.UpdateThread exiting
04/23/08 10:24:50 4040 NodeService     agnodeservice.py:695 INFO  NodeService.__SendStreamsToService
04/23/08 10:24:50 4040 NodeService     agnodeservice.py:699 DEBUG service capabilities: [consumer, video, 9386c9b10d9025fcd05c01987b95f9f1, H261, 90000, 1]
04/23/08 10:24:50 4040 NodeService     agnodeservice.py:713 INFO  Sending stream (type=[consumer, video, 9b651c3353e91e449cc8043567668328, H261, 90000, 1, consumer, video, 9b651cc315e42164ff640735d9f050d2, H264, 90000, 1, consumer, video, 9b651cc315e42164ff640735d9f050d2, MPEG4, 90000, 1, consumer, video, 9b651cc315e42164ff640735d9f050d2, H261AS, 90000, 1]) to service: http://hslAG.blue.jays.creighton.edu:64004/Services/VideoConsumerService.9386c9b10d9025fcd07ad337cf81dfa8
04/23/08 10:24:50 4040 NodeService     agnodeservice.py:695 INFO  NodeService.__SendStreamsToService
04/23/08 10:24:50 4040 NodeService     agnodeservice.py:699 DEBUG service capabilities: [producer, video, 9386c9b10ecc25fcd0ea01b98a8d0e4a, H261, 90000, 1]
04/23/08 10:24:50 4040 NodeService     agnodeservice.py:713 INFO  Sending stream (type=[consumer, video, 9b651c3353e91e449cc8043567668328, H261, 90000, 1, consumer, video, 9b651cc315e42164ff640735d9f050d2, H264, 90000, 1, consumer, video, 9b651cc315e42164ff640735d9f050d2, MPEG4, 90000, 1, consumer, video, 9b651cc315e42164ff640735d9f050d2, H261AS, 90000, 1]) to service: http://hslAG.blue.jays.creighton.edu:58053/Services/VideoProducerService.9386c9b10ecc25fcd12ebdebccb95fbc
04/23/08 10:24:51 4040 VenueClient     venueclient.py:914 DEBUG Updating client profile cache.
04/23/08 10:25:23 3452 VenueClient     venueclient.py:450 DEBUG Calling Heartbeat, time now: 1208964323
04/23/08 10:25:24 3452 VenueClient     venueclient.py:474 DEBUG Next Heartbeat needed within 36s
04/23/08 10:26:00 3536 VenueClient     venueclient.py:450 DEBUG Calling Heartbeat, time now: 1208964360
04/23/08 10:26:01 3536 VenueClient     venueclient.py:474 DEBUG Next Heartbeat needed within 36s
04/23/08 10:26:12 2528 bajjer.io     io.py:161 DEBUG RECV:  

04/23/08 10:26:37 2484 VenueClient     venueclient.py:450 DEBUG Calling Heartbeat, time now: 1208964397
04/23/08 10:26:37 2484 VenueClient     venueclient.py:474 DEBUG Next Heartbeat needed within 36s
04/23/08 10:27:13 1632 VenueClient     venueclient.py:450 DEBUG Calling Heartbeat, time now: 1208964433
04/23/08 10:27:14 2528 bajjer.io     io.py:161 DEBUG RECV:  

04/23/08 10:27:14 1632 VenueClient     venueclient.py:474 DEBUG Next Heartbeat needed within 36s
04/23/08 10:27:50 4080 VenueClient     venueclient.py:450 DEBUG Calling Heartbeat, time now: 1208964470
04/23/08 10:27:50 4080 VenueClient     venueclient.py:474 DEBUG Next Heartbeat needed within 36s
04/23/08 10:28:15 2528 bajjer.io     io.py:161 DEBUG RECV:  

04/23/08 10:28:27 4040 VenueClient     venueclient.py:450 DEBUG Calling Heartbeat, time now: 1208964507
04/23/08 10:28:27 4040 VenueClient     venueclient.py:474 DEBUG Next Heartbeat needed within 36s
04/23/08 10:28:29 2788 NodeService     agnodeservice.py:289 INFO  NodeService.SetServiceEnabledByMediaType
04/23/08 10:28:29 2788 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
04/23/08 10:28:29 3620 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
04/23/08 10:28:29 2788 NodeService     agnodeservice.py:275 INFO  NodeService.SetServiceEnabled
04/23/08 10:28:30 2788 NodeService     agnodeservice.py:289 INFO  NodeService.SetServiceEnabledByMediaType
04/23/08 10:28:30 2788 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
04/23/08 10:28:30 2844 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
04/23/08 10:28:30 2788 NodeService     agnodeservice.py:275 INFO  NodeService.SetServiceEnabled
04/23/08 10:28:30 2788 NodeService     agnodeservice.py:695 INFO  NodeService.__SendStreamsToService
04/23/08 10:28:30 2788 NodeService     agnodeservice.py:699 DEBUG service capabilities: [consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L8, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L8, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, PCMU, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, PCMU, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, GSM, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, GSM, 8000, 1, producer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 16000, 1]
04/23/08 10:28:30 2788 NodeService     agnodeservice.py:713 INFO  Sending stream (type=[consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, L16, 16000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, L16, 8000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, L8, 16000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, L8, 8000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, PCMU, 16000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, PCMU, 8000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, GSM, 16000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, GSM, 8000, 1]) to service: http://hslAG.blue.jays.creighton.edu:52325/Services/AudioService.9386c9b10e7c25fccfe4cb7a1d1b58e8
04/23/08 10:28:30 2788 NodeService     agnodeservice.py:289 INFO  NodeService.SetServiceEnabledByMediaType
04/23/08 10:28:30 2788 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
04/23/08 10:28:30 2768 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
04/23/08 10:28:31 2788 NodeService     agnodeservice.py:275 INFO  NodeService.SetServiceEnabled
04/23/08 10:28:42 2788 NodeService     agnodeservice.py:289 INFO  NodeService.SetServiceEnabledByMediaType
04/23/08 10:28:42 2788 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
04/23/08 10:28:42 3100 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
04/23/08 10:28:43 2788 NodeService     agnodeservice.py:275 INFO  NodeService.SetServiceEnabled
04/23/08 10:28:43 2788 NodeService     agnodeservice.py:695 INFO  NodeService.__SendStreamsToService
04/23/08 10:28:43 2788 NodeService     agnodeservice.py:699 DEBUG service capabilities: [consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L8, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L8, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, PCMU, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, PCMU, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, GSM, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, GSM, 8000, 1, producer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 16000, 1]
04/23/08 10:28:43 2788 NodeService     agnodeservice.py:713 INFO  Sending stream (type=[consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, L16, 16000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, L16, 8000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, L8, 16000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, L8, 8000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, PCMU, 16000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, PCMU, 8000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, GSM, 16000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, GSM, 8000, 1]) to service: http://hslAG.blue.jays.creighton.edu:52325/Services/AudioService.9386c9b10e7c25fccfe4cb7a1d1b58e8
04/23/08 10:28:43 2788 NodeService     agnodeservice.py:289 INFO  NodeService.SetServiceEnabledByMediaType
04/23/08 10:28:43 2788 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
04/23/08 10:28:43 3904 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
04/23/08 10:28:43 2788 NodeService     agnodeservice.py:275 INFO  NodeService.SetServiceEnabled
04/23/08 10:28:59 2788 NodeService     agnodeservice.py:289 INFO  NodeService.SetServiceEnabledByMediaType
04/23/08 10:28:59 2788 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
04/23/08 10:28:59 4048 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
04/23/08 10:28:59 2788 NodeService     agnodeservice.py:275 INFO  NodeService.SetServiceEnabled
04/23/08 10:28:59 2788 NodeService     agnodeservice.py:695 INFO  NodeService.__SendStreamsToService
04/23/08 10:28:59 2788 NodeService     agnodeservice.py:699 DEBUG service capabilities: [consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L8, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L8, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, PCMU, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, PCMU, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, GSM, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, GSM, 8000, 1, producer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 16000, 1]
04/23/08 10:28:59 2788 NodeService     agnodeservice.py:713 INFO  Sending stream (type=[consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, L16, 16000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, L16, 8000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, L8, 16000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, L8, 8000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, PCMU, 16000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, PCMU, 8000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, GSM, 16000, 1, consumer, audio, 9b651c3353e91e449ccb04356ec9eb64, GSM, 8000, 1]) to service: http://hslAG.blue.jays.creighton.edu:52325/Services/AudioService.9386c9b10e7c25fccfe4cb7a1d1b58e8
04/23/08 10:29:03 3200 VenueClient     venueclient.py:450 DEBUG Calling Heartbeat, time now: 1208964543
04/23/08 10:29:03 3200 VenueClient     venueclient.py:474 DEBUG Next Heartbeat needed within 36s
04/23/08 10:29:16 2528 bajjer.io     io.py:161 DEBUG RECV:  

04/23/08 10:29:39 532 VenueClient     venueclient.py:450 DEBUG Calling Heartbeat, time now: 1208964579
04/23/08 10:29:40 532 VenueClient     venueclient.py:474 DEBUG Next Heartbeat needed within 36s
04/23/08 10:30:16 212 VenueClient     venueclient.py:450 DEBUG Calling Heartbeat, time now: 1208964616
04/23/08 10:30:17 2528 bajjer.io     io.py:161 DEBUG RECV:  

04/23/08 10:30:18 212 VenueClient     venueclient.py:474 DEBUG Next Heartbeat needed within 36s
04/23/08 10:30:54 2364 VenueClient     venueclient.py:450 DEBUG Calling Heartbeat, time now: 1208964654
04/23/08 10:30:56 2364 VenueClient     venueclient.py:474 DEBUG Next Heartbeat needed within 36s
04/23/08 10:31:18 2528 bajjer.io     io.py:161 DEBUG RECV:  

04/23/08 10:31:32 3308 VenueClient     venueclient.py:450 DEBUG Calling Heartbeat, time now: 1208964692
04/23/08 10:31:35 3308 VenueClient     venueclient.py:474 DEBUG Next Heartbeat needed within 36s
04/23/08 10:31:53 2788 VenueClient     venueclientui.py:2209 INFO  --------- END VenueClient
04/23/08 10:31:53 2788 VenueClient     venueclient.py:1132 INFO  ExitVenue
04/23/08 10:31:53 2788 VenueClient     venueclient.py:1173 INFO  ExitVenue: Stopping node services
04/23/08 10:31:53 2788 NodeService     agnodeservice.py:301 INFO  NodeService.StopServices
04/23/08 10:31:53 2208 ServiceManager     agservicemanager.py:225 INFO  AGServiceManager.StopServices
04/23/08 10:31:55 2788 NodeService     agnodeservice.py:328 INFO  NodeService.SetStreams
04/23/08 10:31:55 2788 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
04/23/08 10:31:55 3088 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
04/23/08 10:31:55 2788 NodeService     agnodeservice.py:695 INFO  NodeService.__SendStreamsToService
04/23/08 10:31:55 2788 NodeService     agnodeservice.py:699 DEBUG service capabilities: [consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L8, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, L8, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, PCMU, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, PCMU, 8000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, GSM, 16000, 1, consumer, audio, 9386c9b10e7c25fccfc601b98a747f66, GSM, 8000, 1, producer, audio, 9386c9b10e7c25fccfc601b98a747f66, L16, 16000, 1]
04/23/08 10:31:55 2788 NodeService     agnodeservice.py:695 INFO  NodeService.__SendStreamsToService
04/23/08 10:31:55 2788 NodeService     agnodeservice.py:699 DEBUG service capabilities: [consumer, video, 9386c9b10d9025fcd05c01987b95f9f1, H261, 90000, 1]
04/23/08 10:31:55 2788 NodeService     agnodeservice.py:695 INFO  NodeService.__SendStreamsToService
04/23/08 10:31:55 2788 NodeService     agnodeservice.py:699 DEBUG service capabilities: [producer, video, 9386c9b10ecc25fcd0ea01b98a8d0e4a, H261, 90000, 1]
04/23/08 10:31:56 2788 VenueClient     venueclient.py:1103 DEBUG ExitVenue: Stop event client obj
04/23/08 10:31:56 2788 VenueClient     venueclient.py:1105 DEBUG ExitVenue: Remove event client reference
04/23/08 10:31:56 2788 VenueClient     venueclient.py:1110 INFO  ExitVenue: Stopping text client
04/23/08 10:31:56 2788 JabberClient     jabberclient.py:73 DEBUG Sending the presence to 'AG_Art_Lobby(artgridvs.chpc.utah.edu)@conference.mcs.anl.gov/Creighton University HSL' of type 'unavailable'...
04/23/08 10:31:56 2788 bajjer.io     io.py:122 DEBUG SEND: <presence to="AG_Art_Lobby(artgridvs.chpc.utah.edu)@conference.mcs.anl.gov/Creighton University HSL" type="unavailable"><x /></presence>
04/23/08 10:31:56 2788 RTPBeacon     rtpbeacon.py:296 DEBUG Called Stop.
04/23/08 10:31:56 2788 ServiceManager     agservicemanager.py:70 INFO  AGServiceManager.Shutdown
04/23/08 10:31:56 2788 ServiceManager     agservicemanager.py:71 INFO  Remove services
04/23/08 10:31:56 2788 ServiceManager     agservicemanager.py:208 INFO  AGServiceManager.RemoveServices
04/23/08 10:31:56 2788 ServiceManager     agservicemanager.py:162 INFO  AGServiceManager.RemoveService
04/23/08 10:31:56 1624 RTPBeacon     rtpbeacon.py:289 INFO  RTPBeacon.UpdateThread exiting
04/23/08 10:31:56 2528 bajjer.io     io.py:161 DEBUG RECV: <presence to='9b651c3353e925fd211c6e490da9fc4e@jabber.mcs.anl.gov/default' type='unavailable' from='ag_art_lobby(artgridvs.chpc.utah.edu)@conference.mcs.anl.gov/Creighton University HSL'><x/><x xmlns='http://jabber.org/protocol/muc#user'><item jid='9b651c3353e925fd211c6e490da9fc4e@jabber.mcs.anl.gov/default' affiliation='owner' role='none'/></x></presence>
04/23/08 10:31:56 2528 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}presence> level=1
04/23/08 10:31:56 2528 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}x> level=2
04/23/08 10:31:56 2528 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}x> level=2
04/23/08 10:31:56 2528 bajjer.serialize     serialize.py:72 DEBUG start <{http://jabber.org/protocol/muc#user}x> level=2
04/23/08 10:31:56 2528 bajjer.serialize     serialize.py:72 DEBUG start <{http://jabber.org/protocol/muc#user}item> level=3
04/23/08 10:31:56 2528 bajjer.serialize     serialize.py:86 DEBUG end <{http://jabber.org/protocol/muc#user}item> level=3
04/23/08 10:31:56 2528 bajjer.serialize     serialize.py:86 DEBUG end <{http://jabber.org/protocol/muc#user}x> level=2
04/23/08 10:31:56 2528 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}presence> level=1
04/23/08 10:31:56 2528 JabberClient     jabberclient.py:148 DEBUG ag_art_lobby(artgridvs.chpc.utah.edu)@conference.mcs.anl.gov/Creighton University HSL is unavailable (None / None)
04/23/08 10:31:56 2528 VenueClient     venueclientui.py:2322 DEBUG   remove user: Creighton University HSL
04/23/08 10:31:57 2788 ProcessManager     processmanager.py:136 WARNING Couldn't terminate process <PyHANDLE:1264> cleanly (258)
04/23/08 10:31:57 2788 ServiceManager     agservicemanager.py:162 INFO  AGServiceManager.RemoveService
04/23/08 10:31:57 2788 ProcessManager     processmanager.py:136 WARNING Couldn't terminate process <PyHANDLE:1160> cleanly (258)
04/23/08 10:31:57 2788 ServiceManager     agservicemanager.py:162 INFO  AGServiceManager.RemoveService
04/23/08 10:31:58 2788 ServiceManager     agservicemanager.py:73 INFO  Stop network interface
04/23/08 10:31:58 2788 RTPBeacon     rtpbeacon.py:296 DEBUG Called Stop.