05/24/06 16:35:18 3616 Toolkit     config.py:559 DEBUG System hostname of berdondini.italia03.sacmi.priv is valid
05/24/06 16:35:18 3616 Toolkit     toolkit.py:109 DEBUG Initializing AG Toolkit version 3.0.1
05/24/06 16:35:18 3616 Toolkit     toolkit.py:110 INFO  Command and arguments: ['C:\\Programmi\\AGTk-3\\bin\\VenueClient3.py', '--personalNode=1']
05/24/06 16:35:18 3616 VenueClient     preferences.py:180 DEBUG Preferences.LoadPreferences: open file
05/24/06 16:35:18 3616 Toolkit     toolkit.py:159 INFO  Logfile Name: VenueClient.log
05/24/06 16:35:18 3616 Toolkit     venueclient3.py:124 INFO  wx version is: 2.6.2.1
05/24/06 16:35:18 3616 VenueClient     venueclient.py:366 DEBUG __StartWebService: venueclient: http://berdondini.italia03.sacmi.priv:11000/VenueClient
05/24/06 16:35:18 3616 VenueClient     venueclient.py:377 DEBUG __StartWebService: service manager: http://berdondini.italia03.sacmi.priv:11000/ServiceManager
05/24/06 16:35:18 3616 VenueClient     venueclient.py:391 DEBUG __StartWebService: node service: http://berdondini.italia03.sacmi.priv:11000/NodeService
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:635 INFO  NodeService.GetConfigurations
05/24/06 16:35:18 3616 VenueClient     venueclient.py:174 DEBUG Loading node configuration: <AccessGrid.Descriptions.NodeConfigDescription instance at 0x02D1D6C0>
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:375 INFO  NodeService.LoadConfiguration
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:394 INFO  Trying to load node configuration from: C:\Programmi\AGTk-3\Config\nodeConfig\default
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:402 DEBUG section: node
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:404 DEBUG   servicemanagers : servicemanager0
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:402 DEBUG section: service1
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:404 DEBUG   packageName : VideoConsumerService.zip
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:402 DEBUG section: service0
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:404 DEBUG   packageName : AudioService.zip
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:404 DEBUG   serviceConfig : serviceconfig0
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:402 DEBUG section: servicemanager0
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:404 DEBUG   services : service0 service1
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:404 DEBUG   builtin : 1
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:404 DEBUG   name : 
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:404 DEBUG   url : 
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:402 DEBUG section: serviceconfig0
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:404 DEBUG   Force AC97 : False
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:404 DEBUG   Silence Suppression : Off
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:404 DEBUG   Output Gain : 50
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:404 DEBUG   Talk : Off
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:404 DEBUG   Input Gain : 50
05/24/06 16:35:18 3616 NodeService     agnodeservice.py:479 DEBUG using builtin service manager at http://berdondini.italia03.sacmi.priv:11000/ServiceManager
05/24/06 16:35:18 3056 ServiceManager     agservicemanager.py:208 INFO  AGServiceManager.RemoveServices
05/24/06 16:35:18 1716 ServiceManager     agservicemanager.py:102 INFO  AGServiceManager.AddService
05/24/06 16:35:18 1716 ServiceManager     agservicemanager.py:339 INFO  Installed service version: 3.3, package version: 3.3
05/24/06 16:35:18 1716 ServiceManager     agservicemanager.py:374 DEBUG Executing service AudioService
05/24/06 16:35:18 1716 ServiceManager     agservicemanager.py:406 INFO  Running Service; options: ['C:\\Documents and Settings\\BerdoFab.PROTOTIPO\\Dati applicazioni\\AccessGrid3\\local_services\\AudioService\\AudioService.py', '--port', 60924, '--serviceManagerUri', 'http://berdondini.italia03.sacmi.priv:11000/ServiceManager', '--token', 'd5d1de340c703d7b6b2c9625965c7d3c']
05/24/06 16:35:18 1716 ProcessManager     processmanager.py:62 DEBUG Creating process: C:\Python23\pythonw.exe "C:\Documents and Settings\BerdoFab.PROTOTIPO\Dati applicazioni\AccessGrid3\local_services\AudioService\AudioService.py" --port 60924 --serviceManagerUri http://berdondini.italia03.sacmi.priv:11000/ServiceManager --token d5d1de340c703d7b6b2c9625965c7d3c
05/24/06 16:35:18 1716 ProcessManager     processmanager.py:75 DEBUG Create process returns: (<PyHANDLE:980>, <PyHANDLE:968>, 3140, 3212)
05/24/06 16:35:18 1716 ServiceManager     agservicemanager.py:418 INFO  Service registered: http://berdondini.italia03.sacmi.priv:60924/Services/AudioService.d5d1de340c443d7b6b6b4e4bc03ac73b d5d1de340c703d7b6b2c9625965c7d3c
05/24/06 16:35:19 1172 ServiceManager     agservicemanager.py:102 INFO  AGServiceManager.AddService
05/24/06 16:35:19 1172 ServiceManager     agservicemanager.py:339 INFO  Installed service version: 3.02, package version: 3.02
05/24/06 16:35:19 1172 ServiceManager     agservicemanager.py:374 DEBUG Executing service VideoConsumerService
05/24/06 16:35:19 1172 ServiceManager     agservicemanager.py:406 INFO  Running Service; options: ['C:\\Documents and Settings\\BerdoFab.PROTOTIPO\\Dati applicazioni\\AccessGrid3\\local_services\\VideoConsumerService\\VideoConsumerService.py', '--port', 60571, '--serviceManagerUri', 'http://berdondini.italia03.sacmi.priv:11000/ServiceManager', '--token', 'd5d1de340c703d7b6b889e8fa846045f']
05/24/06 16:35:19 1172 ProcessManager     processmanager.py:62 DEBUG Creating process: C:\Python23\pythonw.exe "C:\Documents and Settings\BerdoFab.PROTOTIPO\Dati applicazioni\AccessGrid3\local_services\VideoConsumerService\VideoConsumerService.py" --port 60571 --serviceManagerUri http://berdondini.italia03.sacmi.priv:11000/ServiceManager --token d5d1de340c703d7b6b889e8fa846045f
05/24/06 16:35:19 1172 ProcessManager     processmanager.py:75 DEBUG Create process returns: (<PyHANDLE:964>, <PyHANDLE:992>, 3376, 2296)
05/24/06 16:35:19 1172 ServiceManager     agservicemanager.py:418 INFO  Service registered: http://berdondini.italia03.sacmi.priv:60571/Services/VideoConsumerService.d5d1de340d303d7b6bc6db10261c1f76 d5d1de340c703d7b6b889e8fa846045f
05/24/06 16:35:19 3616 VenueClient     venueclient.py:242 DEBUG get bridges from registry
05/24/06 16:35:20 3616 RegistryClient     registryclient.py:50 INFO  No bridge registry peers reachable
05/24/06 16:35:20 3616 VenueClient     venueclient.py:253 ERROR __LoadBridges: Can not connect to bridge registry http://www.accessgrid.org/registry/peers.txt 
Traceback (most recent call last):
  File "C:\Python23\lib\site-packages\AccessGrid3\AccessGrid\VenueClient.py", line 248, in __LoadBridges
    bridgeList = self.registryClient.LookupBridge(10)
  File "C:\Python23\lib\site-packages\AccessGrid3\AccessGrid\Registry\RegistryClient.py", line 83, in LookupBridge
    bridges = self.serverProxy.LookupBridge()
