[Freeswitch-users] hangup with cause:WRONG_CALL

Discussion:

[Freeswitch-users] hangup with cause:WRONG_CALL_STATE

rockxsj

2014-04-28 11:46:20 UTC

Hi guys!

I found that the some of the incoming call will hangup with case:
WRONG_CALL_STATE,but else will be ok.After a hour,almost every incoming
call will be hangup with the same cause.Then I will restart the
freeswitch,it will server like before:some call will be hangup with the
cause...

28e10f2e-c851-11e3-a146-85a8fadad1b0 2014-04-20 14:01:15.498169
[WARNING] switch_core_state_machine.c:567
28e10f2e-c851-11e3-a146-85a8fadad1b0 sofia/internal/123456 at sipsj.236.cn
Abandoned
28e10f2e-c851-11e3-a146-85a8fadad1b0 2014-04-20 14:01:15.498169 [NOTICE]
switch_core_state_machine.c:570 Hangup
sofia/internal/123456 at sipsj.236.cn [CS_NEW] [WRONG_CALL_STATE]

What does Wrong call state mean and what can I do to fix this issue?

Everything else is working. I can make outgoing calls and call other
extensions.

Thanks in advance.

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140428/9394e2bf/attachment.html

rockxsj

2014-04-28 11:54:46 UTC

Permalink

I am wrong!After a hour, when I call into freeswitch and capture the
package,I get below:
REGISTER sip:sipsj.236.cn:5500 SIP/2.0
Via: SIP/2.0/UDP
172.16.99.207:39687;rport;branch=z9hG4bKPjFvp9llQ7q.cUnieDXaeO.wXH66duUI0T
Route: <sip:sipsj.236.cn:5500;transport=udp;lr>
Max-Forwards: 70
From: <sip:123456 at sipsj.236.cn>;tag=CCdQyIVtFJNZHQ0BAv665tehrdzW--99
To: <sip:123456 at sipsj.236.cn>
Call-ID: .eoYaVPviq-j-VLJK4iv4YlzShnYskxC
CSeq: 3305 REGISTER
User-Agent: CSipSimple_hammerhead-19/r58
Contact: <sip:12366 at 172.16.99.207:39687;ob>
Expires: 900
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY,
REFER, MESSAGE, OPTIONS
Content-Length: 0

INVITE sip:018917730813 at sipsj.236.cn:5500 SIP/2.0
Via: SIP/2.0/UDP
172.16.99.207:39687;rport;branch=z9hG4bKPjFoOT194vinUlA5W3MoXeTC15swubrW8H
Max-Forwards: 70
From: <sip:123456 at sipsj.236.cn>;tag=vF2i0MaWvODqLbAoCjqHJ220VIunKWX7
To: <sip:018917730813 at sipsj.236.cn>
Contact: <sip:123456 at 172.16.99.207:39687;ob>
Call-ID: sGCbAkESQBbFyUCnRYxaR1vyyLwbtdNm
CSeq: 24741 INVITE
Route: <sip:sipsj.236.cn:5500;transport=udp;lr>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY,
REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
User-Agent: CSipSimple_hammerhead-19/r58
X-passid: 5247545
X-session_id: f379hqeo576hm3mf4cm7nhbia6
Content-Type: application/sdp
Content-Length: 338

v=0
o=- 3607645799 3607645799 IN IP4 172.16.99.207
s=pjmedia
c=IN IP4 172.16.99.207
t=0 0
m=audio 4000 RTP/AVP 18 102 101
c=IN IP4 172.16.99.207
a=rtcp:4001 IN IP4 172.16.99.207
a=sendrecv
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:102 ILBC/8000
a=fmtp:102 mode=30
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
SIP/2.0 503 System Busy
Via: SIP/2.0/UDP
172.16.99.207:39687;rport=39687;branch=z9hG4bKPjFvp9llQ7q.cUnieDXaeO.wXH66duUI0T;received=180.168.34.146

From: <sip:123456 at sipsj.236.cn>;tag=CCdQyIVtFJNZHQ0BAv665tehrdzW--99
To: <sip:123456 at sipsj.236.cn>;tag=FBrH35ZS3tQBF
Call-ID: .eoYaVPviq-j-VLJK4iv4YlzShnYskxC

