unimrcpserver pause response for 20 minutes

dd

unread,

Nov 20, 2009, 10:59:39 AM11/20/09

to UniMRCP

I have been trying make a call to unimrcpserver every 10 seconds for
speech recognition, it works well first, then the server stops
reponse, even though calls are still made. After around 20 minutes,
the server resumes taking calls.

I am just wondering if there are any timeout around 20 minutes that
may causes this pause. It seems like the server need to close some
connections or resources before taking new calls.

Thanks,

Arsen Chaloyan

unread,

Nov 20, 2009, 1:10:14 PM11/20/09

to uni...@googlegroups.com

Given only the brief description of the scenario, it's hard to
identify what is going on.

Try to increase max number of connections in a pollset. The default
value is 100.
<param name="max-connection-count" value="100"/>

I wonder whether server rejected incoming calls during that period or
didn't respond at all. Which ASR engine do you use with UniMRCP. Is it
MRCP v1 or v2? How long does a typical recognition session last?

--
Arsen Chaloyan
The author of UniMRCP
http://www.unimrcp.org

dd

unread,

Nov 20, 2009, 1:36:03 PM11/20/09

to UniMRCP

Hello, thanks for reply, Arsen,

I use demorecog plugin and actually no real recognitions are made, for
each call, in demo_recog_channel_recognize, I just create a new thread
which sleeps 200ms before returns a string as the recognition result.

On Nov 20, 1:10 pm, Arsen Chaloyan <achalo...@gmail.com> wrote:
> Given only the brief description of the scenario, it's hard to
> identify what is going on.
>
> Try to increase max number of connections in a pollset. The default
> value is 100.
> <param name="max-connection-count" value="100"/>
>
> I wonder whether server rejected incoming calls during that period or
> didn't respond at all. Which ASR engine do you use with UniMRCP. Is it
> MRCP v1 or v2? How long does a typical recognition session last?
>

Arsen Chaloyan

unread,

Nov 20, 2009, 1:58:05 PM11/20/09

to uni...@googlegroups.com

Increase max connection count and provide logs if it doesn't help.

dd

unread,

Nov 20, 2009, 3:11:05 PM11/20/09

to UniMRCP

Thanks, Arsen,

I increase the max connection to 400, but the same issue showed up.

Here is the log before the server stopped responding: the server was
still alive because it will accept 'quit' command and after 20 minutes
it starts taking calls again.

2009-11-20 15:00:28:268268 [INFO] Recieve SIP Event [nua_i_state]
Status 200 O
K
2009-11-20 15:00:28:268268 [NOTICE] Accepted TCP/MRCPv2 Connection
205.207.240.2
8:4229
2009-11-20 15:00:28:268268 [NOTICE] SIP Call State [completed]
2009-11-20 15:00:28:268268 [INFO] Recieve SIP Event [nua_i_ack]
Status 200 OK
2009-11-20 15:00:28:268268 [INFO] Recieve SIP Event [nua_i_state]
Status 200 O
K
2009-11-20 15:00:28:268268 [NOTICE] SIP Call State [ready]
2009-11-20 15:00:28:268268 [INFO] Recieve SIP Event [nua_i_active]
Status 200
Call active
2009-11-20 15:00:29:783864 [INFO] Receive MRCPv2 Message size=771
MRCP/2.0 771 RECOGNIZE 2938
Cancel-If-Queue: false
Channel-Identifier: db483c4594b75843@speechrecog
Content-Type: application/srgs+xml
Start-Input-Timers: false
Content-Length: 423
Recognition-Timeout:15000
N-Best-List-Length:10
Save-Waveform:false
Speech-Complete-Timeout:2000
Speech-Incomplete-Timeout:2000
Content-Id:rec_gram22428

<?xml version="1.0" encoding="UTF-8"?>
<grammar xmlns="http://www.w3.org/2001/06/grammar" xmlns:xsi="http://
www.w3.org/
2001/XMLSchema-instance" xsi:schemaLocation="http://www.w3.org/2001/06/
grammar
http://www.w3.org/TR/speech-grammar/grammar.xsd" xml:lang="en-US"
version="1.0"
root="_boolean" mode="voice">
<rule id="_boolean" scope="public">
<one-of>
<item>one</item>
<item>three</item>
</one-of>
</rule>
</grammar>

2009-11-20 15:00:29:815113 [INFO] Move Control Channel
<db483c4594b75843@speec
hrecog> to Connection [1]
2009-11-20 15:00:29:815113 [INFO] Process RECOGNIZE Request [2938]
2009-11-20 15:00:29:830738 [INFO] Process RECOGNIZE Response [2938]
2009-11-20 15:00:29:830738 [INFO] State Transition IDLE ->
RECOGNIZING
2009-11-20 15:00:29:830738 [INFO] Send MRCPv2 Message size=86
MRCP/2.0 86 2938 200 IN-PROGRESS
Channel-Identifier:db483c4594b75843@speechrecog

end0 2009-11-20 15:00:29:877612 [INFO] Process RECOGNITION-COMPLETE
Event [293
8]
2009-11-20 15:00:29:877612 [INFO] State Transition RECOGNIZING ->
RECOGNIZED
2009-11-20 15:00:29:877612 [INFO] Send MRCPv2 Message size=271
MRCP/2.0 271 RECOGNITION-COMPLETE 2938 COMPLETE
Channel-Identifier:db483c4594b75843@speechrecog
Completion-Cause:000 success
Content-Length:121

<?xml version="1.0"?><result><interpretation><input>one </
input><confidence>100.
00</confidence></interpretation></result>
2009-11-20 15:00:34:127530 [INFO] Recieve SIP Event [nua_i_bye]
Status 200 Ses
sion Terminated
2009-11-20 15:00:34:127530 [NOTICE] TCP/MRCPv2 Connection Disconnected
2009-11-20 15:00:34:127530 [INFO] Recieve SIP Event [nua_i_state]
Status 200 S
ession Terminated
2009-11-20 15:00:34:127530 [NOTICE] SIP Call State [terminated]
2009-11-20 15:00:34:127530 [INFO] Receive Terminate Request
<db483c4594b75843>

