February 2012 - FreeSWITCH-users - FreeSWITCH Mailing Lists

setting higher ptime with sangoma transcoding card
by Arafath-uz-zaman khan 12 Feb '12

12 Feb '12

Hello All I am using FS with Sangoma transcoding card. Can anyone suggest me how can i user 60 or more ptime for G729 codec? -- Arafath-uz-zaman khan

2 1

How to inspect the jitterbuffer?
by Holger Freyther 11 Feb '12

11 Feb '12

Hi all, I have a setup with a FreeTDM/wanpipe trunk and most of the time the audio skips on the path to this trunk (e.g. after a period of silence, RTP marker bit set by the sender). It is not an issue with FreeTDM/wanpipe but more the way the RTP input is handled by FreeSWITCH. My theory is the audio skips because the RTP 'queue' has an under-run (while it shouldn't, there is no packet loss). So how can I see if the jitterbuffer is enabled for a given call (or globally)? Can I somehow see under-runs? The recovered clock/ clock skew? I couldn't find any of this in the wiki[1]. thanks holger [1] http://wiki.freeswitch.org/wiki/Jitterbuffer

4 11

Skype module killing
by Balamurugan Mahendran 11 Feb '12

11 Feb '12

Skype module crashed the FS freeswitch@internal> load mod_skypopen +OK Reloading XML -ERR [module load file routine returned an error] 2012-02-11 14:18:55.142045 [INFO] mod_pocketsphinx.c:482 PocketSphinx Reloaded freeswitch@internal> 2012-02-11 14:18:55.142045 [INFO] mod_enum.c:812 ENUM Reloaded 2012-02-11 14:18:55.142045 [INFO] switch_time.c:1035 Timezone reloaded 530 definitions 2012-02-11 14:18:55.162036 [WARNING] mod_skypopen.c:1738 [406c21c|1b4c78b] [WARNINGA 1738 ][skype101 ][IDLE,IDLE] STARTING interface_id=1 2012-02-11 14:18:55.162036 [ERR] skypopen_protocol.c:1549 [406c21c|1b4c78b] [ERRORA 1549 ][none ][N/A,N/A] Received error code 5 from X Server 2012-02-11 14:18:55.162036 [ERR] skypopen_protocol.c:1550 [406c21c|1b4c78b] [ERRORA 1550 ][none ][N/A,N/A] Display error for 1, :101 2012-02-11 14:18:55.162036 [ERR] skypopen_protocol.c:1747 [406c21c|1b4c78b] [ERRORA 1747 ][skype101 ][DEAD,IDLE] Fatal display error for :101 - successed to jump 2012-02-11 14:18:55.162036 [WARNING] skypopen_protocol.c:1772 [406c21c|1b4c78b] [WARNINGA 1772 ][skype101 ][DEAD,IDLE] Removing skype interface #skype101 2012-02-11 14:18:55.362032 [NOTICE] mod_skypopen.c:1768 [406c21c|1b4c78b] [NOTICA 1768 ][skype101 ][DEAD,IDLE] WAITING roughly 10 seconds to find a running Skype client and connect to its SKYPE API for interface_id=1 2012-02-11 14:19:05.362034 [ERR] mod_skypopen.c:1781 [406c21c|1b4c78b] [ERRORA 1781 ][skype101 ][DEAD,IDLE] Failed to connect to a SKYPE API for interface_id=1, no SKYPE client running, please (re)start Skype client. Skypopen exiting 2012-02-11 14:19:05.362034 [CRIT] switch_loadable_module.c:1281 Error Loading module /usr/local/freeswitch/mod/mod_skypopen.so **Module load routine returned an error** freeswitch@internal> load mod_skypopen Socket interrupted, bye! 5 XSELINUXs still allocated at reset SCREEN: 0 objects of 168 bytes = 0 total bytes 0 private allocs DEVICE: 4 objects of 48 bytes = 192 total bytes 0 private allocs CLIENT: 0 objects of 160 bytes = 0 total bytes 0 private allocs WINDOW: 0 objects of 32 bytes = 0 total bytes 0 private allocs PIXMAP: 1 objects of 16 bytes = 16 total bytes 0 private allocs GC: 0 objects of 56 bytes = 0 total bytes 0 private allocs CURSOR: 0 objects of 8 bytes = 0 total bytes 0 private allocs CURSOR_BITS: 0 objects of 8 bytes = 0 total bytes 0 private allocs DBE_WINDOW: 0 objects of 24 bytes = 0 total bytes 0 private allocs TOTAL: 5 objects, 208 bytes, 0 allocs 4 DEVICEs still allocated at reset DEVICE: 4 objects of 48 bytes = 192 total bytes 0 private allocs CLIENT: 0 objects of 160 bytes = 0 total bytes 0 private allocs WINDOW: 0 objects of 32 bytes = 0 total bytes 0 private allocs PIXMAP: 1 objects of 16 bytes = 16 total bytes 0 private allocs GC: 0 objects of 56 bytes = 0 total bytes 0 private allocs CURSOR: 0 objects of 8 bytes = 0 total bytes 0 private allocs CURSOR_BITS: 0 objects of 8 bytes = 0 total bytes 0 private allocs DBE_WINDOW: 0 objects of 24 bytes = 0 total bytes 0 private allocs TOTAL: 5 objects, 208 bytes, 0 allocs 1 PIXMAPs still allocated at reset PIXMAP: 1 objects of 16 bytes = 16 total bytes 0 private allocs GC: 0 objects of 56 bytes = 0 total bytes 0 private allocs CURSOR: 0 objects of 8 bytes = 0 total bytes 0 private allocs CURSOR_BITS: 0 objects of 8 bytes = 0 total bytes 0 private allocs DBE_WINDOW: 0 objects of 24 bytes = 0 total bytes 0 private allocs TOTAL: 1 objects, 16 bytes, 0 allocs Thanks, Bala

2 1

Buffer size sanity check failed!
by Rodrigo Andrés Zumaeta 11 Feb '12

11 Feb '12

Hello, this is my first message to the list! when connecting one specific group of users to Freeswitch, I get this message: [CRIT] switch_core_codec.c:760 Buffer size sanity check failed! The call is then dropped. That is, on 1.0.6. When I upgrade to the latest git version, I still get the same message, but the call is not dropped anymore. However, I get only one way audio. As aforementioned, I just get this odd behavior with one group of users. I will be happy to provide any relevant information.

3 4

Re: [Freeswitch-users] Registration problem after attempting to install mod_opal and updating to latest git pull
by Phil Quesinberry 10 Feb '12

10 Feb '12

That appears to have fixed the problem. Thanks again. - Phil _____________________________________________ From: Phil Quesinberry Sent: Friday, February 10, 2012 3:50 PM To: 'freeswitch-users(a)lists.freeswitch.org' Subject: RE: Registration problem after attempting to install mod_opal and updating to latest git pull Ok, here's part of the sip trace as requested.

1 0

Re: [Freeswitch-users] Registration problem after attempting to install mod_opal and updating to latest git pull
by Phil Quesinberry 10 Feb '12

10 Feb '12

Ok, here's part of the sip trace as requested. I won't have time to sanitize the whole thing until later but in the meantime, here's an interesting excerpt that I wanted to make a few comments on: First, notice the "Unauthorized" response to the first registration attempt but the next attempt is successful. This has actually been going on as long as I can remember with this particular provider. ------------------------------------------------------------------------ 2012-02-10 12:14:32.174952 [WARNING] sofia.c:4717 Unregister HL7611 2012-02-10 12:14:32.174952 [WARNING] sofia.c:4730 Ping failed HL7611 with code 900 - count -1/-1/1, state UP send 854 bytes to udp/[140.239.143.5]:5060 at 17:14:33.595032: ------------------------------------------------------------------------ REGISTER sip:sip16.vitelity.net;transport=udp SIP/2.0 Via: SIP/2.0/UDP 71.179.xx.xx:5080;rport;branch=z9hG4bK7857r78Xt8pKg Max-Forwards: 70 From: <sip:thisis7611@sip16.vitelity.net;transport=udp>;tag=D0jHcrrtFjtym To: <sip:thisis7611@sip16.vitelity.net;transport=udp> Call-ID: 04cac6f5-9608-4019-9658-6c0e6d1fd7af CSeq: 24054543 REGISTER Contact: <sip:gw+HL7611@71.179.xx.xx:5080;transport=udp;gw=HL7611> Expires: 120 User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-4c07a00 2012-02-08 16-52-13 -0600 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Authorization: Digest username="thisis7611", realm="asterisk", nonce="xxxxxxxx", algorithm=MD5, uri="sip:sip16.vitelity.net;transport=udp", response="d7ecbd5585a7bef456be36b8e5fe0b8e" Content-Length: 0 ------------------------------------------------------------------------ recv 463 bytes from udp/[140.239.143.5]:5060 at 17:14:33.656879: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 71.179.xx.xx:5080;branch=z9hG4bK7857r78Xt8pKg;received=71.179.xx.xx;rport=50 80 From: <sip:thisis7611@sip16.vitelity.net;transport=udp>;tag=D0jHcrrtFjtym To: <sip:thisis7611@sip16.vitelity.net;transport=udp> Call-ID: 04cac6f5-9608-4019-9658-6c0e6d1fd7af CSeq: 24054543 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Content-Length: 0 ------------------------------------------------------------------------ recv 560 bytes from udp/[140.239.143.5]:5060 at 17:14:33.656961: ------------------------------------------------------------------------ SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 71.179.xx.xx:5080;branch=z9hG4bK7857r78Xt8pKg;received=71.179.xx.xx;rport=50 80 From: <sip:thisis7611@sip16.vitelity.net;transport=udp>;tag=D0jHcrrtFjtym To: <sip:thisis7611@sip16.vitelity.net;transport=udp>;tag=as117f4b90 Call-ID: 04cac6f5-9608-4019-9658-6c0e6d1fd7af CSeq: 24054543 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="xxxxxxxx" Content-Length: 0 ------------------------------------------------------------------------ send 854 bytes to udp/[140.239.143.5]:5060 at 17:14:33.657072: ------------------------------------------------------------------------ REGISTER sip:sip16.vitelity.net;transport=udp SIP/2.0 Via: SIP/2.0/UDP 71.179.xx.xx:5080;rport;branch=z9hG4bK8HZ0t2S1QHD6B Max-Forwards: 70 From: <sip:thisis7611@sip16.vitelity.net;transport=udp>;tag=D0jHcrrtFjtym To: <sip:thisis7611@sip16.vitelity.net;transport=udp> Call-ID: 04cac6f5-9608-4019-9658-6c0e6d1fd7af CSeq: 24054544 REGISTER Contact: <sip:gw+HL7611@71.179.xx.xx:5080;transport=udp;gw=HL7611> Expires: 120 User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-4c07a00 2012-02-08 16-52-13 -0600 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Authorization: Digest username="thisis7611", realm="asterisk", nonce="xxxxxxxx", algorithm=MD5, uri="sip:sip16.vitelity.net;transport=udp", response="d82a35caa676c34e817dd63a658d34ac" Content-Length: 0 ------------------------------------------------------------------------ recv 463 bytes from udp/[140.239.143.5]:5060 at 17:14:33.719551: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 71.179.xx.xx:5080;branch=z9hG4bK8HZ0t2S1QHD6B;received=71.179.xx.xx;rport=50 80 From: <sip:thisis7611@sip16.vitelity.net;transport=udp>;tag=D0jHcrrtFjtym To: <sip:thisis7611@sip16.vitelity.net;transport=udp> Call-ID: 04cac6f5-9608-4019-9658-6c0e6d1fd7af CSeq: 24054544 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Content-Length: 0 ------------------------------------------------------------------------ recv 604 bytes from udp/[140.239.143.5]:5060 at 17:14:33.721743: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 71.179.xx.xx:5080;branch=z9hG4bK8HZ0t2S1QHD6B;received=71.179.xx.xx;rport=50 80 From: <sip:thisis7611@sip16.vitelity.net;transport=udp>;tag=D0jHcrrtFjtym To: <sip:thisis7611@sip16.vitelity.net;transport=udp>;tag=as117f4b90 Call-ID: 04cac6f5-9608-4019-9658-6c0e6d1fd7af CSeq: 24054544 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Expires: 60 Contact: <sip:gw+HL7611@71.179.xx.xx:5080;transport=udp;gw=HL7611>;expires=60 Date: Fri, 10 Feb 2012 17:14:33 GMT Content-Length: 0 Next, what's with the "Bad event", the time discrepancy, and "sip:ping@invalid" in the following exchange? ------------------------------------------------------------------------ recv 683 bytes from udp/[192.168.1.4]:5060 at 19:33:11.486617: ------------------------------------------------------------------------ SUBSCRIBE sip:102@192.168.1.6:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.4;branch=z9hG4bKfe095021fa5ec8e60;rport Route: <sip:192.168.1.6:5060;lr> Max-Forwards: 70 From: "Phil" <sip:102@192.168.1.6:5060>;tag=a4efbafcef To: <sip:102@192.168.1.6:5060> Call-ID: 17f321dbe885cc25 CSeq: 24812 SUBSCRIBE Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, UPDATE, PRACK, SUBSCRIBE, INFO Allow-Events: talk, hold, conference, LocalModeStatus Contact: "Phil" <sip:102@192.168.1.4:5060;transport=udp>;+sip.instance="<urn:uuid:00000000-0 000-1000-8000-00085D296BF3>" Event: as-feature-event Expires: 3600 Supported: path User-Agent: Aastra 9480i/3.2.2.1136 Content-Length: 0 ------------------------------------------------------------------------ send 653 bytes to udp/[192.168.1.4]:5060 at 19:33:11.486724: ------------------------------------------------------------------------ SIP/2.0 489 Bad Event Via: SIP/2.0/UDP 192.168.1.4;branch=z9hG4bKfe095021fa5ec8e60;rport=5060 From: "Phil" <sip:102@192.168.1.6:5060>;tag=a4efbafcef To: <sip:102@192.168.1.6:5060>;tag=gS042N7B17j3j Call-ID: 17f321dbe885cc25 CSeq: 24812 SUBSCRIBE User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-4c07a00 2012-02-08 16-52-13 -0600 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Length: 0 ------------------------------------------------------------------------ recv 958 bytes from udp/[74.93.xx.xx]:1060 at 19:33:11.837860: ------------------------------------------------------------------------ REGISTER sip:qsystemseng.mydomain.org:5060 SIP/2.0 Via: SIP/2.0/UDP 74.93.xx.xx:51660;branch=z9hG4bKaaab13558da89266a;rport Route: <sip:qsystemseng.mydomain.org:5060;lr> Max-Forwards: 70 From: "Tina" <sip:257@qsystemseng.mydomain.org:5060>;tag=5dfb41956c To: "Tina" <sip:257@qsystemseng.mydomain.org:5060> Call-ID: 69748b87cb4b8d1d CSeq: 35981 REGISTER Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, UPDATE, PRACK, SUBSCRIBE, INFO Allow-Events: talk, hold, conference, LocalModeStatus Authorization: Digest username="257",realm="qsystemseng.mydomain.org",nonce="xxxxxxxx",uri="sip:qs ystemseng.mydomain.org:5060",response="48feb96d103538b600c37e6bef966799",alg orithm=MD5,qop=auth,cnonce="xxxxxxxx",nc=00000004 Contact: "Tina" <sip:257@74.93.xx.xx:1060;transport=udp>;+sip.instance="<urn:uuid:00000000-0 000-1000-8000-00085D26F8E9>";expires=90 Supported: path, gruu User-Agent: Aastra 9143i/3.2.2.1136 Content-Length: 0 ------------------------------------------------------------------------ send 640 bytes to udp/[74.93.xx.xx]:1060 at 19:33:11.839412: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 74.93.xx.xx:51660;branch=z9hG4bKaaab13558da89266a;rport=1060 From: "Tina" <sip:257@qsystemseng.mydomain.org:5060>;tag=5dfb41956c To: "Tina" <sip:257@qsystemseng.mydomain.org:5060>;tag=H2SX4grFyg9Ne Call-ID: 69748b87cb4b8d1d CSeq: 35981 REGISTER Contact: <sip:257@74.93.xx.xx:1060;transport=udp>;expires=90 Date: Fri, 10 Feb 2012 19:33:11 GMT User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-4c07a00 2012-02-08 16-52-13 -0600 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Content-Length: 0 ------------------------------------------------------------------------ 2012-02-10 14:33:12.381474 [WARNING] sofia.c:4717 Unregister HL7611 2012-02-10 14:33:12.381474 [WARNING] sofia.c:4730 Ping failed HL7611 with code 900 - count -1/-1/1, state UP 2012-02-10 14:33:13.389496 [WARNING] sofia_reg.c:474 HL7611 Failed Registration [0], setting retry to 90 seconds. recv 917 bytes from udp/[74.93.xx.xx]:1064 at 19:33:13.465516: ------------------------------------------------------------------------ REGISTER sip:qsystemseng.mydomain.org:5060 SIP/2.0 Via: SIP/2.0/UDP 74.93.xx.xx:51620;branch=z9hG4bKc6b28c84c8b2a8b8e;rport Max-Forwards: 70 From: "Laura" <sip:227@qsystemseng.mydomain.org:5060>;tag=87516cda24 To: "Laura" <sip:227@qsystemseng.mydomain.org:5060> Call-ID: 09856e1e62e97740 CSeq: 27466 REGISTER Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, UPDATE, PRACK, SUBSCRIBE, INFO Allow-Events: talk, hold, conference, LocalModeStatus Authorization: Digest username="227",realm="qsystemseng.mydomain.org",nonce="xxxxxxxx",uri="sip:qs ystemseng.mydomain.org:5060",response="05a8d65372aef7cbd3d7968ba5844fa8",alg orithm=MD5,qop=auth,cnonce="xxxxxxxx",nc=00000003 Contact: "Laura" <sip:227@74.93.xx.xx:1064;transport=udp>;+sip.instance="<urn:uuid:00000000-0 000-1000-8000-00085D223D29>";expires=90 Supported: path, gruu User-Agent: Aastra 9143i/3.2.2.1136 Content-Length: 0 ------------------------------------------------------------------------ send 642 bytes to udp/[74.93.xx.xx]:1064 at 19:33:13.467000: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 74.93.xx.xx:51620;branch=z9hG4bKc6b28c84c8b2a8b8e;rport=1064 From: "Laura" <sip:227@qsystemseng.mydomain.org:5060>;tag=87516cda24 To: "Laura" <sip:227@qsystemseng.mydomain.org:5060>;tag=jBKp6B9jUSZ8S Call-ID: 09856e1e62e97740 CSeq: 27466 REGISTER Contact: <sip:227@74.93.xx.xx:1064;transport=udp>;expires=90 Date: Fri, 10 Feb 2012 19:33:13 GMT User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-4c07a00 2012-02-08 16-52-13 -0600 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Content-Length: 0 ------------------------------------------------------------------------ recv 450 bytes from udp/[216.115.69.144]:5060 at 19:33:15.332881: ------------------------------------------------------------------------ OPTIONS sip:71.179.xx.xx:5080 SIP/2.0 Max-Forwards: 10 Record-Route: <sip:216.115.69.144;lr> Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bKfc94.cc57baa771388125df1b041bbd688213.0 Via: SIP/2.0/UDP 70.167.153.136:5060;branch=0 Route: <sip:216.115.69.144;lr;received="sip:71.179.xx.xx:5080"> From: sip:ping@invalid;tag=6c8e4582 To: sip:71.179.xx.xx:5080 Call-ID: aa72439-f0144116-cf3b885(a)70.167.153.136 CSeq: 1 OPTIONS Content-Length: 0 ------------------------------------------------------------------------ send 670 bytes to udp/[216.115.69.144]:5060 at 19:33:15.333175: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bKfc94.cc57baa771388125df1b041bbd688213.0 Via: SIP/2.0/UDP 70.167.153.136:5060;branch=0 From: sip:ping@invalid;tag=6c8e4582 To: <sip:71.179.xx.xx:5080>;tag=21ecKKFFNK3cj Call-ID: aa72439-f0144116-cf3b885(a)70.167.153.136 CSeq: 1 OPTIONS Contact: <sip:thisis7612@71.179.xx.xx:5080;transport=udp;gw=HL7612> User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-4c07a00 2012-02-08 16-52-13 -0600 Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, hold, refer Content-Length: 0 Interestingly, I can't reproduce the SIPBroker problem after the last update, even though I re-enabled ping. Ping is not necessary with SIPBroker as it's only for outgoing calls and you don't need to register with them. I'll try to sanitize and post a complete trace here later tonight. Thanks again, - Phil -----Original Message----- From: Phil Quesinberry [mailto:philq@qsystemsengineering.com] Sent: Thursday, February 09, 2012 12:51 AM To: 'freeswitch-users(a)lists.freeswitch.org' Subject: Registration problem after attempting to install mod_opal and updating to latest git pull After a recent unsuccessful attempt to install mod_opal and updating to a recent git, I now get some odd reports of gateway registration failures, although everything appears to be working ok call-wise for the moment. Doing a 'make uninstall' from /root/opal and updating to the latest git tonight yielded no improvement. Here are some excerpts of failures reported in fs_cli: 2012-02-09 00:12:59.992099 [WARNING] sofia.c:4717 Unregister QS8002 2012-02-09 00:12:59.992099 [WARNING] sofia.c:4730 Ping failed QS8002 with code 900 - count -1/-1/1, state UP 2012-02-09 00:12:59.992099 [WARNING] sofia.c:4717 Unregister HL7612 2012-02-09 00:12:59.992099 [WARNING] sofia.c:4730 Ping failed HL7612 with code 900 - count -1/-1/1, state UP 2012-02-09 00:13:00.011988 [WARNING] sofia.c:4717 Unregister HL7611 2012-02-09 00:13:00.011988 [WARNING] sofia.c:4730 Ping failed HL7611 with code 900 - count -1/-1/1, state UP 2012-02-09 00:13:00.011988 [WARNING] sofia.c:4717 Unregister HL7519 2012-02-09 00:13:00.011988 [WARNING] sofia.c:4730 Ping failed HL7519 with code 900 - count -1/-1/1, state UP 2012-02-09 00:13:00.011988 [WARNING] sofia.c:4717 Unregister CallCentric_T.38 2012-02-09 00:13:00.011988 [WARNING] sofia.c:4730 Ping failed CallCentric_T.38 with code 900 - count -1/-1/1, state UP 2012-02-09 00:13:01.992050 [WARNING] sofia_reg.c:474 QS8002 Failed Registration [0], setting retry to 60 seconds. 2012-02-09 00:13:01.992050 [WARNING] sofia_reg.c:474 HL7612 Failed Registration [0], setting retry to 90 seconds. 2012-02-09 00:13:01.992050 [WARNING] sofia_reg.c:474 HL7611 Failed Registration [0], setting retry to 90 seconds. 2012-02-09 00:13:01.992050 [WARNING] sofia_reg.c:474 HL7519 Failed Registration [0], setting retry to 60 seconds. 2012-02-09 00:13:01.992050 [WARNING] sofia_reg.c:474 CallCentric_T.38 Failed Registration [0], setting retry to 60 seconds. To give a sense of the timing, the three groups of warnings below are contiguous, from a single fs_cli session: 2012-02-09 00:21:18.984384 [WARNING] sofia.c:4717 Unregister HL7611 2012-02-09 00:21:18.984384 [WARNING] sofia.c:4730 Ping failed HL7611 with code 900 - count -1/-1/1, state UP 2012-02-09 00:21:20.988379 [WARNING] sofia_reg.c:474 HL7611 Failed Registration [0], setting retry to 90 seconds. 2012-02-09 00:21:22.988382 [WARNING] sofia.c:4717 Unregister HL7519 2012-02-09 00:21:22.988382 [WARNING] sofia.c:4730 Ping failed HL7519 with code 900 - count -1/-1/1, state UP 2012-02-09 00:21:24.988318 [WARNING] sofia_reg.c:474 HL7519 Failed Registration [0], setting retry to 60 seconds. 2012-02-09 00:22:00.447778 [WARNING] sofia_reg.c:1422 SIP auth challenge (REGISTER) on sofia profile 'internal' for [226(a)qsystemseng.no-ip.org] from ip 74.93.222.182 2012-02-09 00:22:04.147688 [WARNING] sofia.c:4717 Unregister HL7612 2012-02-09 00:22:04.147688 [WARNING] sofia.c:4730 Ping failed HL7612 with code 900 - count -1/-1/1, state UP 2012-02-09 00:22:04.167699 [WARNING] sofia.c:4717 Unregister HL7611 2012-02-09 00:22:04.167699 [WARNING] sofia.c:4730 Ping failed HL7611 with code 900 - count -1/-1/1, state UP 2012-02-09 00:22:04.167699 [WARNING] sofia.c:4717 Unregister CallCentric_T.38 2012-02-09 00:22:04.167699 [WARNING] sofia.c:4730 Ping failed CallCentric_T.38 with code 900 - count -1/-1/1, state UP 2012-02-09 00:22:05.167744 [WARNING] sofia_reg.c:474 HL7611 Failed Registration [0], setting retry to 90 seconds. 2012-02-09 00:22:05.167744 [WARNING] sofia_reg.c:474 CallCentric_T.38 Failed Registration [0], setting retry to 60 seconds. 2012-02-09 00:22:05.167744 [WARNING] sofia.c:4717 Unregister QS8002 2012-02-09 00:22:05.167744 [WARNING] sofia.c:4730 Ping failed QS8002 with code 900 - count -1/-1/1, state UP 2012-02-09 00:22:07.171680 [WARNING] sofia_reg.c:474 QS8002 Failed Registration [0], setting retry to 60 seconds. 2012-02-09 00:22:07.171680 [WARNING] sofia.c:4717 Unregister HL7519 2012-02-09 00:22:07.171680 [WARNING] sofia.c:4730 Ping failed HL7519 with code 900 - count -1/-1/1, state UP One of the gateways is SIPBroker, and calls through that gate starting failing after the update. I realized that the gateway was configured to ping SIPBroker unnecessarily and restarting the gateway would restore service until the ping. I disabled the ping for that gateway and calls kept working after that. Any ideas as to what might be going on? There was no such problem before the FS update/attempt to use mod_opal. I can provide additional/more detailed info if needed. Thanks! Phil Quesinberry Q Systems Engineering, Inc. Electronic Controls and Embedded Systems Development (410) 969-8002 http://www.qsystemsengineering.com

3 2

Help with attended transfer / att_xfer
by Brett Wilson 10 Feb '12

10 Feb '12

Hey guys, I need a pointer. I'm trying to get some kind of functionality where after a attended transfer is completed, ie. The ouside caller is connected to the 2nd phone after the 1st phone has hung up or chosen to continue the transfer. I would like a beep to sound for the 2nd phone or for both legs, just to let the recipient of the transfer know that it has gone through and they can start talking. Any ideas? Thanks Brett Wilson Launch 3 Ventures, LLC 134 Myer Street Hackensack, NJ 07601 Phone: 877.878.9134 Fax: 646.536.3866 Email: Brett.Wilson(a)launch3.net AOL IM: Brett.Wilson(a)launch3.net <blocked::http://www.launch3.net/> www.Launch3.net <http://www.launch3telecom.com/> www.Launch3telecom.com *************************** <http://launch3telecom.blogspot.com/> Description: Description: Blogger-logo <http://www.facebook.com/pages/Launch-3-Telecom/165610820178720> Description: Description: FaceBook-Logo <https://twitter.com/launch3telecom> Description: Description: Twitter-Logo

5 8

Can't call out more than once- long distance calls
by Charles Bujold 10 Feb '12

10 Feb '12

The first long distance call going out, works. If I try to make a second call immediately after I get call "Forbidden", I presume it is an incorrect setting but do not know where to start. Here is a log of the call being rejected. The error 403 says the server gets the call, but will not process it, if I interpret the error message properly. Question is what can say to Freeswitch not to process the call? Thanks for the help cb 2012-02-10 14:35:29.082815 [DEBUG] sofia.c:7538 IP 192.168.20.80 Rejected by acl "domains". Falling back to Digest auth. 2012-02-10 14:35:29.082815 [NOTICE] switch_channel.c:930 New Channel sofia/internal/250(a)192.168.250.20 [36c7a641-bb72-403b-99f4-a2783a8898b8] 2012-02-10 14:35:29.082815 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/250(a)192.168.250.20) Running State Change CS_NEW 2012-02-10 14:35:29.082815 [DEBUG] switch_core_state_machine.c:380 (sofia/internal/250(a)192.168.250.20) State NEW 2012-02-10 14:35:29.082815 [DEBUG] sofia.c:5508 Channel sofia/internal/250(a)192.168.250.20 entering state [received][100] 2012-02-10 14:35:29.082815 [DEBUG] sofia.c:5519 Remote SDP: v=0 o=- 20015 20015 IN IP4 192.168.20.80 s=SDP data c=IN IP4 192.168.20.80 t=0 0 m=audio 11796 RTP/AVP 0 8 18 4 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:4 G723/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 2012-02-10 14:35:29.082815 [DEBUG] sofia_glue.c:4798 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000] 2012-02-10 14:35:29.082815 [DEBUG] sofia_glue.c:2919 Set Codec sofia/internal/250(a)192.168.250.20 PCMU/8000 20 ms 160 samples 64000 bits 2012-02-10 14:35:29.082815 [DEBUG] switch_core_codec.c:111 sofia/internal/250(a)192.168.250.20 Original read codec set to PCMU:0 2012-02-10 14:35:29.082815 [DEBUG] sofia_glue.c:4919 Set 2833 dtmf send/recv payload to 101 2012-02-10 14:35:29.082815 [DEBUG] sofia.c:5731 (sofia/internal/250(a)192.168.250.20) State Change CS_NEW -> CS_INIT 2012-02-10 14:35:29.082815 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/250(a)192.168.250.20 [BREAK] 2012-02-10 14:35:29.082815 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/250(a)192.168.250.20) Running State Change CS_INIT 2012-02-10 14:35:29.082815 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/250(a)192.168.250.20) State INIT 2012-02-10 14:35:29.082815 [DEBUG] mod_sofia.c:85 sofia/internal/250(a)192.168.250.20 SOFIA INIT 2012-02-10 14:35:29.082815 [DEBUG] mod_sofia.c:125 (sofia/internal/250(a)192.168.250.20) State Change CS_INIT -> CS_ROUTING 2012-02-10 14:35:29.082815 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/250(a)192.168.250.20 [BREAK] 2012-02-10 14:35:29.082815 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/250(a)192.168.250.20) State INIT going to sleep 2012-02-10 14:35:29.082815 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/250(a)192.168.250.20) Running State Change CS_ROUTING 2012-02-10 14:35:29.082815 [DEBUG] switch_channel.c:1890 (sofia/internal/250(a)192.168.250.20) Callstate Change DOWN -> RINGING 2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/250(a)192.168.250.20) State ROUTING 2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:148 sofia/internal/250(a)192.168.250.20 SOFIA ROUTING 2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:104 sofia/internal/250(a)192.168.250.20 Standard ROUTING 2012-02-10 14:35:29.102799 [INFO] mod_dialplan_xml.c:481 Processing User1 <250>->18xxxxxxxxx in context default Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->unloop] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->call_direction] continue=true Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [call_direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 ANTI-Action set(call_direction=local) Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->tod_example] continue=true Dialplan: sofia/internal/250(a)192.168.250.20 Date/Time Match (PASS) [tod_example] break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 Action set(open=true) Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->holiday_example] continue=true Dialplan: sofia/internal/250(a)192.168.250.20 Date/TimeMatch (FAIL) [holiday_example] break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->group-intercept] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [group-intercept] destination_number(18xxxxxxxxx) =~ /^\*8$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->redial] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [redial] destination_number(18xxxxxxxxx) =~ /^(redial|\*870)$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->global] continue=true Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [global] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never Dialplan: sofia/internal/250(a)192.168.250.20 Absolute Condition [global] Dialplan: sofia/internal/250(a)192.168.250.20 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) Dialplan: sofia/internal/250(a)192.168.250.20 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_numbe r}) Dialplan: sofia/internal/250(a)192.168.250.20 Action hash(insert/${domain_name}-last_dial/global/${uuid}) Dialplan: sofia/internal/250(a)192.168.250.20 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->snom-demo-2] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [snom-demo-2] destination_number(18xxxxxxxxx) =~ /^\*9001$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->snom-demo-1] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [snom-demo-1] destination_number(18xxxxxxxxx) =~ /^\*9000$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->call_privacy] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [call_privacy] destination_number(18xxxxxxxxx) =~ /^\*67(\d+)$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->call_return] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [call_return] destination_number(18xxxxxxxxx) =~ /^\*69$|^869$|^lcr$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->del-group] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [del-group] destination_number(18xxxxxxxxx) =~ /^\*\*80(\d{2})$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->add-group] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [add-group] destination_number(18xxxxxxxxx) =~ /^\*\*81(\d{2})$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->call-group-simo] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [call-group-simo] destination_number(18xxxxxxxxx) =~ /^\*\*82(\d{2})$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->call-group-order] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [call-group-order] destination_number(18xxxxxxxxx) =~ /^\*83(\d{2})$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->intercept-ext] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [intercept-ext] destination_number(18xxxxxxxxx) =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->Local_Extension_Skinny] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [Local_Extension_Skinny] destination_number(18xxxxxxxxx) =~ /^(11[01][0-9])$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->send_to_voicemail] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [send_to_voicemail] destination_number(18xxxxxxxxx) =~ /^\*99(\d{2,7})$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->VoiceMeUp.911] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [VoiceMeUp.911] destination_number(18xxxxxxxxx) =~ /^911$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->VoiceMeUp.7d] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [VoiceMeUp.7d] destination_number(18xxxxxxxxx) =~ /^(\d{7})$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->VoiceMeUp.tollfree] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (FAIL) [VoiceMeUp.tollfree] destination_number(18xxxxxxxxx) =~ /^1?(8(00|55|66|77|88)[2-9]\d{6})$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 parsing [default->VoiceMeUp.11d] continue=false Dialplan: sofia/internal/250(a)192.168.250.20 Regex (PASS) [VoiceMeUp.11d] destination_number(18xxxxxxxxx) =~ /^\+?(\d{11})$/ break=on-false Dialplan: sofia/internal/250(a)192.168.250.20 Action set(sip_h_X-accountcode=${accountcode}) Dialplan: sofia/internal/250(a)192.168.250.20 Action set(call_direction=outbound) Dialplan: sofia/internal/250(a)192.168.250.20 Action set(hangup_after_bridge=true) Dialplan: sofia/internal/250(a)192.168.250.20 Action set(effective_caller_id_name=${outbound_caller_id_name}) Dialplan: sofia/internal/250(a)192.168.250.20 Action set(effective_caller_id_number=${outbound_caller_id_number}) Dialplan: sofia/internal/250(a)192.168.250.20 Action set(inherit_codec=true) Dialplan: sofia/internal/250(a)192.168.250.20 Action bridge(sofia/gateway/VoiceMeUp/18xxxxxxxxx) 2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:154 (sofia/internal/250(a)192.168.250.20) State Change CS_ROUTING -> CS_EXECUTE 2012-02-10 14:35:29.102799 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/250(a)192.168.250.20 [BREAK] 2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/250(a)192.168.250.20) State ROUTING going to sleep 2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/250(a)192.168.250.20) Running State Change CS_EXECUTE 2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/250(a)192.168.250.20) State EXECUTE 2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:241 sofia/internal/250(a)192.168.250.20 SOFIA EXECUTE 2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:192 sofia/internal/250(a)192.168.250.20 Standard EXECUTE EXECUTE sofia/internal/250(a)192.168.250.20 set(call_direction=local) 2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250(a)192.168.250.20 SET [call_direction]=[local] EXECUTE sofia/internal/250(a)192.168.250.20 set(open=true) 2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250(a)192.168.250.20 SET [open]=[true] EXECUTE sofia/internal/250(a)192.168.250.20 hash(insert/192.168.250.20-spymap/250/36c7a641-bb72-403b-99f4-a2783a8898b8) EXECUTE sofia/internal/250(a)192.168.250.20 hash(insert/192.168.250.20-last_dial/250/18xxxxxxxxx) EXECUTE sofia/internal/250(a)192.168.250.20 hash(insert/192.168.250.20-last_dial/global/36c7a641-bb72-403b-99f4-a2783a88 98b8) EXECUTE sofia/internal/250(a)192.168.250.20 set(RFC2822_DATE=Fri, 10 Feb 2012 14:35:29 -0400) 2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250(a)192.168.250.20 SET [RFC2822_DATE]=[Fri, 10 Feb 2012 14:35:29 -0400] EXECUTE sofia/internal/250(a)192.168.250.20 set(sip_h_X-accountcode=1000) 2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250(a)192.168.250.20 SET [sip_h_X-accountcode]=[1000] EXECUTE sofia/internal/250(a)192.168.250.20 set(call_direction=outbound) 2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250(a)192.168.250.20 SET [call_direction]=[outbound] EXECUTE sofia/internal/250(a)192.168.250.20 set(hangup_after_bridge=true) 2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250(a)192.168.250.20 SET [hangup_after_bridge]=[true] EXECUTE sofia/internal/250(a)192.168.250.20 set(effective_caller_id_name=Tel1) 2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250(a)192.168.250.20 SET [effective_caller_id_name]=[Tel1] EXECUTE sofia/internal/250(a)192.168.250.20 set(effective_caller_id_number=5xxxxxxxxx) 2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250(a)192.168.250.20 SET [effective_caller_id_number]=[5xxxxxxxxx] EXECUTE sofia/internal/250(a)192.168.250.20 set(inherit_codec=true) 2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250(a)192.168.250.20 SET [inherit_codec]=[true] EXECUTE sofia/internal/250(a)192.168.250.20 bridge(sofia/gateway/VoiceMeUp/18xxxxxxxxx) 2012-02-10 14:35:29.102799 [DEBUG] switch_channel.c:1051 sofia/internal/250(a)192.168.250.20 EXPORTING[export_vars] [domain_name]=[192.168.250.20] to event 2012-02-10 14:35:29.102799 [DEBUG] switch_ivr_originate.c:1884 Parsing global variables 2012-02-10 14:35:29.102799 [NOTICE] switch_channel.c:930 New Channel sofia/external/18xxxxxxxxx [5abef3bf-1aa0-4616-89c5-4e134df9328d] 2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:4670 (sofia/external/18xxxxxxxxx) State Change CS_NEW -> CS_INIT 2012-02-10 14:35:29.102799 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/18xxxxxxxxx [BREAK] 2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:362 (sofia/external/18xxxxxxxxx) Running State Change CS_INIT 2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:401 (sofia/external/18xxxxxxxxx) State INIT 2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:85 sofia/external/18xxxxxxxxx SOFIA INIT 2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:125 (sofia/external/18xxxxxxxxx) State Change CS_INIT -> CS_ROUTING 2012-02-10 14:35:29.102799 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/18xxxxxxxxx [BREAK] 2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:401 (sofia/external/18xxxxxxxxx) State INIT going to sleep 2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:362 (sofia/external/18xxxxxxxxx) Running State Change CS_ROUTING 2012-02-10 14:35:29.122800 [DEBUG] switch_channel.c:1890 (sofia/external/18xxxxxxxxx) Callstate Change DOWN -> RINGING 2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:410 (sofia/external/18xxxxxxxxx) State ROUTING 2012-02-10 14:35:29.122800 [DEBUG] mod_sofia.c:148 sofia/external/18xxxxxxxxx SOFIA ROUTING 2012-02-10 14:35:29.122800 [DEBUG] switch_ivr_originate.c:66 (sofia/external/18xxxxxxxxx) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2012-02-10 14:35:29.122800 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/18xxxxxxxxx [BREAK] 2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:410 (sofia/external/18xxxxxxxxx) State ROUTING going to sleep 2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:362 (sofia/external/18xxxxxxxxx) Running State Change CS_CONSUME_MEDIA 2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:429 (sofia/external/18xxxxxxxxx) State CONSUME_MEDIA 2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:429 (sofia/external/18xxxxxxxxx) State CONSUME_MEDIA going to sleep 2012-02-10 14:35:29.122800 [DEBUG] switch_core_session.c:875 Send signal sofia/external/18xxxxxxxxx [BREAK] 2012-02-10 14:35:29.122800 [DEBUG] sofia.c:5508 Channel sofia/external/18xxxxxxxxx entering state [calling][0] 2012-02-10 14:35:29.162820 [DEBUG] switch_core_session.c:875 Send signal sofia/external/18xxxxxxxxx [BREAK] 2012-02-10 14:35:29.162820 [DEBUG] switch_core_session.c:875 Send signal sofia/external/18xxxxxxxxx [BREAK] 2012-02-10 14:35:29.162820 [DEBUG] sofia.c:5508 Channel sofia/external/18xxxxxxxxx entering state [calling][0] 2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:875 Send signal sofia/external/18xxxxxxxxx [BREAK] 2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:875 Send signal sofia/external/18xxxxxxxxx [BREAK] 2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:875 Send signal sofia/external/18xxxxxxxxx [BREAK] 2012-02-10 14:35:29.222819 [DEBUG] sofia.c:5508 Channel sofia/external/18xxxxxxxxx entering state [terminated][403] 2012-02-10 14:35:29.222819 [DEBUG] switch_channel.c:2852 (sofia/external/18xxxxxxxxx) Callstate Change RINGING -> HANGUP 2012-02-10 14:35:29.222819 [NOTICE] sofia.c:6272 Hangup sofia/external/18xxxxxxxxx [CS_CONSUME_MEDIA] [CALL_REJECTED] 2012-02-10 14:35:29.222819 [DEBUG] switch_channel.c:2875 Send signal sofia/external/18xxxxxxxxx [KILL] 2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/18xxxxxxxxx [BREAK] 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:362 (sofia/external/18xxxxxxxxx) Running State Change CS_HANGUP 2012-02-10 14:35:29.222819 [DEBUG] switch_ivr_originate.c:3364 Originate Resulted in Error Cause: 21 [CALL_REJECTED] 2012-02-10 14:35:29.222819 [INFO] mod_dptools.c:2916 Originate Failed. Cause: CALL_REJECTED 2012-02-10 14:35:29.222819 [DEBUG] switch_channel.c:2852 (sofia/internal/250(a)192.168.250.20) Callstate Change RINGING -> HANGUP 2012-02-10 14:35:29.222819 [NOTICE] mod_dptools.c:3035 Hangup sofia/internal/250(a)192.168.250.20 [CS_EXECUTE] [CALL_REJECTED] 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:602 (sofia/external/18xxxxxxxxx) State HANGUP 2012-02-10 14:35:29.222819 [DEBUG] mod_sofia.c:469 Channel sofia/external/18xxxxxxxxx hanging up, cause: CALL_REJECTED 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:47 sofia/external/18xxxxxxxxx Standard HANGUP, cause: CALL_REJECTED 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:602 (sofia/external/18xxxxxxxxx) State HANGUP going to sleep 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:393 (sofia/external/18xxxxxxxxx) State Change CS_HANGUP -> CS_REPORTING 2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/18xxxxxxxxx [BREAK] 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:362 (sofia/external/18xxxxxxxxx) Running State Change CS_REPORTING 2012-02-10 14:35:29.222819 [DEBUG] switch_channel.c:2875 Send signal sofia/internal/250(a)192.168.250.20 [KILL] 2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/250(a)192.168.250.20 [BREAK] 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:662 (sofia/external/18xxxxxxxxx) State REPORTING 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:79 sofia/external/18xxxxxxxxx Standard REPORTING, cause: CALL_REJECTED 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:662 (sofia/external/18xxxxxxxxx) State REPORTING going to sleep 2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:2285 sofia/internal/250(a)192.168.250.20 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/250(a)192.168.250.20) State EXECUTE going to sleep 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/250(a)192.168.250.20) Running State Change CS_HANGUP 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:387 (sofia/external/18xxxxxxxxx) State Change CS_REPORTING -> CS_DESTROY 2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/18xxxxxxxxx [BREAK] 2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1380 Session 7 (sofia/external/18xxxxxxxxx) Locked, Waiting on external entities 2012-02-10 14:35:29.222819 [NOTICE] switch_core_session.c:1398 Session 7 (sofia/external/18xxxxxxxxx) Ended 2012-02-10 14:35:29.222819 [NOTICE] switch_core_session.c:1400 Close Channel sofia/external/18xxxxxxxxx [CS_DESTROY] 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:491 (sofia/external/18xxxxxxxxx) Callstate Change HANGUP -> DOWN 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:494 (sofia/external/18xxxxxxxxx) Running State Change CS_DESTROY 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:504 (sofia/external/18xxxxxxxxx) State DESTROY 2012-02-10 14:35:29.222819 [DEBUG] mod_sofia.c:374 sofia/external/18xxxxxxxxx SOFIA DESTROY 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/250(a)192.168.250.20) State HANGUP 2012-02-10 14:35:29.222819 [DEBUG] mod_sofia.c:463 sofia/internal/250(a)192.168.250.20 Overriding SIP cause 603 with 403 from the other leg 2012-02-10 14:35:29.222819 [DEBUG] mod_sofia.c:469 Channel sofia/internal/250(a)192.168.250.20 hanging up, cause: CALL_REJECTED 2012-02-10 14:35:29.222819 [DEBUG] mod_sofia.c:534 Responding to INVITE with: 403 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:47 sofia/internal/250(a)192.168.250.20 Standard HANGUP, cause: CALL_REJECTED 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/250(a)192.168.250.20) State HANGUP going to sleep 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:393 (sofia/internal/250(a)192.168.250.20) State Change CS_HANGUP -> CS_REPORTING 2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/250(a)192.168.250.20 [BREAK] 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/250(a)192.168.250.20) Running State Change CS_REPORTING 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/250(a)192.168.250.20) State REPORTING 2012-02-10 14:35:29.222819 [DEBUG] switch_nat.c:545 unmapped public port 26608 protocol UDP to localport 26608 2012-02-10 14:35:29.222819 [DEBUG] switch_nat.c:545 unmapped public port 16966 protocol UDP to localport 16966 2012-02-10 14:35:29.222819 [DEBUG] switch_nat.c:545 unmapped public port 16967 protocol UDP to localport 16967 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:86 sofia/external/18xxxxxxxxx Standard DESTROY 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:504 (sofia/external/18xxxxxxxxx) State DESTROY going to sleep 2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:79 sofia/internal/250(a)192.168.250.20 Standard REPORTING, cause: CALL_REJECTED 2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/250(a)192.168.250.20) State REPORTING going to sleep 2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:387 (sofia/internal/250(a)192.168.250.20) State Change CS_REPORTING -> CS_DESTROY 2012-02-10 14:35:29.422846 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/250(a)192.168.250.20 [BREAK] 2012-02-10 14:35:29.422846 [DEBUG] switch_core_session.c:1380 Session 6 (sofia/internal/250(a)192.168.250.20) Locked, Waiting on external entities 2012-02-10 14:35:29.422846 [NOTICE] switch_core_session.c:1398 Session 6 (sofia/internal/250(a)192.168.250.20) Ended 2012-02-10 14:35:29.422846 [NOTICE] switch_core_session.c:1400 Close Channel sofia/internal/250(a)192.168.250.20 [CS_DESTROY] 2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/250(a)192.168.250.20) Callstate Change HANGUP -> DOWN 2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:494 (sofia/internal/250(a)192.168.250.20) Running State Change CS_DESTROY 2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/250(a)192.168.250.20) State DESTROY 2012-02-10 14:35:29.422846 [DEBUG] mod_sofia.c:374 sofia/internal/250(a)192.168.250.20 SOFIA DESTROY 2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:86 sofia/internal/250(a)192.168.250.20 Standard DESTROY 2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/250(a)192.168.250.20) State DESTROY going to sleep

