[Freeswitch-users] TDM400 FXO can dialout only once
Nandy Dagondon
gcd at i.ph
Mon Jan 26 16:39:21 PST 2009
i tested the SVN trunk version. still the same behaviour.
-nandy
On Tue, Jan 27, 2009 at 12:33 AM, Anthony Minessale <
anthony.minessale at gmail.com> wrote:
> The unhanded type message just means that mod_openzap does not do anything
> with the TONE_DETECTED event that was passed
> up from the ozmod_analog.
>
> On Mon, Jan 26, 2009 at 7:32 AM, Nandy Dagondon <gcd at i.ph> wrote:
>
>> that's great. yes, i'm in the philippines. there's a difference in
>> dialtone - it's 425 Hz.
>> -nandy
>>
>>
>>
>> On Mon, Jan 26, 2009 at 6:01 PM, Michael Collins <msc at freeswitch.org>wrote:
>>
>>> I have a TDM400 clone and I will see if I can reproduce these
>>> symptoms. BTW, are you in the Philippines? Is there any difference in
>>> the dial tone there than in the US?
>>> -MC
>>>
>>> On Sun, Jan 25, 2009 at 11:05 PM, Nandy Dagondon <gcd at 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 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
>>> >>
>>> >
>>> >
>>> > _______________________________________________
>>> > 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
>>>
>>
>>
>> _______________________________________________
>> 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
>>
>>
>
>
> --
> Anthony Minessale II
>
> FreeSWITCH http://www.freeswitch.org/
> ClueCon http://www.cluecon.com/
>
> AIM: anthm
> MSN:anthony_minessale at hotmail.com <MSN%3Aanthony_minessale at hotmail.com>
> GTALK/JABBER/PAYPAL:anthony.minessale at gmail.com<PAYPAL%3Aanthony.minessale at gmail.com>
> IRC: irc.freenode.net #freeswitch
>
> FreeSWITCH Developer Conference
> sip:888 at conference.freeswitch.org <sip%3A888 at conference.freeswitch.org>
> iax:guest at conference.freeswitch.org/888
> googletalk:conf+888 at conference.freeswitch.org<googletalk%3Aconf%2B888 at conference.freeswitch.org>
> pstn:213-799-1400
>
> _______________________________________________
> 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/20090127/f56d4f92/attachment-0002.html
More information about the FreeSWITCH-users
mailing list