[SIP Beyond VoIP] File Transfer between Blink clients through Sylkserver

Ravi Kansangara ravi.kansangara at matrixcomsec.com
Wed Oct 7 16:39:32 CEST 2015


​
​
Hi,

I am evaluating Blink Client & Sylk seerver to implement some features
(i.e. Group Chat, File Transfer, Desktop Sharing) over SIP/MSRP.

I have configured Blink Client to transfer media over TCP/MSRP means i have
disabled the TLS.

Same way i have make the "use_tls = False" in MSRP section of config.ini to
relay media over TCP/MSRP.

But in one thread i have read that Sylk server forces the clients to use
MSRP media transfer over TCP/TLS/MSRP only. The flag  "use_tls = False" in
config.ini of sylk server is just used for development phase. In reality
media must be transmitted over TLS.

*Query: *Is this correct information?

*Exception**: File Transfer & Desktop Sharing is not supported over TCP*
*/MSRP** but Group Chat is still working on TCP/MSRP.*

So i have again enabled the TLS in Sylk Server & Blink Client.


*File Transfer over TCP/TLS/MSRP:*

For testing i have established one chat session with Sylk server which will
create conference room on-the-fly.

Also connected the second Blink Client to that conference chat room.

Now when i send one file in chat room (by drag-drop file), It send INVITE
message to Sylk server. Sylk server also returns its TCP/TLS/MSRP port for
media transfer in 200 OK response.

After that Blink Client sends BYE message and File Transfer gets cancelled.

Same thing happens with Desktop Sharing over TCP/TLS/MSRP.

Below are the logs of Blink Client & Sylk server for the File Transfer
Session.


*-------------------------------------------------------------------------------------------*
*Blink Client Logs*
*-------------------------------------------------------------------------------------------*

*notification_trace.txt*

