December 2012 - FreeSWITCH-users - FreeSWITCH Mailing Lists

hylafax + mod_spandsp hangsup immediatly
by Tamer Higazi 06 Dec '12

06 Dec '12

Hi people! I've set up Freeswitch with mod_spandsp and hylafax and the modems are running (initialized) and great so far. Now, when I want to send a fax, hylafax aborts all the time and I am not getting smart. http://permalink.gmane.org/gmane.comp.telephony.fax.hylafax.user/36639 Any ideas what it could be?! Here is the log from the fs_console: 2012-12-06 11:13:49.936819 [DEBUG] mod_spandsp_modem.c:1070 Modem /dev/FS0 [ONHOOK] - Hanging up 2012-12-06 11:13:49.936819 [DEBUG] mod_spandsp_modem.c:1076 Modem /dev/FS0 [ONHOOK] - Changing state to HANGUP 2012-12-06 11:13:49.936819 [DEBUG] mod_spandsp_modem.c:1095 Modem /dev/FS0 [HANGUP] - Changing state to ONHOOK 2012-12-06 11:13:54.456814 [DEBUG] mod_spandsp_modem.c:1044 Modem /dev/FS0 [ONHOOK] - Dialing '1xxxxxxxxxxxxx' 2012-12-06 11:13:54.456814 [DEBUG] mod_spandsp_modem.c:1046 Modem /dev/FS0 [ONHOOK] - Changing state to DIALING 2012-12-06 11:13:54.456814 [NOTICE] switch_channel.c:941 New Channel modem/0/1xxxxxxxxxxxxx [585f9ebe-85fb-4152-b055-99fb72bf22ed] 2012-12-06 11:13:54.456814 [DEBUG] mod_spandsp_modem.c:769 modem/0/1xxxxxxxxxxxxx setup codec L16/8000/20 2012-12-06 11:13:54.456814 [DEBUG] mod_spandsp_modem.c:1003 (modem/0/1xxxxxxxxxxxxx) State Change CS_NEW -> CS_INIT 2012-12-06 11:13:54.456814 [DEBUG] switch_core_session.c:1229 Send signal modem/0/1xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:54.456814 [DEBUG] mod_spandsp_modem.c:581 modem/0/1xxxxxxxxxxxxx CHANNEL KILL 2012-12-06 11:13:54.456814 [DEBUG] switch_channel.c:1108 EXPORT (export_vars) [rtp_autoflush_during_bridge]=[false] 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:385 (modem/0/1xxxxxxxxxxxxx) Running State Change CS_INIT 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:424 (modem/0/1xxxxxxxxxxxxx) State INIT 2012-12-06 11:13:54.456814 [DEBUG] mod_spandsp_modem.c:484 (modem/0/1xxxxxxxxxxxxx) State Change CS_INIT -> CS_ROUTING 2012-12-06 11:13:54.456814 [DEBUG] switch_core_session.c:1229 Send signal modem/0/1xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:54.456814 [DEBUG] mod_spandsp_modem.c:581 modem/0/1xxxxxxxxxxxxx CHANNEL KILL 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:424 (modem/0/1xxxxxxxxxxxxx) State INIT going to sleep 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:385 (modem/0/1xxxxxxxxxxxxx) Running State Change CS_ROUTING 2012-12-06 11:13:54.456814 [DEBUG] switch_channel.c:1934 (modem/0/1xxxxxxxxxxxxx) Callstate Change DOWN -> RINGING 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:433 (modem/0/1xxxxxxxxxxxxx) State ROUTING 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:104 modem/0/1xxxxxxxxxxxxx Standard ROUTING 2012-12-06 11:13:54.456814 [INFO] mod_dialplan_xml.c:485 Processing FSModem <FS0>->1xxxxxxxxxxxxx in context default Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->unloop] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->tod_example] continue=true Dialplan: modem/0/1xxxxxxxxxxxxx Date/Time Match (PASS) [tod_example] break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx Action set(open=true) Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->holiday_example] continue=true Dialplan: modem/0/1xxxxxxxxxxxxx Date/TimeMatch (FAIL) [holiday_example] break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->global-intercept] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [global-intercept] destination_number(1xxxxxxxxxxxxx) =~ /^886$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->group-intercept] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [group-intercept] destination_number(1xxxxxxxxxxxxx) =~ /^\*8$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->intercept-ext] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [intercept-ext] destination_number(1xxxxxxxxxxxxx) =~ /^\*\*(\d+)$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->redial] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [redial] destination_number(1xxxxxxxxxxxxx) =~ /^(redial|870)$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->global] continue=true Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [global] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never Dialplan: modem/0/1xxxxxxxxxxxxx Absolute Condition [global] Dialplan: modem/0/1xxxxxxxxxxxxx Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) Dialplan: modem/0/1xxxxxxxxxxxxx Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) Dialplan: modem/0/1xxxxxxxxxxxxx Action hash(insert/${domain_name}-last_dial/global/${uuid}) Dialplan: modem/0/1xxxxxxxxxxxxx Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->snom-demo-2] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [snom-demo-2] destination_number(1xxxxxxxxxxxxx) =~ /^9001$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->snom-demo-1] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [snom-demo-1] destination_number(1xxxxxxxxxxxxx) =~ /^9000$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->eavesdrop] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [eavesdrop] destination_number(1xxxxxxxxxxxxx) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->eavesdrop] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [eavesdrop] destination_number(1xxxxxxxxxxxxx) =~ /^779$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->call_return] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [call_return] destination_number(1xxxxxxxxxxxxx) =~ /^\*69$|^869$|^lcr$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->del-group] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [del-group] destination_number(1xxxxxxxxxxxxx) =~ /^80(\d{2})$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->add-group] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [add-group] destination_number(1xxxxxxxxxxxxx) =~ /^81(\d{2})$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->call-group-simo] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [call-group-simo] destination_number(1xxxxxxxxxxxxx) =~ /^82(\d{2})$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->call-group-order] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [call-group-order] destination_number(1xxxxxxxxxxxxx) =~ /^83(\d{2})$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->extension-intercom] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [extension-intercom] destination_number(1xxxxxxxxxxxxx) =~ /^8(10[01][0-9])$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->Local_Extension] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [Local_Extension] destination_number(1xxxxxxxxxxxxx) =~ /^(10[01][0-9])$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->Local_Extension_Skinny] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [Local_Extension_Skinny] destination_number(1xxxxxxxxxxxxx) =~ /^(11[01][0-9])$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->group_dial_sales] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [group_dial_sales] destination_number(1xxxxxxxxxxxxx) =~ /^2000$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->group_dial_support] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [group_dial_support] destination_number(1xxxxxxxxxxxxx) =~ /^2001$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->group_dial_billing] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [group_dial_billing] destination_number(1xxxxxxxxxxxxx) =~ /^2002$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->operator] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [operator] destination_number(1xxxxxxxxxxxxx) =~ /^(operator|0)$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->vmain] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [vmain] destination_number(1xxxxxxxxxxxxx) =~ /^vmain$|^4000$|^\*98$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->sip_uri] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [sip_uri] destination_number(1xxxxxxxxxxxxx) =~ /^sip:(.*)$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->nb_conferences] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [nb_conferences] destination_number(1xxxxxxxxxxxxx) =~ /^(30\d{2})$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->wb_conferences] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [wb_conferences] destination_number(1xxxxxxxxxxxxx) =~ /^(31\d{2})$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->uwb_conferences] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [uwb_conferences] destination_number(1xxxxxxxxxxxxx) =~ /^(32\d{2})$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->cdquality_conferences] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [cdquality_conferences] destination_number(1xxxxxxxxxxxxx) =~ /^(33\d{2})$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->freeswitch_public_conf_via_sip] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(1xxxxxxxxxxxxx) =~ /^9(888|8888|1616|3232)$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->mad_boss_intercom] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [mad_boss_intercom] destination_number(1xxxxxxxxxxxxx) =~ /^0911$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->mad_boss_intercom] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [mad_boss_intercom] destination_number(1xxxxxxxxxxxxx) =~ /^0912$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->mad_boss] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [mad_boss] destination_number(1xxxxxxxxxxxxx) =~ /^0913$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->ivr_demo] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [ivr_demo] destination_number(1xxxxxxxxxxxxx) =~ /^5000$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->dynamic_conference] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [dynamic_conference] destination_number(1xxxxxxxxxxxxx) =~ /^5001$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->rtp_multicast_page] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [rtp_multicast_page] destination_number(1xxxxxxxxxxxxx) =~ /^pagegroup$|^7243$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->park] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [park] destination_number(1xxxxxxxxxxxxx) =~ /^5900$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->unpark] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [unpark] destination_number(1xxxxxxxxxxxxx) =~ /^5901$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->valet_park] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [valet_park] destination_number(1xxxxxxxxxxxxx) =~ /^(6000)$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->valet_park] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [valet_park] destination_number(1xxxxxxxxxxxxx) =~ /^(60\d[1-9])$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->park] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [park] source(mod_spandsp) =~ /mod_sofia/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->unpark] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [unpark] source(mod_spandsp) =~ /mod_sofia/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->park] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [park] source(mod_spandsp) =~ /mod_sofia/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->unpark] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [unpark] source(mod_spandsp) =~ /mod_sofia/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->wait] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [wait] destination_number(1xxxxxxxxxxxxx) =~ /^wait$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->fax_receive] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [fax_receive] destination_number(1xxxxxxxxxxxxx) =~ /^9178$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->fax_transmit] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [fax_transmit] destination_number(1xxxxxxxxxxxxx) =~ /^9179$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->ringback_180] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [ringback_180] destination_number(1xxxxxxxxxxxxx) =~ /^9180$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->ringback_183_uk_ring] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [ringback_183_uk_ring] destination_number(1xxxxxxxxxxxxx) =~ /^9181$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->ringback_183_music_ring] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [ringback_183_music_ring] destination_number(1xxxxxxxxxxxxx) =~ /^9182$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->ringback_post_answer_uk_ring] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(1xxxxxxxxxxxxx) =~ /^9183$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->ringback_post_answer_music] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [ringback_post_answer_music] destination_number(1xxxxxxxxxxxxx) =~ /^9184$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->ClueCon] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [ClueCon] destination_number(1xxxxxxxxxxxxx) =~ /^9191$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->show_info] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [show_info] destination_number(1xxxxxxxxxxxxx) =~ /^9192$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->video_record] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [video_record] destination_number(1xxxxxxxxxxxxx) =~ /^9193$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->video_playback] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [video_playback] destination_number(1xxxxxxxxxxxxx) =~ /^9194$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->delay_echo] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [delay_echo] destination_number(1xxxxxxxxxxxxx) =~ /^9195$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->echo] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [echo] destination_number(1xxxxxxxxxxxxx) =~ /^9196$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->milliwatt] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [milliwatt] destination_number(1xxxxxxxxxxxxx) =~ /^9197$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->tone_stream] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [tone_stream] destination_number(1xxxxxxxxxxxxx) =~ /^9198$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->zrtp_enrollement] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [zrtp_enrollement] destination_number(1xxxxxxxxxxxxx) =~ /^9787$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->hold_music] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [hold_music] destination_number(1xxxxxxxxxxxxx) =~ /^9664$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->laugh break] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [laugh break] destination_number(1xxxxxxxxxxxxx) =~ /^9386$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->Talking Clock Time] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [Talking Clock Time] destination_number(1xxxxxxxxxxxxx) =~ /^9170$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->Talking Clock Date] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [Talking Clock Date] destination_number(1xxxxxxxxxxxxx) =~ /^9171$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->Talking Clock Date and Time] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [Talking Clock Date and Time] destination_number(1xxxxxxxxxxxxx) =~ /^9172$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->sipgate0] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [sipgate0] destination_number(1xxxxxxxxxxxxx) =~ /^2(\d{3,})/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->sipgate1] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [sipgate1] destination_number(1xxxxxxxxxxxxx) =~ /^3(\d{3,})/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->ABX] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [ABX] destination_number(1xxxxxxxxxxxxx) =~ /^(90)$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->ABX2] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [ABX2] destination_number(1xxxxxxxxxxxxx) =~ /^(91)$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->FSX] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [FSX] destination_number(1xxxxxxxxxxxxx) =~ /^(92)$/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->easybell0] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (FAIL) [easybell0] destination_number(1xxxxxxxxxxxxx) =~ /^0(\d{3,})/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx parsing [default->easybell1] continue=false Dialplan: modem/0/1xxxxxxxxxxxxx Regex (PASS) [easybell1] destination_number(1xxxxxxxxxxxxx) =~ /^1(\d{3,})/ break=on-false Dialplan: modem/0/1xxxxxxxxxxxxx Action set(effective_caller_id_number=${outbound_caller_id_number}) Dialplan: modem/0/1xxxxxxxxxxxxx Action set(absolute_codec_string=PCMA) Dialplan: modem/0/1xxxxxxxxxxxxx Action set(fax_enable_t38=true) Dialplan: modem/0/1xxxxxxxxxxxxx Action set(fax_enable_t38_request=true) Dialplan: modem/0/1xxxxxxxxxxxxx Action set(execute_on_answer=t38_gateway self) Dialplan: modem/0/1xxxxxxxxxxxxx Action bridge(sofia/gateway/easybell1/xxxxxxxxxxxxx) Dialplan: modem/0/1xxxxxxxxxxxxx Action hangup() 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:154 (modem/0/1xxxxxxxxxxxxx) State Change CS_ROUTING -> CS_EXECUTE 2012-12-06 11:13:54.456814 [DEBUG] switch_core_session.c:1229 Send signal modem/0/1xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:54.456814 [DEBUG] mod_spandsp_modem.c:581 modem/0/1xxxxxxxxxxxxx CHANNEL KILL 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:433 (modem/0/1xxxxxxxxxxxxx) State ROUTING going to sleep 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:385 (modem/0/1xxxxxxxxxxxxx) Running State Change CS_EXECUTE 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:440 (modem/0/1xxxxxxxxxxxxx) State EXECUTE 2012-12-06 11:13:54.456814 [DEBUG] mod_spandsp_modem.c:514 modem/0/1xxxxxxxxxxxxx CHANNEL EXECUTE 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:196 modem/0/1xxxxxxxxxxxxx Standard EXECUTE EXECUTE modem/0/1xxxxxxxxxxxxx set(open=true) 2012-12-06 11:13:54.456814 [DEBUG] mod_dptools.c:1319 modem/0/1xxxxxxxxxxxxx SET [open]=[true] EXECUTE modem/0/1xxxxxxxxxxxxx hash(insert/212.255.30.34-spymap/FS0/585f9ebe-85fb-4152-b055-99fb72bf22ed) EXECUTE modem/0/1xxxxxxxxxxxxx hash(insert/212.255.30.34-last_dial/FS0/1xxxxxxxxxxxxx) EXECUTE modem/0/1xxxxxxxxxxxxx hash(insert/212.255.30.34-last_dial/global/585f9ebe-85fb-4152-b055-99fb72bf22ed) EXECUTE modem/0/1xxxxxxxxxxxxx export(RFC2822_DATE=Thu, 06 Dec 2012 11:13:54 +0100) 2012-12-06 11:13:54.456814 [DEBUG] switch_channel.c:1108 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 06 Dec 2012 11:13:54 +0100] EXECUTE modem/0/1xxxxxxxxxxxxx set(effective_caller_id_number=) 2012-12-06 11:13:54.456814 [DEBUG] mod_dptools.c:1319 modem/0/1xxxxxxxxxxxxx SET [effective_caller_id_number]=[UNDEF] EXECUTE modem/0/1xxxxxxxxxxxxx set(absolute_codec_string=PCMA) 2012-12-06 11:13:54.456814 [DEBUG] mod_dptools.c:1319 modem/0/1xxxxxxxxxxxxx SET [absolute_codec_string]=[PCMA] EXECUTE modem/0/1xxxxxxxxxxxxx set(fax_enable_t38=true) 2012-12-06 11:13:54.456814 [DEBUG] mod_dptools.c:1319 modem/0/1xxxxxxxxxxxxx SET [fax_enable_t38]=[true] EXECUTE modem/0/1xxxxxxxxxxxxx set(fax_enable_t38_request=true) 2012-12-06 11:13:54.456814 [DEBUG] mod_dptools.c:1319 modem/0/1xxxxxxxxxxxxx SET [fax_enable_t38_request]=[true] EXECUTE modem/0/1xxxxxxxxxxxxx set(execute_on_answer=t38_gateway self) 2012-12-06 11:13:54.456814 [DEBUG] mod_dptools.c:1319 modem/0/1xxxxxxxxxxxxx SET [execute_on_answer]=[t38_gateway self] EXECUTE modem/0/1xxxxxxxxxxxxx bridge(sofia/gateway/easybell1/xxxxxxxxxxxxx) 2012-12-06 11:13:54.456814 [DEBUG] switch_channel.c:1062 modem/0/1xxxxxxxxxxxxx EXPORTING[export_vars] [rtp_autoflush_during_bridge]=[false] to event 2012-12-06 11:13:54.456814 [DEBUG] switch_channel.c:1062 modem/0/1xxxxxxxxxxxxx EXPORTING[export_vars] [RFC2822_DATE]=[Thu, 06 Dec 2012 11:13:54 +0100] to event 2012-12-06 11:13:54.456814 [DEBUG] switch_ivr_originate.c:1961 Parsing global variables 2012-12-06 11:13:54.456814 [NOTICE] switch_channel.c:941 New Channel sofia/external/xxxxxxxxxxxxx [b0ac6763-2068-4574-849c-f443d99e978a] 2012-12-06 11:13:54.456814 [DEBUG] mod_sofia.c:4796 (sofia/external/xxxxxxxxxxxxx) State Change CS_NEW -> CS_INIT 2012-12-06 11:13:54.456814 [DEBUG] switch_core_session.c:1229 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:385 (sofia/external/xxxxxxxxxxxxx) Running State Change CS_INIT 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:424 (sofia/external/xxxxxxxxxxxxx) State INIT 2012-12-06 11:13:54.456814 [DEBUG] mod_sofia.c:85 sofia/external/xxxxxxxxxxxxx SOFIA INIT 2012-12-06 11:13:54.456814 [DEBUG] sofia_glue.c:2609 Local SDP: v=0 o=FreeSWITCH 1354769940 1354769941 IN IP4 212.255.30.34 s=FreeSWITCH c=IN IP4 212.255.30.34 t=0 0 m=audio 18894 RTP/AVP 98 0 8 3 101 13 a=rtpmap:98 L16/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2012-12-06 11:13:54.456814 [DEBUG] mod_sofia.c:125 (sofia/external/xxxxxxxxxxxxx) State Change CS_INIT -> CS_ROUTING 2012-12-06 11:13:54.456814 [DEBUG] switch_core_session.c:1229 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:424 (sofia/external/xxxxxxxxxxxxx) State INIT going to sleep 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:385 (sofia/external/xxxxxxxxxxxxx) Running State Change CS_ROUTING 2012-12-06 11:13:54.456814 [DEBUG] switch_channel.c:1934 (sofia/external/xxxxxxxxxxxxx) Callstate Change DOWN -> RINGING 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:433 (sofia/external/xxxxxxxxxxxxx) State ROUTING 2012-12-06 11:13:54.456814 [DEBUG] mod_sofia.c:148 sofia/external/xxxxxxxxxxxxx SOFIA ROUTING 2012-12-06 11:13:54.456814 [DEBUG] switch_ivr_originate.c:67 (sofia/external/xxxxxxxxxxxxx) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2012-12-06 11:13:54.456814 [DEBUG] switch_core_session.c:1229 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:433 (sofia/external/xxxxxxxxxxxxx) State ROUTING going to sleep 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:385 (sofia/external/xxxxxxxxxxxxx) Running State Change CS_CONSUME_MEDIA 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:452 (sofia/external/xxxxxxxxxxxxx) State CONSUME_MEDIA 2012-12-06 11:13:54.456814 [DEBUG] switch_core_state_machine.c:452 (sofia/external/xxxxxxxxxxxxx) State CONSUME_MEDIA going to sleep 2012-12-06 11:13:54.456814 [DEBUG] switch_core_session.c:924 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:54.456814 [DEBUG] sofia.c:6055 Channel sofia/external/xxxxxxxxxxxxx entering state [calling][0] 2012-12-06 11:13:54.496801 [DEBUG] switch_core_session.c:924 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:54.496801 [DEBUG] switch_core_session.c:924 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:54.496801 [DEBUG] sofia.c:6055 Channel sofia/external/xxxxxxxxxxxxx entering state [calling][0] 2012-12-06 11:13:58.656800 [DEBUG] switch_core_session.c:924 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:58.656800 [DEBUG] switch_core_session.c:924 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:58.656800 [DEBUG] sofia.c:6055 Channel sofia/external/xxxxxxxxxxxxx entering state [proceeding][183] 2012-12-06 11:13:58.656800 [DEBUG] sofia.c:6066 Remote SDP: v=0 o=- 7632 1354788832 IN IP4 212.172.97.124 s=- c=IN IP4 212.172.97.124 t=0 0 m=audio 32392 RTP/AVP 8 101 a=rtpmap:101 telephone-event/8000/1 a=fmtp:101 0-15 a=ptime:20 a=nortpproxy:yes 2012-12-06 11:13:58.656800 [DEBUG] sofia_glue.c:3948 Looking for zrtp-hash 2012-12-06 11:13:58.656800 [DEBUG] sofia_glue.c:3926 Deciding whether to pass zrtp-hash between legs 2012-12-06 11:13:58.656800 [DEBUG] sofia_glue.c:3928 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash 2012-12-06 11:13:58.656800 [DEBUG] sofia_glue.c:3948 Looking for zrtp-hash 2012-12-06 11:13:58.656800 [DEBUG] sofia_glue.c:3926 Deciding whether to pass zrtp-hash between legs 2012-12-06 11:13:58.656800 [DEBUG] sofia_glue.c:3928 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash 2012-12-06 11:13:58.656800 [DEBUG] sofia_glue.c:5044 Audio Codec Compare [PCMA:8:8000:20:64000]/[L16:70:8000:20:128000] 2012-12-06 11:13:58.656800 [DEBUG] sofia_glue.c:5044 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMU:0:8000:20:64000] 2012-12-06 11:13:58.656800 [DEBUG] sofia_glue.c:5044 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMA:8:8000:20:64000] 2012-12-06 11:13:58.656800 [DEBUG] sofia_glue.c:3027 Set Codec sofia/external/xxxxxxxxxxxxx PCMA/8000 20 ms 160 samples 64000 bits 2012-12-06 11:13:58.656800 [DEBUG] switch_core_codec.c:111 sofia/external/xxxxxxxxxxxxx Original read codec set to PCMA:8 2012-12-06 11:13:58.656800 [DEBUG] sofia_glue.c:5158 Set 2833 dtmf send payload to 101 2012-12-06 11:13:58.656800 [DEBUG] sofia_glue.c:3276 AUDIO RTP [sofia/external/xxxxxxxxxxxxx] 212.255.30.34 port 18894 -> 212.172.97.124 port 32392 codec: 8 ms: 20 2012-12-06 11:13:58.656800 [DEBUG] switch_rtp.c:1927 Starting timer [soft] 160 bytes per 20ms 2012-12-06 11:13:58.656800 [DEBUG] sofia_glue.c:3540 Set 2833 dtmf send payload to 101 2012-12-06 11:13:58.656800 [DEBUG] sofia_glue.c:3546 Set 2833 dtmf receive payload to 101 2012-12-06 11:13:58.656800 [DEBUG] sofia_glue.c:3573 sofia/external/xxxxxxxxxxxxx Set rtp dtmf delay to 40 2012-12-06 11:13:58.656800 [NOTICE] sofia_glue.c:4176 Pre-Answer sofia/external/xxxxxxxxxxxxx! 2012-12-06 11:13:58.656800 [DEBUG] switch_channel.c:3057 (sofia/external/xxxxxxxxxxxxx) Callstate Change RINGING -> EARLY 2012-12-06 11:13:58.656800 [DEBUG] switch_channel.c:3099 Send signal modem/0/1xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:58.656800 [DEBUG] mod_spandsp_modem.c:581 modem/0/1xxxxxxxxxxxxx CHANNEL KILL 2012-12-06 11:13:58.656800 [DEBUG] mod_spandsp_modem.c:1131 Modem /dev/FS0 [DIALING] - RNG 0 2012-12-06 11:13:58.656800 [DEBUG] mod_spandsp_modem.c:738 Modem /dev/FS0 [DIALING] - Changing state to CONNECTED 2012-12-06 11:13:58.656800 [DEBUG] switch_core_session.c:840 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:58.656800 [DEBUG] switch_core_session.c:778 Send signal modem/0/1xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:58.656800 [DEBUG] mod_spandsp_modem.c:581 modem/0/1xxxxxxxxxxxxx CHANNEL KILL 2012-12-06 11:13:58.656800 [NOTICE] switch_ivr_originate.c:3309 Pre-Answer modem/0/1xxxxxxxxxxxxx! 2012-12-06 11:13:58.656800 [DEBUG] switch_channel.c:3057 (modem/0/1xxxxxxxxxxxxx) Callstate Change RINGING -> EARLY 2012-12-06 11:13:58.656800 [DEBUG] switch_ivr_originate.c:3360 Originate Resulted in Success: [sofia/external/xxxxxxxxxxxxx] 2012-12-06 11:13:58.656800 [DEBUG] switch_core_session.c:778 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:58.656800 [DEBUG] switch_core_session.c:840 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:58.656800 [DEBUG] switch_core_session.c:778 Send signal modem/0/1xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:58.656800 [DEBUG] mod_spandsp_modem.c:581 modem/0/1xxxxxxxxxxxxx CHANNEL KILL 2012-12-06 11:13:58.656800 [DEBUG] switch_ivr_bridge.c:1359 (sofia/external/xxxxxxxxxxxxx) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2012-12-06 11:13:58.656800 [DEBUG] switch_core_session.c:1229 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:58.656800 [DEBUG] switch_core_state_machine.c:385 (sofia/external/xxxxxxxxxxxxx) Running State Change CS_EXCHANGE_MEDIA 2012-12-06 11:13:58.656800 [DEBUG] switch_core_state_machine.c:443 (sofia/external/xxxxxxxxxxxxx) State EXCHANGE_MEDIA 2012-12-06 11:13:58.656800 [DEBUG] mod_sofia.c:652 SOFIA EXCHANGE_MEDIA 2012-12-06 11:13:58.696829 [DEBUG] switch_rtp.c:3594 Correct ip/port confirmed. 2012-12-06 11:13:58.936865 [DEBUG] switch_core_session.c:924 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:58.936865 [DEBUG] switch_core_session.c:924 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:58.956952 [DEBUG] sofia.c:6048 Channel sofia/external/xxxxxxxxxxxxx skipping state [proceeding][180] 2012-12-06 11:13:59.956847 [DEBUG] switch_core_session.c:924 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:59.956847 [DEBUG] switch_core_session.c:924 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:59.956847 [DEBUG] switch_core_session.c:924 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:59.976824 [DEBUG] sofia.c:6055 Channel sofia/external/xxxxxxxxxxxxx entering state [terminated][486] 2012-12-06 11:13:59.976824 [DEBUG] switch_channel.c:2914 (sofia/external/xxxxxxxxxxxxx) Callstate Change EARLY -> HANGUP 2012-12-06 11:13:59.976824 [NOTICE] sofia.c:6847 Hangup sofia/external/xxxxxxxxxxxxx [CS_EXCHANGE_MEDIA] [USER_BUSY] 2012-12-06 11:13:59.976824 [DEBUG] switch_channel.c:2937 Send signal sofia/external/xxxxxxxxxxxxx [KILL] 2012-12-06 11:13:59.976824 [DEBUG] switch_core_session.c:1229 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:59.976824 [DEBUG] switch_ivr_bridge.c:588 BRIDGE THREAD DONE [sofia/external/xxxxxxxxxxxxx] 2012-12-06 11:13:59.976824 [DEBUG] switch_ivr_bridge.c:613 Send signal modem/0/1xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:59.976824 [DEBUG] mod_spandsp_modem.c:581 modem/0/1xxxxxxxxxxxxx CHANNEL KILL 2012-12-06 11:13:59.976824 [DEBUG] switch_core_state_machine.c:443 (sofia/external/xxxxxxxxxxxxx) State EXCHANGE_MEDIA going to sleep 2012-12-06 11:13:59.976824 [DEBUG] switch_core_state_machine.c:385 (sofia/external/xxxxxxxxxxxxx) Running State Change CS_HANGUP 2012-12-06 11:13:59.976824 [DEBUG] switch_core_state_machine.c:625 (sofia/external/xxxxxxxxxxxxx) State HANGUP 2012-12-06 11:13:59.976824 [DEBUG] mod_sofia.c:474 Channel sofia/external/xxxxxxxxxxxxx hanging up, cause: USER_BUSY 2012-12-06 11:13:59.976824 [DEBUG] switch_core_state_machine.c:47 sofia/external/xxxxxxxxxxxxx Standard HANGUP, cause: USER_BUSY 2012-12-06 11:13:59.976824 [DEBUG] switch_core_state_machine.c:625 (sofia/external/xxxxxxxxxxxxx) State HANGUP going to sleep 2012-12-06 11:13:59.976824 [DEBUG] switch_core_state_machine.c:416 (sofia/external/xxxxxxxxxxxxx) State Change CS_HANGUP -> CS_REPORTING 2012-12-06 11:13:59.976824 [DEBUG] switch_core_session.c:1229 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:59.976824 [DEBUG] switch_core_state_machine.c:385 (sofia/external/xxxxxxxxxxxxx) Running State Change CS_REPORTING 2012-12-06 11:13:59.976824 [DEBUG] switch_core_state_machine.c:685 (sofia/external/xxxxxxxxxxxxx) State REPORTING 2012-12-06 11:13:59.976824 [DEBUG] switch_core_state_machine.c:79 sofia/external/xxxxxxxxxxxxx Standard REPORTING, cause: USER_BUSY 2012-12-06 11:13:59.976824 [DEBUG] switch_core_state_machine.c:685 (sofia/external/xxxxxxxxxxxxx) State REPORTING going to sleep 2012-12-06 11:13:59.976824 [DEBUG] switch_core_state_machine.c:410 (sofia/external/xxxxxxxxxxxxx) State Change CS_REPORTING -> CS_DESTROY 2012-12-06 11:13:59.976824 [DEBUG] switch_core_session.c:1229 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:59.976824 [DEBUG] switch_core_session.c:1429 Session 24 (sofia/external/xxxxxxxxxxxxx) Locked, Waiting on external entities 2012-12-06 11:13:59.996802 [DEBUG] switch_ivr_bridge.c:501 sofia/external/xxxxxxxxxxxxx ending bridge by request from write function 2012-12-06 11:13:59.996802 [DEBUG] switch_ivr_bridge.c:588 BRIDGE THREAD DONE [modem/0/1xxxxxxxxxxxxx] 2012-12-06 11:13:59.996802 [DEBUG] switch_ivr_bridge.c:613 Send signal sofia/external/xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:59.996802 [DEBUG] switch_core_session.c:778 Send signal modem/0/1xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:59.996802 [DEBUG] mod_spandsp_modem.c:581 modem/0/1xxxxxxxxxxxxx CHANNEL KILL 2012-12-06 11:13:59.996802 [NOTICE] switch_core_session.c:1447 Session 24 (sofia/external/xxxxxxxxxxxxx) Ended 2012-12-06 11:13:59.996802 [NOTICE] switch_core_session.c:1449 Close Channel sofia/external/xxxxxxxxxxxxx [CS_DESTROY] EXECUTE modem/0/1xxxxxxxxxxxxx hangup() 2012-12-06 11:13:59.996802 [DEBUG] switch_channel.c:2914 (modem/0/1xxxxxxxxxxxxx) Callstate Change EARLY -> HANGUP 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:514 (sofia/external/xxxxxxxxxxxxx) Callstate Change HANGUP -> DOWN 2012-12-06 11:13:59.996802 [NOTICE] mod_dptools.c:1134 Hangup modem/0/1xxxxxxxxxxxxx [CS_EXECUTE] [NORMAL_CLEARING] 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:517 (sofia/external/xxxxxxxxxxxxx) Running State Change CS_DESTROY 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:527 (sofia/external/xxxxxxxxxxxxx) State DESTROY 2012-12-06 11:13:59.996802 [DEBUG] mod_sofia.c:374 sofia/external/xxxxxxxxxxxxx SOFIA DESTROY 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:86 sofia/external/xxxxxxxxxxxxx Standard DESTROY 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:527 (sofia/external/xxxxxxxxxxxxx) State DESTROY going to sleep 2012-12-06 11:13:59.996802 [DEBUG] switch_channel.c:2937 Send signal modem/0/1xxxxxxxxxxxxx [KILL] 2012-12-06 11:13:59.996802 [DEBUG] mod_spandsp_modem.c:581 modem/0/1xxxxxxxxxxxxx CHANNEL KILL 2012-12-06 11:13:59.996802 [DEBUG] switch_core_session.c:1229 Send signal modem/0/1xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:59.996802 [DEBUG] mod_spandsp_modem.c:581 modem/0/1xxxxxxxxxxxxx CHANNEL KILL 2012-12-06 11:13:59.996802 [DEBUG] switch_core_session.c:2345 modem/0/1xxxxxxxxxxxxx skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:440 (modem/0/1xxxxxxxxxxxxx) State EXECUTE going to sleep 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:385 (modem/0/1xxxxxxxxxxxxx) Running State Change CS_HANGUP 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:625 (modem/0/1xxxxxxxxxxxxx) State HANGUP 2012-12-06 11:13:59.996802 [DEBUG] mod_spandsp_modem.c:553 modem/0/1xxxxxxxxxxxxx CHANNEL HANGUP 2012-12-06 11:13:59.996802 [DEBUG] mod_spandsp_modem.c:1070 Modem /dev/FS0 [CONNECTED] - Hanging up 2012-12-06 11:13:59.996802 [DEBUG] mod_spandsp_modem.c:1076 Modem /dev/FS0 [CONNECTED] - Changing state to HANGUP 2012-12-06 11:13:59.996802 [DEBUG] mod_spandsp_modem.c:1095 Modem /dev/FS0 [HANGUP] - Changing state to ONHOOK 2012-12-06 11:13:59.996802 [DEBUG] mod_spandsp_modem.c:1131 Modem /dev/FS0 [ONHOOK] - RNG 0 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:47 modem/0/1xxxxxxxxxxxxx Standard HANGUP, cause: NORMAL_CLEARING 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:625 (modem/0/1xxxxxxxxxxxxx) State HANGUP going to sleep 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:416 (modem/0/1xxxxxxxxxxxxx) State Change CS_HANGUP -> CS_REPORTING 2012-12-06 11:13:59.996802 [DEBUG] switch_core_session.c:1229 Send signal modem/0/1xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:59.996802 [DEBUG] mod_spandsp_modem.c:581 modem/0/1xxxxxxxxxxxxx CHANNEL KILL 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:385 (modem/0/1xxxxxxxxxxxxx) Running State Change CS_REPORTING 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:685 (modem/0/1xxxxxxxxxxxxx) State REPORTING 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:79 modem/0/1xxxxxxxxxxxxx Standard REPORTING, cause: NORMAL_CLEARING 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:685 (modem/0/1xxxxxxxxxxxxx) State REPORTING going to sleep 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:410 (modem/0/1xxxxxxxxxxxxx) State Change CS_REPORTING -> CS_DESTROY 2012-12-06 11:13:59.996802 [DEBUG] switch_core_session.c:1229 Send signal modem/0/1xxxxxxxxxxxxx [BREAK] 2012-12-06 11:13:59.996802 [DEBUG] mod_spandsp_modem.c:581 modem/0/1xxxxxxxxxxxxx CHANNEL KILL 2012-12-06 11:13:59.996802 [DEBUG] switch_core_session.c:1429 Session 23 (modem/0/1xxxxxxxxxxxxx) Locked, Waiting on external entities 2012-12-06 11:13:59.996802 [NOTICE] switch_core_session.c:1447 Session 23 (modem/0/1xxxxxxxxxxxxx) Ended 2012-12-06 11:13:59.996802 [NOTICE] switch_core_session.c:1449 Close Channel modem/0/1xxxxxxxxxxxxx [CS_DESTROY] 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:514 (modem/0/1xxxxxxxxxxxxx) Callstate Change HANGUP -> DOWN 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:517 (modem/0/1xxxxxxxxxxxxx) Running State Change CS_DESTROY 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:527 (modem/0/1xxxxxxxxxxxxx) State DESTROY 2012-12-06 11:13:59.996802 [DEBUG] mod_spandsp_modem.c:534 Modem /dev/FS0 [ONHOOK] - Changing state to ONHOOK 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:86 modem/0/1xxxxxxxxxxxxx Standard DESTROY 2012-12-06 11:13:59.996802 [DEBUG] switch_core_state_machine.c:527 (modem/0/1xxxxxxxxxxxxx) State DESTROY going to sleep 2012-12-06 11:14:00.996881 [DEBUG] mod_spandsp_modem.c:1070 Modem /dev/FS0 [ONHOOK] - Hanging up 2012-12-06 11:14:00.996881 [DEBUG] mod_spandsp_modem.c:1076 Modem /dev/FS0 [ONHOOK] - Changing state to HANGUP 2012-12-06 11:14:00.996881 [DEBUG] mod_spandsp_modem.c:1095 Modem /dev/FS0 [HANGUP] - Changing state to ONHOOK 2012-12-06 11:14:19.676800 [DEBUG] mod_spandsp_modem.c:1070 Modem /dev/FS0 [ONHOOK] - Hanging up 2012-12-06 11:14:19.676800 [DEBUG] mod_spandsp_modem.c:1076 Modem /dev/FS0 [ONHOOK] - Changing state to HANGUP 2012-12-06 11:14:19.676800 [DEBUG] mod_spandsp_modem.c:1095 Modem /dev/FS0 [HANGUP] - Changing state to ONHOOK freeswitch@internal>

