- FreeSWITCH-users - FreeSWITCH Mailing Lists

Help building MOD_FLITE library on Ubuntu
by Carlos Fererro 09 Oct '15

09 Oct '15

Hello, I am trying to build mod_flite module on Ubuntu 14.01 and unfortunately with no luck. The build is looking for the FLITE engine library version 2.0.0 or higher. And consequently suggests installing libflite-dev library. Which I built from source (flite-2.0.0-release.tar.bz2 tarball) as there is no FLITE library version 2.0.0 in the official distribution available (at least I could not find it). Unfortunately no matter where I install the FLITE engine library I build (by default it goes under /usr/local/..) FreeSWITCH build doesn't seem to find it. Needless to say I have zero problem building mod_flite module in the older releases of the FreeSWITCH, where FLITE library tarball (I believe it was 1.5.7) was part of the FreeSWITCH distribution and was built automatically as part of the FreeSWITCH build. Seems me that FreeSWITCH wants to find an FLITE library in the form of the shared library, not .a file as it build from the source. Is there a solution already exists to this? As I am sure I am not the first one with this issue. Greatly appreciate any help on the subject Carlos

2 4

Audio problems in freeswitch
by Utkarsh Singh 09 Oct '15

09 Oct '15

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@example.com<mailto:joeuser@example.com>NOREG 119.9.73.183 alias internalALIASED externalprofile sip:mod_sofia@119.9.73.183<mailto:mod_sofia@119.9.73.183>:5092RUNNING (0) external::example.comgateway sip:joeuser@example.com<mailto:joeuser@example.com>NOREG internal-ipv6profile sip:mod_sofia@[2401:1800:7800:101:4628:b452:ff1c:8e5]:5060RUNNING (0) internalprofile sip:mod_sofia@119.9.73.183<mailto:mod_sofia@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 Action sleep(1000) Dialplan: sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 [BREAK] 2015-10-09 17:13:07.460415 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090) State EXECUTE 2015-10-09 17:13:07.460415 [DEBUG] mod_sofia.c:178 sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 SOFIA EXECUTE 2015-10-09 17:13:07.460415 [DEBUG] switch_core_state_machine.c:258 sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 Standard EXECUTE EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 set(open=true) 2015-10-09 17:13:07.460415 [DEBUG] mod_dptools.c:1555 sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 SET [open]=[true] EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 hash(insert/119.9.73.183-spymap/1014/e8c1522e-6e7a-11e5-80c2-0d55cfaac62d) EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 hash(insert/119.9.73.183-last_dial/1014/1018) EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 hash(insert/119.9.73.183-last_dial/global/e8c1522e-6e7a-11e5-80c2-0d55cfaac62d) EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 SET [ringback]=[%(2000,4000,440,480)] EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 SET [transfer_ringback]=[local_stream://moh] EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 set(call_timeout=30) 2015-10-09 17:13:07.480408 [DEBUG] mod_dptools.c:1555 sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 SET [call_timeout]=[30] EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 SET [hangup_after_bridge]=[true] EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 SET [continue_on_fail]=[true] EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 hash(insert/119.9.73.183-call_return/1018/1014) EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 hash(insert/119.9.73.183-last_dial_ext/1018/e8c1522e-6e7a-11e5-80c2-0d55cfaac62d) EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 SET [called_party_callgroup]=[techsupport] EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 hash(insert/119.9.73.183-last_dial_ext/techsupport/e8c1522e-6e7a-11e5-80c2-0d55cfaac62d) EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 hash(insert/119.9.73.183-last_dial_ext/global/e8c1522e-6e7a-11e5-80c2-0d55cfaac62d) EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 hash(insert/119.9.73.183-last_dial/techsupport/e8c1522e-6e7a-11e5-80c2-0d55cfaac62d) EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 bridge(user/1018(a)119.9.73.183<mailto:user/1018@119.9.73.183>) 2015-10-09 17:13:07.480408 [DEBUG] switch_channel.c:1221 sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:1018@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090! 2015-10-09 17:13:08.720433 [DEBUG] switch_channel.c:3419 (sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 [BREAK] 2015-10-09 17:13:08.720433 [NOTICE] mod_dptools.c:1378 Channel [sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 [BREAK] 2015-10-09 17:13:08.720433 [DEBUG] switch_channel.c:3711 (sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090) Callstate Change EARLY -> ACTIVE 2015-10-09 17:13:08.720433 [DEBUG] sofia.c:6659 Channel sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 entering state [completed][200] EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 sleep(1000) 2015-10-09 17:13:08.720433 [DEBUG] switch_ivr.c:191 Codec Activated L16@8000hz 1 channels 20ms 2015-10-09 17:13:08.860540 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 [BREAK] 2015-10-09 17:13:08.860540 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 [BREAK] 2015-10-09 17:13:08.860540 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 [BREAK] 2015-10-09 17:13:08.860540 [DEBUG] sofia.c:6659 Channel sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 entering state [ready][200] EXECUTE sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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@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@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@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@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@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@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@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@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@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@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@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@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@8000hz 1 channels 20ms 2015-10-09 17:13:37.100420 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 [BREAK] 2015-10-09 17:13:37.120420 [NOTICE] sofia.c:971 Hangup sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 [KILL] 2015-10-09 17:13:37.120420 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090) State HANGUP 2015-10-09 17:13:37.120420 [DEBUG] mod_sofia.c:413 Channel sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 [BREAK] 2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090) State REPORTING 2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 [BREAK] 2015-10-09 17:13:37.120420 [DEBUG] switch_core_session.c:1624 Session 16 (sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090) Ended 2015-10-09 17:13:37.120420 [NOTICE] switch_core_session.c:1646 Close Channel sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090) State DESTROY 2015-10-09 17:13:37.120420 [DEBUG] mod_sofia.c:323 sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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(a)119.9.73.183<mailto:sofia/internal/1014@119.9.73.183>:5090 Standard DESTROY 2015-10-09 17:13:37.120420 [DEBUG] switch_core_state_machine.c:636 (sofia/internal/1014(a)119.9.73.183<mailto:sofia/internal/1014@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

1 0

[ERR] switch_core_sqldb.c:587 NATIVE SQL ERR [cannot commit - no transaction is active]
by Kiran Patil 09 Oct '15

09 Oct '15

Hi, I installed Freeswitch 1.6.2 on CentOS 7. [root@freeswitch freeswitch]# freeswitch -version FreeSWITCH version: 1.6.2~64bit ( 64bit) [root@freeswitch freeswitch]# systemctl status freeswitch.service freeswitch.service - FreeSWITCH Loaded: loaded (/usr/lib/systemd/system/freeswitch.service; enabled) Active: active (running) since Thu 2015-10-08 17:50:38 IST; 29min ago Main PID: 12226 (freeswitch) CGroup: /system.slice/freeswitch.service └─12226 /usr/bin/freeswitch -nc -nf Oct 08 17:50:38 freeswitch systemd[1]: Starting FreeSWITCH... Oct 08 17:50:38 freeswitch systemd[1]: Started FreeSWITCH. Started Freeswitch daemon and typed fs_cli on cmd line and I am seeing only errors as below. Please help me to resolve this issue. BEGIN EXCLUSIVE 2015-10-08 18:11:35.190458 [CRIT] switch_core_sqldb.c:1952 ERROR [attempt to write a readonly database] 2015-10-08 18:11:35.190458 [ERR] switch_core_sqldb.c:587 NATIVE SQL ERR [cannot commit - no transaction is active] COMMIT 2015-10-08 18:11:35.190458 [ERR] switch_core_sqldb.c:587 NATIVE SQL ERR [attempt to write a readonly database] BEGIN EXCLUSIVE 2015-10-08 18:11:35.190458 [CRIT] switch_core_sqldb.c:1952 ERROR [attempt to write a readonly database] 2015-10-08 18:11:35.190458 [ERR] switch_core_sqldb.c:587 NATIVE SQL ERR [cannot commit - no transaction is active] COMMIT 2015-10-08 18:11:35.190458 [ERR] switch_core_sqldb.c:587 NATIVE SQL ERR [attempt to write a readonly database] BEGIN EXCLUSIVE 2015-10-08 18:11:35.190458 [CRIT] switch_core_sqldb.c:1952 ERROR [attempt to write a readonly database] 2015-10-08 18:11:35.190458 [ERR] switch_core_sqldb.c:587 NATIVE SQL ERR [cannot commit - no transaction is active] COMMIT 2015-10-08 18:11:35.190458 [ERR] switch_core_sqldb.c:587 NATIVE SQL ERR [attempt to write a readonly database] BEGIN EXCLUSIVE 2015-10-08 18:11:35.190458 [CRIT] switch_core_sqldb.c:1952 ERROR [attempt to write a readonly database] 2015-10-08 18:11:35.190458 [ERR] switch_core_sqldb.c:587 NATIVE SQL ERR [cannot commit - no transaction is active] COMMIT 2015-10-08 18:11:35.190458 [ERR] switch_core_sqldb.c:587 NATIVE SQL ERR [attempt to write a readonly database] BEGIN EXCLUSIVE 2015-10-08 18:11:35.190458 [CRIT] switch_core_sqldb.c:1952 ERROR [attempt to write a readonly database] 2015-10-08 18:11:35.190458 [ERR] switch_core_sqldb.c:587 NATIVE SQL ERR [cannot commit - no transaction is active] COMMIT 2015-10-08 18:11:35.190458 [ERR] switch_core_sqldb.c:587 NATIVE SQL ERR [attempt to write a readonly database] BEGIN EXCLUSIVE 2015-10-08 18:11:35.190458 [CRIT] switch_core_sqldb.c:1952 ERROR [attempt to write a readonly database] 2015-10-08 18:11:35.190458 [ERR] switch_core_sqldb.c:587 NATIVE SQL ERR [cannot commit - no transaction is active] COMMIT Thanks, Kiran.

4 4

Encryption modes on legs influencing each other
by Johannes Singler 08 Oct '15

08 Oct '15

FS is a B2BUA. However, the two legs of a regular call seem to influence each other, e.g. 1. The caller offers an SDES-SRTP-encrypted connection with ICE. 2. FS offers to the callee a simple RTP connection (no SRTP, no ICE, as configured in the dialplan), callee answers respectively. 3. FS answers caller, but without with neither "a:crypto" entries nor ICE candidates. 4. Why is that? Shouldn't it answer SDES-SRTP with ICE to the original caller, respecting the original caller's offer? When doing WebRTC from the caller (DTLS-SRTP with ICE), this actually works fine (callee unchanged). So what's the general scheme for choosing encryption on either side? Related to that: Can you enable ICE without completely enabling WebRTC (media_webrtc=true) from the dialplan? That would help maybe... -- Johannes Singler Software Engineer Qnective

2 3

DTLS handshake problem
by Tao Zhang 08 Oct '15

08 Oct '15

Hi, Now I think the problem I am having with my verto video demo setup is about DTLS setup. From the FS server log, I can see both audio DTLS and video DTLS states are changed from OFF to HANDSHAKE, but then there is no more event regarding to DTLS state, there is no failure regarding to the HANDSHAKE either. It seems the HANDSHAKE just stuck there...? Could anybody help? The server log is pasted below. 2015-10-07 02:34:58.770773 [INFO] switch_core_media.c:6233 Skipping RTCP ICE (Same as RTP) 2015-10-07 02:34:58.770773 [INFO] switch_rtp.c:3240 Activate RTP/RTCP audio DTLS client 2015-10-07 02:34:58.770773 [INFO] switch_rtp.c:3369 Changing audio DTLS state from OFF to HANDSHAKE 2015-10-07 02:34:58.770773 [DEBUG] switch_core_media.c:1929 Setting Jitterbuffer to 20ms (1 frames) (30 max frames) 2015-10-07 02:34:58.770773 [DEBUG] switch_core_media.c:6306 Set 2833 dtmf send payload to 126 2015-10-07 02:34:58.770773 [DEBUG] switch_core_media.c:6312 Set 2833 dtmf receive payload to 126 2015-10-07 02:34:58.770773 [DEBUG] switch_rtp.c:3731 Starting video timer. 2015-10-07 02:34:58.770773 [DEBUG] switch_core_media.c:6523 VIDEO RTP [verto.rtc/3500] 10.240.15.244:30526->100.8.240.191:54763 codec: 100 ms: 0 [SUCCESS] 2015-10-07 02:34:58.770773 [DEBUG] switch_core_media.c:5180 verto.rtc/3500 Starting Video thread 2015-10-07 02:34:58.770773 [INFO] switch_core_media.c:6564 Activating Video ICE 2015-10-07 02:34:58.770773 [NOTICE] switch_rtp.c:4198 Activating RTP video ICE: OVGmvVZ6AiMvzY7t:2nnpMO2EHNETp4ym 100.8.240.191:54763 2015-10-07 02:34:58.770773 [INFO] switch_core_media.c:6602 Activating VIDEO RTCP PORT 54763 interval 2000 mux 1 2015-10-07 02:34:58.770773 [DEBUG] switch_rtp.c:4095 RTCP send rate is: 2000 and packet rate is: 90000 Remote Port: 54763 2015-10-07 02:34:58.770773 [INFO] switch_core_media.c:6613 Skipping VIDEO RTCP ICE (Same as VIDEO RTP) 2015-10-07 02:34:58.770773 [INFO] switch_rtp.c:3240 Activate RTP/RTCP video DTLS client 2015-10-07 02:34:58.770773 [INFO] switch_rtp.c:3369 Changing video DTLS state from OFF to HANDSHAKE Thanks so much Tao

2 1

whats causing FS compilation to fail due to TPL errors on windows?
by Bipin Patel 08 Oct '15

08 Oct '15

2 1

Windows MSI installers
by Peter Johnson 08 Oct '15

08 Oct '15

Hi there I'm looking to obtain FreeSWITCH for Windows, which I normally download from http://files.freeswitch.org/windows/installer/ - are these really the latest generated from Git HEAD? They are dated in January 2015 and don't seem to be even 1.6? Thanks in advance for any advice Peter

1 0

Conference vs codec sample rate and CPU
by Oleg Stolyar 07 Oct '15

07 Oct '15

Hi guys, I have a bunch of conferences on my FS. Users are either G.711/8000 or G.722/8000. Conference sample rate is set to 8000. When I switched some users to OPUS instead, the CPU consumption jumped 3-4 times even though the conference rate is still 8000. Is this expected? I was hoping to be able to save some CPU by setting the conference sample rate to auto (so it only used high-quality sampling for users that can support it) but it seems it won't work. Any ideas?

8 23

Low-Level Conference API
by Johannes Singler 07 Oct '15

07 Oct '15

mod_conference offers audio/video conferencing services. The usual way to start/join a conference seems to be to dial in to a special number. However, we would like to use mod_conference with custom signaling, so basically just the mixing part. For that, we would need some low-level API for conferences, with functionality like 1. Add a participant with a given offer SDP: return an answer SDP, start mixing it in and start the RTP stream to the new participant. 2. Remove a participant, stop mixing him, and stop the RTP stream to the respective device. Can this be achieved by using mod_event_socket, or would we have to implement a proper FreeSWITCH module for such things? -- Johannes Singler Software Engineer Qnective

3 2

Windows Startup Problem
by Steve Matzura 07 Oct '15

07 Oct '15

Installed last week's 1.6 build and cannot start the CLI. When I run the console program, absolutely nothing happens--no error, no response at all. I have Visual Studio 2012 installed, and when I tried replacing the C++ library with the downloadable redistributable one, Windows informed me my 2012 version is newer, which it most certainly is. I am also aware others have had this problem, as detailed on https://freeswitch.org/confluence/display/FREESWITCH/Windows. The reporter was directed to this list, so now here am I, hoping the solution was posted and someone will please repost it. Thanks in advance.

2 2