[Freeswitch-users] calls appear to be dropping ... from landlines

Thu May 21 20:09:22 PDT 2009

Thanks Brian!  To answer your questions:
Freeswitch svn revision: 12148
Centos rev: 2.6.18-92.el5

And apologies, actually I guess we're using g711 not 729.

Jason:  I agree it would seem to be on the switch/telco side.  And, the
telco says many other people are in the same set-up as us and don't have any
issues, so they're insisting it's on our end.

On Thu, May 21, 2009 at 7:28 PM, Brian West <brian at freeswitch.org> wrote:

>
> On May 21, 2009, at 9:15 PM, Dale Trub wrote:
>
> We're running FreeSwitch as part of a teleconferencing service, inside a
> telcom (so no
> internet latency/NAT issues) and using g.729
>
>
> So you're using g729 with conferences?
>
> We are receiving some complaints of dropped calls,
> including from landlines.   This means they join the conference, and x
> minutes in they simply drop.
>
> I know that cellphones tend to drop calls frequently, but landlines
> are pretty reliable, and we're hearing it a lot.  From the FreeSwitch side
> of things, it just
> looks like those callers hung up (but then dialed back in just a moment
> later).
>
> I'm attaching two different snippets of the FS log files where these issues
> are occurring.
>
>
> Next time please call them .txt because you cause extra work to have to
> open them otherwise.
>
> Does anyone have any recommendations about how to troubleshoot this?
>
> Any known issues/patches in FS that could be biting us?
>
>
> Depends you failed to include some very valid info such as what version or
> svn rev you're running and what linux distro.
>
> Is there some SIP logging we can do to debug?
>
>
> Yes covered on the wiki.
> http://wiki.freeswitch.org/wiki/Debugging_Freeswitch
>
> Are there any paid contractors avail who would have the expertise to look
> into this?
>
>
> email consulting at freeswitch.org
>
> Any help appreciated ... this is a major issue for us!
>
> Thanks much,
>
> -Dale
>
>
> Brian West
> brian at freeswitch.org
>
> -- Meet us at ClueCon!  http://www.cluecon.com
>
>
>
>
>
> _______________________________________________
> 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/20090521/ec9a63b3/attachment-0001.html 
-------------- next part --------------
2009-05-07 17:32:34 [DEBUG] sofia.c:2728 sofia_handle_sip_i_state() Channel sofia/external/phonenumber at ipaddr entering state [terminated]
2009-05-07 17:32:34 [NOTICE] sofia.c:3279 sofia_handle_sip_i_state() Hangup sofia/external/phonenumber at ipaddr [CS_EXECUTE] [NORMAL_CLEARING]
2009-05-07 17:32:34 [DEBUG] switch_channel.c:1566 switch_channel_perform_hangup() Send signal sofia/external/phonenumber at ipaddr [KILL]
2009-05-07 17:32:34 [DEBUG] switch_core_session.c:820 switch_core_session_signal_state_change() Send signal sofia/external/phonenumber at ipaddr [BREAK]
2009-05-07 17:32:34 [DEBUG] mod_conference.c:2132 conference_loop_output() Channel leaving conference, cause: NORMAL_CLEARING
2009-05-07 17:32:34 [DEBUG] switch_core_codec.c:122 switch_core_session_set_read_codec() Restore original codec.
2009-05-07 17:32:34 [DEBUG] switch_core_state_machine.c:464 switch_core_session_run() (sofia/external/phonenumber at ipaddr) State EXECUTE going to sleep
2009-05-07 17:32:34 [DEBUG] switch_core_state_machine.c:383 switch_core_session_run() (sofia/external/phonenumber at ipaddr) Running State Change CS_HANGUP
2009-05-07 17:32:34 [DEBUG] switch_core_state_machine.c:414 switch_core_session_run() (sofia/external/phonenumber at ipaddr) State HANGUP
2009-05-07 17:32:34 [DEBUG] mod_sofia.c:253 sofia_on_hangup() sofia/external/phonenumber at ipaddr Overriding SIP cause 480 with 200 from the other leg
2009-05-07 17:32:34 [DEBUG] mod_sofia.c:287 sofia_on_hangup() Channel sofia/external/phonenumber at ipaddr hanging up, cause: NORMAL_CLEARING
2009-05-07 17:32:34 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() sofia/external/phonenumber at ipaddr Standard HANGUP, cause: NORMAL_CLEARING
2009-05-07 17:32:34 [DEBUG] switch_core_state_machine.c:414 switch_core_session_run() (sofia/external/phonenumber at ipaddr) State HANGUP going to sleep
2009-05-07 17:32:34 [DEBUG] switch_core_session.c:952 switch_core_session_thread() Session 1824 (sofia/external/phonenumber at ipaddr) Locked, Waiting on external entities
2009-05-07 17:32:34 [NOTICE] switch_core_session.c:970 switch_core_session_thread() Session 1824 (sofia/external/phonenumber at ipaddr) Ended
2009-05-07 17:32:34 [NOTICE] switch_core_session.c:972 switch_core_session_thread() Close Channel sofia/external/phonenumber at ipaddr [CS_HANGUP]
2009-05-07 17:32:34 [DEBUG] mod_conference.c:1255 conference_thread_run() Write Lock ON
2009-05-07 17:32:34 [DEBUG] mod_conference.c:2290 conference_record_thread_run() Recording Stopped
2009-05-07 17:32:34 [DEBUG] mod_conference.c:1258 conference_thread_run() Write Lock OFF
2009-05-07 17:32:34 [INFO] sofia_presence.c:561 actual_sofia_presence_event_handler() IN START_PRESENCE_SQL (internal)
2009-05-07 17:32:34 [ERR] sofia_presence.c:569 actual_sofia_presence_event_handler() DUMP PRESENCE SQL:
select sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Active (0 callers)','','ipaddr',sip_presence.status,sip_presence.rpid from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where (event='presence' or event='dialog') and sub_to_user='3000' and (sub_to_host='ipaddr' or presence_hosts like '%ipaddr%') and (sip_subscriptions.profile_name = 'internal' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
EVENT DUMP:
proto: [conf]
login: [3000]
from: [3000 at ipaddr]
status: [Active (0 callers)]
event_type: [presence]
alt_event_type: [dialog]
event_count: [1]
unique-id: [3000]
channel-state: [CS_ROUTING]
answer-state: [confirmed]
call-direction: [inbound]
Event-Name: [PRESENCE_IN]
Core-UUID: [31042480-7d5b-4d01-bda0-ef5758d51085]
FreeSWITCH-Hostname: [servername]
FreeSWITCH-IPv4: [ipaddr]
FreeSWITCH-IPv6: [::1]
Event-Date-Local: [2009-05-07 17:32:34]
Event-Date-GMT: [Fri, 08 May 2009 00:32:34 GMT]
Event-Date-Timestamp: [1241742754401218]
Event-Calling-File: [mod_conference.c]
Event-Calling-Function: [conference_del_member]
Event-Calling-Line-Number: [769]