1 0

Re: [Freeswitch-users] getting error on sysout not in log
by Peter Olsson 06 Dec '12

06 Dec '12

That's from the MySQL libraries. /Peter -----Ursprungligt meddelande----- Från: freeswitch-users-bounces(a)lists.freeswitch.org [mailto:freeswitch-users-bounces@lists.freeswitch.org] För covici(a)ccs.covici.com Skickat: den 6 december 2012 08:31 Till: freeswitch-users(a)lists.freeswitch.org Ämne: [Freeswitch-users] getting error on sysout not in log Hi. I am getting the following error in sysout rather than the .log file: 2012-12-06_07:19:57.65099 Error in my_thread_global_end(): 1 threads didn't exit This is with latest git. The only thing happening at the same time as the listed error was a sofia registration. How can I proceed? It looks I am using sqlite for the databases in the core, if that makes any difference. Thanks in advance for any help. -- Your life is like a penny. You're going to lose it. The question is: How do you spend it? John Covici covici(a)ccs.covici.com _________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting(a)freeswitch.org http://www.freeswitchsolutions.com FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com FreeSWITCH-users mailing list FreeSWITCH-users(a)lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org !DSPAM:50c0471f32761351719099!

1 0

getting error on sysout not in log
by covici＠ccs.covici.com 06 Dec '12

