gistfile1.txt

DEBUG:agent1.StanzaStream:enqueued stanza <presence from=None to='agent2@localhost' id='xrDQt2VgZ8nv3o9g3NgE5' type=<PresenceType.SUBSCRIBE: 'subscribe'>> with token <StanzaToken id=0x000000010d3fee58>
DEBUG:agent1.StanzaStream:forwarding stanza to xmlstream: <presence from=None to='agent2@localhost' id='xrDQt2VgZ8nv3o9g3NgE5' type=<PresenceType.SUBSCRIBE: 'subscribe'>>
DEBUG:agent1.XMLStream:SENT b'<presence to="agent2@localhost" id="xrDQt2VgZ8nv3o9g3NgE5" type="subscribe"/>'
DEBUG:aioopenssl.trace.fd=13:_write_ready: nothing more to write, removing writer
DEBUG:asyncio:poll 9963.207 ms took 1.854 ms: 1 events
DEBUG:agent1.XMLStream:RECV b"<iq id='lx47' type='set'><query ver='107' xmlns='jabber:iq:roster'><item jid='agent2@localhost' ask='subscribe' subscription='none'/></query></iq><presence type='unavailable' to='agent1@localhost' from='agent2@localhost'/>"
DEBUG:agent1.StanzaStream:incoming iq: <iq from=None to=None id='lx47' type=<IQType.SET: 'set'> data=<aioxmpp.roster.xso.Query object at 0x10d3e9a58>>
DEBUG:agent1.StanzaStream:iq is request
DEBUG:agent1.StanzaStream:started task to handle request: <Task pending coro=<RosterClient.handle_roster_push() running at /Users/jpalanca/anaconda/envs/spade-dev/lib/python3.6/site-packages/aioxmpp/roster/service.py:472> cb=[StanzaStream._iq_request_coro_done(<iq from=None... 0x10d3e9a58>>)()] created at /Users/jpalanca/anaconda/envs/spade-dev/lib/python3.6/asyncio/tasks.py:499>
DEBUG:agent1.StanzaStream:enqueued stanza <iq from=None to=None id='lx47' type=<IQType.RESULT: 'result'>> with token <StanzaToken id=0x000000010d391048>
DEBUG:agent1.StanzaStream:forwarding stanza to xmlstream: <iq from=None to=None id='lx47' type=<IQType.RESULT: 'result'>>
DEBUG:agent1.XMLStream:SENT b'<iq id="lx47" type="result"/>'
DEBUG:agent1.StanzaStream:incoming presence: <presence from='agent2@localhost' to='agent1@localhost' id=None type=<PresenceType.UNAVAILABLE: 'unavailable'>>
DEBUG:aioopenssl.trace.fd=13:_write_ready: nothing more to write, removing writer