hey Anthony,

Thanks for help!

ok, I can see a deadlock from logs, but why? I had two profile, internal and igmp profile. 

For me, it looks like profiler are restarting itself, isn't it?

Logs are:

internal relates (works) :

2013-07-01 17:09:40.406838 [CRIT] sofia.c:2627 Waiting for 1 session(s)

2013-07-01 17:09:40.486904 [NOTICE] sofia.c:2650 Waiting for worker thread

2013-07-01 17:09:40.486904 [INFO] switch_core_sqldb.c:1490 sofia:internal Destroying SQL queue.

2013-07-01 17:09:40.686902 [INFO] switch_core_sqldb.c:1434 sofia:internal Stopping SQL thread.

2013-07-01 17:09:40.686902 [DEBUG] sofia.c:2705 Write lock internal

2013-07-01 17:09:40.686902 [DEBUG] sofia.c:2717 Write unlock internal

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 debug [0]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 sip-trace [no]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 sip-capture [no]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 watchdog-enabled [no]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 watchdog-step-timeout [30000]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 watchdog-event-timeout [30000]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 log-auth-failures [true]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 forward-unsolicited-mwi-notify [false]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 context [default]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 rfc2833-pt [101]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 sip-port [5060]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 dialplan [XML]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 dtmf-duration [2000]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 inbound-codec-prefs [G722,PCMU,PCMA,GSM]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 outbound-codec-prefs [G722,PCMU,PCMA,GSM]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 rtp-timer-name [soft]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 rtp-ip [10.0.1.45]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 sip-ip [10.0.1.45]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 hold-music [local_stream://moh]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 apply-nat-acl [nat.auto]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 apply-inbound-acl [domains]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 local-network-acl [localnet.auto]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 record-path [/usr/local/freeswitch/recordings]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 record-template [${caller_id_number}.${target_domain}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 manage-presence [true]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 presence-hosts [10.0.1.45,10.0.1.45]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 presence-privacy [false]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 inbound-codec-negotiation [greedy]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 tls [false]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 tls-only [false]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 tls-bind-params [transport=tls]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 tls-sip-port [5061]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 tls-cert-dir [/usr/local/freeswitch/conf/ssl]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 tls-passphrase []

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 tls-verify-date [true]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 tls-verify-policy [none]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 tls-verify-depth [2]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 tls-verify-in-subjects []

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 tls-version [tlsv1]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 inbound-late-negotiation [true]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 inbound-zrtp-passthru [true]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 suppress-cng [true]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 nonce-ttl [60]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 auth-calls [true]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 inbound-reg-force-matching-username [true]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 auth-all-packets [false]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 ext-rtp-ip [auto-nat]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 ext-sip-ip [auto-nat]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 rtp-timeout-sec [300]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 rtp-hold-timeout-sec [1800]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 force-register-domain [10.0.1.45]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 force-subscription-domain [10.0.1.45]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 force-register-db-domain [10.0.1.45]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:3669 challenge-realm [auto_from]

2013-07-01 17:09:41.146829 [NOTICE] sofia.c:4755 Started Profile internal [sofia_reg_internal]

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:2385 Creating agent for internal

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:2482 Created agent for internal

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:2525 Set params for internal

2013-07-01 17:09:41.146829 [DEBUG] sofia.c:2569 Activated db for internal

2013-07-01 17:09:41.156827 [INFO] switch_core_sqldb.c:1449 sofia:internal Starting SQL thread.

2013-07-01 17:09:41.156827 [DEBUG] sofia.c:2607 Starting thread for internal

2013-07-01 17:09:41.156827 [DEBUG] sofia.c:2285 Launching worker thread for internal

2013-07-01 17:09:41.406840 [CRIT] sofia.c:2627 Waiting for 1 session(s)

                                                                                                   

IGMP parts(deadlock on sofia.c:2705 ):

2013-07-01 17:09:43.406902 [NOTICE] sofia.c:2650 Waiting for worker thread

2013-07-01 17:09:43.406902 [INFO] switch_core_sqldb.c:1490 sofia:igmp Destroying SQL queue.

2013-07-01 17:09:43.606848 [INFO] switch_core_sqldb.c:1434 sofia:igmp Stopping SQL thread.

2013-07-01 17:09:43.606848 [DEBUG] sofia.c:2705 Write lock igmp

2013-07-01 18:10:42.696829 [DEBUG] switch_ivr_originate.c:2044 Parsing global variables

2013-07-01 18:10:42.696829 [DEBUG] switch_event.c:1608 Parsing variable [originate_timeout]=[10]

2013-07-01 18:10:42.696829 [DEBUG] switch_event.c:1608 Parsing variable [channel_username]=[server_user_admin]

2013-07-01 18:10:42.696829 [DEBUG] switch_event.c:1608 Parsing variable [channel_caller_id_number]=[server_user_admin]

2013-07-01 18:10:42.696829 [DEBUG] switch_event.c:1608 Parsing variable [origination_caller_id_name]=[server_user_admin]

2013-07-01 18:10:42.696829 [DEBUG] switch_event.c:1608 Parsing variable [origination_caller_id_number]=[server_user_admin]

2013-07-01 18:10:42.696829 [DEBUG] switch_ivr_originate.c:2044 Parsing global variables

2013-07-01 18:10:42.696829 [ERR] mod_sofia.c:5190 Invalid Profile

2013-07-01 18:10:42.696829 [NOTICE] mod_sofia.c:5473 Close Channel N/A [CS_NEW]

2013-07-01 18:10:42.696829 [DEBUG] switch_core_state_machine.c:568 () Running State Change CS_DESTROY

2013-07-01 18:10:42.696829 [DEBUG] switch_core_state_machine.c:578 (N/A) State DESTROY

2013-07-01 18:10:42.696829 [DEBUG] mod_sofia.c:401 N/A SOFIA DESTROY

2013-07-01 18:10:42.696829 [DEBUG] switch_core_state_machine.c:578 (N/A) State DESTROY going to sleep

2013-07-01 18:10:42.696829 [NOTICE] switch_ivr_originate.c:2639 Cannot create outgoing channel of type [sofia] cause: [INVALID_PROFILE]

2013-07-01 18:10:42.696829 [NOTICE] switch_ivr_originate.c:2639 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]