06 Dec '12

Hi. I am getting the following error in sysout rather than the .log file: 2012-12-06_07:19:57.65099 Error in my_thread_global_end(): 1 threads didn't exit This is with latest git. The only thing happening at the same time as the listed error was a sofia registration. How can I proceed? It looks I am using sqlite for the databases in the core, if that makes any difference. Thanks in advance for any help. -- Your life is like a penny. You're going to lose it. The question is: How do you spend it? John Covici covici(a)ccs.covici.com

1 0

segfault in mod_conference
by covici＠ccs.covici.com 06 Dec '12

06 Dec '12

Hi. I have gotten a seg fault in mod_conference. I used the fscorepb script and so the data is at http://pastebin.freeswitch.org/20294 . I was also getting a strange error, not in the log, but in what I guess would be sysout as follows: 2012-12-06_02:05:40.71336 Error in my_thread_global_end(): 20 threads didn't exit I was getting lots of these with various numbers. My fs version is FreeSWITCH Version 1.3.4b+git~20121114T021840Z~8f0b7e69de (git 8f0b7e6 2012-11-14 02:18:40Z) Should I bother filing a jira, or just update to latest? -- Your life is like a penny. You're going to lose it. The question is: How do you spend it? John Covici covici(a)ccs.covici.com

3 4

