<div dir="ltr"><p dir="ltr" style="font-family:arial,sans-serif;font-size:13px">You should transfer this info to jira, <a href="http://jira.freeswitch.org/" target="_blank">http://jira.freeswitch.org</a> it will easily get lost in this mailing list. My inbox fills hundreds of emails.....</p>
<p dir="ltr" style="font-family:arial,sans-serif;font-size:13px">Its the nest way to track issues.</p><div class="" style="font-family:arial,sans-serif;font-size:13px"></div></div><div class="gmail_extra"><br><br><div class="gmail_quote">
On Fri, Nov 22, 2013 at 2:26 AM, Jean-Paul Iribarren <span dir="ltr"><<a href="mailto:jeanpaul.iribarren@free.fr" target="_blank">jeanpaul.iribarren@free.fr</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">
<div text="#000000" bgcolor="#FFFFFF"><div class="im">
Hi Anthony, thank you for your message.<br>
<br>
Yes, I did try another client on my device; in my first message, I
said:<br>
<br>
[quote]<br></div>
On the other hand, when I use linphonec in the very same conditions,
I can see that FreeSWITCH sends 50 RTP packets per second on
average. Each RTP packet contains 160 samples, and timestamps of
consecutive packets are increased in 160 units steps, resulting in
correct RX audio coming out of the loudspeaker of my device. <br><div><div class="h5">
[/quote]<br>
<br>
I performed yet another test yesterday evening: using sofsip_cli on
caller side, I called a registered client (X-Lite registered as
extension 1000), but after a few seconds I hit "Decline" button on
X-Lite to refuse the incoming call. On sofsip_cli (caller side), the
rx audio was good during the ringback phase (160 samples/pkt,
timestamps growing by 160 units, 50 pkts/s average), but turned
loose as soon as the call was transfered by FS to the voicemail
unit, as a consequence of the callee refusal (160 samples/pkt,
timestamps growing by 320 units, 25 pkts/s average -> slow /
chopped rx audio). Looks like something is wrong regarding
voicemail. But once again, why only in front of sofsip_cli???<br>
<br>
Regarding the capture, here it is (call initiated from sofsip_cli to
an unregistered extension, triggering voicemail immediately, wait
for end of (slow / chopped) voicemail message, hangup from caller
side):<br>
<br>
[capture]<br>
freeswitch@XXX-xxxxxxxxx> recv 675 bytes from
udp/[10.0.0.186]:5060 at 07:54:00.545012:<br>
------------------------------------------------------------------------<br>
INVITE <a>sip:1001@10.0.0.57</a> SIP/2.0<br>
Via: SIP/2.0/UDP 10.0.0.186;rport;branch=z9hG4bK0BaUp7Be94yKS<br>
Max-Forwards: 70<br>
From: <a href="mailto:1009@10.0.0.57" target="_blank"><1009@10.0.0.57></a>;tag=08mryQ3v0DXtF<br>
To: <a><sip:1001@10.0.0.57></a><br>
Call-ID: 5c7dc01d-c230-1231-6982-0050c2d5dd07<br>
CSeq: 51571881 INVITE<br>
Contact: <a><sip:10.0.0.186></a><br>
User-Agent: sofia-sip/1.12.10<br>
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE,
SUBSCRIBE, NOTIFY, REFER, UPDATE<br>
Supported: timer, 100rel<br>
Min-SE: 120<br>
Content-Type: application/sdp<br>
Content-Disposition: session<br>
Content-Length: 148<br>
<br>
v=0<br>
o=- 4844407640550486747 5665314394658789476 IN IP4 10.0.0.186<br>
s=-<br>
c=IN IP4 10.0.0.186<br>
t=0 0<br>
m=audio 16384 RTP/AVP 0<br>
a=rtpmap:0 PCMU/8000<br>
------------------------------------------------------------------------<br>
send 326 bytes to udp/[10.0.0.186]:5060 at 07:54:00.545012:<br>
------------------------------------------------------------------------<br>
SIP/2.0 100 Trying<br>
Via: SIP/2.0/UDP
10.0.0.186;rport=5060;branch=z9hG4bK0BaUp7Be94yKS<br>
From: <a href="mailto:1009@10.0.0.57" target="_blank"><1009@10.0.0.57></a>;tag=08mryQ3v0DXtF<br>
To: <a><sip:1001@10.0.0.57></a><br>
Call-ID: 5c7dc01d-c230-1231-6982-0050c2d5dd07<br>
CSeq: 51571881 INVITE<br>
User-Agent:
FreeSWITCH-mod_sofia/1.5.6b+git~20131101T202135Z~2589bf7750~32bit<br>
Content-Length: 0<br>
<br>
------------------------------------------------------------------------<br>
2013-11-22 08:54:00.560624 [NOTICE] switch_channel.c:1055 New
Channel <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[f28e1f66-1524-4ce5-9fe0-acf7bb929a1c]<br>
2013-11-22 08:54:00.560624 [DEBUG] switch_core_session.c:1037 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:00.560624 [DEBUG] switch_core_state_machine.c:467 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
Running State Change CS_NEW<br>
2013-11-22 08:54:00.560624 [DEBUG] switch_core_session.c:1037 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:00.560624 [DEBUG] switch_core_state_machine.c:485 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State NEW<br>
2013-11-22 08:54:00.591846 [DEBUG] sofia.c:8066 IP 10.0.0.186
Rejected by acl "domains". Falling back to Digest auth.<br>
send 840 bytes to udp/[10.0.0.186]:5060 at 07:54:00.591846:<br>
------------------------------------------------------------------------<br>
SIP/2.0 407 Proxy Authentication Required<br>
Via: SIP/2.0/UDP
10.0.0.186;rport=5060;branch=z9hG4bK0BaUp7Be94yKS<br>
From: <a href="mailto:1009@10.0.0.57" target="_blank"><1009@10.0.0.57></a>;tag=08mryQ3v0DXtF<br>
To: <a><sip:1001@10.0.0.57></a>;tag=29XpXm62B9N6r<br>
Call-ID: 5c7dc01d-c230-1231-6982-0050c2d5dd07<br>
CSeq: 51571881 INVITE<br>
User-Agent:
FreeSWITCH-mod_sofia/1.5.6b+git~20131101T202135Z~2589bf7750~32bit<br>
Accept: application/sdp<br>
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE<br>
Supported: timer, precondition, path, replaces<br>
Allow-Events: talk, hold, conference, presence, as-feature-event,
dialog, line-seize, call-info, sla, include-session-description,
presence.winfo, message-su<br>
mmary, refer<br>
Proxy-Authenticate: Digest realm="10.0.0.57",
nonce="598a20fb-5ab3-4ecf-af64-71388a03b2e6", algorithm=MD5,
qop="auth"<br>
Content-Length: 0<br>
<br>
------------------------------------------------------------------------<br>
2013-11-22 08:54:00.591846 [DEBUG] switch_core_session.c:1037 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:00.591846 [DEBUG] sofia.c:1816 detaching session
f28e1f66-1524-4ce5-9fe0-acf7bb929a1c<br>
recv 287 bytes from udp/[10.0.0.186]:5060 at 07:54:00.607458:<br>
------------------------------------------------------------------------<br>
ACK <a>sip:1001@10.0.0.57</a>
SIP/2.0<br>
Via: SIP/2.0/UDP 10.0.0.186;rport;branch=z9hG4bK0BaUp7Be94yKS<br>
Max-Forwards: 70<br>
From: <a href="mailto:1009@10.0.0.57" target="_blank"><1009@10.0.0.57></a>;tag=08mryQ3v0DXtF<br>
To: <a><sip:1001@10.0.0.57></a>;tag=29XpXm62B9N6r<br>
Call-ID: 5c7dc01d-c230-1231-6982-0050c2d5dd07<br>
CSeq: 51571881 ACK<br>
Content-Length: 0<br>
<br>
------------------------------------------------------------------------<br>
recv 927 bytes from udp/[10.0.0.186]:5060 at 07:54:03.386269:<br>
------------------------------------------------------------------------<br>
INVITE <a>sip:1001@10.0.0.57</a> SIP/2.0<br>
Via: SIP/2.0/UDP 10.0.0.186;rport;branch=z9hG4bK1m3Kr2vH6DN6m<br>
Max-Forwards: 70<br>
From: <a href="mailto:1009@10.0.0.57" target="_blank"><1009@10.0.0.57></a>;tag=08mryQ3v0DXtF<br>
To: <a><sip:1001@10.0.0.57></a><br>
Call-ID: 5c7dc01d-c230-1231-6982-0050c2d5dd07<br>
CSeq: 51571882 INVITE<br>
Contact: <a><sip:10.0.0.186></a><br>
User-Agent: sofia-sip/1.12.10<br>
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE,
SUBSCRIBE, NOTIFY, REFER, UPDATE<br>
Supported: timer, 100rel<br>
Proxy-Authorization: Digest username="1009", realm="10.0.0.57",
nonce="598a20fb-5ab3-4ecf-af64-71388a03b2e6",
cnonce="XIhwKMIwEjGCaQBQwtXdBw", algorithm=MD5,<br>
uri=<a>"sip:1001@10.0.0.57"</a>,
response="df2df2940b0dd6f470f030d80fae7b08", qop=auth, nc=00000001<br>
Min-SE: 120<br>
Content-Type: application/sdp<br>
Content-Disposition: session<br>
Content-Length: 148<br>
<br>
v=0<br>
o=- 4844407640550486747 5665314394658789476 IN IP4 10.0.0.186<br>
s=-<br>
c=IN IP4 10.0.0.186<br>
t=0 0<br>
m=audio 16384 RTP/AVP 0<br>
a=rtpmap:0 PCMU/8000<br>
------------------------------------------------------------------------<br>
send 326 bytes to udp/[10.0.0.186]:5060 at 07:54:03.386269:<br>
------------------------------------------------------------------------<br>
SIP/2.0 100 Trying<br>
Via: SIP/2.0/UDP
10.0.0.186;rport=5060;branch=z9hG4bK1m3Kr2vH6DN6m<br>
From: <a href="mailto:1009@10.0.0.57" target="_blank"><1009@10.0.0.57></a>;tag=08mryQ3v0DXtF<br>
To: <a><sip:1001@10.0.0.57></a><br>
Call-ID: 5c7dc01d-c230-1231-6982-0050c2d5dd07<br>
CSeq: 51571882 INVITE<br>
User-Agent:
FreeSWITCH-mod_sofia/1.5.6b+git~20131101T202135Z~2589bf7750~32bit<br>
Content-Length: 0<br>
<br>
------------------------------------------------------------------------<br>
2013-11-22 08:54:03.370658 [DEBUG] sofia.c:1908 Re-attaching to
session f28e1f66-1524-4ce5-9fe0-acf7bb929a1c<br>
2013-11-22 08:54:03.370658 [DEBUG] switch_core_session.c:1037 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:03.370658 [DEBUG] switch_core_session.c:1037 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:03.401880 [DEBUG] sofia.c:8066 IP 10.0.0.186
Rejected by acl "domains". Falling back to Digest auth.<br>
2013-11-22 08:54:03.417492 [DEBUG] sofia.c:5863 Channel <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
entering state [received][100]<br>
2013-11-22 08:54:03.417492 [DEBUG] sofia.c:5873 Remote SDP:<br>
v=0<br>
o=- 4844407640550486747 5665314394658789476 IN IP4 10.0.0.186<br>
s=-<br>
c=IN IP4 10.0.0.186<br>
t=0 0<br>
m=audio 16384 RTP/AVP 0<br>
a=rtpmap:0 PCMU/8000<br>
<br>
2013-11-22 08:54:03.417492 [DEBUG] sofia.c:6118 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State Change CS_NEW -> CS_INIT<br>
2013-11-22 08:54:03.417492 [DEBUG] switch_core_session.c:1372 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:03.417492 [DEBUG] switch_core_state_machine.c:467 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
Running State Change CS_INIT<br>
2013-11-22 08:54:03.417492 [DEBUG] switch_core_state_machine.c:506 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State INIT<br>
2013-11-22 08:54:03.417492 [DEBUG] mod_sofia.c:87 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
SOFIA INIT<br>
2013-11-22 08:54:03.417492 [DEBUG] switch_core_state_machine.c:40 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Standard INIT<br>
2013-11-22 08:54:03.417492 [DEBUG] switch_core_state_machine.c:48 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State Change CS_INIT -> CS_ROUTING<br>
2013-11-22 08:54:03.417492 [DEBUG] switch_core_session.c:1372 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:03.417492 [DEBUG] switch_core_state_machine.c:506 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State INIT going to sleep<br>
2013-11-22 08:54:03.417492 [DEBUG] switch_core_state_machine.c:467 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
Running State Change CS_ROUTING<br>
2013-11-22 08:54:03.417492 [DEBUG] switch_channel.c:2178 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
Callstate Change DOWN -> RINGING<br>
2013-11-22 08:54:03.417492 [DEBUG] switch_core_state_machine.c:522 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State ROUTING<br>
2013-11-22 08:54:03.417492 [DEBUG] mod_sofia.c:123 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
SOFIA ROUTING<br>
2013-11-22 08:54:03.417492 [DEBUG] switch_core_state_machine.c:164 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Standard ROUTING<br>
2013-11-22 08:54:03.417492 [INFO] mod_dialplan_xml.c:558 Processing
1009 <1009>->1001 in context default<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->unloop] continue=false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/
break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/
break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->tod_example] continue=true<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Date/TimeMatch (FAIL) [tod_example] break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->holiday_example] continue=true<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Date/TimeMatch (FAIL) [holiday_example] break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->global-intercept] continue=false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [global-intercept] destination_number(1001) =~ /^886$/
break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->group-intercept] continue=false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [group-intercept] destination_number(1001) =~ /^\*8$/
break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->intercept-ext] continue=false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [intercept-ext] destination_number(1001) =~
/^\*\*(\d+)$/ break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->redial] continue=false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [redial] destination_number(1001) =~ /^(redial|870)$/
break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->global] continue=true<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [global] ${rtp_has_crypto}() =~
/^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION)
=~ /^(DELAYED NEGOTIATION)/ break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [global] ${switch_r_sdp}(v=0<br>
o=- 4844407640550486747 5665314394658789476 IN IP4 10.0.0.186<br>
s=-<br>
c=IN IP4 10.0.0.186<br>
t=0 0<br>
m=audio 16384 RTP/AVP 0<br>
a=rtpmap:0 PCMU/8000<br>
) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Absolute Condition [global]<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action
hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action
hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action hash(insert/${domain_name}-last_dial/global/${uuid})<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->snom-demo-2] continue=false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [snom-demo-2] destination_number(1001) =~ /^9001$/
break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->snom-demo-1] continue=false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [snom-demo-1] destination_number(1001) =~ /^9000$/
break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->eavesdrop] continue=false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [eavesdrop] destination_number(1001) =~
/^88(\d{4})$|^\*0(.*)$/ break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->eavesdrop] continue=false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [eavesdrop] destination_number(1001) =~ /^779$/
break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->call_return] continue=false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [call_return] destination_number(1001) =~
/^\*69$|^869$|^lcr$/ break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->del-group] continue=false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [del-group] destination_number(1001) =~ /^80(\d{2})$/
break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->add-group] continue=false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [add-group] destination_number(1001) =~ /^81(\d{2})$/
break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->call-group-simo] continue=false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [call-group-simo] destination_number(1001) =~
/^82(\d{2})$/ break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->call-group-order] continue=false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [call-group-order] destination_number(1001) =~
/^83(\d{2})$/ break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->extension-intercom] continue=false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (FAIL) [extension-intercom] destination_number(1001) =~
/^8(10[01][0-9])$/ break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
parsing [default->Local_Extension] continue=false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Regex (PASS) [Local_Extension] destination_number(1001) =~
/^(10[01][0-9])$/ break=on-false<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action export(dialed_extension=1001)<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action bind_meta_app(1 b s execute_extension::dx XML features)<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action bind_meta_app(2 b s record_session::C:/Program
Files/FreeSWITCH/recordings/${caller_id_number}.${strftime(%Y-%m-%<br>
d-%H-%M-%S)}.wav)<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action bind_meta_app(3 b s execute_extension::cf XML features)<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action bind_meta_app(4 b s execute_extension::att_xfer XML features)<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action set(disable_rtp_auto_adjust=true)<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action set(zrtp_secure_media=false)<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action set(ringback=${us-ring})<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action set(transfer_ringback=local_stream://moh)<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action set(call_timeout=30)<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action set(hangup_after_bridge=true)<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action set(continue_on_fail=true)<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action
hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action
hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action
set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name}
var callgroup)})<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action
hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid})<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action hash(insert/${domain_name}-last_dial_ext/global/${uuid})<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action
hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid})<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action bridge(user/${dialed_extension}@${domain_name})<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action answer()<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action sleep(1000)<br>
Dialplan: <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Action bridge(loopback/app=voicemail:default ${domain_name}
${dialed_extension})<br>
2013-11-22 08:54:03.433103 [DEBUG] switch_core_state_machine.c:214 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State Change CS_ROUTING -> CS_EXECUTE<br>
2013-11-22 08:54:03.433103 [DEBUG] switch_core_session.c:1372 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:03.433103 [DEBUG] switch_core_state_machine.c:522 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State ROUTING going to sleep<br>
2013-11-22 08:54:03.433103 [DEBUG] switch_core_state_machine.c:467 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
Running State Change CS_EXECUTE<br>
2013-11-22 08:54:03.433103 [DEBUG] switch_core_state_machine.c:529 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State EXECUTE<br>
2013-11-22 08:54:03.433103 [DEBUG] mod_sofia.c:178 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
SOFIA EXECUTE<br>
2013-11-22 08:54:03.433103 [DEBUG] switch_core_state_machine.c:256 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Standard EXECUTE<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
hash(insert/10.0.0.57-spymap/1009/f28e1f66-1524-4ce5-9fe0-acf7bb929a1c)<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
hash(insert/10.0.0.57-last_dial/1009/1001)<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
hash(insert/10.0.0.57-last_dial/global/f28e1f66-1524-4ce5-9fe0-acf7bb929a1c)<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
export(RFC2822_DATE=Fri, 22 Nov 2013 08:54:03 Paris, Madrid)<br>
2013-11-22 08:54:03.433103 [DEBUG] switch_channel.c:1247 EXPORT
(export_vars) [RFC2822_DATE]=[Fri, 22 Nov 2013 08:54:03 Paris,
Madrid]<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
export(dialed_extension=1001)<br>
2013-11-22 08:54:03.433103 [DEBUG] switch_channel.c:1247 EXPORT
(export_vars) [dialed_extension]=[1001]<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
bind_meta_app(1 b s execute_extension::dx XML features)<br>
2013-11-22 08:54:03.479937 [INFO] switch_ivr_async.c:3631 Bound
B-Leg: *1 execute_extension::dx XML features<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
bind_meta_app(2 b s record_session::C:/Program
Files/FreeSWITCH/recordings/1009.2013-11-22-08-54-03.wav)<br>
2013-11-22 08:54:03.479937 [INFO] switch_ivr_async.c:3631 Bound
B-Leg: *2 record_session::C:/Program
Files/FreeSWITCH/recordings/1009.2013-11-22-08-54-03.wav<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
bind_meta_app(3 b s execute_extension::cf XML features)<br>
2013-11-22 08:54:03.479937 [INFO] switch_ivr_async.c:3631 Bound
B-Leg: *3 execute_extension::cf XML features<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
bind_meta_app(4 b s execute_extension::att_xfer XML features)<br>
2013-11-22 08:54:03.479937 [INFO] switch_ivr_async.c:3631 Bound
B-Leg: *4 execute_extension::att_xfer XML features<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
set(disable_rtp_auto_adjust=true)<br>
2013-11-22 08:54:03.479937 [DEBUG] mod_dptools.c:1402 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
SET [disable_rtp_auto_adjust]=[true]<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
set(zrtp_secure_media=false)<br>
2013-11-22 08:54:03.495548 [DEBUG] mod_dptools.c:1402 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
SET [zrtp_secure_media]=[false]<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
set(ringback=%(2000,4000,440,480))<br>
2013-11-22 08:54:03.495548 [DEBUG] mod_dptools.c:1402 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
SET [ringback]=[%(2000,4000,440,480)]<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
set(transfer_ringback=local_stream://moh)<br>
2013-11-22 08:54:03.495548 [DEBUG] mod_dptools.c:1402 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
SET [transfer_ringback]=[local_stream://moh]<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
set(call_timeout=30)<br>
2013-11-22 08:54:03.495548 [DEBUG] mod_dptools.c:1402 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
SET [call_timeout]=[30]<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
set(hangup_after_bridge=true)<br>
2013-11-22 08:54:03.495548 [DEBUG] mod_dptools.c:1402 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
SET [hangup_after_bridge]=[true]<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
set(continue_on_fail=true)<br>
2013-11-22 08:54:03.495548 [DEBUG] mod_dptools.c:1402 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
SET [continue_on_fail]=[true]<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
hash(insert/10.0.0.57-call_return/1001/1009)<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
hash(insert/10.0.0.57-last_dial_ext/1001/f28e1f66-1524-4ce5-9fe0-acf7bb929a1c)<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
set(called_party_callgroup=techsupport)<br>
2013-11-22 08:54:03.511159 [DEBUG] mod_dptools.c:1402 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
SET [called_party_callgroup]=[techsupport]<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
hash(insert/10.0.0.57-last_dial_ext/techsupport/f28e1f66-1524-4ce5-9fe0-acf7bb929a1c)<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
hash(insert/10.0.0.57-last_dial_ext/global/f28e1f66-1524-4ce5-9fe0-acf7bb929a1c)<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
hash(insert/10.0.0.57-last_dial/techsupport/f28e1f66-1524-4ce5-9fe0-acf7bb929a1c)<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
bridge(<a href="mailto:user/1001@10.0.0.57" target="_blank">user/1001@10.0.0.57</a>)<br>
2013-11-22 08:54:03.511159 [DEBUG] switch_channel.c:1201 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 22 Nov 2013 08:54:03
Paris, M<br>
adrid] to event<br>
2013-11-22 08:54:03.511159 [DEBUG] switch_channel.c:1201 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
EXPORTING[export_vars] [dialed_extension]=[1001] to event<br>
2013-11-22 08:54:03.511159 [DEBUG] switch_ivr_originate.c:2070
Parsing global variables<br>
2013-11-22 08:54:03.511159 [DEBUG] switch_channel.c:1201 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 22 Nov 2013 08:54:03
Paris, M<br>
adrid] to event<br>
2013-11-22 08:54:03.511159 [DEBUG] switch_channel.c:1201 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
EXPORTING[export_vars] [dialed_extension]=[1001] to event<br>
2013-11-22 08:54:03.511159 [DEBUG] switch_ivr_originate.c:2070
Parsing global variables<br>
2013-11-22 08:54:03.511159 [DEBUG] switch_event.c:1680 Parsing
variable [sip_invite_domain]=[10.0.0.57]<br>
2013-11-22 08:54:03.511159 [DEBUG] switch_event.c:1680 Parsing
variable [presence_id]=[<a href="mailto:1001@10.0.0.57" target="_blank">1001@10.0.0.57</a>]<br>
2013-11-22 08:54:03.511159 [NOTICE] switch_ivr_originate.c:2699
Cannot create outgoing channel of type [error] cause:
[USER_NOT_REGISTERED]<br>
2013-11-22 08:54:03.511159 [DEBUG] switch_ivr_originate.c:3670
Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED]<br></div></div>
2013-11-22 08:54:03.511159 [NOTICE] switch_ivr_originate.c:2699
Cannot create outgoing channel of type [user] cause:
[USER_NOT_REGISTERED]<div class="im"><br>
2013-11-22 08:54:03.511159 [DEBUG] switch_ivr_originate.c:3670
Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED]<br></div>
2013-11-22 08:54:03.511159 [INFO] mod_dptools.c:3201 Originate
Failed. Cause: USER_NOT_REGISTERED<div><div class="h5"><br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
answer()<br>
2013-11-22 08:54:03.542382 [DEBUG] switch_core_media.c:2994 Audio
Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000]<br>
2013-11-22 08:54:03.542382 [DEBUG] switch_core_media.c:2994 Audio
Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]<br>
2013-11-22 08:54:03.542382 [DEBUG] switch_core_media.c:1956 Set
Codec <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
PCMU/8000 20 ms 160 samples 64000 bits<br>
2013-11-22 08:54:03.542382 [DEBUG] switch_core_codec.c:111 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Original read codec set to PCMU:0<br>
2013-11-22 08:54:03.542382 [DEBUG] switch_core_media.c:3197 No 2833
in SDP. Disable 2833 dtmf and switch to INFO<br>
2013-11-22 08:54:03.542382 [DEBUG] switch_core_media.c:4321 AUDIO
RTP [<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>]
10.0.0.57 port 22990 -> 10.0.0.186 port 16384 codec: 0 ms:<br>
20<br>
2013-11-22 08:54:03.542382 [DEBUG] switch_rtp.c:2995 Starting timer
[soft] 160 bytes per 20ms<br>
2013-11-22 08:54:03.542382 [NOTICE] sofia_media.c:92 Pre-Answer <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>!<br>
2013-11-22 08:54:03.542382 [DEBUG] switch_channel.c:3404 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
Callstate Change RINGING -> EARLY<br>
2013-11-22 08:54:03.542382 [DEBUG] mod_sofia.c:772 Local SDP <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>:<br>
v=0<br>
o=FreeSWITCH 1385083853 1385083854 IN IP4 10.0.0.57<br>
s=FreeSWITCH<br>
c=IN IP4 10.0.0.57<br>
t=0 0<br>
m=audio 22990 RTP/AVP 0<br>
a=rtpmap:0 PCMU/8000<br>
a=ptime:20<br>
a=sendrecv<br>
<br>
send 1060 bytes to udp/[10.0.0.186]:5060 at 07:54:03.542382:<br>
------------------------------------------------------------------------<br>
SIP/2.0 200 OK<br>
Via: SIP/2.0/UDP
10.0.0.186;rport=5060;branch=z9hG4bK1m3Kr2vH6DN6m<br>
From: <a href="mailto:1009@10.0.0.57" target="_blank"><1009@10.0.0.57></a>;tag=08mryQ3v0DXtF<br>
To: <a><sip:1001@10.0.0.57></a>;tag=3jQFZFQ68HcSm<br>
Call-ID: 5c7dc01d-c230-1231-6982-0050c2d5dd07<br>
CSeq: 51571882 INVITE<br>
Contact: <a><sip:1001@10.0.0.57:5060;transport=udp></a><br>
User-Agent:
FreeSWITCH-mod_sofia/1.5.6b+git~20131101T202135Z~2589bf7750~32bit<br>
Accept: application/sdp<br>
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE<br>
Supported: timer, precondition, path, replaces<br>
Allow-Events: talk, hold, conference, presence, as-feature-event,
dialog, line-seize, call-info, sla, include-session-description,
presence.winfo, message-su<br>
mmary, refer<br>
Min-SE: 120<br>
Content-Type: application/sdp<br>
Content-Disposition: session<br>
Content-Length: 158<br>
Remote-Party-ID: "1001" <a><sip:1001@10.0.0.57></a>;party=calling;privacy=off;screen=no<br>
<br>
v=0<br>
o=FreeSWITCH 1385083853 1385083854 IN IP4 10.0.0.57<br>
s=FreeSWITCH<br>
c=IN IP4 10.0.0.57<br>
t=0 0<br>
m=audio 22990 RTP/AVP 0<br>
a=rtpmap:0 PCMU/8000<br>
a=ptime:20<br>
------------------------------------------------------------------------<br>
2013-11-22 08:54:03.542382 [DEBUG] switch_core_session.c:892 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:03.542382 [DEBUG] switch_core_session.c:1037 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:03.542382 [NOTICE] mod_dptools.c:1225 Channel [<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>]
has been answered<br>
2013-11-22 08:54:03.557993 [DEBUG] switch_channel.c:3685 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
Callstate Change EARLY -> ACTIVE<br>
recv 558 bytes from udp/[10.0.0.186]:5060 at 07:54:03.557993:<br>
------------------------------------------------------------------------<br>
ACK <a>sip:1001@10.0.0.57:5060;transport=udp</a>
SIP/2.0<br>
Via: SIP/2.0/UDP 10.0.0.186;rport;branch=z9hG4bK2XvctXDN3pBSg<br>
Max-Forwards: 70<br>
From: <a href="mailto:1009@10.0.0.57" target="_blank"><1009@10.0.0.57></a>;tag=08mryQ3v0DXtF<br>
To: <a><sip:1001@10.0.0.57></a>;tag=3jQFZFQ68HcSm<br>
Call-ID: 5c7dc01d-c230-1231-6982-0050c2d5dd07<br>
CSeq: 51571882 ACK<br>
Proxy-Authorization: Digest username="1009", realm="10.0.0.57",
nonce="598a20fb-5ab3-4ecf-af64-71388a03b2e6",
cnonce="XIhwKMIwEjGCaQBQwtXdBw", algorithm=MD5,<br>
uri=<a>"sip:1001@10.0.0.57"</a>,
response="df2df2940b0dd6f470f030d80fae7b08", qop=auth, nc=00000001<br>
Content-Length: 0<br>
<br>
------------------------------------------------------------------------<br>
2013-11-22 08:54:03.557993 [DEBUG] sofia.c:5863 Channel <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
entering state [completed][200]<br>
2013-11-22 08:54:03.557993 [DEBUG] switch_core_session.c:1037 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:03.557993 [DEBUG] switch_core_session.c:1037 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:03.557993 [DEBUG] sofia.c:5863 Channel <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
entering state [ready][200]<br>
2013-11-22 08:54:03.557993 [DEBUG] switch_core_session.c:1037 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
sleep(1000)<br>
EXECUTE <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
bridge(loopback/app=voicemail:default 10.0.0.57 1001)<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_channel.c:1768 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
Callstate Change ACTIVE -> RING_WAIT<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_channel.c:1201 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 22 Nov 2013 08:54:03
Paris, M<br>
adrid] to event<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_channel.c:1201 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
EXPORTING[export_vars] [dialed_extension]=[1001] to event<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_ivr_originate.c:2070
Parsing global variables<br>
2013-11-22 08:54:04.572728 [NOTICE] switch_channel.c:1055 New
Channel loopback/app=voicemail:default 10.0.0.57 1001-a
[ba43ba18-52aa-47b4-8311-daef490b5e73]<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:157
loopback/app=voicemail:default 10.0.0.57 1001-a setup codec
PCMU/8000/20<br>
2013-11-22 08:54:04.572728 [NOTICE] switch_channel.c:1053 Rename
Channel loopback/app=voicemail:default 10.0.0.57
1001-a->loopback/voicemail-a [ba43ba18-52aa-47<br>
b4-8311-daef490b5e73]<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:1160
(loopback/voicemail-a) State Change CS_NEW -> CS_INIT<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_session.c:1372 Send
signal loopback/voicemail-a [BREAK]<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:590
loopback/voicemail-a CHANNEL KILL<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:467
(loopback/voicemail-a) Running State Change CS_INIT<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:506
(loopback/voicemail-a) State INIT<br>
2013-11-22 08:54:04.572728 [NOTICE] switch_channel.c:1055 New
Channel loopback/voicemail-b [d1b207bf-b400-40e9-985e-edffbe379887]<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:157
loopback/voicemail-b setup codec PCMU/8000/20<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:273
(loopback/voicemail-b) State Change CS_NEW -> CS_INIT<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_session.c:1372 Send
signal loopback/voicemail-b [BREAK]<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:590
loopback/voicemail-b CHANNEL KILL<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:359
(loopback/voicemail-a) State Change CS_INIT -> CS_ROUTING<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:467
(loopback/voicemail-b) Running State Change CS_INIT<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_session.c:1372 Send
signal loopback/voicemail-a [BREAK]<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:590
loopback/voicemail-a CHANNEL KILL<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:506
(loopback/voicemail-b) State INIT<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:506
(loopback/voicemail-a) State INIT going to sleep<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:359
(loopback/voicemail-b) State Change CS_INIT -> CS_ROUTING<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_session.c:1372 Send
signal loopback/voicemail-b [BREAK]<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:467
(loopback/voicemail-a) Running State Change CS_ROUTING<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:590
loopback/voicemail-b CHANNEL KILL<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:506
(loopback/voicemail-b) State INIT going to sleep<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:522
(loopback/voicemail-a) State ROUTING<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:391
loopback/voicemail-a CHANNEL ROUTING<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:467
(loopback/voicemail-b) Running State Change CS_ROUTING<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_ivr_originate.c:67
(loopback/voicemail-a) State Change CS_ROUTING ->
CS_CONSUME_MEDIA<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_channel.c:2178
(loopback/voicemail-b) Callstate Change DOWN -> RINGING<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_session.c:1372 Send
signal loopback/voicemail-a [BREAK]<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:590
loopback/voicemail-a CHANNEL KILL<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:522
(loopback/voicemail-a) State ROUTING going to sleep<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:522
(loopback/voicemail-b) State ROUTING<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:467
(loopback/voicemail-a) Running State Change CS_CONSUME_MEDIA<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:391
loopback/voicemail-b CHANNEL ROUTING<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:410
(loopback/voicemail-b) State Change CS_ROUTING -> CS_EXECUTE<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:541
(loopback/voicemail-a) State CONSUME_MEDIA<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_session.c:1372 Send
signal loopback/voicemail-b [BREAK]<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:650 CHANNEL
CONSUME_MEDIA<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:590
loopback/voicemail-b CHANNEL KILL<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:541
(loopback/voicemail-a) State CONSUME_MEDIA going to sleep<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:522
(loopback/voicemail-b) State ROUTING going to sleep<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:467
(loopback/voicemail-b) Running State Change CS_EXECUTE<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:529
(loopback/voicemail-b) State EXECUTE<br>
2013-11-22 08:54:04.572728 [DEBUG] mod_loopback.c:433
loopback/voicemail-b CHANNEL EXECUTE<br>
2013-11-22 08:54:04.572728 [DEBUG] switch_core_state_machine.c:256
loopback/voicemail-b Standard EXECUTE<br>
EXECUTE loopback/voicemail-b pre_answer()<br>
2013-11-22 08:54:04.603950 [NOTICE] mod_loopback.c:947 Pre-Answer
loopback/voicemail-a!<br>
2013-11-22 08:54:04.603950 [DEBUG] switch_channel.c:3400 Send signal
<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:04.603950 [DEBUG] switch_channel.c:3404
(loopback/voicemail-a) Callstate Change DOWN -> EARLY<br>
2013-11-22 08:54:04.603950 [DEBUG] switch_core_session.c:892 Send
signal loopback/voicemail-b [BREAK]<br>
2013-11-22 08:54:04.603950 [DEBUG] mod_loopback.c:590
loopback/voicemail-b CHANNEL KILL<br>
2013-11-22 08:54:04.603950 [NOTICE] mod_dptools.c:1260 Pre-Answer
loopback/voicemail-b!<br>
2013-11-22 08:54:04.603950 [DEBUG] switch_channel.c:3404
(loopback/voicemail-b) Callstate Change RINGING -> EARLY<br>
EXECUTE loopback/voicemail-b voicemail(default 10.0.0.57 1001)<br>
2013-11-22 08:54:04.603950 [DEBUG] switch_ivr_originate.c:3532
Originate Resulted in Success: [loopback/voicemail-a]<br>
2013-11-22 08:54:04.603950 [DEBUG] switch_channel.c:1977 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
Callstate Change RING_WAIT -> ACTIVE<br>
2013-11-22 08:54:04.603950 [DEBUG] switch_core_session.c:892 Send
signal loopback/voicemail-a [BREAK]<br>
2013-11-22 08:54:04.603950 [DEBUG] mod_loopback.c:590
loopback/voicemail-a CHANNEL KILL<br>
2013-11-22 08:54:04.619562 [DEBUG] switch_core_session.c:892 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:04.619562 [DEBUG] switch_ivr_bridge.c:1440
(loopback/voicemail-a) State Change CS_CONSUME_MEDIA ->
CS_EXCHANGE_MEDIA<br>
2013-11-22 08:54:04.619562 [DEBUG] switch_core_state_machine.c:467
(loopback/voicemail-a) Running State Change CS_EXCHANGE_MEDIA<br>
2013-11-22 08:54:04.619562 [DEBUG] switch_core_session.c:1372 Send
signal loopback/voicemail-a [BREAK]<br>
2013-11-22 08:54:04.619562 [DEBUG] mod_loopback.c:590
loopback/voicemail-a CHANNEL KILL<br>
2013-11-22 08:54:04.619562 [DEBUG] switch_core_state_machine.c:532
(loopback/voicemail-a) State EXCHANGE_MEDIA<br>
2013-11-22 08:54:04.619562 [DEBUG] mod_loopback.c:612 CHANNEL
LOOPBACK<br>
2013-11-22 08:54:04.744452 [DEBUG] switch_ivr_play_say.c:70 No
language specified - Using [en]<br>
2013-11-22 08:54:04.760063 [DEBUG] switch_ivr_play_say.c:251 Handle
play-file:[voicemail/vm-person.wav] (en:en)<br>
2013-11-22 08:54:04.822509 [DEBUG] switch_ivr_play_say.c:1319 Codec
Activated L16@8000hz 1 channels 20ms<br>
2013-11-22 08:54:07.601320 [DEBUG] switch_ivr_play_say.c:1723 done
playing file C:/Program
Files/FreeSWITCH/sounds/en/us/callie/voicemail/vm-person.wav<br>
2013-11-22 08:54:07.741822 [DEBUG] switch_ivr_play_say.c:251 Handle
say:[1001] (en:en)<br>
2013-11-22 08:54:07.773044 [DEBUG] switch_ivr_play_say.c:1319 Codec
Activated L16@8000hz 1 channels 20ms<br>
2013-11-22 08:54:12.440823 [DEBUG] switch_ivr_play_say.c:1723 done
playing file
file_string://digits/1.wav!digits/0.wav!digits/0.wav!digits/1.wav<br>
2013-11-22 08:54:12.550102 [DEBUG] switch_ivr_play_say.c:251 Handle
play-file:[voicemail/vm-not_available.wav] (en:en)<br>
2013-11-22 08:54:12.596936 [DEBUG] switch_ivr_play_say.c:1319 Codec
Activated L16@8000hz 1 channels 20ms<br>
2013-11-22 08:54:14.532737 [DEBUG] switch_ivr_play_say.c:1723 done
playing file C:/Program
Files/FreeSWITCH/sounds/en/us/callie/voicemail/vm-not_available.wav<br>
2013-11-22 08:54:14.657628 [DEBUG] switch_ivr_play_say.c:70 No
language specified - Using [en]<br>
2013-11-22 08:54:14.657628 [DEBUG] switch_ivr_play_say.c:251 Handle
play-file:[voicemail/vm-record_message.wav] (en:en)<br>
2013-11-22 08:54:14.673239 [DEBUG] switch_ivr_play_say.c:1319 Codec
Activated L16@8000hz 1 channels 20ms<br>
2013-11-22 08:54:23.852683 [DEBUG] switch_ivr_play_say.c:1723 done
playing file C:/Program
Files/FreeSWITCH/sounds/en/us/callie/voicemail/vm-record_message.wav<br>
2013-11-22 08:54:26.459770 [DEBUG] switch_channel.c:3639 Send signal
<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:26.459770 [NOTICE] mod_loopback.c:942 Channel
[loopback/voicemail-a] has been answered<br>
2013-11-22 08:54:26.459770 [DEBUG] switch_channel.c:3685
(loopback/voicemail-a) Callstate Change EARLY -> ACTIVE<br>
2013-11-22 08:54:26.459770 [DEBUG] switch_core_session.c:892 Send
signal loopback/voicemail-b [BREAK]<br>
2013-11-22 08:54:26.459770 [DEBUG] mod_loopback.c:590
loopback/voicemail-b CHANNEL KILL<br>
2013-11-22 08:54:26.459770 [NOTICE] switch_ivr_play_say.c:409
Channel [loopback/voicemail-b] has been answered<br>
2013-11-22 08:54:26.459770 [DEBUG] switch_channel.c:3685
(loopback/voicemail-b) Callstate Change EARLY -> ACTIVE<br>
2013-11-22 08:54:26.459770 [DEBUG] switch_ivr_play_say.c:599 Raw
Codec Activated<br>
2013-11-22 08:54:26.459770 [DEBUG] switch_core_codec.c:221
loopback/voicemail-b Push codec L16:70<br>
2013-11-22 08:54:26.475382 [DEBUG] switch_core_session.c:954 Send
signal loopback/voicemail-a [BREAK]<br>
2013-11-22 08:54:26.475382 [DEBUG] mod_loopback.c:590
loopback/voicemail-a CHANNEL KILL<br>
2013-11-22 08:54:26.475382 [DEBUG] switch_core_session.c:954 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
recv 726 bytes from udp/[10.0.0.186]:5060 at 07:54:28.270681:<br>
------------------------------------------------------------------------<br>
BYE <a>sip:1001@10.0.0.57:5060;transport=udp</a>
SIP/2.0<br>
Via: SIP/2.0/UDP 10.0.0.186;rport;branch=z9hG4bK36N5Uryr0Z1Bc<br>
Max-Forwards: 70<br>
From: <a href="mailto:1009@10.0.0.57" target="_blank"><1009@10.0.0.57></a>;tag=08mryQ3v0DXtF<br>
To: <a><sip:1001@10.0.0.57></a>;tag=3jQFZFQ68HcSm<br>
Call-ID: 5c7dc01d-c230-1231-6982-0050c2d5dd07<br>
CSeq: 51571883 BYE<br>
User-Agent: sofia-sip/1.12.10<br>
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE,
SUBSCRIBE, NOTIFY, REFER, UPDATE<br>
Supported: timer, 100rel<br>
Proxy-Authorization: Digest username="1009", realm="10.0.0.57",
nonce="598a20fb-5ab3-4ecf-af64-71388a03b2e6",
cnonce="XIhwKMIwEjGCaQBQwtXdBw", algorithm=MD5,<br>
uri=<a>"sip:1001@10.0.0.57:5060;transport=udp"</a>,
response="f3fac72314ac0ff146b8561734438dc5", qop=auth, nc=00000002<br>
Content-Length: 0<br>
<br>
------------------------------------------------------------------------<br>
2013-11-22 08:54:28.255070 [DEBUG] switch_core_session.c:1037 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:28.270681 [NOTICE] sofia.c:715 Hangup <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[CS_EXECUTE] [NORMAL_CLEARING]<br>
2013-11-22 08:54:28.270681 [DEBUG] switch_channel.c:3211 Send signal
<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[KILL]<br>
2013-11-22 08:54:28.270681 [DEBUG] switch_core_session.c:1372 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
send 495 bytes to udp/[10.0.0.186]:5060 at 07:54:28.270681:<br>
------------------------------------------------------------------------<br>
SIP/2.0 200 OK<br>
Via: SIP/2.0/UDP
10.0.0.186;rport=5060;branch=z9hG4bK36N5Uryr0Z1Bc<br>
From: <a href="mailto:1009@10.0.0.57" target="_blank"><1009@10.0.0.57></a>;tag=08mryQ3v0DXtF<br>
To: <a><sip:1001@10.0.0.57></a>;tag=3jQFZFQ68HcSm<br>
Call-ID: 5c7dc01d-c230-1231-6982-0050c2d5dd07<br>
CSeq: 51571883 BYE<br>
User-Agent:
FreeSWITCH-mod_sofia/1.5.6b+git~20131101T202135Z~2589bf7750~32bit<br>
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE<br>
Supported: timer, precondition, path, replaces<br>
Content-Length: 0<br>
<br>
------------------------------------------------------------------------<br>
2013-11-22 08:54:28.270681 [DEBUG] switch_ivr_bridge.c:647 BRIDGE
THREAD DONE [<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>]<br>
2013-11-22 08:54:28.270681 [DEBUG] switch_ivr_bridge.c:672 Send
signal loopback/voicemail-a [BREAK]<br>
2013-11-22 08:54:28.270681 [DEBUG] mod_loopback.c:590
loopback/voicemail-a CHANNEL KILL<br>
2013-11-22 08:54:28.301904 [DEBUG] switch_ivr_bridge.c:647 BRIDGE
THREAD DONE [loopback/voicemail-a]<br>
2013-11-22 08:54:28.301904 [DEBUG] switch_ivr_bridge.c:672 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:28.301904 [NOTICE] switch_ivr_bridge.c:735 Hangup
loopback/voicemail-a [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]<br>
2013-11-22 08:54:28.301904 [DEBUG] switch_channel.c:3211 Send signal
loopback/voicemail-a [KILL]<br>
2013-11-22 08:54:28.301904 [DEBUG] mod_loopback.c:590
loopback/voicemail-a CHANNEL KILL<br>
2013-11-22 08:54:28.301904 [DEBUG] switch_core_session.c:1372 Send
signal loopback/voicemail-a [BREAK]<br>
2013-11-22 08:54:28.301904 [DEBUG] mod_loopback.c:590
loopback/voicemail-a CHANNEL KILL<br>
2013-11-22 08:54:28.301904 [DEBUG] switch_ivr_bridge.c:1538
loopback/voicemail-a skip receive message [UNBRIDGE] (channel is
hungup already)<br>
2013-11-22 08:54:28.301904 [DEBUG] switch_core_state_machine.c:532
(loopback/voicemail-a) State EXCHANGE_MEDIA going to sleep<br>
2013-11-22 08:54:28.301904 [DEBUG] switch_core_state_machine.c:467
(loopback/voicemail-a) Running State Change CS_HANGUP<br>
2013-11-22 08:54:28.301904 [DEBUG] switch_ivr_bridge.c:1541 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
skip receive message [UNBRIDGE] (channel is hungup already)<br>
2013-11-22 08:54:28.301904 [DEBUG] switch_core_session.c:2841 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup<br>
already)<br>
2013-11-22 08:54:28.301904 [DEBUG] switch_core_state_machine.c:529 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State EXECUTE going to sleep<br>
2013-11-22 08:54:28.301904 [DEBUG] switch_core_state_machine.c:467 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
Running State Change CS_HANGUP<br>
2013-11-22 08:54:28.301904 [DEBUG] switch_core_state_machine.c:730
(loopback/voicemail-a) State HANGUP<br>
2013-11-22 08:54:28.301904 [DEBUG] mod_loopback.c:537
loopback/voicemail-a CHANNEL HANGUP<br>
2013-11-22 08:54:28.301904 [NOTICE] mod_loopback.c:553 Hangup
loopback/voicemail-b [CS_EXECUTE] [NORMAL_CLEARING]<br>
2013-11-22 08:54:28.301904 [DEBUG] switch_channel.c:3211 Send signal
loopback/voicemail-b [KILL]<br>
2013-11-22 08:54:28.301904 [DEBUG] switch_core_state_machine.c:730 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State HANGUP<br>
2013-11-22 08:54:28.301904 [DEBUG] mod_loopback.c:590
loopback/voicemail-b CHANNEL KILL<br>
2013-11-22 08:54:28.317515 [DEBUG] mod_sofia.c:413 Channel <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
hanging up, cause: NORMAL_CLEARING<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_session.c:1372 Send
signal loopback/voicemail-b [BREAK]<br>
2013-11-22 08:54:28.317515 [DEBUG] mod_loopback.c:590
loopback/voicemail-b CHANNEL KILL<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:58
loopback/voicemail-a Standard HANGUP, cause: NORMAL_CLEARING<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:730
(loopback/voicemail-a) State HANGUP going to sleep<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:743
(loopback/voicemail-a) Callstate Change ACTIVE -> HANGUP<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:498
(loopback/voicemail-a) State Change CS_HANGUP -> CS_REPORTING<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_session.c:1372 Send
signal loopback/voicemail-a [BREAK]<br>
2013-11-22 08:54:28.317515 [DEBUG] mod_loopback.c:590
loopback/voicemail-a CHANNEL KILL<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:467
(loopback/voicemail-a) Running State Change CS_REPORTING<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_codec.c:246
loopback/voicemail-b Restore previous codec PCMU:0.<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:815
(loopback/voicemail-a) State REPORTING<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:102
loopback/voicemail-a Standard REPORTING, cause: NORMAL_CLEARING<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:815
(loopback/voicemail-a) State REPORTING going to sleep<br>
2013-11-22 08:54:28.317515 [DEBUG] mod_voicemail.c:1237 Message is
less than minimum record length: 3, discarding it.<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:492
(loopback/voicemail-a) State Change CS_REPORTING -> CS_DESTROY<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_session.c:1372 Send
signal loopback/voicemail-a [BREAK]<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_session.c:2841
loopback/voicemail-b skip receive message
[APPLICATION_EXEC_COMPLETE] (channel is hungup already)<br>
2013-11-22 08:54:28.317515 [DEBUG] mod_loopback.c:590
loopback/voicemail-a CHANNEL KILL<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:529
(loopback/voicemail-b) State EXECUTE going to sleep<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_session.c:1580
Session 2 (loopback/voicemail-a) Locked, Waiting on external
entities<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:467
(loopback/voicemail-b) Running State Change CS_HANGUP<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:58 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Standard HANGUP, cause: NORMAL_CLEARING<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:730 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State HANGUP going to sleep<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:743 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
Callstate Change ACTIVE -> HANGUP<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:498 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State Change CS_HANGUP -> CS_REPORTING<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:730
(loopback/voicemail-b) State HANGUP<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_session.c:1372 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:28.317515 [DEBUG] mod_loopback.c:537
loopback/voicemail-b CHANNEL HANGUP<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:58
loopback/voicemail-b Standard HANGUP, cause: NORMAL_CLEARING<br>
2013-11-22 08:54:28.317515 [NOTICE] switch_core_session.c:1598
Session 2 (loopback/voicemail-a) Ended<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:467 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
Running State Change CS_REPORTING<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:730
(loopback/voicemail-b) State HANGUP going to sleep<br>
2013-11-22 08:54:28.317515 [NOTICE] switch_core_session.c:1602 Close
Channel loopback/voicemail-a [CS_DESTROY]<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:743
(loopback/voicemail-b) Callstate Change ACTIVE -> HANGUP<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:815 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State REPORTING<br>
2013-11-22 08:54:28.317515 [DEBUG] switch_core_state_machine.c:617
(loopback/voicemail-a) Callstate Change HANGUP -> DOWN<br>
2013-11-22 08:54:28.333126 [DEBUG] switch_core_state_machine.c:498
(loopback/voicemail-b) State Change CS_HANGUP -> CS_REPORTING<br>
2013-11-22 08:54:28.333126 [DEBUG] switch_core_state_machine.c:620
(loopback/voicemail-a) Running State Change CS_DESTROY<br>
2013-11-22 08:54:28.333126 [DEBUG] switch_core_session.c:1372 Send
signal loopback/voicemail-b [BREAK]<br>
2013-11-22 08:54:28.333126 [DEBUG] mod_loopback.c:590
loopback/voicemail-b CHANNEL KILL<br>
2013-11-22 08:54:28.333126 [DEBUG] switch_core_state_machine.c:630
(loopback/voicemail-a) State DESTROY<br>
2013-11-22 08:54:28.333126 [DEBUG] switch_core_state_machine.c:467
(loopback/voicemail-b) Running State Change CS_REPORTING<br>
2013-11-22 08:54:28.333126 [DEBUG] switch_core_state_machine.c:109
loopback/voicemail-a Standard DESTROY<br>
2013-11-22 08:54:28.333126 [DEBUG] switch_core_state_machine.c:630
(loopback/voicemail-a) State DESTROY going to sleep<br>
2013-11-22 08:54:28.333126 [DEBUG] switch_core_state_machine.c:815
(loopback/voicemail-b) State REPORTING<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_state_machine.c:102
loopback/voicemail-b Standard REPORTING, cause: NORMAL_CLEARING<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_state_machine.c:102 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Standard REPORTING, cause: NORMAL_CLEARING<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_state_machine.c:815 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State REPORTING going to sleep<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_state_machine.c:815
(loopback/voicemail-b) State REPORTING going to sleep<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_state_machine.c:492
(loopback/voicemail-b) State Change CS_REPORTING -> CS_DESTROY<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_state_machine.c:492 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State Change CS_REPORTING -> CS_DESTROY<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_session.c:1372 Send
signal loopback/voicemail-b [BREAK]<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_session.c:1372 Send
signal <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[BREAK]<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_session.c:1580
Session 1 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
Locked, Waiting on external entities<br>
2013-11-22 08:54:28.379960 [DEBUG] mod_loopback.c:590
loopback/voicemail-b CHANNEL KILL<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_session.c:1580
Session 3 (loopback/voicemail-b) Locked, Waiting on external
entities<br>
2013-11-22 08:54:28.379960 [NOTICE] switch_core_session.c:1598
Session 1 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
Ended<br>
2013-11-22 08:54:28.379960 [NOTICE] switch_core_session.c:1602 Close
Channel <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
[CS_DESTROY]<br>
2013-11-22 08:54:28.379960 [NOTICE] switch_core_session.c:1598
Session 3 (loopback/voicemail-b) Ended<br>
2013-11-22 08:54:28.379960 [NOTICE] switch_core_session.c:1602 Close
Channel loopback/voicemail-b [CS_DESTROY]<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_state_machine.c:617 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
Callstate Change HANGUP -> DOWN<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_state_machine.c:617
(loopback/voicemail-b) Callstate Change HANGUP -> DOWN<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_state_machine.c:620 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
Running State Change CS_DESTROY<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_state_machine.c:620
(loopback/voicemail-b) Running State Change CS_DESTROY<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_state_machine.c:630 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State DESTROY<br>
2013-11-22 08:54:28.379960 [DEBUG] mod_sofia.c:323 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
SOFIA DESTROY<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_state_machine.c:630
(loopback/voicemail-b) State DESTROY<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_state_machine.c:109
loopback/voicemail-b Standard DESTROY<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_state_machine.c:109 <a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>
Standard DESTROY<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_state_machine.c:630 (<a href="mailto:sofia/internal/1009@10.0.0.57" target="_blank">sofia/internal/1009@10.0.0.57</a>)
State DESTROY going to sleep<br>
2013-11-22 08:54:28.379960 [DEBUG] switch_core_state_machine.c:630
(loopback/voicemail-b) State DESTROY going to sleep<br>
[/capture]<br>
<br>
Can you see anything unusual in the trace above?<br>
-- <br>
JPI<br>
<br>
<br>
</div></div><div class="im"><div>Le 21/11/2013 22:06, Anthony Minessale
a écrit :<br>
</div>
</div><blockquote type="cite">
<div dir="ltr">Do you get different results when you call it with
some other phone in place of sofia_cli ?
<div class="im"><div><br>
</div>
<div>Can you get a full capture of the debug logs</div>
<div><br>
</div>
<div>sofia global siptrace on</div>
<div>console loglevel debug</div>
</div><div class="gmail_extra">sofia tracelevel alert<br>
<br>
[... snip ...]<div><div class="h5"><br>
<br>
-- <br>
Anthony Minessale II<br>
<br>
FreeSWITCH <a href="http://www.freeswitch.org/" target="_blank">http://www.freeswitch.org/</a><br>
ClueCon <a href="http://www.cluecon.com/" target="_blank">http://www.cluecon.com/</a><br>
Twitter: <a href="http://twitter.com/FreeSWITCH_wire" target="_blank">http://twitter.com/FreeSWITCH_wire</a><br>
<br>
AIM: anthm<br>
<a href="mailto:MSN%3Aanthony_minessale@hotmail.com" target="_blank">MSN:anthony_minessale@hotmail.com</a><br>
GTALK/JABBER/<a href="mailto:PAYPAL%3Aanthony.minessale@gmail.com" target="_blank">PAYPAL:anthony.minessale@gmail.com</a><br>
IRC: <a href="http://irc.freenode.net" target="_blank">irc.freenode.net</a>
#freeswitch<br>
<br>
FreeSWITCH Developer Conference<br>
<a href="mailto:sip%3A888@conference.freeswitch.org" target="_blank">sip:888@conference.freeswitch.org</a><br>
<a href="mailto:googletalk%3Aconf%2B888@conference.freeswitch.org" target="_blank">googletalk:conf+888@conference.freeswitch.org</a><br>
pstn:<a href="tel:%2B19193869900" value="+19193869900" target="_blank">+19193869900</a> </div></div></div>
</div><div><div class="h5">
<br>
<fieldset></fieldset>
<br>
<pre>_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
<a href="mailto:consulting@freeswitch.org" target="_blank">consulting@freeswitch.org</a>
<a href="http://www.freeswitchsolutions.com" target="_blank">http://www.freeswitchsolutions.com</a>
FreeSWITCH-powered IP PBX: The CudaTel Communication Server
<a href="http://www.cudatel.com" target="_blank">http://www.cudatel.com</a>
Official FreeSWITCH Sites
<a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a>
<a href="http://wiki.freeswitch.org" target="_blank">http://wiki.freeswitch.org</a>
<a href="http://www.cluecon.com" target="_blank">http://www.cluecon.com</a>
FreeSWITCH-dev mailing list
<a href="mailto:FreeSWITCH-dev@lists.freeswitch.org" target="_blank">FreeSWITCH-dev@lists.freeswitch.org</a>
<a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev" target="_blank">http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev</a>
UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-dev" target="_blank">http://lists.freeswitch.org/mailman/options/freeswitch-dev</a>
<a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a>
</pre>
</div></div></blockquote>
</div>
<br>_________________________________________________________________________<br>
Professional FreeSWITCH Consulting Services:<br>
<a href="mailto:consulting@freeswitch.org">consulting@freeswitch.org</a><br>
<a href="http://www.freeswitchsolutions.com" target="_blank">http://www.freeswitchsolutions.com</a><br>
<br>
FreeSWITCH-powered IP PBX: The CudaTel Communication Server<br>
<a href="http://www.cudatel.com" target="_blank">http://www.cudatel.com</a><br>
<br>
Official FreeSWITCH Sites<br>
<a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><br>
<a href="http://wiki.freeswitch.org" target="_blank">http://wiki.freeswitch.org</a><br>
<a href="http://www.cluecon.com" target="_blank">http://www.cluecon.com</a><br>
<br>
FreeSWITCH-dev mailing list<br>
<a href="mailto:FreeSWITCH-dev@lists.freeswitch.org">FreeSWITCH-dev@lists.freeswitch.org</a><br>
<a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev" target="_blank">http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev</a><br>
UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-dev" target="_blank">http://lists.freeswitch.org/mailman/options/freeswitch-dev</a><br>
<a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><br>
<br></blockquote></div><br><br clear="all"><div><br></div>-- <br>Anthony Minessale II<br><br>FreeSWITCH <a href="http://www.freeswitch.org/">http://www.freeswitch.org/</a><br>ClueCon <a href="http://www.cluecon.com/">http://www.cluecon.com/</a><br>
Twitter: <a href="http://twitter.com/FreeSWITCH_wire">http://twitter.com/FreeSWITCH_wire</a><br><br>AIM: anthm<br><a href="mailto:MSN%3Aanthony_minessale@hotmail.com">MSN:anthony_minessale@hotmail.com</a><br>GTALK/JABBER/<a href="mailto:PAYPAL%3Aanthony.minessale@gmail.com">PAYPAL:anthony.minessale@gmail.com</a><br>
IRC: <a href="http://irc.freenode.net">irc.freenode.net</a> #freeswitch<br><br>FreeSWITCH Developer Conference<br><a href="mailto:sip%3A888@conference.freeswitch.org">sip:888@conference.freeswitch.org</a><br><a href="mailto:googletalk%3Aconf%2B888@conference.freeswitch.org">googletalk:conf+888@conference.freeswitch.org</a><br>
pstn:+19193869900
</div>