This is the complete log of a call from eyeBeam to SIPp:
 
2008-04-17 14:53:45 [NOTICE] switch_channel.c:468 switch_channel_set_name() New Chan sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 [50f628dc-0c7d-11dd-b88d-eff5d417eec1]
2008-04-17 14:53:45 [DEBUG] switch_core_state_machine.c:405 switch_core_session_run() sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 Running State Change CS_NEW
2008-04-17 14:53:45 [DEBUG] switch_core_state_machine.c:409 switch_core_session_run() (sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060) State NEW
2008-04-17 14:53:45 [DEBUG] sofia.c:1427 sofia_handle_sip_i_state() Channel sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 entering state [received]
2008-04-17 14:53:46 [DEBUG] sofia.c:1431 sofia_handle_sip_i_state() Remote SDP:
v=0
o=- 8 2 IN IP4 192.168.0.29
s=CounterPath eyeBeam 1.5
c=IN IP4 192.168.0.29
t=0 0
m=audio 5062 RTP/AVP 0 8 101
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=x-rtp-session-id:8D251B27074A4A0B9AFD0C119E94B4A4
 
2008-04-17 14:53:46 [DEBUG] sofia_glue.c:1718 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMU:0:8000]/[PCMU:0:8000]
2008-04-17 14:53:46 [DEBUG] sofia_glue.c:1096 sofia_glue_tech_set_codec() Set Codec sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 PCMU/8000 20 ms 160 samples
2008-04-17 14:53:46 [DEBUG] sofia_glue.c:1682 sofia_glue_negotiate_sdp() Set 2833 dtmf payload to 101
2008-04-17 14:53:46 [DEBUG] sofia.c:1545 sofia_handle_sip_i_state() sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 State Change CS_NEW -> CS_INIT
2008-04-17 14:53:46 [DEBUG] switch_core_session.c:619 switch_core_session_signal_state_change() Kill sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 [BREAK]
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:405 switch_core_session_run() sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 Running State Change CS_INIT
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:435 switch_core_session_run() (sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060) Calling Handler for State INIT
2008-04-17 14:53:46 [DEBUG] mod_sofia.c:82 sofia_on_init() sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 SOFIA INIT
2008-04-17 14:53:46 [DEBUG] mod_sofia.c:108 sofia_on_init() sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 State Change CS_INIT -> CS_RING
2008-04-17 14:53:46 [DEBUG] switch_core_session.c:619 switch_core_session_signal_state_change() Kill sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 [BREAK]
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:405 switch_core_session_run() sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 Running State Change CS_RING
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:438 switch_core_session_run() (sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060) Calling Handler for State RING
2008-04-17 14:53:46 [DEBUG] mod_sofia.c:119 sofia_on_ring() sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 SOFIA RING
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:65 switch_core_standard_on_ring() Standard RING sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060
2008-04-17 14:53:46 [INFO] mod_dialplan_xml.c:218 dialplan_hunt() Processing Bob->alice!
2008-04-17 14:53:46 [DEBUG] mod_dialplan_xml.c:260 dialplan_hunt() -> Calling parse_exten() ...
2008-04-17 14:53:46 [DEBUG] mod_dialplan_xml.c:103 parse_exten() test conditions destination_number(alice) =~ /(.*)/
2008-04-17 14:53:46 [DEBUG] mod_dialplan_xml.c:274 dialplan_hunt() sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 State Change CS_RING -> CS_EXECUTE
2008-04-17 14:53:46 [DEBUG] switch_core_session.c:619 switch_core_session_signal_state_change() Kill sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 [BREAK]
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:405 switch_core_session_run() sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 Running State Change CS_EXECUTE
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:444 switch_core_session_run() (sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060) Calling Handler for State EXECUTE
2008-04-17 14:53:46 [DEBUG] mod_sofia.c:156 sofia_on_execute() sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 SOFIA EXECUTE
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:128 switch_core_standard_on_execute() Standard EXECUTE
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:142 switch_core_standard_on_execute() sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 Execute set(hangup_after_bridge=true)
2008-04-17 14:53:46 [DEBUG] mod_dptools.c:409 set_function() SET [hangup_after_bridge]=[true]
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:142 switch_core_standard_on_execute() sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 Execute set(bypass_media=true)
2008-04-17 14:53:46 [DEBUG] mod_dptools.c:409 set_function() SET [bypass_media]=[true]
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:142 switch_core_standard_on_execute() sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 Execute bridge(sofia/freeswitch.mocon.esk.fraunhofer.de/alice%freeswitch.mocon.esk.fraunhofer.de)
2008-04-17 14:53:46 [NOTICE] switch_channel.c:468 switch_channel_set_name() New Chan sofia/freeswitch.mocon.esk.fraunhofer.de/alice [50fee576-0c7d-11dd-b88d-eff5d417eec1]
2008-04-17 14:53:46 [DEBUG] mod_sofia.c:1710 sofia_outgoing_channel() sofia/freeswitch.mocon.esk.fraunhofer.de/alice State Change CS_NEW -> CS_INIT
2008-04-17 14:53:46 [DEBUG] switch_core_session.c:619 switch_core_session_signal_state_change() Kill sofia/freeswitch.mocon.esk.fraunhofer.de/alice [BREAK]
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:405 switch_core_session_run() sofia/freeswitch.mocon.esk.fraunhofer.de/alice Running State Change CS_INIT
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:435 switch_core_session_run() (sofia/freeswitch.mocon.esk.fraunhofer.de/alice) Calling Handler for State INIT
2008-04-17 14:53:46 [DEBUG] mod_sofia.c:82 sofia_on_init() sofia/freeswitch.mocon.esk.fraunhofer.de/alice SOFIA INIT
2008-04-17 14:53:46 [DEBUG] sofia.c:201 sofia_event_callback() event [nua_i_state] status [0][INVITE sent] session: sofia/freeswitch.mocon.esk.fraunhofer.de/alice
2008-04-17 14:53:46 [DEBUG] sofia.c:1427 sofia_handle_sip_i_state() Channel sofia/freeswitch.mocon.esk.fraunhofer.de/alice entering state [calling]
2008-04-17 14:53:46 [DEBUG] sofia.c:201 sofia_event_callback() event [nua_r_invite] status [180][Ringing] session: sofia/freeswitch.mocon.esk.fraunhofer.de/alice
2008-04-17 14:53:46 [DEBUG] sofia.c:201 sofia_event_callback() event [nua_i_state] status [180][Ringing] session: sofia/freeswitch.mocon.esk.fraunhofer.de/alice
2008-04-17 14:53:46 [DEBUG] sofia.c:1427 sofia_handle_sip_i_state() Channel sofia/freeswitch.mocon.esk.fraunhofer.de/alice entering state [proceeding]
2008-04-17 14:53:46 [NOTICE] sofia.c:1461 sofia_handle_sip_i_state() Ring-Ready sofia/freeswitch.mocon.esk.fraunhofer.de/alice!
2008-04-17 14:53:46 [NOTICE] mod_sofia.c:945 sofia_receive_message() Ring-Ready sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060!
2008-04-17 14:53:46 [DEBUG] switch_core_session.c:352 switch_core_session_receive_message() Kill sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 [BREAK]
2008-04-17 14:53:46 [DEBUG] sofia.c:1427 sofia_handle_sip_i_state() Channel sofia/freeswitch.mocon.esk.fraunhofer.de/alice entering state [ready]
2008-04-17 14:53:46 [DEBUG] sofia.c:1431 sofia_handle_sip_i_state() Remote SDP:
v=0
o=user1 53655765 2353687637 IN IP4 192.168.0.21
s=-
c=IN IP4 192.168.0.21
t=0 0
m=audio 6000 RTP/AVP 0
a=rtpmap:0 PCMU/8000
 