2009-11-20 15:00:34:127530 [INFO] Recieve SIP Event
[nua_i_terminated] Status
200 Session Terminated
2009-11-20 15:00:34:143155 [INFO] Remove Control Channel
<db483c4594b75843@spe
echrecog> [0]
2009-11-20 15:00:34:143155 [NOTICE] Remove Session <db483c4594b75843>
2009-11-20 15:00:34:143155 [INFO] Close RTP Receive
205.207.240.127:5112 <- 20
5.207.240.28:43790 [r:9 l:0 j:334]
2009-11-20 15:00:34:143155 [NOTICE] Destroy TCP/MRCPv2 Connection
2009-11-20 15:00:34:143155 [INFO] Send Terminate Response
<db483c4594b75843>
2009-11-20 15:00:34:143155 [NOTICE] Destroy Session
2009-11-20 15:00:39:611800 [INFO] Recieve SIP Event [nua_i_invite]
Status 100
Trying
2009-11-20 15:00:39:611800 [INFO] Recieve SIP Event [nua_i_state]
Status 100 T
rying
2009-11-20 15:00:39:611800 [NOTICE] SIP Call State [received]
2009-11-20 15:00:39:611800 [INFO] Found Profile [MRCPv2-Default]
2009-11-20 15:00:39:611800 [INFO] Remote SDP
v=0
o=- 1 1 IN IP4 205.207.240.28
s="MrcpClient"
c=IN IP4 205.207.240.28
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:new
a=resource:speechrecog
a=cmid:1
m=audio 43792 RTP/AVP 0 8
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=sendonly
a=mid:1

2009-11-20 15:00:39:627425 [NOTICE] Add Session <9abe2d427a983645>
2009-11-20 15:00:39:627425 [INFO] Receive Offer <9abe2d427a983645>
[c:1 a:1 v:
0]
2009-11-20 15:00:39:627425 [INFO] Add Control Channel
<9abe2d427a983645@speech
recog> to Pending Connection [1]
2009-11-20 15:00:39:627425 [INFO] Open RTP Receive
205.207.240.127:5114 <- 205
.207.240.28:43792 playout [50 ms]
2009-11-20 15:00:39:643049 [INFO] Send Answer <9abe2d427a983645> [c:
1 a:1 v:0]

2009-11-20 15:00:39:643049 [INFO] Local SDP
v=0
o=UniMRCPServer 0 0 IN IP4 205.207.240.127
s=-
c=IN IP4 205.207.240.127
t=0 0
m=application 1544 TCP/MRCPv2 1
a=setup:passive
a=connection:new
a=channel:9abe2d427a983645@speechrecog
a=cmid:1
m=audio 5114 RTP/AVP 0 8
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=recvonly
a=mid:1

2009-11-20 15:00:39:658674 [INFO] Recieve SIP Event [nua_i_state]
Status 200 O
K
2009-11-20 15:00:39:658674 [NOTICE] Accepted TCP/MRCPv2 Connection
205.207.240.2
8:4231
2009-11-20 15:00:39:658674 [NOTICE] SIP Call State [completed]
2009-11-20 15:00:39:658674 [INFO] Recieve SIP Event [nua_i_ack]
Status 200 OK
2009-11-20 15:00:39:658674 [INFO] Recieve SIP Event [nua_i_state]
Status 200 O
K
2009-11-20 15:00:39:658674 [NOTICE] SIP Call State [ready]
2009-11-20 15:00:39:658674 [INFO] Recieve SIP Event [nua_i_active]
Status 200
Call active
2009-11-20 15:00:41:174270 [INFO] Receive MRCPv2 Message size=771
MRCP/2.0 771 RECOGNIZE 2940
Cancel-If-Queue: false
Channel-Identifier: 9abe2d427a983645@speechrecog
Content-Type: application/srgs+xml
Start-Input-Timers: false
Content-Length: 423
Recognition-Timeout:15000
N-Best-List-Length:10
Save-Waveform:false
Speech-Complete-Timeout:2000
Speech-Incomplete-Timeout:2000
Content-Id:rec_gram12993

<?xml version="1.0" encoding="UTF-8"?>
<grammar xmlns="http://www.w3.org/2001/06/grammar" xmlns:xsi="http://
www.w3.org/
2001/XMLSchema-instance" xsi:schemaLocation="http://www.w3.org/2001/06/
grammar
http://www.w3.org/TR/speech-grammar/grammar.xsd" xml:lang="en-US"
version="1.0"
root="_boolean" mode="voice">
<rule id="_boolean" scope="public">
<one-of>
<item>one</item>
<item>three</item>
</one-of>
</rule>
</grammar>

2009-11-20 15:00:41:205519 [INFO] Move Control Channel
<9abe2d427a983645@speec
hrecog> to Connection [1]
2009-11-20 15:00:41:205519 [INFO] Process RECOGNIZE Request [2940]
2009-11-20 15:00:41:221144 [INFO] Process RECOGNIZE Response [2940]
2009-11-20 15:00:41:221144 [INFO] State Transition IDLE ->
RECOGNIZING
2009-11-20 15:00:41:221144 [INFO] Send MRCPv2 Message size=86
MRCP/2.0 86 2940 200 IN-PROGRESS
Channel-Identifier:9abe2d427a983645@speechrecog

end0 2009-11-20 15:00:41:268018 [INFO] Process RECOGNITION-COMPLETE
Event [294
0]
2009-11-20 15:00:41:268018 [INFO] State Transition RECOGNIZING ->
RECOGNIZED
2009-11-20 15:00:41:268018 [INFO] Send MRCPv2 Message size=271
MRCP/2.0 271 RECOGNITION-COMPLETE 2940 COMPLETE
Channel-Identifier:9abe2d427a983645@speechrecog
Completion-Cause:000 success
Content-Length:121

<?xml version="1.0"?><result><interpretation><input>one </
input><confidence>100.
00</confidence></interpretation></result>
2009-11-20 15:00:45:517937 [INFO] Recieve SIP Event [nua_i_bye]
Status 200 Ses
sion Terminated
2009-11-20 15:00:45:517937 [NOTICE] TCP/MRCPv2 Connection Disconnected
2009-11-20 15:00:45:517937 [INFO] Recieve SIP Event [nua_i_state]
Status 200 S
ession Terminated
2009-11-20 15:00:45:517937 [NOTICE] SIP Call State [terminated]
2009-11-20 15:00:45:517937 [INFO] Recieve SIP Event
[nua_i_terminated] Status
200 Session Terminated
2009-11-20 15:00:45:517937 [INFO] Receive Terminate Request
<9abe2d427a983645>