RESOLVED: Re: NATIVE SQL ERROR [no such table: fifo_outbound]
by Scott 06 Dec '12

06 Dec '12

For closure (mark RESOLVED), no more 'native sql error.' Just did a *'make current'* and received the fresh-out-of-the-oven bump to 1.2.5.3 (from 1.2.5.2), where baseline original pull was the wiki perscribed, 'git clone -b v1.2.stable git://git.freeswitch.org/freeswitch.git' Again, my thanks to all! /Scott On 5 December 2012 20:29, Anthony Minessale <anthony.minessale(a)gmail.com>wrote: > I would say that's a safe bet... > On Dec 5, 2012 6:14 PM, "Scott" <8f27e956(a)gmail.com> wrote: > >> Acknowledged. >> >> That said, though, is the likely fix for this in the 1.2.5-n bump that >> was forecast in today's (WED) teleconference and, therefore, by waiting the >> 24 hours or so, I can should be able to draw down from the 1.2-stable >> branch? >> >> Thanks! >> >>

1 0

NATIVE SQL ERROR [no such table: fifo_outbound]
by Scott 06 Dec '12

06 Dec '12

Just did a 'make current' to update to 1.2.5.2 (from 1.2.5.1). Now, upon restarting freeswitch, it logs the following, ...skipping... SQL [Enabled] 2012-12-05 06:29:58.746676 [ERR] switch_core_sqldb.c:583 NATIVE SQL ERR [no such table: fifo_outbound] update fifo_outbound set start_time=0,stop_time=0,ring_count=0,use_count=0,outbound_call_count=0,outbound_fail_count=0 where static=0 2012-12-05 06:29:58.887575 [ERR] switch_core_sqldb.c:583 NATIVE SQL ERR [no such table: fifo_outbound] delete from fifo_outbound where static=1 and hostname='fs1.soho.local' In so far as I could see, the 'make' finished successfully without apparent errors. Thoughts & suggestions welcome. With thanks,

