On Fri, Feb 26, 2010 at 5:20 AM, Ola Samuelson <[email protected]>wrote:
> Hi all! > I have an installation which works most of the time... sigh... > In fact it works prefectly for weeks then... > Latest 4.0.4 on centos 5.3 from iso. > > This morning no calls in/out could be made. > I looked at the ITSP side and all accounts are registered and not expired. > You could still surf using the same internet line. > > Just restarted all sipxpbx services using web gui....it all works. > > So...my assumptions are: > - Hardware and internet is ok > - Error in sipx > > I think the proper Benny Hill quote here is: "Never assume boy, you make an > ass out of you and me...." > (Or who said that?) > > I took a snapshot and selected a few pieces from the logs where it says > something that looks "not good". > I am wondering if you could see something that looks fishy....i can of > course supply the full trace. > > - Found some "err" in logs > - Found some "loop" - what? How can i fix it? > - Found some transfer errors? > - Found some db error > > More questions: > - Could using accented characters in identities and such a problem? > - I have 9 gateways but i am really only using 4 right now. Problem with > many gateways? > > Many thanks > Logs below. > Regards > Ola > > > > *freeswitch.log* > 2010-02-26 08:10:05 [NOTICE] switch_loadable_module.c:281 > switch_loadable_module_process() Adding File Format 'silence_stream' > 2010-02-26 08:10:05 [CRIT] switch_loadable_module.c:839 > switch_loadable_module_load_file() *Error Loading module > /usr/local/freeswitch/mod/mod_spidermonkey.so* > **/usr/local/freeswitch/mod/mod_spidermonkey.so: cannot open shared object > file: No such file or directory** > 2010-02-26 08:10:05 [CRIT] switch_loadable_module.c:839 > switch_loadable_module_load_file() *Error Loading module > /usr/local/freeswitch/mod/mod_lua.so* > **/usr/local/freeswitch/mod/mod_lua.so: cannot open shared object file: No > such file or directory** > 2010-02-26 08:10:05 [CRIT] switch_loadable_module.c:839 > switch_loadable_module_load_file() *Error Loading module > /usr/local/freeswitch/mod/mod_say_en.so* > **/usr/local/freeswitch/mod/mod_say_en.so: cannot open shared object file: > No such file or directory** > > *sipregistrar.log* > "2010-02-26T07:09:20.210884Z":212876:SIP:INFO:sip.flyglinjen.se::B7F63B90:SipRegistrar:"SignalTask: > terminate signal received." > "2010-02-26T07:09:20.497872Z":212877:SIP:NOTICE:sip.flyglinjen.se:pid-4120:B7F646E0:SipRegistrar:"main: > cleaning up." > "2010-02-26T07:09:22.090660Z":212878:KERNEL:*ERR*:sip.flyglinjen.se:SipServerBroker-8:FFFFFFFF:SipRegistrar:"OsServerSocket: > accept(-1) error: 22=Invalid argument" > "2010-02-26T07:09:23.073270Z":212879:KERNEL:*ERR*:sip.flyglinjen.se:SipServerBroker-18:FFFFFFFF:SipRegistrar:"OsServerSocket: > accept(-1) error: 22=Invalid argument" > "2010-02-26T07:09:24.198922Z":212880:KERNEL:*ERR*:sip.flyglinjen.se:SipServerBroker-25:FFFFFFFF:SipRegistrar:"OsServerSocket: > accept(-1) error: 22=Invalid argument" > > *sipcallresolverlog.log* > "2010-02-18T10:31:56.351371 ":ERR:, ERROR: *null value in column > "from_tag" violates not-null constraint* > > "2010-02-18T11:22:17.749782 ":ERR:Loss of connection to database - retrying > to connect after sleep > "2010-02-18T11:22:21.511664 ":INFO:TERM intercepted. Terminating reader > threads. > "2010-02-18T11:22:21.520658 ":INFO:resolve: Done. Analysis took 3026.000277 > seconds. > "2010-02-18T11:23:42.148861 ":ERR:, ERROR: null value in column "from_tag" > violates not-null constraint > > "2010-02-18T11:23:43.015957 ":ERR:, ERROR: duplicate key violates unique > constraint "cdrs_call_id_unique" > > "2010-02-26T08:09:20.113371 ":INFO:TERM intercepted. Terminating reader > threads. > "2010-02-26T08:09:20.272873 ":INFO:resolve: Done. Analysis took > 679539.96156 seconds. > "2010-02-26T08:14:40.168421 ":ERR:, ERROR: duplicate key violates unique > constraint "cdrs_call_id_unique" > > *sipxproxy.log* > "2010-02-26T03:13:27.141908Z":1625050:KERNEL:INFO:sip.flyglinjen.se: > SipClientTcp-14002:89EE7B90:SipXProxy:*"OsConnectionSocket::read[4] error > or EOF on fd 286, errno = 0 Success"* > "2010-02-26T03:13:27.407087Z":1625051:INCOMING:INFO:sip.flyglinjen.se:SipClientTcp-14003:89DE6B90:SipXProxy:"Read > SIP message:\n----Remote Host:192.168.1.197---- Port: 2337----\nSUBSCRIBE > sip:[email protected]:5060;transport=tcp;line=ix2546j0 SIP/2.0\r\nVia: > SIP/2.0/TCP 192.168.1.197:5060;branch=z9hG4bK-y3n3xnadibzf;rport\r\nFrom: > <sip:[email protected]>;tag=beplvdawcc\r\nTo: > <sip:[email protected];user=phone>\r\nCall-ID: > 3c26701577e6-ep0iohra8d3u\r\nCSeq: 164 SUBSCRIBE\r\nMax-Forwards: > 70\r\nContact: > <sip:[email protected]:5060;transport=tcp;line=jbyrhtkg>;reg-id=1\r\nEvent: > dialog\r\nAccept: application/dialog-info+xml\r\nUser-Agent: > snom320/7.3.14\r\nExpires: 3600\r\nContent-Length: > 0\r\n\r\n====================END====================" > > \n====================END====================" > "2010-02-26T00:33:29.497219Z":1502561:SIP:ERR:sip.flyglinjen.se: > SipClientTcp-13945:B3CF9B90:SipXProxy:"SipClientWriteBuffer[SipClientTcp-13945]:: > *insertMessage mWriteBuffer has 101 entries, exceeding the limit of 100"* > "2010-02-26T00:33:29.498623Z":1502562:OUTGOING:INFO:sip.flyglinjen.se:SipUserAgent-2:B6EC0B90:SipXProxy:"SipUserAgent::sendTcp > TCP SIP User Agent sent message:\n----Remote Host:192.168.1.254---- Port: > 41902----\nSIP/2.0 408 Request timeout\r\nFrom: \"141\" > <sip:[email protected]>; tag=3134310133323035373734343237\r\nTo: \"141\" > <sip:[email protected]>;tag=98c5342c\r\nCall-Id: 3220698761\r\nCseq: 1 > REGISTER\r\nVia: SIP/2.0/TCP > 192.168.1.25;branch=z9hG4bK-sipXecs-468a17514600fef835c5409e5846d47ac7dd;received=192.168.1.254;rport=41902\r\nVia: > SIP/2.0/TCP > 192.168.1.25;branch=z9hG4bK-sipXecs-3f679929c423ba88dac0fb8bc326c192cd51;received=192.168.1.254;rport=41902\r\nVia: > SIP/2.0/TCP > 192.168.1.25;branch=z9hG4bK-sipXecs-3b15f1d1529b48baa01c5f319e2b597a5469;received=192.168.1.254;rport=41895\r\nVia: > SIP/2.0/UDP > 192.168.1.25;branch=z9hG4bK-sipXecs-35070acd5c9af7d24353ddbdcb915883b758;received=192.168.1.254;rport=5060\r\nVia: > SIP/2.0/TCP > 192.168.1.25;branch=z9hG4bK-sipXecs-2b1af7359679c238cb07f91e7af9f56bdc98;received=192.168.1.254;rport=47212\r\nVia: > SIP/2.0/UDP > 192.168.1.25;branch=z9hG4bK-sipXecs-144985028e5033f75f91a40f6db188e8e4ea;received=192.168.1.254;rport=5060\r\nVia: > SIP/2.0/TCP > 192.168.1.25;branch=z9hG4bK-sipXecs-ff1e3f27d715e672eb3cc1642301c9c736ea;received=192.168.1.254;rport=47212\r\nVia: > SIP/2.0/TCP > 192.168.1.25;branch=z9hG4bK-sipXecs-f11f16b734539c2d0f5ec676e3ad3c8d597f;received=192.168.1.254;rport=47212\r\nVia: > SIP/2.0/UDP > 192.168.1.25;branch=z9hG4bK-sipXecs-ee7fb7e4821c39a7e7aabdca17882b8cbacc;received=192.168.1.254;rport=5060\r\nVia: > SIP/2.0/UDP > 127.0.1.1:5109;branch=z9hG4bK-1740403486;rport=5109;received=173.204.53.138\r\nServer: > sipXecs/4.0.4 sipXecs/sipXproxy (Linux)\r\nContent-Length: > 0\r\n\r\n--------------------END--------------------" > > > "2010-02-26T03:12:37.849853Z":1624874:INCOMING:INFO:sip.flyglinjen.se:SipClientUdp-8:B7A4BB90:SipXProxy:"Read > SIP message:\n----Remote Host:192.168.1.25---- Port: 5090----\nSIP/2.0 482 > *Loop detected\r\nVia: *SIP/2.0/UDP > 192.168.1.25;branch=z9hG4bK-sipXecs-871c205161fefb6627d97382f90f2d2f3b3c\r\nVia: > SIP/2.0/TCP > 192.168.1.25;branch=z9hG4bK-sipXecs-87184f91b09ac3e965e50149ecdc83fc72cb~54618be5d9eaba2b3a56bb7761718b23\r\nVia: > SIP/2.0/TCP 192.168.1.199:5060;branch=z9hG4bK-8zr5oax7i1w0;rport=2325\r\nFrom: > \"Bengt Lundin\" <sip:[email protected]>;tag=zvas1wchox\r\nTo: > <sip:[email protected];user=phone>\r\nCall-ID: > 3c2a36e604e6-cqxf2cemdoyg\r\nCSeq: 2 INVITE\r\nServer: sipXecs/4.0.4 > sipXecs/sipxbridge (Linux)\r\nContent-Length: > 0\r\n\r\n====================END====================" > > "2010-02-26T03:12:37.347325Z":1624869:OUTGOING:INFO:sip.flyglinjen.se: > SipClientUdp-8:B7A4BB90:SipXProxy:*"SipUserAgent::sendUdp resend 1 of UDP > message\nUDP SIP User Agent sent message:\n----Remote *Host:192.168.1.25---- > Port: 5090----\nACK > sip:[email protected]:5060;user=phone;transport=udpSIP/2.0\r\nRoute: > <sip:192.168.1.25:5090;lr>\r\nContact: > <sip:[email protected]:5060;transport=tcp;line=ix2546j0;x-sipX-nonat>;reg-id=1\r\nFrom: > <sip:[email protected]>;tag=1s8yu9nvnc\r\nTo: > <sip:[email protected];user=phone>\r\nCall-Id: > 3c2a48ade812-w5cjschjx4t0\r\nCseq: 2 ACK\r\nMax-Forwards: 20\r\nVia: > SIP/2.0/UDP > 192.168.1.25;branch=z9hG4bK-sipXecs-46f1f32d05b465ce44a5f971f0059ce3beca\r\nContent-Length: > 0\r\n\r\n--------------------END--------------------" > > "2010-02-26T00:36:18.382660Z":1547587:KERNEL:*WARNING*:sip.flyglinjen.se:OsTimer-9:B794AB90:SipXProxy:"OsTimerTask::run > firing took 21108 usecs, queue length = 2676" > "2010-02-26T00:36:18.383545Z":1547588:KERNEL:WARNING:sip.flyglinjen.se:OsTimer-9:B794AB90:SipXProxy:"OsTimerTask::insertTimer > timer to fire 25100751 microseconds in the past, queue length = 2677" > > > > > Are any of your phones trying to register via IP instead of sip domain?
_______________________________________________ sipx-users mailing list [email protected] List Archive: http://list.sipfoundry.org/archive/sipx-users Unsubscribe: http://list.sipfoundry.org/mailman/listinfo/sipx-users sipXecs IP PBX -- http://www.sipfoundry.org/