2008-04-17 14:53:46 [DEBUG] switch_channel.c:1440 switch_channel_perform_mark_answered() Kill sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 [BREAK]
2008-04-17 14:53:46 [NOTICE] sofia.c:1705 sofia_handle_sip_i_state() Channel [sofia/freeswitch.mocon.esk.fraunhofer.de/alice] has been answered
2008-04-17 14:53:46 [DEBUG] switch_core_session.c:352 switch_core_session_receive_message() Kill sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 [BREAK]
2008-04-17 14:53:46 [NOTICE] sofia.c:1712 sofia_handle_sip_i_state() Channel [sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060] has been answered
2008-04-17 14:53:46 [DEBUG] sofia.c:201 sofia_event_callback() event [nua_i_state] status [180][Ringing] session: sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060
2008-04-17 14:53:46 [DEBUG] sofia.c:1427 sofia_handle_sip_i_state() Channel sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 entering state [early]
2008-04-17 14:53:46 [DEBUG] sofia.c:1427 sofia_handle_sip_i_state() Channel sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 entering state [completed]
2008-04-17 14:53:46 [DEBUG] mod_sofia.c:108 sofia_on_init() sofia/freeswitch.mocon.esk.fraunhofer.de/alice State Change CS_INIT -> CS_RING
2008-04-17 14:53:46 [DEBUG] switch_core_session.c:619 switch_core_session_signal_state_change() Kill sofia/freeswitch.mocon.esk.fraunhofer.de/alice [BREAK]
2008-04-17 14:53:46 [DEBUG] switch_ivr_originate.c:1158 switch_ivr_originate() Originate Resulted in Success: [sofia/freeswitch.mocon.esk.fraunhofer.de/alice]
2008-04-17 14:53:46 [NOTICE] switch_ivr_bridge.c:467 switch_ivr_signal_bridge() Hangup sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 [CS_EXECUTE] [UNALLOCATED]
2008-04-17 14:53:46 [DEBUG] switch_channel.c:1293 switch_channel_perform_hangup() Kill sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 [KILL]
2008-04-17 14:53:46 [DEBUG] switch_core_session.c:619 switch_core_session_signal_state_change() Kill sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 [BREAK]
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:405 switch_core_session_run() sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 Running State Change CS_HANGUP
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:431 switch_core_session_run() (sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060) Calling Handler for State HANGUP
2008-04-17 14:53:46 [DEBUG] mod_sofia.c:258 sofia_on_hangup() Channel sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 hanging up, cause: UNALLOCATED
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:405 switch_core_session_run() sofia/freeswitch.mocon.esk.fraunhofer.de/alice Running State Change CS_RING
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:438 switch_core_session_run() (sofia/freeswitch.mocon.esk.fraunhofer.de/alice) Calling Handler for State RING
2008-04-17 14:53:46 [DEBUG] mod_sofia.c:119 sofia_on_ring() sofia/freeswitch.mocon.esk.fraunhofer.de/alice SOFIA RING
2008-04-17 14:53:46 [DEBUG] switch_ivr_originate.c:44 originate_on_ring() sofia/freeswitch.mocon.esk.fraunhofer.de/alice State Change CS_RING -> CS_HOLD
2008-04-17 14:53:46 [DEBUG] switch_core_session.c:619 switch_core_session_signal_state_change() Kill sofia/freeswitch.mocon.esk.fraunhofer.de/alice [BREAK]
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:405 switch_core_session_run() sofia/freeswitch.mocon.esk.fraunhofer.de/alice Running State Change CS_HOLD
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:456 switch_core_session_run() (sofia/freeswitch.mocon.esk.fraunhofer.de/alice) Calling Handler for State HOLD
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:227 switch_core_standard_on_hold() Standard HOLD
2008-04-17 14:53:46 [DEBUG] mod_sofia.c:282 sofia_on_hangup() Sending BYE to sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() Standard HANGUP sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060, cause: UNALLOCATED
2008-04-17 14:53:46 [DEBUG] switch_core_session.c:675 switch_core_session_thread() Session 1 (sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060) Locked, Waiting on external entities
2008-04-17 14:53:46 [NOTICE] switch_core_session.c:681 switch_core_session_thread() Session 1 (sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060) Ended
2008-04-17 14:53:46 [NOTICE] switch_core_session.c:683 switch_core_session_thread() Close Channel sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 [CS_HANGUP]