5 8

Single sndcard multiple extensions?
by Jakob 05 Dec '12

05 Dec '12

Hi, I'm trying to get right and left channels from a soundcard into separate alsa or portaudio endpoints. right channel into ext 3001 and left channel into ext 3002 Any hints? Regards Jakob

2 3

Call disconnect upon re-invite - FS tears down call
by Phil Quesinberry 05 Dec '12

05 Dec '12

I'm experiencing an odd issue here where a direct media call (bypass media mode) between two Aastra endpoints is disconnected when one of the endpoints sends a re-invite 2 minutes into the call, presumably to refresh the session - RTP ports & codecs remain unchanged. FreeSWITCH passes the invite on to the other endpoint which replies with its own invite, again keeping the same call parameters, ports/codecs etc. FS then sends a 491 - Request Pending to both endpoints, acknowledges the second endpoint's invite and tears down the call. This seems wrong. is this expected behavior? I went ahead and filed a Jira on this just in case - it includes all trace info: http://jira.freeswitch.org/browse/FS-4754 Phil Quesinberry Q Systems Engineering, Inc. Electronic Controls and Embedded Systems Development (410) 969-8002 http://www.qsystemsengineering.com

7 7

Explanation of rtp_audio_in / out fields in XML CDR's?
by Mick Stevens 05 Dec '12

