[SIP Beyond VoIP] Another case where Sylk would reject Notifications
Hadzhiev, Tihomir
tihomir.hadzhiev at acision.com
Fri Nov 16 19:01:20 CET 2012
Hi Adrian,
Indeed the problem appears there if ( look at the attachment ) the XMPP guy is not present in the active wathers list on the presence server.
[cid:image001.png at 01CDC42C.C3A7C690]
If the XMPP guy is already present there, here are the logs from Sylk, e.g. they should be fine now. However the problem is still there.
Regards,
Tiho
2012-11-16 18:21:48.039094 [sylk-server 32155]: RECEIVED: Packet 11, +0:01:45.148682
10.226.20.240:6060 -(SIP over UDP)-> 10.226.20.56:6060
SUBSCRIBE sip:tihoparvi at testing.com SIP/2.0
Record-Route: <sip:mo at 10.226.20.240:6060;lr>
Route: <sip:10.226.20.56:6060;lr>, <sip:iscmark at scscf.open-ims.test:6060;lr;s=3;h=0;d=0;a=7369703a616c696365406f70656e2d696d732e74657374>
Record-Route: <sip:mo at 10.226.20.240:4060;lr>
Via: SIP/2.0/UDP 10.226.20.240:6060;branch=z9hG4bKd4f7.06c64825.0
Via: SIP/2.0/UDP 10.226.20.240:4060;branch=z9hG4bKd4f7.877cf2b1.0
Via: SIP/2.0/UDP 10.226.55.135:54843;rport=54843;branch=z9hG4bK1353087991942
From: <sip:alice at open-ims.test>;tag=4966
To: <sip:tihoparvi at testing.com>
Call-ID: M-ad1e7055f72d6dcf2ae9d3928c648ad6
CSeq: 601 SUBSCRIBE
Max-Forwards: 15
Allow: INVITE, ACK, CANCEL, BYE, MESSAGE, OPTIONS, NOTIFY, PRACK, UPDATE, REFER
Contact: <sip:alice at 10.226.55.135:54843;transport=udp>;+g.oma.sip-im
User-Agent: IM-client/OMA1.0 Mercuro-Gold/v4.0.1631.0
Expires: 600000
Event: presence
Supported: eventlist
Accept: multipart/related, application/rlmi+xml, application/pidf+xml, application/rpid+xml, application/xcap-diff+xml, message/external-body
Accept-Contact: *;+g.oma.sip-im
P-Access-Network-Info: ADSL;utran-cell-id-3gpp=00000000
Privacy: none
Supported: timer
Allow-Events: refer, presence, presence.winfo, xcap-diff, conference
Content-Length: 0
P-Asserted-Identity: <sip:alice at open-ims.test>
P-Charging-Vector: icid-value="P-CSCFabcd0000000050a67e8500000048";icid-generated-at=10.226.20.240;orig-ioi="open-ims.test"
--
==> notifications_trace.txt <==
2012-11-16 18:21:48.046533 [sylk-server 32155]: Notification name=XMPPSubscriptionDidStart sender=<sylk.applications.xmppgateway.xmpp.subscription.XMPPSubscription object at 0x7fbf98b6e490>
{}
2012-11-16 18:21:48.047186 [sylk-server 32155]: Notification name=XMPPSubscriptionChangedState sender=<sylk.applications.xmppgateway.xmpp.subscription.XMPPSubscription object at 0x7fbf98b6e490>
{'prev_state': None, 'state': 'subscribe_sent'}
==> xmpp_trace.txt <==
2012-11-16T18:21:48.044415+01:00 [sylk-server 32155]: SENDING: Packet 20, +0:01:50.858981
<presence to='tihoparvi at testing.com' from='alice at open-ims.test' type='subscribe'/>
--
2012-11-16T18:21:48.045340+01:00 [sylk-server 32155]: SENDING: Packet 21, +0:01:50.859906
<presence to='tihoparvi at testing.com' from='alice at open-ims.test' type='probe'/>
--
==> notifications_trace.txt <==
2012-11-16 18:21:48.050529 [sylk-server 32155]: Notification name=SIPIncomingSubscriptionGotSubscribe sender=<sipsimple.core._core.IncomingSubscription object at 0x7fbf98b465f0>
{'body': None,
'headers': {'Accept': ['multipart/related',
'application/rlmi+xml',
'application/pidf+xml',
'application/rpid+xml',
'application/xcap-diff+xml',
'message/external-body'],
'Accept-Contact': FrozenHeader('Accept-Contact', '*;+g.oma.sip-im'),
'Allow': ['INVITE',
'ACK',
'CANCEL',
'BYE',
'MESSAGE',
'OPTIONS',
'NOTIFY',
'PRACK',
'UPDATE',
'REFER'],
'Allow-Events': ['refer',
'presence',
'presence.winfo',
'xcap-diff',
'conference'],
'CSeq': (601, 'SUBSCRIBE'),
'Call-ID': FrozenHeader('Call-ID', 'M-ad1e7055f72d6dcf2ae9d3928c648ad6'),
'Contact': [FrozenContactHeader(FrozenSIPURI('10.226.55.135', 'alice', None, 54843, False, frozendict({'transport': 'udp'}), frozendict({})), None, frozendict({'q': '0.0', '+g.oma.sip-im': None}))],
'Content-Length': 0,
'Event': FrozenEventHeader('presence', frozendict({})),
'Expires': 600000,
'From': FrozenFromHeader(FrozenSIPURI('open-ims.test', 'alice', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': '4966'})),
'Max-Forwards': 15,
'P-Access-Network-Info': FrozenHeader('P-Access-Network-Info', 'ADSL;utran-cell-id-3gpp=00000000'),
'P-Asserted-Identity': FrozenHeader('P-Asserted-Identity', '<sip:alice at open-ims.test>'),
'P-Charging-Vector': FrozenHeader('P-Charging-Vector', 'icid-value="P-CSCFabcd0000000050a67e8500000048";icid-generated-at=10.226.20.240;orig-ioi="open-ims.test"'),
'Privacy': FrozenHeader('Privacy', 'none'),
'Record-Route': [FrozenRecordRouteHeader(FrozenSIPURI('10.226.20.240', 'mo', None, 6060, False, frozendict({'lr': None}), frozendict({})), None, frozendict({})),
FrozenRecordRouteHeader(FrozenSIPURI('10.226.20.240', 'mo', None, 4060, False, frozendict({'lr': None}), frozendict({})), None, frozendict({}))],
'Route': [FrozenRouteHeader(FrozenSIPURI('10.226.20.56', None, None, 6060, False, frozendict({'lr': None}), frozendict({})), None, frozendict({})),
FrozenRouteHeader(FrozenSIPURI('scscf.open-ims.test', 'iscmark', None, 6060, False, frozendict({'a': '7369703a616c696365406f70656e2d696d732e74657374', 'h': '0', 's': '3', 'lr': None, 'd': '0'}), frozendict({})), None, frozendict({}))],
'Supported': ['eventlist'],
'To': FrozenToHeader(FrozenSIPURI('testing.com', 'tihoparvi', None, None, False, frozendict({}), frozendict({})), None, frozendict({})),
'User-Agent': FrozenHeader('User-Agent', 'IM-client/OMA1.0 Mercuro-Gold/v4.0.1631.0'),
'Via': [FrozenViaHeader('UDP', '10.226.20.240', 6060, frozendict({'received': '10.226.20.240', 'branch': 'z9hG4bKd4f7.06c64825.0'})),
FrozenViaHeader('UDP', '10.226.20.240', 4060, frozendict({'branch': 'z9hG4bKd4f7.877cf2b1.0'})),
FrozenViaHeader('UDP', '10.226.55.135', 54843, frozendict({'rport': 54843, 'branch': 'z9hG4bK1353087991942'}))]},
'method': 'SUBSCRIBE',
'request_uri': FrozenSIPURI('testing.com', 'tihoparvi', None, None, False, frozendict({}), frozendict({}))}
2012-11-16 18:21:48.052382 [sylk-server 32155]: Notification name=S2XPresenceHandlerDidStart sender=<sylk.applications.xmppgateway.presence.S2XPresenceHandler object at 0x7fbf98b6e450>
{}
==> xmpp_trace.txt <==
2012-11-16T18:21:48.061389+01:00 [sylk-server 32155]: RECEIVED: Packet 22, +0:01:50.875955
<presence from='tihoparvi at testing.com' to='alice at open-ims.test' type='subscribed'/><presence from='tihoparvi at testing.com/CZ-R8LGKEG' to='alice at open-ims.test' xml:lang='en'>
<priority>5</priority>
<c xmlns='http://jabber.org/protocol/caps' node='http://psi-im.org/caps' ver='caps-b75d8d2b25' ext='ca cs ep-notify-2 html'/>
<delay xmlns='urn:xmpp:delay' from='tihoparvi at testing.com/CZ-R8LGKEG' stamp='2012-11-16T17:55:37Z'></delay><x xmlns='jabber:x:delay' stamp='20121116T17:55:37'/></presence><message from='tihoparvi at testing.com' to='alice at open-ims.test'><event xmlns='http://jabber.org/protocol/pubsub#event'><items node='http://jabber.org/protocol/mood'><item id='0'><mood xmlns='http://jabber.org/protocol/mood'/></item></items></event><delay xmlns='urn:xmpp:delay' from='tihoparvi at testing.com/Gajim' stamp='2012-11-15T12:38:18Z'></delay></message><message from='tihoparvi at testing.com' to='alice at open-ims.test'><event xmlns='http://jabber.org/protocol/pubsub#event'><items node='http://jabber.org/protocol/activity'><item id='0'><activity xmlns='http://jabber.org/protocol/activity'/></item></items></event><delay xmlns='urn:xmpp:delay' from='tihoparvi at testing.com/Gajim' stamp='2012-11-15T12:38:18Z'></delay></message>
--
==> notifications_trace.txt <==
2012-11-16 18:21:48.069398 [sylk-server 32155]: Notification name=XMPPGotPresenceSubscriptionStatus sender=<wokkel.component.InternalComponent object at 0x7fbf98ab4310>
{'presence_stanza': <sylk.applications.xmppgateway.xmpp.stanzas.SubscriptionPresence object at 0x7fbf98b6e0d0>}
2012-11-16 18:21:48.073479 [sylk-server 32155]: Notification name=XMPPGotPresenceAvailability sender=<wokkel.component.InternalComponent object at 0x7fbf98ab4310>
{'presence_stanza': <sylk.applications.xmppgateway.xmpp.stanzas.AvailabilityPresence object at 0x7fbf98b6edd0>}
==> sip_trace.txt <==
2012-11-16 18:21:48.070717 [sylk-server 32155]: SENDING: Packet 12, +0:01:45.180305
10.226.20.56:6060 -(SIP over UDP)-> 10.226.20.240:6060
SIP/2.0 202 Accepted
Via: SIP/2.0/UDP 10.226.20.240:6060;received=10.226.20.240;branch=z9hG4bKd4f7.06c64825.0
Via: SIP/2.0/UDP 10.226.20.240:4060;branch=z9hG4bKd4f7.877cf2b1.0
Via: SIP/2.0/UDP 10.226.55.135:54843;rport=54843;branch=z9hG4bK1353087991942
Record-Route: <sip:mo at 10.226.20.240:6060;lr>
Record-Route: <sip:mo at 10.226.20.240:4060;lr>
Call-ID: M-ad1e7055f72d6dcf2ae9d3928c648ad6
From: <sip:alice at open-ims.test>;tag=4966
To: <sip:tihoparvi at testing.com>;tag=SlV2Iku28VhruCL78OCV8fF1rCXJn0GO
CSeq: 601 SUBSCRIBE
Contact: <sip:tihoparvi at 10.226.20.56:6060>
Allow: SUBSCRIBE, NOTIFY, PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE, REFER
Supported: 100rel, replaces, norefersub, gruu
Expires: 3600
Server: SylkServer-2.2.1
Content-Length: 0
--
==> notifications_trace.txt <==
2012-11-16 18:21:48.075909 [sylk-server 32155]: Notification name=SIPIncomingSubscriptionAnsweredSubscribe sender=<sipsimple.core._core.IncomingSubscription object at 0x7fbf98b465f0>
{'body': None,
'code': 202,
'headers': {'Allow': ['SUBSCRIBE',
'NOTIFY',
'PRACK',
'INVITE',
'ACK',
'BYE',
'CANCEL',
'UPDATE',
'MESSAGE',
'REFER'],
'CSeq': (601, 'SUBSCRIBE'),
'Call-ID': FrozenHeader('Call-ID', 'M-ad1e7055f72d6dcf2ae9d3928c648ad6'),
'Contact': [FrozenContactHeader(FrozenSIPURI('10.226.20.56', 'tihoparvi', None, 6060, False, frozendict({}), frozendict({})), None, frozendict({'q': '0.0'}))],
'Expires': 3600,
'From': FrozenFromHeader(FrozenSIPURI('open-ims.test', 'alice', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': '4966'})),
'Record-Route': [FrozenRecordRouteHeader(FrozenSIPURI('10.226.20.240', 'mo', None, 6060, False, frozendict({'lr': None}), frozendict({})), None, frozendict({})),
FrozenRecordRouteHeader(FrozenSIPURI('10.226.20.240', 'mo', None, 4060, False, frozendict({'lr': None}), frozendict({})), None, frozendict({}))],
'Server': FrozenHeader('Server', 'SylkServer-2.2.1'),
'Supported': ['100rel', 'replaces', 'norefersub', 'gruu'],
'To': FrozenToHeader(FrozenSIPURI('testing.com', 'tihoparvi', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': 'SlV2Iku28VhruCL78OCV8fF1rCXJn0GO'})),
'Via': [FrozenViaHeader('UDP', '10.226.20.240', 6060, frozendict({'received': '10.226.20.240', 'branch': 'z9hG4bKd4f7.06c64825.0'})),
FrozenViaHeader('UDP', '10.226.20.240', 4060, frozendict({'branch': 'z9hG4bKd4f7.877cf2b1.0'})),
FrozenViaHeader('UDP', '10.226.55.135', 54843, frozendict({'rport': 54843, 'branch': 'z9hG4bK1353087991942'}))]},
'reason': 'Accepted'}
2012-11-16 18:21:48.080795 [sylk-server 32155]: Notification name=SIPIncomingSubscriptionChangedState sender=<sipsimple.core._core.IncomingSubscription object at 0x7fbf98b465f0>
{'prev_state': 'incoming', 'state': 'pending'}
==> sip_trace.txt <==
2012-11-16 18:21:48.071347 [sylk-server 32155]: SENDING: Packet 13, +0:01:45.180935
10.226.20.56:6060 -(SIP over UDP)-> 10.226.20.240:6060
NOTIFY sip:alice at 10.226.55.135:54843;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.226.20.56:6060;rport;branch=z9hG4bKPjtRYp4q228nsE5zzyG.DvAuEygcUO3iAd
Max-Forwards: 70
From: <sip:tihoparvi at testing.com>;tag=SlV2Iku28VhruCL78OCV8fF1rCXJn0GO
To: <sip:alice at open-ims.test>;tag=4966
Contact: <sip:tihoparvi at 10.226.20.56:6060>
Call-ID: M-ad1e7055f72d6dcf2ae9d3928c648ad6
CSeq: 745 NOTIFY
Route: <sip:mo at 10.226.20.240:6060;lr>
Route: <sip:mo at 10.226.20.240:4060;lr>
Event: presence
Subscription-State: pending;expires=599999
Allow-Events: conference, refer, presence
User-Agent: SylkServer-2.2.1
Content-Length: 0
--
==> notifications_trace.txt <==
2012-11-16 18:21:48.085043 [sylk-server 32155]: Notification name=SIPIncomingSubscriptionSentNotify sender=<sipsimple.core._core.IncomingSubscription object at 0x7fbf98b465f0>
{'body': None,
'headers': {'Allow-Events': ['conference', 'refer', 'presence'],
'CSeq': (745, 'NOTIFY'),
'Call-ID': FrozenHeader('Call-ID', 'M-ad1e7055f72d6dcf2ae9d3928c648ad6'),
'Contact': [FrozenContactHeader(FrozenSIPURI('10.226.20.56', 'tihoparvi', None, 6060, False, frozendict({}), frozendict({})), None, frozendict({'q': '0.0'}))],
'Event': FrozenEventHeader('presence', frozendict({})),
'From': FrozenFromHeader(FrozenSIPURI('testing.com', 'tihoparvi', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': 'SlV2Iku28VhruCL78OCV8fF1rCXJn0GO'})),
'Max-Forwards': 70,
'Route': [FrozenRouteHeader(FrozenSIPURI('10.226.20.240', 'mo', None, 6060, False, frozendict({'lr': None}), frozendict({})), None, frozendict({})),
FrozenRouteHeader(FrozenSIPURI('10.226.20.240', 'mo', None, 4060, False, frozendict({'lr': None}), frozendict({})), None, frozendict({}))],
'Subscription-State': FrozenSubscriptionStateHeader('pending', frozendict({'expires': '599999'})),
'To': FrozenToHeader(FrozenSIPURI('open-ims.test', 'alice', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': '4966'})),
'User-Agent': FrozenHeader('User-Agent', 'SylkServer-2.2.1'),
'Via': [FrozenViaHeader('UDP', '10.226.20.56', 6060, frozendict({'rport': 0, 'branch': 'z9hG4bKPjtRYp4q228nsE5zzyG.DvAuEygcUO3iAd'}))]},
'method': 'NOTIFY',
'request_uri': FrozenSIPURI('10.226.55.135', 'alice', None, 54843, False, frozendict({'transport': 'udp'}), frozendict({}))}
==> sip_trace.txt <==
2012-11-16 18:21:48.071807 [sylk-server 32155]: RECEIVED: Packet 14, +0:01:45.181395
10.226.20.240:6060 -(SIP over UDP)-> 10.226.20.56:6060
NOTIFY sip:pzxgtjqf at 10.226.20.56:6060 SIP/2.0
Via: SIP/2.0/UDP 10.226.20.240:6060;branch=z9hG4bK11cb.65d48ec7.0
Via: SIP/2.0/UDP 10.226.20.56:5080;branch=z9hG4bK11cb.fd672952.0
To: sip:tihoparvi at testing.com;tag=2XomvFoZmppiOkFV42NLtViH1Vc5dfxy
From: sip:alice at open-ims.test;tag=48b5b8952e8d0bca6256941910840e90-430a
CSeq: 4 NOTIFY
Call-ID: ANximeQT1XBTSiV0YDuWArRp1lb4tmO2
Content-Length: 1210
User-Agent: kamailio (3.3.2 (x86_64/linux))
Max-Forwards: 16
Event: presence
Contact: <sip:10.226.20.56:5080;transport=udp>
Subscription-State: active;expires=3494
Content-Type: application/pidf+xml
<?xml version="1.0" encoding="utf-8"?>
<presence xmlns:cp="urn:ietf:params:xml:ns:pidf:cipid" xmlns:caps="urn:ietf:params:xml:ns:pidf:caps" xmlns:rpid="urn:ietf:params:xml:ns:pidf:rpid" xmlns:pdm="urn:ietf:params:xml:ns:pidf:data-model" xmlns:p="urn:ietf:params:xml:ns:pidf-diff" xmlns:op="urn:oma:xml:prs:pidf:oma-pres" xmlns="urn:ietf:params:xml:ns:pidf" entity="sip:alice at open-ims.test">
<pdm:person id="HJWESMVC">
<op:overriding-willingness>
<op:basic>open</op:basic>
</op:overriding-willingness>
<rpid:activities>
<rpid:unknown/>
</rpid:activities>
<rpid:mood>
<rpid:neutral/>
</rpid:mood>
<cp:homepage>http://dir.bg</cp:homepage>
<pdm:note>TEST TEXT</pdm:note>
</pdm:person>
<pdm:device id="d0001">
<status>
<basic>open</basic>
</status>
<caps:devcaps>
<caps:mobility>
<caps:supported>
<caps:fixed/>
</caps:supported>
</caps:mobility>
</caps:devcaps>
<op:network-availability>
<op:network id="IMS">
<op:active/>
</op:network>
</op:network-availability>
<pdm:deviceID>urn:uuid:3ca50bcb-7a67-44f1-afd0-994a55f930f4</pdm:deviceID>
</pdm:device>
</presence>
--
==> notifications_trace.txt <==
2012-11-16 18:21:48.088445 [sylk-server 32155]: Notification name=SIPSubscriptionGotNotify sender=<sipsimple.core._core.Subscription object at 0x7fbf98bc3880>
{'body': '<?xml version="1.0" encoding="utf-8"?>\n<presence xmlns:cp="urn:ietf:params:xml:ns:pidf:cipid" xmlns:caps="urn:ietf:params:xml:ns:pidf:caps" xmlns:rpid="urn:ietf:params:xml:ns:pidf:rpid" xmlns:pdm="urn:ietf:params:xml:ns:pidf:data-model" xmlns:p="urn:ietf:params:xml:ns:pidf-diff" xmlns:op="urn:oma:xml:prs:pidf:oma-pres" xmlns="urn:ietf:params:xml:ns:pidf" entity="sip:alice at open-ims.test">\n <pdm:person id="HJWESMVC">\n <op:overriding-willingness>\n <op:basic>open</op:basic>\n </op:overriding-willingness>\n <rpid:activities>\n <rpid:unknown/>\n </rpid:activities>\n <rpid:mood>\n <rpid:neutral/>\n </rpid:mood>\n <cp:homepage>http://dir.bg</cp:homepage>\n <pdm:note>TEST TEXT</pdm:note>\n </pdm:person>\n <pdm:device id="d0001">\n <status>\n <basic>open</basic>\n </status>\n <caps:devcaps>\n <caps:mobility>\n <caps:supported>\n <caps:fixed/>\n </caps:supported>\n </caps:mobility>\n </caps:devcaps>\n <op:network-availability>\n <op:network id="IMS">\n <op:active/>\n </op:network>\n </op:network-availability>\n <pdm:deviceID>urn:uuid:3ca50bcb-7a67-44f1-afd0-994a55f930f4</pdm:deviceID>\n </pdm:device>\n</presence>\n',
'content_type': 'application/pidf+xml',
'event': 'presence',
'from_header': FrozenFromHeader(FrozenSIPURI('open-ims.test', 'alice', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': '48b5b8952e8d0bca6256941910840e90-430a'})),
'headers': {'CSeq': (4, 'NOTIFY'),
'Call-ID': FrozenHeader('Call-ID', 'ANximeQT1XBTSiV0YDuWArRp1lb4tmO2'),
'Contact': [FrozenContactHeader(FrozenSIPURI('10.226.20.56', None, None, 5080, False, frozendict({'transport': 'udp'}), frozendict({})), None, frozendict({'q': '0.0'}))],
'Content-Length': 1210,
'Content-Type': FrozenContentTypeHeader('application/pidf+xml', frozendict({})),
'Event': FrozenEventHeader('presence', frozendict({})),
'From': FrozenFromHeader(FrozenSIPURI('open-ims.test', 'alice', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': '48b5b8952e8d0bca6256941910840e90-430a'})),
'Max-Forwards': 16,
'Subscription-State': FrozenSubscriptionStateHeader('active', frozendict({'expires': '3494'})),
'To': FrozenToHeader(FrozenSIPURI('testing.com', 'tihoparvi', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': '2XomvFoZmppiOkFV42NLtViH1Vc5dfxy'})),
'User-Agent': FrozenHeader('User-Agent', 'kamailio (3.3.2 (x86_64/linux))'),
'Via': [FrozenViaHeader('UDP', '10.226.20.240', 6060, frozendict({'received': '10.226.20.240', 'branch': 'z9hG4bK11cb.65d48ec7.0'})),
FrozenViaHeader('UDP', '10.226.20.56', 5080, frozendict({'branch': 'z9hG4bK11cb.fd672952.0'}))]},
'request_uri': FrozenSIPURI('10.226.20.56', 'pzxgtjqf', None, 6060, False, frozendict({}), frozendict({})),
'to_header': FrozenToHeader(FrozenSIPURI('testing.com', 'tihoparvi', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': '2XomvFoZmppiOkFV42NLtViH1Vc5dfxy'}))}
2012-11-16 18:21:48.096808 [sylk-server 32155]: Notification name=XMPPSubscriptionChangedState sender=<sylk.applications.xmppgateway.xmpp.subscription.XMPPSubscription object at 0x7fbf98b6e490>
{'prev_state': 'subscribe_sent', 'state': 'active'}
2012-11-16 18:21:48.097182 [sylk-server 32155]: Notification name=XMPPSubscriptionGotNotify sender=<sylk.applications.xmppgateway.xmpp.subscription.XMPPSubscription object at 0x7fbf98b6e490>
{'presence': <sylk.applications.xmppgateway.xmpp.stanzas.AvailabilityPresence object at 0x7fbf98b6edd0>}
==> sip_trace.txt <==
2012-11-16 18:21:48.082057 [sylk-server 32155]: SENDING: Packet 15, +0:01:45.191645
10.226.20.56:6060 -(SIP over UDP)-> 10.226.20.240:6060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.226.20.240:6060;received=10.226.20.240;branch=z9hG4bK11cb.65d48ec7.0
Via: SIP/2.0/UDP 10.226.20.56:5080;branch=z9hG4bK11cb.fd672952.0
Call-ID: ANximeQT1XBTSiV0YDuWArRp1lb4tmO2
From: <sip:alice at open-ims.test>;tag=48b5b8952e8d0bca6256941910840e90-430a
To: <sip:tihoparvi at testing.com>;tag=2XomvFoZmppiOkFV42NLtViH1Vc5dfxy
CSeq: 4 NOTIFY
Contact: <sip:pzxgtjqf at 10.226.20.56:6060>
Allow: SUBSCRIBE, NOTIFY, PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE, REFER
Supported: 100rel, replaces, norefersub, gruu
Server: SylkServer-2.2.1
Content-Length: 0
--
==> notifications_trace.txt <==
2012-11-16 18:21:48.098436 [sylk-server 32155]: Notification name=SIPIncomingSubscriptionChangedState sender=<sipsimple.core._core.IncomingSubscription object at 0x7fbf98b465f0>
{'prev_state': 'pending', 'state': 'active'}
==> sip_trace.txt <==
2012-11-16 18:21:48.082845 [sylk-server 32155]: SENDING: Packet 16, +0:01:45.192433
10.226.20.56:6060 -(SIP over UDP)-> 10.226.20.240:6060
NOTIFY sip:alice at 10.226.55.135:54843;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.226.20.56:6060;rport;branch=z9hG4bKPjC5wjf5OTpQVBtHxL-L5intW.qiYGypi7
Max-Forwards: 70
From: <sip:tihoparvi at testing.com>;tag=SlV2Iku28VhruCL78OCV8fF1rCXJn0GO
To: <sip:alice at open-ims.test>;tag=4966
Contact: <sip:tihoparvi at 10.226.20.56:6060>
Call-ID: M-ad1e7055f72d6dcf2ae9d3928c648ad6
CSeq: 746 NOTIFY
Route: <sip:mo at 10.226.20.240:6060;lr>
Route: <sip:mo at 10.226.20.240:4060;lr>
Event: presence
Subscription-State: active;expires=599999
Allow-Events: conference, refer, presence
User-Agent: SylkServer-2.2.1
Content-Length: 0
--
==> notifications_trace.txt <==
2012-11-16 18:21:48.100861 [sylk-server 32155]: Notification name=SIPIncomingSubscriptionSentNotify sender=<sipsimple.core._core.IncomingSubscription object at 0x7fbf98b465f0>
{'body': None,
'headers': {'Allow-Events': ['conference', 'refer', 'presence'],
'CSeq': (746, 'NOTIFY'),
'Call-ID': FrozenHeader('Call-ID', 'M-ad1e7055f72d6dcf2ae9d3928c648ad6'),
'Contact': [FrozenContactHeader(FrozenSIPURI('10.226.20.56', 'tihoparvi', None, 6060, False, frozendict({}), frozendict({})), None, frozendict({'q': '0.0'}))],
'Event': FrozenEventHeader('presence', frozendict({})),
'From': FrozenFromHeader(FrozenSIPURI('testing.com', 'tihoparvi', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': 'SlV2Iku28VhruCL78OCV8fF1rCXJn0GO'})),
'Max-Forwards': 70,
'Route': [FrozenRouteHeader(FrozenSIPURI('10.226.20.240', 'mo', None, 6060, False, frozendict({'lr': None}), frozendict({})), None, frozendict({})),
FrozenRouteHeader(FrozenSIPURI('10.226.20.240', 'mo', None, 4060, False, frozendict({'lr': None}), frozendict({})), None, frozendict({}))],
'Subscription-State': FrozenSubscriptionStateHeader('active', frozendict({'expires': '599999'})),
'To': FrozenToHeader(FrozenSIPURI('open-ims.test', 'alice', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': '4966'})),
'User-Agent': FrozenHeader('User-Agent', 'SylkServer-2.2.1'),
'Via': [FrozenViaHeader('UDP', '10.226.20.56', 6060, frozendict({'rport': 0, 'branch': 'z9hG4bKPjC5wjf5OTpQVBtHxL-L5intW.qiYGypi7'}))]},
'method': 'NOTIFY',
'request_uri': FrozenSIPURI('10.226.55.135', 'alice', None, 54843, False, frozendict({'transport': 'udp'}), frozendict({}))}
==> sip_trace.txt <==
2012-11-16 18:21:48.083113 [sylk-server 32155]: RECEIVED: Packet 17, +0:01:45.192701
10.226.20.240:6060 -(SIP over UDP)-> 10.226.20.56:6060
SIP/2.0 200 OK
Max-Forwards: 70
User-Agent: IM-client/OMA1.0 Mercuro-Gold/v4.0.1631.0
Via: SIP/2.0/UDP 10.226.20.56:6060;rport=6060;branch=z9hG4bKPjtRYp4q228nsE5zzyG.DvAuEygcUO3iAd
From: <sip:tihoparvi at testing.com>;tag=SlV2Iku28VhruCL78OCV8fF1rCXJn0GO
To: <sip:alice at open-ims.test>;tag=4966
Call-ID: M-ad1e7055f72d6dcf2ae9d3928c648ad6
CSeq: 745 NOTIFY
Privacy: none
Content-Length: 0
--
==> notifications_trace.txt <==
2012-11-16 18:21:48.102617 [sylk-server 32155]: Notification name=SIPIncomingSubscriptionNotifyDidSucceed sender=<sipsimple.core._core.IncomingSubscription object at 0x7fbf98b465f0>
{'body': None,
'code': 200,
'headers': {'CSeq': (745, 'NOTIFY'),
'Call-ID': FrozenHeader('Call-ID', 'M-ad1e7055f72d6dcf2ae9d3928c648ad6'),
'Content-Length': 0,
'From': FrozenFromHeader(FrozenSIPURI('testing.com', 'tihoparvi', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': 'SlV2Iku28VhruCL78OCV8fF1rCXJn0GO'})),
'Max-Forwards': 70,
'Privacy': FrozenHeader('Privacy', 'none'),
'To': FrozenToHeader(FrozenSIPURI('open-ims.test', 'alice', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': '4966'})),
'User-Agent': FrozenHeader('User-Agent', 'IM-client/OMA1.0 Mercuro-Gold/v4.0.1631.0'),
'Via': [FrozenViaHeader('UDP', '10.226.20.56', 6060, frozendict({'rport': 6060, 'branch': 'z9hG4bKPjtRYp4q228nsE5zzyG.DvAuEygcUO3iAd'}))]},
'reason': 'OK'}
==> sip_trace.txt <==
2012-11-16 18:21:48.096238 [sylk-server 32155]: SENDING: Packet 18, +0:01:45.205826
10.226.20.56:6060 -(SIP over UDP)-> 10.226.20.240:6060
NOTIFY sip:alice at 10.226.55.135:54843;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.226.20.56:6060;rport;branch=z9hG4bKPj.MrNM6zk4NVy8dGKA8zWIXGHAZDgQkUs
Max-Forwards: 70
From: <sip:tihoparvi at testing.com>;tag=SlV2Iku28VhruCL78OCV8fF1rCXJn0GO
To: <sip:alice at open-ims.test>;tag=4966
Contact: <sip:tihoparvi at 10.226.20.56:6060>
Call-ID: M-ad1e7055f72d6dcf2ae9d3928c648ad6
CSeq: 747 NOTIFY
Route: <sip:mo at 10.226.20.240:6060;lr>
Route: <sip:mo at 10.226.20.240:4060;lr>
Event: presence
Subscription-State: active;expires=599999
Allow-Events: conference, refer, presence
User-Agent: SylkServer-2.2.1
Content-Type: application/pidf+xml
Content-Length: 873
<?xml version='1.0' encoding='UTF-8'?>
<presence xmlns:dm="urn:ietf:params:xml:ns:pidf:data-model" xmlns:agp-pidf="urn:ag-projects:xml:ns:pidf" xmlns:caps="urn:ietf:params:xml:ns:pidf:caps" xmlns:rpid="urn:ietf:params:xml:ns:pidf:rpid" xmlns="urn:ietf:params:xml:ns:pidf" entity="tihoparvi%40testing.com"><tuple id="ID-435a2d52384c474b4547"><status><basic>open</basic><agp-pidf:extended>available</agp-pidf:extended></status><dm:deviceID>435a2d52384c474b4547</dm:deviceID><agp-pidf:device-info id="435a2d52384c474b4547"><agp-pidf:description>CZ-R8LGKEG</agp-pidf:description></agp-pidf:device-info><caps:servcaps><caps:message>true</caps:message><caps:text>true</caps:text></caps:servcaps><contact>sip%3Atihoparvi%40testing.com%3Bxmpp%3Bgr%3D435a2d52384c474b4547</contact></tuple><dm:person id="ID-ed8975ecb63f91ca1146ca625b6fca2d"><rpid:activities/></dm:person></presence>
--
==> notifications_trace.txt <==
2012-11-16 18:21:48.106539 [sylk-server 32155]: Notification name=SIPIncomingSubscriptionSentNotify sender=<sipsimple.core._core.IncomingSubscription object at 0x7fbf98b465f0>
{'body': '<?xml version=\'1.0\' encoding=\'UTF-8\'?>\n<presence xmlns:dm="urn:ietf:params:xml:ns:pidf:data-model" xmlns:agp-pidf="urn:ag-projects:xml:ns:pidf" xmlns:caps="urn:ietf:params:xml:ns:pidf:caps" xmlns:rpid="urn:ietf:params:xml:ns:pidf:rpid" xmlns="urn:ietf:params:xml:ns:pidf" entity="tihoparvi%40testing.com"><tuple id="ID-435a2d52384c474b4547"><status><basic>open</basic><agp-pidf:extended>available</agp-pidf:extended></status><dm:deviceID>435a2d52384c474b4547</dm:deviceID><agp-pidf:device-info id="435a2d52384c474b4547"><agp-pidf:description>CZ-R8LGKEG</agp-pidf:description></agp-pidf:device-info><caps:servcaps><caps:message>true</caps:message><caps:text>true</caps:text></caps:servcaps><contact>sip%3Atihoparvi%40testing.com%3Bxmpp%3Bgr%3D435a2d52384c474b4547</contact></tuple><dm:person id="ID-ed8975ecb63f91ca1146ca625b6fca2d"><rpid:activities/></dm:person></presence>',
'headers': {'Allow-Events': ['conference', 'refer', 'presence'],
'CSeq': (747, 'NOTIFY'),
'Call-ID': FrozenHeader('Call-ID', 'M-ad1e7055f72d6dcf2ae9d3928c648ad6'),
'Contact': [FrozenContactHeader(FrozenSIPURI('10.226.20.56', 'tihoparvi', None, 6060, False, frozendict({}), frozendict({})), None, frozendict({'q': '0.0'}))],
'Event': FrozenEventHeader('presence', frozendict({})),
'From': FrozenFromHeader(FrozenSIPURI('testing.com', 'tihoparvi', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': 'SlV2Iku28VhruCL78OCV8fF1rCXJn0GO'})),
'Max-Forwards': 70,
'Route': [FrozenRouteHeader(FrozenSIPURI('10.226.20.240', 'mo', None, 6060, False, frozendict({'lr': None}), frozendict({})), None, frozendict({})),
FrozenRouteHeader(FrozenSIPURI('10.226.20.240', 'mo', None, 4060, False, frozendict({'lr': None}), frozendict({})), None, frozendict({}))],
'Subscription-State': FrozenSubscriptionStateHeader('active', frozendict({'expires': '599999'})),
'To': FrozenToHeader(FrozenSIPURI('open-ims.test', 'alice', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': '4966'})),
'User-Agent': FrozenHeader('User-Agent', 'SylkServer-2.2.1'),
'Via': [FrozenViaHeader('UDP', '10.226.20.56', 6060, frozendict({'rport': 0, 'branch': 'z9hG4bKPj.MrNM6zk4NVy8dGKA8zWIXGHAZDgQkUs'}))]},
'method': 'NOTIFY',
'request_uri': FrozenSIPURI('10.226.55.135', 'alice', None, 54843, False, frozendict({'transport': 'udp'}), frozendict({}))}
==> sip_trace.txt <==
2012-11-16 18:21:48.096514 [sylk-server 32155]: RECEIVED: Packet 19, +0:01:45.206102
10.226.20.240:6060 -(SIP over UDP)-> 10.226.20.56:6060
SIP/2.0 200 OK
Max-Forwards: 70
User-Agent: IM-client/OMA1.0 Mercuro-Gold/v4.0.1631.0
Via: SIP/2.0/UDP 10.226.20.56:6060;rport=6060;branch=z9hG4bKPjC5wjf5OTpQVBtHxL-L5intW.qiYGypi7
From: <sip:tihoparvi at testing.com>;tag=SlV2Iku28VhruCL78OCV8fF1rCXJn0GO
To: <sip:alice at open-ims.test>;tag=4966
Call-ID: M-ad1e7055f72d6dcf2ae9d3928c648ad6
CSeq: 746 NOTIFY
Privacy: none
Content-Length: 0
--
==> notifications_trace.txt <==
2012-11-16 18:21:48.108363 [sylk-server 32155]: Notification name=SIPIncomingSubscriptionNotifyDidSucceed sender=<sipsimple.core._core.IncomingSubscription object at 0x7fbf98b465f0>
{'body': None,
'code': 200,
'headers': {'CSeq': (746, 'NOTIFY'),
'Call-ID': FrozenHeader('Call-ID', 'M-ad1e7055f72d6dcf2ae9d3928c648ad6'),
'Content-Length': 0,
'From': FrozenFromHeader(FrozenSIPURI('testing.com', 'tihoparvi', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': 'SlV2Iku28VhruCL78OCV8fF1rCXJn0GO'})),
'Max-Forwards': 70,
'Privacy': FrozenHeader('Privacy', 'none'),
'To': FrozenToHeader(FrozenSIPURI('open-ims.test', 'alice', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': '4966'})),
'User-Agent': FrozenHeader('User-Agent', 'IM-client/OMA1.0 Mercuro-Gold/v4.0.1631.0'),
'Via': [FrozenViaHeader('UDP', '10.226.20.56', 6060, frozendict({'rport': 6060, 'branch': 'z9hG4bKPjC5wjf5OTpQVBtHxL-L5intW.qiYGypi7'}))]},
'reason': 'OK'}
==> sip_trace.txt <==
2012-11-16 18:21:48.115763 [sylk-server 32155]: RECEIVED: Packet 20, +0:01:45.225351
10.226.20.240:6060 -(SIP over UDP)-> 10.226.20.56:6060
SIP/2.0 200 OK
Max-Forwards: 70
User-Agent: IM-client/OMA1.0 Mercuro-Gold/v4.0.1631.0
Via: SIP/2.0/UDP 10.226.20.56:6060;rport=6060;branch=z9hG4bKPj.MrNM6zk4NVy8dGKA8zWIXGHAZDgQkUs
From: <sip:tihoparvi at testing.com>;tag=SlV2Iku28VhruCL78OCV8fF1rCXJn0GO
To: <sip:alice at open-ims.test>;tag=4966
Call-ID: M-ad1e7055f72d6dcf2ae9d3928c648ad6
CSeq: 747 NOTIFY
Privacy: none
Content-Length: 0
--
==> notifications_trace.txt <==
2012-11-16 18:21:48.117928 [sylk-server 32155]: Notification name=SIPIncomingSubscriptionNotifyDidSucceed sender=<sipsimple.core._core.IncomingSubscription object at 0x7fbf98b465f0>
{'body': None,
'code': 200,
'headers': {'CSeq': (747, 'NOTIFY'),
'Call-ID': FrozenHeader('Call-ID', 'M-ad1e7055f72d6dcf2ae9d3928c648ad6'),
'Content-Length': 0,
'From': FrozenFromHeader(FrozenSIPURI('testing.com', 'tihoparvi', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': 'SlV2Iku28VhruCL78OCV8fF1rCXJn0GO'})),
'Max-Forwards': 70,
'Privacy': FrozenHeader('Privacy', 'none'),
'To': FrozenToHeader(FrozenSIPURI('open-ims.test', 'alice', None, None, False, frozendict({}), frozendict({})), None, frozendict({'tag': '4966'})),
'User-Agent': FrozenHeader('User-Agent', 'IM-client/OMA1.0 Mercuro-Gold/v4.0.1631.0'),
'Via': [FrozenViaHeader('UDP', '10.226.20.56', 6060, frozendict({'rport': 6060, 'branch': 'z9hG4bKPj.MrNM6zk4NVy8dGKA8zWIXGHAZDgQkUs'}))]},
'reason': 'OK'}
________________________________
This e-mail and any attachment is for authorised use by the intended recipient(s) only. It may contain proprietary material, confidential information and/or be subject to legal privilege. It should not be copied, disclosed to, retained or used by, any other party. If you are not an intended recipient then please promptly delete this e-mail and any attachment and all copies and inform the sender. Thank you for understanding.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ag-projects.com/pipermail/sipbeyondvoip/attachments/20121116/449e6794/attachment-0001.html>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: image001.png
Type: image/png
Size: 11863 bytes
Desc: image001.png
URL: <http://lists.ag-projects.com/pipermail/sipbeyondvoip/attachments/20121116/449e6794/attachment-0001.png>
More information about the SIPBeyondVoIP
mailing list