2 1

cluecon 2012 date confirm
by Seven Du 10 Feb '12

10 Feb '12

Hi, Had a wonderful time in Cluecon 2011 and would like to confirm the date as it looks like no schedule for 2012 on cluecon.com and I only found a count down on freeswitch.org. So would like to confirm will it be Aug. 6th this year? 179 Days10 Hours2 Minutes37 (http://www.cluecon.com/?fs1) select now() + interval '179 days'; ?column? ------------------------------- 2012-08-06 13:47:33.990137+08 (1 row) Thanks. -- About: http://about.me/dujinfang Blog: http://www.dujinfang.com Proj: http://www.freeswitch.org.cn Sent with Sparrow (http://www.sparrowmailapp.com)

3 4

FreeTDM: how to disable callerid?
by Valery Kalinin 10 Feb '12

10 Feb '12

Hi! Work with FreeTDM (latest git) & wanpipe (3.5.24) freetdm.conf.xml: <analog_spans> <span name="FXO"> <param name="enable_callerid" value="false"/> <param name="wait_dialtone_timeout" value="0"/> <param name="dialplan" value="XML"/> <param name="context" value="default"/> </span> </analog_spans> But in log: 2012-02-10 08:23:01.408284 [DEBUG] ftmod_wanpipe.c:1630 [s1c2][1:4] read wanpipe event 7 2012-02-10 08:23:01.408284 [DEBUG] ftmod_analog.c:998 [s1c2][1:4] Received event [RING_START] in state [DOWN] 2012-02-10 08:23:01.408284 [DEBUG] ftmod_analog.c:1026 [s1c2][1:4] Changed state from DOWN to GET_CALLERID 2012-02-10 08:23:01.408284 [DEBUG] ftmod_analog.c:439 [s1c2][1:4] ANALOG CHANNEL thread starting. 2012-02-10 08:23:01.408284 [DEBUG] ftdm_io.c:3133 [s1c2][1:4] Enabled software DTMF detector 2012-02-10 08:23:01.408284 [DEBUG] ftmod_analog.c:459 [s1c2][1:4] Initialized DTMF detection 2012-02-10 08:23:01.408284 [DEBUG] ftmod_analog.c:640 [s1c2][1:4] Completed state change from DOWN to GET_CALLERID 2012-02-10 08:23:01.408284 [DEBUG] ftmod_analog.c:646 [s1c2][1:4] Executing state handler on 1:2 for GET_CALLERID 2012-02-10 08:23:01.428290 [DEBUG] ftmod_wanpipe.c:965 [s1c2][1:4] First packet read stats: Rx queue len: 0, Rx 2012-02-10 08:23:02.168285 [DEBUG] ftmod_wanpipe.c:1630 [s1c2][1:4] read wanpipe event 7 All the same GET_CALLERID is called!

3 2