that's great. yes, i'm in the philippines. there's a difference in dialtone - it's 425 Hz.<br> -nandy<br>
<br><br><div class="gmail_quote">On Mon, Jan 26, 2009 at 6:01 PM, Michael Collins <span dir="ltr"><<a href="mailto:msc@freeswitch.org">msc@freeswitch.org</a>></span> wrote:<br><blockquote class="gmail_quote" style="border-left: 1px solid rgb(204, 204, 204); margin: 0pt 0pt 0pt 0.8ex; padding-left: 1ex;">
I have a TDM400 clone and I will see if I can reproduce these<br>
symptoms. BTW, are you in the Philippines? Is there any difference in<br>
the dial tone there than in the US?<br>
<font color="#888888">-MC<br>
</font><div><div></div><div class="Wj3C7c"><br>
On Sun, Jan 25, 2009 at 11:05 PM, Nandy Dagondon <<a href="mailto:gcd@i.ph">gcd@i.ph</a>> wrote:<br>
> i monitored the line using another phone. there's indeed dialtone in all<br>
> attempts.<br>
> i see TONE_DETECTED in the first call but i wonder there's a WARNING message<br>
> immediately following [WARNING] mod_openzap.c:1196 on_fxo_signal() Unhandled<br>
> type for channel 2:1.<br>
> the dialtone freq should be okay since it's detected in the first call.could<br>
> the WARNING message gives us a hint of a possible problem other than the<br>
> dialtone freq?<br>
><br>
> okay, i'll try the SVN version next.<br>
><br>
><br>
> On Mon, Jan 26, 2009 at 10:18 AM, Anthony Minessale<br>
> <<a href="mailto:anthony.minessale@gmail.com">anthony.minessale@gmail.com</a>> wrote:<br>
>><br>
>> Its not detecting a dial tone on the failure case.<br>
>> Before dialing it waits until it picks up dialtone.<br>
>> Try the svn trunk version to see if it works any better or verify there is<br>
>> a dialtone on the line.<br>
>><br>
>> On Jan 25, 2009 6:19 PM, "Nandy Dagondon" <<a href="mailto:gcd@i.ph">gcd@i.ph</a>> wrote:<br>
>><br>
>> hi everybody,<br>
>><br>
>> i installed FS 1.0.2 to an Atom mobo (Intel D945GCLF2). it's working using<br>
>> IP phones, softphones and digium FXS port. but there's a problem in dialing<br>
>> out to PSTN using digium tdm400 fxo - it works fine on the first attempt<br>
>> (after starting FS) but it fails on the subsequent attempts. i tested to<br>
>> call using the FXS port and IP phone. same problem.<br>
>><br>
>> before i place any call, i checked >oz dump 2 1 (show current state =<br>
>> DOWN, last state = DOWN)<br>
>><br>
>> in the first call, there's this message:<br>
>> [WARNING] mod_openzap.c:1196 on_fxo_signal() Unhandled type for channel<br>
>> 2:1<br>
>> but<br>
>><br>
>> then i hangup. checked >oz dump 21 (show current state=DOWN, last<br>
>> state=HANGUP)<br>
>><br>
>> in the 2nd (and subsequent) attempts, the fxo just goes off-hook but<br>
>> doesn't send the dtmf tones.<br>
>> >oz dump 2 1 (shows current state = DIALING, last state = DOWN)<br>
>><br>
>> has anyone encountered this problem before? i appreciate for any help to<br>
>> correct this problem.<br>
>><br>
>> tks,<br>
>> nandy<br>
>><br>
>><br>
>> Environment:<br>
>> ==================<br>
>> kernel 2.6.18-92.1.22.el5<br>
>> FS 1.0.2<br>
>> zaptel 1.4.11<br>
>> oslec<br>
>> digium TDM400P Rev. I (1-FXS, 2-FXO,3-4 vacant)<br>
>><br>
>> zaptel.conf<br>
>> ========<br>
>> loadzone = us<br>
>> defaultzone=us<br>
>> channels=1-2<br>
>> alaw=1-4<br>
>> fxsks=2<br>
>> fxoks=1<br>
>><br>
>><br>
>> openzap.conf.xml:<br>
>> ===============<br>
>> <configuration name="openzap.conf" description="OpenZAP Configuration"><br>
>> <settings><br>
>> <param name="debug" value="9"/><br>
>> <!-- <param name="hold-music" value="$${moh_uri}"/> --><br>
>> <!-- <param name="enable-analog-option" value="call-swap"/> --><br>
>> <!-- <param name="enable-analog-option" value="3-way"/> --><br>
>> </settings><br>
>> <!-- one entry here per openzap span --><br>
>> <analog_spans><br>
>> <span id="1"><br>
>> <!--<param name="hold-music" value="$${moh_uri}"/>--><br>
>> <!--<param name="enable-analog-option" value="call-swap"/>--><br>
>> <!--<param name="enable-analog-option" value="3-way"/>--><br>
>> <param name="tonegroup" value="us"/><br>
>> <param name="digit-timeout" value="2000"/><br>
>> <param name="max-digits" value="15"/><br>
>> <param name="dialplan" value="XML"/><br>
>> <param name="context" value="default"/><br>
>> <param name="enable-callerid" value="false"/><br>
>> <!-- regex to stop dialing when it matches --><br>
>> <!--<param name="dial-regex" value="5555"/>--><br>
>> <!-- regex to stop dialing when it does not match --><br>
>> <!--<param name="fail-dial-regex" value="^5"/>--><br>
>> </span><br>
>> <span id="2"><br>
>> <!--<param name="hold-music" value="$${moh_uri}"/>--><br>
>> <!--<param name="enable-analog-option" value="call-swap"/>--><br>
>> <!--<param name="enable-analog-option" value="3-way"/>--><br>
>> <param name="tonegroup" value="us"/><br>
>> <param name="digit-timeout" value="2000"/><br>
>> <param name="max-digits" value="11"/><br>
>> <param name="dialplan" value="XML"/><br>
>> <param name="context" value="default"/><br>
>> <param name="enable-callerid" value=""false/><br>
>> <!-- regex to stop dialing when it matches --><br>
>> <!--<param name="dial-regex" value="5555"/>--><br>
>> <!-- regex to stop dialing when it does not match --><br>
>> <!--<param name="fail-dial-regex" value="^5"/>--><br>
>> </span><br>
>> </analog_spans><br>
>> </configuration><br>
>><br>
>> openzap.conf<br>
>> ==========<br>
>> [span zt]<br>
>> name => OpenZAP FXS<br>
>> number => 1<br>
>> fxs-channel => 1<br>
>><br>
>> [span zt]<br>
>> name => OpenZAP FXO<br>
>> number => 2<br>
>> fxo-channel => 2<br>
>><br>
>> tones.conf (the dialtone and ring tone is set to Philipping tones)<br>
>> ========<br>
>> [us]<br>
>> generate-dial => v=-7;%(1000,0,425)<br>
>> detect-dial => 425<br>
>><br>
>> generate-ring => v=-7;%(1000,4000,425,480)<br>
>> detect-ring => 425,480<br>
>><br>
>> generate-busy => v=-7;%(500,500,480,620)<br>
>> detect-busy => 480,620<br>
>><br>
>> generate-attn => v=0;%(200,300,1400,1800)<br>
>> detect-attn => 1400,1800<br>
>><br>
>> generate-callwaiting-sas => v=0;%(300,10000,440)<br>
>> detect-callwaiting-sas => 440<br>
>><br>
>> generate-callwaiting-cas => v=0;%(80,0,2750,2130)<br>
>> detect-callwaiting-cas => 2750,2130<br>
>><br>
>> detect-fail1 => 913.8<br>
>> detect-fail2 => 1370.6<br>
>> detect-fail3 => 776.7<br>
>><br>
>> LOG OF FIRST CALL (OK)<br>
>> ====================<br>
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:152<br>
>> switch_core_standard_on_execute() OpenZAP/1:1/93400534 Execute<br>
>> bridge(openzap/2/1/3400534)<br>
>> 2009-01-25 10:35:58 [DEBUG] mod_openzap.c:340 tech_init() Set codec PCMU<br>
>> 20ms<br>
>> 2009-01-25 10:35:58 [DEBUG] mod_openzap.c:1017 channel_outgoing_channel()<br>
>> Connect outbound channel OpenZAP/2:1/3400534<br>
>> 2009-01-25 10:35:58 [NOTICE] switch_channel.c:565<br>
>> switch_channel_set_name() New Channel OpenZAP/2:1/3400534<br>
>> [e5f12114-ea88-11dd-9f5c-290fb4a527a4]<br>
>> 2009-01-25 10:35:58 [DEBUG] mod_openzap.c:1026 channel_outgoing_channel()<br>
>> (OpenZAP/2:1/3400534) State Change CS_NEW -> CS_INIT<br>
>> 2009-01-25 10:35:58 [DEBUG] switch_core_session.c:807<br>
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534<br>
>> [BREAK]<br>
>> 2009-01-25 10:35:58 [DEBUG] ozmod_analog.c:52 analog_fxo_outgoing_call()<br>
>> Changing state on 2:1 from DOWN to DIALING<br>
>> 2009-01-25 10:35:58 [DEBUG] ozmod_analog.c:239 zap_analog_channel_run()<br>
>> ANALOG CHANNEL thread starting.<br>
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:379<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change CS_INIT<br>
>> 2009-01-25 10:35:58 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()<br>
>> Executing state handler on 2:1 for DIALING<br>
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:444<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State INIT<br>
>> 2009-01-25 10:35:58 [DEBUG] mod_openzap.c:364 channel_on_init()<br>
>> (OpenZAP/2:1/3400534) State Change CS_INIT -> CS_ROUTING<br>
>> 2009-01-25 10:35:58 [DEBUG] switch_core_session.c:807<br>
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534<br>
>> [BREAK]<br>
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:444<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State INIT going to sleep<br>
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:379<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change<br>
>> CS_ROUTING<br>
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:447<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State ROUTING<br>
>> 2009-01-25 10:35:58 [DEBUG] mod_openzap.c:387 channel_on_routing()<br>
>> OpenZAP/2:1/3400534 CHANNEL ROUTING<br>
>> 2009-01-25 10:35:58 [DEBUG] switch_ivr_originate.c:58<br>
>> originate_on_routing() (OpenZAP/2:1/3400534) State Change CS_ROUTING -><br>
>> CS_CONSUME_MEDIA<br>
>> 2009-01-25 10:35:58 [DEBUG] switch_core_session.c:807<br>
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534<br>
>> [BREAK]<br>
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:447<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State ROUTING going to sleep<br>
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:379<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change<br>
>> CS_CONSUME_MEDIA<br>
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:466<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State CONSUME_MEDIA<br>
>> 2009-01-25 10:35:59 [DEBUG] ozmod_analog.c:615 zap_analog_channel_run()<br>
>> Detected tone DIAL on 2:1<br>
>> 2009-01-25 10:35:59 [DEBUG] mod_openzap.c:1153 on_fxo_signal() got FXO sig<br>
>> 2:1 [TONE_DETECTED]<br>
>> 2009-01-25 10:35:59 [WARNING] mod_openzap.c:1196 on_fxo_signal() Unhandled<br>
>> type for channel 2:1<br>
>> 2009-01-25 10:35:59 [DEBUG] zap_io.c:1179 zchan_activate_dtmf_buffer()<br>
>> Created DTMF Buffer!<br>
>> 2009-01-25 10:35:59 [DEBUG] zap_io.c:1715 handle_dtmf() 2:1 GENERATE DTMF<br>
>> [3400534]<br>
>> 2009-01-25 10:36:03 [DEBUG] ozmod_analog.c:302 zap_analog_channel_run()<br>
>> Changing state on 2:1 from DIALING to UP<br>
>> 2009-01-25 10:36:03 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()<br>
>> Executing state handler on 2:1 for UP<br>
>> 2009-01-25 10:36:03 [DEBUG] mod_openzap.c:1153 on_fxo_signal() got FXO sig<br>
>> 2:1 [UP]<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_channel.c:1710<br>
>> switch_channel_perform_mark_answered() Send signal OpenZAP/1:1/93400534<br>
>> [BREAK]<br>
>> 2009-01-25 10:36:03 [NOTICE] mod_openzap.c:1180 on_fxo_signal() Channel<br>
>> [OpenZAP/2:1/3400534] has been answered<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_channel.c:177<br>
>> switch_channel_audio_sync() OpenZAP/2:1/3400534 receive message [AUDIO_SYNC]<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_channel.c:1768<br>
>> switch_channel_perform_answer() OpenZAP/1:1/93400534 receive message<br>
>> [ANSWER]<br>
>> 2009-01-25 10:36:03 [DEBUG] mod_openzap.c:813<br>
>> channel_receive_message_fxs() Changing state on 1:1 from IDLE to UP<br>
>> 2009-01-25 10:36:03 [NOTICE] mod_openzap.c:814<br>
>> channel_receive_message_fxs() Channel [OpenZAP/1:1/93400534] has been<br>
>> answered<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_channel.c:177<br>
>> switch_channel_audio_sync() OpenZAP/1:1/93400534 receive message<br>
>> [AUDIO_SYNC]<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_core_session.c:511<br>
>> switch_core_session_perform_receive_message() Send signal<br>
>> OpenZAP/1:1/93400534 [BREAK]<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_ivr_originate.c:1627<br>
>> switch_ivr_originate() Originate Resulted in Success: [OpenZAP/2:1/3400534]<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_channel.c:177<br>
>> switch_channel_audio_sync() OpenZAP/2:1/3400534 receive message [AUDIO_SYNC]<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_channel.c:177<br>
>> switch_channel_audio_sync() OpenZAP/1:1/93400534 receive message<br>
>> [AUDIO_SYNC]<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_ivr_bridge.c:862<br>
>> switch_ivr_multi_threaded_bridge() OpenZAP/2:1/3400534 receive message<br>
>> [BRIDGE]<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_core_session.c:511<br>
>> switch_core_session_perform_receive_message() Send signal<br>
>> OpenZAP/2:1/3400534 [BREAK]<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_ivr_bridge.c:869<br>
>> switch_ivr_multi_threaded_bridge() OpenZAP/1:1/93400534 receive message<br>
>> [BRIDGE]<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_core_session.c:511<br>
>> switch_core_session_perform_receive_message() Send signal<br>
>> OpenZAP/1:1/93400534 [BREAK]<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_ivr_bridge.c:913<br>
>> switch_ivr_multi_threaded_bridge() (OpenZAP/2:1/3400534) State Change<br>
>> CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_core_session.c:807<br>
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534<br>
>> [BREAK]<br>
>> 2009-01-25 10:36:03 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()<br>
>> Executing state handler on 1:1 for UP<br>
>> 2009-01-25 10:36:03 [DEBUG] mod_openzap.c:1212 on_fxs_signal() got FXS sig<br>
>> [UP]<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_core_state_machine.c:466<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State CONSUME_MEDIA going to<br>
>> sleep<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_core_state_machine.c:379<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change<br>
>> CS_EXCHANGE_MEDIA<br>
>> 2009-01-25 10:36:03 [DEBUG] switch_core_state_machine.c:457<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State EXCHANGE_MEDIA<br>
>> 2009-01-25 10:36:03 [DEBUG] mod_openzap.c:511 channel_on_exchange_media()<br>
>> CHANNEL EXCHANGE_MEDIA<br>
>> 2009-01-25 10:36:16 [DEBUG] ozmod_analog.c:744 process_event() EVENT<br>
>> [ONHOOK][1:1] STATE [UP]<br>
>> 2009-01-25 10:36:16 [DEBUG] ozmod_analog.c:780 process_event() Changing<br>
>> state on 1:1 from UP to DOWN<br>
>> 2009-01-25 10:36:16 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()<br>
>> Executing state handler on 1:1 for DOWN<br>
>> 2009-01-25 10:36:16 [DEBUG] mod_openzap.c:1212 on_fxs_signal() got FXS sig<br>
>> [STOP]<br>
>> 2009-01-25 10:36:16 [NOTICE] mod_openzap.c:1300 on_fxs_signal() Hangup<br>
>> OpenZAP/1:1/93400534 [CS_EXECUTE] [NORMAL_CLEARING]<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_channel.c:1494<br>
>> switch_channel_perform_hangup() Send signal OpenZAP/1:1/93400534 [KILL]<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_core_session.c:807<br>
>> switch_core_session_signal_state_change() Send signal OpenZAP/1:1/93400534<br>
>> [BREAK]<br>
>> 2009-01-25 10:36:16 [DEBUG] zap_io.c:1125 zap_channel_done() channel done<br>
>> 1:1<br>
>> 2009-01-25 10:36:16 [DEBUG] ozmod_analog.c:726 zap_analog_channel_run()<br>
>> ANALOG CHANNEL 1:1 thread ended.<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:360 audio_bridge_thread()<br>
>> OpenZAP/1:1/93400534 ending bridge by request from read function<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:435 audio_bridge_thread()<br>
>> BRIDGE THREAD DONE [OpenZAP/1:1/93400534]<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:439 audio_bridge_thread()<br>
>> Send signal OpenZAP/2:1/3400534 [BREAK]<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:354 audio_bridge_thread()<br>
>> OpenZAP/1:1/93400534 ending bridge by request from write function<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:409 audio_bridge_thread()<br>
>> OpenZAP/2:1/3400534 receive message [UNBRIDGE]<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_core_session.c:511<br>
>> switch_core_session_perform_receive_message() Send signal<br>
>> OpenZAP/2:1/3400534 [BREAK]<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:435 audio_bridge_thread()<br>
>> BRIDGE THREAD DONE [OpenZAP/2:1/3400534]<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:439 audio_bridge_thread()<br>
>> Send signal OpenZAP/1:1/93400534 [BREAK]<br>
>> 2009-01-25 10:36:16 [NOTICE] switch_ivr_bridge.c:470<br>
>> audio_bridge_on_exchange_media() Hangup OpenZAP/2:1/3400534<br>
>> [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_channel.c:1494<br>
>> switch_channel_perform_hangup() Send signal OpenZAP/2:1/3400534 [KILL]<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_core_session.c:807<br>
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534<br>
>> [BREAK]<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:457<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State EXCHANGE_MEDIA going<br>
>> to sleep<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:379<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change<br>
>> CS_HANGUP<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:410<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State HANGUP<br>
>> 2009-01-25 10:36:16 [DEBUG] mod_openzap.c:429 channel_on_hangup() Changing<br>
>> state on 2:1 from UP to HANGUP<br>
>> 2009-01-25 10:36:16 [DEBUG] mod_openzap.c:472 channel_on_hangup()<br>
>> OpenZAP/2:1/3400534 CHANNEL HANGUP<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:46<br>
>> switch_core_standard_on_hangup() OpenZAP/2:1/3400534 Standard HANGUP, cause:<br>
>> NORMAL_CLEARING<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:410<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State HANGUP going to sleep<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_core_session.c:939<br>
>> switch_core_session_thread() Session 2 (OpenZAP/2:1/3400534) Locked, Waiting<br>
>> on external entities<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:454<br>
>> switch_core_session_run() (OpenZAP/1:1/93400534) State EXECUTE going to<br>
>> sleep<br>
>> 2009-01-25 10:36:16 [NOTICE] switch_core_session.c:957<br>
>> switch_core_session_thread() Session 2 (OpenZAP/2:1/3400534) Ended<br>
>> 2009-01-25 10:36:16 [NOTICE] switch_core_session.c:959<br>
>> switch_core_session_thread() Close Channel OpenZAP/2:1/3400534 [CS_HANGUP]<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:379<br>
>> switch_core_session_run() (OpenZAP/1:1/93400534) Running State Change<br>
>> CS_HANGUP<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:410<br>
>> switch_core_session_run() (OpenZAP/1:1/93400534) State HANGUP<br>
>> 2009-01-25 10:36:16 [DEBUG] mod_openzap.c:472 channel_on_hangup()<br>
>> OpenZAP/1:1/93400534 CHANNEL HANGUP<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:46<br>
>> switch_core_standard_on_hangup() OpenZAP/1:1/93400534 Standard HANGUP,<br>
>> cause: NORMAL_CLEARING<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:410<br>
>> switch_core_session_run() (OpenZAP/1:1/93400534) State HANGUP going to sleep<br>
>> 2009-01-25 10:36:16 [DEBUG] switch_core_session.c:939<br>
>> switch_core_session_thread() Session 1 (OpenZAP/1:1/93400534) Locked,<br>
>> Waiting on external entities<br>
>> 2009-01-25 10:36:16 [NOTICE] switch_core_session.c:957<br>
>> switch_core_session_thread() Session 1 (OpenZAP/1:1/93400534) Ended<br>
>> 2009-01-25 10:36:16 [NOTICE] switch_core_session.c:959<br>
>> switch_core_session_thread() Close Channel OpenZAP/1:1/93400534 [CS_HANGUP]<br>
>> 2009-01-25 10:36:16 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()<br>
>> Executing state handler on 2:1 for HANGUP<br>
>> 2009-01-25 10:36:17 [DEBUG] ozmod_analog.c:351 zap_analog_channel_run()<br>
>> Changing state on 2:1 from HANGUP to DOWN<br>
>> 2009-01-25 10:36:17 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()<br>
>> Executing state handler on 2:1 for DOWN<br>
>> 2009-01-25 10:36:17 [DEBUG] mod_openzap.c:1153 on_fxo_signal() got FXO sig<br>
>> 2:1 [STOP]<br>
>> 2009-01-25 10:36:17 [DEBUG] zap_io.c:1125 zap_channel_done() channel done<br>
>> 2:1<br>
>> 2009-01-25 10:36:17 [DEBUG] ozmod_analog.c:726 zap_analog_channel_run()<br>
>> ANALOG CHANNEL 2:1 thread ended.<br>
>><br>
>> LOG OF FAILED CALLS<br>
>> ==================<br>
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:152<br>
>> switch_core_standard_on_execute() OpenZAP/1:1/93400534 Execute<br>
>> bridge(openzap/2/1/3400534)<br>
>> 2009-01-25 10:36:55 [DEBUG] mod_openzap.c:340 tech_init() Set codec PCMU<br>
>> 20ms<br>
>> 2009-01-25 10:36:55 [DEBUG] mod_openzap.c:1017 channel_outgoing_channel()<br>
>> Connect outbound channel OpenZAP/2:1/3400534<br>
>> 2009-01-25 10:36:55 [NOTICE] switch_channel.c:565<br>
>> switch_channel_set_name() New Channel OpenZAP/2:1/3400534<br>
>> [079f5420-ea89-11dd-9f5c-290fb4a527a4]<br>
>> 2009-01-25 10:36:55 [DEBUG] mod_openzap.c:1026 channel_outgoing_channel()<br>
>> (OpenZAP/2:1/3400534) State Change CS_NEW -> CS_INIT<br>
>> 2009-01-25 10:36:55 [DEBUG] switch_core_session.c:807<br>
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534<br>
>> [BREAK]<br>
>> 2009-01-25 10:36:55 [DEBUG] ozmod_analog.c:52 analog_fxo_outgoing_call()<br>
>> Changing state on 2:1 from DOWN to DIALING<br>
>> 2009-01-25 10:36:55 [DEBUG] ozmod_analog.c:239 zap_analog_channel_run()<br>
>> ANALOG CHANNEL thread starting.<br>
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:379<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change CS_INIT<br>
>> 2009-01-25 10:36:55 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()<br>
>> Executing state handler on 2:1 for DIALING<br>
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:444<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State INIT<br>
>> 2009-01-25 10:36:55 [DEBUG] mod_openzap.c:364 channel_on_init()<br>
>> (OpenZAP/2:1/3400534) State Change CS_INIT -> CS_ROUTING<br>
>> 2009-01-25 10:36:55 [DEBUG] switch_core_session.c:807<br>
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534<br>
>> [BREAK]<br>
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:444<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State INIT going to sleep<br>
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:379<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change<br>
>> CS_ROUTING<br>
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:447<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State ROUTING<br>
>> 2009-01-25 10:36:55 [DEBUG] mod_openzap.c:387 channel_on_routing()<br>
>> OpenZAP/2:1/3400534 CHANNEL ROUTING<br>
>> 2009-01-25 10:36:55 [DEBUG] switch_ivr_originate.c:58<br>
>> originate_on_routing() (OpenZAP/2:1/3400534) State Change CS_ROUTING -><br>
>> CS_CONSUME_MEDIA<br>
>> 2009-01-25 10:36:55 [DEBUG] switch_core_session.c:807<br>
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534<br>
>> [BREAK]<br>
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:447<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State ROUTING going to sleep<br>
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:379<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change<br>
>> CS_CONSUME_MEDIA<br>
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:466<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State CONSUME_MEDIA<br>
>> 2009-01-25 10:37:08 [DEBUG] ozmod_analog.c:744 process_event() EVENT<br>
>> [ONHOOK][1:1] STATE [IDLE]<br>
>> 2009-01-25 10:37:08 [DEBUG] ozmod_analog.c:780 process_event() Changing<br>
>> state on 1:1 from IDLE to DOWN<br>
>> 2009-01-25 10:37:08 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()<br>
>> Executing state handler on 1:1 for DOWN<br>
>> 2009-01-25 10:37:08 [DEBUG] mod_openzap.c:1212 on_fxs_signal() got FXS sig<br>
>> [STOP]<br>
>> 2009-01-25 10:37:08 [NOTICE] mod_openzap.c:1300 on_fxs_signal() Hangup<br>
>> OpenZAP/1:1/93400534 [CS_EXECUTE] [NORMAL_CLEARING]<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_channel.c:1494<br>
>> switch_channel_perform_hangup() Send signal OpenZAP/1:1/93400534 [KILL]<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_core_session.c:807<br>
>> switch_core_session_signal_state_change() Send signal OpenZAP/1:1/93400534<br>
>> [BREAK]<br>
>> 2009-01-25 10:37:08 [DEBUG] zap_io.c:1125 zap_channel_done() channel done<br>
>> 1:1<br>
>> 2009-01-25 10:37:08 [DEBUG] ozmod_analog.c:726 zap_analog_channel_run()<br>
>> ANALOG CHANNEL 1:1 thread ended.<br>
>> 2009-01-25 10:37:08 [NOTICE] switch_ivr_originate.c:1566<br>
>> switch_ivr_originate() Hangup OpenZAP/2:1/3400534 [CS_CONSUME_MEDIA]<br>
>> [ORIGINATOR_CANCEL]<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_channel.c:1494<br>
>> switch_channel_perform_hangup() Send signal OpenZAP/2:1/3400534 [KILL]<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_core_session.c:807<br>
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534<br>
>> [BREAK]<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_ivr_originate.c:1691<br>
>> switch_ivr_originate() Originate Cancelled by originator termination Cause:<br>
>> 487 [ORIGINATOR_CANCEL]<br>
>> 2009-01-25 10:37:08 [INFO] mod_dptools.c:1909 audio_bridge_function()<br>
>> Originate Failed. Cause: ORIGINATOR_CANCEL<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:454<br>
>> switch_core_session_run() (OpenZAP/1:1/93400534) State EXECUTE going to<br>
>> sleep<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:379<br>
>> switch_core_session_run() (OpenZAP/1:1/93400534) Running State Change<br>
>> CS_HANGUP<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:410<br>
>> switch_core_session_run() (OpenZAP/1:1/93400534) State HANGUP<br>
>> 2009-01-25 10:37:08 [DEBUG] mod_openzap.c:472 channel_on_hangup()<br>
>> OpenZAP/1:1/93400534 CHANNEL HANGUP<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:46<br>
>> switch_core_standard_on_hangup() OpenZAP/1:1/93400534 Standard HANGUP,<br>
>> cause: NORMAL_CLEARING<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:410<br>
>> switch_core_session_run() (OpenZAP/1:1/93400534) State HANGUP going to sleep<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_core_session.c:939<br>
>> switch_core_session_thread() Session 3 (OpenZAP/1:1/93400534) Locked,<br>
>> Waiting on external entities<br>
>> 2009-01-25 10:37:08 [NOTICE] switch_core_session.c:957<br>
>> switch_core_session_thread() Session 3 (OpenZAP/1:1/93400534) Ended<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:466<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State CONSUME_MEDIA going to<br>
>> sleep<br>
>> 2009-01-25 10:37:08 [NOTICE] switch_core_session.c:959<br>
>> switch_core_session_thread() Close Channel OpenZAP/1:1/93400534 [CS_HANGUP]<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:379<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change<br>
>> CS_HANGUP<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:410<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State HANGUP<br>
>> 2009-01-25 10:37:08 [DEBUG] mod_openzap.c:429 channel_on_hangup() Changing<br>
>> state on 2:1 from DIALING to HANGUP<br>
>> 2009-01-25 10:37:08 [DEBUG] mod_openzap.c:472 channel_on_hangup()<br>
>> OpenZAP/2:1/3400534 CHANNEL HANGUP<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:46<br>
>> switch_core_standard_on_hangup() OpenZAP/2:1/3400534 Standard HANGUP, cause:<br>
>> ORIGINATOR_CANCEL<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:410<br>
>> switch_core_session_run() (OpenZAP/2:1/3400534) State HANGUP going to sleep<br>
>> 2009-01-25 10:37:08 [DEBUG] switch_core_session.c:939<br>
>> switch_core_session_thread() Session 4 (OpenZAP/2:1/3400534) Locked, Waiting<br>
>> on external entities<br>
>> 2009-01-25 10:37:08 [NOTICE] switch_core_session.c:957<br>
>> switch_core_session_thread() Session 4 (OpenZAP/2:1/3400534) Ended<br>
>> 2009-01-25 10:37:08 [NOTICE] switch_core_session.c:959<br>
>> switch_core_session_thread() Close Channel OpenZAP/2:1/3400534 [CS_HANGUP]<br>
>> 2009-01-25 10:37:08 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()<br>
>> Executing state handler on 2:1 for HANGUP<br>
>> 2009-01-25 10:37:09 [DEBUG] ozmod_analog.c:351 zap_analog_channel_run()<br>
>> Changing state on 2:1 from HANGUP to DOWN<br>
>> 2009-01-25 10:37:09 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()<br>
>> Executing state handler on 2:1 for DOWN<br>
>> 2009-01-25 10:37:09 [DEBUG] mod_openzap.c:1153 on_fxo_signal() got FXO sig<br>
>> 2:1 [STOP]<br>
>> 2009-01-25 10:37:09 [DEBUG] zap_io.c:1125 zap_channel_done() channel done<br>
>> 2:1<br>
>> 2009-01-25 10:37:09 [DEBUG] ozmod_analog.c:726 zap_analog_channel_run()<br>
>> ANALOG CHANNEL 2:1 thread ended.<br>
>><br>
>><br>
>><br>
>><br>
>><br>
>><br>
>> _______________________________________________<br>
>> Freeswitch-users mailing list<br>
>> <a href="mailto:Freeswitch-users@lists.freeswitch.org">Freeswitch-users@lists.freeswitch.org</a><br>
>> <a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/listinfo/freeswitch-users</a><br>
>> UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/options/freeswitch-users</a><br>
>> <a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><br>
>><br>
>><br>
>> _______________________________________________<br>
>> Freeswitch-users mailing list<br>
>> <a href="mailto:Freeswitch-users@lists.freeswitch.org">Freeswitch-users@lists.freeswitch.org</a><br>
>> <a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/listinfo/freeswitch-users</a><br>
>> UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/options/freeswitch-users</a><br>
>> <a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><br>
>><br>
><br>
><br>
> _______________________________________________<br>
> Freeswitch-users mailing list<br>
> <a href="mailto:Freeswitch-users@lists.freeswitch.org">Freeswitch-users@lists.freeswitch.org</a><br>
> <a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/listinfo/freeswitch-users</a><br>
> UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/options/freeswitch-users</a><br>
> <a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><br>
><br>
><br>
<br>
_______________________________________________<br>
Freeswitch-users mailing list<br>
<a href="mailto:Freeswitch-users@lists.freeswitch.org">Freeswitch-users@lists.freeswitch.org</a><br>
<a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/listinfo/freeswitch-users</a><br>
UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/options/freeswitch-users</a><br>
<a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><br>
</div></div></blockquote></div><br>