Comment #1 on issue 190 by [email protected]: Container doesn't send
send ACK to 487
http://code.google.com/p/sipservlets/issues/detail?id=190
Log entries for a test call. Using Tomcat and log level set to FINEST
2013-01-22 15:25:57,186 INFO [CallDownOneShotResource] (http-8080-1) A
call back for a call with ID CA430c63259abd45b292170109a15b002e is being
sent to null the contents are enclosed below.
{"Resident":false,"ExternalID":"6675647064140959745","ResponderPhoneNumber":"9549376176","QuestionNumber":0,"TypeOfResponse":900,"LibraryName":"HealthSense","ScriptName":"CallDownOneShot"}
2013-01-22 15:25:57,187 INFO [CallDownOneShotResource] (http-8080-1)
Starting a new call from +19524007201 To +19549376176. The application
located @
http://10.0.6.73:8080/translator/scripts/call-down-one-shot/translator will
be executed during this call.Finally, all callbacks will be made to
http://10.0.6.73:8080/translator/scripts/call-down-one-shot/responder-call-cleanup
2013-01-22 15:25:57,455 INFO [TranslatorResource] (http-8080-1) RCML
Response for call with ID CA430c63259abd45b292170109a15b002e.
<Response><Gather numDigits="2" finishOnKey="1"
action="input-accept?Attempts=1" timeout="30"><Say><![CDATA[<voice
gender="male"><prosody rate="default">Pull Cord of Room 207 in room 2 0 7
is requesting assistance. Pull Cord of Room 207 in room 2 0 7 is
requesting assistance. Pull Cord of Room 207 in room 2 0 7 is requesting
assistance. </prosody></voice>]]></Say><Say><![CDATA[<voice
gender="male"><prosody rate="default">Please enter your access code
followed by the pound key.
</prosody></voice>]]></Say></Gather><Redirect>translator?Attempts=1</Redirect></Response>
2013-01-22 15:25:57,844 INFO [SIPTransactionStack] (pool-4-thread-1)
<message
from="10.0.6.73:5080"
to="68.68.118.47:5060"
time="1358889957838"
isSender="true"
transactionId="z9hg4bk7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529"
callId="[email protected]"
firstLine="INVITE sip:[email protected] SIP/2.0"
<![CDATA[INVITE sip:[email protected] SIP/2.0
Call-ID: [email protected]
CSeq: 1 INVITE
From:
<sip:[email protected]>;tag=14659765_57a5b08a_7e385d13-7774-46a4-9c74-36a412278334
To: <sip:[email protected]>
Max-Forwards: 70
Contact: <sip:[email protected]:5080>
Route: <sip:68.68.118.47;lr>
Content-Type: application/sdp
Via: SIP/2.0/UDP
10.0.6.73:5080;branch=z9hG4bK7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529
Content-Length: 248
v=0
o=- 1358889957540 1 IN IP4 10.0.6.73
s=Mobicents Media Server
c=IN IP4 10.0.6.73
t=0 0
m=audio 63666 RTP/AVP 97 8 0 101
a=rtpmap:97 l16/8000
a=rtpmap:8 pcma/8000
a=rtpmap:0 pcmu/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
]]>
</message>
2013-01-22 15:25:57,876 INFO [SIPTransactionStack]
(Mobicents-SIP-Servlets-UDPMessageChannelThread-0) <message
from="68.68.118.47:5060"
to="10.0.6.73:5080"
time="1358889957875"
isSender="false"
transactionId="z9hg4bk7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529"
callId="[email protected]"
firstLine="SIP/2.0 100 Trying"
<![CDATA[SIP/2.0 100 Trying
Via: SIP/2.0/UDP
10.0.6.73:5080;branch=z9hG4bK7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529
From:
<sip:[email protected]>;tag=14659765_57a5b08a_7e385d13-7774-46a4-9c74-36a412278334
To: <sip:[email protected]>;tag=gK04812019
Call-ID: [email protected]
CSeq: 1 INVITE
Content-Length: 0
]]>
</message>
2013-01-22 15:26:00,023 INFO [ResourceResolverBean] (scheduler_Worker-8)
Reading CONTEXT /translator
2013-01-22 15:26:00,024 INFO [ResourceResolverBean] (scheduler_Worker-8)
Reading CONTEXT
2013-01-22 15:26:00,024 INFO [ResourceResolverBean] (scheduler_Worker-8)
Reading CONTEXT /manager
2013-01-22 15:26:00,025 INFO [ResourceResolverBean] (scheduler_Worker-8)
Reading CONTEXT /sip-servlets-management
2013-01-22 15:26:00,025 INFO [ResourceResolverBean] (scheduler_Worker-8)
Reading CONTEXT /docs
2013-01-22 15:26:00,025 INFO [ResourceResolverBean] (scheduler_Worker-8)
Reading CONTEXT /examples
2013-01-22 15:26:00,025 INFO [ResourceResolverBean] (scheduler_Worker-8)
Reading CONTEXT /host-manager
2013-01-22 15:26:00,025 INFO [ResourceResolverBean] (scheduler_Worker-8)
Reading CONTEXT /probe
2013-01-22 15:26:00,025 INFO [ResourceResolverBean] (scheduler_Worker-8)
Reading CONTEXT /click2call
2013-01-22 15:26:00,025 INFO [ResourceResolverBean] (scheduler_Worker-8)
Reading CONTEXT /mobicentsreply
2013-01-22 15:26:00,025 INFO [ResourceResolverBean] (scheduler_Worker-8)
Reading CONTEXT /restcomm
2013-01-22 15:26:00,026 INFO [ResourceResolverBean] (scheduler_Worker-8)
Reading GLOBAL resources
2013-01-22 15:26:00,039 INFO [ResourceResolverBean] (scheduler_Worker-8)
reading resource:
Catalina:type=Resource,resourcetype=Global,class=org.apache.catalina.UserDatabase,name="UserDatabase"
2013-01-22 15:26:00,485 INFO [SIPTransactionStack]
(Mobicents-SIP-Servlets-UDPMessageChannelThread-1) <message
from="68.68.118.47:5060"
to="10.0.6.73:5080"
time="1358889960484"
isSender="false"
transactionId="z9hg4bk7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529"
callId="[email protected]"
firstLine="SIP/2.0 183 Session Progress"
<![CDATA[SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP
10.0.6.73:5080;branch=z9hG4bK7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529
From:
<sip:[email protected]>;tag=14659765_57a5b08a_7e385d13-7774-46a4-9c74-36a412278334
To: <sip:[email protected]>;tag=gK04812019
Call-ID: [email protected]
CSeq: 1 INVITE
Contact: <sip:[email protected]:5060>
Allow: INVITE,ACK,CANCEL,BYE,PRACK,UPDATE,OPTIONS,MESSAGE,PUBLISH
Content-Disposition: session;handling=required
Content-Type: application/sdp
Content-Length: 231
v=0
o=Sonus_UAC 8153 2713 IN IP4 68.68.118.47
s=SIP Media Capabilities
c=IN IP4 68.68.118.43
t=0 0
m=audio 28918 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=ptime:20
]]>
</message>
2013-01-22 15:26:00,508 INFO [ResponderCallCleanupResource] (http-8080-3)
Responder call with ID CA430c63259abd45b292170109a15b002e ended with the
following status ringing.
2013-01-22 15:26:00,510 INFO [ResponderCallCleanupResource] (http-8080-3)
A call back for a call with ID CA430c63259abd45b292170109a15b002e is being
sent to null the contents are enclosed below.
{"Resident":false,"ExternalID":"6675647064140959745","ResponderPhoneNumber":"9549376176","QuestionNumber":0,"TypeOfResponse":901,"LibraryName":"HealthSense","ScriptName":"CallDownOneShot"}
2013-01-22 15:26:10,516 INFO [SIPTransactionStack] (pool-4-thread-1)
<message
from="10.0.6.73:5080"
to="68.68.118.47:5060"
time="1358889970515"
isSender="true"
transactionId="z9hg4bk7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529:cancel"
callId="[email protected]"
firstLine="CANCEL sip:[email protected] SIP/2.0"
<![CDATA[CANCEL sip:[email protected] SIP/2.0
Call-ID: [email protected]
To: <sip:[email protected]>
CSeq: 1 CANCEL
From:
<sip:[email protected]>;tag=14659765_57a5b08a_7e385d13-7774-46a4-9c74-36a412278334
Via: SIP/2.0/UDP
10.0.6.73:5080;branch=z9hG4bK7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529
Max-Forwards: 70
Route: <sip:68.68.118.47;lr>
Content-Length: 0
]]>
</message>
2013-01-22 15:26:10,520 INFO [SipSessionImpl] (pool-4-thread-1)
Invalidating the sip session
(14659765_57a5b08a_7e385d13-7774-46a4-9c74-36a412278334:[email protected]:7e385d13-7774-46a4-9c74-36a412278334:RestComm)
2013-01-22 15:26:10,542 INFO [ResponderCallCleanupResource] (http-8080-4)
Responder call with ID CA430c63259abd45b292170109a15b002e ended with the
following status cancelled.
2013-01-22 15:26:10,544 INFO [ResponderCallCleanupResource] (http-8080-4)
A call back for a call with ID CA430c63259abd45b292170109a15b002e is being
sent to null the contents are enclosed below.
{"Resident":false,"ExternalID":"6675647064140959745","ResponderPhoneNumber":"9549376176","QuestionNumber":0,"TypeOfResponse":0,"LibraryName":"HealthSense","ScriptName":"CallDownOneShot"}
2013-01-22 15:26:10,549 INFO [SIPTransactionStack]
(Mobicents-SIP-Servlets-UDPMessageChannelThread-2) <message
from="68.68.118.47:5060"
to="10.0.6.73:5080"
time="1358889970549"
isSender="false"
transactionId="z9hg4bk7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529:cancel"
callId="[email protected]"
firstLine="SIP/2.0 200 OK"
<![CDATA[SIP/2.0 200 OK
Via: SIP/2.0/UDP
10.0.6.73:5080;branch=z9hG4bK7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529
From:
<sip:[email protected]>;tag=14659765_57a5b08a_7e385d13-7774-46a4-9c74-36a412278334
To: <sip:[email protected]>;tag=gK04812019
Call-ID: [email protected]
CSeq: 1 CANCEL
Content-Length: 0
]]>
</message>
2013-01-22 15:26:10,551 INFO [SIPTransactionStack]
(Mobicents-SIP-Servlets-UDPMessageChannelThread-2) <message
from="68.68.118.47:5060"
to="10.0.6.73:5080"
time="1358889970551"
isSender="false"
transactionId="z9hg4bk7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529"
callId="[email protected]"
firstLine="SIP/2.0 487 Request Terminated"
<![CDATA[SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP
10.0.6.73:5080;branch=z9hG4bK7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529
From:
<sip:[email protected]>;tag=14659765_57a5b08a_7e385d13-7774-46a4-9c74-36a412278334
To: <sip:[email protected]>;tag=gK04812019
Call-ID: [email protected]
CSeq: 1 INVITE
Content-Length: 0
]]>
</message>
2013-01-22 15:26:11,044 INFO [SIPTransactionStack]
(Mobicents-SIP-Servlets-UDPMessageChannelThread-4) <message
from="68.68.118.47:5060"
to="10.0.6.73:5080"
time="1358889971043"
isSender="false"
transactionId="z9hg4bk7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529"
callId="[email protected]"
firstLine="SIP/2.0 487 Request Terminated"
<![CDATA[SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP
10.0.6.73:5080;branch=z9hG4bK7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529
From:
<sip:[email protected]>;tag=14659765_57a5b08a_7e385d13-7774-46a4-9c74-36a412278334
To: <sip:[email protected]>;tag=gK04812019
Call-ID: [email protected]
CSeq: 1 INVITE
Content-Length: 0
]]>
</message>
2013-01-22 15:26:12,045 INFO [SIPTransactionStack]
(Mobicents-SIP-Servlets-UDPMessageChannelThread-5) <message
from="68.68.118.47:5060"
to="10.0.6.73:5080"
time="1358889972043"
isSender="false"
transactionId="z9hg4bk7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529"
callId="[email protected]"
firstLine="SIP/2.0 487 Request Terminated"
<![CDATA[SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP
10.0.6.73:5080;branch=z9hG4bK7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529
From:
<sip:[email protected]>;tag=14659765_57a5b08a_7e385d13-7774-46a4-9c74-36a412278334
To: <sip:[email protected]>;tag=gK04812019
Call-ID: [email protected]
CSeq: 1 INVITE
Content-Length: 0
]]>
</message>
2013-01-22 15:26:14,044 INFO [SIPTransactionStack]
(Mobicents-SIP-Servlets-UDPMessageChannelThread-6) <message
from="68.68.118.47:5060"
to="10.0.6.73:5080"
time="1358889974043"
isSender="false"
transactionId="z9hg4bk7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529"
callId="[email protected]"
firstLine="SIP/2.0 487 Request Terminated"
<![CDATA[SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP
10.0.6.73:5080;branch=z9hG4bK7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529
From:
<sip:[email protected]>;tag=14659765_57a5b08a_7e385d13-7774-46a4-9c74-36a412278334
To: <sip:[email protected]>;tag=gK04812019
Call-ID: [email protected]
CSeq: 1 INVITE
Content-Length: 0
]]>
</message>
2013-01-22 15:26:18,044 INFO [SIPTransactionStack]
(Mobicents-SIP-Servlets-UDPMessageChannelThread-7) <message
from="68.68.118.47:5060"
to="10.0.6.73:5080"
time="1358889978044"
isSender="false"
transactionId="z9hg4bk7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529"
callId="[email protected]"
firstLine="SIP/2.0 487 Request Terminated"
<![CDATA[SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP
10.0.6.73:5080;branch=z9hG4bK7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529
From:
<sip:[email protected]>;tag=14659765_57a5b08a_7e385d13-7774-46a4-9c74-36a412278334
To: <sip:[email protected]>;tag=gK04812019
Call-ID: [email protected]
CSeq: 1 INVITE
Content-Length: 0
]]>
</message>
2013-01-22 15:26:22,044 INFO [SIPTransactionStack]
(Mobicents-SIP-Servlets-UDPMessageChannelThread-8) <message
from="68.68.118.47:5060"
to="10.0.6.73:5080"
time="1358889982044"
isSender="false"
transactionId="z9hg4bk7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529"
callId="[email protected]"
firstLine="SIP/2.0 487 Request Terminated"
<![CDATA[SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP
10.0.6.73:5080;branch=z9hG4bK7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529
From:
<sip:[email protected]>;tag=14659765_57a5b08a_7e385d13-7774-46a4-9c74-36a412278334
To: <sip:[email protected]>;tag=gK04812019
Call-ID: [email protected]
CSeq: 1 INVITE
Content-Length: 0
]]>
</message>
2013-01-22 15:26:26,047 INFO [SIPTransactionStack]
(Mobicents-SIP-Servlets-UDPMessageChannelThread-9) <message
from="68.68.118.47:5060"
to="10.0.6.73:5080"
time="1358889986047"
isSender="false"
transactionId="z9hg4bk7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529"
callId="[email protected]"
firstLine="SIP/2.0 487 Request Terminated"
<![CDATA[SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP
10.0.6.73:5080;branch=z9hG4bK7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529
From:
<sip:[email protected]>;tag=14659765_57a5b08a_7e385d13-7774-46a4-9c74-36a412278334
To: <sip:[email protected]>;tag=gK04812019
Call-ID: [email protected]
CSeq: 1 INVITE
Content-Length: 0
]]>
</message>
2013-01-22 15:26:30,044 INFO [SIPTransactionStack]
(Mobicents-SIP-Servlets-UDPMessageChannelThread-10) <message
from="68.68.118.47:5060"
to="10.0.6.73:5080"
time="1358889990043"
isSender="false"
transactionId="z9hg4bk7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529"
callId="[email protected]"
firstLine="SIP/2.0 487 Request Terminated"
<![CDATA[SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP
10.0.6.73:5080;branch=z9hG4bK7e385d13-7774-46a4-9c74-36a412278334_57a5b08a_13417566032812529
From:
<sip:[email protected]>;tag=14659765_57a5b08a_7e385d13-7774-46a4-9c74-36a412278334
To: <sip:[email protected]>;tag=gK04812019
Call-ID: [email protected]
CSeq: 1 INVITE
Content-Length: 0
]]>
</message>
