[Freeswitch-users] TDM400 FXO can dialout only once
Nandy Dagondon
gcd at i.ph
Sun Feb 1 00:30:39 PST 2009
hi everybody,
i created [ph] tone definition per raul's suggestion and changed
/etc/zaptel.conf entries to:
tonezone=ph
defaultzone=ph
but it didn't solve the problem.
i captured the console log during start-up and shutdown. i noticed openzap
related errors during shutdown. here's the snippet of the log:
STARTUP
---------------
2009-02-01 15:58:10 [NOTICE] zap_io.c:2517 zap_global_init() Modules
configured: 1
2009-02-01 15:58:10 [INFO] zap_io.c:2341 zap_load_module() Loading IO from
/opt/freeswitch/mod/ozmod_zt.so
2009-02-01 15:58:10 [INFO] zap_io.c:2127 load_config() auto-loaded 'zt'
2009-02-01 15:58:10 [INFO] ozmod_zt.c:186 zt_open_range() configuring device
/dev/zap/channel channel 1 as OpenZAP device 1:1 fd:39
2009-02-01 15:58:10 [INFO] ozmod_zt.c:186 zt_open_range() configuring device
/dev/zap/channel channel 2 as OpenZAP device 2:1 fd:40
2009-02-01 15:58:10 [INFO] zap_io.c:2265 load_config() Configured 2
channel(s)
2009-02-01 15:58:10 [INFO] zap_io.c:2358 zap_load_module() Loading SIG from
/opt/freeswitch/mod/ozmod_analog.so
2009-02-01 15:58:10 [INFO] zap_io.c:2474 zap_configure_span() auto-loaded
'analog'
2009-02-01 15:58:10 [CONSOLE] switch_loadable_module.c:857
switch_loadable_module_load_file() Successfully Loaded [mod_openzap]
--- DIDN'T MAKE ANY CALL ---
SHUTDOWN
------------------
2009-02-01 15:59:07 [NOTICE] switch_loadable_module.c:536
switch_loadable_module_unprocess() Deleting API Function 'oz'
2009-02-01 15:59:07 [CONSOLE] switch_loadable_module.c:1231 do_shutdown()
Stopping: mod_openzap
2009-02-01 15:59:07 [INFO] zap_io.c:256 zap_channel_destroy() Closing
channel zt:1:1 fd:39
2009-02-01 15:59:07 [INFO] zap_io.c:256 zap_channel_destroy() Closing
channel zt:2:1 fd:40
2009-02-01 15:59:08 [ERR] ozmod_analog.c:899 zap_analog_run() Failure
Polling event! [no matching descriptor]
2009-02-01 15:59:08 [ERR] ozmod_analog.c:899 zap_analog_run() Failure
Polling event! [no matching descriptor]
2009-02-01 15:59:08 [INFO] zap_io.c:2456 zap_unload_modules() Unloading
/opt/freeswitch/mod/ozmod_analog.so
2009-02-01 15:59:08 [INFO] zap_io.c:2441 zap_unload_modules() Unloading IO
zt
2009-02-01 15:59:08 [INFO] zap_io.c:2456 zap_unload_modules() Unloading
/opt/freeswitch/mod/ozmod_zt.so
i also notice the same ERR flag during shutdown after making test calls.
any suggestion what to do next?
tks for your assistance.
rgds,
-nandy
On Tue, Jan 27, 2009 at 8:39 AM, Nandy Dagondon <gcd at i.ph> wrote:
> 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/20090201/c925d46d/attachment-0001.html
More information about the Freeswitch-users
mailing list