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

Wed Oct 29 01:08:23 PDT 2008

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

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.
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.9 (MingW32)
Comment: Using GnuPG with Mozilla - http://enigmail.mozdev.org

iEYEARECAAYFAkkIGfcACgkQ4tZeNddg3dybkwCgsbmA9R7ZOaA8L5a2e7eQVeCD
+oQAnA0zoCaClcEhfzz2EbllEW3E5wi5
=oAY/
-----END PGP SIGNATURE-----