Alois Komenda
Fraunhofer-Einrichtung für Systeme der Kommunikationstechnik ESK



 


Von: freeswitch-users-bounces@lists.freeswitch.org [mailto:freeswitch-users-bounces@lists.freeswitch.org] Im Auftrag von Brian West
Gesendet: Donnerstag, 17. April 2008 17:08
An: freeswitch-users@lists.freeswitch.org
Betreff: Re: [Freeswitch-users] Hangup Cause: UNALLOCATED

Can you include the log prior to this?

/b

On Apr 17, 2008, at 9:46 AM, Alois Komenda wrote:
Hello,
 
I'm trying to place a call between two SIPp instances over FreeSWITCH. Before the connection is set up completeley FS sends a BYE to the caller. In the logs is see the hangup cause is "UNALLOCATED". That happens only if there is a pause after sending the ACK. 
 
Hangup sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 [CS_EXECUTE] [UNALLOCATED]
2008-04-17 14:53:46 [DEBUG] switch_channel.c:1293 switch_channel_perform_hangup() Kill sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 [KILL]
2008-04-17 14:53:46 [DEBUG] switch_core_session.c:619 switch_core_session_signal_state_change() Kill sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 [BREAK]
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:405 switch_core_session_run() sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 Running State Change CS_HANGUP
2008-04-17 14:53:46 [DEBUG] switch_core_state_machine.c:431 switch_core_session_run() (sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060) Calling Handler for State HANGUP
2008-04-17 14:53:46 [DEBUG] mod_sofia.c:258 sofia_on_hangup() Channel sofia/freeswitch.mocon.esk.fraunhofer.de/bob@freeswitch.mocon.esk.fraunhofer.de:5060 hanging up, cause: UNALLOCATED
 
If I the call is placed by a softphone the same behavoir occurs.
What does that mean? Is it a bug in FS?
 
Thanks in advance!
 

Alois Komenda
Fraunhofer-Einrichtung für Systeme der Kommunikationstechnik ESK

 
_______________________________________________
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