2009-11-20 15:00:45:517937 [NOTICE] Remove Session <9abe2d427a983645>
2009-11-20 15:00:45:517937 [INFO] Remove Control Channel
<9abe2d427a983645@spe
echrecog> [0]
2009-11-20 15:00:45:517937 [INFO] Close RTP Receive
205.207.240.127:5114 <- 20
5.207.240.28:43792 [r:7 l:0 j:281]
2009-11-20 15:00:45:533561 [NOTICE] Destroy TCP/MRCPv2 Connection
2009-11-20 15:00:45:533561 [INFO] Send Terminate Response
<9abe2d427a983645>
2009-11-20 15:00:45:533561 [NOTICE] Destroy Session

> The author of UniMRCPhttp://www.unimrcp.org- Hide quoted text -
>
> - Show quoted text -

dd

unread,

Nov 20, 2009, 3:27:22 PM11/20/09

to UniMRCP

Hi, Arsen, I just reformatted the log a bit so it is more easy to
read, by the way, I just noted that there is an item in the log:

2009-11-20 15:00:28:237018 [INFO] Open RTP Receive
205.207.240.127:5112 <- 205.207.240.28:43790 playout [50 ms]

what is the 'playout[50 ms]' supposed to be? is it the length of a
speech audio frame? could it be possible that the issue was caused by
waiting for speech input?

Attachment: log

2009-11-20 15:00:28:221394 [INFO] Recieve SIP Event [nua_i_invite]
Status 100 Trying
2009-11-20 15:00:28:221394 [INFO] Recieve SIP Event [nua_i_state]
Status 100 Trying
2009-11-20 15:00:28:221394 [NOTICE] SIP Call State [received]
2009-11-20 15:00:28:221394 [INFO] Found Profile [MRCPv2-Default]
2009-11-20 15:00:28:221394 [INFO] Remote SDP

v=0
o=- 1 1 IN IP4 205.207.240.28
s="MrcpClient"
c=IN IP4 205.207.240.28
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:new
a=resource:speechrecog
a=cmid:1

m=audio 43790 RTP/AVP 0 8

a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=sendonly
a=mid:1

2009-11-20 15:00:28:237018 [NOTICE] Add Session <db483c4594b75843>
2009-11-20 15:00:28:237018 [INFO] Receive Offer <db483c4594b75843>

[c:1 a:1 v:0]

2009-11-20 15:00:28:237018 [INFO] Add Control Channel
<db483c4594b75843@speechrecog> to Pending Connection [1]
2009-11-20 15:00:28:237018 [INFO] Open RTP Receive
205.207.240.127:5112 <- 205.207.240.28:43790 playout [50 ms]
2009-11-20 15:00:28:252643 [INFO] Send Answer <db483c4594b75843> [c:

1 a:1 v:0]

2009-11-20 15:00:28:252643 [INFO] Local SDP

v=0
o=UniMRCPServer 0 0 IN IP4 205.207.240.127
s=-
c=IN IP4 205.207.240.127
t=0 0
m=application 1544 TCP/MRCPv2 1
a=setup:passive
a=connection:new

a=channel:db483c4594b75843@speechrecog
a=cmid:1
m=audio 5112 RTP/AVP 0 8

a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=recvonly
a=mid:1

2009-11-20 15:00:28:268268 [INFO] Recieve SIP Event [nua_i_state]

Status 200 OK
2009-11-20 15:00:28:268268 [NOTICE] Accepted TCP/MRCPv2 Connection
205.207.240.28:4229

2009-11-20 15:00:28:268268 [NOTICE] SIP Call State [completed]
2009-11-20 15:00:28:268268 [INFO] Recieve SIP Event [nua_i_ack]
Status 200 OK
2009-11-20 15:00:28:268268 [INFO] Recieve SIP Event [nua_i_state]
Status 200 OK

<db483c4594b75843@speechrecog> to Connection [1]

2009-11-20 15:00:29:815113 [INFO] Process RECOGNIZE Request [2938]
2009-11-20 15:00:29:830738 [INFO] Process RECOGNIZE Response [2938]
2009-11-20 15:00:29:830738 [INFO] State Transition IDLE ->
RECOGNIZING
2009-11-20 15:00:29:830738 [INFO] Send MRCPv2 Message size=86
MRCP/2.0 86 2938 200 IN-PROGRESS
Channel-Identifier:db483c4594b75843@speechrecog

end0 2009-11-20 15:00:29:877612 [INFO] Process RECOGNITION-COMPLETE

Event [2938]

2009-11-20 15:00:29:877612 [INFO] State Transition RECOGNIZING ->
RECOGNIZED
2009-11-20 15:00:29:877612 [INFO] Send MRCPv2 Message size=271
MRCP/2.0 271 RECOGNITION-COMPLETE 2938 COMPLETE
Channel-Identifier:db483c4594b75843@speechrecog
Completion-Cause:000 success
Content-Length:121

<?xml version="1.0"?><result><interpretation><input>one </

input><confidence>100.00</confidence></interpretation></result>

2009-11-20 15:00:34:127530 [INFO] Recieve SIP Event [nua_i_bye]

Status 200 Session Terminated

2009-11-20 15:00:34:127530 [NOTICE] TCP/MRCPv2 Connection Disconnected
2009-11-20 15:00:34:127530 [INFO] Recieve SIP Event [nua_i_state]

Status 200 Session Terminated
2009-11-20 15:00:34:127530 [NOTICE] SIP Call State [terminated]
2009-11-20 15:00:34:127530 [INFO] Receive Terminate Request
<db483c4594b75843>

2009-11-20 15:00:34:127530 [INFO] Recieve SIP Event
[nua_i_terminated] Status
200 Session Terminated
2009-11-20 15:00:34:143155 [INFO] Remove Control Channel

<db483c4594b75843@speechrecog> [0]

2009-11-20 15:00:34:143155 [NOTICE] Remove Session <db483c4594b75843>
2009-11-20 15:00:34:143155 [INFO] Close RTP Receive

