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"/>
    
    
    
</settings>

<analog_spans>
    <span id="1">
      
      
      
      <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"/>
      
      
      
      
    </span>
    <span id="2">
      
      
      
      <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/>
      
      
      
      
    </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