On Sun, Feb 1, 2009 at 3:17 AM, Nandy Dagondon <gcd@i.ph> wrote:

hi,

i found a major one. this time i deliberately set the dialtone freq to US std on my PH definition. i expect FS wont dial at all. but to my surprise, the problem is gone!! i checked the log. it indicates successful detection of DIALTONE.

going on further. i noticed FXO wont hangup on busy tone.

one possibility is the volume settings. the default is -7. how many dBm is this? and what is the dB equivalent per increment?

tks n rgds,
nandy

On Sun, Feb 1, 2009 at 4:30 PM, Nandy Dagondon <gcd@i.ph> wrote:
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@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@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@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@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@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"/>
>> 
>> 
>> 
>> </settings>
>> 
>> <analog_spans>
>> <span id="1">
>> 
>> 
>> 
>> <param name="tonegroup" value="us"/>
>> <param name="digit-timeout" value="2000"/>
>> <param name="max-digits" value="15"/>
>> <param name="dialplan" value="XML"/>
>> <param name="context" value="default"/>
>> <param name="enable-callerid" value="false"/>
>> 
>> 
>> 
>> 
>> </span>
>> <span id="2">
>> 
>> 
>> 
>> <param name="tonegroup" value="us"/>
>> <param name="digit-timeout" value="2000"/>
>> <param name="max-digits" value="11"/>
>> <param name="dialplan" value="XML"/>
>> <param name="context" value="default"/>
>> <param name="enable-callerid" value=""false/>
>> 
>> 
>> 
>> 
>> </span>
>> </analog_spans>
>> </configuration>
>>
>> openzap.conf
>> ==========
>> [span zt]
>> name => OpenZAP FXS
>> number => 1
>> fxs-channel => 1
>>
>> [span zt]
>> name => OpenZAP FXO
>> number => 2
>> fxo-channel => 2
>>
>> tones.conf (the dialtone and ring tone is set to Philipping tones)
>> ========
>> [us]
>> generate-dial => v=-7;%(1000,0,425)
>> detect-dial => 425
>>
>> generate-ring => v=-7;%(1000,4000,425,480)
>> detect-ring => 425,480
>>
>> generate-busy => v=-7;%(500,500,480,620)
>> detect-busy => 480,620
>>
>> generate-attn => v=0;%(200,300,1400,1800)
>> detect-attn => 1400,1800
>>
>> generate-callwaiting-sas => v=0;%(300,10000,440)
>> detect-callwaiting-sas => 440
>>
>> generate-callwaiting-cas => v=0;%(80,0,2750,2130)
>> detect-callwaiting-cas => 2750,2130
>>
>> detect-fail1 => 913.8
>> detect-fail2 => 1370.6
>> detect-fail3 => 776.7
>>
>> LOG OF FIRST CALL (OK)
>> ====================
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:152
>> switch_core_standard_on_execute() OpenZAP/1:1/93400534 Execute
>> bridge(openzap/2/1/3400534)
>> 2009-01-25 10:35:58 [DEBUG] mod_openzap.c:340 tech_init() Set codec PCMU
>> 20ms
>> 2009-01-25 10:35:58 [DEBUG] mod_openzap.c:1017 channel_outgoing_channel()
>> Connect outbound channel OpenZAP/2:1/3400534
>> 2009-01-25 10:35:58 [NOTICE] switch_channel.c:565
>> switch_channel_set_name() New Channel OpenZAP/2:1/3400534
>> [e5f12114-ea88-11dd-9f5c-290fb4a527a4]
>> 2009-01-25 10:35:58 [DEBUG] mod_openzap.c:1026 channel_outgoing_channel()
>> (OpenZAP/2:1/3400534) State Change CS_NEW -> CS_INIT
>> 2009-01-25 10:35:58 [DEBUG] switch_core_session.c:807
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534
>> [BREAK]
>> 2009-01-25 10:35:58 [DEBUG] ozmod_analog.c:52 analog_fxo_outgoing_call()
>> Changing state on 2:1 from DOWN to DIALING
>> 2009-01-25 10:35:58 [DEBUG] ozmod_analog.c:239 zap_analog_channel_run()
>> ANALOG CHANNEL thread starting.
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:379
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change CS_INIT
>> 2009-01-25 10:35:58 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()
>> Executing state handler on 2:1 for DIALING
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:444
>> switch_core_session_run() (OpenZAP/2:1/3400534) State INIT
>> 2009-01-25 10:35:58 [DEBUG] mod_openzap.c:364 channel_on_init()
>> (OpenZAP/2:1/3400534) State Change CS_INIT -> CS_ROUTING
>> 2009-01-25 10:35:58 [DEBUG] switch_core_session.c:807
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534
>> [BREAK]
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:444
>> switch_core_session_run() (OpenZAP/2:1/3400534) State INIT going to sleep
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:379
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change
>> CS_ROUTING
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:447
>> switch_core_session_run() (OpenZAP/2:1/3400534) State ROUTING
>> 2009-01-25 10:35:58 [DEBUG] mod_openzap.c:387 channel_on_routing()
>> OpenZAP/2:1/3400534 CHANNEL ROUTING
>> 2009-01-25 10:35:58 [DEBUG] switch_ivr_originate.c:58
>> originate_on_routing() (OpenZAP/2:1/3400534) State Change CS_ROUTING ->
>> CS_CONSUME_MEDIA
>> 2009-01-25 10:35:58 [DEBUG] switch_core_session.c:807
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534
>> [BREAK]
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:447
>> switch_core_session_run() (OpenZAP/2:1/3400534) State ROUTING going to sleep
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:379
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change
>> CS_CONSUME_MEDIA
>> 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:466
>> switch_core_session_run() (OpenZAP/2:1/3400534) State CONSUME_MEDIA
>> 2009-01-25 10:35:59 [DEBUG] ozmod_analog.c:615 zap_analog_channel_run()
>> Detected tone DIAL on 2:1
>> 2009-01-25 10:35:59 [DEBUG] mod_openzap.c:1153 on_fxo_signal() got FXO sig
>> 2:1 [TONE_DETECTED]
>> 2009-01-25 10:35:59 [WARNING] mod_openzap.c:1196 on_fxo_signal() Unhandled
>> type for channel 2:1
>> 2009-01-25 10:35:59 [DEBUG] zap_io.c:1179 zchan_activate_dtmf_buffer()
>> Created DTMF Buffer!
>> 2009-01-25 10:35:59 [DEBUG] zap_io.c:1715 handle_dtmf() 2:1 GENERATE DTMF
>> [3400534]
>> 2009-01-25 10:36:03 [DEBUG] ozmod_analog.c:302 zap_analog_channel_run()
>> Changing state on 2:1 from DIALING to UP
>> 2009-01-25 10:36:03 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()
>> Executing state handler on 2:1 for UP
>> 2009-01-25 10:36:03 [DEBUG] mod_openzap.c:1153 on_fxo_signal() got FXO sig
>> 2:1 [UP]
>> 2009-01-25 10:36:03 [DEBUG] switch_channel.c:1710
>> switch_channel_perform_mark_answered() Send signal OpenZAP/1:1/93400534
>> [BREAK]
>> 2009-01-25 10:36:03 [NOTICE] mod_openzap.c:1180 on_fxo_signal() Channel
>> [OpenZAP/2:1/3400534] has been answered
>> 2009-01-25 10:36:03 [DEBUG] switch_channel.c:177
>> switch_channel_audio_sync() OpenZAP/2:1/3400534 receive message [AUDIO_SYNC]
>> 2009-01-25 10:36:03 [DEBUG] switch_channel.c:1768
>> switch_channel_perform_answer() OpenZAP/1:1/93400534 receive message
>> [ANSWER]
>> 2009-01-25 10:36:03 [DEBUG] mod_openzap.c:813
>> channel_receive_message_fxs() Changing state on 1:1 from IDLE to UP
>> 2009-01-25 10:36:03 [NOTICE] mod_openzap.c:814
>> channel_receive_message_fxs() Channel [OpenZAP/1:1/93400534] has been
>> answered
>> 2009-01-25 10:36:03 [DEBUG] switch_channel.c:177
>> switch_channel_audio_sync() OpenZAP/1:1/93400534 receive message
>> [AUDIO_SYNC]
>> 2009-01-25 10:36:03 [DEBUG] switch_core_session.c:511
>> switch_core_session_perform_receive_message() Send signal
>> OpenZAP/1:1/93400534 [BREAK]
>> 2009-01-25 10:36:03 [DEBUG] switch_ivr_originate.c:1627
>> switch_ivr_originate() Originate Resulted in Success: [OpenZAP/2:1/3400534]
>> 2009-01-25 10:36:03 [DEBUG] switch_channel.c:177
>> switch_channel_audio_sync() OpenZAP/2:1/3400534 receive message [AUDIO_SYNC]
>> 2009-01-25 10:36:03 [DEBUG] switch_channel.c:177
>> switch_channel_audio_sync() OpenZAP/1:1/93400534 receive message
>> [AUDIO_SYNC]
>> 2009-01-25 10:36:03 [DEBUG] switch_ivr_bridge.c:862
>> switch_ivr_multi_threaded_bridge() OpenZAP/2:1/3400534 receive message
>> [BRIDGE]
>> 2009-01-25 10:36:03 [DEBUG] switch_core_session.c:511
>> switch_core_session_perform_receive_message() Send signal
>> OpenZAP/2:1/3400534 [BREAK]
>> 2009-01-25 10:36:03 [DEBUG] switch_ivr_bridge.c:869
>> switch_ivr_multi_threaded_bridge() OpenZAP/1:1/93400534 receive message
>> [BRIDGE]
>> 2009-01-25 10:36:03 [DEBUG] switch_core_session.c:511
>> switch_core_session_perform_receive_message() Send signal
>> OpenZAP/1:1/93400534 [BREAK]
>> 2009-01-25 10:36:03 [DEBUG] switch_ivr_bridge.c:913
>> switch_ivr_multi_threaded_bridge() (OpenZAP/2:1/3400534) State Change
>> CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
>> 2009-01-25 10:36:03 [DEBUG] switch_core_session.c:807
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534
>> [BREAK]
>> 2009-01-25 10:36:03 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()
>> Executing state handler on 1:1 for UP
>> 2009-01-25 10:36:03 [DEBUG] mod_openzap.c:1212 on_fxs_signal() got FXS sig
>> [UP]
>> 2009-01-25 10:36:03 [DEBUG] switch_core_state_machine.c:466
>> switch_core_session_run() (OpenZAP/2:1/3400534) State CONSUME_MEDIA going to
>> sleep
>> 2009-01-25 10:36:03 [DEBUG] switch_core_state_machine.c:379
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change
>> CS_EXCHANGE_MEDIA
>> 2009-01-25 10:36:03 [DEBUG] switch_core_state_machine.c:457
>> switch_core_session_run() (OpenZAP/2:1/3400534) State EXCHANGE_MEDIA
>> 2009-01-25 10:36:03 [DEBUG] mod_openzap.c:511 channel_on_exchange_media()
>> CHANNEL EXCHANGE_MEDIA
>> 2009-01-25 10:36:16 [DEBUG] ozmod_analog.c:744 process_event() EVENT
>> [ONHOOK][1:1] STATE [UP]
>> 2009-01-25 10:36:16 [DEBUG] ozmod_analog.c:780 process_event() Changing
>> state on 1:1 from UP to DOWN
>> 2009-01-25 10:36:16 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()
>> Executing state handler on 1:1 for DOWN
>> 2009-01-25 10:36:16 [DEBUG] mod_openzap.c:1212 on_fxs_signal() got FXS sig
>> [STOP]
>> 2009-01-25 10:36:16 [NOTICE] mod_openzap.c:1300 on_fxs_signal() Hangup
>> OpenZAP/1:1/93400534 [CS_EXECUTE] [NORMAL_CLEARING]
>> 2009-01-25 10:36:16 [DEBUG] switch_channel.c:1494
>> switch_channel_perform_hangup() Send signal OpenZAP/1:1/93400534 [KILL]
>> 2009-01-25 10:36:16 [DEBUG] switch_core_session.c:807
>> switch_core_session_signal_state_change() Send signal OpenZAP/1:1/93400534
>> [BREAK]
>> 2009-01-25 10:36:16 [DEBUG] zap_io.c:1125 zap_channel_done() channel done
>> 1:1
>> 2009-01-25 10:36:16 [DEBUG] ozmod_analog.c:726 zap_analog_channel_run()
>> ANALOG CHANNEL 1:1 thread ended.
>> 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:360 audio_bridge_thread()
>> OpenZAP/1:1/93400534 ending bridge by request from read function
>> 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:435 audio_bridge_thread()
>> BRIDGE THREAD DONE [OpenZAP/1:1/93400534]
>> 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:439 audio_bridge_thread()
>> Send signal OpenZAP/2:1/3400534 [BREAK]
>> 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:354 audio_bridge_thread()
>> OpenZAP/1:1/93400534 ending bridge by request from write function
>> 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:409 audio_bridge_thread()
>> OpenZAP/2:1/3400534 receive message [UNBRIDGE]
>> 2009-01-25 10:36:16 [DEBUG] switch_core_session.c:511
>> switch_core_session_perform_receive_message() Send signal
>> OpenZAP/2:1/3400534 [BREAK]
>> 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:435 audio_bridge_thread()
>> BRIDGE THREAD DONE [OpenZAP/2:1/3400534]
>> 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:439 audio_bridge_thread()
>> Send signal OpenZAP/1:1/93400534 [BREAK]
>> 2009-01-25 10:36:16 [NOTICE] switch_ivr_bridge.c:470
>> audio_bridge_on_exchange_media() Hangup OpenZAP/2:1/3400534
>> [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
>> 2009-01-25 10:36:16 [DEBUG] switch_channel.c:1494
>> switch_channel_perform_hangup() Send signal OpenZAP/2:1/3400534 [KILL]
>> 2009-01-25 10:36:16 [DEBUG] switch_core_session.c:807
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534
>> [BREAK]
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:457
>> switch_core_session_run() (OpenZAP/2:1/3400534) State EXCHANGE_MEDIA going
>> to sleep
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:379
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change
>> CS_HANGUP
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:410
>> switch_core_session_run() (OpenZAP/2:1/3400534) State HANGUP
>> 2009-01-25 10:36:16 [DEBUG] mod_openzap.c:429 channel_on_hangup() Changing
>> state on 2:1 from UP to HANGUP
>> 2009-01-25 10:36:16 [DEBUG] mod_openzap.c:472 channel_on_hangup()
>> OpenZAP/2:1/3400534 CHANNEL HANGUP
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:46
>> switch_core_standard_on_hangup() OpenZAP/2:1/3400534 Standard HANGUP, cause:
>> NORMAL_CLEARING
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:410
>> switch_core_session_run() (OpenZAP/2:1/3400534) State HANGUP going to sleep
>> 2009-01-25 10:36:16 [DEBUG] switch_core_session.c:939
>> switch_core_session_thread() Session 2 (OpenZAP/2:1/3400534) Locked, Waiting
>> on external entities
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:454
>> switch_core_session_run() (OpenZAP/1:1/93400534) State EXECUTE going to
>> sleep
>> 2009-01-25 10:36:16 [NOTICE] switch_core_session.c:957
>> switch_core_session_thread() Session 2 (OpenZAP/2:1/3400534) Ended
>> 2009-01-25 10:36:16 [NOTICE] switch_core_session.c:959
>> switch_core_session_thread() Close Channel OpenZAP/2:1/3400534 [CS_HANGUP]
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:379
>> switch_core_session_run() (OpenZAP/1:1/93400534) Running State Change
>> CS_HANGUP
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:410
>> switch_core_session_run() (OpenZAP/1:1/93400534) State HANGUP
>> 2009-01-25 10:36:16 [DEBUG] mod_openzap.c:472 channel_on_hangup()
>> OpenZAP/1:1/93400534 CHANNEL HANGUP
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:46
>> switch_core_standard_on_hangup() OpenZAP/1:1/93400534 Standard HANGUP,
>> cause: NORMAL_CLEARING
>> 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:410
>> switch_core_session_run() (OpenZAP/1:1/93400534) State HANGUP going to sleep
>> 2009-01-25 10:36:16 [DEBUG] switch_core_session.c:939
>> switch_core_session_thread() Session 1 (OpenZAP/1:1/93400534) Locked,
>> Waiting on external entities
>> 2009-01-25 10:36:16 [NOTICE] switch_core_session.c:957
>> switch_core_session_thread() Session 1 (OpenZAP/1:1/93400534) Ended
>> 2009-01-25 10:36:16 [NOTICE] switch_core_session.c:959
>> switch_core_session_thread() Close Channel OpenZAP/1:1/93400534 [CS_HANGUP]
>> 2009-01-25 10:36:16 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()
>> Executing state handler on 2:1 for HANGUP
>> 2009-01-25 10:36:17 [DEBUG] ozmod_analog.c:351 zap_analog_channel_run()
>> Changing state on 2:1 from HANGUP to DOWN
>> 2009-01-25 10:36:17 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()
>> Executing state handler on 2:1 for DOWN
>> 2009-01-25 10:36:17 [DEBUG] mod_openzap.c:1153 on_fxo_signal() got FXO sig
>> 2:1 [STOP]
>> 2009-01-25 10:36:17 [DEBUG] zap_io.c:1125 zap_channel_done() channel done
>> 2:1
>> 2009-01-25 10:36:17 [DEBUG] ozmod_analog.c:726 zap_analog_channel_run()
>> ANALOG CHANNEL 2:1 thread ended.
>>
>> LOG OF FAILED CALLS
>> ==================
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:152
>> switch_core_standard_on_execute() OpenZAP/1:1/93400534 Execute
>> bridge(openzap/2/1/3400534)
>> 2009-01-25 10:36:55 [DEBUG] mod_openzap.c:340 tech_init() Set codec PCMU
>> 20ms
>> 2009-01-25 10:36:55 [DEBUG] mod_openzap.c:1017 channel_outgoing_channel()
>> Connect outbound channel OpenZAP/2:1/3400534
>> 2009-01-25 10:36:55 [NOTICE] switch_channel.c:565
>> switch_channel_set_name() New Channel OpenZAP/2:1/3400534
>> [079f5420-ea89-11dd-9f5c-290fb4a527a4]
>> 2009-01-25 10:36:55 [DEBUG] mod_openzap.c:1026 channel_outgoing_channel()
>> (OpenZAP/2:1/3400534) State Change CS_NEW -> CS_INIT
>> 2009-01-25 10:36:55 [DEBUG] switch_core_session.c:807
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534
>> [BREAK]
>> 2009-01-25 10:36:55 [DEBUG] ozmod_analog.c:52 analog_fxo_outgoing_call()
>> Changing state on 2:1 from DOWN to DIALING
>> 2009-01-25 10:36:55 [DEBUG] ozmod_analog.c:239 zap_analog_channel_run()
>> ANALOG CHANNEL thread starting.
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:379
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change CS_INIT
>> 2009-01-25 10:36:55 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()
>> Executing state handler on 2:1 for DIALING
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:444
>> switch_core_session_run() (OpenZAP/2:1/3400534) State INIT
>> 2009-01-25 10:36:55 [DEBUG] mod_openzap.c:364 channel_on_init()
>> (OpenZAP/2:1/3400534) State Change CS_INIT -> CS_ROUTING
>> 2009-01-25 10:36:55 [DEBUG] switch_core_session.c:807
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534
>> [BREAK]
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:444
>> switch_core_session_run() (OpenZAP/2:1/3400534) State INIT going to sleep
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:379
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change
>> CS_ROUTING
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:447
>> switch_core_session_run() (OpenZAP/2:1/3400534) State ROUTING
>> 2009-01-25 10:36:55 [DEBUG] mod_openzap.c:387 channel_on_routing()
>> OpenZAP/2:1/3400534 CHANNEL ROUTING
>> 2009-01-25 10:36:55 [DEBUG] switch_ivr_originate.c:58
>> originate_on_routing() (OpenZAP/2:1/3400534) State Change CS_ROUTING ->
>> CS_CONSUME_MEDIA
>> 2009-01-25 10:36:55 [DEBUG] switch_core_session.c:807
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534
>> [BREAK]
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:447
>> switch_core_session_run() (OpenZAP/2:1/3400534) State ROUTING going to sleep
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:379
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change
>> CS_CONSUME_MEDIA
>> 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:466
>> switch_core_session_run() (OpenZAP/2:1/3400534) State CONSUME_MEDIA
>> 2009-01-25 10:37:08 [DEBUG] ozmod_analog.c:744 process_event() EVENT
>> [ONHOOK][1:1] STATE [IDLE]
>> 2009-01-25 10:37:08 [DEBUG] ozmod_analog.c:780 process_event() Changing
>> state on 1:1 from IDLE to DOWN
>> 2009-01-25 10:37:08 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()
>> Executing state handler on 1:1 for DOWN
>> 2009-01-25 10:37:08 [DEBUG] mod_openzap.c:1212 on_fxs_signal() got FXS sig
>> [STOP]
>> 2009-01-25 10:37:08 [NOTICE] mod_openzap.c:1300 on_fxs_signal() Hangup
>> OpenZAP/1:1/93400534 [CS_EXECUTE] [NORMAL_CLEARING]
>> 2009-01-25 10:37:08 [DEBUG] switch_channel.c:1494
>> switch_channel_perform_hangup() Send signal OpenZAP/1:1/93400534 [KILL]
>> 2009-01-25 10:37:08 [DEBUG] switch_core_session.c:807
>> switch_core_session_signal_state_change() Send signal OpenZAP/1:1/93400534
>> [BREAK]
>> 2009-01-25 10:37:08 [DEBUG] zap_io.c:1125 zap_channel_done() channel done
>> 1:1
>> 2009-01-25 10:37:08 [DEBUG] ozmod_analog.c:726 zap_analog_channel_run()
>> ANALOG CHANNEL 1:1 thread ended.
>> 2009-01-25 10:37:08 [NOTICE] switch_ivr_originate.c:1566
>> switch_ivr_originate() Hangup OpenZAP/2:1/3400534 [CS_CONSUME_MEDIA]
>> [ORIGINATOR_CANCEL]
>> 2009-01-25 10:37:08 [DEBUG] switch_channel.c:1494
>> switch_channel_perform_hangup() Send signal OpenZAP/2:1/3400534 [KILL]
>> 2009-01-25 10:37:08 [DEBUG] switch_core_session.c:807
>> switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534
>> [BREAK]
>> 2009-01-25 10:37:08 [DEBUG] switch_ivr_originate.c:1691
>> switch_ivr_originate() Originate Cancelled by originator termination Cause:
>> 487 [ORIGINATOR_CANCEL]
>> 2009-01-25 10:37:08 [INFO] mod_dptools.c:1909 audio_bridge_function()
>> Originate Failed. Cause: ORIGINATOR_CANCEL
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:454
>> switch_core_session_run() (OpenZAP/1:1/93400534) State EXECUTE going to
>> sleep
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:379
>> switch_core_session_run() (OpenZAP/1:1/93400534) Running State Change
>> CS_HANGUP
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:410
>> switch_core_session_run() (OpenZAP/1:1/93400534) State HANGUP
>> 2009-01-25 10:37:08 [DEBUG] mod_openzap.c:472 channel_on_hangup()
>> OpenZAP/1:1/93400534 CHANNEL HANGUP
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:46
>> switch_core_standard_on_hangup() OpenZAP/1:1/93400534 Standard HANGUP,
>> cause: NORMAL_CLEARING
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:410
>> switch_core_session_run() (OpenZAP/1:1/93400534) State HANGUP going to sleep
>> 2009-01-25 10:37:08 [DEBUG] switch_core_session.c:939
>> switch_core_session_thread() Session 3 (OpenZAP/1:1/93400534) Locked,
>> Waiting on external entities
>> 2009-01-25 10:37:08 [NOTICE] switch_core_session.c:957
>> switch_core_session_thread() Session 3 (OpenZAP/1:1/93400534) Ended
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:466
>> switch_core_session_run() (OpenZAP/2:1/3400534) State CONSUME_MEDIA going to
>> sleep
>> 2009-01-25 10:37:08 [NOTICE] switch_core_session.c:959
>> switch_core_session_thread() Close Channel OpenZAP/1:1/93400534 [CS_HANGUP]
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:379
>> switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change
>> CS_HANGUP
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:410
>> switch_core_session_run() (OpenZAP/2:1/3400534) State HANGUP
>> 2009-01-25 10:37:08 [DEBUG] mod_openzap.c:429 channel_on_hangup() Changing
>> state on 2:1 from DIALING to HANGUP
>> 2009-01-25 10:37:08 [DEBUG] mod_openzap.c:472 channel_on_hangup()
>> OpenZAP/2:1/3400534 CHANNEL HANGUP
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:46
>> switch_core_standard_on_hangup() OpenZAP/2:1/3400534 Standard HANGUP, cause:
>> ORIGINATOR_CANCEL
>> 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:410
>> switch_core_session_run() (OpenZAP/2:1/3400534) State HANGUP going to sleep
>> 2009-01-25 10:37:08 [DEBUG] switch_core_session.c:939
>> switch_core_session_thread() Session 4 (OpenZAP/2:1/3400534) Locked, Waiting
>> on external entities
>> 2009-01-25 10:37:08 [NOTICE] switch_core_session.c:957
>> switch_core_session_thread() Session 4 (OpenZAP/2:1/3400534) Ended
>> 2009-01-25 10:37:08 [NOTICE] switch_core_session.c:959
>> switch_core_session_thread() Close Channel OpenZAP/2:1/3400534 [CS_HANGUP]
>> 2009-01-25 10:37:08 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()
>> Executing state handler on 2:1 for HANGUP
>> 2009-01-25 10:37:09 [DEBUG] ozmod_analog.c:351 zap_analog_channel_run()
>> Changing state on 2:1 from HANGUP to DOWN
>> 2009-01-25 10:37:09 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run()
>> Executing state handler on 2:1 for DOWN
>> 2009-01-25 10:37:09 [DEBUG] mod_openzap.c:1153 on_fxo_signal() got FXO sig
>> 2:1 [STOP]
>> 2009-01-25 10:37:09 [DEBUG] zap_io.c:1125 zap_channel_done() channel done
>> 2:1
>> 2009-01-25 10:37:09 [DEBUG] ozmod_analog.c:726 zap_analog_channel_run()
>> ANALOG CHANNEL 2:1 thread ended.
>>
>>
>>
>>
>>
>>
>> _______________________________________________
>> Freeswitch-users mailing list
>> Freeswitch-users@lists.freeswitch.org
>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
>> http://www.freeswitch.org
>>
>>
>> _______________________________________________
>> 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

_______________________________________________
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

--
Anthony Minessale II

FreeSWITCH http://www.freeswitch.org/
ClueCon http://www.cluecon.com/

AIM: anthm
MSN:anthony_minessale@hotmail.com
GTALK/JABBER/PAYPAL:anthony.minessale@gmail.com
IRC: irc.freenode.net #freeswitch

FreeSWITCH Developer Conference
sip:888@conference.freeswitch.org
iax:guest@conference.freeswitch.org/888
googletalk:conf+888@conference.freeswitch.org
pstn:213-799-1400

_______________________________________________
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