205.207.240.127:5112 <- 205.207.240.28:43790 [r:9 l:0 j:334]

2009-11-20 15:00:34:143155 [NOTICE] Destroy TCP/MRCPv2 Connection
2009-11-20 15:00:34:143155 [INFO] Send Terminate Response
<db483c4594b75843>
2009-11-20 15:00:34:143155 [NOTICE] Destroy Session
2009-11-20 15:00:39:611800 [INFO] Recieve SIP Event [nua_i_invite]
Status 100 Trying
2009-11-20 15:00:39:611800 [INFO] Recieve SIP Event [nua_i_state]
Status 100 Trying

2009-11-20 15:00:39:611800 [NOTICE] SIP Call State [received]
2009-11-20 15:00:39:611800 [INFO] Found Profile [MRCPv2-Default]
2009-11-20 15:00:39:611800 [INFO] Remote SDP
v=0
o=- 1 1 IN IP4 205.207.240.28
s="MrcpClient"
c=IN IP4 205.207.240.28
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:new
a=resource:speechrecog
a=cmid:1
m=audio 43792 RTP/AVP 0 8
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=sendonly
a=mid:1

2009-11-20 15:00:39:627425 [NOTICE] Add Session <9abe2d427a983645>
2009-11-20 15:00:39:627425 [INFO] Receive Offer <9abe2d427a983645>
[c:1 a:1 v:0]
2009-11-20 15:00:39:627425 [INFO] Add Control Channel

<9abe2d427a983645@speechrecog> to Pending Connection [1]

2009-11-20 15:00:39:627425 [INFO] Open RTP Receive

205.207.240.127:5114 <- 205.207.240.28:43792 playout [50 ms]

2009-11-20 15:00:39:643049 [INFO] Send Answer <9abe2d427a983645> [c:
1 a:1 v:0]

2009-11-20 15:00:39:643049 [INFO] Local SDP
v=0
o=UniMRCPServer 0 0 IN IP4 205.207.240.127
s=-
c=IN IP4 205.207.240.127
t=0 0
m=application 1544 TCP/MRCPv2 1
a=setup:passive
a=connection:new
a=channel:9abe2d427a983645@speechrecog
a=cmid:1
m=audio 5114 RTP/AVP 0 8
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=recvonly
a=mid:1

2009-11-20 15:00:39:658674 [INFO] Recieve SIP Event [nua_i_state]

Status 200 OK
2009-11-20 15:00:39:658674 [NOTICE] Accepted TCP/MRCPv2 Connection
205.207.240.28:4231

2009-11-20 15:00:39:658674 [NOTICE] SIP Call State [completed]
2009-11-20 15:00:39:658674 [INFO] Recieve SIP Event [nua_i_ack]
Status 200 OK
2009-11-20 15:00:39:658674 [INFO] Recieve SIP Event [nua_i_state]
Status 200 OK

2009-11-20 15:00:39:658674 [NOTICE] SIP Call State [ready]
2009-11-20 15:00:39:658674 [INFO] Recieve SIP Event [nua_i_active]
Status 200
Call active
2009-11-20 15:00:41:174270 [INFO] Receive MRCPv2 Message size=771
MRCP/2.0 771 RECOGNIZE 2940
Cancel-If-Queue: false
Channel-Identifier: 9abe2d427a983645@speechrecog
Content-Type: application/srgs+xml
Start-Input-Timers: false
Content-Length: 423
Recognition-Timeout:15000
N-Best-List-Length:10
Save-Waveform:false
Speech-Complete-Timeout:2000
Speech-Incomplete-Timeout:2000
Content-Id:rec_gram12993

<?xml version="1.0" encoding="UTF-8"?>
<grammar xmlns="http://www.w3.org/2001/06/grammar" xmlns:xsi="http://

www.w3.org/2001/XMLSchema-instance" xsi:schemaLocation="http://
www.w3.org/2001/06/grammar

http://www.w3.org/TR/speech-grammar/grammar.xsd" xml:lang="en-US"
version="1.0"
root="_boolean" mode="voice">
<rule id="_boolean" scope="public">
<one-of>
<item>one</item>
<item>three</item>
</one-of>
</rule>
</grammar>

2009-11-20 15:00:41:205519 [INFO] Move Control Channel

<9abe2d427a983645@speechrecog> to Connection [1]

2009-11-20 15:00:41:205519 [INFO] Process RECOGNIZE Request [2940]
2009-11-20 15:00:41:221144 [INFO] Process RECOGNIZE Response [2940]
2009-11-20 15:00:41:221144 [INFO] State Transition IDLE ->
RECOGNIZING
2009-11-20 15:00:41:221144 [INFO] Send MRCPv2 Message size=86
MRCP/2.0 86 2940 200 IN-PROGRESS
Channel-Identifier:9abe2d427a983645@speechrecog

end0 2009-11-20 15:00:41:268018 [INFO] Process RECOGNITION-COMPLETE

Event [2940]

2009-11-20 15:00:41:268018 [INFO] State Transition RECOGNIZING ->
RECOGNIZED
2009-11-20 15:00:41:268018 [INFO] Send MRCPv2 Message size=271
MRCP/2.0 271 RECOGNITION-COMPLETE 2940 COMPLETE
Channel-Identifier:9abe2d427a983645@speechrecog
Completion-Cause:000 success
Content-Length:121

<?xml version="1.0"?><result><interpretation><input>one </

input><confidence>100.00</confidence></interpretation></result>

2009-11-20 15:00:45:517937 [INFO] Recieve SIP Event [nua_i_bye]

Status 200 Session Terminated

2009-11-20 15:00:45:517937 [NOTICE] TCP/MRCPv2 Connection Disconnected
2009-11-20 15:00:45:517937 [INFO] Recieve SIP Event [nua_i_state]

Status 200 Session Terminated
2009-11-20 15:00:45:517937 [NOTICE] SIP Call State [terminated]
2009-11-20 15:00:45:517937 [INFO] Recieve SIP Event
[nua_i_terminated] Status 200 Session Terminated
2009-11-20 15:00:45:517937 [INFO] Receive Terminate Request
<9abe2d427a983645>

