[Freeswitch-users] TDM400 FXO can dialout only once

Sun Jan 25 23:05:36 PST 2009

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 at 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 at 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 at 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 at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20090126/e7a66331/attachment-0002.html 