I will get 503 System Busy.

Post by rockxsj
Hi guys!
WRONG_CALL_STATE,but else will be ok.After a hour,almost every
incoming call will be hangup with the same cause.Then I will restart
the freeswitch,it will server like before:some call will be hangup
with the cause...
28e10f2e-c851-11e3-a146-85a8fadad1b0 2014-04-20 14:01:15.498169
[WARNING] switch_core_state_machine.c:567
28e10f2e-c851-11e3-a146-85a8fadad1b0 sofia/internal/12366 at sipsj.236.cn
Abandoned
28e10f2e-c851-11e3-a146-85a8fadad1b0 2014-04-20 14:01:15.498169
[NOTICE] switch_core_state_machine.c:570 Hangup
sofia/internal/12366 at sipsj.236.cn [CS_NEW] [WRONG_CALL_STATE]
What does Wrong call state mean and what can I do to fix this issue?
Everything else is working. I can make outgoing calls and call other
extensions.
Thanks in advance.

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140428/e68cfea5/attachment-0001.html

rockxsj

2014-04-28 18:13:58 UTC

Permalink

My client send sip message via udp,so I think the WRONG_CALL_STATE may
product by some package send failure.The most important problem is
client recive "503 System Busy".When I trace the code,I find it is
product in src/mod/endpoints/mod_sofia/sofia.c,and the condition is: if
(switch_queue_size(mod_sofia_globals.msg_queue) > (unsigned
int)critical) { nua_respond(nh, 503, "System Busy",
SIPTAG_RETRY_AFTER_STR("300"), NUTAG_WITH_THIS(nua), TAG_END()); goto end;}

Why will product these?

Post by rockxsj
Hi guys!
WRONG_CALL_STATE,but else will be ok.After a hour,almost every
incoming call will be hangup with the same cause.Then I will restart
the freeswitch,it will server like before:some call will be hangup
with the cause...
28e10f2e-c851-11e3-a146-85a8fadad1b0 2014-04-20 14:01:15.498169
[WARNING] switch_core_state_machine.c:567
28e10f2e-c851-11e3-a146-85a8fadad1b0
sofia/internal/123456 at sipsj.236.cn Abandoned
28e10f2e-c851-11e3-a146-85a8fadad1b0 2014-04-20 14:01:15.498169
[NOTICE] switch_core_state_machine.c:570 Hangup
sofia/internal/123456 at sipsj.236.cn [CS_NEW] [WRONG_CALL_STATE]
What does Wrong call state mean and what can I do to fix this issue?
Everything else is working. I can make outgoing calls and call other
extensions.
Thanks in advance.

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140428/691021d0/attachment-0001.html

Michael Jerris

2014-04-28 12:03:11 UTC

Permalink

I think we have already fixed this bug. Have you tried updating to more recent code?

Post by rockxsj
Hi guys!
I found that the some of the incoming call will hangup with case: WRONG_CALL_STATE,but else will be ok.After a hour,almost every incoming call will be hangup with the same cause.Then I will restart the freeswitch,it will server like before:some call will be hangup with the cause...
28e10f2e-c851-11e3-a146-85a8fadad1b0 2014-04-20 14:01:15.498169 [WARNING] switch_core_state_machine.c:567 28e10f2e-c851-11e3-a146-85a8fadad1b0 sofia/internal/123456 at sipsj.236.cn Abandoned
28e10f2e-c851-11e3-a146-85a8fadad1b0 2014-04-20 14:01:15.498169 [NOTICE] switch_core_state_machine.c:570 Hangup sofia/internal/123456 at sipsj.236.cn [CS_NEW] [WRONG_CALL_STATE]
What does Wrong call state mean and what can I do to fix this issue?
Everything else is working. I can make outgoing calls and call other
extensions.
Thanks in advance.
_________________________________________________________________________
consulting at freeswitch.org
http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.com
Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com
FreeSWITCH-users mailing list
FreeSWITCH-users at lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140428/fcc29ad2/attachment.html