2009-11-20 15:00:45:517937 [NOTICE] Remove Session <9abe2d427a983645>
2009-11-20 15:00:45:517937 [INFO] Remove Control Channel

<9abe2d427a983645@speechrecog> [0]

2009-11-20 15:00:45:517937 [INFO] Close RTP Receive

205.207.240.127:5114 <- 205.207.240.28:43792 [r:7 l:0 j:281]

2009-11-20 15:00:45:533561 [NOTICE] Destroy TCP/MRCPv2 Connection
2009-11-20 15:00:45:533561 [INFO] Send Terminate Response
<9abe2d427a983645>
2009-11-20 15:00:45:533561 [NOTICE] Destroy Session

> ...
>
> read more »- Hide quoted text -

Arsen Chaloyan

unread,

Nov 21, 2009, 1:25:38 PM11/21/09

to uni...@googlegroups.com

>> Here is the log before the server stopped responding: the server was
>> still alive because it will accept 'quit' command and after 20 minutes
>> it starts taking calls again.

Does this mean that during the period when server stops responding you
see nothing (no updates) in unimrcp console or logs. What does your
client get?
Try to enable Sofia-SIP debug and check what is going on SIP transport layer.

Before running server from command line set the following environment vars

for Windows
set SOFIA_DEBUG=9
set NUA_DEBUG=9
set SOA_DEBUG=9
set NEA_DEBUG=9
set IPTSEC_DEBUG=9
set NTA_DEBUG=9
set TPORT_DEBUG=9
set TPORT_LOG=9
set SU_DEBUG=9
unimrcpserver

for Linux
export SOFIA_DEBUG=9
export NUA_DEBUG=9
export SOA_DEBUG=9
export NEA_DEBUG=9
export IPTSEC_DEBUG=9
export NTA_DEBUG=9
export TPORT_DEBUG=9
export TPORT_LOG=9
export SU_DEBUG=9
./unimrcpserver

> what is the 'playout[50 ms]' supposed to be? is it the length of a
> speech audio frame? could it be possible that the issue was caused by
> waiting for speech input?

Playout delay is a delay in jitter buffer required to compensate
network variation. Small playout delay may cause recognition to fail,
but I hardly assume this parameter could be somehow related to this
issue.

dd

unread,

Nov 23, 2009, 10:32:09 AM11/23/09

to UniMRCP

Hi, Arsen,

Attached pleased find the new log.

When the server stops responding, the client just said 'open session
timeout', and when using WireShark, no SIP was captured, I have been
trying using Nuance speech server with the same MRCP client and found
no issue.

When the server stops responding, the console outputs:

nua(028AF5D0): sent signal r_destroy
nua(028AF5D0): recv signal r_destroy
nta_leg_destroy(00000000)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)

Also, in the attachment in the end of this mail, I noticed that there
is a log saying
'tport(02896900): set timer at 1799973 ms because idle since send',
the 30 minutes timer seems close to the nonresponding time.

Attechment log:

nua(028AF5D0): sent signal r_respond
nua(028AF5D0): recv signal r_respond 200 OK
soa_set_params(static::028AF998, ...) called
soa_set_user_sdp(static::028AF998, 00000000, 028C7ADD, -1) called
soa_set_capability_sdp(static::028AF998, 00000000, 028C7ADD, -1)
called
soa_generate_answer(static::028AF998) called
soa_static_offer_answer_action(028AF998, soa_generate_answer): called
soa_static(028AF998, soa_generate_answer): generating local
description
soa_static(028AF998, soa_generate_answer): upgrade with remote
description
soa_sdp_mode_set(0195F1B8, 028C6C70, ""): called
soa_static(028AF998, soa_generate_answer): storing local description
soa_activate(static::028AF998, (nil)) called
soa_get_local_sdp(static::028AF998, [00000000], [0195F2F4],
[0195F2E8]) called
tport_tsend(02896900) tpn = TCP/205.207.240.28:3148
tport_vsend(02896900): 894 bytes of 894 to tcp/205.207.240.28:3148
tport_vsend returned 894
2009-11-23 10:11:56:837944 [NOTICE] Accepted TCP/MRCPv2 Connection
205.207.240.2
8:3149
send 894 bytes to tcp/[205.207.240.28]:3148 at 15:11:56.837944:

------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/TCP
205.207.240.28;branch=z9hG4bK0a976154427196;rport=3148
From: vbv_client
<sip:vbv_c...@205.207.240.28>;tag=976154427214522
To: MediaServer
<sip:mreso...@205.207.240.127:5061>;tag=cyeeZZ1c1cX3D
Call-ID:1258989067DAE976154427F2145
CSeq:1 INVITE
Contact: <sip:205.207.240.127:5061>
User-Agent: UniMRCP SofiaSIP
Accept: application/sdp
Allow: INVITE, ACK, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE,
NOTIFY, REFER
, UPDATE
Supported: timer, 100rel
Min-SE: 120
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 308

v=0
o=UniMRCPServer 2419171921701213196 7169641961360276672 IN IP4
205.207.240.12

7
s=-
c=IN IP4 205.207.240.127
t=0 0
m=application 1544 TCP/MRCPv2 1
a=setup:passive
a=connection:new

a=channel:44a20dea77e58345@speechrecog
a=cmid:1
m=audio 5122 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=recvonly
a=mid:1

------------------------------------------------------------------------
tport(02896900): reset timer
nta: sent 200 OK for INVITE (1)
nta: timer shortened to 500 ms
nua(028AF5D0): call state changed: received -> completed, sent answer
soa_get_local_sdp(static::028AF998, [0195F4D0], [0195F4C4],
[00000000]) called
soa_get_params(static::028AF998, ...) called
nua(028AF5D0): event i_state 200 OK
tport_wakeup(02896900): events IN
tport_recv_event(02896900)
2009-11-23 10:11:56:879747 [INFO] Recieve SIP Event [nua_i_state]
Status 200 O
K
tport(02896900) msg 028C7320 from (tcp/205.207.240.28:3148) has 397
bytes, vecle
n = 1
2009-11-23 10:11:56:879747 [NOTICE] SIP Call State [completed]
recv 397 bytes from tcp/[205.207.240.28]:3148 at 15:11:56.879747:

