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