李俊俊

2014-04-28 13:07:23 UTC

Permalink

Yes,my code is pulled from the master branch on 4.23.It seems that the push
is fast than pop.I add some code to log the queue length,just like below:
if (!sofia_private) {
if (sess_count >= sess_max || !sofia_test_pflag(profile, PFLAG_RUNNING) ||
!switch_core_ready_inbound()) {
nua_respond(nh, 503, "Maximum Calls In Progress",
SIPTAG_RETRY_AFTER_STR("300"), NUTAG_WITH_THIS(nua), TAG_END());
goto end;
}

switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_DEBUG,
"switch_queue_size(mod_sofia_globals.msg_queue):%d\n",
switch_queue_size(mod_sofia_globals.msg_queue));
switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_DEBUG, "critical:%d\n",
(unsigned int)critical);

if (switch_queue_size(mod_sofia_globals.msg_queue) > (unsigned
int)critical) {
nua_respond(nh, 503, "System Busy", SIPTAG_RETRY_AFTER_STR("300"),
NUTAG_WITH_THIS(nua), TAG_END());
goto end;
}

if (sofia_test_pflag(profile, PFLAG_STANDBY)) {
nua_respond(nh, 503, "System Paused", NUTAG_WITH_THIS(nua), TAG_END());
goto end;
}
}

And I found the switch_queue_size(mod_sofia_globals.msg_queue) is increase
so fast and almost did not decrease,the below is the log:
2014-04-28 18:55:31.416405 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56157
2014-04-28 18:55:31.436402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56158
2014-04-28 18:55:31.436402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56159
2014-04-28 18:55:31.476402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56160
2014-04-28 18:55:31.496402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56161
2014-04-28 18:55:31.496402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56162
2014-04-28 18:55:31.496402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56163
2014-04-28 18:55:31.516402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56164
2014-04-28 18:55:31.516402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56165
2014-04-28 18:55:31.536402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56166
2014-04-28 18:55:31.556402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56167
2014-04-28 18:55:31.576407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56168
2014-04-28 18:55:31.576407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56169
2014-04-28 18:55:31.576407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56170
2014-04-28 18:55:31.596403 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56171
2014-04-28 18:55:31.616404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56169
2014-04-28 18:55:31.636404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56170
2014-04-28 18:55:31.656403 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56172
2014-04-28 18:55:31.656403 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56173
2014-04-28 18:55:31.656403 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56174
2014-04-28 18:55:31.676404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56175
2014-04-28 18:55:31.676404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56175
2014-04-28 18:55:31.716404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56177
2014-04-28 18:55:31.716404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56178
2014-04-28 18:55:31.756407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56180
2014-04-28 18:55:31.756407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56181
2014-04-28 18:55:31.756407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56182
2014-04-28 18:55:31.756407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56183
2014-04-28 18:55:31.776414 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56184
2014-04-28 18:55:31.796402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56185
2014-04-28 18:55:31.836415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56179
2014-04-28 18:55:31.856415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56180
2014-04-28 18:55:31.896415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56183
2014-04-28 18:55:31.896415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56184
2014-04-28 18:55:31.896415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56185
2014-04-28 18:55:31.916416 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56186
2014-04-28 18:55:31.916416 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56187
2014-04-28 18:55:31.916416 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56188
2014-04-28 18:55:31.936415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56189
2014-04-28 18:55:31.936415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56190
2014-04-28 18:55:31.936415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56191
2014-04-28 18:55:31.996416 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56192
2014-04-28 18:55:32.036417 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56194
2014-04-28 18:55:32.036417 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56195
2014-04-28 18:55:32.036417 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56196
2014-04-28 18:55:32.056417 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56197

Can you confirm again?

