- FreeSWITCH-users - FreeSWITCH Mailing Lists

pfSense 2.0 with FreeSWITCH and FusionPBX?
by n43w79 04 Sep '11

04 Sep '11

Hi, Just wondering is there a FS package for pfSense 2.0? I am trying to test with PC Engines MB alix.2d3. Perhaps, like the Android port, may be FS can be installed in pfSense as a native library as well? Btw, like GillesToo, I don't have the technical skills to port FS as well. Thank you.

1 0

FXO/FXS card advice
by ocset 04 Sep '11

04 Sep '11

Hi As a new member of the forum, I am curious to know your experience with FXO/FXS cards. I have a SPA3102 and have configured it to work with FS, but it feels a bit like I am trying to make a square peg fit a round hole. I am hoping to implement FS at a business which currently has 4 POTS lines and would prefer to use an internal IDE card for the job of integrating these phone lines into FreeSwitch. Has anyone got some advice on which cards I should be looking at that just "work" with FS? What about echo cancellation - is that something I should just cater for or does it depend on the client situation? What about software cancellation? This seems like one of those times when too much choice is a bad thing and I need some guidance on what has worked for you. All advice would be greatly appreciated. Regards

9 17

mod_callcenter questions
by Игорь Кувалдин 04 Sep '11

04 Sep '11

Hi all! Sorry for my bad english. My question about of the application mod_callcenter. Is there an analog variable hangup_after_conference but for call-center? I would like to continue in the dialplan to check the value of CC-Cause and CC-Cause-Reason and depending on it to redirect the call to the IVR, perform another extension, or simply execution another extension. Because now exit the application terminating the current call in mod_callcenter.

1 0

Need help cc Gateway (SIP trunk) setup for provider using Auth ID
by Alexander Harm 04 Sep '11

04 Sep '11

My SIP provider uses - User ID (same as Caller ID Number) - Password - Auth ID (different from User ID) for registration. I have to admit that I'm completely at loss on how to configure freeSWITCH using Auth ID. I tried all combinations I could think off but I just keep getting 403 error messages. Help is very much appreciated.

3 4

FreeSwitch and core dumps
by Tomasz Hyziak 02 Sep '11

02 Sep '11

