[Freeswitch-users] Problem with mod_openzap.c and SS7boost

Fri Oct 31 00:02:35 PDT 2008

-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

Hello,

just to put get some attention on this ...

regards
Helmut

Helmut Kuper schrieb:
> Hi Anthony,
> 
> ok, here the console output. There were no purple lines
> 
> 2008-10-29 09:04:57 [DEBUG] switch_core_state_machine.c:144
> switch_core_standard_on_execute()
> sofia/softswitch_side/031152112850 at sip1.ewetel.net Execute
> bridge(OpenZAP/1/a/44180002850)
> 2008-10-29 09:04:57 [DEBUG] ozmod_ss7_boost.c:225
> ss7_boost_channel_request() TX EVENT: CALL_START:(80) [w1g1] CSid=2
> Seq=1 Cn=[hk] Cd=[44180002850] Ci=[31152112850]
> 2008-10-29 09:04:58 [DEBUG] sofia.c:2269 sofia_handle_sip_i_state()
> Channel sofia/softswitch_side/031152112850 at sip1.ewetel.net entering
> state [terminated]
> 2008-10-29 09:04:58 [NOTICE] sofia.c:2765 sofia_handle_sip_i_state()
> Hangup sofia/softswitch_side/031152112850 at sip1.ewetel.net [CS_EXECUTE]
> [ORIGINATOR_CANCEL]
> 2008-10-29 09:04:58 [DEBUG] switch_channel.c:1449
> switch_channel_perform_hangup() Send signal
> sofia/softswitch_side/031152112850 at sip1.ewetel.net [KILL]
> 2008-10-29 09:04:58 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Send signal
> sofia/softswitch_side/031152112850 at sip1.ewetel.net [BREAK]
> 2008-10-29 09:04:58 [DEBUG] ozmod_ss7_boost.c:933 zap_ss7_boost_run() RX
> EVENT (N): CALL_START_ACK:(81) [w1g30] Rc=0 CSid=2 Seq=3
> 2008-10-29 09:04:58 [DEBUG] ss7_boost_client.c:300
> __ss7bc_connection_read() Rx sync ok
> 2008-10-29 09:04:58 [DEBUG] ozmod_ss7_boost.c:297
> handle_call_start_ack() Assign chan 1:29 (1:30) CSid=2
> 2008-10-29 09:04:58 [CRIT] ozmod_ss7_boost.c:245
> ss7_boost_channel_request() setting init state to progress_media
> 2008-10-29 09:04:58 [DEBUG] mod_openzap.c:340 tech_init() Set codec PCMA
> 20ms
> 2008-10-29 09:04:58 [DEBUG] mod_openzap.c:1022
> channel_outgoing_channel() Connect outbound channel OpenZAP/1:29/44180002850
> 2008-10-29 09:04:58 [NOTICE] switch_channel.c:553
> switch_channel_set_name() New Channel OpenZAP/1:29/44180002850
> [a231e1ef-2cfc-4de2-ab73-2175c184dff5]
> 2008-10-29 09:04:58 [DEBUG] mod_openzap.c:1031
> channel_outgoing_channel() (OpenZAP/1:29/44180002850) State Change
> CS_NEW -> CS_INIT
> 2008-10-29 09:04:58 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Send signal
> OpenZAP/1:29/44180002850 [BREAK]
> 2008-10-29 09:04:58 [DEBUG] switch_ivr_originate.c:1598
> switch_ivr_originate() Originate Cancelled by originator termination
> Cause: 487 [ORIGINATOR_CANCEL]
> 2008-10-29 09:04:58 [NOTICE] switch_ivr_originate.c:1637
> switch_ivr_originate() Hangup OpenZAP/1:29/44180002850 [CS_INIT]
> [ORIGINATOR_CANCEL]
> 2008-10-29 09:04:58 [DEBUG] switch_channel.c:1449
> switch_channel_perform_hangup() Send signal OpenZAP/1:29/44180002850 [KILL]
> 2008-10-29 09:04:58 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Send signal
> OpenZAP/1:29/44180002850 [BREAK]
> 2008-10-29 09:04:58 [INFO] mod_dptools.c:1843 audio_bridge_function()
> Originate Failed.  Cause: ORIGINATOR_CANCEL
> 2008-10-29 09:04:58 [DEBUG] switch_core_state_machine.c:432
> switch_core_session_run()
> (sofia/softswitch_side/031152112850 at sip1.ewetel.net) State EXECUTE going
> to sleep
> 2008-10-29 09:04:58 [DEBUG] switch_core_state_machine.c:367
> switch_core_session_run()
> (sofia/softswitch_side/031152112850 at sip1.ewetel.net) Running State
> Change CS_HANGUP
> 2008-10-29 09:04:58 [DEBUG] switch_core_state_machine.c:395
> switch_core_session_run()
> (sofia/softswitch_side/031152112850 at sip1.ewetel.net) State HANGUP
> 2008-10-29 09:04:58 [DEBUG] mod_sofia.c:276 sofia_on_hangup() Channel
> sofia/softswitch_side/031152112850 at sip1.ewetel.net hanging up, cause:
> ORIGINATOR_CANCEL
> 2008-10-29 09:04:58 [DEBUG] switch_core_state_machine.c:46
> switch_core_standard_on_hangup()
> sofia/softswitch_side/031152112850 at sip1.ewetel.net Standard HANGUP,
> cause: ORIGINATOR_CANCEL
> 2008-10-29 09:04:58 [DEBUG] switch_core_state_machine.c:395
> switch_core_session_run()
> (sofia/softswitch_side/031152112850 at sip1.ewetel.net) State HANGUP going
> to sleep
> 2008-10-29 09:04:58 [DEBUG] switch_core_session.c:860
> switch_core_session_thread() Session 3
> (sofia/softswitch_side/031152112850 at sip1.ewetel.net) Locked, Waiting on
> external entities
> 2008-10-29 09:04:58 [NOTICE] switch_core_session.c:878
> switch_core_session_thread() Session 3
> (sofia/softswitch_side/031152112850 at sip1.ewetel.net) Ended
> 2008-10-29 09:04:58 [NOTICE] switch_core_session.c:880
> switch_core_session_thread() Close Channel
> sofia/softswitch_side/031152112850 at sip1.ewetel.net [CS_HANGUP]
> 2008-10-29 09:04:58 [DEBUG] switch_core_state_machine.c:367
> switch_core_session_run() (OpenZAP/1:29/44180002850) Running State
> Change CS_HANGUP
> 2008-10-29 09:04:58 [DEBUG] switch_core_state_machine.c:395
> switch_core_session_run() (OpenZAP/1:29/44180002850) State HANGUP
> 2008-10-29 09:04:58 [DEBUG] mod_openzap.c:476 channel_on_hangup()
> OpenZAP/1:29/44180002850 CHANNEL HANGUP
> 2008-10-29 09:04:58 [DEBUG] switch_core_state_machine.c:46
> switch_core_standard_on_hangup() OpenZAP/1:29/44180002850 Standard
> HANGUP, cause: ORIGINATOR_CANCEL
> 2008-10-29 09:04:58 [DEBUG] switch_core_state_machine.c:395
> switch_core_session_run() (OpenZAP/1:29/44180002850) State HANGUP going
> to sleep
> 2008-10-29 09:04:58 [DEBUG] switch_core_session.c:860
> switch_core_session_thread() Session 4 (OpenZAP/1:29/44180002850)
> Locked, Waiting on external entities
> 2008-10-29 09:04:58 [NOTICE] switch_core_session.c:878
> switch_core_session_thread() Session 4 (OpenZAP/1:29/44180002850) Ended
> 2008-10-29 09:04:58 [NOTICE] switch_core_session.c:880
> switch_core_session_thread() Close Channel OpenZAP/1:29/44180002850
> [CS_HANGUP]
> 2008-10-29 09:04:58 [DEBUG] switch_core_codec.c:76
> switch_core_session_set_read_codec() resetting to uninitilized codec,
> setting to NULL
> 2008-10-29 09:05:07 [DEBUG] ozmod_ss7_boost.c:933 zap_ss7_boost_run() RX
> EVENT (N): CALL_STOPPED:(85) [w1g31] Rc=16 CSid=1 Seq=4
> 2008-10-29 09:05:07 [DEBUG] ss7_boost_client.c:300
> __ss7bc_connection_read() Rx sync ok
> 2008-10-29 09:05:07 [DEBUG] ozmod_ss7_boost.c:409 handle_call_stop()
> Changing state on 1:30 from DOWN to TERMINATING
> 2008-10-29 09:05:07 [DEBUG] ozmod_ss7_boost.c:645 state_advance() 1:30
> STATE [TERMINATING]
> 2008-10-29 09:05:07 [DEBUG] mod_openzap.c:1439 on_clear_channel_signal()
> got clear channel sig [STOP]
> 2008-10-29 09:05:07 [DEBUG] ozmod_ss7_boost.c:783 state_advance()
> Changing state on 1:30 from TERMINATING to HANGUP_COMPLETE
> 2008-10-29 09:05:07 [DEBUG] ss7_boost_client.c:216 ss7bc_exec_command()
> TX EVENT (N): CALL_STOPPED_ACK:(86) [w1g31] Rc=0 CSid=0 Seq=2
> 2008-10-29 09:05:07 [DEBUG] ozmod_ss7_boost.c:645 state_advance() 1:30
> STATE [HANGUP_COMPLETE]
> 2008-10-29 09:05:07 [DEBUG] ozmod_ss7_boost.c:737 state_advance()
> Changing state on 1:30 from HANGUP_COMPLETE to DOWN
> 2008-10-29 09:05:07 [DEBUG] ozmod_ss7_boost.c:645 state_advance() 1:30
> STATE [DOWN]
> 2008-10-29 09:05:07 [DEBUG] zap_io.c:1124 zap_channel_done() channel
> done 1:30
> 2008-10-29 09:05:12 [DEBUG] ozmod_ss7_boost.c:933 zap_ss7_boost_run() RX
> EVENT (N): CALL_ANSWERED:(84) [w1g30] Rc=0 CSid=2 Seq=5
> 2008-10-29 09:05:12 [DEBUG] ss7_boost_client.c:300
> __ss7bc_connection_read() Rx sync ok
> 2008-10-29 09:05:36 [DEBUG] ozmod_ss7_boost.c:933 zap_ss7_boost_run() RX
> EVENT (N): CALL_STOPPED:(85) [w1g30] Rc=16 CSid=2 Seq=6
> 2008-10-29 09:05:36 [DEBUG] ss7_boost_client.c:300
> __ss7bc_connection_read() Rx sync ok
> 2008-10-29 09:05:36 [DEBUG] ozmod_ss7_boost.c:409 handle_call_stop()
> Changing state on 1:29 from DOWN to TERMINATING
> 2008-10-29 09:05:36 [DEBUG] ozmod_ss7_boost.c:645 state_advance() 1:29
> STATE [TERMINATING]
> 2008-10-29 09:05:36 [DEBUG] mod_openzap.c:1439 on_clear_channel_signal()
> got clear channel sig [STOP]
> 2008-10-29 09:05:36 [DEBUG] ozmod_ss7_boost.c:783 state_advance()
> Changing state on 1:29 from TERMINATING to HANGUP_COMPLETE
> 2008-10-29 09:05:36 [DEBUG] ss7_boost_client.c:216 ss7bc_exec_command()
> TX EVENT (N): CALL_STOPPED_ACK:(86) [w1g30] Rc=0 CSid=0 Seq=3
> 2008-10-29 09:05:36 [DEBUG] ozmod_ss7_boost.c:645 state_advance() 1:29
> STATE [HANGUP_COMPLETE]
> 2008-10-29 09:05:36 [DEBUG] ozmod_ss7_boost.c:737 state_advance()
> Changing state on 1:29 from HANGUP_COMPLETE to DOWN
> 2008-10-29 09:05:36 [DEBUG] ozmod_ss7_boost.c:645 state_advance() 1:29
> STATE [DOWN]
> 2008-10-29 09:05:36 [DEBUG] zap_io.c:1124 zap_channel_done() channel
> done 1:29
> 
> 
> Anthony Minessale schrieb:
>> can you add more debug to the following places (with your patch disabled)
> 
>> ozmod_ss7_boost.c:245
>> zap_log(ZAP_LOG_CRIT, "setting init state to progress_media\n");
> 
>> zap_io.c:999
>> zap_log(ZAP_LOG_CRIT, "init_state=%d\n", zchan->init_state);
> 
> 
>> mod_openzap.c:368
>> zap_log(ZAP_LOG_CRIT, "calling zap_channel_init\n");
> 
> 
>> also look for any purple veto lines during the call.

_______________________________________________
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

-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.9 (MingW32)
Comment: Using GnuPG with Mozilla - http://enigmail.mozdev.org

iEYEARECAAYFAkkKrYsACgkQ4tZeNddg3dwvLwCdG43Mip83jsdE/NDL5Ujn2jcQ
1BUAniUk54jKjjoelXl9JHxOEH4sT/0g
=tzIY
-----END PGP SIGNATURE-----