Post by Michael Jerris
I think we have already fixed this bug. Have you tried updating to more recent code?
Hi guys!
WRONG_CALL_STATE,but else will be ok.After a hour,almost every incoming
call will be hangup with the same cause.Then I will restart the
freeswitch,it will server like before:some call will be hangup with the
cause...
28e10f2e-c851-11e3-a146-85a8fadad1b0 2014-04-20 14:01:15.498169 [WARNING]
switch_core_state_machine.c:567 28e10f2e-c851-11e3-a146-85a8fadad1b0
sofia/internal/123456 at sipsj.236.cn Abandoned
28e10f2e-c851-11e3-a146-85a8fadad1b0 2014-04-20 14:01:15.498169 [NOTICE]
switch_core_state_machine.c:570 Hangup sofia/internal/123456 at sipsj.236.cn[CS_NEW] [WRONG_CALL_STATE]
What does Wrong call state mean and what can I do to fix this issue?
Everything else is working. I can make outgoing calls and call other
extensions.
Thanks in advance.
_________________________________________________________________________
consulting at freeswitch.org
http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.com
Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com
FreeSWITCH-users mailing list
FreeSWITCH-users at lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org
_________________________________________________________________________
consulting at freeswitch.org
http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.com
Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com
FreeSWITCH-users mailing list
FreeSWITCH-users at lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org

--
?????http://yazi.whoknow.me
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140428/b55b306d/attachment-0001.html

Michael Jerris

2014-04-28 13:22:28 UTC

Permalink

This is a completely different issue than i was talking about. What rate of traffic are you putting on this box? it seems your box is way underpowered for whatever your doing, or your using something to lookup users or something of the sort that is blocking or taking way too long.

Post by æä¿ä¿
if (!sofia_private) {
if (sess_count >= sess_max || !sofia_test_pflag(profile, PFLAG_RUNNING) || !switch_core_ready_inbound()) {
nua_respond(nh, 503, "Maximum Calls In Progress", SIPTAG_RETRY_AFTER_STR("300"), NUTAG_WITH_THIS(nua), TAG_END());
goto end;
}
switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_DEBUG, "switch_queue_size(mod_sofia_globals.msg_queue):%d\n", switch_queue_size(mod_sofia_globals.msg_queue));
switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_DEBUG, "critical:%d\n", (unsigned int)critical);
if (switch_queue_size(mod_sofia_globals.msg_queue) > (unsigned int)critical) {
nua_respond(nh, 503, "System Busy", SIPTAG_RETRY_AFTER_STR("300"), NUTAG_WITH_THIS(nua), TAG_END());
goto end;
}
if (sofia_test_pflag(profile, PFLAG_STANDBY)) {
nua_respond(nh, 503, "System Paused", NUTAG_WITH_THIS(nua), TAG_END());
goto end;
}
}
2014-04-28 18:55:31.416405 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56157
2014-04-28 18:55:31.436402 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56158
2014-04-28 18:55:31.436402 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56159
2014-04-28 18:55:31.476402 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56160
2014-04-28 18:55:31.496402 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56161
2014-04-28 18:55:31.496402 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56162
2014-04-28 18:55:31.496402 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56163
2014-04-28 18:55:31.516402 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56164
2014-04-28 18:55:31.516402 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56165
2014-04-28 18:55:31.536402 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56166
2014-04-28 18:55:31.556402 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56167
2014-04-28 18:55:31.576407 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56168
2014-04-28 18:55:31.576407 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56169
2014-04-28 18:55:31.576407 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56170
2014-04-28 18:55:31.596403 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56171
2014-04-28 18:55:31.616404 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56169
2014-04-28 18:55:31.636404 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56170
2014-04-28 18:55:31.656403 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56172
2014-04-28 18:55:31.656403 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56173
2014-04-28 18:55:31.656403 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56174
2014-04-28 18:55:31.676404 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56175
2014-04-28 18:55:31.676404 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56175
2014-04-28 18:55:31.716404 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56177
2014-04-28 18:55:31.716404 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56178
2014-04-28 18:55:31.756407 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56180
2014-04-28 18:55:31.756407 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56181
2014-04-28 18:55:31.756407 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56182
2014-04-28 18:55:31.756407 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56183
2014-04-28 18:55:31.776414 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56184
2014-04-28 18:55:31.796402 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56185
2014-04-28 18:55:31.836415 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56179
2014-04-28 18:55:31.856415 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56180
2014-04-28 18:55:31.896415 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56183
2014-04-28 18:55:31.896415 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56184
2014-04-28 18:55:31.896415 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56185
2014-04-28 18:55:31.916416 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56186
2014-04-28 18:55:31.916416 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56187
2014-04-28 18:55:31.916416 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56188
2014-04-28 18:55:31.936415 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56189
2014-04-28 18:55:31.936415 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56190
2014-04-28 18:55:31.936415 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56191
2014-04-28 18:55:31.996416 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56192
2014-04-28 18:55:32.036417 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56194
2014-04-28 18:55:32.036417 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56195
2014-04-28 18:55:32.036417 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56196
2014-04-28 18:55:32.056417 [DEBUG] sofia.c:2081 switch_queue_size(mod_sofia_globals.msg_queue):56197
Can you confirm again?
I think we have already fixed this bug. Have you tried updating to more recent code?

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140428/2c416ec8/attachment.html