05 Dec '12

Hi Folks, I'm trying to use FS XML CDR's to diagnose historic audio problems. I think I can work out some of the rtp_audio_in / out fields (raw bytes & media bytes being nearly equal looks like a good sign) but am wondering about the skip, cng & flush fields for example? I have tried Googling this & can find evidence of other people having asked this question but not of the answer. I have also checked my FS 106 & Cookbook book's without success. The wiki appears to be down at the moment so my apologies if the answer lies there. I know how to do this in real time using wireshark etc but am interested in being able to do some analysis on historic problems reported by customers that aren't willing/able to replicate the problem in order for a protocol trace to be captured. Any help much appreciated! Rgds, Mick Tel/SMS. +44(0)7967 594432 Fax. +44(0)7053 452429 Email/IM. mickstevens(a)yahoo.com Skype: mick_stevens www.facebook.com/mickstevens www.twitter.com/mickstevens

3 6

[Confidential] - uuid_broadcast
by Nuwan Wijerathne 05 Dec '12

05 Dec '12

Hi all, * I have added further comments to this issue (case FS-4884). Please close the case as it's resolved now (Please see comments on the JIRA). Thank you all for your help. Nuwan. This e-mail and any attachments are for the intended addressee(s) only and may contain confidential and/or privileged material. If you are not a named addressee, do not use, retain or disclose such information. This email is not guaranteed to be free from viruses and does not bind Unify in any contract or obligation. Unify Business Solutions Ltd. Registered in England and Wales. No: 4749638 Registered Office: Ambassador House, 5 Midland Way, Barlborough, S43 4XA United Kingdom.

1 0