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.