2009-05-07 17:32:34 [INFO] sofia_presence.c:577 actual_sofia_presence_event_handler() IN END_PRESENCE_SQL (internal)
2009-05-07 17:32:34 [WARNING] sofia_presence.c:531 actual_sofia_presence_event_handler() external is passive, skipping
2009-05-07 17:32:34 [INFO] sofia_presence.c:561 actual_sofia_presence_event_handler() IN START_PRESENCE_SQL (internal-ipv6)
2009-05-07 17:32:34 [ERR] sofia_presence.c:569 actual_sofia_presence_event_handler() DUMP PRESENCE SQL:
select sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Active (0 callers)','','ipaddr',sip_presence.status,sip_presence.rpid from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where (event='presence' or event='dialog') and sub_to_user='3000' and (sub_to_host='ipaddr' or presence_hosts like '%ipaddr%') and (sip_subscriptions.profile_name = 'internal-ipv6' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
EVENT DUMP:
proto: [conf]
login: [3000]
from: [3000 at ipaddr]
status: [Active (0 callers)]
event_type: [presence]
alt_event_type: [dialog]
event_count: [1]
unique-id: [3000]
channel-state: [CS_ROUTING]
answer-state: [confirmed]
call-direction: [inbound]
Event-Name: [PRESENCE_IN]
Core-UUID: [31042480-7d5b-4d01-bda0-ef5758d51085]
FreeSWITCH-Hostname: [servername]
FreeSWITCH-IPv4: [ipaddr]
FreeSWITCH-IPv6: [::1]
Event-Date-Local: [2009-05-07 17:32:34]
Event-Date-GMT: [Fri, 08 May 2009 00:32:34 GMT]
Event-Date-Timestamp: [1241742754401218]
Event-Calling-File: [mod_conference.c]
Event-Calling-Function: [conference_del_member]
Event-Calling-Line-Number: [769]