李俊俊

2014-04-28 14:02:06 UTC

Permalink

I add some code to log the pop function,just like below:
switch_mutex_lock(mod_sofia_globals.mutex);
n = msg_queue_threads;
msg_queue_threads++;
switch_mutex_unlock(mod_sofia_globals.mutex);

switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_WARNING, "MSG Thread %d
Started\n", my_id);

for(;;) {

if (switch_queue_pop(q, &pop) != SWITCH_STATUS_SUCCESS) {
switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_WARNING, "POP FAIL %s\n",
"SELF");
switch_cond_next();
continue;
}

if (pop) {
sofia_dispatch_event_t *de = (sofia_dispatch_event_t *) pop;
switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_WARNING, "MSG Thread %d
Dispatcher Started\n", n);
sofia_process_dispatch_event(&de);
switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_WARNING, "MSG Thread %d
Dispatcher Ended\n", n);
} else {
break;
}
}

And I got logs like below:
2014-04-28 21:23:54.229117 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:54.229174 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
2014-04-28 21:23:54.231115 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:54.231137 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
2014-04-28 21:23:54.894118 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:54.894157 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
2014-04-28 21:23:54.894232 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:55.200100 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
2014-04-28 21:23:55.200127 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:55.540108 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
2014-04-28 21:23:55.540108 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:55.840114 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
2014-04-28 21:23:55.840114 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:55.840114 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
2014-04-28 21:23:55.840114 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:56.100116 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
2014-04-28 21:23:56.100116 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:56.380119 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended

I found it is too long between a pop ended and other stated.I think this
will make the pop function execute slow than push.Will it?