Hi I've got a problem with FS core dump - http://pastebin.freeswitch.org/17255 When FS shuts down with a core dump it restarts but all calls are disconnected after 30 seconds. Only restart helps for some time. In CDR i see at these calls hangup_cause NORMAL_UNSPECIFIED and billsec 33 or 34 seconds. I cannot find anything interesting in logs... Here it is: b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [NOTICE] switch_channel.c:907 New Channel sofia/external/501XXXXXX(a)trunk.dialog.pl [b541f405-1a39-4cbc-80dc-8bdbc92ed070] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] sofia.c:5142 Channel sofia/external/501XXXXXX(a)trunk.dialog.pl entering state [received][100] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_state_machine.c:330 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Running State Change CS_NEW b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] sofia.c:5153 Remote SDP: v=0 o=BroadWorks 51808039 1 IN IP4 10.165.64.36 s=- c=IN IP4 10.165.64.36 t=0 0 m=audio 22558 RTP/AVP 0 8 101 b=AS:82 a=rtpmap:0 PCMU/8000/1 a=rtpmap:8 PCMA/8000/1 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_state_machine.c:348 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State NEW b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] sofia_glue.c:4731 Audio Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] sofia_glue.c:4731 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] sofia_glue.c:2839 Set Codec sofia/external/501XXXXXX(a)trunk.dialog.pl PCMU/8000 20 ms 160 samples 64000 bits b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] sofia_glue.c:4845 Set 2833 dtmf send/recv payload to 101 b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] sofia.c:5343 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State Change CS_NEW -> CS_INIT b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_session.c:1156 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_state_machine.c:330 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Running State Change CS_INIT b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_state_machine.c:369 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State INIT b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] mod_sofia.c:85 sofia/external/501XXXXXX(a)trunk.dialog.pl SOFIA INIT b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] mod_sofia.c:125 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State Change CS_INIT -> CS_ROUTING b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_session.c:1156 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_state_machine.c:369 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State INIT going to sleep b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_state_machine.c:330 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Running State Change CS_ROUTING b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_channel.c:1836 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Callstate Change DOWN -> RINGING b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_state_machine.c:378 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State ROUTING b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] mod_sofia.c:148 sofia/external/501XXXXXX(a)trunk.dialog.pl SOFIA ROUTING b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_state_machine.c:78 sofia/external/501XXXXXX(a)trunk.dialog.pl Standard ROUTING b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [INFO] mod_dialplan_xml.c:336 Processing 501XXXXXX <501XXXXXX>->71XXXXXXX in context public b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl parsing [public->unloop] continue=false b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl parsing [public->outside_call] continue=true b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Absolute Condition [outside_call] b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(outside_call=true) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl parsing [public->call_debug] continue=true b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl parsing [public->public_extensions] continue=false b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Regex (FAIL) [public_extensions] destination_number(71XXXXXXX) =~ /^(1[0-9][0-9][0-9])$/ break=on-false b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl parsing [public->in_dialog_1] continue=false b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Date/Time Match (FAIL) [in_dialog_1] break=on-true b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Regex (PASS) [in_dialog_1] destination_number(71XXXXXXX) =~ /^(717739100|71XXXXXXX|717854012)$/ break=on-false b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(RECORD_STEREO=true) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action record_session(/srv/nagrania/aktualne/IN_${strftime(%Y%m%d-%H%M%S)}_${destination_number}_${caller_id_number}.wav) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(media_bug_answer_req=true) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(domain_name=192.168.0.8) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action export(absolute_codec_string=PCMU,PCMA) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action export(codec_string=PCMU,PCMA) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(jitterbuffer_msec=180) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set_audio_level(read 1) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(hangup_after_bridge=true) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(bind_meta_key=9) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action bind_meta_app(1 b s execute_extension::dx XML features) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action bind_meta_app(2 b s execute_extension::cf XML features) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action bind_meta_app(3 b s execute_extension::att_xfer XML features) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(call_timeout=60) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(continue_on_fail=NO_ANSWER) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(limit_ignore_transfer=true) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action answer() b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action sleep(1000) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(fifo_music=/srv/nagrania/ivr/ponce-preludio-in-e-major-10s.wav) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action ivr(ivr) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action fifo(fifo_ogolne in) b541f405-1a39-4cbc-80dc-8bdbc92ed070 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action hangup() b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_state_machine.c:122 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State Change CS_ROUTING -> CS_EXECUTE b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_session.c:1156 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_state_machine.c:378 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State ROUTING going to sleep b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_state_machine.c:330 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Running State Change CS_EXECUTE b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_state_machine.c:385 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State EXECUTE b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] mod_sofia.c:241 sofia/external/501XXXXXX(a)trunk.dialog.pl SOFIA EXECUTE b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_state_machine.c:160 sofia/external/501XXXXXX(a)trunk.dialog.pl Standard EXECUTE b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(outside_call=true) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [outside_call]=[true] b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(RFC2822_DATE=Thu, 01 Sep 2011 08:36:08 +0200) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [RFC2822_DATE]=[Thu, 01 Sep 2011 08:36:08 +0200] b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(RECORD_STEREO=true) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [RECORD_STEREO]=[true] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.103283 [DEBUG] switch_core_session.c:1983 Application record_session Requires media! pre_answering channel sofia/external/501XXXXXX(a)trunk.dialog.pl 2011-09-01 08:36:08.103283 [INFO] switch_core_session.c:1985 Sending early media 2011-09-01 08:36:08.143282 [DEBUG] switch_nat.c:510 mapped public port 29830 protocol UDP to localport 29830 2011-09-01 08:36:08.163284 [DEBUG] switch_nat.c:510 mapped public port 29831 protocol UDP to localport 29831 b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.163284 [DEBUG] sofia_glue.c:3091 AUDIO RTP [sofia/external/501XXXXXX(a)trunk.dialog.pl] 172.20.0.22 port 29830 -> 10.165.64.36 port 22558 codec: 0 ms: 20 b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.163284 [DEBUG] switch_rtp.c:1637 Starting timer [soft] 160 bytes per 20ms b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.163284 [DEBUG] sofia_glue.c:3353 Set 2833 dtmf send payload to 101 b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.163284 [DEBUG] sofia_glue.c:3358 Set 2833 dtmf receive payload to 101 b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.163284 [DEBUG] mod_sofia.c:2436 Ring SDP: v=0 o=FreeSWITCH 1314829138 1314829139 IN IP4 10.64.64.64 s=FreeSWITCH c=IN IP4 10.64.64.64 t=0 0 m=audio 29830 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.163284 [NOTICE] mod_sofia.c:2439 Pre-Answer sofia/external/501XXXXXX(a)trunk.dialog.pl! b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.163284 [DEBUG] switch_channel.c:2851 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Callstate Change RINGING -> EARLY b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] switch_core_session.c:713 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl record_session(/srv/nagrania/aktualne/IN_20110901-083608_71XXXXXXX_501XXXXXX.wav) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] switch_core_session.c:857 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] sofia.c:5135 Channel sofia/external/501XXXXXX(a)trunk.dialog.pl skipping state [early][183] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] switch_core_media_bug.c:360 Attaching BUG to sofia/external/501XXXXXX(a)trunk.dialog.pl b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(media_bug_answer_req=true) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [media_bug_answer_req]=[true] b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(domain_name=192.168.0.8) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [domain_name]=[192.168.0.8] b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl export(absolute_codec_string=PCMU,PCMA) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] switch_channel.c:1074 EXPORT (export_vars) [absolute_codec_string]=[PCMU,PCMA] b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl export(codec_string=PCMU,PCMA) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] switch_channel.c:1074 EXPORT (export_vars) [codec_string]=[PCMU,PCMA] b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(jitterbuffer_msec=180) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [jitterbuffer_msec]=[180] b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set_audio_level(read 1) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] switch_core_media_bug.c:360 Attaching BUG to sofia/external/501XXXXXX(a)trunk.dialog.pl b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(hangup_after_bridge=true) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [hangup_after_bridge]=[true] b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(bind_meta_key=9) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [bind_meta_key]=[9] b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl bind_meta_app(1 b s execute_extension::dx XML features) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [INFO] switch_ivr_async.c:3069 Bound B-Leg: 91 execute_extension::dx XML features b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl bind_meta_app(2 b s execute_extension::cf XML features) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [INFO] switch_ivr_async.c:3069 Bound B-Leg: 92 execute_extension::cf XML features b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl bind_meta_app(3 b s execute_extension::att_xfer XML features) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [INFO] switch_ivr_async.c:3069 Bound B-Leg: 93 execute_extension::att_xfer XML features b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(call_timeout=60) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [call_timeout]=[60] b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(continue_on_fail=NO_ANSWER) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [continue_on_fail]=[NO_ANSWER] b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(limit_ignore_transfer=true) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [limit_ignore_transfer]=[true] b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl answer() b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] mod_sofia.c:738 Local SDP sofia/external/501XXXXXX(a)trunk.dialog.pl: v=0 o=FreeSWITCH 1314829138 1314829140 IN IP4 10.64.64.64 s=FreeSWITCH c=IN IP4 10.64.64.64 t=0 0 m=audio 29830 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] switch_core_session.c:713 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] switch_channel.c:3043 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Callstate Change EARLY -> ACTIVE b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] switch_core_session.c:857 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [NOTICE] mod_dptools.c:930 Channel [sofia/external/501XXXXXX(a)trunk.dialog.pl] has been answered b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:08.183289 [DEBUG] sofia.c:5142 Channel sofia/external/501XXXXXX(a)trunk.dialog.pl entering state [completed][200] b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl sleep(1000) b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(fifo_music=/srv/nagrania/ivr/ponce-preludio-in-e-major-10s.wav) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:09.203286 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [fifo_music]=[/srv/nagrania/ivr/ponce-preludio-in-e-major-10s.wav] b541f405-1a39-4cbc-80dc-8bdbc92ed070 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl ivr(ivr) 2011-09-01 08:36:09.203286 [DEBUG] switch_ivr_menu.c:660 switch_ivr_menu_stack_xml_add binding 'menu-exit' 2011-09-01 08:36:09.203286 [DEBUG] switch_ivr_menu.c:660 switch_ivr_menu_stack_xml_add binding 'menu-sub' 2011-09-01 08:36:09.203286 [DEBUG] switch_ivr_menu.c:660 switch_ivr_menu_stack_xml_add binding 'menu-exec-app' 2011-09-01 08:36:09.203286 [DEBUG] switch_ivr_menu.c:660 switch_ivr_menu_stack_xml_add binding 'menu-play-sound' 2011-09-01 08:36:09.203286 [DEBUG] switch_ivr_menu.c:660 switch_ivr_menu_stack_xml_add binding 'menu-back' 2011-09-01 08:36:09.203286 [DEBUG] switch_ivr_menu.c:660 switch_ivr_menu_stack_xml_add binding 'menu-top' 2011-09-01 08:36:09.203286 [DEBUG] switch_ivr_menu.c:791 building menu 'ivr' 2011-09-01 08:36:09.203286 [DEBUG] switch_ivr_menu.c:852 binding menu action 'menu-exec-app' to '1' 2011-09-01 08:36:09.203286 [DEBUG] switch_ivr_menu.c:852 binding menu action 'menu-exec-app' to '2' 2011-09-01 08:36:09.203286 [DEBUG] switch_ivr_menu.c:852 binding menu action 'menu-top' to '9' b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:09.203286 [DEBUG] switch_ivr_menu.c:428 Executing IVR menu ivr 2011-09-01 08:36:09.203286 [DEBUG] switch_core_file.c:180 File /srv/nagrania/ivr/powitanie_pelne.wav sample rate 22050 doesn't match requested rate 8000 b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:09.203286 [DEBUG] switch_ivr_play_say.c:1302 Codec Activated L16@8000hz 1 channels 20ms b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:09.203286 [DEBUG] switch_ivr_play_say.c:1351 Setup timer success 320 bytes per 20 ms! b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:26.823286 [DEBUG] switch_ivr_play_say.c:1672 done playing file b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:26.823286 [DEBUG] switch_ivr_menu.c:343 waiting for 1/1 digits t/o 2000 2011-09-01 08:36:30.443283 [DEBUG] switch_nat.c:306 got UPnP keep alive packet: NOTIFY * HTTP/1.1 HOST: 239.255.255.250:1900 CACHE-CONTROL: max-age=180 Location: http://192.168.0.6:5431/dyndev/uuid:001c1091-9dfb-001c-1091-9dfb00005800 NT: urn:schemas-upnp-org:service:WANPPPConnection:1 NTS: ssdp:alive SERVER:LINUX/2.4 UPnP/1.0 BRCM400/1.0 USN: uuid:001c1091-9dfb-001c-1091-9dfb02005800::urn:schemas-upnp-org:service:WANPPPConnection:1 b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:31.843284 [DEBUG] switch_ivr_menu.c:390 digits '' 2011-09-01 08:36:31.843284 [DEBUG] switch_core_file.c:180 File /srv/nagrania/ivr/powitanie_skrocone.wav sample rate 22050 doesn't match requested rate 8000 b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:31.843284 [DEBUG] switch_ivr_play_say.c:1302 Codec Activated L16@8000hz 1 channels 20ms b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:31.843284 [DEBUG] switch_ivr_play_say.c:1351 Setup timer success 320 bytes per 20 ms! 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [NOTICE] switch_channel.c:907 New Channel sofia/external/501XXXXXX(a)trunk.dialog.pl [28f96f4c-4fd0-4d19-bd69-6b08e6887db2] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] sofia.c:5142 Channel sofia/external/501XXXXXX(a)trunk.dialog.pl entering state [received][100] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_state_machine.c:330 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Running State Change CS_NEW 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] sofia.c:5153 Remote SDP: v=0 o=BroadWorks 51808287 1 IN IP4 10.165.64.36 s=- c=IN IP4 10.165.64.36 t=0 0 m=audio 22914 RTP/AVP 0 8 101 b=AS:82 a=rtpmap:0 PCMU/8000/1 a=rtpmap:8 PCMA/8000/1 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_state_machine.c:348 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State NEW 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] sofia_glue.c:4731 Audio Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] sofia_glue.c:4731 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] sofia_glue.c:2839 Set Codec sofia/external/501XXXXXX(a)trunk.dialog.pl PCMU/8000 20 ms 160 samples 64000 bits 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] sofia_glue.c:4845 Set 2833 dtmf send/recv payload to 101 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] sofia.c:5343 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State Change CS_NEW -> CS_INIT 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_session.c:1156 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_state_machine.c:330 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Running State Change CS_INIT 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_state_machine.c:369 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State INIT 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] mod_sofia.c:85 sofia/external/501XXXXXX(a)trunk.dialog.pl SOFIA INIT 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] mod_sofia.c:125 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State Change CS_INIT -> CS_ROUTING 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_session.c:1156 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_state_machine.c:369 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State INIT going to sleep 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_state_machine.c:330 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Running State Change CS_ROUTING 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_channel.c:1836 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Callstate Change DOWN -> RINGING 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_state_machine.c:378 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State ROUTING 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] mod_sofia.c:148 sofia/external/501XXXXXX(a)trunk.dialog.pl SOFIA ROUTING 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_state_machine.c:78 sofia/external/501XXXXXX(a)trunk.dialog.pl Standard ROUTING 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [INFO] mod_dialplan_xml.c:336 Processing 501XXXXXX <501XXXXXX>->71XXXXXXX in context public 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl parsing [public->unloop] continue=false 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl parsing [public->outside_call] continue=true 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Absolute Condition [outside_call] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(outside_call=true) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl parsing [public->call_debug] continue=true 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl parsing [public->public_extensions] continue=false 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Regex (FAIL) [public_extensions] destination_number(71XXXXXXX) =~ /^(1[0-9][0-9][0-9])$/ break=on-false 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl parsing [public->in_dialog_1] continue=false 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Date/Time Match (FAIL) [in_dialog_1] break=on-true 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Regex (PASS) [in_dialog_1] destination_number(71XXXXXXX) =~ /^(717739100|71XXXXXXX|717854012)$/ break=on-false 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(RECORD_STEREO=true) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action record_session(/srv/nagrania/aktualne/IN_${strftime(%Y%m%d-%H%M%S)}_${destination_number}_${caller_id_number}.wav) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(media_bug_answer_req=true) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(domain_name=192.168.0.8) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action export(absolute_codec_string=PCMU,PCMA) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action export(codec_string=PCMU,PCMA) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(jitterbuffer_msec=180) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set_audio_level(read 1) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(hangup_after_bridge=true) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(bind_meta_key=9) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action bind_meta_app(1 b s execute_extension::dx XML features) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action bind_meta_app(2 b s execute_extension::cf XML features) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action bind_meta_app(3 b s execute_extension::att_xfer XML features) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(call_timeout=60) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(continue_on_fail=NO_ANSWER) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(limit_ignore_transfer=true) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action answer() 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action sleep(1000) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action set(fifo_music=/srv/nagrania/ivr/ponce-preludio-in-e-major-10s.wav) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action ivr(ivr) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action fifo(fifo_ogolne in) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 Dialplan: sofia/external/501XXXXXX(a)trunk.dialog.pl Action hangup() 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_state_machine.c:122 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State Change CS_ROUTING -> CS_EXECUTE 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_session.c:1156 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_state_machine.c:378 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State ROUTING going to sleep 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_state_machine.c:330 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Running State Change CS_EXECUTE 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_state_machine.c:385 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State EXECUTE 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] mod_sofia.c:241 sofia/external/501XXXXXX(a)trunk.dialog.pl SOFIA EXECUTE 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_state_machine.c:160 sofia/external/501XXXXXX(a)trunk.dialog.pl Standard EXECUTE 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(outside_call=true) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [outside_call]=[true] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(RFC2822_DATE=Thu, 01 Sep 2011 08:36:35 +0200) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [RFC2822_DATE]=[Thu, 01 Sep 2011 08:36:35 +0200] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(RECORD_STEREO=true) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [RECORD_STEREO]=[true] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.283283 [DEBUG] switch_core_session.c:1983 Application record_session Requires media! pre_answering channel sofia/external/501XXXXXX(a)trunk.dialog.pl 2011-09-01 08:36:35.283283 [INFO] switch_core_session.c:1985 Sending early media 2011-09-01 08:36:35.323283 [DEBUG] switch_nat.c:510 mapped public port 30160 protocol UDP to localport 30160 2011-09-01 08:36:35.363285 [DEBUG] switch_nat.c:510 mapped public port 30161 protocol UDP to localport 30161 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] sofia_glue.c:3091 AUDIO RTP [sofia/external/501XXXXXX(a)trunk.dialog.pl] 172.20.0.22 port 30160 -> 10.165.64.36 port 22914 codec: 0 ms: 20 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] switch_rtp.c:1637 Starting timer [soft] 160 bytes per 20ms 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] sofia_glue.c:3353 Set 2833 dtmf send payload to 101 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] sofia_glue.c:3358 Set 2833 dtmf receive payload to 101 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] mod_sofia.c:2436 Ring SDP: v=0 o=FreeSWITCH 1314828835 1314828836 IN IP4 10.64.64.64 s=FreeSWITCH c=IN IP4 10.64.64.64 t=0 0 m=audio 30160 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [NOTICE] mod_sofia.c:2439 Pre-Answer sofia/external/501XXXXXX(a)trunk.dialog.pl! 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] switch_channel.c:2851 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Callstate Change RINGING -> EARLY 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] switch_core_session.c:713 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl record_session(/srv/nagrania/aktualne/IN_20110901-083635_71XXXXXXX_501XXXXXX.wav) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] switch_core_session.c:857 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] sofia.c:5135 Channel sofia/external/501XXXXXX(a)trunk.dialog.pl skipping state [early][183] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] switch_core_media_bug.c:360 Attaching BUG to sofia/external/501XXXXXX(a)trunk.dialog.pl 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(media_bug_answer_req=true) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [media_bug_answer_req]=[true] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(domain_name=192.168.0.8) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [domain_name]=[192.168.0.8] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl export(absolute_codec_string=PCMU,PCMA) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] switch_channel.c:1074 EXPORT (export_vars) [absolute_codec_string]=[PCMU,PCMA] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl export(codec_string=PCMU,PCMA) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] switch_channel.c:1074 EXPORT (export_vars) [codec_string]=[PCMU,PCMA] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(jitterbuffer_msec=180) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [jitterbuffer_msec]=[180] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set_audio_level(read 1) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] switch_core_media_bug.c:360 Attaching BUG to sofia/external/501XXXXXX(a)trunk.dialog.pl 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(hangup_after_bridge=true) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [hangup_after_bridge]=[true] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(bind_meta_key=9) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [bind_meta_key]=[9] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl bind_meta_app(1 b s execute_extension::dx XML features) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [INFO] switch_ivr_async.c:3069 Bound B-Leg: 91 execute_extension::dx XML features 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl bind_meta_app(2 b s execute_extension::cf XML features) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [INFO] switch_ivr_async.c:3069 Bound B-Leg: 92 execute_extension::cf XML features 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl bind_meta_app(3 b s execute_extension::att_xfer XML features) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [INFO] switch_ivr_async.c:3069 Bound B-Leg: 93 execute_extension::att_xfer XML features 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(call_timeout=60) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [call_timeout]=[60] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(continue_on_fail=NO_ANSWER) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [continue_on_fail]=[NO_ANSWER] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(limit_ignore_transfer=true) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [limit_ignore_transfer]=[true] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl answer() 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] mod_sofia.c:738 Local SDP sofia/external/501XXXXXX(a)trunk.dialog.pl: v=0 o=FreeSWITCH 1314828835 1314828837 IN IP4 10.64.64.64 s=FreeSWITCH c=IN IP4 10.64.64.64 t=0 0 m=audio 30160 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] switch_core_session.c:713 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] switch_channel.c:3043 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Callstate Change EARLY -> ACTIVE 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] switch_core_session.c:857 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [NOTICE] mod_dptools.c:930 Channel [sofia/external/501XXXXXX(a)trunk.dialog.pl] has been answered 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:35.363285 [DEBUG] sofia.c:5142 Channel sofia/external/501XXXXXX(a)trunk.dialog.pl entering state [completed][200] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl sleep(1000) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl set(fifo_music=/srv/nagrania/ivr/ponce-preludio-in-e-major-10s.wav) 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:36.363287 [DEBUG] mod_dptools.c:1063 sofia/external/501XXXXXX(a)trunk.dialog.pl SET [fifo_music]=[/srv/nagrania/ivr/ponce-preludio-in-e-major-10s.wav] 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 EXECUTE sofia/external/501XXXXXX(a)trunk.dialog.pl ivr(ivr) 2011-09-01 08:36:36.363287 [DEBUG] switch_ivr_menu.c:660 switch_ivr_menu_stack_xml_add binding 'menu-exit' 2011-09-01 08:36:36.363287 [DEBUG] switch_ivr_menu.c:660 switch_ivr_menu_stack_xml_add binding 'menu-sub' 2011-09-01 08:36:36.363287 [DEBUG] switch_ivr_menu.c:660 switch_ivr_menu_stack_xml_add binding 'menu-exec-app' 2011-09-01 08:36:36.363287 [DEBUG] switch_ivr_menu.c:660 switch_ivr_menu_stack_xml_add binding 'menu-play-sound' 2011-09-01 08:36:36.363287 [DEBUG] switch_ivr_menu.c:660 switch_ivr_menu_stack_xml_add binding 'menu-back' 2011-09-01 08:36:36.363287 [DEBUG] switch_ivr_menu.c:660 switch_ivr_menu_stack_xml_add binding 'menu-top' 2011-09-01 08:36:36.363287 [DEBUG] switch_ivr_menu.c:791 building menu 'ivr' 2011-09-01 08:36:36.363287 [DEBUG] switch_ivr_menu.c:852 binding menu action 'menu-exec-app' to '1' 2011-09-01 08:36:36.363287 [DEBUG] switch_ivr_menu.c:852 binding menu action 'menu-exec-app' to '2' 2011-09-01 08:36:36.363287 [DEBUG] switch_ivr_menu.c:852 binding menu action 'menu-top' to '9' 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:36.363287 [DEBUG] switch_ivr_menu.c:428 Executing IVR menu ivr 2011-09-01 08:36:36.363287 [DEBUG] switch_core_file.c:180 File /srv/nagrania/ivr/powitanie_pelne.wav sample rate 22050 doesn't match requested rate 8000 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:36.363287 [DEBUG] switch_ivr_play_say.c:1302 Codec Activated L16@8000hz 1 channels 20ms 28f96f4c-4fd0-4d19-bd69-6b08e6887db2 2011-09-01 08:36:36.363287 [DEBUG] switch_ivr_play_say.c:1351 Setup timer success 320 bytes per 20 ms! b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:40.183284 [DEBUG] switch_core_session.c:857 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:40.183284 [DEBUG] switch_core_session.c:857 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:40.203284 [DEBUG] sofia.c:5142 Channel sofia/external/501XXXXXX(a)trunk.dialog.pl entering state [terminating][0] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:40.203284 [DEBUG] switch_channel.c:2775 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Callstate Change ACTIVE -> HANGUP b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:40.203284 [NOTICE] sofia.c:5867 Hangup sofia/external/501XXXXXX(a)trunk.dialog.pl [CS_EXECUTE] [NORMAL_UNSPECIFIED] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:40.203284 [DEBUG] switch_channel.c:2791 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [KILL] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:40.203284 [DEBUG] switch_core_session.c:1156 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:40.203284 [DEBUG] switch_ivr_play_say.c:1672 done playing file b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:40.203284 [DEBUG] switch_ivr_menu.c:343 waiting for 1/1 digits t/o 2000 b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:40.203284 [DEBUG] switch_ivr_menu.c:390 digits '' b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:40.203284 [DEBUG] switch_ivr_menu.c:580 IVR menu 'ivr' no input detected b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.203285 [DEBUG] switch_ivr_menu.c:594 exit-sound '/srv/nagrania/ivr/laczenie_z_operatorem.wav' b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.203285 [DEBUG] switch_core_session.c:2133 sofia/external/501XXXXXX(a)trunk.dialog.pl skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.203285 [DEBUG] switch_core_state_machine.c:385 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State EXECUTE going to sleep b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.203285 [DEBUG] switch_core_state_machine.c:330 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Running State Change CS_HANGUP b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.203285 [DEBUG] switch_core_media_bug.c:480 Removing BUG from sofia/external/501XXXXXX(a)trunk.dialog.pl b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.203285 [DEBUG] switch_ivr_async.c:937 Stop recording file /srv/nagrania/aktualne/IN_20110901-083608_71XXXXXXX_501XXXXXX.wav b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.203285 [DEBUG] switch_core_media_bug.c:480 Removing BUG from sofia/external/501XXXXXX(a)trunk.dialog.pl b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.203285 [DEBUG] switch_core_state_machine.c:580 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State HANGUP b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.203285 [DEBUG] mod_sofia.c:458 Channel sofia/external/501XXXXXX(a)trunk.dialog.pl hanging up, cause: NORMAL_UNSPECIFIED b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.203285 [DEBUG] switch_core_state_machine.c:46 sofia/external/501XXXXXX(a)trunk.dialog.pl Standard HANGUP, cause: NORMAL_UNSPECIFIED b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.203285 [DEBUG] switch_core_state_machine.c:580 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State HANGUP going to sleep b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.203285 [DEBUG] switch_core_state_machine.c:361 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State Change CS_HANGUP -> CS_REPORTING b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.203285 [DEBUG] switch_core_session.c:1156 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.203285 [DEBUG] switch_core_state_machine.c:330 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Running State Change CS_REPORTING b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.203285 [DEBUG] switch_core_state_machine.c:640 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State REPORTING b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.263295 [DEBUG] switch_core_state_machine.c:53 sofia/external/501XXXXXX(a)trunk.dialog.pl Standard REPORTING, cause: NORMAL_UNSPECIFIED b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.263295 [DEBUG] switch_core_state_machine.c:640 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State REPORTING going to sleep b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.263295 [DEBUG] switch_core_state_machine.c:355 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State Change CS_REPORTING -> CS_DESTROY b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.263295 [DEBUG] switch_core_session.c:1156 Send signal sofia/external/501XXXXXX(a)trunk.dialog.pl [BREAK] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.263295 [DEBUG] switch_core_session.c:1328 Session 2 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Locked, Waiting on external entities b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.263295 [NOTICE] switch_core_session.c:1346 Session 2 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Ended b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.263295 [NOTICE] switch_core_session.c:1348 Close Channel sofia/external/501XXXXXX(a)trunk.dialog.pl [CS_DESTROY] b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.263295 [DEBUG] switch_core_state_machine.c:469 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Callstate Change HANGUP -> DOWN b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.263295 [DEBUG] switch_core_state_machine.c:472 (sofia/external/501XXXXXX(a)trunk.dialog.pl) Running State Change CS_DESTROY b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.263295 [DEBUG] switch_core_state_machine.c:482 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State DESTROY b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.263295 [DEBUG] mod_sofia.c:363 sofia/external/501XXXXXX(a)trunk.dialog.pl SOFIA DESTROY b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.343286 [DEBUG] switch_core_state_machine.c:60 sofia/external/501XXXXXX(a)trunk.dialog.pl Standard DESTROY b541f405-1a39-4cbc-80dc-8bdbc92ed070 2011-09-01 08:36:41.343286 [DEBUG] switch_core_state_machine.c:482 (sofia/external/501XXXXXX(a)trunk.dialog.pl) State DESTROY going to sleep Any ideas ? -- Greetings - Tomasz Hyziak