------------------------------------------------------------------------
ACK sip:mreso...@205.207.240.127:5061 SIP/2.0
Via:SIP/2.0/TCP 205.207.240.28;branch=z9hG4bK0a976154427ac0
Contact:<sip:vbv_c...@205.207.240.28;transport=TCP>
Max-Forwards: 6
To: MediaServer
<sip:mreso...@205.207.240.127:5061>;tag=cyeeZZ1c1cX3D
From: vbv_client
<sip:vbv_c...@205.207.240.28>;tag=976154427214522
Call-ID: 1258989067DAE976154427F2145
CSeq: 2 ACK
Content-Length: 0

------------------------------------------------------------------------
tport(02896900): msg 028C7320 (397 bytes) from tcp/205.207.240.28:3148/
sip next=
00000000
nta: received ACK sip:mreso...@205.207.240.127:5061 SIP/2.0 (CSeq 2)
nta: ACK (2) is going to INVITE (1)
soa_clear_remote_sdp(static::028AF998) called
nua(028AF5D0): event i_ack 200 OK
nua(028AF5D0): call state changed: completed -> ready
2009-11-23 10:11:56:893681 [INFO] Recieve SIP Event [nua_i_ack]
Status 200 OK
nua(028AF5D0): event i_state 200 OK
nua(028AF5D0): event i_active 200 Call active
2009-11-23 10:11:56:893681 [INFO] Recieve SIP Event [nua_i_state]
Status 200 O
K
nta: incoming_free(028AD708)
2009-11-23 10:11:56:907615 [NOTICE] SIP Call State [ready]
tport(02896900): reset timer
2009-11-23 10:11:56:907615 [INFO] Recieve SIP Event [nua_i_active]
Status 200
Call active
tport(02896900): set timer at 1799973 ms because idle since recv
nta: timer not set
2009-11-23 10:11:58:189552 [INFO] Receive MRCPv2 Message size=770
MRCP/2.0 770 RECOGNIZE 4036
Cancel-If-Queue: false
Channel-Identifier: 44a20dea77e58345@speechrecog

Content-Type: application/srgs+xml
Start-Input-Timers: false
Content-Length: 423
Recognition-Timeout:15000
N-Best-List-Length:10
Save-Waveform:false
Speech-Complete-Timeout:2000
Speech-Incomplete-Timeout:2000

Content-Id:rec_gram7949

<?xml version="1.0" encoding="UTF-8"?>
<grammar xmlns="http://www.w3.org/2001/06/grammar" xmlns:xsi="http://
www.w3.org/
2001/XMLSchema-instance" xsi:schemaLocation="http://www.w3.org/2001/06/
grammar
http://www.w3.org/TR/speech-grammar/grammar.xsd" xml:lang="en-US"
version="1.0"
root="_boolean" mode="voice">
<rule id="_boolean" scope="public">
<one-of>
<item>one</item>
<item>three</item>
</one-of>
</rule>
</grammar>

2009-11-23 10:11:58:217420 [INFO] Move Control Channel
<44a20dea77e58345@speec
hrecog> to Connection [1]
2009-11-23 10:11:58:217420 [INFO] Process RECOGNIZE Request [4036]
2009-11-23 10:11:58:231354 [INFO] Process RECOGNIZE Response [4036]
2009-11-23 10:11:58:231354 [INFO] State Transition IDLE ->
RECOGNIZING
2009-11-23 10:11:58:231354 [INFO] Send MRCPv2 Message size=86
MRCP/2.0 86 4036 200 IN-PROGRESS
Channel-Identifier:44a20dea77e58345@speechrecog

end0 2009-11-23 10:11:58:273157 [INFO] Process RECOGNITION-COMPLETE
Event [403
6]
2009-11-23 10:11:58:273157 [INFO] State Transition RECOGNIZING ->
RECOGNIZED
2009-11-23 10:11:58:273157 [INFO] Send MRCPv2 Message size=271
MRCP/2.0 271 RECOGNITION-COMPLETE 4036 COMPLETE
Channel-Identifier:44a20dea77e58345@speechrecog

Completion-Cause:000 success
Content-Length:121

<?xml version="1.0"?><result><interpretation><input>one </
input><confidence>100.
00</confidence></interpretation></result>

tport_wakeup(02896900): events IN
tport_recv_event(02896900)
tport(02896900) msg 029E00B8 from (tcp/205.207.240.28:3148) has 397
bytes, vecle
n = 1
recv 397 bytes from tcp/[205.207.240.28]:3148 at 15:12:02.091100:

------------------------------------------------------------------------
BYE sip:mreso...@205.207.240.127:5061 SIP/2.0
Via:SIP/2.0/TCP 205.207.240.28;branch=z9hG4bK0a976154427b1e
Contact:<sip:vbv_c...@205.207.240.28;transport=TCP>
Max-Forwards: 6
To: MediaServer
<sip:mreso...@205.207.240.127:5061>;tag=cyeeZZ1c1cX3D
From: vbv_client
<sip:vbv_c...@205.207.240.28>;tag=976154427214522
Call-ID: 1258989067DAE976154427F2145
CSeq: 3 BYE
Content-Length: 0

------------------------------------------------------------------------
tport(02896900): msg 029E00B8 (397 bytes) from tcp/205.207.240.28:3148/
sip next=
00000000
nta: received BYE sip:mreso...@205.207.240.127:5061 SIP/2.0 (CSeq 3)
nta: canonizing sip:mreso...@205.207.240.127:5061 with contact
nta: BYE (3) going to existing leg
tport_tsend(02896900) tpn = TCP/205.207.240.28:3148
tport_vsend(02896900): 447 bytes of 447 to tcp/205.207.240.28:3148
tport_vsend returned 447
2009-11-23 10:12:02:105034 [NOTICE] TCP/MRCPv2 Connection Disconnected
send 447 bytes to tcp/[205.207.240.28]:3148 at 15:12:02.105034:

------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/TCP
205.207.240.28;branch=z9hG4bK0a976154427b1e;rport=3148
From: vbv_client
<sip:vbv_c...@205.207.240.28>;tag=976154427214522
To: MediaServer
<sip:mreso...@205.207.240.127:5061>;tag=cyeeZZ1c1cX3D
Call-ID: 1258989067DAE976154427F2145
CSeq: 3 BYE
User-Agent: UniMRCP SofiaSIP
Allow: INVITE, ACK, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE,
NOTIFY, REFER
, UPDATE
Supported: timer, 100rel
Content-Length: 0

