[Freeswitch-users] sofia profile internal fails to restart since enabling odbc

[Johannes Jakob]

Hi,

I recently switched our PBX to ODBC (mysql) driven database for all things freeswitch.

Since then, I can't just

sofia profile internal restart

without it being deleted:

2012-05-18 11:07:46.275244 [NOTICE] sofia.c:2092 Waiting for worker thread
nua_destroy(0x7fb73c0088d0): FATAL: nua_shutdown not completed


[…]
2012-05-18 11:07:46.275244 [NOTICE] sofia.c:4665 Started Profile internal [sofia_reg_internal]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:1861 Creating agent for internal
2012-05-18 11:07:46.275244 [ERR] sofia.c:1940 Error Creating SIP UA for profile: internal




How can I debug/trace what's keeping sofia UA from stopping?


Thanks for your help!

 Regards,

  Johannes



freeswitch at internal> version
FreeSWITCH Version 1.1.beta1 (git-c41a16d 2012-04-05 14-28-01 -0500)




autoload_configs# grep odbc * | grep -v "<\!--"
cidlookup.conf.xml: <param name="odbc-dsn" value="phone:phone:phone"/>
db.conf.xml: <param name="odbc-dsn" value="freeswitch"/>
easyroute.conf.xml: <param name="odbc-retries" value="120"/>
lcr.conf.xml: <param name="odbc-dsn" value="freeswitch-mysql:freeswitch:Fr33Sw1tch"/>
spidermonkey.conf.xml: <load module="mod_spidermonkey_odbc"/>
voicemail.conf.xml: <param name="odbc-dsn" value="freeswitch"/>





(cidlookup, easyroute, lcr and spidermonkey aren't used anyways)




"full log":

2012-05-18 11:07:46.275244 [NOTICE] sofia.c:2092 Waiting for worker thread
nua_destroy(0x7fb73c0088d0): FATAL: nua_shutdown not completed
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:2141 Write lock internal
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:2152 Write unlock internal
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 user-agent-string [FreeSWITCH]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 debug [0]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 sip-trace [no]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 sip-capture [no]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 watchdog-enabled [no]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 watchdog-step-timeout [30000]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 watchdog-event-timeout [30000]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 log-auth-failures [true]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 forward-unsolicited-mwi-notify [false]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 context [public]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 rfc2833-pt [101]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 sip-port [5060]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 dialplan [XML]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 dtmf-duration [2000]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 inbound-codec-prefs [PCMA,PCMU,G7221 at 32000h,G7221 at 16000h,G722,iLBC,GSM,H263,H264]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 outbound-codec-prefs [PCMA,PCMU,G7221 at 32000h,G7221 at 16000h,G722,iLBC,GSM,H263,H264]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 rtp-timer-name [soft]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 rtp-ip [94.186.133.69]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 sip-ip [94.186.133.69]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 hold-music [local_stream://moh]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 apply-nat-acl [nat.auto]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 apply-inbound-acl [domains]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 local-network-acl [localnet.auto]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 record-path [/usr/local/freeswitch/recordings]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 record-template [${caller_id_number}.${target_domain}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 manage-presence [true]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 presence-hosts [,94.186.133.69]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 presence-privacy []
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 inbound-codec-negotiation [generous]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 tls [true]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 tls-only [false]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 tls-bind-params [transport=tls]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 tls-sip-port [5061]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 tls-cert-dir [/usr/local/freeswitch/conf/ssl]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 tls-passphrase []
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 tls-verify-date [true]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 tls-verify-policy [none]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 tls-verify-depth [2]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 tls-verify-in-subjects []
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 tls-version [tlsv1]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 nonce-ttl [60]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 auth-calls [true]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 inbound-reg-force-matching-username [true]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 auth-all-packets [false]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 ext-rtp-ip [auto-nat]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 ext-sip-ip [auto-nat]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 rtp-timeout-sec [300]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 rtp-hold-timeout-sec [1800]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 challenge-realm [auto_from]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 t38_passthru [true]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 t38-passthru [true]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 sip-force-expires [120]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:3770 multiple-registrations [true]
2012-05-18 11:07:46.275244 [NOTICE] sofia.c:4665 Started Profile internal [sofia_reg_internal]
2012-05-18 11:07:46.275244 [DEBUG] sofia.c:1861 Creating agent for internal
2012-05-18 11:07:46.275244 [ERR] sofia.c:1940 Error Creating SIP UA for profile: internal