Project

General

Profile

IO-sipit29 » History » Version 6

Adrian Georgescu, 10/24/2011 11:40 AM

1 1 Adrian Georgescu
= SIPIT 29 =
2 2 Adrian Georgescu
3 3 Adrian Georgescu
== Sending BYE exception when using TCP transport ==
4 2 Adrian Georgescu
5 6 Adrian Georgescu
The problem occurs when proxy is stateless and is requesting authorization for the BYE.
6
 
7
8 5 Adrian Georgescu
{{{
9
SENDING: Packet 188, +0:14:23.287009
10
2011-10-24 11:35:51.825513: 132.177.252.163:51280 -(SIP over tls)-> 132.177.253.22:5061
11
INVITE sip:ag02@cisco.sipit.net SIP/2.0
12
Via: SIP/2.0/tls 132.177.252.163:51280;rport;branch=z9hG4bKPjGqaVmgy6QI4hqusA17.VmYbSEjEn1S-y
13
Max-Forwards: 70
14
From: "Adrian Georgescu" <sip:ag01@cisco.sipit.net>;tag=tG4TWxTurelH-rGZRnivbJ96jCHFsQS8
15
To: <sip:ag02@cisco.sipit.net>
16
Contact: <sip:doszpemb@132.177.252.163:51544;transport=tls>
17
Call-ID: wm-ihLWnDBKwk743UftUCoP-H5qoETv-
18
CSeq: 4485 INVITE
19
Allow: SUBSCRIBE, NOTIFY, PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE, REFER
20
Supported: 100rel, replaces, norefersub
21
User-Agent: Blink Pro 1.3.1 (MacOSX)
22
Proxy-Authorization: Digest username="cisco", realm="h1.cisco.sipit.net", nonce="5f8327823b80b7c1ad635e68c6ab5ef8b1ac0010d74c76ea30d139be4b0e", uri="sip:ag02@cisco.sipit.net", response="02829cb450bd6f420e8f980f80dec000", algorithm=MD5, cnonce="WMYZEu5MhMBRJr97hlz3Nxqd9pidFkvZ", opaque="AQAAANXGRIqnJLpaa2+oaGI3teAaoA95", qop=auth, nc=00000001
23
Content-Type: application/sdp
24
Content-Length:   269
25
26
v=0
27
o=- 3528437751 3528437751 IN IP4 132.177.252.163
28
s=Blink Pro 1.3.1 (MacOSX)
29
c=IN IP4 132.177.252.163
30
t=0 0
31
m=audio 50004 RTP/AVP 9 0 101
32
a=rtcp:50005
33
a=rtpmap:9 G722/8000
34
a=rtpmap:0 PCMU/8000
35
a=rtpmap:101 telephone-event/8000
36
a=fmtp:101 0-15
37
a=sendrecv
38
39
RECEIVED: Packet 189, +0:14:23.333731
40
2011-10-24 11:35:51.872235: 132.177.253.22:5061 -(SIP over tls)-> 132.177.252.163:51280
41
SIP/2.0 100 Trying
42
Via: SIP/2.0/tls 132.177.252.163:51280;branch=z9hG4bKPjGqaVmgy6QI4hqusA17.VmYbSEjEn1S-y;received=132.177.252.163;rport=51280;ingress-zone=DefaultZone
43
Call-ID: wm-ihLWnDBKwk743UftUCoP-H5qoETv-
44
CSeq: 4485 INVITE
45
From: "Adrian Georgescu" <sip:ag01@cisco.sipit.net>;tag=tG4TWxTurelH-rGZRnivbJ96jCHFsQS8
46
To: <sip:ag02@cisco.sipit.net>
47
Server: TANDBERG/4103 (X7.1PreAlpha29 (TEST SW))
48
Content-Length: 0
49
50
RECEIVED: Packet 190, +0:14:23.380310
51
2011-10-24 11:35:51.918814: 132.177.253.22:5061 -(SIP over tls)-> 132.177.252.163:51280
52
SIP/2.0 180 Ringing
53
Via: SIP/2.0/tls 132.177.252.163:51280;rport=51280;received=132.177.252.163;branch=z9hG4bKPjGqaVmgy6QI4hqusA17.VmYbSEjEn1S-y;ingress-zone=DefaultZone
54
Call-ID: wm-ihLWnDBKwk743UftUCoP-H5qoETv-
55
CSeq: 4485 INVITE
56
Contact: <sip:qwbivsgz@132.177.252.111:60868>
57
From: "Adrian Georgescu" <sip:ag01@cisco.sipit.net>;tag=tG4TWxTurelH-rGZRnivbJ96jCHFsQS8
58
To: <sip:ag02@cisco.sipit.net>;tag=nkEDOGe3nL5K7PwTs1-DzrwUmbdzAYUy
59
Record-Route: <sip:proxy-call-id=9041af5c-fe23-11e0-8ac1-000c29ab0f7b@132.177.253.22:5060;transport=udp;lr>
60
Record-Route: <sip:proxy-call-id=9041af5c-fe23-11e0-8ac1-000c29ab0f7b@132.177.253.22:5061;transport=tls;lr>
61
Allow: SUBSCRIBE,NOTIFY,PRACK,INVITE,ACK,BYE,CANCEL,UPDATE,MESSAGE,REFER
62
Server: Blink Pro 1.3.1 (MacOSX)
63
Content-Length: 0
64
65
RECEIVED: Packet 191, +0:14:28.691178
66
2011-10-24 11:35:57.229682: 132.177.253.22:5061 -(SIP over tls)-> 132.177.252.163:51280
67
SIP/2.0 200 OK
68
Via: SIP/2.0/tls 132.177.252.163:51280;rport=51280;received=132.177.252.163;branch=z9hG4bKPjGqaVmgy6QI4hqusA17.VmYbSEjEn1S-y;ingress-zone=DefaultZone
69
Call-ID: wm-ihLWnDBKwk743UftUCoP-H5qoETv-
70
CSeq: 4485 INVITE
71
Contact: <sip:qwbivsgz@132.177.252.111:60868>
72
From: "Adrian Georgescu" <sip:ag01@cisco.sipit.net>;tag=tG4TWxTurelH-rGZRnivbJ96jCHFsQS8
73
To: <sip:ag02@cisco.sipit.net>;tag=nkEDOGe3nL5K7PwTs1-DzrwUmbdzAYUy
74
Record-Route: <sip:proxy-call-id=9041af5c-fe23-11e0-8ac1-000c29ab0f7b@132.177.253.22:5060;transport=udp;lr>
75
Record-Route: <sip:proxy-call-id=9041af5c-fe23-11e0-8ac1-000c29ab0f7b@132.177.253.22:5061;transport=tls;lr>
76
Allow: SUBSCRIBE,NOTIFY,PRACK,INVITE,ACK,BYE,CANCEL,UPDATE,MESSAGE,REFER
77
Server: Blink Pro 1.3.1 (MacOSX)
78
Supported: 100rel,replaces,norefersub
79
Content-Type: application/sdp
80
Content-Length: 255
81
82
v=0
83
o=- 3528437757 3528437758 IN IP4 132.177.252.111
84
s=Blink Pro 1.3.1 (MacOSX)
85
c=IN IP4 132.177.252.111
86
b=AS:384
87
t=0 0
88
m=audio 50018 RTP/AVP 9 101
89
a=rtcp:50019
90
a=rtpmap:9 G722/8000
91
a=rtpmap:101 telephone-event/8000
92
a=fmtp:101 0-15
93
a=sendrecv
94
95
SENDING: Packet 192, +0:14:28.691472
96
2011-10-24 11:35:57.229976: 132.177.252.163:51280 -(SIP over tls)-> 132.177.253.22:5061
97
ACK sip:qwbivsgz@132.177.252.111:60868 SIP/2.0
98
Via: SIP/2.0/tls 132.177.252.163:51280;rport;branch=z9hG4bKPjydOyhHyq82C92apBgV97cGE2r.G2e4GO
99
Max-Forwards: 70
100
From: "Adrian Georgescu" <sip:ag01@cisco.sipit.net>;tag=tG4TWxTurelH-rGZRnivbJ96jCHFsQS8
101
To: <sip:ag02@cisco.sipit.net>;tag=nkEDOGe3nL5K7PwTs1-DzrwUmbdzAYUy
102
Call-ID: wm-ihLWnDBKwk743UftUCoP-H5qoETv-
103
CSeq: 4485 ACK
104
Route: <sip:proxy-call-id=9041af5c-fe23-11e0-8ac1-000c29ab0f7b@132.177.253.22:5061;transport=tls;lr>
105
Route: <sip:proxy-call-id=9041af5c-fe23-11e0-8ac1-000c29ab0f7b@132.177.253.22:5060;transport=udp;lr>
106
User-Agent: Blink Pro 1.3.1 (MacOSX)
107
Content-Length:  0
108
109
SENDING: Packet 193, +0:14:32.184140
110
2011-10-24 11:36:00.722644: 132.177.252.163:51280 -(SIP over tls)-> 132.177.253.22:5061
111
BYE sip:qwbivsgz@132.177.252.111:60868 SIP/2.0
112
Via: SIP/2.0/tls 132.177.252.163:51280;rport;branch=z9hG4bKPjqWIKkD-f1CW8BystKkNHu3QQ.HhB920m
113
Max-Forwards: 70
114
From: "Adrian Georgescu" <sip:ag01@cisco.sipit.net>;tag=tG4TWxTurelH-rGZRnivbJ96jCHFsQS8
115
To: <sip:ag02@cisco.sipit.net>;tag=nkEDOGe3nL5K7PwTs1-DzrwUmbdzAYUy
116
Call-ID: wm-ihLWnDBKwk743UftUCoP-H5qoETv-
117
CSeq: 4486 BYE
118
Route: <sip:proxy-call-id=9041af5c-fe23-11e0-8ac1-000c29ab0f7b@132.177.253.22:5061;transport=tls;lr>
119
Route: <sip:proxy-call-id=9041af5c-fe23-11e0-8ac1-000c29ab0f7b@132.177.253.22:5060;transport=udp;lr>
120
User-Agent: Blink Pro 1.3.1 (MacOSX)
121
Content-Length:  0
122
123
RECEIVED: Packet 194, +0:14:32.185918
124
2011-10-24 11:36:00.724422: 132.177.253.22:5061 -(SIP over tls)-> 132.177.252.163:51280
125
SIP/2.0 407 Proxy Authentication Required
126
Via: SIP/2.0/tls 132.177.252.163:51280;branch=z9hG4bKPjqWIKkD-f1CW8BystKkNHu3QQ.HhB920m;received=132.177.252.163;rport=51280
127
Call-ID: wm-ihLWnDBKwk743UftUCoP-H5qoETv-
128
CSeq: 4486 BYE
129
From: "Adrian Georgescu" <sip:ag01@cisco.sipit.net>;tag=tG4TWxTurelH-rGZRnivbJ96jCHFsQS8
130
To: <sip:ag02@cisco.sipit.net>;tag=nkEDOGe3nL5K7PwTs1-DzrwUmbdzAYUy
131
Server: TANDBERG/4103 (X7.1PreAlpha29 (TEST SW))
132
Proxy-Authenticate: Digest realm="h1.cisco.sipit.net", nonce="ecf10cc969b2858a1f46ce145b58ba291426098f076e4fa29924531f706e", opaque="AQAAANXGRIqnJLpaa2+oaGI3teAaoA95", stale=FALSE, algorithm=MD5, qop="auth"
133
Content-Length: 0
134
135
SENDING: Packet 195, +0:14:32.186342
136
2011-10-24 11:36:00.724846: 132.177.252.163:51280 -(SIP over tls)-> 132.177.253.22:5061
137
BYE sip:qwbivsgz@132.177.252.111:60868 SIP/2.0
138
Via: SIP/2.0/tls 132.177.252.163:51280;rport;branch=z9hG4bKPj7LZjnOhpR57.YTxGAv42MyoF7RSBupjk
139
Max-Forwards: 70
140
From: "Adrian Georgescu" <sip:ag01@cisco.sipit.net>;tag=tG4TWxTurelH-rGZRnivbJ96jCHFsQS8
141
To: <sip:ag02@cisco.sipit.net>;tag=nkEDOGe3nL5K7PwTs1-DzrwUmbdzAYUy
142
Call-ID: wm-ihLWnDBKwk743UftUCoP-H5qoETv-
143
CSeq: 4487 BYE
144
Route: <sip:proxy-call-id=9041af5c-fe23-11e0-8ac1-000c29ab0f7b@132.177.253.22:5061;transport=tls;lr>
145
Route: <sip:proxy-call-id=9041af5c-fe23-11e0-8ac1-000c29ab0f7b@132.177.253.22:5060;transport=udp;lr>
146
User-Agent: Blink Pro 1.3.1 (MacOSX)
147
Proxy-Authorization: Digest username="cisco", realm="h1.cisco.sipit.net", nonce="ecf10cc969b2858a1f46ce145b58ba291426098f076e4fa29924531f706e", uri="sip:qwbivsgz@132.177.252.111:60868", response="6ed728af6b1b5443c953ec89d17151c6", algorithm=MD5, cnonce="WMYZEu5MhMBRJr97hlz3Nxqd9pidFkvZ", opaque="AQAAANXGRIqnJLpaa2+oaGI3teAaoA95", qop=auth, nc=00000001
148
Content-Length:  0
149
150
RECEIVED: Packet 196, +0:14:32.203260
151
2011-10-24 11:36:00.741764: 132.177.253.22:5061 -(SIP over tls)-> 132.177.252.163:51280
152
SIP/2.0 200 OK
153
Via: SIP/2.0/tls 132.177.252.163:51280;rport=51280;received=132.177.252.163;branch=z9hG4bKPj7LZjnOhpR57.YTxGAv42MyoF7RSBupjk;ingress-zone=DefaultZone
154
Call-ID: wm-ihLWnDBKwk743UftUCoP-H5qoETv-
155
CSeq: 4487 BYE
156
From: "Adrian Georgescu" <sip:ag01@cisco.sipit.net>;tag=tG4TWxTurelH-rGZRnivbJ96jCHFsQS8
157
To: <sip:ag02@cisco.sipit.net>;tag=nkEDOGe3nL5K7PwTs1-DzrwUmbdzAYUy
158
Server: Blink Pro 1.3.1 (MacOSX)
159
Content-Length: 0
160
161
2011-10-24 11:36:12.346891: DNS lookup A h1.cisco.sipit.net succeeded, ttl=600: 132.177.253.22
162
163
164
}}}
165 2 Adrian Georgescu
166
{{{
167
2011-10-24 10:42:21.419 Blink Pro[1668:a0f] [Session with ag02@cisco.sipit.net] Ending session with audio stream
168
2011-10-24 10:42:21.457 Blink Pro[1668:6527] Traceback (most recent call last):
169
  File "/Users/adigeo/work/blink/build/Release/Blink Pro.app/Contents/Resources/lib/twisted/internet/base.py", line 757, in runUntilCurrent
170
    call.func(*call.args, **call.kw)
171
  File "/Users/adigeo/work/blink/build/Release/Blink Pro.app/Contents/Resources/lib/eventlet/coros.py", line 253, in _do_acquire
172
    waiter.switch()
173
  File "/Users/adigeo/work/blink/build/Release/Blink Pro.app/Contents/Resources/lib/eventlet/api.py", line 235, in _spawn_startup
174
    return cb(*args, **kw)
175
  File "/Users/adigeo/work/blink/build/Release/Blink Pro.app/Contents/Resources/lib/sipsimple/session.py", line 1904, in end
176
    TimestampedNotificationData(originator='local', method='BYE', code=notification.data.code, reason=notification.data.reason))
177
AttributeError: 'NotificationData' object has no attribute 'code'
178
2011-10-24 10:42:21.474 Blink Pro[1668:a0f] [Session with ag02@cisco.sipit.net] Session will end (local)
179
2011-10-24 10:42:21.643 Blink Pro[1668:a0f] [Session with ag02@cisco.sipit.net] Audio stream ended
180
}}}
181
182
183
184
{{{
185
2011-10-24 10:31:00.239837: Notification name=SIPInvitationChangedState
186
sender=<sipsimple.core._core.Invitation object at 0x18c5ab10>
187
data=NotificationData(originator='local', body=None, prev_sub_state='normal',
188
timestamp=datetime.datetime(2011, 10, 24, 10, 31, 0, 232073), headers={'Via':
189
[FrozenViaHeader('tls', '132.177.252.163', 50951, frozendict({'rport': 0,
190
'branch': 'z9hG4bKPj5bKvhluLSr5OxNTM0vdb4umkCpVNtox8'}))], 'From':
191
FrozenFromHeader(FrozenSIPURI('cisco.sipit.net', 'ag01', None, None, False,
192
frozendict({}), frozendict({})), u'Adrian Georgescu', frozendict({'tag':
193
'kfOPAVWAfIHorykAJBu31vAmIZif780n'})), 'Route':
194
[FrozenRouteHeader(FrozenSIPURI('132.177.253.22',
195
'proxy-call-id=844482fa-fe1a-11e0-bd41-000c29ab0f7b', None, 5061, False,
196
frozendict({'lr': 1, 'transport': 'tls'}), frozendict({})), None,
197
frozendict({})), FrozenRouteHeader(FrozenSIPURI('132.177.253.22',
198
'proxy-call-id=844482fa-fe1a-11e0-bd41-000c29ab0f7b', None, 5061, False,
199
frozendict({'lr': 1, 'transport': 'tls'}), frozendict({})), None,
200
frozendict({}))], 'User-Agent': FrozenHeader('User-Agent', 'Blink Pro 1.3.1
201
(MacOSX)'), 'To': FrozenToHeader(FrozenSIPURI('cisco.sipit.net', 'ag02', None,
202
None, False, frozendict({}), frozendict({})), None, frozendict({'tag':
203
'-9Gm-krVFw154HwQh5W.UsZY12oHT9di'})), 'CSeq': (8401, 'BYE'), 'Call-ID':
204
FrozenHeader('Call-ID', 'JzYfmKI6hZg0iBD9t4WdpKNhW6SyQzlm'), 'Max-Forwards':
205
70}, state='disconnecting', prev_state='connected', method='BYE',
206
request_uri=FrozenSIPURI('132.177.252.111', 'qwbivsgz', None, 51564, False,
207
frozendict({'transport': 'tls'}), frozendict({})))
208
209
210
2011-10-24 10:31:00.259586: Notification name=SIPInvitationChangedState
211
sender=<sipsimple.core._core.Invitation object at 0x18c5ab10>
212
data=NotificationData(originator='local', disconnect_reason='user request',
213
state='disconnected', prev_state='disconnecting',
214 1 Adrian Georgescu
timestamp=datetime.datetime(2011, 10, 24, 10, 31, 0, 250102))
215 3 Adrian Georgescu
}}}
216
217
218
200 OK rdata seems to be none for received 200OK for the sent out BYE
219
220
221
=== UDP trace that works ===
222
223
{{{
224
2011-10-24 10:46:07.277414: Notification name=SIPInvitationChangedState
225
sender=<sipsimple.core._core.Invitation object at 0x18c5abf8>
226
data=NotificationData(originator='local', body=None, prev_sub_state='normal',
227
timestamp=datetime.datetime(2011, 10, 24, 10, 46, 7, 268879), headers={'Via':
228
[FrozenViaHeader('UDP', '132.177.252.163', 60653, frozendict({'rport': 0,
229
'branch': 'z9hG4bKPjVWjI8ftu8DW7rR5WhnOsbgpAWQFpbFYT'}))], 'From':
230
FrozenFromHeader(FrozenSIPURI('cisco.sipit.net', 'ag01', None, None, False,
231
frozendict({}), frozendict({})), u'Adrian Georgescu', frozendict({'tag':
232
'M5uaZcSknKymRnkGTjRG88C8DG99m5Hy'})), 'Route':
233
[FrozenRouteHeader(FrozenSIPURI('132.177.253.22',
234
'proxy-call-id=99e57f04-fe1c-11e0-ba17-000c29ab0f7b', None, 5060, False,
235
frozendict({'lr': 1, 'transport': 'udp'}), frozendict({})), None,
236
frozendict({})), FrozenRouteHeader(FrozenSIPURI('132.177.253.22',
237
'proxy-call-id=99e57f04-fe1c-11e0-ba17-000c29ab0f7b', None, 5060, False,
238
frozendict({'lr': 1, 'transport': 'tcp'}), frozendict({})), None,
239
frozendict({}))], 'User-Agent': FrozenHeader('User-Agent', 'Blink Pro 1.3.1
240
(MacOSX)'), 'To': FrozenToHeader(FrozenSIPURI('cisco.sipit.net', 'ag02', None,
241
None, False, frozendict({}), frozendict({})), None, frozendict({'tag':
242
'hNd9U3UKc.VP-1WWYP-S8MegH2yeRc5e'})), 'CSeq': (28697, 'BYE'), 'Call-ID':
243
FrozenHeader('Call-ID', '41QYdm0-Eny2uHKzgETWx57HhGq47xPl'), 'Max-Forwards':
244
70}, state='disconnecting', prev_state='connected', method='BYE',
245
request_uri=FrozenSIPURI('132.177.252.111', 'qwbivsgz', None, 51252, False,
246
frozendict({'transport': 'tcp'}), frozendict({})))
247
248
249
2011-10-24 10:46:07.293137: Notification name=SIPInvitationChangedState
250
sender=<sipsimple.core._core.Invitation object at 0x18c5abf8>
251
data=NotificationData(originator='remote', body=None, code=200,
252
disconnect_reason='user request', timestamp=datetime.datetime(2011, 10, 24, 10,
253
46, 7, 287860), state='disconnected', headers={'Content-Length': 0, 'Via':
254
[FrozenViaHeader('UDP', '132.177.252.163', 60653, frozendict({'received':
255
'132.177.252.163', 'ingress-zone': 'DefaultSubZone', 'rport': 60653, 'branch':
256
'z9hG4bKPjB6pOFsn58EsyG4sXhsB.72oClDLkXjEW'}))], 'From':
257
FrozenFromHeader(FrozenSIPURI('cisco.sipit.net', 'ag01', None, None, False,
258
frozendict({}), frozendict({})), u'Adrian Georgescu', frozendict({'tag':
259
'M5uaZcSknKymRnkGTjRG88C8DG99m5Hy'})), 'Server': FrozenHeader('Server', 'Blink
260
Pro 1.3.1 (MacOSX)'), 'To': FrozenToHeader(FrozenSIPURI('cisco.sipit.net',
261
'ag02', None, None, False, frozendict({}), frozendict({})), None,
262
frozendict({'tag': 'hNd9U3UKc.VP-1WWYP-S8MegH2yeRc5e'})), 'CSeq': (28698,
263
'BYE'), 'Call-ID': FrozenHeader('Call-ID', '41QYdm0-Eny2uHKzgETWx57HhGq47xPl')},
264
reason='OK', prev_state='disconnecting')
265 2 Adrian Georgescu
}}}