[AG-DEV] Custom Node Service Enabling/Disabling Problem
Thomas D. Uram
turam at mcs.anl.gov
Fri Aug 11 10:03:33 CDT 2006
That is an interesting mix of modules identified in the traceback.
Todd, does
that make sense to you in the context of the installations you've made on
that machine? I know that there may be AG3 code in site-packages/AccessGrid
with Doug Kosovic's Fedora packages, so maybe that explains their presence.
It does not, however, explain the _use_ of both of them in one execution.
The exception is likely due to the rate comparison in this code (the
first line of
which appears in the exception):
if (str(self.type) != str(capability.type) or
str(self.codec) != str(capability.codec) or
int(self.channels) != int(capability.channels) or
int(self.rate) != int(capability.rate)):
because:
- DebugService creates Capabilities, specifying only the type and role
attributes
- the Capability class uses a default of None for the 'rate' attribute
- Capability.matches casts rate to an int for comparison, which fails if
'rate' is None
I've changed the default value for Capability.rate from None to zero in
cvs now.
The problem can be avoided in current services by initializing the
'rate' attribute to zero.
Tom
On 8/11/06 4:00 AM, Michael Braitmaier wrote:
> Hi Todd!
>
> Looking more into your debug file I can see, that AccessGrid obviously
> uses AccessGrid code from different versions like in the following
> extract of the logfile:
>
> Traceback (most recent call last):
> File "/usr/lib/python2.4/site-packages/AccessGrid/AGNodeService.py",
> line 708, in __SendStreamsToService
> if c.matches(cap):
> File
> "/usr/lib/python2.4/site-packages/AccessGrid3/AccessGrid/Descriptions.py",
> line 314, in matches
> if (str(self.type) != str(capability.type) or
> TypeError: int() argument must be a string or a number
>
> The file AGNodeService.py seems to come from
> python2.4/site-packages/AccessGrid
> while the file Descriptions.py is coming from
> python2.4/site-packages/accessGrid3/AccessGrid
>
> Possibly there is a mix of AG2.4 and AG3.0 code?
>
> Michael
>
>
>
>
> Michael Braitmaier schrieb:
>
>> Hi Todd!
>>
>> In fact I think the problem narrows down to the follwing line in the
>> log:
>>
>> File
>> "/usr/lib/python2.4/site-packages/AccessGrid3/AccessGrid/Descriptions.py",
>> line 314, in matches
>> if (str(self.type) != str(capability.type) or
>>
>> TypeError: int() argument must be a string or a number
>>
>>
>> Apprently the two values are not compareable in some way.
>> I assume you use the modified code which I sent you.
>> The problem could possibly be introduced by my modifications.
>> It would be helpful to see what values are contained in
>> self.type and
>> capability.type
>>
>> Possibly one of the variables contain a "None"-value (however this
>> value got there), which would lead to the given error message.
>>
>> Refering to Brians assumption on the service not being able to handle
>> the stream:
>> In fact at the end the
>>
>> raise SetStreamException(failedSends)
>>
>> is just a propagation of the exception in the Capability's match
>> function.
>> So I don't think that this caused by the service not being able to
>> habndle the stream.
>>
>> Todd:
>> If you can tell me what values are contained in the variables, I can
>> help you find the problem and even update my sources eliminating the
>> bug.
>>
>> Michael
>>
>>
>> Todd Zimmerman schrieb:
>>
>>> Sorry for the spam - but just realized that it looks like this issue
>>> was identified and a fix was
>>> made by Michael Braitmaier.
>>>
>>> The problem was the default None values of rate and code in the
>>> Capabilities class. Michael made
>>> the default value of codec = "" and rate = 0 - which allows the
>>> match function to properly check for
>>> matches. This seems to alleviate the issue - but does it raise any
>>> other problems??
>>>
>>> Cheers,
>>>
>>> Todd
>>>
>>> Todd Zimmerman wrote:
>>>
>>>
>>>> one more time WITH the logfiles... ;-)
>>>>
>>>> Todd Zimmerman wrote:
>>>>
>>>>> Oh... I sent them to ag-dev yesterday...
>>>>>
>>>>> Here's a resend.
>>>>>
>>>>>
>>>>> Looks like the relevent section is:
>>>>>
>>>>>
>>>>> 08/09/2006 11:15:40 AM -1208600896 NodeManagementUIClasses
>>>>> NodeManagementUIClasses.py:1043 ERROR
>>>>> Error enabling service
>>>>> Traceback (most recent call last):
>>>>> File
>>>>> "/usr/lib/python2.4/site-packages/AccessGrid3/AccessGrid/NodeManagementUIClasses.py",
>>>>> line
>>>>> 1037, in EnableService
>>>>> self.nodeServiceHandle.SetServiceEnabled(service.uri, 1)
>>>>> File
>>>>> "/usr/lib/python2.4/site-packages/AccessGrid/AGNodeService.py",
>>>>> line 278, in SetServiceEnabled
>>>>> self.__SendStreamsToService( serviceUri )
>>>>> File
>>>>> "/usr/lib/python2.4/site-packages/AccessGrid/AGNodeService.py",
>>>>> line 723, in
>>>>> __SendStreamsToService
>>>>> raise SetStreamException(failedSends)
>>>>> SetStreamException: Error updating [producer, debug, None, None,
>>>>> None, 1, consumer, debug, None,
>>>>> None, None, 1]
>>>>>
>>>>>
>>>>>
>>>>> Cheers,
>>>>>
>>>>> Todd
>>>>>
>>>>> Thomas D. Uram wrote:
>>>>>
>>>>>> Any details from logfile?
>>>>>>
>>>>>>
>>>>>>
>>>>>> On 8/9/06 1:59 PM, Todd Zimmerman wrote:
>>>>>>
>>>>>>> Thomas D. Uram wrote:
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>> Also, which platform?
>>>>>>>>
>>>>>>>
>>>>>>> Fedora Core 5 running AccessGrid-3.0.2-0.1.20060727cvs
>>>>>>>
>>>>>>> Cheers,
>>>>>>>
>>>>>>>
>>>>>>> Todd
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>> ------------------------------------------------------------------------
>>>>>>>
>>>>>>>
>>>>>>> 08/09/06 11:15:07 -1208117568 Toolkit Config.py:559 DEBUG
>>>>>>> System hostname of floyd.irmacs.sfu.ca is valid
>>>>>>> 08/09/06 11:15:07 -1208117568 Toolkit Toolkit.py:111 DEBUG
>>>>>>> Initializing AG Toolkit version 3.0.2
>>>>>>> 08/09/06 11:15:07 -1208117568 Toolkit Toolkit.py:112 INFO
>>>>>>> Command and arguments:
>>>>>>> ['/data/tz/.AccessGrid3/local_services/DebugService/DebugService.py',
>>>>>>> '--port', '64864', '--serviceManagerUri',
>>>>>>> 'http://floyd.irmacs.sfu.ca:11000/ServiceManager', '--token',
>>>>>>> '8e3a358332f86536e976086dbec2c57f']
>>>>>>> 08/09/06 11:15:07 -1208117568 VenueClient Preferences.py:180
>>>>>>> DEBUG Preferences.LoadPreferences: open file
>>>>>>> 08/09/06 11:15:07 -1208117568 Toolkit Toolkit.py:161 INFO
>>>>>>> Logfile Name: DebugService.log
>>>>>>> 08/09/06 11:15:07 -1208117568 Toolkit Toolkit.py:650 INFO
>>>>>>> Service init: have profile None
>>>>>>> 08/09/06 11:15:07 -1208117568 Toolkit Toolkit.py:675 INFO
>>>>>>> Service Initialization Complete.
>>>>>>> 08/09/06 11:15:07 -1208117568 Toolkit AGService.py:363 INFO
>>>>>>> Starting Service URI:
>>>>>>> http://floyd.irmacs.sfu.ca:64864/Services/DebugService.8e3a3583333a6536e9540206adb5e731
>>>>>>>
>>>>>>> 08/09/06 11:15:07 -1208117568 Toolkit AGService.py:373 DEBUG
>>>>>>> Registering with service manager;
>>>>>>> url=http://floyd.irmacs.sfu.ca:11000/ServiceManager
>>>>>>> 08/09/06 11:15:07 -1225385056 Toolkit DebugService.py:68
>>>>>>> INFO SetIdentity method called
>>>>>>> 08/09/06 11:15:12 -1225385056 Toolkit AGService.py:214 INFO
>>>>>>> AGService.SetEnabled : enabled = 0
>>>>>>> 08/09/06 11:15:12 -1225385056 Toolkit AGService.py:220 INFO
>>>>>>> Stopping service
>>>>>>> 08/09/06 11:15:12 -1225385056 Toolkit DebugService.py:50
>>>>>>> INFO Stop method called
>>>>>>> 08/09/06 11:15:14 -1225385056 Toolkit AGService.py:214 INFO
>>>>>>> AGService.SetEnabled : enabled = 1
>>>>>>> 08/09/06 11:15:15 -1225385056 Toolkit AGService.py:214 INFO
>>>>>>> AGService.SetEnabled : enabled = 0
>>>>>>> 08/09/06 11:15:15 -1225385056 Toolkit AGService.py:220 INFO
>>>>>>> Stopping service
>>>>>>> 08/09/06 11:15:15 -1225385056 Toolkit DebugService.py:50
>>>>>>> INFO Stop method called
>>>>>>> 08/09/06 11:15:17 -1225385056 Toolkit AGService.py:214 INFO
>>>>>>> AGService.SetEnabled : enabled = 1
>>>>>>> 08/09/06 11:15:23 -1225385056 Toolkit DebugService.py:50
>>>>>>> INFO Stop method called
>>>>>>> 08/09/06 11:15:30 -1225385056 Toolkit AGService.py:214 INFO
>>>>>>> AGService.SetEnabled : enabled = 0
>>>>>>> 08/09/06 11:15:30 -1225385056 Toolkit AGService.py:220 INFO
>>>>>>> Stopping service
>>>>>>> 08/09/06 11:15:30 -1225385056 Toolkit DebugService.py:50
>>>>>>> INFO Stop method called
>>>>>>> 08/09/06 11:15:38 -1225385056 Toolkit AGService.py:214 INFO
>>>>>>> AGService.SetEnabled : enabled = 0
>>>>>>> 08/09/06 11:15:38 -1225385056 Toolkit AGService.py:220 INFO
>>>>>>> Stopping service
>>>>>>> 08/09/06 11:15:38 -1225385056 Toolkit DebugService.py:50
>>>>>>> INFO Stop method called
>>>>>>> 08/09/06 11:15:55 -1225385056 Toolkit DebugService.py:50
>>>>>>> INFO Stop method called
>>>>>>> 08/09/06 11:15:58 -1225385056 Toolkit AGService.py:238 INFO
>>>>>>> Shut service down
>>>>>>> 08/09/06 11:15:58 -1225385056 Toolkit DebugService.py:50
>>>>>>> INFO Stop method called
>>>>>>> 08/09/06 11:15:58 -1208117568 Toolkit AGService.py:238 INFO
>>>>>>> Shut service down
>>>>>>> 08/09/06 11:15:58 -1208117568 Toolkit DebugService.py:50
>>>>>>> INFO Stop method called
>>>>>>>
>>>>>>> ------------------------------------------------------------------------
>>>>>>>
>>>>>>>
>>>>>>> 08/09/2006 11:14:55 AM -1208600896 VenueClientController
>>>>>>> VenueClientController.py:432 DEBUG VenueClientController calling
>>>>>>> Venue.EnterVenue
>>>>>>> 08/09/2006 11:14:55 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:1015 DEBUG EnterVenue;
>>>>>>> url=https://venueserver2.westgrid.ca:8000/Venues/default
>>>>>>> 08/09/2006 11:14:55 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:658 INFO NodeService.GetCapabilities
>>>>>>> 08/09/2006 11:14:55 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:257 INFO NodeService.GetServices
>>>>>>> 08/09/2006 11:14:55 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:218 INFO AGServiceManager.GetServices
>>>>>>> 08/09/2006 11:14:55 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:1049 DEBUG calling __EnterVenue
>>>>>>> 08/09/2006 11:14:55 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:806 DEBUG EnterVenue: Invoke Venue.Enter
>>>>>>> 08/09/2006 11:14:55 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:808 DEBUG after Venue.Enter
>>>>>>> 08/09/2006 11:14:55 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:810 DEBUG EnterVenue: Invoke Venue.getstate
>>>>>>> 08/09/2006 11:14:56 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:812 DEBUG EnterVenue: done Venue.getstate
>>>>>>> 08/09/2006 11:14:57 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:835 DEBUG Setting isInVenue flag.
>>>>>>> 08/09/2006 11:14:57 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:1051 DEBUG after __EnterVenue
>>>>>>> 08/09/2006 11:14:57 AM -1208600896 VenueClient
>>>>>>> VenueClientUI.py:2577 DEBUG bin.VenueClient::EnterVenue: Enter
>>>>>>> venue with url:
>>>>>>> https://venueserver2.westgrid.ca:8000/Venues/default
>>>>>>> 08/09/2006 11:14:57 AM -1208600896 VenueClient
>>>>>>> VenueClientUI.py:2690 DEBUG Entered venue
>>>>>>> 08/09/2006 11:14:57 AM -1208600896 VenueClientController
>>>>>>> VenueClientController.py:434 DEBUG VenueClientController after
>>>>>>> Venue.EnterVenue
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 VenueClient
>>>>>>> VenueClient.py:450 DEBUG Calling Heartbeat, time now: 1155147297
>>>>>>> 08/09/2006 11:14:57 AM -1208600896 VenueClient
>>>>>>> VenueClientUI.py:3324 DEBUG ContentListPanel.AddParticipant::
>>>>>>> AddParticipant Todd Zimmerman (called from
>>>>>>> ('/usr/lib/python2.4/site-packages/wx-2.6-gtk2-unicode/wx/_core.py',
>>>>>>> 13535, '<lambda>', 'lambda event: event.callable(*event.args,
>>>>>>> **event.kw) )'))
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 VenueClient
>>>>>>> VenueClient.py:474 DEBUG Next Heartbeat needed within 36s
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 JabberClient
>>>>>>> JabberClient.py:33 INFO Connecting to Jabber Server
>>>>>>> 'jabber.mcs.anl.gov' ...
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.io io.py:100 DEBUG
>>>>>>> RECV: <?xml version='1.0'?><stream:stream
>>>>>>> xmlns:stream='http://etherx.jabber.org/streams' id='44DA2621'
>>>>>>> xmlns='jabber:client' from='jabber.mcs.anl.gov'>
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:65 DEBUG feed parser initialized
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start
>>>>>>> <{http://etherx.jabber.org/streams}stream> level=0
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 JabberClient
>>>>>>> JabberClient.py:168 INFO Registering the user
>>>>>>> '8180087163ff65364de2ad59cc6ba1e9 at jabber.mcs.anl.gov/default' in
>>>>>>> jabber server ...
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.io io.py:122 DEBUG
>>>>>>> SEND: <iq type="get"><query xmlns="jabber:iq:register" /></iq>
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 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>
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}iq> level=1
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:iq:register}query> level=2
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:iq:register}password> level=3
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:iq:register}password> level=3
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:iq:register}password> level=3
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:iq:register}password> level=3
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:iq:register}instructions>
>>>>>>> level=3
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:iq:register}instructions>
>>>>>>> level=3
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:iq:register}name> level=3
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:iq:register}name> level=3
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:iq:register}email> level=3
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:iq:register}email> level=3
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:iq:register}username> level=3
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:iq:register}username> level=3
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:iq:register}query> level=2
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}iq> level=1
>>>>>>> 08/09/2006 11:14:57 AM -1225692256 bajjer.io io.py:122 DEBUG
>>>>>>> SEND: <iq type="set"><query
>>>>>>> xmlns="jabber:iq:register"><username>8180087163ff65364de2ad59cc6ba1e9</username><password>8180087163ff65364de2ad59cc6ba1e9</password></query></iq>
>>>>>>>
>>>>>>> 08/09/2006 11:14:58 AM -1225692256 bajjer.io io.py:161 DEBUG
>>>>>>> RECV: <iq type='result'/>
>>>>>>> 08/09/2006 11:14:58 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}iq> level=1
>>>>>>> 08/09/2006 11:14:58 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}iq> level=1
>>>>>>> 08/09/2006 11:14:58 AM -1208600896 GroupMsgClient
>>>>>>> GroupMsgClient.py:112 INFO Connected. Connection ID:
>>>>>>> 8180087163ff65364de2ad59cc6ba1e9
>>>>>>> 08/09/2006 11:14:58 AM -1208600896 EventClient
>>>>>>> InsecureVenueEventClient.py:60 INFO BaseVenueEventClient made
>>>>>>> connection.
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 JabberClient
>>>>>>> JabberClient.py:177 INFO Attempting to log in as
>>>>>>> 8180087163ff65364de2ad59cc6ba1e9 at jabber.mcs.anl.gov/default ...
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.io io.py:122 DEBUG
>>>>>>> SEND: <iq type="get"><query
>>>>>>> xmlns="jabber:iq:auth"><username>8180087163ff65364de2ad59cc6ba1e9</username></query></iq>
>>>>>>>
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.io io.py:161 DEBUG
>>>>>>> RECV: <iq type='result'><query
>>>>>>> xmlns='jabber:iq:auth'><username>8180087163ff65364de2ad59cc6ba1e9</username><digest/><password/><resource/></query></iq>
>>>>>>>
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}iq> level=1
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:iq:auth}query> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:iq:auth}username> level=3
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:iq:auth}username> level=3
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:iq:auth}digest> level=3
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:iq:auth}digest> level=3
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:iq:auth}password> level=3
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:iq:auth}password> level=3
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:iq:auth}resource> level=3
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:iq:auth}resource> level=3
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:iq:auth}query> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}iq> level=1
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.stream
>>>>>>> stream.py:326 DEBUG Recv: <gov.lbl.dsd.bajjer.stanza.Iq object
>>>>>>> at 0xb46c2dcc>
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.stream
>>>>>>> stream.py:342 DEBUG performing 'digest' authentication
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.io io.py:122 DEBUG
>>>>>>> SEND: <iq type="set"><query
>>>>>>> xmlns="jabber:iq:auth"><resource>default</resource><username>8180087163ff65364de2ad59cc6ba1e9</username><digest>cf40bb3969d2778217e87b01c0948d0cf96ec019</digest></query></iq>
>>>>>>>
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.io io.py:161 DEBUG
>>>>>>> RECV: <iq type='result' id='pthsock_client_auth_ID'/>
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}iq> level=1
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}iq> level=1
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.stream
>>>>>>> stream.py:351 DEBUG Authorization succeeded
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.io io.py:122 DEBUG
>>>>>>> SEND: <iq from="8180087163ff65364de2ad59cc6ba1e9"
>>>>>>> type="get"><query xmlns="jabber:iq:roster" /></iq>
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.io io.py:161 DEBUG
>>>>>>> RECV: <iq
>>>>>>> from='8180087163ff65364de2ad59cc6ba1e9 at jabber.mcs.anl.gov/default'
>>>>>>> type='result'><query xmlns='jabber:iq:roster'/></iq>
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}iq> level=1
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:iq:roster}query> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:iq:roster}query> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}iq> level=1
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.io io.py:122 DEBUG
>>>>>>> SEND: <presence />
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 JabberClient
>>>>>>> JabberClient.py:73 DEBUG Sending the presence to
>>>>>>> 'Main-Lobby(venueserver2.westgrid.ca)@conference.mcs.anl.gov/Todd
>>>>>>> Zimmerman' of type 'available'...
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 bajjer.io io.py:122 DEBUG
>>>>>>> SEND: <presence
>>>>>>> to="Main-Lobby(venueserver2.westgrid.ca)@conference.mcs.anl.gov/Todd
>>>>>>> Zimmerman" type="available"><x /></presence>
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 VenueClient
>>>>>>> VenueClient.py:959 INFO VenueClient.StartBeacon: Address
>>>>>>> 233.59.192.88/49482
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 RTPBeacon
>>>>>>> RTPBeacon.py:278 DEBUG Called Start.
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 VenueClient
>>>>>>> VenueClient.py:1231 INFO In UpdateStream: transport=unicast
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.io io.py:161 DEBUG
>>>>>>> RECV: <message from='jabber.mcs.anl.gov'
>>>>>>> to='8180087163ff65364de2ad59cc6ba1e9 at jabber.mcs.anl.gov'>
>>>>>>> <subject>Welcome!</subject>
>>>>>>> <body>Welcome to the Access Grid Jabber server @
>>>>>>> jabber.mcs.anl.gov -- we hope you enjoy this service! For
>>>>>>> information about how to use Jabber, visit the Jabber
>>>>>>> User's Guide at http://jabbermanual.jabberstudio.org/</body>
>>>>>>> <x xmlns='jabber:x:delay'
>>>>>>> from='8180087163ff65364de2ad59cc6ba1e9 at jabber.mcs.anl.gov'
>>>>>>> stamp='20060809T18:14:57'>Offline Storage</x></message>
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}message> level=1
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}subject> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}subject> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}body> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}body> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:x:delay}x> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:x:delay}x> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}message> level=1
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 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/
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 VenueClient
>>>>>>> VenueClient.py:1260 DEBUG Got location from bridge: unicast
>>>>>>> milton.mcs.anl.gov 53768
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 VenueClient
>>>>>>> VenueClient.py:1231 INFO In UpdateStream: transport=unicast
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.io io.py:161 DEBUG
>>>>>>> RECV: <presence
>>>>>>> to='8180087163ff65364de2ad59cc6ba1e9 at jabber.mcs.anl.gov/default'
>>>>>>> from='main-lobby(venueserver2.westgrid.ca)@conference.mcs.anl.gov/Todd
>>>>>>> Zimmerman'><x/><x
>>>>>>> xmlns='http://jabber.org/protocol/muc#user'><item
>>>>>>> affiliation='none' role='participant'/></x></presence>
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}presence> level=1
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}x> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}x> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start
>>>>>>> <{http://jabber.org/protocol/muc#user}x> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start
>>>>>>> <{http://jabber.org/protocol/muc#user}item> level=3
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end
>>>>>>> <{http://jabber.org/protocol/muc#user}item> level=3
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end
>>>>>>> <{http://jabber.org/protocol/muc#user}x> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}presence> level=1
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 JabberClient
>>>>>>> JabberClient.py:145 DEBUG
>>>>>>> main-lobby(venueserver2.westgrid.ca)@conference.mcs.anl.gov/Todd
>>>>>>> Zimmerman is available (None / None)
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 VenueClient
>>>>>>> VenueClientUI.py:2299 DEBUG add user: Todd Zimmerman
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.io io.py:161 DEBUG
>>>>>>> RECV: <message type='groupchat'
>>>>>>> to='8180087163ff65364de2ad59cc6ba1e9 at jabber.mcs.anl.gov/default'
>>>>>>> from='main-lobby(venueserver2.westgrid.ca)@conference.mcs.anl.gov'><body>main-lobby(venueserver2.westgrid.ca)</body></message>
>>>>>>>
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}message> level=1
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}body> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}body> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}message> level=1
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 JabberClient
>>>>>>> JabberClient.py:133 DEBUG
>>>>>>> main-lobby(venueserver2.westgrid.ca)@conference.mcs.anl.go:
>>>>>>> main-lobby(venueserver2.westgrid.ca)
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.io io.py:161 DEBUG
>>>>>>> RECV: <message type='groupchat'
>>>>>>> to='8180087163ff65364de2ad59cc6ba1e9 at jabber.mcs.anl.gov/default'
>>>>>>> from='main-lobby(venueserver2.westgrid.ca)@conference.mcs.anl.gov'><body>This
>>>>>>> room supports the MUC protocol.</body></message>
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}message> level=1
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}body> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}body> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}message> level=1
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 VenueClient
>>>>>>> VenueClient.py:1260 DEBUG Got location from bridge: unicast
>>>>>>> milton.mcs.anl.gov 52884
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 VenueClient
>>>>>>> VenueClient.py:1231 INFO In UpdateStream: transport=unicast
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.io io.py:161 DEBUG
>>>>>>> RECV: <message type='groupchat'
>>>>>>> from='main-lobby(venueserver2.westgrid.ca)@conference.mcs.anl.gov'
>>>>>>> to='8180087163ff65364de2ad59cc6ba1e9 at jabber.mcs.anl.gov/default'><body>Todd
>>>>>>> Zimmerman has joined this session</body></message>
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}message> level=1
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}body> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}body> level=2
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}message> level=1
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 JabberClient
>>>>>>> JabberClient.py:133 DEBUG
>>>>>>> main-lobby(venueserver2.westgrid.ca)@conference.mcs.anl.go: This
>>>>>>> room supports the MUC protocol.
>>>>>>> 08/09/2006 11:14:59 AM -1345344608 JabberClient
>>>>>>> JabberClient.py:133 DEBUG
>>>>>>> main-lobby(venueserver2.westgrid.ca)@conference.mcs.anl.go: Todd
>>>>>>> Zimmerman has joined this session
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 VenueClient
>>>>>>> VenueClient.py:1260 DEBUG Got location from bridge: unicast
>>>>>>> milton.mcs.anl.gov 52844
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 RTPBeacon
>>>>>>> RTPBeacon.py:296 DEBUG Called Stop.
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 VenueClient
>>>>>>> VenueClient.py:951 INFO Beacon being reconfigured, stopping
>>>>>>> running beacon
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 RTPBeacon
>>>>>>> RTPBeacon.py:296 DEBUG Called Stop.
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 VenueClient
>>>>>>> VenueClient.py:959 INFO VenueClient.StartBeacon: Address
>>>>>>> milton.mcs.anl.gov/52844
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 RTPBeacon
>>>>>>> RTPBeacon.py:278 DEBUG Called Start.
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 VenueClient
>>>>>>> VenueClient.py:1190 DEBUG UpdateNodeService: Method
>>>>>>> UpdateNodeService called
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 VenueClient
>>>>>>> VenueClient.py:1195 DEBUG Setting node service streams
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 NodeService
>>>>>>> AGNodeService.py:328 INFO NodeService.SetStreams
>>>>>>> 08/09/2006 11:14:59 AM -1225692256 NodeService
>>>>>>> AGNodeService.py:257 INFO NodeService.GetServices
>>>>>>> 08/09/2006 11:14:59 AM -1387304032 ServiceManager
>>>>>>> AGServiceManager.py:218 INFO AGServiceManager.GetServices
>>>>>>> 08/09/2006 11:15:00 AM -1225692256 NodeService
>>>>>>> AGNodeService.py:695 INFO NodeService.__SendStreamsToService
>>>>>>> 08/09/2006 11:15:00 AM -1366324320 RTPBeacon
>>>>>>> RTPBeacon.py:289 INFO RTPBeacon.UpdateThread exiting
>>>>>>> 08/09/2006 11:15:00 AM -1225692256 NodeService
>>>>>>> AGNodeService.py:699 DEBUG service capabilities: [consumer,
>>>>>>> audio, 8e3a358333096536dec30af9596ae5ae, L16, 16000, 1,
>>>>>>> consumer, audio, 8e3a358333096536dec30af9596ae5ae, L16, 8000, 1,
>>>>>>> consumer, audio, 8e3a358333096536dec30af9596ae5ae, L8, 16000, 1,
>>>>>>> consumer, audio, 8e3a358333096536dec30af9596ae5ae, L8, 8000, 1,
>>>>>>> consumer, audio, 8e3a358333096536dec30af9596ae5ae, PCMU, 16000,
>>>>>>> 1, consumer, audio, 8e3a358333096536dec30af9596ae5ae, PCMU,
>>>>>>> 8000, 1, consumer, audio, 8e3a358333096536dec30af9596ae5ae, GSM,
>>>>>>> 16000, 1, consumer, audio, 8e3a358333096536dec30af9596ae5ae,
>>>>>>> GSM, 8000, 1, producer, audio, 8e3a358333096536dec30af9596ae5ae,
>>>>>>> L16, 16000, 1]
>>>>>>> 08/09/2006 11:15:00 AM -1225692256 NodeService
>>>>>>> AGNodeService.py:713 INFO Sending stream (type=[consumer,
>>>>>>> audio, 8180087163ff629be0bebd48a1912f0f, L16, 16000, 1,
>>>>>>> consumer, audio, 8180087163ff629be0bebd48a1912f0f, L16, 8000, 1,
>>>>>>> consumer, audio, 8180087163ff629be0bebd48a1912f0f, L8, 16000, 1,
>>>>>>> consumer, audio, 8180087163ff629be0bebd48a1912f0f, L8, 8000, 1,
>>>>>>> consumer, audio, 8180087163ff629be0bebd48a1912f0f, PCMU, 16000,
>>>>>>> 1, consumer, audio, 8180087163ff629be0bebd48a1912f0f, PCMU,
>>>>>>> 8000, 1, consumer, audio, 8180087163ff629be0bebd48a1912f0f, GSM,
>>>>>>> 16000, 1, consumer, audio, 8180087163ff629be0bebd48a1912f0f,
>>>>>>> GSM, 8000, 1]) to service:
>>>>>>> http://floyd.irmacs.sfu.ca:56488/Services/AudioService.8e3a358333096536decfdc29e8e7216d
>>>>>>>
>>>>>>> 08/09/2006 11:15:00 AM -1225692256 NodeService
>>>>>>> AGNodeService.py:695 INFO NodeService.__SendStreamsToService
>>>>>>> 08/09/2006 11:15:00 AM -1225692256 NodeService
>>>>>>> AGNodeService.py:699 DEBUG service capabilities: [consumer,
>>>>>>> video, 8e3a358333036536de76e51f5b703c18, H261, 90000, 1,
>>>>>>> producer, video, 8e3a358333036536de76e51f5b703c18, H261, 90000, 1]
>>>>>>> 08/09/2006 11:15:00 AM -1225692256 NodeService
>>>>>>> AGNodeService.py:713 INFO Sending stream (type=[consumer,
>>>>>>> video, 8180087163ff629be0bbbd489dcbd809, H261, 90000, 1]) to
>>>>>>> service:
>>>>>>> http://floyd.irmacs.sfu.ca:62272/Services/VideoService.8e3a358333036536de95b64fe81b7c08
>>>>>>>
>>>>>>> 08/09/2006 11:15:00 AM -1225692256 VenueClient
>>>>>>> VenueClient.py:914 DEBUG Updating client profile cache.
>>>>>>> 08/09/2006 11:15:00 AM -1208600896 VenueClient
>>>>>>> VenueClientUI.py:1291 DEBUG VenueClientFrame.ManageNodeCB: open
>>>>>>> node management
>>>>>>> 08/09/2006 11:15:00 AM -1208600896 VenueClient
>>>>>>> VenueClientUI.py:1292 DEBUG nodeservice url:
>>>>>>> <AccessGrid.AGNodeService.AGNodeService instance at 0xb6f3518c>
>>>>>>> 08/09/2006 11:15:00 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:248 INFO NodeService.GetServiceManagers
>>>>>>> 08/09/2006 11:15:00 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:218 INFO AGServiceManager.GetServices
>>>>>>> 08/09/2006 11:15:07 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:102 INFO AGServiceManager.AddService
>>>>>>> 08/09/2006 11:15:07 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:339 INFO Installed service version: 1.1,
>>>>>>> package version: 1.1
>>>>>>> 08/09/2006 11:15:07 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:374 DEBUG Executing service DebugService
>>>>>>> 08/09/2006 11:15:07 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:406 INFO Running Service; options:
>>>>>>> ['/data/tz/.AccessGrid3/local_services/DebugService/DebugService.py',
>>>>>>> '--port', 64864, '--serviceManagerUri',
>>>>>>> 'http://floyd.irmacs.sfu.ca:11000/ServiceManager', '--token',
>>>>>>> '8e3a358332f86536e976086dbec2c57f']
>>>>>>> 08/09/2006 11:15:07 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:418 INFO Service registered:
>>>>>>> http://floyd.irmacs.sfu.ca:64864/Services/DebugService.8e3a3583333a6536e9540206adb5e731
>>>>>>> 8e3a358332f86536e976086dbec2c57f
>>>>>>> 08/09/2006 11:15:07 AM -1208600896 NodeManagementUIClasses
>>>>>>> NodeManagementUIClasses.py:1000 INFO No resources for service
>>>>>>> 08/09/2006 11:15:07 AM -1208600896 VenueClient
>>>>>>> VenueClientUI.py:1933 INFO OnNodeActivity: got action: add_service
>>>>>>> 08/09/2006 11:15:07 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:248 INFO NodeService.GetServiceManagers
>>>>>>> 08/09/2006 11:15:07 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:218 INFO AGServiceManager.GetServices
>>>>>>> 08/09/2006 11:15:12 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:275 INFO NodeService.SetServiceEnabled
>>>>>>> 08/09/2006 11:15:12 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:248 INFO NodeService.GetServiceManagers
>>>>>>> 08/09/2006 11:15:12 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:218 INFO AGServiceManager.GetServices
>>>>>>> 08/09/2006 11:15:14 AM -1208600896 NodeManagementUIClasses
>>>>>>> NodeManagementUIClasses.py:1036 DEBUG
>>>>>>> NodeManagementClientFrame.EnableService: Enabling Service:
>>>>>>> DebugService
>>>>>>> 08/09/2006 11:15:14 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:275 INFO NodeService.SetServiceEnabled
>>>>>>> 08/09/2006 11:15:14 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:695 INFO NodeService.__SendStreamsToService
>>>>>>> 08/09/2006 11:15:14 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:699 DEBUG service capabilities: [producer,
>>>>>>> debug, None, None, None, 1, consumer, debug, None, None, None, 1]
>>>>>>> 08/09/2006 11:15:14 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:248 INFO NodeService.GetServiceManagers
>>>>>>> 08/09/2006 11:15:14 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:218 INFO AGServiceManager.GetServices
>>>>>>> 08/09/2006 11:15:15 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:275 INFO NodeService.SetServiceEnabled
>>>>>>> 08/09/2006 11:15:15 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:248 INFO NodeService.GetServiceManagers
>>>>>>> 08/09/2006 11:15:15 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:218 INFO AGServiceManager.GetServices
>>>>>>> 08/09/2006 11:15:16 AM -1208600896 NodeManagementUIClasses
>>>>>>> NodeManagementUIClasses.py:1036 DEBUG
>>>>>>> NodeManagementClientFrame.EnableService: Enabling Service:
>>>>>>> DebugService
>>>>>>> 08/09/2006 11:15:17 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:275 INFO NodeService.SetServiceEnabled
>>>>>>> 08/09/2006 11:15:17 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:695 INFO NodeService.__SendStreamsToService
>>>>>>> 08/09/2006 11:15:17 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:699 DEBUG service capabilities: [producer,
>>>>>>> debug, None, None, None, 1, consumer, debug, None, None, None, 1]
>>>>>>> 08/09/2006 11:15:17 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:248 INFO NodeService.GetServiceManagers
>>>>>>> 08/09/2006 11:15:17 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:218 INFO AGServiceManager.GetServices
>>>>>>> 08/09/2006 11:15:23 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:1132 INFO ExitVenue
>>>>>>> 08/09/2006 11:15:23 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:1173 INFO ExitVenue: Stopping node services
>>>>>>> 08/09/2006 11:15:23 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:301 INFO NodeService.StopServices
>>>>>>> 08/09/2006 11:15:23 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:225 INFO AGServiceManager.StopServices
>>>>>>> 08/09/2006 11:15:24 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:328 INFO NodeService.SetStreams
>>>>>>> 08/09/2006 11:15:24 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:257 INFO NodeService.GetServices
>>>>>>> 08/09/2006 11:15:24 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:218 INFO AGServiceManager.GetServices
>>>>>>> 08/09/2006 11:15:24 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:695 INFO NodeService.__SendStreamsToService
>>>>>>> 08/09/2006 11:15:24 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:699 DEBUG service capabilities: [consumer,
>>>>>>> audio, 8e3a358333096536dec30af9596ae5ae, L16, 16000, 1,
>>>>>>> consumer, audio, 8e3a358333096536dec30af9596ae5ae, L16, 8000, 1,
>>>>>>> consumer, audio, 8e3a358333096536dec30af9596ae5ae, L8, 16000, 1,
>>>>>>> consumer, audio, 8e3a358333096536dec30af9596ae5ae, L8, 8000, 1,
>>>>>>> consumer, audio, 8e3a358333096536dec30af9596ae5ae, PCMU, 16000,
>>>>>>> 1, consumer, audio, 8e3a358333096536dec30af9596ae5ae, PCMU,
>>>>>>> 8000, 1, consumer, audio, 8e3a358333096536dec30af9596ae5ae, GSM,
>>>>>>> 16000, 1, consumer, audio, 8e3a358333096536dec30af9596ae5ae,
>>>>>>> GSM, 8000, 1, producer, audio, 8e3a358333096536dec30af9596ae5ae,
>>>>>>> L16, 16000, 1]
>>>>>>> 08/09/2006 11:15:24 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:695 INFO NodeService.__SendStreamsToService
>>>>>>> 08/09/2006 11:15:24 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:699 DEBUG service capabilities: [producer,
>>>>>>> debug, None, None, None, 1, consumer, debug, None, None, None, 1]
>>>>>>> 08/09/2006 11:15:24 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:695 INFO NodeService.__SendStreamsToService
>>>>>>> 08/09/2006 11:15:24 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:699 DEBUG service capabilities: [consumer,
>>>>>>> video, 8e3a358333036536de76e51f5b703c18, H261, 90000, 1,
>>>>>>> producer, video, 8e3a358333036536de76e51f5b703c18, H261, 90000, 1]
>>>>>>> 08/09/2006 11:15:25 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:1103 DEBUG ExitVenue: Stop event client obj
>>>>>>> 08/09/2006 11:15:25 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:1105 DEBUG ExitVenue: Remove event client reference
>>>>>>> 08/09/2006 11:15:25 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:1110 INFO ExitVenue: Stopping text client
>>>>>>> 08/09/2006 11:15:25 AM -1208600896 JabberClient
>>>>>>> JabberClient.py:73 DEBUG Sending the presence to
>>>>>>> 'Main-Lobby(venueserver2.westgrid.ca)@conference.mcs.anl.gov/Todd
>>>>>>> Zimmerman' of type 'unavailable'...
>>>>>>> 08/09/2006 11:15:25 AM -1208600896 bajjer.io io.py:122 DEBUG
>>>>>>> SEND: <presence
>>>>>>> to="Main-Lobby(venueserver2.westgrid.ca)@conference.mcs.anl.gov/Todd
>>>>>>> Zimmerman" type="unavailable"><x /></presence>
>>>>>>> 08/09/2006 11:15:25 AM -1208600896 RTPBeacon
>>>>>>> RTPBeacon.py:296 DEBUG Called Stop.
>>>>>>> 08/09/2006 11:15:25 AM -1208600896 VenueClientController
>>>>>>> VenueClientController.py:432 DEBUG VenueClientController calling
>>>>>>> Venue.EnterVenue
>>>>>>> 08/09/2006 11:15:25 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:1015 DEBUG EnterVenue;
>>>>>>> url=https://venueserver2.westgrid.ca:8000/Venues/8e3a35534ae01a166229939b5aed6d47
>>>>>>>
>>>>>>> 08/09/2006 11:15:25 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:658 INFO NodeService.GetCapabilities
>>>>>>> 08/09/2006 11:15:25 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:257 INFO NodeService.GetServices
>>>>>>> 08/09/2006 11:15:25 AM -1334854752 ServiceManager
>>>>>>> AGServiceManager.py:218 INFO AGServiceManager.GetServices
>>>>>>> 08/09/2006 11:15:25 AM -1345344608 bajjer.io io.py:161 DEBUG
>>>>>>> RECV: <presence
>>>>>>> to='8180087163ff65364de2ad59cc6ba1e9 at jabber.mcs.anl.gov/default'
>>>>>>> type='unavailable'
>>>>>>> from='main-lobby(venueserver2.westgrid.ca)@conference.mcs.anl.gov/Todd
>>>>>>> Zimmerman'><x/><x
>>>>>>> xmlns='http://jabber.org/protocol/muc#user'><item
>>>>>>> affiliation='none' role='none'/></x></presence>
>>>>>>> 08/09/2006 11:15:25 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}presence> level=1
>>>>>>> 08/09/2006 11:15:25 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}x> level=2
>>>>>>> 08/09/2006 11:15:25 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}x> level=2
>>>>>>> 08/09/2006 11:15:25 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start
>>>>>>> <{http://jabber.org/protocol/muc#user}x> level=2
>>>>>>> 08/09/2006 11:15:25 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start
>>>>>>> <{http://jabber.org/protocol/muc#user}item> level=3
>>>>>>> 08/09/2006 11:15:25 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end
>>>>>>> <{http://jabber.org/protocol/muc#user}item> level=3
>>>>>>> 08/09/2006 11:15:25 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end
>>>>>>> <{http://jabber.org/protocol/muc#user}x> level=2
>>>>>>> 08/09/2006 11:15:25 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}presence> level=1
>>>>>>> 08/09/2006 11:15:25 AM -1345344608 JabberClient
>>>>>>> JabberClient.py:148 DEBUG
>>>>>>> main-lobby(venueserver2.westgrid.ca)@conference.mcs.anl.gov/Todd
>>>>>>> Zimmerman is unavailable (None / None)
>>>>>>> 08/09/2006 11:15:25 AM -1345344608 VenueClient
>>>>>>> VenueClientUI.py:2313 DEBUG remove user: Todd Zimmerman
>>>>>>> 08/09/2006 11:15:25 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:1049 DEBUG calling __EnterVenue
>>>>>>> 08/09/2006 11:15:25 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:806 DEBUG EnterVenue: Invoke Venue.Enter
>>>>>>> 08/09/2006 11:15:25 AM -1376814176 RTPBeacon
>>>>>>> RTPBeacon.py:289 INFO RTPBeacon.UpdateThread exiting
>>>>>>> 08/09/2006 11:15:25 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:808 DEBUG after Venue.Enter
>>>>>>> 08/09/2006 11:15:25 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:810 DEBUG EnterVenue: Invoke Venue.getstate
>>>>>>> 08/09/2006 11:15:26 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:812 DEBUG EnterVenue: done Venue.getstate
>>>>>>> 08/09/2006 11:15:26 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:835 DEBUG Setting isInVenue flag.
>>>>>>> 08/09/2006 11:15:26 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:1051 DEBUG after __EnterVenue
>>>>>>> 08/09/2006 11:15:26 AM -1208600896 VenueClient
>>>>>>> VenueClientUI.py:2577 DEBUG bin.VenueClient::EnterVenue: Enter
>>>>>>> venue with url:
>>>>>>> https://venueserver2.westgrid.ca:8000/Venues/8e3a35534ae01a166229939b5aed6d47
>>>>>>>
>>>>>>> 08/09/2006 11:15:26 AM -1208600896 VenueClient
>>>>>>> VenueClientUI.py:2690 DEBUG Entered venue
>>>>>>> 08/09/2006 11:15:26 AM -1208600896 VenueClientController
>>>>>>> VenueClientController.py:434 DEBUG VenueClientController after
>>>>>>> Venue.EnterVenue
>>>>>>> 08/09/2006 11:15:26 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:450 DEBUG Calling Heartbeat, time now: 1155147326
>>>>>>> 08/09/2006 11:15:26 AM -1208600896 VenueClient
>>>>>>> VenueClientUI.py:3340 DEBUG ContentListPanel.RemoveParticipant:
>>>>>>> Remove participant
>>>>>>> 08/09/2006 11:15:26 AM -1208600896 VenueClient
>>>>>>> VenueClientUI.py:3324 DEBUG ContentListPanel.AddParticipant::
>>>>>>> AddParticipant Todd Zimmerman (called from
>>>>>>> ('/usr/lib/python2.4/site-packages/wx-2.6-gtk2-unicode/wx/_core.py',
>>>>>>> 13535, '<lambda>', 'lambda event: event.callable(*event.args,
>>>>>>> **event.kw) )'))
>>>>>>> 08/09/2006 11:15:27 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:474 DEBUG Next Heartbeat needed within 36s
>>>>>>> 08/09/2006 11:15:27 AM -1376814176 JabberClient
>>>>>>> JabberClient.py:73 DEBUG Sending the presence to
>>>>>>> 'AG-Tech-Room(venueserver2.westgrid.ca)@conference.mcs.anl.gov/Todd
>>>>>>> Zimmerman' of type 'available'...
>>>>>>> 08/09/2006 11:15:27 AM -1376814176 bajjer.io io.py:122 DEBUG
>>>>>>> SEND: <presence
>>>>>>> to="AG-Tech-Room(venueserver2.westgrid.ca)@conference.mcs.anl.gov/Todd
>>>>>>> Zimmerman" type="available"><x /></presence>
>>>>>>> 08/09/2006 11:15:27 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:951 INFO Beacon being reconfigured, stopping
>>>>>>> running beacon
>>>>>>> 08/09/2006 11:15:27 AM -1376814176 RTPBeacon
>>>>>>> RTPBeacon.py:296 DEBUG Called Stop.
>>>>>>> 08/09/2006 11:15:27 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:959 INFO VenueClient.StartBeacon: Address
>>>>>>> 233.59.192.114/63534
>>>>>>> 08/09/2006 11:15:27 AM -1376814176 RTPBeacon
>>>>>>> RTPBeacon.py:278 DEBUG Called Start.
>>>>>>> 08/09/2006 11:15:27 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:1231 INFO In UpdateStream: transport=unicast
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.io io.py:161 DEBUG
>>>>>>> RECV: <presence
>>>>>>> to='8180087163ff65364de2ad59cc6ba1e9 at jabber.mcs.anl.gov/default'
>>>>>>> from='ag-tech-room(venueserver2.westgrid.ca)@conference.mcs.anl.gov/Todd
>>>>>>> Zimmerman'><x/><x
>>>>>>> xmlns='http://jabber.org/protocol/muc#user'><item
>>>>>>> affiliation='none' role='participant'/></x></presence>
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}presence> level=1
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}x> level=2
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}x> level=2
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start
>>>>>>> <{http://jabber.org/protocol/muc#user}x> level=2
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start
>>>>>>> <{http://jabber.org/protocol/muc#user}item> level=3
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end
>>>>>>> <{http://jabber.org/protocol/muc#user}item> level=3
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end
>>>>>>> <{http://jabber.org/protocol/muc#user}x> level=2
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}presence> level=1
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 JabberClient
>>>>>>> JabberClient.py:145 DEBUG
>>>>>>> ag-tech-room(venueserver2.westgrid.ca)@conference.mcs.anl.gov/Todd
>>>>>>> Zimmerman is available (None / None)
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 VenueClient
>>>>>>> VenueClientUI.py:2299 DEBUG add user: Todd Zimmerman
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.io io.py:161 DEBUG
>>>>>>> RECV: <message type='groupchat'
>>>>>>> to='8180087163ff65364de2ad59cc6ba1e9 at jabber.mcs.anl.gov/default'
>>>>>>> from='ag-tech-room(venueserver2.westgrid.ca)@conference.mcs.anl.gov'><body>ag-tech-room(venueserver2.westgrid.ca)</body></message>
>>>>>>>
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}message> level=1
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}body> level=2
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}body> level=2
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}message> level=1
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 JabberClient
>>>>>>> JabberClient.py:133 DEBUG
>>>>>>> ag-tech-room(venueserver2.westgrid.ca)@conference.mcs.anl.go:
>>>>>>> ag-tech-room(venueserver2.westgrid.ca)
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.io io.py:161 DEBUG
>>>>>>> RECV: <message type='groupchat'
>>>>>>> to='8180087163ff65364de2ad59cc6ba1e9 at jabber.mcs.anl.gov/default'
>>>>>>> from='ag-tech-room(venueserver2.westgrid.ca)@conference.mcs.anl.gov'><body>This
>>>>>>> room supports the MUC protocol.</body></message>
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}message> level=1
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}body> level=2
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}body> level=2
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}message> level=1
>>>>>>> 08/09/2006 11:15:27 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:1260 DEBUG Got location from bridge: unicast
>>>>>>> milton.mcs.anl.gov 53368
>>>>>>> 08/09/2006 11:15:27 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:1231 INFO In UpdateStream: transport=unicast
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.io io.py:161 DEBUG
>>>>>>> RECV: <message type='groupchat'
>>>>>>> from='ag-tech-room(venueserver2.westgrid.ca)@conference.mcs.anl.gov'
>>>>>>> to='8180087163ff65364de2ad59cc6ba1e9 at jabber.mcs.anl.gov/default'><body>Todd
>>>>>>> Zimmerman has joined this session</body></message>
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}message> level=1
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}body> level=2
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}body> level=2
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}message> level=1
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 JabberClient
>>>>>>> JabberClient.py:133 DEBUG
>>>>>>> ag-tech-room(venueserver2.westgrid.ca)@conference.mcs.anl.go:
>>>>>>> This room supports the MUC protocol.
>>>>>>> 08/09/2006 11:15:27 AM -1208600896 EventClient
>>>>>>> InsecureVenueEventClient.py:77 INFO BaseVenueEventClient lost
>>>>>>> connection.
>>>>>>> 08/09/2006 11:15:27 AM -1345344608 JabberClient
>>>>>>> JabberClient.py:133 DEBUG
>>>>>>> ag-tech-room(venueserver2.westgrid.ca)@conference.mcs.anl.go:
>>>>>>> Todd Zimmerman has joined this session
>>>>>>> 08/09/2006 11:15:27 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:1260 DEBUG Got location from bridge: unicast
>>>>>>> milton.mcs.anl.gov 53100
>>>>>>> 08/09/2006 11:15:27 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:1231 INFO In UpdateStream: transport=unicast
>>>>>>> 08/09/2006 11:15:27 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:1260 DEBUG Got location from bridge: unicast
>>>>>>> milton.mcs.anl.gov 53004
>>>>>>> 08/09/2006 11:15:27 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:1231 INFO In UpdateStream: transport=unicast
>>>>>>> 08/09/2006 11:15:27 AM -1208600896 GroupMsgClient
>>>>>>> GroupMsgClient.py:112 INFO Connected. Connection ID:
>>>>>>> 8180087163ff6536598e606758d7f94f
>>>>>>> 08/09/2006 11:15:27 AM -1208600896 EventClient
>>>>>>> InsecureVenueEventClient.py:60 INFO BaseVenueEventClient made
>>>>>>> connection.
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:1260 DEBUG Got location from bridge: unicast
>>>>>>> milton.mcs.anl.gov 52582
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 RTPBeacon
>>>>>>> RTPBeacon.py:296 DEBUG Called Stop.
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:951 INFO Beacon being reconfigured, stopping
>>>>>>> running beacon
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 RTPBeacon
>>>>>>> RTPBeacon.py:296 DEBUG Called Stop.
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:959 INFO VenueClient.StartBeacon: Address
>>>>>>> milton.mcs.anl.gov/52582
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 RTPBeacon
>>>>>>> RTPBeacon.py:278 DEBUG Called Start.
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:1190 DEBUG UpdateNodeService: Method
>>>>>>> UpdateNodeService called
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:1195 DEBUG Setting node service streams
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 NodeService
>>>>>>> AGNodeService.py:328 INFO NodeService.SetStreams
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 NodeService
>>>>>>> AGNodeService.py:257 INFO NodeService.GetServices
>>>>>>> 08/09/2006 11:15:28 AM -1398592608 ServiceManager
>>>>>>> AGServiceManager.py:218 INFO AGServiceManager.GetServices
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 NodeService
>>>>>>> AGNodeService.py:695 INFO NodeService.__SendStreamsToService
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 NodeService
>>>>>>> AGNodeService.py:699 DEBUG service capabilities: [consumer,
>>>>>>> audio, 8e3a358333096536dec30af9596ae5ae, L16, 16000, 1,
>>>>>>> consumer, audio, 8e3a358333096536dec30af9596ae5ae, L16, 8000, 1,
>>>>>>> consumer, audio, 8e3a358333096536dec30af9596ae5ae, L8, 16000, 1,
>>>>>>> consumer, audio, 8e3a358333096536dec30af9596ae5ae, L8, 8000, 1,
>>>>>>> consumer, audio, 8e3a358333096536dec30af9596ae5ae, PCMU, 16000,
>>>>>>> 1, consumer, audio, 8e3a358333096536dec30af9596ae5ae, PCMU,
>>>>>>> 8000, 1, consumer, audio, 8e3a358333096536dec30af9596ae5ae, GSM,
>>>>>>> 16000, 1, consumer, audio, 8e3a358333096536dec30af9596ae5ae,
>>>>>>> GSM, 8000, 1, producer, audio, 8e3a358333096536dec30af9596ae5ae,
>>>>>>> L16, 16000, 1]
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 NodeService
>>>>>>> AGNodeService.py:713 INFO Sending stream (type=[consumer,
>>>>>>> audio, 8180087163ff629be074b224aad547b3, L16, 16000, 1,
>>>>>>> consumer, audio, 8180087163ff629be074b224aad547b3, L16, 8000, 1,
>>>>>>> consumer, audio, 8180087163ff629be074b224aad547b3, L8, 16000, 1,
>>>>>>> consumer, audio, 8180087163ff629be074b224aad547b3, L8, 8000, 1,
>>>>>>> consumer, audio, 8180087163ff629be074b224aad547b3, PCMU, 16000,
>>>>>>> 1, consumer, audio, 8180087163ff629be074b224aad547b3, PCMU,
>>>>>>> 8000, 1, consumer, audio, 8180087163ff629be074b224aad547b3, GSM,
>>>>>>> 16000, 1, consumer, audio, 8180087163ff629be074b224aad547b3,
>>>>>>> GSM, 8000, 1]) to service:
>>>>>>> http://floyd.irmacs.sfu.ca:56488/Services/AudioService.8e3a358333096536decfdc29e8e7216d
>>>>>>>
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 NodeService
>>>>>>> AGNodeService.py:695 INFO NodeService.__SendStreamsToService
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 NodeService
>>>>>>> AGNodeService.py:699 DEBUG service capabilities: [producer,
>>>>>>> debug, None, None, None, 1, consumer, debug, None, None, None, 1]
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 NodeService
>>>>>>> AGNodeService.py:718 ERROR Exception in
>>>>>>> AGNodeService.__SendStreamsToService.
>>>>>>> Traceback (most recent call last):
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid/AGNodeService.py",
>>>>>>> line 708, in __SendStreamsToService
>>>>>>> if c.matches(cap):
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid3/AccessGrid/Descriptions.py",
>>>>>>> line 314, in matches
>>>>>>> if (str(self.type) != str(capability.type) or
>>>>>>> TypeError: int() argument must be a string or a number
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:1199 ERROR Error setting streams
>>>>>>> Traceback (most recent call last):
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid3/AccessGrid/VenueClient.py",
>>>>>>> line 1197, in UpdateNodeService
>>>>>>> self.nodeService.SetStreams( self.streamDescList )
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid/AGNodeService.py",
>>>>>>> line 340, in SetStreams
>>>>>>> self.__SendStreamsToService( service.uri )
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid/AGNodeService.py",
>>>>>>> line 723, in __SendStreamsToService
>>>>>>> raise SetStreamException(failedSends)
>>>>>>> SetStreamException: Error updating [producer, debug, None, None,
>>>>>>> None, 1, consumer, debug, None, None, None, 1]
>>>>>>>
>>>>>>> 08/09/2006 11:15:28 AM -1376814176 VenueClient
>>>>>>> VenueClient.py:914 DEBUG Updating client profile cache.
>>>>>>> 08/09/2006 11:15:28 AM -1225692256 RTPBeacon
>>>>>>> RTPBeacon.py:289 INFO RTPBeacon.UpdateThread exiting
>>>>>>> 08/09/2006 11:15:30 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:275 INFO NodeService.SetServiceEnabled
>>>>>>> 08/09/2006 11:15:30 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:248 INFO NodeService.GetServiceManagers
>>>>>>> 08/09/2006 11:15:30 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:218 INFO AGServiceManager.GetServices
>>>>>>> 08/09/2006 11:15:35 AM -1208600896 NodeManagementUIClasses
>>>>>>> NodeManagementUIClasses.py:1036 DEBUG
>>>>>>> NodeManagementClientFrame.EnableService: Enabling Service:
>>>>>>> DebugService
>>>>>>> 08/09/2006 11:15:35 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:275 INFO NodeService.SetServiceEnabled
>>>>>>> 08/09/2006 11:15:35 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:695 INFO NodeService.__SendStreamsToService
>>>>>>> 08/09/2006 11:15:35 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:699 DEBUG service capabilities: [producer,
>>>>>>> debug, None, None, None, 1, consumer, debug, None, None, None, 1]
>>>>>>> 08/09/2006 11:15:35 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:718 ERROR Exception in
>>>>>>> AGNodeService.__SendStreamsToService.
>>>>>>> Traceback (most recent call last):
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid/AGNodeService.py",
>>>>>>> line 708, in __SendStreamsToService
>>>>>>> if c.matches(cap):
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid3/AccessGrid/Descriptions.py",
>>>>>>> line 314, in matches
>>>>>>> if (str(self.type) != str(capability.type) or
>>>>>>> TypeError: int() argument must be a string or a number
>>>>>>> 08/09/2006 11:15:35 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:282 ERROR
>>>>>>> http://floyd.irmacs.sfu.ca:64864/Services/DebugService.8e3a3583333a6536e9540206adb5e731
>>>>>>>
>>>>>>> Traceback (most recent call last):
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid/AGNodeService.py",
>>>>>>> line 278, in SetServiceEnabled
>>>>>>> self.__SendStreamsToService( serviceUri )
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid/AGNodeService.py",
>>>>>>> line 723, in __SendStreamsToService
>>>>>>> raise SetStreamException(failedSends)
>>>>>>> SetStreamException: Error updating [producer, debug, None, None,
>>>>>>> None, 1, consumer, debug, None, None, None, 1]
>>>>>>>
>>>>>>> 08/09/2006 11:15:35 AM -1208600896 NodeManagementUIClasses
>>>>>>> NodeManagementUIClasses.py:1043 ERROR Error enabling service
>>>>>>> Traceback (most recent call last):
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid3/AccessGrid/NodeManagementUIClasses.py",
>>>>>>> line 1037, in EnableService
>>>>>>> self.nodeServiceHandle.SetServiceEnabled(service.uri, 1)
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid/AGNodeService.py",
>>>>>>> line 278, in SetServiceEnabled
>>>>>>> self.__SendStreamsToService( serviceUri )
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid/AGNodeService.py",
>>>>>>> line 723, in __SendStreamsToService
>>>>>>> raise SetStreamException(failedSends)
>>>>>>> SetStreamException: Error updating [producer, debug, None, None,
>>>>>>> None, 1, consumer, debug, None, None, None, 1]
>>>>>>>
>>>>>>> 08/09/2006 11:15:38 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:275 INFO NodeService.SetServiceEnabled
>>>>>>> 08/09/2006 11:15:38 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:248 INFO NodeService.GetServiceManagers
>>>>>>> 08/09/2006 11:15:38 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:218 INFO AGServiceManager.GetServices
>>>>>>> 08/09/2006 11:15:40 AM -1208600896 NodeManagementUIClasses
>>>>>>> NodeManagementUIClasses.py:1036 DEBUG
>>>>>>> NodeManagementClientFrame.EnableService: Enabling Service:
>>>>>>> DebugService
>>>>>>> 08/09/2006 11:15:40 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:275 INFO NodeService.SetServiceEnabled
>>>>>>> 08/09/2006 11:15:40 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:695 INFO NodeService.__SendStreamsToService
>>>>>>> 08/09/2006 11:15:40 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:699 DEBUG service capabilities: [producer,
>>>>>>> debug, None, None, None, 1, consumer, debug, None, None, None, 1]
>>>>>>> 08/09/2006 11:15:40 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:718 ERROR Exception in
>>>>>>> AGNodeService.__SendStreamsToService.
>>>>>>> Traceback (most recent call last):
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid/AGNodeService.py",
>>>>>>> line 708, in __SendStreamsToService
>>>>>>> if c.matches(cap):
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid3/AccessGrid/Descriptions.py",
>>>>>>> line 314, in matches
>>>>>>> if (str(self.type) != str(capability.type) or
>>>>>>> TypeError: int() argument must be a string or a number
>>>>>>> 08/09/2006 11:15:40 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:282 ERROR
>>>>>>> http://floyd.irmacs.sfu.ca:64864/Services/DebugService.8e3a3583333a6536e9540206adb5e731
>>>>>>>
>>>>>>> Traceback (most recent call last):
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid/AGNodeService.py",
>>>>>>> line 278, in SetServiceEnabled
>>>>>>> self.__SendStreamsToService( serviceUri )
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid/AGNodeService.py",
>>>>>>> line 723, in __SendStreamsToService
>>>>>>> raise SetStreamException(failedSends)
>>>>>>> SetStreamException: Error updating [producer, debug, None, None,
>>>>>>> None, 1, consumer, debug, None, None, None, 1]
>>>>>>>
>>>>>>> 08/09/2006 11:15:40 AM -1208600896 NodeManagementUIClasses
>>>>>>> NodeManagementUIClasses.py:1043 ERROR Error enabling service
>>>>>>> Traceback (most recent call last):
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid3/AccessGrid/NodeManagementUIClasses.py",
>>>>>>> line 1037, in EnableService
>>>>>>> self.nodeServiceHandle.SetServiceEnabled(service.uri, 1)
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid/AGNodeService.py",
>>>>>>> line 278, in SetServiceEnabled
>>>>>>> self.__SendStreamsToService( serviceUri )
>>>>>>> File
>>>>>>> "/usr/lib/python2.4/site-packages/AccessGrid/AGNodeService.py",
>>>>>>> line 723, in __SendStreamsToService
>>>>>>> raise SetStreamException(failedSends)
>>>>>>> SetStreamException: Error updating [producer, debug, None, None,
>>>>>>> None, 1, consumer, debug, None, None, None, 1]
>>>>>>>
>>>>>>> 08/09/2006 11:15:54 AM -1208600896 VenueClient
>>>>>>> VenueClientUI.py:2200 INFO --------- END VenueClient
>>>>>>> 08/09/2006 11:15:54 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:1132 INFO ExitVenue
>>>>>>> 08/09/2006 11:15:54 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:1173 INFO ExitVenue: Stopping node services
>>>>>>> 08/09/2006 11:15:54 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:301 INFO NodeService.StopServices
>>>>>>> 08/09/2006 11:15:54 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:225 INFO AGServiceManager.StopServices
>>>>>>> 08/09/2006 11:15:55 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:328 INFO NodeService.SetStreams
>>>>>>> 08/09/2006 11:15:55 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:257 INFO NodeService.GetServices
>>>>>>> 08/09/2006 11:15:55 AM -1225692256 ServiceManager
>>>>>>> AGServiceManager.py:218 INFO AGServiceManager.GetServices
>>>>>>> 08/09/2006 11:15:55 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:695 INFO NodeService.__SendStreamsToService
>>>>>>> 08/09/2006 11:15:55 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:699 DEBUG service capabilities: [consumer,
>>>>>>> audio, 8e3a358333096536dec30af9596ae5ae, L16, 16000, 1,
>>>>>>> consumer, audio, 8e3a358333096536dec30af9596ae5ae, L16, 8000, 1,
>>>>>>> consumer, audio, 8e3a358333096536dec30af9596ae5ae, L8, 16000, 1,
>>>>>>> consumer, audio, 8e3a358333096536dec30af9596ae5ae, L8, 8000, 1,
>>>>>>> consumer, audio, 8e3a358333096536dec30af9596ae5ae, PCMU, 16000,
>>>>>>> 1, consumer, audio, 8e3a358333096536dec30af9596ae5ae, PCMU,
>>>>>>> 8000, 1, consumer, audio, 8e3a358333096536dec30af9596ae5ae, GSM,
>>>>>>> 16000, 1, consumer, audio, 8e3a358333096536dec30af9596ae5ae,
>>>>>>> GSM, 8000, 1, producer, audio, 8e3a358333096536dec30af9596ae5ae,
>>>>>>> L16, 16000, 1]
>>>>>>> 08/09/2006 11:15:55 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:695 INFO NodeService.__SendStreamsToService
>>>>>>> 08/09/2006 11:15:56 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:699 DEBUG service capabilities: [producer,
>>>>>>> debug, None, None, None, 1, consumer, debug, None, None, None, 1]
>>>>>>> 08/09/2006 11:15:56 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:695 INFO NodeService.__SendStreamsToService
>>>>>>> 08/09/2006 11:15:56 AM -1208600896 NodeService
>>>>>>> AGNodeService.py:699 DEBUG service capabilities: [consumer,
>>>>>>> video, 8e3a358333036536de76e51f5b703c18, H261, 90000, 1,
>>>>>>> producer, video, 8e3a358333036536de76e51f5b703c18, H261, 90000, 1]
>>>>>>> 08/09/2006 11:15:56 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:1103 DEBUG ExitVenue: Stop event client obj
>>>>>>> 08/09/2006 11:15:56 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:1105 DEBUG ExitVenue: Remove event client reference
>>>>>>> 08/09/2006 11:15:56 AM -1208600896 VenueClient
>>>>>>> VenueClient.py:1110 INFO ExitVenue: Stopping text client
>>>>>>> 08/09/2006 11:15:56 AM -1208600896 JabberClient
>>>>>>> JabberClient.py:73 DEBUG Sending the presence to
>>>>>>> 'AG-Tech-Room(venueserver2.westgrid.ca)@conference.mcs.anl.gov/Todd
>>>>>>> Zimmerman' of type 'unavailable'...
>>>>>>> 08/09/2006 11:15:56 AM -1208600896 bajjer.io io.py:122 DEBUG
>>>>>>> SEND: <presence
>>>>>>> to="AG-Tech-Room(venueserver2.westgrid.ca)@conference.mcs.anl.gov/Todd
>>>>>>> Zimmerman" type="unavailable"><x /></presence>
>>>>>>> 08/09/2006 11:15:56 AM -1208600896 RTPBeacon
>>>>>>> RTPBeacon.py:296 DEBUG Called Stop.
>>>>>>> 08/09/2006 11:15:56 AM -1208600896 ServiceManager
>>>>>>> AGServiceManager.py:70 INFO AGServiceManager.Shutdown
>>>>>>> 08/09/2006 11:15:56 AM -1208600896 ServiceManager
>>>>>>> AGServiceManager.py:71 INFO Remove services
>>>>>>> 08/09/2006 11:15:56 AM -1208600896 ServiceManager
>>>>>>> AGServiceManager.py:208 INFO AGServiceManager.RemoveServices
>>>>>>> 08/09/2006 11:15:56 AM -1208600896 ServiceManager
>>>>>>> AGServiceManager.py:162 INFO AGServiceManager.RemoveService
>>>>>>> 08/09/2006 11:15:56 AM -1345344608 bajjer.io io.py:161 DEBUG
>>>>>>> RECV: <presence
>>>>>>> to='8180087163ff65364de2ad59cc6ba1e9 at jabber.mcs.anl.gov/default'
>>>>>>> type='unavailable'
>>>>>>> from='ag-tech-room(venueserver2.westgrid.ca)@conference.mcs.anl.gov/Todd
>>>>>>> Zimmerman'><x/><x
>>>>>>> xmlns='http://jabber.org/protocol/muc#user'><item
>>>>>>> affiliation='none' role='none'/></x></presence>
>>>>>>> 08/09/2006 11:15:56 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}presence> level=1
>>>>>>> 08/09/2006 11:15:56 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start <{jabber:client}x> level=2
>>>>>>> 08/09/2006 11:15:56 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}x> level=2
>>>>>>> 08/09/2006 11:15:56 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start
>>>>>>> <{http://jabber.org/protocol/muc#user}x> level=2
>>>>>>> 08/09/2006 11:15:56 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:72 DEBUG start
>>>>>>> <{http://jabber.org/protocol/muc#user}item> level=3
>>>>>>> 08/09/2006 11:15:56 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end
>>>>>>> <{http://jabber.org/protocol/muc#user}item> level=3
>>>>>>> 08/09/2006 11:15:56 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end
>>>>>>> <{http://jabber.org/protocol/muc#user}x> level=2
>>>>>>> 08/09/2006 11:15:56 AM -1366324320 RTPBeacon
>>>>>>> RTPBeacon.py:289 INFO RTPBeacon.UpdateThread exiting
>>>>>>> 08/09/2006 11:15:56 AM -1345344608 bajjer.serialize
>>>>>>> serialize.py:86 DEBUG end <{jabber:client}presence> level=1
>>>>>>> 08/09/2006 11:15:56 AM -1345344608 JabberClient
>>>>>>> JabberClient.py:148 DEBUG
>>>>>>> ag-tech-room(venueserver2.westgrid.ca)@conference.mcs.anl.gov/Todd
>>>>>>> Zimmerman is unavailable (None / None)
>>>>>>> 08/09/2006 11:15:56 AM -1345344608 VenueClient
>>>>>>> VenueClientUI.py:2313 DEBUG remove user: Todd Zimmerman
>>>>>>> 08/09/2006 11:15:58 AM -1208600896 ServiceManager
>>>>>>> AGServiceManager.py:162 INFO AGServiceManager.RemoveService
>>>>>>> 08/09/2006 11:16:00 AM -1208600896 ServiceManager
>>>>>>> AGServiceManager.py:162 INFO AGServiceManager.RemoveService
>>>>>>> 08/09/2006 11:16:02 AM -1208600896 ServiceManager
>>>>>>> AGServiceManager.py:73 INFO Stop network interface
>>>>>>> 08/09/2006 11:16:02 AM -1208600896 RTPBeacon
>>>>>>> RTPBeacon.py:296 DEBUG Called Stop.
>>>>>>>
>>>>>>
>>>
>>>
>>>
>>>
>>
>>
>
>
More information about the ag-dev
mailing list