------------------------------------------------------------------------
tport(02896900): reset timer
nta: sent 200 OK for BYE (3)
nua(028AF5D0): event i_bye 200 Session Terminated
nua(028AF5D0): removing session usage
2009-11-23 10:12:02:118968 [INFO] Recieve SIP Event [nua_i_bye]

Status 200 Ses
sion Terminated

nua(028AF5D0): call state changed: ready -> terminated
nua(028AF5D0): event i_state 200 Session Terminated
nua(028AF5D0): event i_terminated 200 Session Terminated
2009-11-23 10:12:02:132902 [INFO] Recieve SIP Event [nua_i_state]

Status 200 S
ession Terminated

soa_destroy(static::028AF998) called
2009-11-23 10:12:02:132902 [NOTICE] SIP Call State [terminated]
nta_leg_destroy(028AFC28)
2009-11-23 10:12:02:132902 [INFO] Recieve SIP Event

[nua_i_terminated] Status
200 Session Terminated

nta: incoming_free(029E0AD0)
2009-11-23 10:12:02:132902 [INFO] Receive Terminate Request
<44a20dea77e58345>

tport(02896900): reset timer
2009-11-23 10:12:02:132902 [NOTICE] Remove Session <44a20dea77e58345>
tport(02896900): set timer at 1799973 ms because idle since send
2009-11-23 10:12:02:132902 [INFO] Remove Control Channel
<44a20dea77e58345@spe
echrecog> [0]
tport_wakeup(02896900): events HUP
tport(02896900)
tport(02896900, 0)
tport_set_events(02896900): events
tport(02896900): set timer at 1799959 ms because idle since send
2009-11-23 10:12:02:146836 [INFO] Close RTP Receive
205.207.240.127:5122 <- 20
5.207.240.28:45480 [r:9 l:0 j:458]
2009-11-23 10:12:02:146836 [NOTICE] Destroy TCP/MRCPv2 Connection
2009-11-23 10:12:02:146836 [INFO] Send Terminate Response
<44a20dea77e58345>
2009-11-23 10:12:02:146836 [NOTICE] Destroy Session
nua(028AF5D0): sent signal r_destroy
nua(028AF5D0): recv signal r_destroy
nta_leg_destroy(00000000)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)
tport_wakeup_pri(0114C570): events ACCEPT
tport(0114C570): new secondary tport 028C6C70
tport(028C6C70): (null)/(null):(null)

Arsen Chaloyan

unread,

Nov 23, 2009, 12:56:29 PM11/23/09

to uni...@googlegroups.com

Hi,

On Mon, Nov 23, 2009 at 7:32 PM, dd <hua...@gmail.com> wrote:
>
> Hi, Arsen,
>
> Attached pleased find the new log.

tport(028C6C70): (null)/(null):(null) looks strange to me, something
is wrong on Sofia-SIP's tport layer.

>
> When the server stops responding, the client just said 'open session
> timeout', and when using WireShark, no SIP was captured,

This looks a bit strange too. If you see no SIP messages at all, what
server should respond to.
I assume your client should be sending SIP INVITEs at least.

>I have been trying using Nuance speech server with the same MRCP client and found no issue.

Well, good yo know. BTW, what client do you use? Where do you run
UniMRCP server on Win or Linux.
I'm almost sure the problem is in SIP over TCP, but still not sure who
is guilty.
So you mat want to try SIP over UDP next and see if the issue remains.

dd

unread,

Nov 23, 2009, 2:28:06 PM11/23/09

to UniMRCP

Hi, Arsen,

The uniMRCP server is running on Windows, 'tport(028C6C70): (null)/
(null):(null)' seems like the waiting sockets exceed the 64 limits
which is set by '#define SU_WAIT_MAX (64)' (not pretty sure
though),

how can I configure the server so we can try SIP over UDP instead of
over TCP?

On Nov 23, 12:56 pm, Arsen Chaloyan <achalo...@gmail.com> wrote:
> Hi,
>

> > <sip:vbv_cli...@205.207.240.28>;tag=976154427214522
> > To: MediaServer
> > <sip:mresour...@205.207.240.127:5061>;tag=cyeeZZ1c1cX3D

> > ACK sip:mresour...@205.207.240.127:5061 SIP/2.0
> > Via:SIP/2.0/TCP 205.207.240.28;branch=z9hG4bK0a976154427ac0
> > Contact:<sip:vbv_cli...@205.207.240.28;transport=TCP>
> > Max-Forwards: 6
> > To: MediaServer
> > <sip:mresour...@205.207.240.127:5061>;tag=cyeeZZ1c1cX3D
> > From: vbv_client
> > <sip:vbv_cli...@205.207.240.28>;tag=976154427214522

> > Call-ID: 1258989067DAE976154427F2145
> > CSeq: 2 ACK
> > Content-Length: 0
>
> > ------------------------------------------------------------------------
> > tport(02896900): msg 028C7320 (397 bytes) from tcp/205.207.240.28:3148/
> > sip next=
> > 00000000

> > nta: received ACK sip:mresour...@205.207.240.127:5061 SIP/2.0 (CSeq 2)

Arsen Chaloyan

unread,

Nov 23, 2009, 2:36:33 PM11/23/09

to uni...@googlegroups.com

On Mon, Nov 23, 2009 at 11:28 PM, dd <hua...@gmail.com> wrote:
>
> Hi, Arsen,
>
> The uniMRCP server is running on Windows, 'tport(028C6C70): (null)/
> (null):(null)' seems like the waiting sockets exceed the 64 limits
> which is set by '#define SU_WAIT_MAX (64)' (not pretty sure
> though),

Well, I'll check it out later.

>
> how can I configure the server so we can try SIP over UDP instead of
> over TCP?

By default server binds on both UDP and TCP ports
<param name="sip-transport" value="udp,tcp"/>

So it's up to your client what transport to use. If you want server to
accept only UDP, set the following
<param name="sip-transport" value="udp"/>

I'm more and more doubtful which MRCP client you use...

dd

unread,