2009-05-07 17:32:34 [INFO] sofia_presence.c:577 actual_sofia_presence_event_handler() IN END_PRESENCE_SQL (internal-ipv6)
2009-05-07 17:32:34 [WARNING] sofia_presence.c:524 actual_sofia_presence_event_handler() default is an alias, skipping
2009-05-07 17:32:34 [WARNING] sofia_presence.c:524 actual_sofia_presence_event_handler() nat is an alias, skipping
2009-05-07 17:32:34 [WARNING] sofia_presence.c:524 actual_sofia_presence_event_handler() ipaddr is an alias, skipping
2009-05-07 17:32:34 [WARNING] sofia_presence.c:524 actual_sofia_presence_event_handler() outbound is an alias, skipping
2009-05-07 17:32:34 [INFO] sofia_presence.c:561 actual_sofia_presence_event_handler() IN START_PRESENCE_SQL (internal)
2009-05-07 17:32:34 [ERR] sofia_presence.c:569 actual_sofia_presence_event_handler() DUMP PRESENCE SQL:
select sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Inactive','busy','ipaddr',sip_presence.status,sip_presence.rpid from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where (event='presence' or event='dialog') and sub_to_user='3000' and (sub_to_host='ipaddr' or presence_hosts like '%ipaddr%') and (sip_subscriptions.profile_name = 'internal' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
EVENT DUMP:
proto: [conf]
login: [3000]
from: [3000 at ipaddr]
status: [Inactive]
rpid: [idle]
event_type: [presence]
alt_event_type: [dialog]
event_count: [2]
unique-id: [3000]
channel-state: [CS_HANGUP]
answer-state: [terminated]
call-direction: [inbound]
Event-Name: [PRESENCE_IN]
Core-UUID: [31042480-7d5b-4d01-bda0-ef5758d51085]
FreeSWITCH-Hostname: [servername]
FreeSWITCH-IPv4: [ipaddr]
FreeSWITCH-IPv6: [::1]
Event-Date-Local: [2009-05-07 17:32:34]
Event-Date-GMT: [Fri, 08 May 2009 00:32:34 GMT]
Event-Date-Timestamp: [1241742754421218]
Event-Calling-File: [mod_conference.c]
Event-Calling-Function: [conference_thread_run]
Event-Calling-Line-Number: [1184]

2009-05-07 17:32:34 [INFO] sofia_presence.c:577 actual_sofia_presence_event_handler() IN END_PRESENCE_SQL (internal)
2009-05-07 17:32:34 [WARNING] sofia_presence.c:531 actual_sofia_presence_event_handler() external is passive, skipping
2009-05-07 17:32:34 [INFO] sofia_presence.c:561 actual_sofia_presence_event_handler() IN START_PRESENCE_SQL (internal-ipv6)
2009-05-07 17:32:34 [ERR] sofia_presence.c:569 actual_sofia_presence_event_handler() DUMP PRESENCE SQL:
select sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Inactive','busy','ipaddr',sip_presence.status,sip_presence.rpid from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where (event='presence' or event='dialog') and sub_to_user='3000' and (sub_to_host='ipaddr' or presence_hosts like '%ipaddr%') and (sip_subscriptions.profile_name = 'internal-ipv6' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
EVENT DUMP:
proto: [conf]
login: [3000]
from: [3000 at ipaddr]
status: [Inactive]
rpid: [idle]
event_type: [presence]
alt_event_type: [dialog]
event_count: [2]
unique-id: [3000]
channel-state: [CS_HANGUP]
answer-state: [terminated]
call-direction: [inbound]
Event-Name: [PRESENCE_IN]
Core-UUID: [31042480-7d5b-4d01-bda0-ef5758d51085]
FreeSWITCH-Hostname: [servername]
FreeSWITCH-IPv4: [ipaddr]
FreeSWITCH-IPv6: [::1]
Event-Date-Local: [2009-05-07 17:32:34]
Event-Date-GMT: [Fri, 08 May 2009 00:32:34 GMT]
Event-Date-Timestamp: [1241742754421218]
Event-Calling-File: [mod_conference.c]
Event-Calling-Function: [conference_thread_run]
Event-Calling-Line-Number: [1184]