2015-09-29 19:40:20.046875 [blink.exe 2872]: Notification
name=MediaStreamDidInitialize
sender=<sipsimple.streams.msrp.FileTransferStream object at 0x094615cc>
data=NotificationData()
2015-09-29 19:40:20.062500 [blink.exe 2872]: Notification
name=SIPInvitationChangedState sender=<sipsimple.core._core.Invitation
object at 0x04bf32cc> data=NotificationData(originator='local',
body='v=0\r\no=- 3652544420 3652544420 IN IP4 192.168.101.26\r\ns=Blink
1.4.2 (Windows)\r\nt=0 0\r\nm=message 2855 TCP/TLS/MSRP *\r\nc=IN IP4
192.168.101.26\r\na=path:msrps://
192.168.101.26:2855/d41793c2e535905b840;tcp\r\na=sendonly\r\na=accept-types:*\r\na=setup:active\r\na=file-selector:name:"test.pdf"
type:application/pdf size:257365
hash:sha1:CD:DD:B2:7F:80:CA:72:B2:E2:86:20:4F:A7:77:E2:C6:CF:19:B1:C1\r\na=x-file-offset\r\na=file-transfer-id:c6817484-53e0-48f1-9817-e3c3fc5ab919\r\n',
state='outgoing', prev_state=None, headers={'Via': [FrozenViaHeader('UDP',
'192.168.101.26', 3451, frozendict({'rport': 0, 'branch':
'z9hG4bKPj0d46618b5761475aae4c1ef825b303ce'}))], 'From':
FrozenFromHeader(FrozenSIPURI('192.168.101.6', 'ravi', None, None, False,
frozendict({}), frozendict({})), u'ravi', frozendict({'tag':
'd2816e639c83426db91aafcc5af5c54d'})), 'Route':
[FrozenRouteHeader(FrozenSIPURI('192.168.101.6', None, None, None, False,
frozendict({'lr': None, 'hide': None}), frozendict({})), None,
frozendict({}))], 'Supported': ['100rel', 'replaces', 'norefersub',
'gruu'], 'User-Agent': FrozenHeader('User-Agent', 'Blink 1.4.2 (Windows)'),
'To': FrozenToHeader(FrozenSIPURI('192.168.101.6', 'sylkchat', None, None,
False, frozendict({}), frozendict({})), None, frozendict({})), 'Contact':
[FrozenContactHeader(FrozenSIPURI('192.168.101.26', '61503427', None, 3451,
False, frozendict({}), frozendict({})), None, frozendict({'q': '0.0'}))],
'CSeq': (30333, 'INVITE'), 'Allow': ['SUBSCRIBE', 'NOTIFY', 'PRACK',
'INVITE', 'ACK', 'BYE', 'CANCEL', 'UPDATE', 'MESSAGE', 'REFER'], 'Call-ID':
FrozenHeader('Call-ID', '0c6928edc82b4d8abba6c4f93a698176'),
'Max-Forwards': 70}, method='INVITE',
request_uri=FrozenSIPURI('192.168.101.6', 'sylkchat', None, None, False,
frozendict({}), frozendict({})))
2015-09-29 19:40:20.078125 [blink.exe 2872]: Notification
name=SIPInvitationChangedState sender=<sipsimple.core._core.Invitation
object at 0x04bf32cc> data=NotificationData(originator='remote',
body='v=0\r\no=- 3652544364 3652544365 IN IP4
192.168.101.6\r\ns=SylkServer-3.0.1\r\nt=0 0\r\nm=message 37614
TCP/TLS/MSRP *\r\nc=IN IP4 192.168.101.6\r\na=path:msrps://
192.168.101.6:37614/622bef1e9b29bcdb5a7;tcp\r\na=recvonly\r\na=accept-types:*\r\na=setup:passive\r\na=file-selector:name:"test.pdf"
type:application/pdf size:257365
hash:sha1:CD:DD:B2:7F:80:CA:72:B2:E2:86:20:4F:A7:77:E2:C6:CF:19:B1:C1\r\na=x-file-offset\r\na=file-transfer-id:c6817484-53e0-48f1-9817-e3c3fc5ab919\r\n',
reason='OK', code=200, headers={'Content-Length': 453, 'Via':
[FrozenViaHeader('UDP', '192.168.101.26', 3451, frozendict({'received':
'192.168.101.26', 'rport': 3451, 'branch':
'z9hG4bKPj0d46618b5761475aae4c1ef825b303ce'}))], 'From':
FrozenFromHeader(FrozenSIPURI('192.168.101.6', 'ravi', None, None, False,
frozendict({}), frozendict({})), u'ravi', frozendict({'tag':
'd2816e639c83426db91aafcc5af5c54d'})), 'Supported': ['100rel', 'replaces',
'norefersub', 'gruu'], 'Server': FrozenHeader('Server',
'SylkServer-3.0.1'), 'To': FrozenToHeader(FrozenSIPURI('192.168.101.6',
'sylkchat', None, None, False, frozendict({}), frozendict({})), None,
frozendict({'tag': '-ptXgZJXQ2UtyKBLn3GFu8vOJuug7QW3'})), 'Contact':
[FrozenContactHeader(FrozenSIPURI('192.168.101.6', 'sylkserver', None,
5060, False, frozendict({}), frozendict({})), None, frozendict({'q': '0.0',
'isfocus': None}))], 'CSeq': (30333, 'INVITE'), 'Allow': ['SUBSCRIBE',
'NOTIFY', 'PRACK', 'INVITE', 'ACK', 'BYE', 'CANCEL', 'UPDATE', 'MESSAGE',
'REFER'], 'Call-ID': FrozenHeader('Call-ID',
'0c6928edc82b4d8abba6c4f93a698176'), 'Content-Type':
FrozenContentTypeHeader('application/sdp', frozendict({}))},
prev_state='outgoing', state='connecting')
2015-09-29 19:40:20.078125 [blink.exe 2872]: Notification
name=SIPInvitationGotSDPUpdate sender=<sipsimple.core._core.Invitation
object at 0x04bf32cc>
data=NotificationData(local_sdp=FrozenSDPSession('192.168.101.26',
3652544420L, 3652544420L, '-', 'IN', 'IP4', 'Blink 1.4.2 (Windows)', None,
0, 0, frozenlist([]), frozenlist([]),
frozenlist([FrozenSDPMediaStream('message', 2855, 'TCP/TLS/MSRP', 1,
frozenlist(['*']), FrozenSDPConnection('192.168.101.26', 'IN', 'IP4'),
frozenlist([FrozenSDPAttribute('path', 'msrps://
192.168.101.26:2855/d41793c2e535905b840;tcp'),
FrozenSDPAttribute('sendonly', ''), FrozenSDPAttribute('accept-types',
'*'), FrozenSDPAttribute('setup', 'active'),
FrozenSDPAttribute('file-selector', 'name:"test.pdf" type:application/pdf
size:257365
hash:sha1:CD:DD:B2:7F:80:CA:72:B2:E2:86:20:4F:A7:77:E2:C6:CF:19:B1:C1'),
FrozenSDPAttribute('x-file-offset', ''),
FrozenSDPAttribute('file-transfer-id',
'c6817484-53e0-48f1-9817-e3c3fc5ab919')]), frozenlist([]))])),
succeeded=True, remote_sdp=FrozenSDPSession('192.168.101.6', 3652544364L,
3652544365L, '-', 'IN', 'IP4', 'SylkServer-3.0.1', None, 0, 0,
frozenlist([]), frozenlist([]), frozenlist([FrozenSDPMediaStream('message',
37614, 'TCP/TLS/MSRP', 0, frozenlist(['*']),
FrozenSDPConnection('192.168.101.6', 'IN', 'IP4'),
frozenlist([FrozenSDPAttribute('path', 'msrps://
192.168.101.6:37614/622bef1e9b29bcdb5a7;tcp'),
FrozenSDPAttribute('recvonly', ''), FrozenSDPAttribute('accept-types',
'*'), FrozenSDPAttribute('setup', 'passive'),
FrozenSDPAttribute('file-selector', 'name:"test.pdf" type:application/pdf
size:257365
hash:sha1:CD:DD:B2:7F:80:CA:72:B2:E2:86:20:4F:A7:77:E2:C6:CF:19:B1:C1'),
FrozenSDPAttribute('x-file-offset', ''),
FrozenSDPAttribute('file-transfer-id',
'c6817484-53e0-48f1-9817-e3c3fc5ab919')]), frozenlist([]))])))
2015-09-29 19:40:20.078125 [blink.exe 2872]: Notification
name=SIPInvitationChangedState sender=<sipsimple.core._core.Invitation
object at 0x04bf32cc> data=NotificationData(originator=None, body=None,
state='connected', prev_state='connecting', headers={}, sub_state='normal')
2015-09-29 19:40:20.078125 [blink.exe 2872]: Notification
name=SIPSessionWillStart sender=<sipsimple.session.Session object at
0x0921e94c> data=NotificationData()
2015-09-29 19:40:20.078125 [blink.exe 2872]: Notification
name=BlinkFileTransferDidChangeState
sender=<blink.sessions.BlinkFileTransfer object at 0x04ff560c>
data=NotificationData(old_state='connecting',
new_state='connecting/starting')
2015-09-29 19:40:20.078125 [blink.exe 2872]: Notification
name=MSRPLibraryLog sender=UnknownSender
data=NotificationData(message='Connecting to msrps://
192.168.101.6:37614/622bef1e9b29bcdb5a7;tcp', level=INFO)
2015-09-29 19:40:20.078125 [blink.exe 2872]: Notification
name=FileTransferItemDidChange
sender=FileTransferItem(<blink.sessions.BlinkFileTransfer object at
0x04ff560c>) data=NotificationData()
2015-09-29 19:40:20.078125 [blink.exe 2872]: Notification
name=MediaStreamDidFail sender=<sipsimple.streams.msrp.FileTransferStream
object at 0x094615cc> data=NotificationData(reason='The TLS connection was
non-properly terminated.', context='start')
2015-09-29 19:40:20.078125 [blink.exe 2872]: Notification
name=SIPSessionWillEnd sender=<sipsimple.session.Session object at
0x0921e94c> data=NotificationData(originator='local')
2015-09-29 19:40:20.093750 [blink.exe 2872]: Notification
name=MediaStreamWillEnd sender=<sipsimple.streams.msrp.FileTransferStream
object at 0x094615cc> data=NotificationData()
2015-09-29 19:40:20.093750 [blink.exe 2872]: Notification
name=FileTransferHandlerDidEnd
sender=<sipsimple.streams.msrp.OutgoingFileTransferHandler object at
0x094612ac> data=NotificationData(reason='Cancelled', error=True)
2015-09-29 19:40:20.093750 [blink.exe 2872]: Notification
name=MediaStreamDidEnd sender=<sipsimple.streams.msrp.FileTransferStream
object at 0x094615cc> data=NotificationData(error='The TLS connection was
non-properly terminated.')
2015-09-29 19:40:20.093750 [blink.exe 2872]: Notification
name=SIPInvitationChangedState sender=<sipsimple.core._core.Invitation
object at 0x04bf32cc> data=NotificationData(originator='local', body=None,
prev_sub_state='normal', headers={'Via': [FrozenViaHeader('UDP',
'192.168.101.26', 3451, frozendict({'rport': 0, 'branch':
'z9hG4bKPjd14e492fc254443da081d5567b9b5371'}))], 'From':
FrozenFromHeader(FrozenSIPURI('192.168.101.6', 'ravi', None, None, False,
frozendict({}), frozendict({})), u'ravi', frozendict({'tag':
'd2816e639c83426db91aafcc5af5c54d'})), 'User-Agent':
FrozenHeader('User-Agent', 'Blink 1.4.2 (Windows)'), 'To':
FrozenToHeader(FrozenSIPURI('192.168.101.6', 'sylkchat', None, None, False,
frozendict({}), frozendict({})), None, frozendict({'tag':
'-ptXgZJXQ2UtyKBLn3GFu8vOJuug7QW3'})), 'CSeq': (30334, 'BYE'), 'Call-ID':
FrozenHeader('Call-ID', '0c6928edc82b4d8abba6c4f93a698176'),
'Max-Forwards': 70}, state='disconnecting', prev_state='connected',
method='BYE', request_uri=FrozenSIPURI('192.168.101.6', 'sylkserver', None,
5060, False, frozendict({}), frozendict({})))
2015-09-29 19:40:20.093750 [blink.exe 2872]: Notification
name=SIPInvitationChangedState sender=<sipsimple.core._core.Invitation
object at 0x04bf32cc> data=NotificationData(originator='remote', body=None,
code=200, disconnect_reason='media stream failed to start',
state='disconnected', headers={'Content-Length': 0, 'Via':
[FrozenViaHeader('UDP', '192.168.101.6', 5060, frozendict({'received':
'192.168.101.6', 'rport': 5060, 'branch':
'z9hG4bKPjQwRVREmrE7VqnpZ6vZsm8eLx74.PCRoL'}))], 'From':
FrozenFromHeader(FrozenSIPURI('192.168.101.6', 'sylkchat', None, None,
False, frozendict({}), frozendict({})), None, frozendict({'tag':
'-ptXgZJXQ2UtyKBLn3GFu8vOJuug7QW3'})), 'User-Agent':
FrozenHeader('User-Agent', 'SylkServer-3.0.1'), 'To':
FrozenToHeader(FrozenSIPURI('192.168.101.6', 'ravi', None, None, False,
frozendict({}), frozendict({})), u'ravi', frozendict({'tag':
'd2816e639c83426db91aafcc5af5c54d'})), 'Reason': FrozenReasonHeader('SIP ',
frozendict({'text': '"media stream failed to start" ', 'cause': '500'})),
'CSeq': (5967, 'BYE'), 'Call-ID': FrozenHeader('Call-ID',
'0c6928edc82b4d8abba6c4f93a698176'), 'Max-Forwards': 70}, reason='Normal
call clearing', prev_state='disconnecting', method='BYE',
request_uri=FrozenSIPURI('192.168.101.26', '61503427', None, 3451, False,
frozendict({}), frozendict({})))
2015-09-29 19:40:20.093750 [blink.exe 2872]: Notification
name=SIPSessionDidProcessTransaction sender=<sipsimple.session.Session
object at 0x0921e94c> data=NotificationData(originator='remote',
reason='OK', code=200, method='BYE')
2015-09-29 19:40:20.093750 [blink.exe 2872]: Notification
name=SIPSessionDidFail sender=<sipsimple.session.Session object at
0x0921e94c> data=NotificationData(originator='remote',
redirect_identities=None, code=0, reason=None, failure_reason='media stream
failed: The TLS connection was non-properly terminated.')
2015-09-29 19:40:20.109375 [blink.exe 2872]: Notification
name=BlinkFileTransferDidChangeState
sender=<blink.sessions.BlinkFileTransfer object at 0x04ff560c>
data=NotificationData(old_state='connecting/starting', new_state='ending')

*-------------------------------------------------------------------------------------------*
*Sylk Server Logs*
*-------------------------------------------------------------------------------------------*

*notification_trace.log*

2015-09-30 01:09:24.560815 [sylk-server 2602]: Notification
name=SIPInvitationChangedState sender=<sipsimple.core._core.Invitation
object at 0xb02c7f0c>
{'body': 'v=0\r\no=- 3652544420 3652544420 IN IP4 192.168.101.26\r\ns=Blink
1.4.2 (Windows)\r\nt=0 0\r\nm=message 2855 TCP/TLS/MSRP *\r\nc=IN IP4
192.168.101.26\r\na=path:msrps://
192.168.101.26:2855/d41793c2e535905b840;tcp\r\na=sendonly\r\na=accept-types:*\r\na=setup:active\r\na=file-selector:name:"test.pdf"
type:application/pdf size:257365
hash:sha1:CD:DD:B2:7F:80:CA:72:B2:E2:86:20:4F:A7:77:E2:C6:CF:19:B1:C1\r\na=x-file-offset\r\na=file-transfer-id:c6817484-53e0-48f1-9817-e3c3fc5ab919\r\n',
 'headers': {'Allow': ['SUBSCRIBE',
                       'NOTIFY',
                       'PRACK',
                       'INVITE',
                       'ACK',
                       'BYE',
                       'CANCEL',
                       'UPDATE',
                       'MESSAGE',
                       'REFER'],
             'CSeq': (30333, 'INVITE'),
             'Call-ID': FrozenHeader('Call-ID',
'0c6928edc82b4d8abba6c4f93a698176'),
             'Contact': [FrozenContactHeader(FrozenSIPURI('192.168.101.26',
'61503427', None, 3451, False, frozendict({}), frozendict({})), None,
frozendict({'q': '0.0'}))],
             'Content-Length': 458,
             'Content-Type': FrozenContentTypeHeader('application/sdp',
frozendict({})),
             'From': FrozenFromHeader(FrozenSIPURI('192.168.101.6', 'ravi',
None, None, False, frozendict({}), frozendict({})), u'ravi',
frozendict({'tag': 'd2816e639c83426db91aafcc5af5c54d'})),
             'Max-Forwards': 70,
             'Supported': ['100rel', 'replaces', 'norefersub', 'gruu'],
             'To': FrozenToHeader(FrozenSIPURI('192.168.101.6', 'sylkchat',
None, None, False, frozendict({}), frozendict({})), None, frozendict({})),
             'User-Agent': FrozenHeader('User-Agent', 'Blink 1.4.2
(Windows)'),
             'Via': [FrozenViaHeader('UDP', '192.168.101.26', 3451,
frozendict({'received': '192.168.101.26', 'rport': 3451, 'branch':
'z9hG4bKPj0d46618b5761475aae4c1ef825b303ce'}))]},
 'method': 'INVITE',
 'originator': 'remote',
 'prev_state': None,
 'request_uri': FrozenSIPURI('192.168.101.6', 'sylkchat', None, None,
False, frozendict({}), frozendict({})),
 'state': 'incoming'}
2015-09-30 01:09:24.564811 [sylk-server 2602]: Notification
name=SIPSessionNewIncoming sender=<sylk.session.Session object at
0xb0008f2c>
{'headers': {'Allow': ['SUBSCRIBE',
                       'NOTIFY',
                       'PRACK',
                       'INVITE',
                       'ACK',
                       'BYE',
                       'CANCEL',
                       'UPDATE',
                       'MESSAGE',
                       'REFER'],
             'CSeq': (30333, 'INVITE'),
             'Call-ID': FrozenHeader('Call-ID',
'0c6928edc82b4d8abba6c4f93a698176'),
             'Contact': [FrozenContactHeader(FrozenSIPURI('192.168.101.26',
'61503427', None, 3451, False, frozendict({}), frozendict({})), None,
frozendict({'q': '0.0'}))],
             'Content-Length': 458,
             'Content-Type': FrozenContentTypeHeader('application/sdp',
frozendict({})),
             'From': FrozenFromHeader(FrozenSIPURI('192.168.101.6', 'ravi',
None, None, False, frozendict({}), frozendict({})), u'ravi',
frozendict({'tag': 'd2816e639c83426db91aafcc5af5c54d'})),
             'Max-Forwards': 70,
             'Supported': ['100rel', 'replaces', 'norefersub', 'gruu'],
             'To': FrozenToHeader(FrozenSIPURI('192.168.101.6', 'sylkchat',
None, None, False, frozendict({}), frozendict({})), None, frozendict({})),
             'User-Agent': FrozenHeader('User-Agent', 'Blink 1.4.2
(Windows)'),
             'Via': [FrozenViaHeader('UDP', '192.168.101.26', 3451,
frozendict({'received': '192.168.101.26', 'rport': 3451, 'branch':
'z9hG4bKPj0d46618b5761475aae4c1ef825b303ce'}))]},
 'streams': [<sipsimple.streams.msrp.FileTransferStream object at
0xb0014b8c>]}
2015-09-30 01:09:24.565590 [sylk-server 2602]: Notification
name=FileTransferHandlerDidInitialize
sender=<sipsimple.streams.msrp.IncomingFileTransferHandler object at
0xb0014b6c>
{}
2015-09-30 01:09:24.571947 [sylk-server 2602]: Notification
name=MSRPLibraryLog sender=UnknownSender
{'level': INFO,
 'message': 'Listening for incoming MSRPS connections on 192.168.101.6:37614
'}
2015-09-30 01:09:24.572097 [sylk-server 2602]: Notification
name=MediaStreamDidInitialize
sender=<sipsimple.streams.msrp.FileTransferStream object at 0xb0014b8c>
{}
2015-09-30 01:09:24.572467 [sylk-server 2602]: Notification
name=SIPSessionWillStart sender=<sylk.session.Session object at 0xb0008f2c>
{}
2015-09-30 01:09:24.578756 [sylk-server 2602]: Notification
name=SIPInvitationGotSDPUpdate sender=<sipsimple.core._core.Invitation
object at 0xb02c7f0c>
{'local_sdp': FrozenSDPSession('192.168.101.6', 3652544364L, 3652544365L,
'-', 'IN', 'IP4', 'SylkServer-3.0.1', None, 0, 0, frozenlist([]),
frozenlist([]), frozenlist([FrozenSDPMediaStream('message', 37614,
'TCP/TLS/MSRP', 1, frozenlist(['*']), FrozenSDPConnection('192.168.101.6',
'IN', 'IP4'), frozenlist([FrozenSDPAttribute('path', 'msrps://
192.168.101.6:37614/622bef1e9b29bcdb5a7;tcp'),
FrozenSDPAttribute('recvonly', ''), FrozenSDPAttribute('accept-types',
'*'), FrozenSDPAttribute('setup', 'passive'),
FrozenSDPAttribute('file-selector', 'name:"test.pdf" type:application/pdf
size:257365
hash:sha1:CD:DD:B2:7F:80:CA:72:B2:E2:86:20:4F:A7:77:E2:C6:CF:19:B1:C1'),
FrozenSDPAttribute('x-file-offset', ''),
FrozenSDPAttribute('file-transfer-id',
'c6817484-53e0-48f1-9817-e3c3fc5ab919')]), frozenlist([]))])),
 'remote_sdp': FrozenSDPSession('192.168.101.26', 3652544420L, 3652544420L,
'-', 'IN', 'IP4', 'Blink 1.4.2 (Windows)', None, 0, 0, frozenlist([]),
frozenlist([]), frozenlist([FrozenSDPMediaStream('message', 2855,
'TCP/TLS/MSRP', 0, frozenlist(['*']), FrozenSDPConnection('192.168.101.26',
'IN', 'IP4'), frozenlist([FrozenSDPAttribute('path', 'msrps://
192.168.101.26:2855/d41793c2e535905b840;tcp'),
FrozenSDPAttribute('sendonly', ''), FrozenSDPAttribute('accept-types',
'*'), FrozenSDPAttribute('setup', 'active'),
FrozenSDPAttribute('file-selector', 'name:"test.pdf" type:application/pdf
size:257365
hash:sha1:CD:DD:B2:7F:80:CA:72:B2:E2:86:20:4F:A7:77:E2:C6:CF:19:B1:C1'),
FrozenSDPAttribute('x-file-offset', ''),
FrozenSDPAttribute('file-transfer-id',
'c6817484-53e0-48f1-9817-e3c3fc5ab919')]), frozenlist([]))])),
 'succeeded': True}
2015-09-30 01:09:24.579913 [sylk-server 2602]: Notification
name=SIPInvitationChangedState sender=<sipsimple.core._core.Invitation
object at 0xb02c7f0c>
{'body': 'v=0\r\no=- 3652544364 3652544365 IN IP4
192.168.101.6\r\ns=SylkServer-3.0.1\r\nt=0 0\r\nm=message 37614
TCP/TLS/MSRP *\r\nc=IN IP4 192.168.101.6\r\na=path:msrps://
192.168.101.6:37614/622bef1e9b29bcdb5a7;tcp\r\na=recvonly\r\na=accept-types:*\r\na=setup:passive\r\na=file-selector:name:"test.pdf"
type:application/pdf size:257365
hash:sha1:CD:DD:B2:7F:80:CA:72:B2:E2:86:20:4F:A7:77:E2:C6:CF:19:B1:C1\r\na=x-file-offset\r\na=file-transfer-id:c6817484-53e0-48f1-9817-e3c3fc5ab919\r\n',
 'code': 200,
 'headers': {'Allow': ['SUBSCRIBE',
                       'NOTIFY',
                       'PRACK',
                       'INVITE',
                       'ACK',
                       'BYE',
                       'CANCEL',
                       'UPDATE',
                       'MESSAGE',
                       'REFER'],
             'CSeq': (30333, 'INVITE'),
             'Call-ID': FrozenHeader('Call-ID',
'0c6928edc82b4d8abba6c4f93a698176'),
             'Contact': [FrozenContactHeader(FrozenSIPURI('192.168.101.6',
'sylkserver', None, 5060, False, frozendict({}), frozendict({})), None,
frozendict({'q': '0.0', 'isfocus': None}))],
             'From': FrozenFromHeader(FrozenSIPURI('192.168.101.6', 'ravi',
None, None, False, frozendict({}), frozendict({})), u'ravi',
frozendict({'tag': 'd2816e639c83426db91aafcc5af5c54d'})),
             'Server': FrozenHeader('Server', 'SylkServer-3.0.1'),
             'Supported': ['100rel', 'replaces', 'norefersub', 'gruu'],
             'To': FrozenToHeader(FrozenSIPURI('192.168.101.6', 'sylkchat',
None, None, False, frozendict({}), frozendict({})), None,
frozendict({'tag': '-ptXgZJXQ2UtyKBLn3GFu8vOJuug7QW3'})),
             'Via': [FrozenViaHeader('UDP', '192.168.101.26', 3451,
frozendict({'received': '192.168.101.26', 'rport': 3451, 'branch':
'z9hG4bKPj0d46618b5761475aae4c1ef825b303ce'}))]},
 'originator': 'local',
 'prev_state': 'incoming',
 'reason': 'OK',
 'state': 'connecting'}
2015-09-30 01:09:24.580434 [sylk-server 2602]: Notification
name=SIPInvitationChangedState sender=<sipsimple.core._core.Invitation
object at 0xb02c7f0c>
{'body': None,
 'headers': {'CSeq': (30333, 'ACK'),
             'Call-ID': FrozenHeader('Call-ID',
'0c6928edc82b4d8abba6c4f93a698176'),
             'Content-Length': 0,
             'From': FrozenFromHeader(FrozenSIPURI('192.168.101.6', 'ravi',
None, None, False, frozendict({}), frozendict({})), u'ravi',
frozendict({'tag': 'd2816e639c83426db91aafcc5af5c54d'})),
             'Max-Forwards': 70,
             'To': FrozenToHeader(FrozenSIPURI('192.168.101.6', 'sylkchat',
None, None, False, frozendict({}), frozendict({})), None,
frozendict({'tag': '-ptXgZJXQ2UtyKBLn3GFu8vOJuug7QW3'})),
             'User-Agent': FrozenHeader('User-Agent', 'Blink 1.4.2
(Windows)'),
             'Via': [FrozenViaHeader('UDP', '192.168.101.26', 3451,
frozendict({'received': '192.168.101.26', 'rport': 3451, 'branch':
'z9hG4bKPj6ee65b77db864a28bc511a5fcb7025c9'}))]},
 'method': 'ACK',
 'originator': 'remote',
 'prev_state': 'connecting',
 'request_uri': FrozenSIPURI('192.168.101.6', 'sylkserver', None, 5060,
False, frozendict({}), frozendict({})),
 'state': 'connected',
 'sub_state': 'normal'}
2015-09-30 01:09:24.584113 [sylk-server 2602]: Notification
name=MSRPLibraryLog sender=UnknownSender
{'level': INFO,
 'message': 'Closed connection to 192.168.101.26:3829 (Could not negotiate
a supported cipher suite.)'}
2015-09-30 01:09:24.584432 [sylk-server 2602]: Notification
name=MediaStreamDidFail sender=<sipsimple.streams.msrp.FileTransferStream
object at 0xb0014b8c>
{'context': 'start', 'reason': 'Could not negotiate a supported cipher
suite.'}
2015-09-30 01:09:24.584870 [sylk-server 2602]: Notification
name=SIPSessionWillEnd sender=<sylk.session.Session object at 0xb0008f2c>
{'originator': 'local'}
2015-09-30 01:09:24.585210 [sylk-server 2602]: Notification
name=MediaStreamWillEnd sender=<sipsimple.streams.msrp.FileTransferStream
object at 0xb0014b8c>
{}
2015-09-30 01:09:24.585348 [sylk-server 2602]: Notification
name=FileTransferHandlerDidEnd
sender=<sipsimple.streams.msrp.IncomingFileTransferHandler object at
0xb0014b6c>
{'error': True, 'reason': 'Cancelled'}
2015-09-30 01:09:24.585526 [sylk-server 2602]: Notification
name=MediaStreamDidEnd sender=<sipsimple.streams.msrp.FileTransferStream
object at 0xb0014b8c>
{'error': 'Could not negotiate a supported cipher suite.'}
2015-09-30 01:09:24.589553 [sylk-server 2602]: Notification
name=SIPInvitationChangedState sender=<sipsimple.core._core.Invitation
object at 0xb02c7f0c>
{'body': None,
 'headers': {'CSeq': (5967, 'BYE'),
             'Call-ID': FrozenHeader('Call-ID',
'0c6928edc82b4d8abba6c4f93a698176'),
             'From': FrozenFromHeader(FrozenSIPURI('192.168.101.6',
'sylkchat', None, None, False, frozendict({}), frozendict({})), None,
frozendict({'tag': '-ptXgZJXQ2UtyKBLn3GFu8vOJuug7QW3'})),
             'Max-Forwards': 70,
             'Reason': FrozenReasonHeader('SIP ', frozendict({'text':
'"media stream failed to start" ', 'cause': '500'})),
             'To': FrozenToHeader(FrozenSIPURI('192.168.101.6', 'ravi',
None, None, False, frozendict({}), frozendict({})), u'ravi',
frozendict({'tag': 'd2816e639c83426db91aafcc5af5c54d'})),
             'User-Agent': FrozenHeader('User-Agent', 'SylkServer-3.0.1'),
             'Via': [FrozenViaHeader('UDP', '192.168.101.6', 5060,
frozendict({'rport': 0, 'branch':
'z9hG4bKPjQwRVREmrE7VqnpZ6vZsm8eLx74.PCRoL'}))]},
 'method': 'BYE',
 'originator': 'local',
 'prev_state': 'connected',
 'prev_sub_state': 'normal',
 'request_uri': FrozenSIPURI('192.168.101.26', '61503427', None, 3451,
False, frozendict({}), frozendict({})),
 'state': 'disconnecting'}
2015-09-30 01:09:24.591332 [sylk-server 2602]: Notification
name=SIPInvitationChangedState sender=<sipsimple.core._core.Invitation
object at 0xb02c7f0c>
{'body': None,
 'code': 200,
 'disconnect_reason': 'user request',
 'headers': {'CSeq': (5967, 'BYE'),
             'Call-ID': FrozenHeader('Call-ID',
'0c6928edc82b4d8abba6c4f93a698176'),
             'Content-Length': 0,
             'From': FrozenFromHeader(FrozenSIPURI('192.168.101.6',
'sylkchat', None, None, False, frozendict({}), frozendict({})), None,
frozendict({'tag': '-ptXgZJXQ2UtyKBLn3GFu8vOJuug7QW3'})),
             'Server': FrozenHeader('Server', 'Blink 1.4.2 (Windows)'),
             'To': FrozenToHeader(FrozenSIPURI('192.168.101.6', 'ravi',
None, None, False, frozendict({}), frozendict({})), u'ravi',
frozendict({'tag': 'd2816e639c83426db91aafcc5af5c54d'})),
             'Via': [FrozenViaHeader('UDP', '192.168.101.6', 5060,
frozendict({'received': '192.168.101.6', 'rport': 5060, 'branch':
'z9hG4bKPjQwRVREmrE7VqnpZ6vZsm8eLx74.PCRoL'}))]},
 'originator': 'remote',
 'prev_state': 'disconnecting',
 'reason': 'Normal call clearing',
 'state': 'disconnected'}
2015-09-30 01:09:24.591575 [sylk-server 2602]: Notification
name=SIPSessionDidFail sender=<sylk.session.Session object at 0xb0008f2c>
{'code': 0,
 'failure_reason': 'media stream failed: Could not negotiate a supported
cipher suite.',
 'originator': 'local',
 'reason': None,
 'redirect_identities': None}
2015-09-30 01:09:28.540861 [sylk-server 2602]: Notification
name=SIPInvitationChangedState sender=<sipsimple.core._core.Invitation
object at 0xb02c7d4c>
{'body': None,
 'code': 200,
 'disconnect_reason': 'user request',
 'headers': {'CSeq': (1870, 'BYE'),
             'Call-ID': FrozenHeader('Call-ID',
'ab832b2941524bbf853894478e7bcf5d'),
             'Content-Length': 0,
             'From': FrozenFromHeader(FrozenSIPURI('192.168.101.6', 'ravi',
None, None, False, frozendict({}), frozendict({})), u'ravi',
frozendict({'tag': '136feef1d7d248e4a17151169406eb36'})),
             'Max-Forwards': 70,
             'To': FrozenToHeader(FrozenSIPURI('192.168.101.6', 'sylkchat',
None, None, False, frozendict({}), frozendict({})), None,
frozendict({'tag': '750wMeNVMOlRJc.lc4ykopqP4t2MxTdd'})),
             'User-Agent': FrozenHeader('User-Agent', 'Blink 1.4.2
(Windows)'),
             'Via': [FrozenViaHeader('UDP', '192.168.101.26', 3451,
frozendict({'received': '192.168.101.26', 'rport': 3451, 'branch':
'z9hG4bKPjb4d10913608f4e7f9b9e5a6e4b7ae2a4'}))]},
 'method': 'BYE',
 'originator': 'remote',
 'prev_state': 'connected',
 'prev_sub_state': 'normal',
 'reason': 'Normal call clearing',
 'request_uri': FrozenSIPURI('192.168.101.6', 'sylkserver', None, 5060,
False, frozendict({}), frozendict({})),
 'state': 'disconnected'}


*core_trace.log*

2015-09-30 01:09:24.553800 [sylk-server 2602] (5) 2015-09-30 01:09:24.553
sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=30333
(rdata0xb15260a0)
2015-09-30 01:09:24.554452 [sylk-server 2602] (5) 2015-09-30 01:09:24.553
 tsx0xb156d7a8 ..Transaction created for Request msg INVITE/cseq=30333
(rdata0xb15260a0)
2015-09-30 01:09:24.554871 [sylk-server 2602] (5) 2015-09-30 01:09:24.553
 tsx0xb156d7a8 .Incoming Request msg INVITE/cseq=30333 (rdata0xb15260a0) in
state Null
2015-09-30 01:09:24.555386 [sylk-server 2602] (5) 2015-09-30 01:09:24.553
 tsx0xb156d7a8 ..State changed from Null to Trying, event=RX_MSG
2015-09-30 01:09:24.555509 [sylk-server 2602] (5) 2015-09-30 01:09:24.553
 dlg0xb156cfa0 ...Transaction tsx0xb156d7a8 state changed to Trying
2015-09-30 01:09:24.555798 [sylk-server 2602] (5) 2015-09-30 01:09:24.553
 dlg0xb156cfa0 .UAS dialog created
2015-09-30 01:09:24.555905 [sylk-server 2602] (5) 2015-09-30 01:09:24.553
 dlg0xb156cfa0 .Module mod-invite added as dialog usage, data=0xb156e728
2015-09-30 01:09:24.556072 [sylk-server 2602] (5) 2015-09-30 01:09:24.553
 dlg0xb156cfa0 ..Session count inc to 2 by mod-invite
2015-09-30 01:09:24.556443 [sylk-server 2602] (5) 2015-09-30 01:09:24.553
 inv0xb156cfa0 .UAS invite session created for dialog dlg0xb156cfa0
2015-09-30 01:09:24.556730 [sylk-server 2602] (5) 2015-09-30 01:09:24.553
    endpoint ..Response msg 100/INVITE/cseq=30333 (tdta0xb156f258) created
2015-09-30 01:09:24.557028 [sylk-server 2602] (5) 2015-09-30 01:09:24.553
 dlg0xb156cfa0 ..Initial answer Response msg 100/INVITE/cseq=30333
(tdta0xb156f258)
2015-09-30 01:09:24.573843 [sylk-server 2602] (5) 2015-09-30 01:09:24.557
 inv0xb156cfa0 .Sending Response msg 100/INVITE/cseq=30333 (tdta0xb156f258)
2015-09-30 01:09:24.574034 [sylk-server 2602] (5) 2015-09-30 01:09:24.557
 dlg0xb156cfa0 ..Sending Response msg 100/INVITE/cseq=30333 (tdta0xb156f258)
2015-09-30 01:09:24.574096 [sylk-server 2602] (5) 2015-09-30 01:09:24.557
 tsx0xb156d7a8 ..Sending Response msg 100/INVITE/cseq=30333
(tdta0xb156f258) in state Trying
2015-09-30 01:09:24.574302 [sylk-server 2602] (5) 2015-09-30 01:09:24.560
 tsx0xb156d7a8 ...State changed from Trying to Proceeding, event=TX_MSG
2015-09-30 01:09:24.574520 [sylk-server 2602] (5) 2015-09-30 01:09:24.560
 dlg0xb156cfa0 ....Transaction tsx0xb156d7a8 state changed to Proceeding
2015-09-30 01:09:24.574702 [sylk-server 2602] (5) 2015-09-30 01:09:24.572
 inv0xb156cfa0 .SDP negotiation done, status=0
2015-09-30 01:09:24.574753 [sylk-server 2602] (5) 2015-09-30 01:09:24.572
 inv0xb156cfa0 .Sending Response msg 200/INVITE/cseq=30333 (tdta0xb156f258)
2015-09-30 01:09:24.574898 [sylk-server 2602] (5) 2015-09-30 01:09:24.572
 dlg0xb156cfa0 ..Sending Response msg 200/INVITE/cseq=30333 (tdta0xb156f258)
2015-09-30 01:09:24.575016 [sylk-server 2602] (5) 2015-09-30 01:09:24.572
 tsx0xb156d7a8 ..Sending Response msg 200/INVITE/cseq=30333
(tdta0xb156f258) in state Proceeding
2015-09-30 01:09:24.575553 [sylk-server 2602] (5) 2015-09-30 01:09:24.572
 tsx0xb156d7a8 ...State changed from Proceeding to Completed, event=TX_MSG
2015-09-30 01:09:24.575842 [sylk-server 2602] (5) 2015-09-30 01:09:24.572
 dlg0xb156cfa0 ....Transaction tsx0xb156d7a8 state changed to Completed
2015-09-30 01:09:24.576221 [sylk-server 2602] (5) 2015-09-30 01:09:24.573
sip_endpoint.c Processing incoming message: Request msg ACK/cseq=30333
(rdata0xb15260a0)
2015-09-30 01:09:24.576900 [sylk-server 2602] (5) 2015-09-30 01:09:24.573
 dlg0xb156cfa0 .Received Request msg ACK/cseq=30333 (rdata0xb15260a0)
2015-09-30 01:09:24.576999 [sylk-server 2602] (5) 2015-09-30 01:09:24.573
 tsx0xb156d7a8 ..Request to terminate transaction
2015-09-30 01:09:24.577165 [sylk-server 2602] (5) 2015-09-30 01:09:24.573
 tsx0xb156d7a8 ...State changed from Completed to Terminated, event=USER
2015-09-30 01:09:24.577311 [sylk-server 2602] (5) 2015-09-30 01:09:24.573
 dlg0xb156cfa0 ....Transaction tsx0xb156d7a8 state changed to Terminated
2015-09-30 01:09:24.586278 [sylk-server 2602] (5) 2015-09-30 01:09:24.578
 tsx0xb156d7a8 Timeout timer event
2015-09-30 01:09:24.586453 [sylk-server 2602] (5) 2015-09-30 01:09:24.578
 tsx0xb156d7a8 .State changed from Terminated to Destroyed, event=TIMER
2015-09-30 01:09:24.586500 [sylk-server 2602] (5) 2015-09-30 01:09:24.578
tdta0xb156f258 ..Destroying txdata Response msg 200/INVITE/cseq=30333
(tdta0xb156f258)
2015-09-30 01:09:24.586624 [sylk-server 2602] (5) 2015-09-30 01:09:24.578
 tsx0xb156d7a8 Transaction destroyed



*Best Regards,*
*Ravi Kansangara*
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ag-projects.com/pipermail/sipbeyondvoip/attachments/20151007/1ec5454e/attachment-0001.html>


More information about the SIPBeyondVoIP mailing list