4 6

recordFile max_len not obeyed
by Neil Patel 02 Sep '11

02 Sep '11

Hi All, Based on the documentation<http://wiki.freeswitch.org/wiki/Mod_lua#session:recordFile>, I am executing the following in a lua script: session:recordFile(filename, 10000, 100, 5); I expect that the recording will terminate automatically after 10 seconds. But it's not, the recording stays engaged long after 10s till I either terminate with '#' or there is enough trailing silence. What's the problem? Thanks in advance, Neil

3 4

Strange one-way audio problem
by Erin O'Meara 02 Sep '11

02 Sep '11

I have a Freeswitch server in the cloud with three SIP providers, DIDForSale and IPKall for incoming and CallWithUs for outgoing. When someone calls me, thru either incoming provider I hear everything clear but i'm choppy to the other end. When I make an outgoing call, audio is great in and out, also if I do an echo test my audio is perfect. I have check the freeswitch.log and its not transcoding (grep transcod freeswitch.log) which was a suggestion from #freeswitch also added verbose_sdp=true <param name="vad" value="disable"/> <param name="suppress-cng" value="true"/> with no success, any thoughts? Regards, 206.905.9520 http://salmonbaytechnology.com

1 0

session:recordFile(-) always creates empty wav file during outbound IVR call
by king2kin 02 Sep '11