2009-05-07 17:32:34 [INFO] sofia_presence.c:577 actual_sofia_presence_event_handler() IN END_PRESENCE_SQL (internal-ipv6)
2009-05-07 17:32:34 [WARNING] sofia_presence.c:524 actual_sofia_presence_event_handler() default is an alias, skipping
2009-05-07 17:32:34 [WARNING] sofia_presence.c:524 actual_sofia_presence_event_handler() nat is an alias, skipping
2009-05-07 17:32:34 [WARNING] sofia_presence.c:524 actual_sofia_presence_event_handler() ipaddr is an alias, skipping
2009-05-07 17:32:34 [WARNING] sofia_presence.c:524 actual_sofia_presence_event_handler() outbound is an alias, skipping
-------------- next part --------------
2009-05-07 17:36:28 [DEBUG] mod_conference.c:2950 conf_api_sub_kick() Send signal sofia/external/phonenumber at ipaddr [BREAK]
2009-05-07 17:36:28 [DEBUG] mod_conference.c:2132 conference_loop_output() Channel leaving conference, cause: NONE
2009-05-07 17:36:28 [DEBUG] mod_conference.c:5006 conference_function() sofia/external/phonenumber at ipaddr receive message [UNBRIDGE]
2009-05-07 17:36:28 [DEBUG] switch_core_session.c:523 switch_core_session_perform_receive_message() Send signal sofia/external/phonenumber at ipaddr [BREAK]
2009-05-07 17:36:28 [DEBUG] switch_core_codec.c:122 switch_core_session_set_read_codec() Restore original codec.
2009-05-07 17:36:28 [DEBUG] switch_ivr_play_say.c:989 switch_ivr_play_file() Codec Activated L16 at 8000hz 1 channels 20ms
2009-05-07 17:36:28 [INFO] sofia_presence.c:561 actual_sofia_presence_event_handler() IN START_PRESENCE_SQL (internal)
2009-05-07 17:36:28 [ERR] sofia_presence.c:569 actual_sofia_presence_event_handler() DUMP PRESENCE SQL:
select sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Active (0 callers)','','ipaddr',sip_presence.status,sip_presence.rpid from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where (event='presence' or event='dialog') and sub_to_user='3000' and (sub_to_host='ipaddr' or presence_hosts like '%ipaddr%') and (sip_subscriptions.profile_name = 'internal' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
EVENT DUMP:
proto: [conf]
login: [3000]
from: [3000 at ipaddr]
status: [Active (0 callers)]
event_type: [presence]
alt_event_type: [dialog]
event_count: [4]
unique-id: [3000]
channel-state: [CS_ROUTING]
answer-state: [confirmed]
call-direction: [inbound]
Event-Name: [PRESENCE_IN]
Core-UUID: [31042480-7d5b-4d01-bda0-ef5758d51085]
FreeSWITCH-Hostname: [servername]
FreeSWITCH-IPv4: [ipaddr]
FreeSWITCH-IPv6: [::1]
Event-Date-Local: [2009-05-07 17:36:28]
Event-Date-GMT: [Fri, 08 May 2009 00:36:28 GMT]
Event-Date-Timestamp: [1241742988481266]
Event-Calling-File: [mod_conference.c]
Event-Calling-Function: [conference_del_member]
Event-Calling-Line-Number: [769]

2009-05-07 17:36:28 [INFO] sofia_presence.c:577 actual_sofia_presence_event_handler() IN END_PRESENCE_SQL (internal)
2009-05-07 17:36:28 [WARNING] sofia_presence.c:531 actual_sofia_presence_event_handler() external is passive, skipping
2009-05-07 17:36:28 [INFO] sofia_presence.c:561 actual_sofia_presence_event_handler() IN START_PRESENCE_SQL (internal-ipv6)
2009-05-07 17:36:28 [ERR] sofia_presence.c:569 actual_sofia_presence_event_handler() DUMP PRESENCE SQL:
select sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Active (0 callers)','','ipaddr',sip_presence.status,sip_presence.rpid from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where (event='presence' or event='dialog') and sub_to_user='3000' and (sub_to_host='ipaddr' or presence_hosts like '%ipaddr%') and (sip_subscriptions.profile_name = 'internal-ipv6' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
EVENT DUMP:
proto: [conf]
login: [3000]
from: [3000 at ipaddr]
status: [Active (0 callers)]
event_type: [presence]
alt_event_type: [dialog]
event_count: [4]
unique-id: [3000]
channel-state: [CS_ROUTING]
answer-state: [confirmed]
call-direction: [inbound]
Event-Name: [PRESENCE_IN]
Core-UUID: [31042480-7d5b-4d01-bda0-ef5758d51085]
FreeSWITCH-Hostname: [servername]
FreeSWITCH-IPv4: [ipaddr]
FreeSWITCH-IPv6: [::1]
Event-Date-Local: [2009-05-07 17:36:28]
Event-Date-GMT: [Fri, 08 May 2009 00:36:28 GMT]
Event-Date-Timestamp: [1241742988481266]
Event-Calling-File: [mod_conference.c]
Event-Calling-Function: [conference_del_member]
Event-Calling-Line-Number: [769]

