Re: [Freeswitch-users] TDM400 FXO can dialout only once

26 Jan 2009


      You may want to define the conf/tones.conf file with the proper tone
definitions for your country, then specify that tone group (tonegroup=XX
where XX is the 2 letters code for your country). For example, this is
the definition for my country (Brazil) and I also have tonegroup=br in
my openzap configuration file:

[br]
generate-busy => v=-7;%(250,250,425)
generate-dial => v=-7;%(1000,0,425)
generate-ring => v=-7;%(1000,4000,425)
generate-callwaiting-sas => v=0;%(300,10000,425)
detect-busy => 425
detect-dial => 425
detect-ring => 425 
detect-callwaiting-sas => 425

Regards,

Raul Fragoso

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