Post by Michael Jerris
This is a completely different issue than i was talking about. What rate
of traffic are you putting on this box? it seems your box is way
underpowered for whatever your doing, or your using something to lookup
users or something of the sort that is blocking or taking way too long.
Yes,my code is pulled from the master branch on 4.23.It <http://4.23.it/>seems that the push is fast than pop.I add some code to log the queue
if (!sofia_private) {
if (sess_count >= sess_max || !sofia_test_pflag(profile, PFLAG_RUNNING)
|| !switch_core_ready_inbound()) {
nua_respond(nh, 503, "Maximum Calls In Progress",
SIPTAG_RETRY_AFTER_STR("300"), NUTAG_WITH_THIS(nua), TAG_END());
goto end;
}
switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_DEBUG,
"switch_queue_size(mod_sofia_globals.msg_queue):%d\n",
switch_queue_size(mod_sofia_globals.msg_queue));
switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_DEBUG, "critical:%d\n",
(unsigned int)critical);
if (switch_queue_size(mod_sofia_globals.msg_queue) > (unsigned
int)critical) {
nua_respond(nh, 503, "System Busy", SIPTAG_RETRY_AFTER_STR("300"),
NUTAG_WITH_THIS(nua), TAG_END());
goto end;
}
if (sofia_test_pflag(profile, PFLAG_STANDBY)) {
nua_respond(nh, 503, "System Paused", NUTAG_WITH_THIS(nua), TAG_END());
goto end;
}
}
And I found the switch_queue_size(mod_sofia_globals.msg_queue) is increase
2014-04-28 18:55:31.416405 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56157
2014-04-28 18:55:31.436402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56158
2014-04-28 18:55:31.436402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56159
2014-04-28 18:55:31.476402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56160
2014-04-28 18:55:31.496402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56161
2014-04-28 18:55:31.496402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56162
2014-04-28 18:55:31.496402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56163
2014-04-28 18:55:31.516402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56164
2014-04-28 18:55:31.516402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56165
2014-04-28 18:55:31.536402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56166
2014-04-28 18:55:31.556402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56167
2014-04-28 18:55:31.576407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56168
2014-04-28 18:55:31.576407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56169
2014-04-28 18:55:31.576407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56170
2014-04-28 18:55:31.596403 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56171
2014-04-28 18:55:31.616404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56169
2014-04-28 18:55:31.636404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56170
2014-04-28 18:55:31.656403 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56172
2014-04-28 18:55:31.656403 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56173
2014-04-28 18:55:31.656403 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56174
2014-04-28 18:55:31.676404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56175
2014-04-28 18:55:31.676404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56175
2014-04-28 18:55:31.716404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56177
2014-04-28 18:55:31.716404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56178
2014-04-28 18:55:31.756407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56180
2014-04-28 18:55:31.756407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56181
2014-04-28 18:55:31.756407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56182
2014-04-28 18:55:31.756407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56183
2014-04-28 18:55:31.776414 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56184
2014-04-28 18:55:31.796402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56185
2014-04-28 18:55:31.836415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56179
2014-04-28 18:55:31.856415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56180
2014-04-28 18:55:31.896415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56183
2014-04-28 18:55:31.896415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56184
2014-04-28 18:55:31.896415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56185
2014-04-28 18:55:31.916416 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56186
2014-04-28 18:55:31.916416 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56187
2014-04-28 18:55:31.916416 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56188
2014-04-28 18:55:31.936415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56189
2014-04-28 18:55:31.936415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56190
2014-04-28 18:55:31.936415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56191
2014-04-28 18:55:31.996416 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56192
2014-04-28 18:55:32.036417 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56194
2014-04-28 18:55:32.036417 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56195
2014-04-28 18:55:32.036417 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56196
2014-04-28 18:55:32.056417 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56197
Can you confirm again?

_________________________________________________________________________
consulting at freeswitch.org
http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.com
Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com
FreeSWITCH-users mailing list
FreeSWITCH-users at lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org

李俊俊

2014-04-29 07:51:12 UTC

Permalink

I have found the problem,It is because of the client send register message
every 5s.Now I have disable-register in sofia profile.

Post by æä¿ä¿
switch_mutex_lock(mod_sofia_globals.mutex);
n = msg_queue_threads;
msg_queue_threads++;
switch_mutex_unlock(mod_sofia_globals.mutex);
switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_WARNING, "MSG Thread %d
Started\n", my_id);
for(;;) {
if (switch_queue_pop(q, &pop) != SWITCH_STATUS_SUCCESS) {
switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_WARNING, "POP FAIL
%s\n", "SELF");
switch_cond_next();
continue;
}
if (pop) {
sofia_dispatch_event_t *de = (sofia_dispatch_event_t *) pop;
switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_WARNING, "MSG Thread %d
Dispatcher Started\n", n);
sofia_process_dispatch_event(&de);
switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_WARNING, "MSG Thread %d
Dispatcher Ended\n", n);
} else {
break;
}
}
2014-04-28 21:23:54.229117 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:54.229174 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
2014-04-28 21:23:54.231115 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:54.231137 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
2014-04-28 21:23:54.894118 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:54.894157 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
2014-04-28 21:23:54.894232 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:55.200100 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
2014-04-28 21:23:55.200127 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:55.540108 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
2014-04-28 21:23:55.540108 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:55.840114 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
2014-04-28 21:23:55.840114 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:55.840114 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
2014-04-28 21:23:55.840114 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:56.100116 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
2014-04-28 21:23:56.100116 [WARNING] sofia.c:1922 MSG Thread 0 Dispatcher
Started
2014-04-28 21:23:56.380119 [WARNING] sofia.c:1924 MSG Thread 0 Dispatcher
Ended
I found it is too long between a pop ended and other stated.I think this
will make the pop function execute slow than push.Will it?