2009-05-07 17:36:28 [INFO] sofia_presence.c:577 actual_sofia_presence_event_handler() IN END_PRESENCE_SQL (internal-ipv6)
2009-05-07 17:36:28 [WARNING] sofia_presence.c:524 actual_sofia_presence_event_handler() default is an alias, skipping
2009-05-07 17:36:28 [WARNING] sofia_presence.c:524 actual_sofia_presence_event_handler() nat is an alias, skipping
2009-05-07 17:36:28 [WARNING] sofia_presence.c:524 actual_sofia_presence_event_handler() ipaddr is an alias, skipping
2009-05-07 17:36:28 [WARNING] sofia_presence.c:524 actual_sofia_presence_event_handler() outbound is an alias, skipping
2009-05-07 17:36:28 [DEBUG] mod_conference.c:1255 conference_thread_run() Write Lock ON
2009-05-07 17:36:28 [DEBUG] mod_conference.c:2290 conference_record_thread_run() Recording Stopped
2009-05-07 17:36:28 [INFO] sofia_presence.c:561 actual_sofia_presence_event_handler() IN START_PRESENCE_SQL (internal)
2009-05-07 17:36:28 [ERR] sofia_presence.c:569 actual_sofia_presence_event_handler() DUMP PRESENCE SQL:
select sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Inactive','busy','ipaddr',sip_presence.status,sip_presence.rpid from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where (event='presence' or event='dialog') and sub_to_user='3000' and (sub_to_host='ipaddr' or presence_hosts like '%ipaddr%') and (sip_subscriptions.profile_name = 'internal' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
EVENT DUMP:
proto: [conf]
login: [3000]
from: [3000 at ipaddr]
status: [Inactive]
rpid: [idle]
event_type: [presence]
alt_event_type: [dialog]
event_count: [5]
unique-id: [3000]
channel-state: [CS_HANGUP]
answer-state: [terminated]
call-direction: [inbound]
Event-Name: [PRESENCE_IN]
Core-UUID: [31042480-7d5b-4d01-bda0-ef5758d51085]
FreeSWITCH-Hostname: [servername]
FreeSWITCH-IPv4: [ipaddr]
FreeSWITCH-IPv6: [::1]
Event-Date-Local: [2009-05-07 17:36:28]
Event-Date-GMT: [Fri, 08 May 2009 00:36:28 GMT]
Event-Date-Timestamp: [1241742988501266]
Event-Calling-File: [mod_conference.c]
Event-Calling-Function: [conference_thread_run]
Event-Calling-Line-Number: [1184]