02 Sep '11

Hi folks, With Lua script and/or originate command, I have tried recording a message file during outbound IVR call over and over, session:recordFile(-) inside Lua script does create a wav file during each of my testings but the recorded audio file is always empty. However, session:recordFile(-) works well for inbound IVR call. I tried the session:recordFile(-) via Lua script in three ways: 1. run lua script "test_outcall_ivr.lua" at freeswitch command-line: luarun test_outcall_ivr.lua -- [test_outcall_ivr.lua] { local sessionx = freeswitch.Session("sofia/gateway/sip.tpad.com/1726011", session) -- Set the path separator pathsep = '/' -- Windows users do this instead: -- pathsep = '\' -- Answer the call -- sessionx:answer() --Create a string with path and filename of a sound file prompt = "ivr" .. pathsep .. "ivr-welcome_to_freeswitch.wav" -- Print a log message freeswitch.consoleLog("INFO","Prompt file is '" .. prompt .. "'\n") --Play the prompt sessionx:streamFile(prompt) -- Record record file sessionx:streamFile("phrase:voicemail_record_message") -- Play a ""bong"" tone prior to recording sessionx:streamFile("tone_stream://v=-7;%(100,0,941.0,1477.0);v=-7;>=2;+=.1;%(1000, 0, 640)") -- record a message filename = sessionx:getVariable('sounds_dir') .. pathsep .. "123.wav" sessionx:recordFile(filename,300,100,10) -- play back the recorded msg sessionx:streamFile(filename) -- Hangup sessionx:hangup() } 2. I also tried it differently by submitting the following commands at the FreeSwitch command-line interface: originate user/1005 &transfer(8887 xml default) originate user/1005 &lua('test1.lua') originate sofia/gateway/sip.tpad.com/1726011 &lua('test1.lua') -- [test1.lua] { -- Set the path separator pathsep = '/' -- Windows users do this instead: -- pathsep = '\' --Answer the call session:answer() --Create a string with path and filename of a sound file prompt = "ivr" .. pathsep .. "ivr-welcome_to_freeswitch.wav" -- Print a log message freeswitch.consoleLog("INFO","Prompt file is '" .. prompt .. "'\n") --Play the prompt session:streamFile(prompt) -- Record record file session:streamFile("phrase:voicemail_record_message") -- Play a ""bong"" tone prior to recording session:streamFile("tone_stream://v=-7;%(100,0,941.0,1477.0);v=-7;>=2;+=.1;%(1000, 0, 640)") -- record a message filename = session:getVariable('sounds_dir') .. pathsep .. "123.wav" session:recordFile(filename,300,100,10) -- play back the recorded msg session:streamFile(filename) -- Hangup session:hangup() } -- [xml dialplan for extension 8887]: { <extension name="Simple Lua Test"> <condition field="destination_number" expression="^(8887)$"> <action application="set" data="record_waste_resources=true"/> <action application="lua" data="test1.lua"/> </condition> </extension> } ====================================================== For all the above testing cases, session:recordFile(-) always creates an empty wav file for each of outbound IVR calls, however, if I make an inbound IVR　call to run Lua script "test1.lua", session:recordFile(-) always works perfect to generate a normal wav file. So, what's wrong with [session:recordFile(-)] during an outbound IVR call? x.k.

3 12

Investigating build testing for FreeSWITCH
by Michael Collins 01 Sep '11

01 Sep '11

Hey all, On today's conference call someone pointed out that there is a utility called BuildBot that helps to automate testing of new commits to git, svn, etc. You can find it at buildbot.net. The catch: it's written in Python. (blech) I tried to build it on 3 different systems and I get 3 different undecipherable Python-ish errors. (Stuff like syntax errors during the build or syntax errors in twstd while it's running, dependency mismatches for python-virtualenv, etc.) If you have a system that can test this out, and you can stand to use Python this much (:D) then please try out the steps outlined here: http://buildbot.net/buildbot/docs/current/tutorial/firstrun.html Supposedly this thing will let us run multiple tests on multiple boxes and aggregate the results. I'll believe it when I see it. :) If you get this working please let me know. Also, if you can set up a test platform that would also be helpful. Ideally we would have multiple Linuxes, OS X, Windows, etc. and test on all these platforms. Let me know what you come up with. Thanks, Michael

3 7

freeswitch-1.0.7 Conference set auto outcall bug?
by qiaoqiao7036 01 Sep '11

01 Sep '11

Hi, I have used the function of conference set auto outcall in freeswitch 1.0.6, it is very good, several days ago, I installed a new freeswitch with git, the version is 1.0.7, I wrote a very simple dialplan to test the conference auto outcall function, and the problem happened: This is my dialplan, I have tested it in my freeswitch 1.0.6 successfully: <extension name="Demonstrate conference_set_auto_outcall"> <condition field="destination_number" expression="^(12345)$"> <action application="answer"/> <action application="set" data="conference_auto_outcall_timeout=5"/> <action application="set" data="conference_auto_outcall_flags=none"/> <action application="set" data="conference_auto_outcall_caller_id_name=$${ caller_id_name}"/> <action application="set" data="conference_auto_outcall_caller_id_number=$${ caller_id_number}"/> <action application="set" data="conference_auto_outcall_profile=default"/> <action application="conference_set_auto_outcall" data="[call_timeout=15]user/1001@$${domain}"/> <action application="conference_set_auto_outcall" data="[call_timeout=15]user/1002@$${domain}"/> <action application="conference" data="$1@default"/> </condition> </extension> Problem 1: When I use a softphone to dial number 12345, in my old freeswitch I can hear the moh music when the phone 1001 and 1002 is ring, But in freeswitch 1.0.7 I can't hear anything, and I found the debug informations in freeswitch cli: 2011-08-31 18:59:14.520108 [DEBUG] switch_core_session.c:1156 Send signal sofia/internal/sip:1001@192.168.2.226:5060 [BREAK] 2011-08-31 18:59:14.520108 [DEBUG] switch_core_state_machine.c:369 (sofia/internal/sip:1001@192.168.2.226:5060) State INIT going to sleep 2011-08-31 18:59:14.520108 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/sip:1001@192.168.2.226:5060) Running State Change CS_ROUTING 2011-08-31 18:59:14.520108 [DEBUG] switch_channel.c:1828 (sofia/internal/sip:1001@192.168.2.226:5060) Callstate Change DOWN -> RINGING 2011-08-31 18:59:14.520108 [DEBUG] switch_core_session.c:857 Send signal sofia/internal/sip:1001@192.168.2.226:5060 [BREAK] 2011-08-31 18:59:14.520108 [DEBUG] sofia.c:5128 Channel sofia/internal/sip:1001@192.168.2.226:5060 entering state [calling][0] 2011-08-31 18:59:14.520108 [DEBUG] switch_core_state_machine.c:378 (sofia/internal/sip:1001@192.168.2.226:5060) State ROUTING 2011-08-31 18:59:14.520108 [DEBUG] mod_sofia.c:148 sofia/internal/sip:1001@192.168.2.226:5060 SOFIA ROUTING 2011-08-31 18:59:14.520108 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/sip:1001@192.168.2.226:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2011-08-31 18:59:14.520108 [DEBUG] switch_core_session.c:1156 Send signal sofia/internal/sip:1001@192.168.2.226:5060 [BREAK] 2011-08-31 18:59:14.520108 [DEBUG] switch_core_state_machine.c:378 (sofia/internal/sip:1001@192.168.2.226:5060) State ROUTING going to sleep 2011-08-31 18:59:14.520108 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/sip:1001@192.168.2.226:5060) Running State Change CS_CONSUME_MEDIA 2011-08-31 18:59:14.520108 [DEBUG] switch_core_state_machine.c:397 (sofia/internal/sip:1001@192.168.2.226:5060) State CONSUME_MEDIA 2011-08-31 18:59:14.520108 [DEBUG] switch_core_state_machine.c:397 (sofia/internal/sip:1001@192.168.2.226:5060) State CONSUME_MEDIA going to sleep 2011-08-31 18:59:14.540080 [DEBUG] mod_local_stream.c:421 Opening Stream [moh/8000] 8000hz 2011-08-31 18:59:14.540080 [DEBUG] switch_core_session.c:857 Send signal sofia/internal/1000(a)192.168.2.242 [BREAK] 2011-08-31 18:59:14.540080 [DEBUG] switch_core_session.c:857 Send signal sofia/internal/1000(a)192.168.2.242 [BREAK] 2011-08-31 18:59:14.540080 [DEBUG] switch_core_session.c:857 Send signal sofia/internal/1000(a)192.168.2.242 [BREAK] 2011-08-31 18:59:14.559978 [DEBUG] sofia.c:5128 Channel sofia/internal/1000(a)192.168.2.242 entering state [ready][200] That mean the freeswitch is sending the moh music to the caller 1000, is that right? But I can't hear anything. Problem 2: when just one phone pickup, like phone 1001, and phone 1002 don't, (I use phone 1000 to call number 12345) I can't hear anything from phone 1001 when 1002 is ringing except phone 1002 pick up or when the phone 1002 is timeout to ring, it's very stranger!!! I tested this situation in freeswitch 1.0.6, when just only person pickup the phone the conference will work, 1000 will hear the phone 1001's voice, don't need the 1002 to pickup or timeout. By the way: I have tested this with the newest version: FreeSWITCH Version 1.0.head (git-6a5f6e5 2011-08-30 15-00-07 -0500) The problem is still exist. Thank you for advising me how to solve this problem. Regards! Dennis

2 1