[Freeswitch-users] Audio problems in freeswitch
Utkarsh Singh
Utkarsh.Singh at timesinternet.in
Fri Oct 9 19:33:51 MSD 2015
My freeswitch status is:
Name Type DataState
=================================================================================================
external-ipv6profile sip:mod_sofia@[2401:1800:7800:101:4628:b452:ff1c:8e5]:5080RUNNING (0)
external-ipv6::example.comgateway sip:joeuser at example.com<mailto:joeuser at example.com>NOREG
119.9.73.183 alias internalALIASED
externalprofile sip:mod_sofia at 119.9.73.183<mailto:mod_sofia at 119.9.73.183>:5092RUNNING (0)
external::example.comgateway sip:joeuser at example.com<mailto:joeuser at example.com>NOREG
internal-ipv6profile sip:mod_sofia@[2401:1800:7800:101:4628:b452:ff1c:8e5]:5060RUNNING (0)
internalprofile sip:mod_sofia at 119.9.73.183<mailto:mod_sofia at 119.9.73.183>:5090RUNNING (0)
=================================================================================================
I have integrated freeswitch with kamailio on the same server. When I only use kamailio as voip. I am able to listen to voice , but when I use only freeswitch or both freeswitch and kamailio I do not get voice.
I EVEN DO NOT GET FREESWITCH VOICEMAIL SOUNDS.(WHEN USER IS NOT REGISTERED).
I think its a minor issue but I am not able to figure it out.
The logs are:
Dialplan: sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 Action sleep(1000)
Dialplan: sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension})
2015-10-09 17:13:07.460415 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) State Change CS_ROUTING -> CS_EXECUTE
2015-10-09 17:13:07.460415 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 [BREAK]
2015-10-09 17:13:07.460415 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) State ROUTING going to sleep
2015-10-09 17:13:07.460415 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) Running State Change CS_EXECUTE
2015-10-09 17:13:07.460415 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) State EXECUTE
2015-10-09 17:13:07.460415 [DEBUG] mod_sofia.c:178 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 SOFIA EXECUTE
2015-10-09 17:13:07.460415 [DEBUG] switch_core_state_machine.c:258 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 Standard EXECUTE
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 set(open=true)
2015-10-09 17:13:07.460415 [DEBUG] mod_dptools.c:1555 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 SET [open]=[true]
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 hash(insert/119.9.73.183-spymap/1014/e8c1522e-6e7a-11e5-80c2-0d55cfaac62d)
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 hash(insert/119.9.73.183-last_dial/1014/1018)
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 hash(insert/119.9.73.183-last_dial/global/e8c1522e-6e7a-11e5-80c2-0d55cfaac62d)
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 export(RFC2822_DATE=Fri, 09 Oct 2015 17:13:07 +0530)
2015-10-09 17:13:07.460415 [DEBUG] switch_channel.c:1267 EXPORT (export_vars) [RFC2822_DATE]=[Fri, 09 Oct 2015 17:13:07 +0530]
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 export(dialed_extension=1018)
2015-10-09 17:13:07.460415 [DEBUG] switch_channel.c:1267 EXPORT (export_vars) [dialed_extension]=[1018]
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 bind_meta_app(1 b s execute_extension::dx XML features)
2015-10-09 17:13:07.460415 [INFO] switch_ivr_async.c:3932 Bound B-Leg: *1 execute_extension::dx XML features
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/1014.2015-10-09-17-13-07.wav)
2015-10-09 17:13:07.460415 [INFO] switch_ivr_async.c:3932 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/1014.2015-10-09-17-13-07.wav
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 bind_meta_app(3 b s execute_extension::cf XML features)
2015-10-09 17:13:07.460415 [INFO] switch_ivr_async.c:3932 Bound B-Leg: *3 execute_extension::cf XML features
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 bind_meta_app(4 b s execute_extension::att_xfer XML features)
2015-10-09 17:13:07.460415 [INFO] switch_ivr_async.c:3932 Bound B-Leg: *4 execute_extension::att_xfer XML features
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 set(ringback=%(2000,4000,440,480))
2015-10-09 17:13:07.460415 [DEBUG] mod_dptools.c:1555 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 SET [ringback]=[%(2000,4000,440,480)]
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 set(transfer_ringback=local_stream://moh)
2015-10-09 17:13:07.460415 [DEBUG] mod_dptools.c:1555 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 SET [transfer_ringback]=[local_stream://moh]
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 set(call_timeout=30)
2015-10-09 17:13:07.480408 [DEBUG] mod_dptools.c:1555 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 SET [call_timeout]=[30]
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 set(hangup_after_bridge=true)
2015-10-09 17:13:07.480408 [DEBUG] mod_dptools.c:1555 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 SET [hangup_after_bridge]=[true]
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 set(continue_on_fail=true)
2015-10-09 17:13:07.480408 [DEBUG] mod_dptools.c:1555 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 SET [continue_on_fail]=[true]
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 hash(insert/119.9.73.183-call_return/1018/1014)
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 hash(insert/119.9.73.183-last_dial_ext/1018/e8c1522e-6e7a-11e5-80c2-0d55cfaac62d)
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 set(called_party_callgroup=techsupport)
2015-10-09 17:13:07.480408 [DEBUG] mod_dptools.c:1555 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 SET [called_party_callgroup]=[techsupport]
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 hash(insert/119.9.73.183-last_dial_ext/techsupport/e8c1522e-6e7a-11e5-80c2-0d55cfaac62d)
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 hash(insert/119.9.73.183-last_dial_ext/global/e8c1522e-6e7a-11e5-80c2-0d55cfaac62d)
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 hash(insert/119.9.73.183-last_dial/techsupport/e8c1522e-6e7a-11e5-80c2-0d55cfaac62d)
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 bridge(user/1018 at 119.9.73.183<mailto:user/1018 at 119.9.73.183>)
2015-10-09 17:13:07.480408 [DEBUG] switch_channel.c:1221 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 09 Oct 2015 17:13:07 +0530] to event
2015-10-09 17:13:07.480408 [DEBUG] switch_channel.c:1221 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 EXPORTING[export_vars] [dialed_extension]=[1018] to event
2015-10-09 17:13:07.480408 [DEBUG] switch_ivr_originate.c:2101 Parsing global variables
2015-10-09 17:13:07.480408 [DEBUG] switch_channel.c:1221 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 09 Oct 2015 17:13:07 +0530] to event
2015-10-09 17:13:07.480408 [DEBUG] switch_channel.c:1221 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 EXPORTING[export_vars] [dialed_extension]=[1018] to event
2015-10-09 17:13:07.480408 [DEBUG] switch_ivr_originate.c:2101 Parsing global variables
2015-10-09 17:13:07.480408 [DEBUG] switch_event.c:1698 Parsing variable [sip_invite_domain]=[119.9.73.183]
2015-10-09 17:13:07.480408 [DEBUG] switch_event.c:1698 Parsing variable [presence_id]=[1018 at 119.9.73.183<mailto:1018 at 119.9.73.183>]
2015-10-09 17:13:07.480408 [NOTICE] switch_ivr_originate.c:2736 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
2015-10-09 17:13:07.480408 [DEBUG] switch_ivr_originate.c:3724 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED]
2015-10-09 17:13:07.480408 [NOTICE] switch_ivr_originate.c:2736 Cannot create outgoing channel of type [user] cause: [USER_NOT_REGISTERED]
2015-10-09 17:13:07.480408 [DEBUG] switch_ivr_originate.c:3724 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED]
2015-10-09 17:13:07.480408 [INFO] mod_dptools.c:3535 Originate Failed. Cause: USER_NOT_REGISTERED
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 answer()
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G729:18:8000:20:8000:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [GSM:3:8000:20:13200:1]/[GSM:3:8000:20:13200:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3734 Audio Codec Compare [GSM:3:8000:20:13200:1] ++++ is saved as a match
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [speex:110:8000:20:0:1]/[G729:18:8000:20:8000:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [speex:110:8000:20:0:1]/[GSM:3:8000:20:13200:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3734 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3734 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [iLBC:98:8000:30:0:1]/[G729:18:8000:20:8000:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [iLBC:98:8000:30:0:1]/[GSM:3:8000:20:13200:1]
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3595 Set telephone-event payload to 101
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:2506 Set Codec sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 GSM/8000 20 ms 160 samples 13200 bits 1 channels
2015-10-09 17:13:07.480408 [DEBUG] switch_core_codec.c:111 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 Original read codec set to GSM:3
2015-10-09 17:13:07.480408 [DEBUG] switch_core_media.c:3942 Set 2833 dtmf send/recv payload to 101
2015-10-09 17:13:08.720433 [DEBUG] switch_core_media.c:4725 STUN Success [119.9.73.183]:[31858]
2015-10-09 17:13:08.720433 [DEBUG] switch_core_media.c:4729 STUN Not Required ip and port match. [119.9.73.183]:[31858]
2015-10-09 17:13:08.720433 [DEBUG] switch_core_media.c:5178 AUDIO RTP [sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090] 119.9.73.183 port 31858 -> 10.150.216.71 port 8000 codec: 3 ms: 20
2015-10-09 17:13:08.720433 [DEBUG] switch_rtp.c:3579 Starting timer [soft] 160 bytes per 20ms
2015-10-09 17:13:08.720433 [DEBUG] switch_core_media.c:5476 Set 2833 dtmf send payload to 101
2015-10-09 17:13:08.720433 [DEBUG] switch_core_media.c:5482 Set 2833 dtmf receive payload to 101
2015-10-09 17:13:08.720433 [DEBUG] switch_core_media.c:5504 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 Set rtp dtmf delay to 40
2015-10-09 17:13:08.720433 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090!
2015-10-09 17:13:08.720433 [DEBUG] switch_channel.c:3419 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) Callstate Change RINGING -> EARLY
2015-10-09 17:13:08.720433 [DEBUG] mod_sofia.c:780 Local SDP sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090:
v=0
o=FreeSWITCH 1444359130 1444359131 IN IP4 119.9.73.183
s=FreeSWITCH
c=IN IP4 119.9.73.183
t=0 0
m=audio 31858 RTP/AVP 3 101
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2015-10-09 17:13:08.720433 [DEBUG] switch_core_session.c:913 Send signal sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 [BREAK]
2015-10-09 17:13:08.720433 [NOTICE] mod_dptools.c:1378 Channel [sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090] has been answered
2015-10-09 17:13:08.720433 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 [BREAK]
2015-10-09 17:13:08.720433 [DEBUG] switch_channel.c:3711 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) Callstate Change EARLY -> ACTIVE
2015-10-09 17:13:08.720433 [DEBUG] sofia.c:6659 Channel sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 entering state [completed][200]
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 sleep(1000)
2015-10-09 17:13:08.720433 [DEBUG] switch_ivr.c:191 Codec Activated L16 at 8000hz 1 channels 20ms
2015-10-09 17:13:08.860540 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 [BREAK]
2015-10-09 17:13:08.860540 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 [BREAK]
2015-10-09 17:13:08.860540 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 [BREAK]
2015-10-09 17:13:08.860540 [DEBUG] sofia.c:6659 Channel sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 entering state [ready][200]
EXECUTE sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 bridge(loopback/app=voicemail:default 119.9.73.183 1018)
2015-10-09 17:13:09.740418 [DEBUG] switch_channel.c:1786 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) Callstate Change ACTIVE -> RING_WAIT
2015-10-09 17:13:09.740418 [DEBUG] switch_channel.c:1221 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 09 Oct 2015 17:13:07 +0530] to event
2015-10-09 17:13:09.740418 [DEBUG] switch_channel.c:1221 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 EXPORTING[export_vars] [dialed_extension]=[1018] to event
2015-10-09 17:13:09.740418 [DEBUG] switch_ivr_originate.c:2101 Parsing global variables
2015-10-09 17:13:09.740418 [NOTICE] switch_channel.c:1075 New Channel loopback/app=voicemail:default 119.9.73.183 1018-a [ea1cd2ec-6e7a-11e5-80e5-0d55cfaac62d]
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:157 loopback/app=voicemail:default 119.9.73.183 1018-a setup codec GSM/8000/20
2015-10-09 17:13:09.740418 [NOTICE] switch_channel.c:1073 Rename Channel loopback/app=voicemail:default 119.9.73.183 1018-a->loopback/voicemail-a [ea1cd2ec-6e7a-11e5-80e5-0d55cfaac62d]
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:1160 (loopback/voicemail-a) State Change CS_NEW -> CS_INIT
2015-10-09 17:13:09.740418 [DEBUG] switch_core_session.c:1397 Send signal loopback/voicemail-a [BREAK]
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:590 loopback/voicemail-a CHANNEL KILL
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:472 (loopback/voicemail-a) Running State Change CS_INIT
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:512 (loopback/voicemail-a) State INIT
2015-10-09 17:13:09.740418 [NOTICE] switch_channel.c:1075 New Channel loopback/voicemail-b [ea1cfa74-6e7a-11e5-80e9-0d55cfaac62d]
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:157 loopback/voicemail-b setup codec GSM/8000/20
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:273 (loopback/voicemail-b) State Change CS_NEW -> CS_INIT
2015-10-09 17:13:09.740418 [DEBUG] switch_core_session.c:1397 Send signal loopback/voicemail-b [BREAK]
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:590 loopback/voicemail-b CHANNEL KILL
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:359 (loopback/voicemail-a) State Change CS_INIT -> CS_ROUTING
2015-10-09 17:13:09.740418 [DEBUG] switch_core_session.c:1397 Send signal loopback/voicemail-a [BREAK]
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:590 loopback/voicemail-a CHANNEL KILL
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:512 (loopback/voicemail-a) State INIT going to sleep
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:472 (loopback/voicemail-b) Running State Change CS_INIT
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:512 (loopback/voicemail-b) State INIT
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:359 (loopback/voicemail-b) State Change CS_INIT -> CS_ROUTING
2015-10-09 17:13:09.740418 [DEBUG] switch_core_session.c:1397 Send signal loopback/voicemail-b [BREAK]
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:590 loopback/voicemail-b CHANNEL KILL
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:512 (loopback/voicemail-b) State INIT going to sleep
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:472 (loopback/voicemail-a) Running State Change CS_ROUTING
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:472 (loopback/voicemail-b) Running State Change CS_ROUTING
2015-10-09 17:13:09.740418 [DEBUG] switch_channel.c:2204 (loopback/voicemail-b) Callstate Change DOWN -> RINGING
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:528 (loopback/voicemail-a) State ROUTING
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:391 loopback/voicemail-a CHANNEL ROUTING
2015-10-09 17:13:09.740418 [DEBUG] switch_ivr_originate.c:67 (loopback/voicemail-a) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2015-10-09 17:13:09.740418 [DEBUG] switch_core_session.c:1397 Send signal loopback/voicemail-a [BREAK]
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:590 loopback/voicemail-a CHANNEL KILL
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:528 (loopback/voicemail-a) State ROUTING going to sleep
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:472 (loopback/voicemail-a) Running State Change CS_CONSUME_MEDIA
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:528 (loopback/voicemail-b) State ROUTING
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:391 loopback/voicemail-b CHANNEL ROUTING
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:410 (loopback/voicemail-b) State Change CS_ROUTING -> CS_EXECUTE
2015-10-09 17:13:09.740418 [DEBUG] switch_core_session.c:1397 Send signal loopback/voicemail-b [BREAK]
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:590 loopback/voicemail-b CHANNEL KILL
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:528 (loopback/voicemail-b) State ROUTING going to sleep
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:472 (loopback/voicemail-b) Running State Change CS_EXECUTE
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:547 (loopback/voicemail-a) State CONSUME_MEDIA
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:535 (loopback/voicemail-b) State EXECUTE
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:650 CHANNEL CONSUME_MEDIA
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:433 loopback/voicemail-b CHANNEL EXECUTE
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:547 (loopback/voicemail-a) State CONSUME_MEDIA going to sleep
2015-10-09 17:13:09.740418 [DEBUG] switch_core_state_machine.c:258 loopback/voicemail-b Standard EXECUTE
EXECUTE loopback/voicemail-b pre_answer()
2015-10-09 17:13:09.740418 [NOTICE] mod_loopback.c:947 Pre-Answer loopback/voicemail-a!
2015-10-09 17:13:09.740418 [DEBUG] switch_channel.c:3415 Send signal sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 [BREAK]
2015-10-09 17:13:09.740418 [DEBUG] switch_channel.c:3419 (loopback/voicemail-a) Callstate Change DOWN -> EARLY
2015-10-09 17:13:09.740418 [DEBUG] switch_core_session.c:913 Send signal loopback/voicemail-b [BREAK]
2015-10-09 17:13:09.740418 [DEBUG] mod_loopback.c:590 loopback/voicemail-b CHANNEL KILL
2015-10-09 17:13:09.740418 [NOTICE] mod_dptools.c:1413 Pre-Answer loopback/voicemail-b!
2015-10-09 17:13:09.740418 [DEBUG] switch_channel.c:3419 (loopback/voicemail-b) Callstate Change RINGING -> EARLY
EXECUTE loopback/voicemail-b voicemail(default 119.9.73.183 1018)
2015-10-09 17:13:09.740418 [DEBUG] switch_ivr.c:191 Codec Activated L16 at 8000hz 1 channels 20ms
2015-10-09 17:13:09.760410 [DEBUG] switch_ivr_originate.c:3581 Originate Resulted in Success: [loopback/voicemail-a]
2015-10-09 17:13:09.760410 [DEBUG] switch_channel.c:1999 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) Callstate Change RING_WAIT -> ACTIVE
2015-10-09 17:13:09.760410 [DEBUG] switch_core_session.c:913 Send signal loopback/voicemail-a [BREAK]
2015-10-09 17:13:09.760410 [DEBUG] mod_loopback.c:590 loopback/voicemail-a CHANNEL KILL
2015-10-09 17:13:09.760410 [DEBUG] switch_core_session.c:913 Send signal sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 [BREAK]
2015-10-09 17:13:09.760410 [DEBUG] switch_ivr_bridge.c:1474 (loopback/voicemail-a) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2015-10-09 17:13:09.760410 [DEBUG] switch_core_session.c:1397 Send signal loopback/voicemail-a [BREAK]
2015-10-09 17:13:09.760410 [DEBUG] mod_loopback.c:590 loopback/voicemail-a CHANNEL KILL
2015-10-09 17:13:09.760410 [DEBUG] switch_core_state_machine.c:472 (loopback/voicemail-a) Running State Change CS_EXCHANGE_MEDIA
2015-10-09 17:13:09.760410 [DEBUG] switch_core_state_machine.c:538 (loopback/voicemail-a) State EXCHANGE_MEDIA
2015-10-09 17:13:09.760410 [DEBUG] mod_loopback.c:612 CHANNEL LOOPBACK
2015-10-09 17:13:09.840422 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
2015-10-09 17:13:09.860417 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-person.wav] (en:en)
2015-10-09 17:13:09.860417 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16 at 8000hz 1 channels 20ms
2015-10-09 17:13:11.200540 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-person.wav
2015-10-09 17:13:11.200540 [DEBUG] switch_ivr.c:191 Codec Activated L16 at 8000hz 1 channels 20ms
2015-10-09 17:13:11.320536 [DEBUG] switch_ivr_play_say.c:250 Handle say:[1018] (en:en)
2015-10-09 17:13:11.320536 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16 at 8000hz 1 channels 20ms
2015-10-09 17:13:13.340417 [DEBUG] switch_ivr_play_say.c:1747 done playing file file_string://digits/1.wav!digits/0.wav!digits/1.wav!digits/8.wav
2015-10-09 17:13:13.340417 [DEBUG] switch_ivr.c:191 Codec Activated L16 at 8000hz 1 channels 20ms
2015-10-09 17:13:13.460543 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-not_available.wav] (en:en)
2015-10-09 17:13:13.460543 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16 at 8000hz 1 channels 20ms
2015-10-09 17:13:14.440420 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-not_available.wav
2015-10-09 17:13:14.440420 [DEBUG] switch_ivr.c:191 Codec Activated L16 at 8000hz 1 channels 20ms
2015-10-09 17:13:14.540443 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
2015-10-09 17:13:14.560425 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-record_message.wav] (en:en)
2015-10-09 17:13:14.560425 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16 at 8000hz 1 channels 20ms
2015-10-09 17:13:19.140420 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-record_message.wav
2015-10-09 17:13:19.140420 [DEBUG] switch_ivr.c:191 Codec Activated L16 at 8000hz 1 channels 20ms
2015-10-09 17:13:20.260422 [DEBUG] switch_ivr_play_say.c:593 Raw Codec Activated
2015-10-09 17:13:20.260422 [DEBUG] switch_core_codec.c:221 loopback/voicemail-b Push codec L16:100
2015-10-09 17:13:22.260461 [DEBUG] switch_core_codec.c:246 loopback/voicemail-b Restore previous codec GSM:3.
2015-10-09 17:13:22.260461 [DEBUG] mod_voicemail.c:1245 Message is less than minimum record length: 3, discarding it.
2015-10-09 17:13:22.260461 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
2015-10-09 17:13:22.260461 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-too-small.wav] (en:en)
2015-10-09 17:13:22.280421 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16 at 8000hz 1 channels 20ms
2015-10-09 17:13:26.320413 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-too-small.wav
2015-10-09 17:13:26.320413 [DEBUG] switch_ivr.c:191 Codec Activated L16 at 8000hz 1 channels 20ms
2015-10-09 17:13:26.420413 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
2015-10-09 17:13:26.420413 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-record_message.wav] (en:en)
2015-10-09 17:13:26.440408 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16 at 8000hz 1 channels 20ms
2015-10-09 17:13:31.020425 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-record_message.wav
2015-10-09 17:13:31.020425 [DEBUG] switch_ivr.c:191 Codec Activated L16 at 8000hz 1 channels 20ms
2015-10-09 17:13:32.140423 [DEBUG] switch_ivr_play_say.c:593 Raw Codec Activated
2015-10-09 17:13:32.140423 [DEBUG] switch_core_codec.c:221 loopback/voicemail-b Push codec L16:100
2015-10-09 17:13:34.140416 [DEBUG] switch_core_codec.c:246 loopback/voicemail-b Restore previous codec GSM:3.
2015-10-09 17:13:34.140416 [DEBUG] mod_voicemail.c:1245 Message is less than minimum record length: 3, discarding it.
2015-10-09 17:13:34.140416 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
2015-10-09 17:13:34.140416 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-too-small.wav] (en:en)
2015-10-09 17:13:34.160413 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16 at 8000hz 1 channels 20ms
2015-10-09 17:13:37.100420 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 [BREAK]
2015-10-09 17:13:37.120420 [NOTICE] sofia.c:971 Hangup sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 [CS_EXECUTE] [NORMAL_CLEARING]
2015-10-09 17:13:37.120420 [DEBUG] switch_channel.c:3242 Send signal sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 [KILL]
2015-10-09 17:13:37.120420 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 [BREAK]
2015-10-09 17:13:37.120420 [DEBUG] switch_ivr_bridge.c:660 BRIDGE THREAD DONE [sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090]
2015-10-09 17:13:37.120420 [DEBUG] switch_ivr_bridge.c:690 Send signal loopback/voicemail-a [BREAK]
2015-10-09 17:13:37.120420 [DEBUG] mod_loopback.c:590 loopback/voicemail-a CHANNEL KILL
2015-10-09 17:13:37.120420 [DEBUG] switch_ivr_bridge.c:579 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 ending bridge by request from write function
2015-10-09 17:13:37.120420 [DEBUG] switch_ivr_bridge.c:660 BRIDGE THREAD DONE [loopback/voicemail-a]
2015-10-09 17:13:37.120420 [DEBUG] switch_ivr_bridge.c:690 Send signal sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 [BREAK]
2015-10-09 17:13:37.120420 [NOTICE] switch_ivr_bridge.c:758 Hangup loopback/voicemail-a [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL]
2015-10-09 17:13:37.120420 [DEBUG] switch_channel.c:3242 Send signal loopback/voicemail-a [KILL]
2015-10-09 17:13:37.120420 [DEBUG] mod_loopback.c:590 loopback/voicemail-a CHANNEL KILL
2015-10-09 17:13:37.120420 [DEBUG] switch_core_session.c:1397 Send signal loopback/voicemail-a [BREAK]
2015-10-09 17:13:37.120420 [DEBUG] mod_loopback.c:590 loopback/voicemail-a CHANNEL KILL
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:538 (loopback/voicemail-a) State EXCHANGE_MEDIA going to sleep
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:472 (loopback/voicemail-a) Running State Change CS_HANGUP
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:735 (loopback/voicemail-a) Callstate Change EARLY -> HANGUP
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:737 (loopback/voicemail-a) State HANGUP
2015-10-09 17:13:37.120420 [DEBUG] mod_loopback.c:537 loopback/voicemail-a CHANNEL HANGUP
2015-10-09 17:13:37.120420 [NOTICE] mod_loopback.c:553 Hangup loopback/voicemail-b [CS_EXECUTE] [ORIGINATOR_CANCEL]
2015-10-09 17:13:37.120420 [DEBUG] switch_ivr_play_say.c:1747 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-too-small.wav
2015-10-09 17:13:37.120420 [DEBUG] switch_channel.c:3242 Send signal loopback/voicemail-b [KILL]
2015-10-09 17:13:37.120420 [DEBUG] mod_loopback.c:590 loopback/voicemail-b CHANNEL KILL
2015-10-09 17:13:37.120420 [DEBUG] switch_core_session.c:1397 Send signal loopback/voicemail-b [BREAK]
2015-10-09 17:13:37.120420 [DEBUG] mod_loopback.c:590 loopback/voicemail-b CHANNEL KILL
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:60 loopback/voicemail-a Standard HANGUP, cause: ORIGINATOR_CANCEL
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:737 (loopback/voicemail-a) State HANGUP going to sleep
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:504 (loopback/voicemail-a) State Change CS_HANGUP -> CS_REPORTING
2015-10-09 17:13:37.120420 [DEBUG] switch_core_session.c:1397 Send signal loopback/voicemail-a [BREAK]
2015-10-09 17:13:37.120420 [DEBUG] mod_loopback.c:590 loopback/voicemail-a CHANNEL KILL
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:472 (loopback/voicemail-a) Running State Change CS_REPORTING
2015-10-09 17:13:37.120420 [DEBUG] switch_ivr_bridge.c:1572 loopback/voicemail-a skip receive message [UNBRIDGE] (channel is hungup already)
2015-10-09 17:13:37.120420 [DEBUG] switch_ivr_bridge.c:1575 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 skip receive message [UNBRIDGE] (channel is hungup already)
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:823 (loopback/voicemail-a) State REPORTING
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:104 loopback/voicemail-a Standard REPORTING, cause: ORIGINATOR_CANCEL
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:823 (loopback/voicemail-a) State REPORTING going to sleep
2015-10-09 17:13:37.120420 [DEBUG] switch_core_session.c:2767 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) State EXECUTE going to sleep
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) Running State Change CS_HANGUP
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:498 (loopback/voicemail-a) State Change CS_REPORTING -> CS_DESTROY
2015-10-09 17:13:37.120420 [DEBUG] switch_core_session.c:1397 Send signal loopback/voicemail-a [BREAK]
2015-10-09 17:13:37.120420 [DEBUG] mod_loopback.c:590 loopback/voicemail-a CHANNEL KILL
2015-10-09 17:13:37.120420 [DEBUG] switch_core_session.c:1624 Session 17 (loopback/voicemail-a) Locked, Waiting on external entities
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:735 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) Callstate Change ACTIVE -> HANGUP
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:737 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) State HANGUP
2015-10-09 17:13:37.120420 [DEBUG] mod_sofia.c:413 Channel sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 hanging up, cause: NORMAL_CLEARING
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 Standard HANGUP, cause: NORMAL_CLEARING
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:737 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) State HANGUP going to sleep
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) State Change CS_HANGUP -> CS_REPORTING
2015-10-09 17:13:37.120420 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 [BREAK]
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) Running State Change CS_REPORTING
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:823 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) State REPORTING
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 Standard REPORTING, cause: NORMAL_CLEARING
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:823 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) State REPORTING going to sleep
2015-10-09 17:13:37.120420 [DEBUG] switch_core_session.c:2767 loopback/voicemail-b skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:535 (loopback/voicemail-b) State EXECUTE going to sleep
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:498 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) State Change CS_REPORTING -> CS_DESTROY
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:472 (loopback/voicemail-b) Running State Change CS_HANGUP
2015-10-09 17:13:37.120420 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 [BREAK]
2015-10-09 17:13:37.120420 [DEBUG] switch_core_session.c:1624 Session 16 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) Locked, Waiting on external entities
2015-10-09 17:13:37.120420 [NOTICE] switch_core_session.c:1642 Session 16 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) Ended
2015-10-09 17:13:37.120420 [NOTICE] switch_core_session.c:1646 Close Channel sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 [CS_DESTROY]
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:735 (loopback/voicemail-b) Callstate Change EARLY -> HANGUP
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:737 (loopback/voicemail-b) State HANGUP
2015-10-09 17:13:37.120420 [DEBUG] mod_loopback.c:537 loopback/voicemail-b CHANNEL HANGUP
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:60 loopback/voicemail-b Standard HANGUP, cause: ORIGINATOR_CANCEL
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:737 (loopback/voicemail-b) State HANGUP going to sleep
2015-10-09 17:13:37.120420 [NOTICE] switch_core_session.c:1642 Session 17 (loopback/voicemail-a) Ended
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:504 (loopback/voicemail-b) State Change CS_HANGUP -> CS_REPORTING
2015-10-09 17:13:37.120420 [NOTICE] switch_core_session.c:1646 Close Channel loopback/voicemail-a [CS_DESTROY]
2015-10-09 17:13:37.120420 [DEBUG] switch_core_session.c:1397 Send signal loopback/voicemail-b [BREAK]
2015-10-09 17:13:37.120420 [DEBUG] mod_loopback.c:590 loopback/voicemail-b CHANNEL KILL
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:472 (loopback/voicemail-b) Running State Change CS_REPORTING
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:626 (loopback/voicemail-a) Running State Change CS_DESTROY
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:626 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) Running State Change CS_DESTROY
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:823 (loopback/voicemail-b) State REPORTING
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:636 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) State DESTROY
2015-10-09 17:13:37.120420 [DEBUG] mod_sofia.c:323 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 SOFIA DESTROY
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:104 loopback/voicemail-b Standard REPORTING, cause: ORIGINATOR_CANCEL
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:823 (loopback/voicemail-b) State REPORTING going to sleep
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:111 sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090 Standard DESTROY
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:636 (sofia/internal/1014 at 119.9.73.183<mailto:sofia/internal/1014 at 119.9.73.183>:5090) State DESTROY going to sleep
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:636 (loopback/voicemail-a) State DESTROY
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:111 loopback/voicemail-a Standard DESTROY
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:636 (loopback/voicemail-a) State DESTROY going to sleep
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:498 (loopback/voicemail-b) State Change CS_REPORTING -> CS_DESTROY
2015-10-09 17:13:37.120420 [DEBUG] switch_core_session.c:1397 Send signal loopback/voicemail-b [BREAK]
2015-10-09 17:13:37.120420 [DEBUG] mod_loopback.c:590 loopback/voicemail-b CHANNEL KILL
2015-10-09 17:13:37.120420 [DEBUG] switch_core_session.c:1624 Session 18 (loopback/voicemail-b) Locked, Waiting on external entities
2015-10-09 17:13:37.120420 [NOTICE] switch_core_session.c:1642 Session 18 (loopback/voicemail-b) Ended
2015-10-09 17:13:37.120420 [NOTICE] switch_core_session.c:1646 Close Channel loopback/voicemail-b [CS_DESTROY]
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:626 (loopback/voicemail-b) Running State Change CS_DESTROY
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:636 (loopback/voicemail-b) State DESTROY
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:111 loopback/voicemail-b Standard DESTROY
2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:636 (loopback/voicemail-b) State DESTROY going to sleep
2015-10-09 17:15:01.140419 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/Master.csv
2015-10-09 17:15:01.140419 [NOTICE] mod_logfile.c:213 New log started.
Thanks in advance !!!
Utkarsh
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20151009/81d91240/attachment-0001.html
Join us at ClueCon 2016 Aug 8-12, 2016
More information about the FreeSWITCH-users
mailing list