2009-05-07 17:36:28 [INFO] sofia_presence.c:577 actual_sofia_presence_event_handler() IN END_PRESENCE_SQL (internal)
2009-05-07 17:36:28 [WARNING] sofia_presence.c:531 actual_sofia_presence_event_handler() external is passive, skipping
2009-05-07 17:36:28 [INFO] sofia_presence.c:561 actual_sofia_presence_event_handler() IN START_PRESENCE_SQL (internal-ipv6)
2009-05-07 17:36:28 [ERR] sofia_presence.c:569 actual_sofia_presence_event_handler() DUMP PRESENCE SQL:
select sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Inactive','busy','ipaddr',sip_presence.status,sip_presence.rpid from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where (event='presence' or event='dialog') and sub_to_user='3000' and (sub_to_host='ipaddr' or presence_hosts like '%ipaddr%') and (sip_subscriptions.profile_name = 'internal-ipv6' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
EVENT DUMP:
proto: [conf]
login: [3000]
from: [3000 at ipaddr]
status: [Inactive]
rpid: [idle]
event_type: [presence]
alt_event_type: [dialog]
event_count: [5]
unique-id: [3000]
channel-state: [CS_HANGUP]
answer-state: [terminated]
call-direction: [inbound]
Event-Name: [PRESENCE_IN]
Core-UUID: [31042480-7d5b-4d01-bda0-ef5758d51085]
FreeSWITCH-Hostname: [servername]
FreeSWITCH-IPv4: [ipaddr]
FreeSWITCH-IPv6: [::1]
Event-Date-Local: [2009-05-07 17:36:28]
Event-Date-GMT: [Fri, 08 May 2009 00:36:28 GMT]
Event-Date-Timestamp: [1241742988501266]
Event-Calling-File: [mod_conference.c]
Event-Calling-Function: [conference_thread_run]
Event-Calling-Line-Number: [1184]

2009-05-07 17:36:28 [INFO] sofia_presence.c:577 actual_sofia_presence_event_handler() IN END_PRESENCE_SQL (internal-ipv6)
2009-05-07 17:36:28 [WARNING] sofia_presence.c:524 actual_sofia_presence_event_handler() default is an alias, skipping
2009-05-07 17:36:28 [WARNING] sofia_presence.c:524 actual_sofia_presence_event_handler() nat is an alias, skipping
2009-05-07 17:36:28 [WARNING] sofia_presence.c:524 actual_sofia_presence_event_handler() ipaddr is an alias, skipping
2009-05-07 17:36:28 [WARNING] sofia_presence.c:524 actual_sofia_presence_event_handler() outbound is an alias, skipping
2009-05-07 17:36:30 [DEBUG] switch_ivr_play_say.c:1279 switch_ivr_play_file() done playing file
2009-05-07 17:36:30 [DEBUG] mod_conference.c:1258 conference_thread_run() Write Lock OFF
2009-05-07 17:36:30 [NOTICE] switch_core_state_machine.c:168 switch_core_standard_on_execute() Hangup sofia/external/phonenumber at ipaddr [CS_EXECUTE] [NORMAL_CLEARING]
2009-05-07 17:36:30 [DEBUG] switch_channel.c:1566 switch_channel_perform_hangup() Send signal sofia/external/phonenumber at ipaddr [KILL]
2009-05-07 17:36:30 [DEBUG] switch_core_session.c:820 switch_core_session_signal_state_change() Send signal sofia/external/phonenumber at ipaddr [BREAK]
2009-05-07 17:36:30 [DEBUG] switch_core_state_machine.c:464 switch_core_session_run() (sofia/external/phonenumber at ipaddr) State EXECUTE going to sleep
2009-05-07 17:36:30 [DEBUG] switch_core_state_machine.c:383 switch_core_session_run() (sofia/external/phonenumber at ipaddr) Running State Change CS_HANGUP
2009-05-07 17:36:30 [DEBUG] switch_core_state_machine.c:414 switch_core_session_run() (sofia/external/phonenumber at ipaddr) State HANGUP
2009-05-07 17:36:30 [DEBUG] mod_sofia.c:287 sofia_on_hangup() Channel sofia/external/phonenumber at ipaddr hanging up, cause: NORMAL_CLEARING
2009-05-07 17:36:30 [DEBUG] mod_sofia.c:344 sofia_on_hangup() Sending BYE to sofia/external/phonenumber at ipaddr
2009-05-07 17:36:30 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() sofia/external/phonenumber at ipaddr Standard HANGUP, cause: NORMAL_CLEARING
2009-05-07 17:36:30 [DEBUG] switch_core_state_machine.c:414 switch_core_session_run() (sofia/external/phonenumber at ipaddr) State HANGUP going to sleep
2009-05-07 17:36:30 [DEBUG] switch_core_session.c:952 switch_core_session_thread() Session 1825 (sofia/external/phonenumber at ipaddr) Locked, Waiting on external entities
2009-05-07 17:36:30 [NOTICE] switch_core_session.c:970 switch_core_session_thread() Session 1825 (sofia/external/phonenumber at ipaddr) Ended
2009-05-07 17:36:30 [NOTICE] switch_core_session.c:972 switch_core_session_thread() Close Channel sofia/external/phonenumber at ipaddr [CS_HANGUP]