Nov 23, 2009, 4:12:47 PM11/23/09

to UniMRCP

Hi, Arsen,

I configured the server to accept only UDP, but it doesn't work, the
client I used is the vbMRCPclient from Pronexus.

I am just wondering how the server handles more than 64 connections if
the waiting sockets are limited by 64? I think only change #define
SU_WAIT_MAX (64) to a bigger value doesn't work because it is limit
imposed by winsock.

On Nov 23, 2:36 pm, Arsen Chaloyan <achalo...@gmail.com> wrote:

Arsen Chaloyan

unread,

Nov 24, 2009, 2:04:30 AM11/24/09

to uni...@googlegroups.com

Hi,

On Tue, Nov 24, 2009 at 1:12 AM, dd <hua...@gmail.com> wrote:
>
> Hi, Arsen,
>
> I configured the server to accept only UDP, but it doesn't work, the
> client I used is the vbMRCPclient from Pronexus.

To make this work the client should be configured to use SIP over UDP,
if supported.

> I am just wondering how the server handles more than 64 connections if
> the waiting sockets are limited by 64? I think only change #define
> SU_WAIT_MAX (64) to a bigger value doesn't work because it is limit
> imposed by winsock.

Yes, Windows port of Sofia-SIP's su_port interface is limited with 64
socket descriptors. And the problem is in max number of descriptors
Win32's events mechanism allows to wait for. APR implements the same
routine in more convenient way. They re-defined FD_SETSIZE to be 1024
for Windows and use select() routine instead.
Even though this doesn't mean you are limited with 64 SIP/MRCPv2
sessions on Windows. SIP sessions can be established through shared,
persistent TCP connection, which Sofia-SIP stack handles gracefully.
I'm not sure how the client you use behaves. Alternatively you can
define multiple Sofia-SIP agents in unimrcpserver.xml and distribute
the sessions among these agents. Currently UniMRCP server provides no
such distribution mechanism, but it can be done externally if needed.

Anyway, first I'd like to understand what the problem was in. Have you
reached 64 simultaneous SIP/TCP connections during your tests?

dd

unread,

Nov 24, 2009, 10:49:57 AM11/24/09

to UniMRCP

Hi, Arsen,

I have try the mrcpclient with Nuance so I assume that there may be
issue on the server, after discussing with you, I think it may be
helpful to take a step back by trying unimrcpserver/unimrcpclient
setup.

I try to run two unimrcpclients from different machines and each trys
to connect to the same unimrcpserver, but the server only responses to
one of the two clients. is there a way to run mulitple session, for
example, run mulitple recogs on the client?

On Nov 24, 2:04 am, Arsen Chaloyan <achalo...@gmail.com> wrote:
> Hi,
>

Arsen Chaloyan

unread,

Nov 24, 2009, 1:12:01 PM11/24/09

to uni...@googlegroups.com

Hi,

On Tue, Nov 24, 2009 at 7:49 PM, dd <hua...@gmail.com> wrote:
>
> Hi, Arsen,
>
> I have try the mrcpclient with Nuance so I assume that there may be
> issue on the server, after discussing with you, I think it may be
> helpful to take a step back by trying unimrcpserver/unimrcpclient
> setup.

I think, it's a good idea.

>
> I try to run two unimrcpclients from different machines and each trys
> to connect to the same unimrcpserver, but the server only responses to
> one of the two clients.

Server accepts multiple clients. Clients may run on the same or
different hosts. It's supported for sure. You should have
misconfigured either server or one of the clients. Pay attention to
firewall settings and/or ip addresses.

> is there a way to run mulitple session, for
> example, run mulitple recogs on the client?

You can 'run recog' as many times as you need. I'll add a possibility
to generate a bulk of sessions at once in the future, when I have some
spare time.

dd

unread,

Nov 25, 2009, 5:46:16 PM11/25/09

to UniMRCP

Hi, Arsen,

I am just wondering if you could tell me how to configure the sofia
sip so that can response to a specific port other othe 5060, I am
trying to use UDP but the client will pick a port randomly. Is there a
way to know the port picked and then send responses to the port on the
server side?

Thanks,

On Nov 24, 1:12 pm, Arsen Chaloyan <achalo...@gmail.com> wrote:
> Hi,
>

Arsen Chaloyan

unread,

Nov 26, 2009, 5:58:33 AM11/26/09

to uni...@googlegroups.com

Hi,

It's a responsibility of Sofia-SIP to decide where the response should
be sent based on SIP headers. There is no such an option to configure.
BTW, your client may send SIP messages from one port, but receive from
another. There is nothing wrong in this behavior.
What are you trying to achieve at this time? Are you behind NAT?
It seems I've missed the point.

> --~--~---------~--~----~------------~-------~--~----~
> You received this message because you are subscribed to the Google Groups "UniMRCP" group.
> To post to this group, send email to uni...@googlegroups.com
> To unsubscribe from this group, send email to unimrcp+u...@googlegroups.com
> For more options, visit this group at http://groups.google.com/group/unimrcp?hl=en
> -~----------~----~----~----~------~----~------~--~---

dd

unread,

Nov 27, 2009, 11:43:42 AM11/27/09

to UniMRCP

Hi, Arsen,

After couple days of digging into the code, I found out the problem is
caused by the mrcpclient I have been using. It uses a port other than
5060 to send/receive messages which is not included in the sip header,
in the mean while, the Sofia sip replies the INVITE to 5060 by
default. I asked them to included the port number in the INVITE
header, and everything works fine now.

Thank you for helping me on this issue.

On Nov 26, 5:58 am, Arsen Chaloyan <achalo...@gmail.com> wrote:
> Hi,
>

Arsen Chaloyan

unread,

Nov 27, 2009, 12:20:44 PM11/27/09

to uni...@googlegroups.com

Great, you've got it resolved.
Thanks for making the final status clear for us.
It's rather important. Though, it seems not everyone follows that simple rule.

> --

>
> You received this message because you are subscribed to the Google Groups "UniMRCP" group.

> To post to this group, send email to uni...@googlegroups.com.
> To unsubscribe from this group, send email to unimrcp+u...@googlegroups.com.
> For more options, visit this group at http://groups.google.com/group/unimrcp?hl=en.

Reply all

Reply to author

Forward