Post by Michael Jerris
This is a completely different issue than i was talking about. What rate
of traffic are you putting on this box? it seems your box is way
underpowered for whatever your doing, or your using something to lookup
users or something of the sort that is blocking or taking way too long.
Yes,my code is pulled from the master branch on 4.23.It <http://4.23.it/>seems that the push is fast than pop.I add some code to log the queue
if (!sofia_private) {
if (sess_count >= sess_max || !sofia_test_pflag(profile, PFLAG_RUNNING)
|| !switch_core_ready_inbound()) {
nua_respond(nh, 503, "Maximum Calls In Progress",
SIPTAG_RETRY_AFTER_STR("300"), NUTAG_WITH_THIS(nua), TAG_END());
goto end;
}
switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_DEBUG,
"switch_queue_size(mod_sofia_globals.msg_queue):%d\n",
switch_queue_size(mod_sofia_globals.msg_queue));
switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_DEBUG,
"critical:%d\n", (unsigned int)critical);
if (switch_queue_size(mod_sofia_globals.msg_queue) > (unsigned int)critical) {
nua_respond(nh, 503, "System Busy", SIPTAG_RETRY_AFTER_STR("300"),
NUTAG_WITH_THIS(nua), TAG_END());
goto end;
}
if (sofia_test_pflag(profile, PFLAG_STANDBY)) {
nua_respond(nh, 503, "System Paused", NUTAG_WITH_THIS(nua), TAG_END());
goto end;
}
}
And I found the switch_queue_size(mod_sofia_globals.msg_queue) is
2014-04-28 18:55:31.416405 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56157
2014-04-28 18:55:31.436402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56158
2014-04-28 18:55:31.436402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56159
2014-04-28 18:55:31.476402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56160
2014-04-28 18:55:31.496402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56161
2014-04-28 18:55:31.496402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56162
2014-04-28 18:55:31.496402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56163
2014-04-28 18:55:31.516402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56164
2014-04-28 18:55:31.516402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56165
2014-04-28 18:55:31.536402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56166
2014-04-28 18:55:31.556402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56167
2014-04-28 18:55:31.576407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56168
2014-04-28 18:55:31.576407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56169
2014-04-28 18:55:31.576407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56170
2014-04-28 18:55:31.596403 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56171
2014-04-28 18:55:31.616404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56169
2014-04-28 18:55:31.636404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56170
2014-04-28 18:55:31.656403 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56172
2014-04-28 18:55:31.656403 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56173
2014-04-28 18:55:31.656403 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56174
2014-04-28 18:55:31.676404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56175
2014-04-28 18:55:31.676404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56175
2014-04-28 18:55:31.716404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56177
2014-04-28 18:55:31.716404 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56178
2014-04-28 18:55:31.756407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56180
2014-04-28 18:55:31.756407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56181
2014-04-28 18:55:31.756407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56182
2014-04-28 18:55:31.756407 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56183
2014-04-28 18:55:31.776414 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56184
2014-04-28 18:55:31.796402 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56185
2014-04-28 18:55:31.836415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56179
2014-04-28 18:55:31.856415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56180
2014-04-28 18:55:31.896415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56183
2014-04-28 18:55:31.896415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56184
2014-04-28 18:55:31.896415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56185
2014-04-28 18:55:31.916416 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56186
2014-04-28 18:55:31.916416 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56187
2014-04-28 18:55:31.916416 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56188
2014-04-28 18:55:31.936415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56189
2014-04-28 18:55:31.936415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56190
2014-04-28 18:55:31.936415 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56191
2014-04-28 18:55:31.996416 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56192
2014-04-28 18:55:32.036417 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56194
2014-04-28 18:55:32.036417 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56195
2014-04-28 18:55:32.036417 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56196
2014-04-28 18:55:32.056417 [DEBUG] sofia.c:2081
switch_queue_size(mod_sofia_globals.msg_queue):56197
Can you confirm again?