AttributeError: 'NoneType' object has no attribute 'LookupBridge'
05/24/06 16:35:20 3616 VenueClient     venueclient.py:256 DEBUG set bridges in prefs
05/24/06 16:35:20 3616 VenueClient     venueclient.py:261 DEBUG connect to bridge
05/24/06 16:35:20 3616 VenueClient     venueclient.py:278 DEBUG exiting loadbridges
05/24/06 16:35:20 3616 NodeService     agnodeservice.py:289 INFO  NodeService.SetServiceEnabledByMediaType
05/24/06 16:35:20 3616 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
05/24/06 16:35:20 2528 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
05/24/06 16:35:20 3616 NodeService     agnodeservice.py:275 INFO  NodeService.SetServiceEnabled
05/24/06 16:35:20 3616 NodeService     agnodeservice.py:695 INFO  NodeService.__SendStreamsToService
05/24/06 16:35:20 3616 NodeService     agnodeservice.py:699 DEBUG service capabilities: [consumer, audio, d5d1de340c443d7b6b590467f9831593, L16, 16000, 1, consumer, audio, d5d1de340c443d7b6b590467f9831593, L16, 8000, 1, consumer, audio, d5d1de340c443d7b6b590467f9831593, L8, 16000, 1, consumer, audio, d5d1de340c443d7b6b590467f9831593, L8, 8000, 1, consumer, audio, d5d1de340c443d7b6b590467f9831593, PCMU, 16000, 1, consumer, audio, d5d1de340c443d7b6b590467f9831593, PCMU, 8000, 1, consumer, audio, d5d1de340c443d7b6b590467f9831593, GSM, 16000, 1, consumer, audio, d5d1de340c443d7b6b590467f9831593, GSM, 8000, 1, producer, audio, d5d1de340c443d7b6b590467f9831593, L16, 16000, 1]
05/24/06 16:35:20 3616 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
05/24/06 16:35:20 3520 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
05/24/06 16:35:20 3616 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
05/24/06 16:35:20 2648 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
05/24/06 16:35:28 3616 VenueClientController     venueclientcontroller.py:374 DEBUG VenueClientController calling Venue.EnterVenue
05/24/06 16:35:28 3616 VenueClient     venueclient.py:991 DEBUG EnterVenue; url=https://vv3.mcs.anl.gov:8000/Venues/default
05/24/06 16:35:28 3616 NodeService     agnodeservice.py:658 INFO  NodeService.GetCapabilities
05/24/06 16:35:28 3616 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
05/24/06 16:35:28 4092 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
05/24/06 16:35:28 3616 VenueClient     venueclient.py:1025 DEBUG calling __EnterVenue
05/24/06 16:35:28 3616 VenueClient     venueclient.py:792 DEBUG EnterVenue: Invoke Venue.Enter
05/24/06 16:35:31 3616 VenueClient     venueclient.py:794 DEBUG after Venue.Enter
05/24/06 16:35:31 3616 VenueClient     venueclient.py:796 DEBUG EnterVenue: Invoke Venue.getstate
05/24/06 16:35:32 3616 VenueClient     venueclient.py:798 DEBUG EnterVenue: done Venue.getstate
05/24/06 16:35:34 3616 VenueClient     venueclient.py:821 DEBUG Setting isInVenue flag.
05/24/06 16:35:34 816 VenueClient     venueclient.py:441 DEBUG Calling Heartbeat, time now: 1148481334
05/24/06 16:35:34 3616 VenueClient     venueclient.py:1027 DEBUG after __EnterVenue
05/24/06 16:35:34 3616 VenueClient     venueclientui.py:2486 DEBUG bin.VenueClient::EnterVenue: Enter venue with url: https://vv3.mcs.anl.gov:8000/Venues/default
05/24/06 16:35:34 3616 VenueClient     venueclientui.py:2599 DEBUG Entered venue
05/24/06 16:35:34 3616 VenueClientController     venueclientcontroller.py:376 DEBUG VenueClientController after Venue.EnterVenue
05/24/06 16:35:34 3616 VenueClient     venueclientui.py:3196 DEBUG ContentListPanel.AddParticipant:: AddParticipant Franca Fiumana (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) )'))
05/24/06 16:35:34 3616 VenueClient     venueclientui.py:3196 DEBUG ContentListPanel.AddParticipant:: AddParticipant Tom Uram (Linux) (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) )'))
05/24/06 16:35:34 3616 VenueClient     venueclientui.py:3196 DEBUG ContentListPanel.AddParticipant:: AddParticipant Tom Uram (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) )'))
05/24/06 16:35:34 3616 VenueClient     venueclientui.py:3196 DEBUG ContentListPanel.AddParticipant:: AddParticipant Fabrizio Berdondini (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) )'))
05/24/06 16:35:35 816 VenueClient     venueclient.py:460 DEBUG Next Heartbeat needed before: 36
05/24/06 16:35:35 816 JabberClient     jabberclient.py:33 INFO  Connecting to Jabber Server 'jabber.mcs.anl.gov' ...
05/24/06 16:35:35 816 bajjer.io     io.py:100 DEBUG RECV: <?xml version='1.0'?><stream:stream xmlns:stream='http://etherx.jabber.org/streams' id='44746F36' xmlns='jabber:client' from='jabber.mcs.anl.gov'>
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:65 DEBUG feed parser initialized
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:72 DEBUG start <{http://etherx.jabber.org/streams}stream> level=0
05/24/06 16:35:35 816 JabberClient     jabberclient.py:168 INFO  Registering the user '8cdd0b2c36c83d7b715e746f36c218a6@jabber.mcs.anl.gov/default' in jabber server ...
05/24/06 16:35:35 816 bajjer.io     io.py:122 DEBUG SEND: <iq type="get"><query xmlns="jabber:iq:register" /></iq>
05/24/06 16:35:35 816 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>
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}iq> level=1
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:register}query> level=2
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:register}password> level=3
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:register}password> level=3
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:register}password> level=3
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:register}password> level=3
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:register}instructions> level=3
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:register}instructions> level=3
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:register}name> level=3
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:register}name> level=3
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:register}email> level=3
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:register}email> level=3
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:register}username> level=3
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:register}username> level=3
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:register}query> level=2
05/24/06 16:35:35 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}iq> level=1
05/24/06 16:35:35 816 bajjer.io     io.py:122 DEBUG SEND: <iq type="set"><query xmlns="jabber:iq:register"><username>8cdd0b2c36c83d7b715e746f36c218a6</username><password>8cdd0b2c36c83d7b715e746f36c218a6</password></query></iq>
05/24/06 16:35:36 816 bajjer.io     io.py:161 DEBUG RECV: <iq type='result'/>
05/24/06 16:35:36 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}iq> level=1
05/24/06 16:35:36 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}iq> level=1
05/24/06 16:35:37 816 JabberClient     jabberclient.py:177 INFO  Attempting to log in as 8cdd0b2c36c83d7b715e746f36c218a6@jabber.mcs.anl.gov/default ...
05/24/06 16:35:37 816 bajjer.io     io.py:122 DEBUG SEND: <iq type="get"><query xmlns="jabber:iq:auth"><username>8cdd0b2c36c83d7b715e746f36c218a6</username></query></iq>
05/24/06 16:35:37 816 bajjer.io     io.py:161 DEBUG RECV: <iq type='result'><query xmlns='jabber:iq:auth'><username>8cdd0b2c36c83d7b715e746f36c218a6</username><digest/><password/><resource/></query></iq>
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}iq> level=1
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:auth}query> level=2
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:auth}username> level=3
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:auth}username> level=3
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:auth}digest> level=3
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:auth}digest> level=3
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:auth}password> level=3
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:auth}password> level=3
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:auth}resource> level=3
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:auth}resource> level=3
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:auth}query> level=2
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}iq> level=1
05/24/06 16:35:37 816 bajjer.stream     stream.py:326 DEBUG Recv: <gov.lbl.dsd.bajjer.stanza.Iq object at 0x0306E030>
05/24/06 16:35:37 816 bajjer.stream     stream.py:342 DEBUG performing 'digest' authentication
05/24/06 16:35:37 816 bajjer.io     io.py:122 DEBUG SEND: <iq type="set"><query xmlns="jabber:iq:auth"><resource>default</resource><username>8cdd0b2c36c83d7b715e746f36c218a6</username><digest>5e271f66c8a13c6a82a32c5b2accb666927d5670</digest></query></iq>
05/24/06 16:35:37 816 bajjer.io     io.py:161 DEBUG RECV: <iq type='result' id='pthsock_client_auth_ID'/>
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}iq> level=1
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}iq> level=1
05/24/06 16:35:37 816 bajjer.stream     stream.py:351 DEBUG Authorization succeeded
05/24/06 16:35:37 816 bajjer.io     io.py:122 DEBUG SEND: <iq from="8cdd0b2c36c83d7b715e746f36c218a6" type="get"><query xmlns="jabber:iq:roster" /></iq>
05/24/06 16:35:37 816 bajjer.io     io.py:161 DEBUG RECV: <iq from='8cdd0b2c36c83d7b715e746f36c218a6@jabber.mcs.anl.gov/default' type='result'><query xmlns='jabber:iq:roster'/></iq>
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}iq> level=1
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:iq:roster}query> level=2
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:iq:roster}query> level=2
05/24/06 16:35:37 816 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}iq> level=1
05/24/06 16:35:37 816 bajjer.io     io.py:122 DEBUG SEND: <presence />
05/24/06 16:35:37 816 JabberClient     jabberclient.py:73 DEBUG Sending the presence to 'Argonne-Lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov/Fabrizio Berdondini' of type 'available'...
05/24/06 16:35:37 816 bajjer.io     io.py:122 DEBUG SEND: <presence to="Argonne-Lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov/Fabrizio Berdondini" type="available"><x /></presence>
05/24/06 16:35:37 816 VenueClient     venueclient.py:935 INFO  VenueClient.StartBeacon: Address 224.2.157.5/64094
05/24/06 16:35:37 3636 bajjer.io     io.py:161 DEBUG RECV: <message from='jabber.mcs.anl.gov' to='8cdd0b2c36c83d7b715e746f36c218a6@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='8cdd0b2c36c83d7b715e746f36c218a6@jabber.mcs.anl.gov' stamp='20060524T14:35:34'>Offline Storage</x></message>
05/24/06 16:35:37 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}message> level=1
05/24/06 16:35:37 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}subject> level=2
05/24/06 16:35:37 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}subject> level=2
05/24/06 16:35:37 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}body> level=2
05/24/06 16:35:37 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}body> level=2
05/24/06 16:35:37 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:x:delay}x> level=2
05/24/06 16:35:37 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:x:delay}x> level=2
05/24/06 16:35:37 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}message> level=1
05/24/06 16:35:37 3636 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/
05/24/06 16:35:37 816 RTPBeacon     rtpbeacon.py:278 DEBUG Called Start.
05/24/06 16:35:37 816 VenueClient     venueclient.py:1161 DEBUG UpdateNodeService: Method UpdateNodeService called
05/24/06 16:35:37 816 VenueClient     venueclient.py:1174 DEBUG profile has not changed, not updating services
05/24/06 16:35:37 816 VenueClient     venueclient.py:1178 DEBUG Setting node service streams
05/24/06 16:35:37 816 NodeService     agnodeservice.py:328 INFO  NodeService.SetStreams
05/24/06 16:35:37 816 NodeService     agnodeservice.py:257 INFO  NodeService.GetServices
05/24/06 16:35:37 3636 bajjer.io     io.py:161 DEBUG RECV: <presence to='8cdd0b2c36c83d7b715e746f36c218a6@jabber.mcs.anl.gov/default' from='argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov/Fabrizio Berdondini'><x/><x xmlns='http://jabber.org/protocol/muc#user'><item affiliation='none' role='participant'/></x></presence>
05/24/06 16:35:37 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}presence> level=1
05/24/06 16:35:37 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}x> level=2
05/24/06 16:35:37 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}x> level=2
05/24/06 16:35:37 3636 bajjer.serialize     serialize.py:72 DEBUG start <{http://jabber.org/protocol/muc#user}x> level=2
05/24/06 16:35:37 3636 bajjer.serialize     serialize.py:72 DEBUG start <{http://jabber.org/protocol/muc#user}item> level=3
05/24/06 16:35:37 3636 bajjer.serialize     serialize.py:86 DEBUG end <{http://jabber.org/protocol/muc#user}item> level=3
05/24/06 16:35:37 3636 bajjer.serialize     serialize.py:86 DEBUG end <{http://jabber.org/protocol/muc#user}x> level=2
05/24/06 16:35:37 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}presence> level=1
05/24/06 16:35:37 3636 JabberClient     jabberclient.py:145 DEBUG argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov/Fabrizio Berdondini is available (None / None)
05/24/06 16:35:37 732 ServiceManager     agservicemanager.py:218 INFO  AGServiceManager.GetServices
05/24/06 16:35:38 816 NodeService     agnodeservice.py:695 INFO  NodeService.__SendStreamsToService
05/24/06 16:35:38 3636 VenueClient     venueclientui.py:2208 DEBUG   add user: Fabrizio Berdondini
05/24/06 16:35:38 3636 bajjer.io     io.py:161 DEBUG RECV: <message type='groupchat' to='8cdd0b2c36c83d7b715e746f36c218a6@jabber.mcs.anl.gov/default' from='argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov'><body>argonne-lobby(vv3.mcs.anl.gov)</body></message>
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}message> level=1
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}body> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}body> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}message> level=1
05/24/06 16:35:38 816 NodeService     agnodeservice.py:699 DEBUG service capabilities: [consumer, audio, d5d1de340c443d7b6b590467f9831593, L16, 16000, 1, consumer, audio, d5d1de340c443d7b6b590467f9831593, L16, 8000, 1, consumer, audio, d5d1de340c443d7b6b590467f9831593, L8, 16000, 1, consumer, audio, d5d1de340c443d7b6b590467f9831593, L8, 8000, 1, consumer, audio, d5d1de340c443d7b6b590467f9831593, PCMU, 16000, 1, consumer, audio, d5d1de340c443d7b6b590467f9831593, PCMU, 8000, 1, consumer, audio, d5d1de340c443d7b6b590467f9831593, GSM, 16000, 1, consumer, audio, d5d1de340c443d7b6b590467f9831593, GSM, 8000, 1, producer, audio, d5d1de340c443d7b6b590467f9831593, L16, 16000, 1]
05/24/06 16:35:38 816 NodeService     agnodeservice.py:713 INFO  Sending stream (type=[consumer, audio, 8cdd225c15fc3cf5c367015eb70c254f, L16, 16000, 1, consumer, audio, 8cdd225c15fc3cf5c367015eb70c254f, L16, 8000, 1, consumer, audio, 8cdd225c15fc3cf5c367015eb70c254f, L8, 16000, 1, consumer, audio, 8cdd225c15fc3cf5c367015eb70c254f, L8, 8000, 1, consumer, audio, 8cdd225c15fc3cf5c367015eb70c254f, PCMU, 16000, 1, consumer, audio, 8cdd225c15fc3cf5c367015eb70c254f, PCMU, 8000, 1, consumer, audio, 8cdd225c15fc3cf5c367015eb70c254f, GSM, 16000, 1, consumer, audio, 8cdd225c15fc3cf5c367015eb70c254f, GSM, 8000, 1, producer, audio, 8cdd225c15fc3cf5c367015eb70c254f, L16, 16000, 1]) to service: http://berdondini.italia03.sacmi.priv:60924/Services/AudioService.d5d1de340c443d7b6b6b4e4bc03ac73b
05/24/06 16:35:38 3636 JabberClient     jabberclient.py:133 DEBUG argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.go: argonne-lobby(vv3.mcs.anl.gov)
05/24/06 16:35:38 3636 bajjer.io     io.py:161 DEBUG RECV: <message type='groupchat' to='8cdd0b2c36c83d7b715e746f36c218a6@jabber.mcs.anl.gov/default' from='argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov'><body>This room supports the MUC protocol.</body></message>
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}message> level=1
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}body> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}body> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}message> level=1
05/24/06 16:35:38 3636 bajjer.io     io.py:161 DEBUG RECV: <presence to='8cdd0b2c36c83d7b715e746f36c218a6@jabber.mcs.anl.gov/default' from='argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov/Tom Uram'><x/><x xmlns='jabber:x:delay' from='Argonne-Lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov/Tom Uram' stamp='20060524T14:20:53'/><x xmlns='http://jabber.org/protocol/muc#user'><item affiliation='owner' role='moderator'/></x></presence>
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}presence> level=1
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:x:delay}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:x:delay}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{http://jabber.org/protocol/muc#user}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{http://jabber.org/protocol/muc#user}item> level=3
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{http://jabber.org/protocol/muc#user}item> level=3
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{http://jabber.org/protocol/muc#user}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}presence> level=1
05/24/06 16:35:38 3636 JabberClient     jabberclient.py:133 DEBUG argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.go: This room supports the MUC protocol.
05/24/06 16:35:38 3636 JabberClient     jabberclient.py:145 DEBUG argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov/Tom Uram is available (None / None)
05/24/06 16:35:38 3636 VenueClient     venueclientui.py:2208 DEBUG   add user: Tom Uram
05/24/06 16:35:38 3636 bajjer.io     io.py:161 DEBUG RECV: <presence to='8cdd0b2c36c83d7b715e746f36c218a6@jabber.mcs.anl.gov/default' from='argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov/Tom Uram (Linux)'><x/><x xmlns='jabber:x:delay' from='Argonne-Lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov/Tom Uram (Linux)' stamp='20060524T14:21:09'/><x xmlns='http://jabber.org/protocol/muc#user'><item affiliation='none' role='participant'/></x></presence>
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}presence> level=1
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:x:delay}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:x:delay}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{http://jabber.org/protocol/muc#user}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{http://jabber.org/protocol/muc#user}item> level=3
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{http://jabber.org/protocol/muc#user}item> level=3
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{http://jabber.org/protocol/muc#user}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}presence> level=1
05/24/06 16:35:38 3636 bajjer.io     io.py:161 DEBUG RECV: <presence to='8cdd0b2c36c83d7b715e746f36c218a6@jabber.mcs.anl.gov/default' from='argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov/Franca Fiumana'><x/><x xmlns='jabber:x:delay' from='Argonne-Lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov/Franca Fiumana' stamp='20060524T14:29:58'/><x xmlns='http://jabber.org/protocol/muc#user'><item affiliation='none' role='participant'/></x></presence>
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}presence> level=1
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:x:delay}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:x:delay}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{http://jabber.org/protocol/muc#user}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{http://jabber.org/protocol/muc#user}item> level=3
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{http://jabber.org/protocol/muc#user}item> level=3
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{http://jabber.org/protocol/muc#user}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}presence> level=1
05/24/06 16:35:38 3636 JabberClient     jabberclient.py:145 DEBUG argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov/Tom Uram (Linux) is available (None / None)
05/24/06 16:35:38 3636 VenueClient     venueclientui.py:2208 DEBUG   add user: Tom Uram (Linux)
05/24/06 16:35:38 3636 bajjer.io     io.py:161 DEBUG RECV: <message to='8cdd0b2c36c83d7b715e746f36c218a6@jabber.mcs.anl.gov/default' type='groupchat' from='argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov/Fabrizio Berdondini'><body>salve</body><x xmlns='jabber:x:delay' from='Argonne-Lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov' stamp='20060524T14:31:17'>argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov</x></message>
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}message> level=1
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}body> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}body> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:x:delay}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:x:delay}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}message> level=1
05/24/06 16:35:38 816 NodeService     agnodeservice.py:695 INFO  NodeService.__SendStreamsToService
05/24/06 16:35:38 816 NodeService     agnodeservice.py:699 DEBUG service capabilities: [consumer, video, d5d1de340d303d7b6bb40467f966b321, H261, 90000, 1]
05/24/06 16:35:38 816 NodeService     agnodeservice.py:713 INFO  Sending stream (type=[producer, video, 8cdd225c0c9c3cfbf9c201872e10fad0, H261, 90000, 1]) to service: http://berdondini.italia03.sacmi.priv:60571/Services/VideoConsumerService.d5d1de340d303d7b6bc6db10261c1f76
05/24/06 16:35:38 3616 GroupMsgClient     groupmsgclient.py:112 INFO  Connected.  Connection ID: 8cdd0b2c36c83d7b715e746f36c218a6
05/24/06 16:35:38 3616 EventClient     insecurevenueeventclient.py:60 INFO  BaseVenueEventClient made connection.
05/24/06 16:35:38 3636 JabberClient     jabberclient.py:145 DEBUG argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov/Franca Fiumana is available (None / None)
05/24/06 16:35:38 3636 VenueClient     venueclientui.py:2208 DEBUG   add user: Franca Fiumana
05/24/06 16:35:38 3636 bajjer.io     io.py:161 DEBUG RECV: <message to='8cdd0b2c36c83d7b715e746f36c218a6@jabber.mcs.anl.gov/default' type='groupchat' from='argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov/Fabrizio Berdondini'><body>hello there</body><x xmlns='jabber:x:delay' from='Argonne-Lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov' stamp='20060524T14:31:34'>argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov</x></message>
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}message> level=1
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}body> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}body> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:x:delay}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:x:delay}x> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}message> level=1
05/24/06 16:35:38 816 VenueClient     venueclient.py:896 DEBUG Updating client profile cache.
05/24/06 16:35:38 3636 bajjer.io     io.py:161 DEBUG RECV: <message type='groupchat' from='argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.gov' to='8cdd0b2c36c83d7b715e746f36c218a6@jabber.mcs.anl.gov/default'><body>Fabrizio Berdondini has joined this session</body></message>
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}message> level=1
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:72 DEBUG start <{jabber:client}body> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}body> level=2
05/24/06 16:35:38 3636 bajjer.serialize     serialize.py:86 DEBUG end <{jabber:client}message> level=1
05/24/06 16:35:38 3636 JabberClient     jabberclient.py:133 DEBUG argonne-lobby(vv3.mcs.anl.gov)@conference.mcs.anl.go: Fabrizio Berdondini has joined this session
05/24/06 16:36:11 2556 VenueClient     venueclient.py:441 DEBUG Calling Heartbeat, time now: 1148481371
05/24/06 16:36:11 2556 VenueClient     venueclient.py:460 DEBUG Next Heartbeat needed before: 36
05/24/06 16:36:47 3548 VenueClient     venueclient.py:441 DEBUG Calling Heartbeat, time now: 1148481407
05/24/06 16:36:48 3548 VenueClient     venueclient.py:460 DEBUG Next Heartbeat needed before: 36
05/24/06 16:37:24 1932 VenueClient     venueclient.py:441 DEBUG Calling Heartbeat, time now: 1148481444
05/24/06 16:37:25 1932 VenueClient     venueclient.py:460 DEBUG Next Heartbeat needed before: 36
05/24/06 16:38:01 3304 VenueClient     venueclient.py:441 DEBUG Calling Heartbeat, time now: 1148481481
05/24/06 16:38:02 3304 VenueClient     venueclient.py:460 DEBUG Next Heartbeat needed before: 36
05/24/06 16:38:38 2584 VenueClient     venueclient.py:441 DEBUG Calling Heartbeat, time now: 1148481518
05/24/06 16:38:38 2584 VenueClient     venueclient.py:460 DEBUG Next Heartbeat needed before: 36
05/24/06 16:39:14 3372 VenueClient     venueclient.py:441 DEBUG Calling Heartbeat, time now: 1148481554
05/24/06 16:39:15 3372 VenueClient     venueclient.py:460 DEBUG Next Heartbeat needed before: 36
05/24/06 16:39:51 3800 VenueClient     venueclient.py:441 DEBUG Calling Heartbeat, time now: 1148481591
05/24/06 16:39:52 3800 VenueClient     venueclient.py:460 DEBUG Next Heartbeat needed before: 36
05/24/06 16:40:28 2564 VenueClient     venueclient.py:441 DEBUG Calling Heartbeat, time now: 1148481628
05/24/06 16:40:29 2564 VenueClient     venueclient.py:460 DEBUG Next Heartbeat needed before: 36
05/24/06 16:41:05 4020 VenueClient     venueclient.py:441 DEBUG Calling Heartbeat, time now: 1148481665
05/24/06 16:41:06 4020 VenueClient     venueclient.py:460 DEBUG Next Heartbeat needed before: 36
05/24/06 16:41:42 224 VenueClient     venueclient.py:441 DEBUG Calling Heartbeat, time now: 1148481702
05/24/06 16:41:42 224 VenueClient     venueclient.py:460 DEBUG Next Heartbeat needed before: 36