2013-07-01 18:10:42.696829 [DEBUG] switch_ivr_originate.c:3605 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED]

2013-07-01 18:10:42.696829 [NOTICE] switch_ivr_originate.c:2639 Cannot create outgoing channel of type [user] cause: [USER_NOT_REGISTERED]

2013-07-01 18:10:42.696829 [DEBUG] switch_ivr_originate.c:3605 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED]

I did use 'show channels', and there is none.

Any idea?

Cheers

On Fri, Jun 28, 2013 at 11:50 PM, Anthony Minessale <anthony.minessale@gmail.com> wrote:

Look in your logs for any messages about that profile.

Also gcore the process and do a thread apply all bt to see any hints.

On Thu, Jun 27, 2013 at 10:39 PM, Xijing Dai <dxj19831029@gmail.com> wrote:

hey all,

I have experienced a lot of times that sofia profile stops to response for any sip messages. And when you type sofia status, that profile is gone, but if we check the port by netstat, the port is still opened by freeswitch.

This happens if we keep freeswitch running in 1 - 2 days. And there is no error log.

Any idea on how to debug?

Cheers.

_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
consulting@freeswitch.org
http://www.freeswitchsolutions.com

FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.com

Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com

FreeSWITCH-dev mailing list
FreeSWITCH-dev@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-dev
http://www.freeswitch.org

--
Anthony Minessale II

FreeSWITCH http://www.freeswitch.org/
ClueCon http://www.cluecon.com/
Twitter: http://twitter.com/FreeSWITCH_wire

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
googletalk:conf+888@conference.freeswitch.org
pstn:+19193869900

_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
consulting@freeswitch.org
http://www.freeswitchsolutions.com

FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.com

Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com

FreeSWITCH-dev mailing list
FreeSWITCH-dev@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-dev
http://www.freeswitch.org

_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
consulting@freeswitch.org
http://www.freeswitchsolutions.com

FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.com

Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com

FreeSWITCH-dev mailing list
FreeSWITCH-dev@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-dev
http://www.freeswitch.org