Sip error 488

363 views
Skip to first unread message

Jim Kusznir

unread,
Dec 2, 2014, 10:36:24 AM12/2/14
to 2600hz...@googlegroups.com
Hi all:

I'm configuring a spa-3102 ATA that I had lying around, ultimately to be an outbound fax device.  However, for starters, I've hooked a POTS phone to it, and just want to get regular calling working.

Whenever I dial on-net (local extensions), all works great.  When I dial off-net (eg, my cell phone), the ATA plays a recording that the call could not be completed due to service provider trouble.  When I dig in further, I see that kazoo has sent sip error 488.

When I researched sip error 488, it seems that generally means codec negiotiation failed.  My codecs are g711u and g711a, and that's enabled on both the ATA and Kazoo (device settings).  I'm not sure where else to look, or how to further address this.  The error itself is kinda vague....

How do I fix this?

Thanks!

Arek Fryz

unread,
Dec 2, 2014, 3:18:06 PM12/2/14
to 2600hz...@googlegroups.com
Did you set device as fax device in kazoo? It may be trying to force T38 re-INVITE and that is not acceptable by your carrier or ATA. I only see 488 with ATAs when carrier did not accept T38 or carrier/kazoo tried to force T38 and adapter did not accept it.

I'd start with freeswitch debug log. Check invite and response to it.



Regards,
Arek Fryz



REMAC ENTERPRISES, LTD.




----
The information transmitted is intended only for the person or entity to
which it is addressed and may contain confidential and/or privileged
material. Any review, retransmission, dissemination or other use of, or
taking of any action in reliance upon, this information by persons or
entities other than the intended recipient is prohibited. If you received
this in error, please contact the sender and delete the material from any
computer.

--
You received this message because you are subscribed to the Google Groups "2600hz-users" group.
To unsubscribe from this group and stop receiving emails from it, send an email to 2600hz-users...@googlegroups.com.
For more options, visit https://groups.google.com/d/optout.

Jim Kusznir

unread,
Dec 3, 2014, 6:05:43 AM12/3/14
to 2600hz...@googlegroups.com
Thanks to a lot of help with people on the IRC channel, I've managed to figure out some logging, and it appears that for some reason kazoo is generating a very broke SDP to send to the carrier.  First, here's the logs from freeswitch, grepping for the carrier leg of the call first:

[root@voip ~]# ./sipify.sh /var/log/freeswitch/debug.log |grep  e587d6fc-7a7c-11e4-9589-61695c765d5d
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [NOTICE] switch_channel.c:1054 New Channel sofia/sipinterface_1/+15093...@sip1.vitelity.net [e587d6fc-7a7c-11e4-9589-61695c765d5d]
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] mod_sofia.c:4591 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State Change CS_NEW -> CS_INIT
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/+15093...@sip1.vitelity.net [BREAK]
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) Running State Change CS_INIT
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:510 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State INIT
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] mod_sofia.c:87 sofia/sipinterface_1/+15093...@sip1.vitelity.net SOFIA INIT
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] sofia_glue.c:1226 sofia/sipinterface_1/+15093...@sip1.vitelity.net sending invite version: 1.4.7  64bit
e587d6fc-7a7c-11e4-9589-61695c765d5d Local SDP:
e587d6fc-7a7c-11e4-9589-61695c765d5d v=0
e587d6fc-7a7c-11e4-9589-61695c765d5d o=FreeSWITCH 1417539697 1417539698 IN IP4 207.166.134.124
e587d6fc-7a7c-11e4-9589-61695c765d5d s=FreeSWITCH
e587d6fc-7a7c-11e4-9589-61695c765d5d c=IN IP4 207.166.134.124
e587d6fc-7a7c-11e4-9589-61695c765d5d t=0 0
e587d6fc-7a7c-11e4-9589-61695c765d5d m=audio 24060 RTP/AVP 0 101 13
e587d6fc-7a7c-11e4-9589-61695c765d5d a=rtpmap:0 PCMU/8000
e587d6fc-7a7c-11e4-9589-61695c765d5d a=rtpmap:101 telephone-event/8000
e587d6fc-7a7c-11e4-9589-61695c765d5d a=fmtp:101 0-16
e587d6fc-7a7c-11e4-9589-61695c765d5d a=ptime:30
e587d6fc-7a7c-11e4-9589-61695c765d5d a=sendrecv
e587d6fc-7a7c-11e4-9589-61695c765d5d m=audio 24060 RTP/AVP 98 99 100 9 0 8 3 102 101 13
e587d6fc-7a7c-11e4-9589-61695c765d5d a=rtpmap:98 opus/48000/2
e587d6fc-7a7c-11e4-9589-61695c765d5d a=fmtp:98 useinbandfec=1; usedtx=1; maxaveragebitrate=30000; ptime=20; minptime=20; maxptime=20; samplerate=48000
e587d6fc-7a7c-11e4-9589-61695c765d5d a=rtpmap:99 G7221/32000
e587d6fc-7a7c-11e4-9589-61695c765d5d a=fmtp:99 bitrate=48000
e587d6fc-7a7c-11e4-9589-61695c765d5d a=rtpmap:100 G7221/16000
e587d6fc-7a7c-11e4-9589-61695c765d5d a=fmtp:100 bitrate=32000
e587d6fc-7a7c-11e4-9589-61695c765d5d a=rtpmap:9 G722/8000
e587d6fc-7a7c-11e4-9589-61695c765d5d a=rtpmap:0 PCMU/8000
e587d6fc-7a7c-11e4-9589-61695c765d5d a=rtpmap:8 PCMA/8000
e587d6fc-7a7c-11e4-9589-61695c765d5d a=rtpmap:3 GSM/8000
e587d6fc-7a7c-11e4-9589-61695c765d5d a=rtpmap:102 SPEEX/8000
e587d6fc-7a7c-11e4-9589-61695c765d5d a=rtpmap:101 telephone-event/8000
e587d6fc-7a7c-11e4-9589-61695c765d5d a=fmtp:101 0-16
e587d6fc-7a7c-11e4-9589-61695c765d5d a=ptime:20
e587d6fc-7a7c-11e4-9589-61695c765d5d a=sendrecv
e587d6fc-7a7c-11e4-9589-61695c765d5d m=video 30056 RTP/AVP 34
e587d6fc-7a7c-11e4-9589-61695c765d5d a=rtpmap:34 H263/90000
e587d6fc-7a7c-11e4-9589-61695c765d5d 
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:40 sofia/sipinterface_1/+15093...@sip1.vitelity.net Standard INIT
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:48 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State Change CS_INIT -> CS_ROUTING
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/+15093...@sip1.vitelity.net [BREAK]
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:510 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State INIT going to sleep
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) Running State Change CS_ROUTING
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State ROUTING
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] mod_sofia.c:123 sofia/sipinterface_1/+15093...@sip1.vitelity.net SOFIA ROUTING
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] switch_ivr_originate.c:67 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State Change CS_ROUTING -> CS_CONSUME_MEDIA
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/+15093...@sip1.vitelity.net [BREAK]
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State ROUTING going to sleep
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) Running State Change CS_CONSUME_MEDIA
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:545 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State CONSUME_MEDIA
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:545 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State CONSUME_MEDIA going to sleep
e587d6fc-7a7c-11e4-9589-61695c765d5d send 1717 bytes to udp/[64.2.142.26]:5060 at 18:42:37.339839:
e587d6fc-7a7c-11e4-9589-61695c765d5d    ------------------------------------------------------------------------
e587d6fc-7a7c-11e4-9589-61695c765d5d    INVITE sip:+15093...@sip1.vitelity.net SIP/2.0
e587d6fc-7a7c-11e4-9589-61695c765d5d    Via: SIP/2.0/UDP 207.166.134.124:11000;rport;branch=z9hG4bKXr44t83rDBrjH
e587d6fc-7a7c-11e4-9589-61695c765d5d    Max-Forwards: 49
e587d6fc-7a7c-11e4-9589-61695c765d5d    From: "PalouseTech FAX" <sip:+15093...@207.166.134.124>;tag=Xy9jH8rp7103j
e587d6fc-7a7c-11e4-9589-61695c765d5d    To: <sip:+15093...@sip1.vitelity.net>
e587d6fc-7a7c-11e4-9589-61695c765d5d    Call-ID: e587d6fc-7a7c-11e4-9589-61695c765d5d
e587d6fc-7a7c-11e4-9589-61695c765d5d    CSeq: 68445814 INVITE
e587d6fc-7a7c-11e4-9589-61695c765d5d    Contact: <sip:mod_...@207.166.134.124:11000>
e587d6fc-7a7c-11e4-9589-61695c765d5d    User-Agent: 2600hz
e587d6fc-7a7c-11e4-9589-61695c765d5d    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
e587d6fc-7a7c-11e4-9589-61695c765d5d    Supported: path, replaces
e587d6fc-7a7c-11e4-9589-61695c765d5d    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
e587d6fc-7a7c-11e4-9589-61695c765d5d    Content-Type: application/sdp
e587d6fc-7a7c-11e4-9589-61695c765d5d    Content-Disposition: session
e587d6fc-7a7c-11e4-9589-61695c765d5d    Content-Length: 750
e587d6fc-7a7c-11e4-9589-61695c765d5d    X-AUTH-IP: 76.178.145.28
e587d6fc-7a7c-11e4-9589-61695c765d5d    X-FS-Support: update_display,send_info
e587d6fc-7a7c-11e4-9589-61695c765d5d    Remote-Party-ID: "PalouseTech FAX" <sip:+15093...@207.166.134.124>;party=calling;screen=yes;privacy=off
e587d6fc-7a7c-11e4-9589-61695c765d5d    
e587d6fc-7a7c-11e4-9589-61695c765d5d    v=0
e587d6fc-7a7c-11e4-9589-61695c765d5d    o=FreeSWITCH 1417539697 1417539698 IN IP4 207.166.134.124
e587d6fc-7a7c-11e4-9589-61695c765d5d    s=FreeSWITCH
e587d6fc-7a7c-11e4-9589-61695c765d5d    c=IN IP4 207.166.134.124
e587d6fc-7a7c-11e4-9589-61695c765d5d    t=0 0
e587d6fc-7a7c-11e4-9589-61695c765d5d    m=audio 24060 RTP/AVP 0 101 13
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=rtpmap:0 PCMU/8000
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=rtpmap:101 telephone-event/8000
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=fmtp:101 0-16
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=ptime:30
e587d6fc-7a7c-11e4-9589-61695c765d5d    m=audio 24060 RTP/AVP 98 99 100 9 0 8 3 102 101 13
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=rtpmap:98 opus/48000/2
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=fmtp:98 useinbandfec=1; usedtx=1; maxaveragebitrate=30000; ptime=20; minptime=20; maxptime=20; samplerate=48000
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=rtpmap:99 G7221/32000
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=fmtp:99 bitrate=48000
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=rtpmap:100 G7221/16000
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=fmtp:100 bitrate=32000
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=rtpmap:9 G722/8000
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=rtpmap:0 PCMU/8000
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=rtpmap:8 PCMA/8000
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=rtpmap:3 GSM/8000
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=rtpmap:102 SPEEX/8000
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=rtpmap:101 telephone-event/8000
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=fmtp:101 0-16
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=ptime:20
e587d6fc-7a7c-11e4-9589-61695c765d5d    m=video 30056 RTP/AVP 34
e587d6fc-7a7c-11e4-9589-61695c765d5d    a=rtpmap:34 H263/90000
e587d6fc-7a7c-11e4-9589-61695c765d5d    ------------------------------------------------------------------------
e587d6fc-7a7c-11e4-9589-61695c765d5d recv 483 bytes from udp/[64.2.142.26]:5060 at 18:42:37.341615:
e587d6fc-7a7c-11e4-9589-61695c765d5d    ------------------------------------------------------------------------
e587d6fc-7a7c-11e4-9589-61695c765d5d    SIP/2.0 488 Not acceptable here
e587d6fc-7a7c-11e4-9589-61695c765d5d    Via: SIP/2.0/UDP 207.166.134.124:11000;branch=z9hG4bKXr44t83rDBrjH;received=207.166.134.124;rport=11000
e587d6fc-7a7c-11e4-9589-61695c765d5d    From: "PalouseTech FAX" <sip:+15093...@207.166.134.124>;tag=Xy9jH8rp7103j
e587d6fc-7a7c-11e4-9589-61695c765d5d    To: <sip:+15093...@sip1.vitelity.net>;tag=as6d5597db
e587d6fc-7a7c-11e4-9589-61695c765d5d    Call-ID: e587d6fc-7a7c-11e4-9589-61695c765d5d
e587d6fc-7a7c-11e4-9589-61695c765d5d    CSeq: 68445814 INVITE
e587d6fc-7a7c-11e4-9589-61695c765d5d    User-Agent: packetrino
e587d6fc-7a7c-11e4-9589-61695c765d5d    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
e587d6fc-7a7c-11e4-9589-61695c765d5d    Supported: replaces
e587d6fc-7a7c-11e4-9589-61695c765d5d    Content-Length: 0
e587d6fc-7a7c-11e4-9589-61695c765d5d    
e587d6fc-7a7c-11e4-9589-61695c765d5d    ------------------------------------------------------------------------
e587d6fc-7a7c-11e4-9589-61695c765d5d send 363 bytes to udp/[64.2.142.26]:5060 at 18:42:37.341726:
e587d6fc-7a7c-11e4-9589-61695c765d5d    ------------------------------------------------------------------------
e587d6fc-7a7c-11e4-9589-61695c765d5d    ACK sip:+15093...@sip1.vitelity.net SIP/2.0
e587d6fc-7a7c-11e4-9589-61695c765d5d    Via: SIP/2.0/UDP 207.166.134.124:11000;rport;branch=z9hG4bKXr44t83rDBrjH
e587d6fc-7a7c-11e4-9589-61695c765d5d    Max-Forwards: 49
e587d6fc-7a7c-11e4-9589-61695c765d5d    From: "PalouseTech FAX" <sip:+15093...@207.166.134.124>;tag=Xy9jH8rp7103j
e587d6fc-7a7c-11e4-9589-61695c765d5d    To: <sip:+15093...@sip1.vitelity.net>;tag=as6d5597db
e587d6fc-7a7c-11e4-9589-61695c765d5d    Call-ID: e587d6fc-7a7c-11e4-9589-61695c765d5d
e587d6fc-7a7c-11e4-9589-61695c765d5d    CSeq: 68445814 ACK
e587d6fc-7a7c-11e4-9589-61695c765d5d    Content-Length: 0
e587d6fc-7a7c-11e4-9589-61695c765d5d    
e587d6fc-7a7c-11e4-9589-61695c765d5d    ------------------------------------------------------------------------
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/+15093...@sip1.vitelity.net [BREAK]
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/+15093...@sip1.vitelity.net [BREAK]
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/+15093...@sip1.vitelity.net [BREAK]
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/+15093...@sip1.vitelity.net [BREAK]
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] sofia.c:6413 Channel sofia/sipinterface_1/+15093...@sip1.vitelity.net entering state [calling][0]
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] sofia.c:6413 Channel sofia/sipinterface_1/+15093...@sip1.vitelity.net entering state [terminated][488]
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [NOTICE] sofia.c:7299 Hangup sofia/sipinterface_1/+15093...@sip1.vitelity.net [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION]
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_channel.c:3217 Send signal sofia/sipinterface_1/+15093...@sip1.vitelity.net [KILL]
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/+15093...@sip1.vitelity.net [BREAK]
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) Running State Change CS_HANGUP
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:733 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) Callstate Change DOWN -> HANGUP
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:735 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State HANGUP
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] mod_sofia.c:413 Channel sofia/sipinterface_1/+15093...@sip1.vitelity.net hanging up, cause: INCOMPATIBLE_DESTINATION
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:58 sofia/sipinterface_1/+15093...@sip1.vitelity.net Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:735 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State HANGUP going to sleep
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:502 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State Change CS_HANGUP -> CS_REPORTING
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/+15093...@sip1.vitelity.net [BREAK]
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) Running State Change CS_REPORTING
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:821 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State REPORTING
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:102 sofia/sipinterface_1/+15093...@sip1.vitelity.net Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:821 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State REPORTING going to sleep
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:496 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State Change CS_REPORTING -> CS_DESTROY
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/+15093...@sip1.vitelity.net [BREAK]
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_session.c:1614 Session 3763 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) Locked, Waiting on external entities
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [NOTICE] switch_core_session.c:1632 Session 3763 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) Ended
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [NOTICE] switch_core_session.c:1636 Close Channel sofia/sipinterface_1/+15093...@sip1.vitelity.net [CS_DESTROY]
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:624 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) Running State Change CS_DESTROY
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:634 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State DESTROY
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] mod_sofia.c:323 sofia/sipinterface_1/+15093...@sip1.vitelity.net SOFIA DESTROY
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:109 sofia/sipinterface_1/+15093...@sip1.vitelity.net Standard DESTROY
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:634 (sofia/sipinterface_1/+15093...@sip1.vitelity.net) State DESTROY going to sleep
2014-12-02 18:42:37.359783 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(e587d6fc-7a7c-11e4-9589-61695c765d5d ecallmgr_Channel-Authorized=true;ecallmgr_Global-Resource=true;ecallmgr_Reseller-ID=73ba02f305fd80850bd1246529aa33b0;ecallmgr_Account-ID=73ba02f305fd80850bd1246529aa33b0)

--------------
--------------
Now the ATA side of the call log:
--------------
--------------

Dec  2 18:42:37 voip 2600hz[960]: |4738178f...@192.168.4.131|ecallmgr_call_control:705 (<0.17722.70>) added leg e587d6fc-7a7c-11e4-9589-61695c765d5d to call
[root@voip ~]# ./sipify.sh /var/log/freeswitch/debug.log |grep  4738178f-ad2ea12f
4738178f...@192.168.4.131 recv 1018 bytes from udp/[207.166.134.124]:5060 at 18:42:36.433817:
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131    Record-Route: <sip:207.166.134.124;lr=on;ftag=af40a40fd1ab7eafo0>
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bKc979.eb72fad3.0
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 76.178.145.28:1024;branch=z9hG4bK-7d25adaf
4738178f...@192.168.4.131    From: FAX <sip:10...@palousetech.com>;tag=af40a40fd1ab7eafo0
4738178f...@192.168.4.131    Remote-Party-ID: FAX <sip:10...@palousetech.com>;screen=yes;party=calling
4738178f...@192.168.4.131    CSeq: 101 INVITE
4738178f...@192.168.4.131    Max-Forwards: 50
4738178f...@192.168.4.131    Expires: 240
4738178f...@192.168.4.131    User-Agent: Linksys/SPA3102-5.2.13(GW002)
4738178f...@192.168.4.131    Content-Length: 253
4738178f...@192.168.4.131    Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER
4738178f...@192.168.4.131    Supported: x-sipura, replaces
4738178f...@192.168.4.131    Content-Type: application/sdp
4738178f...@192.168.4.131    X-AUTH-IP: 76.178.145.28
4738178f...@192.168.4.131    o=- 47347 47347 IN IP4 76.178.145.28
4738178f...@192.168.4.131    c=IN IP4 76.178.145.28
4738178f...@192.168.4.131    m=audio 16390 RTP/AVP 0 100 101
4738178f...@192.168.4.131    a=rtpmap:0 PCMU/8000
4738178f...@192.168.4.131    a=rtpmap:100 NSE/8000
4738178f...@192.168.4.131    a=fmtp:100 192-193
4738178f...@192.168.4.131    a=rtpmap:101 telephone-event/8000
4738178f...@192.168.4.131    a=fmtp:101 0-15
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131 send 412 bytes to udp/[207.166.134.124]:5060 at 18:42:36.434274:
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131    SIP/2.0 100 Trying
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bKc979.eb72fad3.0
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 76.178.145.28:1024;branch=z9hG4bK-7d25adaf
4738178f...@192.168.4.131    Record-Route: <sip:207.166.134.124;lr=on;ftag=af40a40fd1ab7eafo0>
4738178f...@192.168.4.131    From: FAX <sip:10...@palousetech.com>;tag=af40a40fd1ab7eafo0
4738178f...@192.168.4.131    CSeq: 101 INVITE
4738178f...@192.168.4.131    User-Agent: 2600hz
4738178f...@192.168.4.131    Content-Length: 0
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131 2014-12-02 18:42:36.419480 [NOTICE] switch_channel.c:1054 New Channel sofia/sipinterface_1/10...@palousetech.com [4738178f...@192.168.4.131]
4738178f...@192.168.4.131 2014-12-02 18:42:36.419480 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:36.419480 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:36.419480 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/10...@palousetech.com) Running State Change CS_NEW
4738178f...@192.168.4.131 2014-12-02 18:42:36.419480 [DEBUG] sofia.c:8703 sofia/sipinterface_1/10...@palousetech.com receiving invite from 207.166.134.124:5060 version: 1.4.7  64bit
2014-12-02 18:42:36.419480 [DEBUG] sofia_reg.c:3345 sofia pre-register disabled4738...@192.168.4.131 2014-12-02 18:42:36.419480 [DEBUG] switch_core_state_machine.c:489 (sofia/sipinterface_1/10...@palousetech.com) State NEW
4738178f...@192.168.4.131 send 844 bytes to udp/[207.166.134.124]:5060 at 18:42:36.436571:
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131    SIP/2.0 407 Proxy Authentication Required
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bKc979.eb72fad3.0
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 76.178.145.28:1024;branch=z9hG4bK-7d25adaf
4738178f...@192.168.4.131    From: FAX <sip:10...@palousetech.com>;tag=af40a40fd1ab7eafo0
4738178f...@192.168.4.131    CSeq: 101 INVITE
4738178f...@192.168.4.131    User-Agent: 2600hz
4738178f...@192.168.4.131    Accept: application/sdp
4738178f...@192.168.4.131    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
4738178f...@192.168.4.131    Supported: path, replaces
4738178f...@192.168.4.131    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
4738178f...@192.168.4.131    Proxy-Authenticate: Digest realm="palousetech.com", nonce="e4fe3a00-7a7c-11e4-9578-61695c765d5d", algorithm=MD5, qop="auth"
4738178f...@192.168.4.131    Content-Length: 0
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131 recv 321 bytes from udp/[207.166.134.124]:5060 at 18:42:36.437089:
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bKc979.eb72fad3.0
4738178f...@192.168.4.131    From: FAX <sip:10...@palousetech.com>;tag=af40a40fd1ab7eafo0
4738178f...@192.168.4.131    CSeq: 101 ACK
4738178f...@192.168.4.131    Max-Forwards: 50
4738178f...@192.168.4.131    Content-Length: 0
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131 2014-12-02 18:42:36.419480 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
2014-12-02 18:42:36.419480 [DEBUG] sofia.c:2033 detaching session 4738178f...@192.168.4.131
4738178f...@192.168.4.131 recv 1266 bytes from udp/[207.166.134.124]:5060 at 18:42:36.504369:
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131    Record-Route: <sip:207.166.134.124;lr=on;ftag=af40a40fd1ab7eafo0>
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bK9979.a2c78cd7.0
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 76.178.145.28:1024;branch=z9hG4bK-ed7be6db
4738178f...@192.168.4.131    From: FAX <sip:10...@palousetech.com>;tag=af40a40fd1ab7eafo0
4738178f...@192.168.4.131    Remote-Party-ID: FAX <sip:10...@palousetech.com>;screen=yes;party=calling
4738178f...@192.168.4.131    CSeq: 102 INVITE
4738178f...@192.168.4.131    Max-Forwards: 50
4738178f...@192.168.4.131    Proxy-Authorization: Digest username="1099",realm="palousetech.com",nonce="e4fe3a00-7a7c-11e4-9578-61695c765d5d",uri="sip:50933...@palousetech.com",algorithm=MD5,response="2f8c7fb0f156f8f89381ac73e0126742",qop=auth,nc=00000001,cnonce="a8a10196"
4738178f...@192.168.4.131    Expires: 240
4738178f...@192.168.4.131    User-Agent: Linksys/SPA3102-5.2.13(GW002)
4738178f...@192.168.4.131    Content-Length: 253
4738178f...@192.168.4.131    Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER
4738178f...@192.168.4.131    Supported: x-sipura, replaces
4738178f...@192.168.4.131    Content-Type: application/sdp
4738178f...@192.168.4.131    X-AUTH-IP: 76.178.145.28
4738178f...@192.168.4.131    o=- 47347 47347 IN IP4 76.178.145.28
4738178f...@192.168.4.131    c=IN IP4 76.178.145.28
4738178f...@192.168.4.131    m=audio 16390 RTP/AVP 0 100 101
4738178f...@192.168.4.131    a=rtpmap:0 PCMU/8000
4738178f...@192.168.4.131    a=rtpmap:100 NSE/8000
4738178f...@192.168.4.131    a=fmtp:100 192-193
4738178f...@192.168.4.131    a=rtpmap:101 telephone-event/8000
4738178f...@192.168.4.131    a=fmtp:101 0-15
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131 send 412 bytes to udp/[207.166.134.124]:5060 at 18:42:36.504784:
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131    SIP/2.0 100 Trying
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bK9979.a2c78cd7.0
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 76.178.145.28:1024;branch=z9hG4bK-ed7be6db
4738178f...@192.168.4.131    Record-Route: <sip:207.166.134.124;lr=on;ftag=af40a40fd1ab7eafo0>
4738178f...@192.168.4.131    From: FAX <sip:10...@palousetech.com>;tag=af40a40fd1ab7eafo0
4738178f...@192.168.4.131    CSeq: 102 INVITE
4738178f...@192.168.4.131    User-Agent: 2600hz
4738178f...@192.168.4.131    Content-Length: 0
4738178f...@192.168.4.131    ------------------------------------------------------------------------
2014-12-02 18:42:36.499451 [DEBUG] sofia.c:2138 Re-attaching to session 4738178f...@192.168.4.131
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] sofia.c:8703 sofia/sipinterface_1/10...@palousetech.com receiving invite from 207.166.134.124:5060 version: 1.4.7  64bit
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] sofia.c:6413 Channel sofia/sipinterface_1/10...@palousetech.com entering state [received][100]
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] sofia.c:6423 Remote SDP:
4738178f...@192.168.4.131 o=- 47347 47347 IN IP4 76.178.145.28
4738178f...@192.168.4.131 c=IN IP4 76.178.145.28
4738178f...@192.168.4.131 m=audio 16390 RTP/AVP 0 100 101
4738178f...@192.168.4.131 a=rtpmap:0 PCMU/8000
4738178f...@192.168.4.131 a=rtpmap:100 NSE/8000
4738178f...@192.168.4.131 a=fmtp:100 192-193
4738178f...@192.168.4.131 a=rtpmap:101 telephone-event/8000
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] sofia.c:6678 (sofia/sipinterface_1/10...@palousetech.com) State Change CS_NEW -> CS_INIT
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/10...@palousetech.com) Running State Change CS_INIT
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] switch_core_state_machine.c:510 (sofia/sipinterface_1/10...@palousetech.com) State INIT
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] mod_sofia.c:87 sofia/sipinterface_1/10...@palousetech.com SOFIA INIT
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] switch_core_state_machine.c:40 sofia/sipinterface_1/10...@palousetech.com Standard INIT
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] switch_core_state_machine.c:48 (sofia/sipinterface_1/10...@palousetech.com) State Change CS_INIT -> CS_ROUTING
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] switch_core_state_machine.c:510 (sofia/sipinterface_1/10...@palousetech.com) State INIT going to sleep
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/10...@palousetech.com) Running State Change CS_ROUTING
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] switch_channel.c:2179 (sofia/sipinterface_1/10...@palousetech.com) Callstate Change DOWN -> RINGING
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/10...@palousetech.com) State ROUTING
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] mod_sofia.c:123 sofia/sipinterface_1/10...@palousetech.com SOFIA ROUTING
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [DEBUG] switch_core_state_machine.c:164 sofia/sipinterface_1/10...@palousetech.com Standard ROUTING
4738178f...@192.168.4.131 2014-12-02 18:42:36.499451 [INFO] mod_dialplan_xml.c:558 Processing FAX <1099>->5093385920 in context context_2
4738178f...@192.168.4.131 Dialplan: sofia/sipinterface_1/10...@palousetech.com parsing [context_2->park] continue=false
4738178f...@192.168.4.131 |--- Dialplan: Processing recursive conditions level:1 [park_recur_1] require-nested=TRUE
4738178f...@192.168.4.131 |--- Dialplan: sofia/sipinterface_1/10...@palousetech.com Regex (PASS) [park_recur_1] variable_ecallmgr_Bridge-ID() =~ /^$/ break=on-false
4738178f...@192.168.4.131 |--- Dialplan: sofia/sipinterface_1/10...@palousetech.com Action export(ecallmgr_Bridge-ID=${UUID})
4738178f...@192.168.4.131 Dialplan: sofia/sipinterface_1/10...@palousetech.com Absolute Condition [park]
4738178f...@192.168.4.131 Dialplan: sofia/sipinterface_1/10...@palousetech.com Action log(NOTICE log|${uuid}|ecal...@voip.palousetech.com won call control)
4738178f...@192.168.4.131 Dialplan: sofia/sipinterface_1/10...@palousetech.com Action export(ecallmgr_Ecallmgr-Node=ecal...@voip.palousetech.com)
4738178f...@192.168.4.131 Dialplan: sofia/sipinterface_1/10...@palousetech.com Action set(ringback=%(2000,4000,440,480))
4738178f...@192.168.4.131 Dialplan: sofia/sipinterface_1/10...@palousetech.com Action set(transfer_ringback=%(2000,4000,440,480))
4738178f...@192.168.4.131 Dialplan: sofia/sipinterface_1/10...@palousetech.com Action park()
4738178f...@192.168.4.131 2014-12-02 18:42:36.683328 [DEBUG] switch_core_state_machine.c:214 (sofia/sipinterface_1/10...@palousetech.com) State Change CS_ROUTING -> CS_EXECUTE
4738178f...@192.168.4.131 2014-12-02 18:42:36.683328 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:36.683328 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/10...@palousetech.com) State ROUTING going to sleep
4738178f...@192.168.4.131 2014-12-02 18:42:36.683328 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/10...@palousetech.com) Running State Change CS_EXECUTE
4738178f...@192.168.4.131 2014-12-02 18:42:36.683328 [DEBUG] switch_core_state_machine.c:533 (sofia/sipinterface_1/10...@palousetech.com) State EXECUTE
4738178f...@192.168.4.131 2014-12-02 18:42:36.683328 [DEBUG] mod_sofia.c:178 sofia/sipinterface_1/10...@palousetech.com SOFIA EXECUTE
4738178f...@192.168.4.131 2014-12-02 18:42:36.683328 [DEBUG] switch_core_state_machine.c:256 sofia/sipinterface_1/10...@palousetech.com Standard EXECUTE
4738178f...@192.168.4.131 EXECUTE sofia/sipinterface_1/10...@palousetech.com export(ecallmgr_Bridge-ID=4738178f...@192.168.4.131)
4738178f...@192.168.4.131 2014-12-02 18:42:36.683328 [DEBUG] switch_channel.c:1246 EXPORT (export_vars) [ecallmgr_Bridge-ID]=[4738178f...@192.168.4.131]
4738178f...@192.168.4.131 2014-12-02 18:42:36.683328 [NOTICE] mod_dptools.c:1667 log|4738178f...@192.168.4.131|ecal...@voip.palousetech.com won call control
4738178f...@192.168.4.131 EXECUTE sofia/sipinterface_1/10...@palousetech.com export(ecallmgr_Ecallmgr-Node=ecal...@voip.palousetech.com)
4738178f...@192.168.4.131 2014-12-02 18:42:36.683328 [DEBUG] switch_channel.c:1246 EXPORT (export_vars) [ecallmgr_Ecallmgr-Node]=[ecal...@voip.palousetech.com]
4738178f...@192.168.4.131 EXECUTE sofia/sipinterface_1/10...@palousetech.com set(ringback=%(2000,4000,440,480))
4738178f...@192.168.4.131 2014-12-02 18:42:36.683328 [DEBUG] mod_dptools.c:1435 sofia/sipinterface_1/10...@palousetech.com SET [ringback]=[%(2000,4000,440,480)]
4738178f...@192.168.4.131 EXECUTE sofia/sipinterface_1/10...@palousetech.com set(transfer_ringback=%(2000,4000,440,480))
4738178f...@192.168.4.131 2014-12-02 18:42:36.683328 [DEBUG] mod_dptools.c:1435 sofia/sipinterface_1/10...@palousetech.com SET [transfer_ringback]=[%(2000,4000,440,480)]
4738178f...@192.168.4.131 EXECUTE sofia/sipinterface_1/10...@palousetech.com park()
2014-12-02 18:42:36.920033 [NOTICE] kazoo_node.c:285 log|4738178f...@192.168.4.131|executing export hold_music=silence_stream://300000 
4738178f...@192.168.4.131 2014-12-02 18:42:36.920033 [DEBUG] switch_core_session.c:1187 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:36.939856 [DEBUG] switch_ivr.c:614 sofia/sipinterface_1/10...@palousetech.com Command Execute export(hold_music=silence_stream://300000)
4738178f...@192.168.4.131 EXECUTE sofia/sipinterface_1/10...@palousetech.com export(hold_music=silence_stream://300000)
4738178f...@192.168.4.131 2014-12-02 18:42:36.939856 [DEBUG] switch_channel.c:1246 EXPORT (export_vars) [hold_music]=[silence_stream://300000]
2014-12-02 18:42:37.119462 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(4738178f...@192.168.4.131 effective_caller_id_name=Fax User;effective_caller_id_number=1099;ecallmgr_Fetch-ID=e50b0686-7a7c-11e4-957a-61695c765d5d;ecallmgr_Username=1099;ecallmgr_Realm=palousetech.com;ecallmgr_Account-ID=73ba02f305fd80850bd1246529aa33b0;ecallmgr_Authorizing-ID=1ce5aeb8b9c6f2a151560b1534645cdc;ecallmgr_Authorizing-Type=device;ecallmgr_Owner-ID=9cb2d4aacd57002fc15513d0263893c2;ecallmgr_Account-Realm=palousetech.com;ecallmgr_Account-Name=PalouseTech;presence_id=10...@palousetech.com)
2014-12-02 18:42:37.319613 [NOTICE] kazoo_node.c:302 log|4738178f...@192.168.4.131|building xferext extension: set ignore_display_updates=true
2014-12-02 18:42:37.319613 [NOTICE] kazoo_node.c:302 log|4738178f...@192.168.4.131|building xferext extension: set continue_on_fail=true
2014-12-02 18:42:37.319613 [NOTICE] kazoo_node.c:302 log|4738178f...@192.168.4.131|building xferext extension: export sip_redirect_context=context_2
2014-12-02 18:42:37.319613 [NOTICE] kazoo_node.c:302 log|4738178f...@192.168.4.131|building xferext extension: export ecallmgr_Inception=${ecallmgr_Inception}
2014-12-02 18:42:37.319613 [NOTICE] kazoo_node.c:302 log|4738178f...@192.168.4.131|building xferext extension: bridge {ignore_display_updates='true',ecallmgr_Account-ID='73ba02f305fd80850bd1246529aa33b0',ecallmgr_Reseller-ID='73ba02f305fd80850bd1246529aa33b0',effective_caller_id_number='+15093397676',effective_caller_id_name='PalouseTech FAX',origination_caller_id_number='+15093397676',origination_caller_id_name='PalouseTech FAX',ignore_early_media='false',local_var_clobber='true'}[sip_auth_password='password',sip_auth_username='kusznir',ecallmgr_Global-Resource='true',ecallmgr_Resource-ID='carrier-Vitelity',ecallmgr_Original-Number='5093385920',leg_progress_timeout='10',effective_callee_id_number='5093385920',effective_callee_id_name='5093385920']sofia/sipinterface_1/+15093...@sip1.vitelity.net
2014-12-02 18:42:37.319613 [NOTICE] kazoo_node.c:302 log|4738178f...@192.168.4.131|building xferext extension: event Event-Name=CUSTOM,Event-Subclass=whistle::masquerade,whistle_event_name=CHANNEL_EXECUTE_COMPLETE,whistle_application_name=bridge
2014-12-02 18:42:37.319613 [NOTICE] kazoo_node.c:302 log|4738178f...@192.168.4.131|building xferext extension: park 
2014-12-02 18:42:37.319613 [NOTICE] kazoo_node.c:305 log|4738178f...@192.168.4.131|transfered call to xferext extension
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_core_session.c:1187 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_channel.c:3098 (sofia/sipinterface_1/10...@palousetech.com) State Change CS_EXECUTE -> CS_ROUTING
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:533 (sofia/sipinterface_1/10...@palousetech.com) State EXECUTE going to sleep
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/10...@palousetech.com) Running State Change CS_ROUTING
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/10...@palousetech.com) State ROUTING
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] mod_sofia.c:123 sofia/sipinterface_1/10...@palousetech.com SOFIA ROUTING
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:164 sofia/sipinterface_1/10...@palousetech.com Standard ROUTING
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:184 (sofia/sipinterface_1/10...@palousetech.com) State Change CS_ROUTING -> CS_EXECUTE
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:526 (sofia/sipinterface_1/10...@palousetech.com) State ROUTING going to sleep
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/10...@palousetech.com) Running State Change CS_EXECUTE
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:533 (sofia/sipinterface_1/10...@palousetech.com) State EXECUTE
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] mod_sofia.c:178 sofia/sipinterface_1/10...@palousetech.com SOFIA EXECUTE
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:256 sofia/sipinterface_1/10...@palousetech.com Standard EXECUTE
4738178f...@192.168.4.131 EXECUTE sofia/sipinterface_1/10...@palousetech.com set(ignore_display_updates=true)
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] mod_dptools.c:1435 sofia/sipinterface_1/10...@palousetech.com SET [ignore_display_updates]=[true]
4738178f...@192.168.4.131 EXECUTE sofia/sipinterface_1/10...@palousetech.com set(continue_on_fail=true)
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] mod_dptools.c:1435 sofia/sipinterface_1/10...@palousetech.com SET [continue_on_fail]=[true]
4738178f...@192.168.4.131 EXECUTE sofia/sipinterface_1/10...@palousetech.com export(sip_redirect_context=context_2)
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_channel.c:1246 EXPORT (export_vars) [sip_redirect_context]=[context_2]
4738178f...@192.168.4.131 EXECUTE sofia/sipinterface_1/10...@palousetech.com export(ecallmgr_Inception=)
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_channel.c:1246 EXPORT (export_vars) [ecallmgr_Inception]=[UNDEF]
4738178f...@192.168.4.131 EXECUTE sofia/sipinterface_1/10...@palousetech.com bridge({ignore_display_updates=true,ecallmgr_Account-ID=73ba02f305fd80850bd1246529aa33b0,ecallmgr_Reseller-ID=73ba02f305fd80850bd1246529aa33b0,effective_caller_id_number=+15093397676,effective_caller_id_name=PalouseTech FAX,origination_caller_id_number=+15093397676,origination_caller_id_name=PalouseTech FAX,ignore_early_media=false,local_var_clobber=true}[sip_auth_password=password,sip_auth_username=kusznir,ecallmgr_Global-Resource=true,ecallmgr_Resource-ID=carrier-Vitelity,ecallmgr_Original-Number=5093385920,leg_progress_timeout=10,effective_callee_id_number=5093385920,effective_callee_id_name=5093385920]sofia/sipinterface_1/+15093...@sip1.vitelity.net)
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_channel.c:1200 sofia/sipinterface_1/10...@palousetech.com EXPORTING[export_vars] [ecallmgr_Bridge-ID]=[4738178f...@192.168.4.131] to event
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_channel.c:1200 sofia/sipinterface_1/10...@palousetech.com EXPORTING[export_vars] [ecallmgr_Ecallmgr-Node]=[ecal...@voip.palousetech.com] to event
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_channel.c:1200 sofia/sipinterface_1/10...@palousetech.com EXPORTING[export_vars] [hold_music]=[silence_stream://300000] to event
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_channel.c:1200 sofia/sipinterface_1/10...@palousetech.com EXPORTING[export_vars] [sip_redirect_context]=[context_2] to event
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_ivr_originate.c:2079 Parsing global variables
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_ivr_originate.c:2526 Parsing session specific variables
4738178f...@192.168.4.131 2014-12-02 18:42:37.319613 [DEBUG] switch_ivr_originate.c:2829 sofia/sipinterface_1/+15093...@sip1.vitelity.net Setting leg progress timeout to 10
4738178f...@192.168.4.131 2014-12-02 18:42:37.339558 [DEBUG] switch_ivr_originate.c:3695 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION]
4738178f...@192.168.4.131 2014-12-02 18:42:37.339558 [INFO] mod_dptools.c:3277 Originate Failed.  Cause: INCOMPATIBLE_DESTINATION
4738178f...@192.168.4.131 EXECUTE sofia/sipinterface_1/10...@palousetech.com event(Event-Name=CUSTOM,Event-Subclass=whistle::masquerade,whistle_event_name=CHANNEL_EXECUTE_COMPLETE,whistle_application_name=bridge)
4738178f...@192.168.4.131 EXECUTE sofia/sipinterface_1/10...@palousetech.com park()
2014-12-02 18:42:37.399544 [NOTICE] kazoo_node.c:285 log|4738178f...@192.168.4.131|executing pre_answer  
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_session.c:1187 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_ivr.c:614 sofia/sipinterface_1/10...@palousetech.com Command Execute pre_answer()
4738178f...@192.168.4.131 EXECUTE sofia/sipinterface_1/10...@palousetech.com pre_answer()
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[opus:116:48000:20:0:1]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[G7221:115:32000:20:48000:1]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[G7221:107:16000:20:32000:1]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[G722:9:8000:20:64000:1]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[PCMU:0:8000:20:64000:1]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3509 Audio Codec Compare [PCMU:0:8000:20:64000:1] is saved as a near-match
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[PCMA:8:8000:20:64000:1]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[GSM:3:8000:20:13200:1]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[SPEEX:99:8000:20:24600:1]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [NSE:100:8000:30:0:1]/[opus:116:48000:20:0:1]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [NSE:100:8000:30:0:1]/[G7221:115:32000:20:48000:1]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [NSE:100:8000:30:0:1]/[G7221:107:16000:20:32000:1]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [NSE:100:8000:30:0:1]/[G722:9:8000:20:64000:1]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [NSE:100:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [NSE:100:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [NSE:100:8000:30:0:1]/[GSM:3:8000:20:13200:1]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [NSE:100:8000:30:0:1]/[SPEEX:99:8000:20:24600:1]
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3395 Set telephone-event payload to 101
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3579 Substituting codec PCMU@30i@8000h@1c
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:2352 Set Codec sofia/sipinterface_1/10...@palousetech.com PCMU/8000 30 ms 240 samples 64000 bits 1 channels
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_codec.c:111 sofia/sipinterface_1/10...@palousetech.com Original read codec set to PCMU:0
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3719 Set 2833 dtmf send/recv payload to 101
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:4939 AUDIO RTP [sofia/sipinterface_1/10...@palousetech.com] 207.166.134.124 port 16836 -> 76.178.145.28 port 16390 codec: 0 ms: 30
4738178f...@192.168.4.131 2014-12-02 18:42:37.399544 [DEBUG] switch_rtp.c:3383 Starting timer [soft] 240 bytes per 30ms
4738178f...@192.168.4.131 2014-12-02 18:42:37.419782 [DEBUG] switch_core_media.c:5287 Set 2833 dtmf send payload to 101
4738178f...@192.168.4.131 2014-12-02 18:42:37.419782 [DEBUG] switch_core_media.c:5293 Set 2833 dtmf receive payload to 101
4738178f...@192.168.4.131 2014-12-02 18:42:37.419782 [NOTICE] sofia_media.c:92 Pre-Answer sofia/sipinterface_1/10...@palousetech.com!
4738178f...@192.168.4.131 2014-12-02 18:42:37.419782 [DEBUG] switch_channel.c:3394 (sofia/sipinterface_1/10...@palousetech.com) Callstate Change RINGING -> EARLY
4738178f...@192.168.4.131 2014-12-02 18:42:37.419782 [DEBUG] mod_sofia.c:2228 Ring SDP:
4738178f...@192.168.4.131 o=FreeSWITCH 1417546921 1417546922 IN IP4 207.166.134.124
4738178f...@192.168.4.131 c=IN IP4 207.166.134.124
4738178f...@192.168.4.131 m=audio 16836 RTP/AVP 0 101
4738178f...@192.168.4.131 a=rtpmap:0 PCMU/8000
4738178f...@192.168.4.131 a=rtpmap:101 telephone-event/8000
4738178f...@192.168.4.131 2014-12-02 18:42:37.419782 [DEBUG] switch_core_session.c:907 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:37.419782 [DEBUG] switch_ivr.c:961 Codec Activated L16@8000hz 1 channels 30ms
4738178f...@192.168.4.131 send 1225 bytes to udp/[207.166.134.124]:5060 at 18:42:37.431599:
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131    SIP/2.0 183 Session Progress
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bK9979.a2c78cd7.0
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 76.178.145.28:1024;branch=z9hG4bK-ed7be6db
4738178f...@192.168.4.131    Record-Route: <sip:207.166.134.124;lr=on;ftag=af40a40fd1ab7eafo0>
4738178f...@192.168.4.131    From: FAX <sip:10...@palousetech.com>;tag=af40a40fd1ab7eafo0
4738178f...@192.168.4.131    CSeq: 102 INVITE
4738178f...@192.168.4.131    Contact: <sip:5093385920@207.166.134.124:11000;transport=udp>
4738178f...@192.168.4.131    User-Agent: 2600hz
4738178f...@192.168.4.131    Accept: application/sdp
4738178f...@192.168.4.131    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
4738178f...@192.168.4.131    Supported: path, replaces
4738178f...@192.168.4.131    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
4738178f...@192.168.4.131    Content-Type: application/sdp
4738178f...@192.168.4.131    Content-Disposition: session
4738178f...@192.168.4.131    Content-Length: 226
4738178f...@192.168.4.131    Remote-Party-ID: "5093385920" <sip:50933...@palousetech.com>;party=calling;privacy=off;screen=no
4738178f...@192.168.4.131    o=FreeSWITCH 1417546921 1417546922 IN IP4 207.166.134.124
4738178f...@192.168.4.131    s=FreeSWITCH
4738178f...@192.168.4.131    c=IN IP4 207.166.134.124
4738178f...@192.168.4.131    m=audio 16836 RTP/AVP 0 101
4738178f...@192.168.4.131    a=rtpmap:0 PCMU/8000
4738178f...@192.168.4.131    a=rtpmap:101 telephone-event/8000
4738178f...@192.168.4.131    a=fmtp:101 0-16
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131 2014-12-02 18:42:37.419782 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:37.419782 [DEBUG] sofia.c:6413 Channel sofia/sipinterface_1/10...@palousetech.com entering state [early][183]
2014-12-02 18:42:37.600043 [NOTICE] kazoo_node.c:285 log|4738178f...@192.168.4.131|executing playback ${http_get(http://127.0.0.1:15984/system_media/fault-facility_trouble/fault-facility_trouble.wav)} 
4738178f...@192.168.4.131 2014-12-02 18:42:37.600043 [DEBUG] switch_core_session.c:1187 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:37.600043 [DEBUG] switch_rtp.c:5617 Correct ip/port confirmed.
4738178f...@192.168.4.131 2014-12-02 18:42:37.600043 [DEBUG] switch_ivr.c:614 sofia/sipinterface_1/10...@palousetech.com Command Execute playback(${http_get(http://127.0.0.1:15984/system_media/fault-facility_trouble/fault-facility_trouble.wav)})
4738178f...@192.168.4.131 2014-12-02 18:42:37.600043 [DEBUG] mod_http_cache.c:523 Locked cache
4738178f...@192.168.4.131 2014-12-02 18:42:37.600043 [DEBUG] mod_http_cache.c:651 Cache HIT: size = 109 (48 MB), hit ratio = 256/365
4738178f...@192.168.4.131 2014-12-02 18:42:37.600043 [DEBUG] mod_http_cache.c:534 Unlocked cache
4738178f...@192.168.4.131 EXECUTE sofia/sipinterface_1/10...@palousetech.com playback(/usr/share/freeswitch/http_cache/f0/64bdbc-79e4-11e4-8588-61695c765d5d.wav)
4738178f...@192.168.4.131 2014-12-02 18:42:37.600043 [DEBUG] switch_ivr_play_say.c:1306 Codec Activated L16@8000hz 1 channels 30ms
4738178f...@192.168.4.131 recv 309 bytes from udp/[207.166.134.124]:5060 at 18:42:41.447744:
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bK9979.a2c78cd7.0
4738178f...@192.168.4.131    From: FAX <sip:10...@palousetech.com>;tag=af40a40fd1ab7eafo0
4738178f...@192.168.4.131    CSeq: 102 CANCEL
4738178f...@192.168.4.131    Max-Forwards: 50
4738178f...@192.168.4.131    Content-Length: 0
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131 send 278 bytes to udp/[207.166.134.124]:5060 at 18:42:41.447949:
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131    SIP/2.0 200 OK
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bK9979.a2c78cd7.0
4738178f...@192.168.4.131    From: FAX <sip:10...@palousetech.com>;tag=af40a40fd1ab7eafo0
4738178f...@192.168.4.131    CSeq: 102 CANCEL
4738178f...@192.168.4.131    Content-Length: 0
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131 send 683 bytes to udp/[207.166.134.124]:5060 at 18:42:41.448282:
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131    SIP/2.0 487 Request Terminated
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bK9979.a2c78cd7.0
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 76.178.145.28:1024;branch=z9hG4bK-ed7be6db
4738178f...@192.168.4.131    From: FAX <sip:10...@palousetech.com>;tag=af40a40fd1ab7eafo0
4738178f...@192.168.4.131    CSeq: 102 INVITE
4738178f...@192.168.4.131    User-Agent: 2600hz
4738178f...@192.168.4.131    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
4738178f...@192.168.4.131    Supported: path, replaces
4738178f...@192.168.4.131    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
4738178f...@192.168.4.131    Content-Length: 0
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131 recv 321 bytes from udp/[207.166.134.124]:5060 at 18:42:41.448607:
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131    Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bK9979.a2c78cd7.0
4738178f...@192.168.4.131    From: FAX <sip:10...@palousetech.com>;tag=af40a40fd1ab7eafo0
4738178f...@192.168.4.131    CSeq: 102 ACK
4738178f...@192.168.4.131    Max-Forwards: 50
4738178f...@192.168.4.131    Content-Length: 0
4738178f...@192.168.4.131    ------------------------------------------------------------------------
4738178f...@192.168.4.131 2014-12-02 18:42:41.439471 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:41.439471 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:41.439471 [DEBUG] switch_core_session.c:1052 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:41.439471 [DEBUG] sofia.c:6413 Channel sofia/sipinterface_1/10...@palousetech.com entering state [terminated][487]
4738178f...@192.168.4.131 2014-12-02 18:42:41.439471 [NOTICE] sofia.c:7299 Hangup sofia/sipinterface_1/10...@palousetech.com [CS_EXECUTE] [ORIGINATOR_CANCEL]
4738178f...@192.168.4.131 2014-12-02 18:42:41.439471 [DEBUG] switch_channel.c:3217 Send signal sofia/sipinterface_1/10...@palousetech.com [KILL]
4738178f...@192.168.4.131 2014-12-02 18:42:41.439471 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:41.439471 [DEBUG] switch_ivr_play_say.c:1715 done playing file /usr/share/freeswitch/http_cache/f0/64bdbc-79e4-11e4-8588-61695c765d5d.wav
4738178f...@192.168.4.131 2014-12-02 18:42:41.439471 [DEBUG] switch_core_session.c:2887 sofia/sipinterface_1/10...@palousetech.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
4738178f...@192.168.4.131 2014-12-02 18:42:41.439471 [DEBUG] switch_ivr.c:657 sofia/sipinterface_1/10...@palousetech.com skip receive message [AUDIO_SYNC] (channel is hungup already)
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_session.c:2887 sofia/sipinterface_1/10...@palousetech.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:533 (sofia/sipinterface_1/10...@palousetech.com) State EXECUTE going to sleep
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/10...@palousetech.com) Running State Change CS_HANGUP
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:733 (sofia/sipinterface_1/10...@palousetech.com) Callstate Change EARLY -> HANGUP
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:735 (sofia/sipinterface_1/10...@palousetech.com) State HANGUP
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] mod_sofia.c:407 sofia/sipinterface_1/10...@palousetech.com Overriding SIP cause 487 with 488 from the other leg
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] mod_sofia.c:413 Channel sofia/sipinterface_1/10...@palousetech.com hanging up, cause: ORIGINATOR_CANCEL
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:58 sofia/sipinterface_1/10...@palousetech.com Standard HANGUP, cause: ORIGINATOR_CANCEL
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:735 (sofia/sipinterface_1/10...@palousetech.com) State HANGUP going to sleep
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:502 (sofia/sipinterface_1/10...@palousetech.com) State Change CS_HANGUP -> CS_REPORTING
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_1/10...@palousetech.com) Running State Change CS_REPORTING
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:821 (sofia/sipinterface_1/10...@palousetech.com) State REPORTING
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:102 sofia/sipinterface_1/10...@palousetech.com Standard REPORTING, cause: ORIGINATOR_CANCEL
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:821 (sofia/sipinterface_1/10...@palousetech.com) State REPORTING going to sleep
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:496 (sofia/sipinterface_1/10...@palousetech.com) State Change CS_REPORTING -> CS_DESTROY
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_session.c:1387 Send signal sofia/sipinterface_1/10...@palousetech.com [BREAK]
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_session.c:1614 Session 3762 (sofia/sipinterface_1/10...@palousetech.com) Locked, Waiting on external entities
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [NOTICE] switch_core_session.c:1632 Session 3762 (sofia/sipinterface_1/10...@palousetech.com) Ended
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [NOTICE] switch_core_session.c:1636 Close Channel sofia/sipinterface_1/10...@palousetech.com [CS_DESTROY]
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:624 (sofia/sipinterface_1/10...@palousetech.com) Running State Change CS_DESTROY
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:634 (sofia/sipinterface_1/10...@palousetech.com) State DESTROY
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] mod_sofia.c:323 sofia/sipinterface_1/10...@palousetech.com SOFIA DESTROY
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:109 sofia/sipinterface_1/10...@palousetech.com Standard DESTROY
4738178f...@192.168.4.131 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:634 (sofia/sipinterface_1/10...@palousetech.com) State DESTROY going to sleep
2014-12-02 18:42:41.459474 [NOTICE] kazoo_node.c:285 log|4738178f...@192.168.4.131|executing respond 488 Incompatible destination 

Arek Fryz

unread,
Dec 3, 2014, 10:07:58 AM12/3/14
to 2600hz...@googlegroups.com
Were you able to fix it?



Regards,
Arek Fryz



REMAC ENTERPRISES, LTD.




----
The information transmitted is intended only for the person or entity to
which it is addressed and may contain confidential and/or privileged
material. Any review, retransmission, dissemination or other use of, or
taking of any action in reliance upon, this information by persons or
entities other than the intended recipient is prohibited. If you received
this in error, please contact the sender and delete the material from any
computer.

Jim Kusznir

unread,
Dec 3, 2014, 12:50:38 PM12/3/14
to 2600hz...@googlegroups.com
And of course, I just re-tested it again after NOT CHANGING ANYTHING, and it worked.....

I am dumbfounded how often Kazoo changes its behavior after being left to sit overnight....

This now is fixed, but I have no idea why...

--Jim

On Wed, Dec 3, 2014 at 9:48 AM, Jim Kusznir <jkus...@gmail.com> wrote:
No, I haven't. 

It appears that Kazoo is doing something very weird with the SDP.  My carrier says that "this exceeds normal support; if you want us to look into this further, you'd have to pay for professional services".  

The SDP from the ATA is good, the SDP from kazoo is very "strange", and a couple people on the IRC channel have commented on how strage and broken the kazoo-generated SDP is.  I'm also quite confused, as I'm having this issue only with this one ATA; I've got two other models of ATAs in use on the system, and all my IP phones are all working fine.

I'd really appreciate help figuring this out....

--Jim

Jim Kusznir

unread,
Dec 3, 2014, 12:50:41 PM12/3/14
to 2600hz...@googlegroups.com
No, I haven't. 

It appears that Kazoo is doing something very weird with the SDP.  My carrier says that "this exceeds normal support; if you want us to look into this further, you'd have to pay for professional services".  

The SDP from the ATA is good, the SDP from kazoo is very "strange", and a couple people on the IRC channel have commented on how strage and broken the kazoo-generated SDP is.  I'm also quite confused, as I'm having this issue only with this one ATA; I've got two other models of ATAs in use on the system, and all my IP phones are all working fine.

I'd really appreciate help figuring this out....

--Jim

Darren Schreiber

unread,
Dec 3, 2014, 12:55:01 PM12/3/14
to 2600hz...@googlegroups.com
Kazoo doesn’t generate SDP. FreeSWITCH does. The only thing after that is Kamailio which does NAT mixup, but only on inbound calls.

Your assessment that Kazoo has anything to do with this seems faulty to me.

James Aimonetti

unread,
Dec 3, 2014, 12:57:05 PM12/3/14
to 2600hz...@googlegroups.com
-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

Agreed; the bridge string Kazoo issued has no codec directives in it.
Have you changed any of the stock FreeSWITCH configs installed by Kazoo?

Le 2014-12-03 09:54, Darren Schreiber a écrit :
> Kazoo doesn’t generate SDP. FreeSWITCH does. The only thing after
> that is Kamailio which does NAT mixup, but only on inbound calls.
>
> Your assessment that Kazoo has anything to do with this seems
> faulty to me.
>
> From: Jim Kusznir <jkus...@gmail.com<mailto:jkus...@gmail.com>>
> Reply-To:
> "2600hz...@googlegroups.com<mailto:2600hz...@googlegroups.com>"
> <2600hz...@googlegroups.com<mailto:2600hz...@googlegroups.com>>
>
>
Date: Wednesday, December 3, 2014 at 12:48 PM
> To:
> "2600hz...@googlegroups.com<mailto:2600hz...@googlegroups.com>"
> <2600hz...@googlegroups.com<mailto:2600hz...@googlegroups.com>>
>
>
Subject: Re: Sip error 488
>
> No, I haven't.
>
> It appears that Kazoo is doing something very weird with the SDP.
> My carrier says that "this exceeds normal support; if you want us
> to look into this further, you'd have to pay for professional
> services".
>
> The SDP from the ATA is good, the SDP from kazoo is very "strange",
> and a couple people on the IRC channel have commented on how strage
> and broken the kazoo-generated SDP is. I'm also quite confused, as
> I'm having this issue only with this one ATA; I've got two other
> models of ATAs in use on the system, and all my IP phones are all
> working fine.
>
> I'd really appreciate help figuring this out....
>
> --Jim
>
> On Wed, Dec 3, 2014 at 5:42 AM, Arek Fryz
> <af...@remacservices.net<mailto:af...@remacservices.net>> wrote:
> Were you able to fix it?
>
>
>
> Regards, Arek Fryz
>
> [https://s3.amazonaws.com/remac-cdn/remac-logo.jpg]
>
> REMAC ENTERPRISES, LTD. Phone: (773)
> 888-5395<tel:%28773%29%20888-5395> Fax: (224)
> 655-6556<tel:%28224%29%20655-6556>
> www.remacenterprises.com<http://www.remacenterprises.com>
>
>
>
>
> ---- The information transmitted is intended only for the person or
> entity to which it is addressed and may contain confidential and/or
> privileged material. Any review, retransmission, dissemination or
> other use of, or taking of any action in reliance upon, this
> information by persons or entities other than the intended
> recipient is prohibited. If you received this in error, please
> contact the sender and delete the material from any computer.
>
> On Tue, Dec 2, 2014 at 10:42 PM, Jim Kusznir
> <jkus...@gmail.com<mailto:jkus...@gmail.com>> wrote: Thanks to a
> lot of help with people on the IRC channel, I've managed to figure
> out some logging, and it appears that for some reason kazoo is
> generating a very broke SDP to send to the carrier. First, here's
> the logs from freeswitch, grepping for the carrier leg of the call
> first:
>
> [root@voip ~]# ./sipify.sh /var/log/freeswitch/debug.log |grep
> e587d6fc-7a7c-11e4-9589-61695c765d5d
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613
> [NOTICE] switch_channel.c:1054 New Channel
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> [e587d6fc-7a7c-11e4-9589-61695c765d5d]
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613
> [DEBUG] mod_sofia.c:4591
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State Change CS_NEW -> CS_INIT e587d6fc-7a7c-11e4-9589-61695c765d5d
> 2014-12-02 18:42:37.319613 [DEBUG] switch_core_session.c:1387 Send
> signal
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> [BREAK] e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.319613 [DEBUG] switch_core_state_machine.c:470
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> Running State Change CS_INIT e587d6fc-7a7c-11e4-9589-61695c765d5d
> 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:510
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State INIT e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.319613 [DEBUG] mod_sofia.c:87
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> SOFIA INIT e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.319613 [DEBUG] sofia_glue.c:1226
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> Standard INIT e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.319613 [DEBUG] switch_core_state_machine.c:48
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State Change CS_INIT -> CS_ROUTING
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613
> [DEBUG] switch_core_session.c:1387 Send signal
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> [BREAK] e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.319613 [DEBUG] switch_core_state_machine.c:510
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State INIT going to sleep e587d6fc-7a7c-11e4-9589-61695c765d5d
> 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:470
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> Running State Change CS_ROUTING
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613
> [DEBUG] switch_core_state_machine.c:526
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State ROUTING e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.319613 [DEBUG] mod_sofia.c:123
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> SOFIA ROUTING e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.319613 [DEBUG] switch_ivr_originate.c:67
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State Change CS_ROUTING -> CS_CONSUME_MEDIA
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613
> [DEBUG] switch_core_session.c:1387 Send signal
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> [BREAK] e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.319613 [DEBUG] switch_core_state_machine.c:526
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State ROUTING going to sleep e587d6fc-7a7c-11e4-9589-61695c765d5d
> 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:470
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> Running State Change CS_CONSUME_MEDIA
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.319613
> [DEBUG] switch_core_state_machine.c:545
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State CONSUME_MEDIA e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.319613 [DEBUG] switch_core_state_machine.c:545
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State CONSUME_MEDIA going to sleep
> e587d6fc-7a7c-11e4-9589-61695c765d5d send 1717 bytes to
> udp/[64.2.142.26]:5060 at 18:42:37.339839:
> e587d6fc-7a7c-11e4-9589-61695c765d5d
> ------------------------------------------------------------------------
>
>
e587d6fc-7a7c-11e4-9589-61695c765d5d INVITE
sip:+15093...@sip1.vitelity.net<mailto:sip%3A%2B1509...@sip1.vitelity.net>
SIP/2.0
> e587d6fc-7a7c-11e4-9589-61695c765d5d Via: SIP/2.0/UDP
> 207.166.134.124:11000;rport;branch=z9hG4bKXr44t83rDBrjH
> e587d6fc-7a7c-11e4-9589-61695c765d5d Max-Forwards: 49
> e587d6fc-7a7c-11e4-9589-61695c765d5d From: "PalouseTech FAX"
> <sip:+15093...@207.166.134.124<mailto:sip%3A%2B1509...@207.166.134.124>>;tag=Xy9jH8rp7103j
>
>
e587d6fc-7a7c-11e4-9589-61695c765d5d To:
<sip:+15093...@sip1.vitelity.net<mailto:sip%3A%2B1509...@sip1.vitelity.net>>
> e587d6fc-7a7c-11e4-9589-61695c765d5d Call-ID:
> e587d6fc-7a7c-11e4-9589-61695c765d5d
> e587d6fc-7a7c-11e4-9589-61695c765d5d CSeq: 68445814 INVITE
> e587d6fc-7a7c-11e4-9589-61695c765d5d Contact:
> <sip:mod_...@207.166.134.124:11000<http://sip:mod_...@207.166.134.124:11000>>
>
>
e587d6fc-7a7c-11e4-9589-61695c765d5d User-Agent: 2600hz
> e587d6fc-7a7c-11e4-9589-61695c765d5d Allow: INVITE, ACK, BYE,
> CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY,
> PUBLISH, SUBSCRIBE e587d6fc-7a7c-11e4-9589-61695c765d5d
> Supported: path, replaces e587d6fc-7a7c-11e4-9589-61695c765d5d
> Allow-Events: talk, hold, conference, presence, as-feature-event,
> dialog, line-seize, call-info, sla, include-session-description,
> presence.winfo, message-summary, refer
> e587d6fc-7a7c-11e4-9589-61695c765d5d Content-Type:
> application/sdp e587d6fc-7a7c-11e4-9589-61695c765d5d
> Content-Disposition: session e587d6fc-7a7c-11e4-9589-61695c765d5d
> Content-Length: 750 e587d6fc-7a7c-11e4-9589-61695c765d5d
> X-AUTH-IP: 76.178.145.28 e587d6fc-7a7c-11e4-9589-61695c765d5d
> X-FS-Support: update_display,send_info
> e587d6fc-7a7c-11e4-9589-61695c765d5d Remote-Party-ID:
> "PalouseTech FAX"
> <sip:+15093...@207.166.134.124<mailto:sip%3A%2B1509...@207.166.134.124>>;party=calling;screen=yes;privacy=off
<sip:+15093...@207.166.134.124<mailto:sip%3A%2B1509...@207.166.134.124>>;tag=Xy9jH8rp7103j
> e587d6fc-7a7c-11e4-9589-61695c765d5d To:
> <sip:+15093...@sip1.vitelity.net<mailto:sip%3A%2B1509...@sip1.vitelity.net>>;tag=as6d5597db
>
>
e587d6fc-7a7c-11e4-9589-61695c765d5d Call-ID:
e587d6fc-7a7c-11e4-9589-61695c765d5d
> e587d6fc-7a7c-11e4-9589-61695c765d5d CSeq: 68445814 INVITE
> e587d6fc-7a7c-11e4-9589-61695c765d5d User-Agent: packetrino
> e587d6fc-7a7c-11e4-9589-61695c765d5d Allow: INVITE, ACK, CANCEL,
> OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
> e587d6fc-7a7c-11e4-9589-61695c765d5d Supported: replaces
> e587d6fc-7a7c-11e4-9589-61695c765d5d Content-Length: 0
> e587d6fc-7a7c-11e4-9589-61695c765d5d
> e587d6fc-7a7c-11e4-9589-61695c765d5d
> ------------------------------------------------------------------------
>
>
e587d6fc-7a7c-11e4-9589-61695c765d5d send 363 bytes to
udp/[64.2.142.26]:5060 at 18:42:37.341726:
> e587d6fc-7a7c-11e4-9589-61695c765d5d
> ------------------------------------------------------------------------
>
>
e587d6fc-7a7c-11e4-9589-61695c765d5d ACK
sip:+15093...@sip1.vitelity.net<mailto:sip%3A%2B1509...@sip1.vitelity.net>
SIP/2.0
> e587d6fc-7a7c-11e4-9589-61695c765d5d Via: SIP/2.0/UDP
> 207.166.134.124:11000;rport;branch=z9hG4bKXr44t83rDBrjH
> e587d6fc-7a7c-11e4-9589-61695c765d5d Max-Forwards: 49
> e587d6fc-7a7c-11e4-9589-61695c765d5d From: "PalouseTech FAX"
> <sip:+15093...@207.166.134.124<mailto:sip%3A%2B1509...@207.166.134.124>>;tag=Xy9jH8rp7103j
>
>
e587d6fc-7a7c-11e4-9589-61695c765d5d To:
<sip:+15093...@sip1.vitelity.net<mailto:sip%3A%2B1509...@sip1.vitelity.net>>;tag=as6d5597db
> e587d6fc-7a7c-11e4-9589-61695c765d5d Call-ID:
> e587d6fc-7a7c-11e4-9589-61695c765d5d
> e587d6fc-7a7c-11e4-9589-61695c765d5d CSeq: 68445814 ACK
> e587d6fc-7a7c-11e4-9589-61695c765d5d Content-Length: 0
> e587d6fc-7a7c-11e4-9589-61695c765d5d
> e587d6fc-7a7c-11e4-9589-61695c765d5d
> ------------------------------------------------------------------------
>
>
e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558 [DEBUG]
switch_core_session.c:1052 Send signal
sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
[BREAK]
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558
> [DEBUG] switch_core_session.c:1052 Send signal
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> [BREAK] e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.339558 [DEBUG] switch_core_session.c:1052 Send signal
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> [BREAK] e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.339558 [DEBUG] switch_core_session.c:1052 Send signal
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> [BREAK] e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.339558 [DEBUG] sofia.c:6413 Channel
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> entering state [calling][0] e587d6fc-7a7c-11e4-9589-61695c765d5d
> 2014-12-02 18:42:37.339558 [DEBUG] sofia.c:6413 Channel
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> entering state [terminated][488]
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558
> [NOTICE] sofia.c:7299 Hangup
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION]
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558
> [DEBUG] switch_channel.c:3217 Send signal
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> [KILL] e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.339558 [DEBUG] switch_core_session.c:1387 Send signal
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> [BREAK] e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.339558 [DEBUG] switch_core_state_machine.c:470
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> Running State Change CS_HANGUP e587d6fc-7a7c-11e4-9589-61695c765d5d
> 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:733
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> Callstate Change DOWN -> HANGUP
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558
> [DEBUG] switch_core_state_machine.c:735
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State HANGUP e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.339558 [DEBUG] mod_sofia.c:413 Channel
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> hanging up, cause: INCOMPATIBLE_DESTINATION
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558
> [DEBUG] switch_core_state_machine.c:58
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558
> [DEBUG] switch_core_state_machine.c:735
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State HANGUP going to sleep e587d6fc-7a7c-11e4-9589-61695c765d5d
> 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:502
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State Change CS_HANGUP -> CS_REPORTING
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558
> [DEBUG] switch_core_session.c:1387 Send signal
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> [BREAK] e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.339558 [DEBUG] switch_core_state_machine.c:470
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> Running State Change CS_REPORTING
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558
> [DEBUG] switch_core_state_machine.c:821
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State REPORTING e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.339558 [DEBUG] switch_core_state_machine.c:102
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558
> [DEBUG] switch_core_state_machine.c:821
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State REPORTING going to sleep e587d6fc-7a7c-11e4-9589-61695c765d5d
> 2014-12-02 18:42:37.339558 [DEBUG] switch_core_state_machine.c:496
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State Change CS_REPORTING -> CS_DESTROY
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558
> [DEBUG] switch_core_session.c:1387 Send signal
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> [BREAK] e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.339558 [DEBUG] switch_core_session.c:1614 Session 3763
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> Locked, Waiting on external entities
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558
> [NOTICE] switch_core_session.c:1632 Session 3763
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> Ended e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.339558 [NOTICE] switch_core_session.c:1636 Close Channel
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> [CS_DESTROY] e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.339558 [DEBUG] switch_core_state_machine.c:624
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> Running State Change CS_DESTROY
> e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02 18:42:37.339558
> [DEBUG] switch_core_state_machine.c:634
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State DESTROY e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.339558 [DEBUG] mod_sofia.c:323
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> SOFIA DESTROY e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.339558 [DEBUG] switch_core_state_machine.c:109
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> Standard DESTROY e587d6fc-7a7c-11e4-9589-61695c765d5d 2014-12-02
> 18:42:37.339558 [DEBUG] switch_core_state_machine.c:634
> (sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
> State DESTROY going to sleep 2014-12-02 18:42:37.359783 [INFO]
> kazoo_node.c:625 exec:
> uuid_setvar_multi(e587d6fc-7a7c-11e4-9589-61695c765d5d
> ecallmgr_Channel-Authorized=true;ecallmgr_Global-Resource=true;ecallmgr_Reseller-ID=73ba02f305fd80850bd1246529aa33b0;ecallmgr_Account-ID=73ba02f305fd80850bd1246529aa33b0)
>
> -------------- -------------- Now the ATA side of the call log:
> -------------- --------------
>
> Dec 2 18:42:37 voip 2600hz[960]:
> |4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>|ecallmgr_call_control:705
> (<0.17722.70>) added leg e587d6fc-7a7c-11e4-9589-61695c765d5d to
> call [root@voip ~]# ./sipify.sh /var/log/freeswitch/debug.log |grep
> 4738178f-ad2ea12f
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> recv 1018 bytes from udp/[207.166.134.124]:5060 at
> 18:42:36.433817:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> ------------------------------------------------------------------------
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
INVITE
sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>
SIP/2.0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Record-Route: <sip:207.166.134.124;lr=on;ftag=af40a40fd1ab7eafo0>
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bKc979.eb72fad3.0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 76.178.145.28:1024;branch=z9hG4bK-7d25adaf
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> From: FAX
> <sip:10...@palousetech.com<mailto:sip%3A1...@palousetech.com>>;tag=af40a40fd1ab7eafo0
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
To:
<sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>>
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Remote-Party-ID: FAX
> <sip:10...@palousetech.com<mailto:sip%3A1...@palousetech.com>>;screen=yes;party=calling
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
Call-ID:
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> CSeq: 101 INVITE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Max-Forwards: 50
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Contact: FAX
> <sip:10...@76.178.145.28:1024<http://sip:10...@76.178.145.28:1024>>
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Expires: 240
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> User-Agent: Linksys/SPA3102-5.2.13(GW002)
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Content-Length: 253
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Supported: x-sipura, replaces
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Content-Type: application/sdp
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> X-AUTH-IP: 76.178.145.28
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
v=0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> o=- 47347 47347 IN IP4 76.178.145.28
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> s=-
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> c=IN IP4 76.178.145.28
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> t=0 0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> m=audio 16390 RTP/AVP 0 100 101
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=rtpmap:0 PCMU/8000
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=rtpmap:100 NSE/8000
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=fmtp:100 192-193
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=rtpmap:101 telephone-event/8000
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=fmtp:101 0-15
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=ptime:30
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=sendrecv
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> ------------------------------------------------------------------------
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
send 412 bytes to udp/[207.166.134.124]:5060 at 18:42:36.434274:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> ------------------------------------------------------------------------
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
SIP/2.0 100 Trying
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bKc979.eb72fad3.0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 76.178.145.28:1024;branch=z9hG4bK-7d25adaf
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Record-Route: <sip:207.166.134.124;lr=on;ftag=af40a40fd1ab7eafo0>
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> From: FAX
> <sip:10...@palousetech.com<mailto:sip%3A1...@palousetech.com>>;tag=af40a40fd1ab7eafo0
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
To:
<sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>>
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Call-ID:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
CSeq: 101 INVITE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> User-Agent: 2600hz
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Content-Length: 0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
------------------------------------------------------------------------
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.419480 [NOTICE] switch_channel.c:1054 New
> Channel
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>]
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
2014-12-02 18:42:36.419480 [DEBUG] switch_core_session.c:1052 Send
signal
sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
[BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.419480 [DEBUG] switch_core_session.c:1052 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.419480 [DEBUG] switch_core_state_machine.c:470
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> Running State Change CS_NEW
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.419480 [DEBUG] sofia.c:8703
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> receiving invite from
> 207.166.134.124:5060<http://207.166.134.124:5060> version: 1.4.7
> 64bit 2014-12-02 18:42:36.419480 [DEBUG] sofia_reg.c:3345 sofia
> pre-register
> disabled4738...@192.168.4.131<mailto:disabled4738...@192.168.4.131>
> 2014-12-02 18:42:36.419480 [DEBUG] switch_core_state_machine.c:489
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State NEW
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> send 844 bytes to udp/[207.166.134.124]:5060 at 18:42:36.436571:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> ------------------------------------------------------------------------
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
SIP/2.0 407 Proxy Authentication Required
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bKc979.eb72fad3.0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 76.178.145.28:1024;branch=z9hG4bK-7d25adaf
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> From: FAX
> <sip:10...@palousetech.com<mailto:sip%3A1...@palousetech.com>>;tag=af40a40fd1ab7eafo0
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
To:
<sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>>;tag=UcQ1DjQFDgmyB
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Call-ID:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
CSeq: 101 INVITE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> User-Agent: 2600hz
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Accept: application/sdp
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
> REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Supported: path, replaces
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Allow-Events: talk, hold, conference, presence, as-feature-event,
> dialog, line-seize, call-info, sla, include-session-description,
> presence.winfo, message-summary, refer
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Proxy-Authenticate: Digest
> realm="palousetech.com<http://palousetech.com>",
> nonce="e4fe3a00-7a7c-11e4-9578-61695c765d5d", algorithm=MD5,
> qop="auth"
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Content-Length: 0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
------------------------------------------------------------------------
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> recv 321 bytes from udp/[207.166.134.124]:5060 at 18:42:36.437089:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> ------------------------------------------------------------------------
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
ACK
sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>
SIP/2.0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bKc979.eb72fad3.0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> From: FAX
> <sip:10...@palousetech.com<mailto:sip%3A1...@palousetech.com>>;tag=af40a40fd1ab7eafo0
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
To:
<sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>>;tag=UcQ1DjQFDgmyB
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Call-ID:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
CSeq: 101 ACK
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Max-Forwards: 50
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Content-Length: 0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
------------------------------------------------------------------------
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.419480 [DEBUG] switch_core_session.c:1052 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK] 2014-12-02 18:42:36.419480 [DEBUG] sofia.c:2033 detaching
> session
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
recv 1266 bytes from udp/[207.166.134.124]:5060 at 18:42:36.504369:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> ------------------------------------------------------------------------
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
INVITE
sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>
SIP/2.0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Record-Route: <sip:207.166.134.124;lr=on;ftag=af40a40fd1ab7eafo0>
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bK9979.a2c78cd7.0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 76.178.145.28:1024;branch=z9hG4bK-ed7be6db
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> From: FAX
> <sip:10...@palousetech.com<mailto:sip%3A1...@palousetech.com>>;tag=af40a40fd1ab7eafo0
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
To:
<sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>>
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Remote-Party-ID: FAX
> <sip:10...@palousetech.com<mailto:sip%3A1...@palousetech.com>>;screen=yes;party=calling
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
Call-ID:
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> CSeq: 102 INVITE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Max-Forwards: 50
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Proxy-Authorization: Digest
> username="1099",realm="palousetech.com<http://palousetech.com>",nonce="e4fe3a00-7a7c-11e4-9578-61695c765d5d",uri="sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>",algorithm=MD5,response="2f8c7fb0f156f8f89381ac73e0126742",qop=auth,nc=00000001,cnonce="a8a10196"
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
Contact: FAX
<sip:10...@76.178.145.28:1024<http://sip:10...@76.178.145.28:1024>>
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Expires: 240
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> User-Agent: Linksys/SPA3102-5.2.13(GW002)
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Content-Length: 253
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Supported: x-sipura, replaces
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Content-Type: application/sdp
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> X-AUTH-IP: 76.178.145.28
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
v=0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> o=- 47347 47347 IN IP4 76.178.145.28
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> s=-
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> c=IN IP4 76.178.145.28
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> t=0 0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> m=audio 16390 RTP/AVP 0 100 101
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=rtpmap:0 PCMU/8000
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=rtpmap:100 NSE/8000
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=fmtp:100 192-193
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=rtpmap:101 telephone-event/8000
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=fmtp:101 0-15
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=ptime:30
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=sendrecv
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> ------------------------------------------------------------------------
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
send 412 bytes to udp/[207.166.134.124]:5060 at 18:42:36.504784:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> ------------------------------------------------------------------------
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
SIP/2.0 100 Trying
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bK9979.a2c78cd7.0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 76.178.145.28:1024;branch=z9hG4bK-ed7be6db
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Record-Route: <sip:207.166.134.124;lr=on;ftag=af40a40fd1ab7eafo0>
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> From: FAX
> <sip:10...@palousetech.com<mailto:sip%3A1...@palousetech.com>>;tag=af40a40fd1ab7eafo0
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
To:
<sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>>
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Call-ID:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
CSeq: 102 INVITE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> User-Agent: 2600hz
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Content-Length: 0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
------------------------------------------------------------------------
> 2014-12-02 18:42:36.499451 [DEBUG] sofia.c:2138 Re-attaching to
> session
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
2014-12-02 18:42:36.499451 [DEBUG] switch_core_session.c:1052 Send
signal
sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
[BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] switch_core_session.c:1052 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] sofia.c:8703
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> receiving invite from
> 207.166.134.124:5060<http://207.166.134.124:5060> version: 1.4.7
> 64bit
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] sofia.c:6413 Channel
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> entering state [received][100]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] sofia.c:6423 Remote SDP:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> v=0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> o=- 47347 47347 IN IP4 76.178.145.28
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> s=-
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> c=IN IP4 76.178.145.28
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> t=0 0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> m=audio 16390 RTP/AVP 0 100 101
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=rtpmap:0 PCMU/8000
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=rtpmap:100 NSE/8000
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=fmtp:100 192-193
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=rtpmap:101 telephone-event/8000
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=fmtp:101 0-15
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=ptime:30
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
2014-12-02 18:42:36.499451 [DEBUG] sofia.c:6678
(sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
State Change CS_NEW -> CS_INIT
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] switch_core_session.c:1387 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] switch_core_state_machine.c:470
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> Running State Change CS_INIT
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] switch_core_state_machine.c:510
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State INIT
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] mod_sofia.c:87
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> SOFIA INIT
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] switch_core_state_machine.c:40
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Standard INIT
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] switch_core_state_machine.c:48
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State Change CS_INIT -> CS_ROUTING
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] switch_core_session.c:1387 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] switch_core_state_machine.c:510
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State INIT going to sleep
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] switch_core_state_machine.c:470
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> Running State Change CS_ROUTING
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] switch_channel.c:2179
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> Callstate Change DOWN -> RINGING
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] switch_core_state_machine.c:526
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State ROUTING
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] mod_sofia.c:123
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> SOFIA ROUTING
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [DEBUG] switch_core_state_machine.c:164
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Standard ROUTING
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.499451 [INFO] mod_dialplan_xml.c:558 Processing
> FAX <1099>->5093385920<tel:5093385920> in context context_2
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Dialplan:
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> parsing [context_2->park] continue=false
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> |--- Dialplan: Processing recursive conditions level:1
> [park_recur_1] require-nested=TRUE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> |--- Dialplan:
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Regex (PASS) [park_recur_1] variable_ecallmgr_Bridge-ID() =~ /^$/
> break=on-false
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> |--- Dialplan:
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Action export(ecallmgr_Bridge-ID=${UUID})
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Dialplan:
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Absolute Condition [park]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Dialplan:
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Action log(NOTICE
> log|${uuid}|ecal...@voip.palousetech.com<mailto:ecal...@voip.palousetech.com>
> won call control)
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Dialplan:
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Action
> export(ecallmgr_Ecallmgr-Node=ecal...@voip.palousetech.com<mailto:ecal...@voip.palousetech.com>)
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
Dialplan:
sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
Action set(ringback=%(2000,4000,440,480))
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Dialplan:
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Action set(transfer_ringback=%(2000,4000,440,480))
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Dialplan:
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Action park()
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.683328 [DEBUG] switch_core_state_machine.c:214
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State Change CS_ROUTING -> CS_EXECUTE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.683328 [DEBUG] switch_core_session.c:1387 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.683328 [DEBUG] switch_core_state_machine.c:526
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State ROUTING going to sleep
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.683328 [DEBUG] switch_core_state_machine.c:470
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> Running State Change CS_EXECUTE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.683328 [DEBUG] switch_core_state_machine.c:533
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State EXECUTE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.683328 [DEBUG] mod_sofia.c:178
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> SOFIA EXECUTE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.683328 [DEBUG] switch_core_state_machine.c:256
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Standard EXECUTE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> EXECUTE
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> export(ecallmgr_Bridge-ID=4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>)
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
2014-12-02 18:42:36.683328 [DEBUG] switch_channel.c:1246 EXPORT
(export_vars)
[ecallmgr_Bridge-ID]=[4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> EXECUTE
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> log(NOTICE
> log|4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>|ecal...@voip.palousetech.com<mailto:ecal...@voip.palousetech.com>
> won call control)
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.683328 [NOTICE] mod_dptools.c:1667
> log|4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>|ecal...@voip.palousetech.com<mailto:ecal...@voip.palousetech.com>
> won call control
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> EXECUTE
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> export(ecallmgr_Ecallmgr-Node=ecal...@voip.palousetech.com<mailto:ecal...@voip.palousetech.com>)
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
2014-12-02 18:42:36.683328 [DEBUG] switch_channel.c:1246 EXPORT
(export_vars)
[ecallmgr_Ecallmgr-Node]=[ecal...@voip.palousetech.com<mailto:ecal...@voip.palousetech.com>]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> EXECUTE
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> set(ringback=%(2000,4000,440,480))
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.683328 [DEBUG] mod_dptools.c:1435
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> SET [ringback]=[%(2000,4000,440,480)]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> EXECUTE
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> set(transfer_ringback=%(2000,4000,440,480))
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.683328 [DEBUG] mod_dptools.c:1435
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> SET [transfer_ringback]=[%(2000,4000,440,480)]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> EXECUTE
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> park() 2014-12-02 18:42:36.920033 [NOTICE] kazoo_node.c:285
> log|4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>|executing
> export hold_music=silence_stream://300000
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.920033 [DEBUG] switch_core_session.c:1187 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.939856 [DEBUG] switch_ivr.c:614
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Command Execute export(hold_music=silence_stream://300000)
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> EXECUTE
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> export(hold_music=silence_stream://300000)
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:36.939856 [DEBUG] switch_channel.c:1246 EXPORT
> (export_vars) [hold_music]=[silence_stream://300000] 2014-12-02
> 18:42:37.119462 [INFO] kazoo_node.c:625 exec:
> uuid_setvar_multi(4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> effective_caller_id_name=Fax
> User;effective_caller_id_number=1099;ecallmgr_Fetch-ID=e50b0686-7a7c-11e4-957a-61695c765d5d;ecallmgr_Username=1099;ecallmgr_Realm=palousetech.com<http://palousetech.com>;ecallmgr_Account-ID=73ba02f305fd80850bd1246529aa33b0;ecallmgr_Authorizing-ID=1ce5aeb8b9c6f2a151560b1534645cdc;ecallmgr_Authorizing-Type=device;ecallmgr_Owner-ID=9cb2d4aacd57002fc15513d0263893c2;ecallmgr_Account-Realm=palousetech.com<http://palousetech.com>;ecallmgr_Account-Name=PalouseTech;presence_id=10...@palousetech.com<mailto:10...@palousetech.com>)
>
>
2014-12-02 18:42:37.319613 [NOTICE] kazoo_node.c:302
log|4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>|building
xferext extension: set ignore_display_updates=true
> 2014-12-02 18:42:37.319613 [NOTICE] kazoo_node.c:302
> log|4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>|building
> xferext extension: set continue_on_fail=true 2014-12-02
> 18:42:37.319613 [NOTICE] kazoo_node.c:302
> log|4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>|building
> xferext extension: export sip_redirect_context=context_2 2014-12-02
> 18:42:37.319613 [NOTICE] kazoo_node.c:302
> log|4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>|building
> xferext extension: export ecallmgr_Inception=${ecallmgr_Inception}
> 2014-12-02 18:42:37.319613 [NOTICE] kazoo_node.c:302
> log|4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>|building
> xferext extension: bridge
> {ignore_display_updates='true',ecallmgr_Account-ID='73ba02f305fd80850bd1246529aa33b0',ecallmgr_Reseller-ID='73ba02f305fd80850bd1246529aa33b0',effective_caller_id_number='+15093397676',effective_caller_id_name='PalouseTech
> FAX',origination_caller_id_number='+15093397676',origination_caller_id_name='PalouseTech
> FAX',ignore_early_media='false',local_var_clobber='true'}[sip_auth_password='password',sip_auth_username='kusznir',ecallmgr_Global-Resource='true',ecallmgr_Resource-ID='carrier-Vitelity',ecallmgr_Original-Number='5093385920',leg_progress_timeout='10',effective_callee_id_number='5093385920',effective_callee_id_name='5093385920']sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
>
>
2014-12-02 18:42:37.319613 [NOTICE] kazoo_node.c:302
log|4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>|building
xferext extension: event
Event-Name=CUSTOM,Event-Subclass=whistle::masquerade,whistle_event_name=CHANNEL_EXECUTE_COMPLETE,whistle_application_name=bridge
> 2014-12-02 18:42:37.319613 [NOTICE] kazoo_node.c:302
> log|4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>|building
> xferext extension: park 2014-12-02 18:42:37.319613 [NOTICE]
> kazoo_node.c:305
> log|4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>|transfered
> call to xferext extension
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_core_session.c:1187 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_channel.c:3098
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State Change CS_EXECUTE -> CS_ROUTING
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_core_session.c:1387 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:533
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State EXECUTE going to sleep
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:470
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> Running State Change CS_ROUTING
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:526
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State ROUTING
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] mod_sofia.c:123
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> SOFIA ROUTING
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:164
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Standard ROUTING
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:184
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State Change CS_ROUTING -> CS_EXECUTE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_core_session.c:1387 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:526
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State ROUTING going to sleep
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:470
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> Running State Change CS_EXECUTE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:533
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State EXECUTE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] mod_sofia.c:178
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> SOFIA EXECUTE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_core_state_machine.c:256
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Standard EXECUTE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> EXECUTE
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> set(ignore_display_updates=true)
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] mod_dptools.c:1435
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> SET [ignore_display_updates]=[true]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> EXECUTE
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> set(continue_on_fail=true)
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] mod_dptools.c:1435
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> SET [continue_on_fail]=[true]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> EXECUTE
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> export(sip_redirect_context=context_2)
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_channel.c:1246 EXPORT
> (export_vars) [sip_redirect_context]=[context_2]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> EXECUTE
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> export(ecallmgr_Inception=)
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_channel.c:1246 EXPORT
> (export_vars) [ecallmgr_Inception]=[UNDEF]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> EXECUTE
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> bridge({ignore_display_updates=true,ecallmgr_Account-ID=73ba02f305fd80850bd1246529aa33b0,ecallmgr_Reseller-ID=73ba02f305fd80850bd1246529aa33b0,effective_caller_id_number=+15093397676,effective_caller_id_name=PalouseTech
> FAX,origination_caller_id_number=+15093397676,origination_caller_id_name=PalouseTech
> FAX,ignore_early_media=false,local_var_clobber=true}[sip_auth_password=password,sip_auth_username=kusznir,ecallmgr_Global-Resource=true,ecallmgr_Resource-ID=carrier-Vitelity,ecallmgr_Original-Number=5093385920,leg_progress_timeout=10,effective_callee_id_number=5093385920,effective_callee_id_name=5093385920]sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>)
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
2014-12-02 18:42:37.319613 [DEBUG] switch_channel.c:1200
sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
EXPORTING[export_vars]
[ecallmgr_Bridge-ID]=[4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>]
to event
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_channel.c:1200
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> EXPORTING[export_vars]
> [ecallmgr_Ecallmgr-Node]=[ecal...@voip.palousetech.com<mailto:ecal...@voip.palousetech.com>]
> to event
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_channel.c:1200
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> EXPORTING[export_vars] [hold_music]=[silence_stream://300000] to
> event
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_channel.c:1200
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> EXPORTING[export_vars] [sip_redirect_context]=[context_2] to event
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_ivr_originate.c:2079
> Parsing global variables
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_ivr_originate.c:2526
> Parsing session specific variables
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.319613 [DEBUG] switch_ivr_originate.c:2829
> sofia/sipinterface_1/+15093...@sip1.vitelity.net<mailto:15093...@sip1.vitelity.net>
> Setting leg progress timeout to 10
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.339558 [DEBUG] switch_ivr_originate.c:3695
> Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.339558 [INFO] mod_dptools.c:3277 Originate
> Failed. Cause: INCOMPATIBLE_DESTINATION
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> EXECUTE
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> event(Event-Name=CUSTOM,Event-Subclass=whistle::masquerade,whistle_event_name=CHANNEL_EXECUTE_COMPLETE,whistle_application_name=bridge)
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
EXECUTE
sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
park()
> 2014-12-02 18:42:37.399544 [NOTICE] kazoo_node.c:285
> log|4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>|executing
> pre_answer
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_session.c:1187 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_ivr.c:614
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Command Execute pre_answer()
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> EXECUTE
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> pre_answer()
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio
> Codec Compare [PCMU:0:8000:30:64000:1]/[opus:116:48000:20:0:1]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio
> Codec Compare
> [PCMU:0:8000:30:64000:1]/[G7221:115:32000:20:48000:1]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio
> Codec Compare
> [PCMU:0:8000:30:64000:1]/[G7221:107:16000:20:32000:1]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio
> Codec Compare [PCMU:0:8000:30:64000:1]/[G722:9:8000:20:64000:1]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio
> Codec Compare [PCMU:0:8000:30:64000:1]/[PCMU:0:8000:20:64000:1]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3509 Audio
> Codec Compare [PCMU:0:8000:20:64000:1] is saved as a near-match
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio
> Codec Compare [PCMU:0:8000:30:64000:1]/[PCMA:8:8000:20:64000:1]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio
> Codec Compare [PCMU:0:8000:30:64000:1]/[GSM:3:8000:20:13200:1]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio
> Codec Compare [PCMU:0:8000:30:64000:1]/[SPEEX:99:8000:20:24600:1]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio
> Codec Compare [NSE:100:8000:30:0:1]/[opus:116:48000:20:0:1]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio
> Codec Compare [NSE:100:8000:30:0:1]/[G7221:115:32000:20:48000:1]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio
> Codec Compare [NSE:100:8000:30:0:1]/[G7221:107:16000:20:32000:1]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio
> Codec Compare [NSE:100:8000:30:0:1]/[G722:9:8000:20:64000:1]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio
> Codec Compare [NSE:100:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio
> Codec Compare [NSE:100:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio
> Codec Compare [NSE:100:8000:30:0:1]/[GSM:3:8000:20:13200:1]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3474 Audio
> Codec Compare [NSE:100:8000:30:0:1]/[SPEEX:99:8000:20:24600:1]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3395 Set
> telephone-event payload to 101
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3579
> Substituting codec PCMU@30i@8000h@1c
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:2352 Set
> Codec
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> PCMU/8000 30 ms 240 samples 64000 bits 1 channels
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_codec.c:111
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Original read codec set to PCMU:0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:3719 Set
> 2833 dtmf send/recv payload to 101
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_core_media.c:4939 AUDIO
> RTP
> [sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>]
> 207.166.134.124 port 16836 -> 76.178.145.28 port 16390 codec: 0 ms:
> 30
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.399544 [DEBUG] switch_rtp.c:3383 Starting timer
> [soft] 240 bytes per 30ms
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.419782 [DEBUG] switch_core_media.c:5287 Set
> 2833 dtmf send payload to 101
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.419782 [DEBUG] switch_core_media.c:5293 Set
> 2833 dtmf receive payload to 101
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.419782 [NOTICE] sofia_media.c:92 Pre-Answer
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>!
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
2014-12-02 18:42:37.419782 [DEBUG] switch_channel.c:3394
(sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
Callstate Change RINGING -> EARLY
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.419782 [DEBUG] mod_sofia.c:2228 Ring SDP:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> v=0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> o=FreeSWITCH 1417546921 1417546922 IN IP4 207.166.134.124
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> s=FreeSWITCH
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> c=IN IP4 207.166.134.124
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> t=0 0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> m=audio 16836 RTP/AVP 0 101
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=rtpmap:0 PCMU/8000
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=rtpmap:101 telephone-event/8000
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=fmtp:101 0-16
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=ptime:30
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=sendrecv
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
2014-12-02 18:42:37.419782 [DEBUG] switch_core_session.c:907 Send signal
sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
[BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.419782 [DEBUG] switch_ivr.c:961 Codec Activated
> L16@8000hz 1 channels 30ms
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> send 1225 bytes to udp/[207.166.134.124]:5060 at 18:42:37.431599:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> ------------------------------------------------------------------------
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
SIP/2.0 183 Session Progress
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bK9979.a2c78cd7.0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 76.178.145.28:1024;branch=z9hG4bK-ed7be6db
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Record-Route: <sip:207.166.134.124;lr=on;ftag=af40a40fd1ab7eafo0>
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> From: FAX
> <sip:10...@palousetech.com<mailto:sip%3A1...@palousetech.com>>;tag=af40a40fd1ab7eafo0
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
To:
<sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>>;tag=vNgtFD8jaSaHQ
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Call-ID:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
CSeq: 102 INVITE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Contact:
> <sip:5093385920<tel:5093385920>@207.166.134.124:11000;transport=udp>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
User-Agent: 2600hz
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Accept: application/sdp
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
> REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Supported: path, replaces
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Allow-Events: talk, hold, conference, presence, as-feature-event,
> dialog, line-seize, call-info, sla, include-session-description,
> presence.winfo, message-summary, refer
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Content-Type: application/sdp
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Content-Disposition: session
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Content-Length: 226
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Remote-Party-ID: "5093385920<tel:5093385920>"
> <sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>>;party=calling;privacy=off;screen=no
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> v=0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> o=FreeSWITCH 1417546921 1417546922 IN IP4 207.166.134.124
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> s=FreeSWITCH
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> c=IN IP4 207.166.134.124
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> t=0 0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> m=audio 16836 RTP/AVP 0 101
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=rtpmap:0 PCMU/8000
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=rtpmap:101 telephone-event/8000
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=fmtp:101 0-16
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> a=ptime:30
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> ------------------------------------------------------------------------
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
2014-12-02 18:42:37.419782 [DEBUG] switch_core_session.c:1052 Send
signal
sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
[BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.419782 [DEBUG] sofia.c:6413 Channel
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> entering state [early][183] 2014-12-02 18:42:37.600043 [NOTICE]
> kazoo_node.c:285
> log|4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>|executing
> playback
> ${http_get(http://127.0.0.1:15984/system_media/fault-facility_trouble/fault-facility_trouble.wav)}
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
2014-12-02 18:42:37.600043 [DEBUG] switch_core_session.c:1187 Send
signal
sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
[BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.600043 [DEBUG] switch_rtp.c:5617 Correct
> ip/port confirmed.
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.600043 [DEBUG] switch_ivr.c:614
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Command Execute
> playback(${http_get(http://127.0.0.1:15984/system_media/fault-facility_trouble/fault-facility_trouble.wav)})
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
2014-12-02 18:42:37.600043 [DEBUG] mod_http_cache.c:523 Locked cache
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.600043 [DEBUG] mod_http_cache.c:651 Cache HIT:
> size = 109 (48 MB), hit ratio = 256/365
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:37.600043 [DEBUG] mod_http_cache.c:534 Unlocked
> cache
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> EXECUTE
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> playback(/usr/share/freeswitch/http_cache/f0/64bdbc-79e4-11e4-8588-61695c765d5d.wav)
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
2014-12-02 18:42:37.600043 [DEBUG] switch_ivr_play_say.c:1306 Codec
Activated L16@8000hz 1 channels 30ms
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> recv 309 bytes from udp/[207.166.134.124]:5060 at 18:42:41.447744:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> ------------------------------------------------------------------------
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
CANCEL
sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>
SIP/2.0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bK9979.a2c78cd7.0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> From: FAX
> <sip:10...@palousetech.com<mailto:sip%3A1...@palousetech.com>>;tag=af40a40fd1ab7eafo0
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
To:
<sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>>
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Call-ID:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
CSeq: 102 CANCEL
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Max-Forwards: 50
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Content-Length: 0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
------------------------------------------------------------------------
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> send 278 bytes to udp/[207.166.134.124]:5060 at 18:42:41.447949:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> ------------------------------------------------------------------------
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
SIP/2.0 200 OK
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bK9979.a2c78cd7.0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> From: FAX
> <sip:10...@palousetech.com<mailto:sip%3A1...@palousetech.com>>;tag=af40a40fd1ab7eafo0
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
To:
<sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>>;tag=vNgtFD8jaSaHQ
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Call-ID:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
CSeq: 102 CANCEL
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Content-Length: 0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
------------------------------------------------------------------------
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> send 683 bytes to udp/[207.166.134.124]:5060 at 18:42:41.448282:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> ------------------------------------------------------------------------
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
SIP/2.0 487 Request Terminated
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bK9979.a2c78cd7.0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 76.178.145.28:1024;branch=z9hG4bK-ed7be6db
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> From: FAX
> <sip:10...@palousetech.com<mailto:sip%3A1...@palousetech.com>>;tag=af40a40fd1ab7eafo0
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
To:
<sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>>;tag=vNgtFD8jaSaHQ
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Call-ID:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
CSeq: 102 INVITE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> User-Agent: 2600hz
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
> REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Supported: path, replaces
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Allow-Events: talk, hold, conference, presence, as-feature-event,
> dialog, line-seize, call-info, sla, include-session-description,
> presence.winfo, message-summary, refer
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Content-Length: 0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
------------------------------------------------------------------------
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> recv 321 bytes from udp/[207.166.134.124]:5060 at 18:42:41.448607:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> ------------------------------------------------------------------------
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
ACK
sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>
SIP/2.0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Via: SIP/2.0/UDP 207.166.134.124;branch=z9hG4bK9979.a2c78cd7.0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> From: FAX
> <sip:10...@palousetech.com<mailto:sip%3A1...@palousetech.com>>;tag=af40a40fd1ab7eafo0
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
To:
<sip:50933...@palousetech.com<mailto:sip%3A5093...@palousetech.com>>;tag=vNgtFD8jaSaHQ
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Call-ID:
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
CSeq: 102 ACK
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Max-Forwards: 50
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> Content-Length: 0
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
------------------------------------------------------------------------
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.439471 [DEBUG] switch_core_session.c:1052 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.439471 [DEBUG] switch_core_session.c:1052 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.439471 [DEBUG] switch_core_session.c:1052 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.439471 [DEBUG] sofia.c:6413 Channel
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> entering state [terminated][487]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.439471 [NOTICE] sofia.c:7299 Hangup
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [CS_EXECUTE] [ORIGINATOR_CANCEL]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.439471 [DEBUG] switch_channel.c:3217 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [KILL]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.439471 [DEBUG] switch_core_session.c:1387 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.439471 [DEBUG] switch_ivr_play_say.c:1715 done
> playing file
> /usr/share/freeswitch/http_cache/f0/64bdbc-79e4-11e4-8588-61695c765d5d.wav
>
>
4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
2014-12-02 18:42:41.439471 [DEBUG] switch_core_session.c:2887
sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.439471 [DEBUG] switch_ivr.c:657
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> skip receive message [AUDIO_SYNC] (channel is hungup already)
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_session.c:2887
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup
> already)
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:533
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State EXECUTE going to sleep
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:470
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> Running State Change CS_HANGUP
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:733
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> Callstate Change EARLY -> HANGUP
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:735
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State HANGUP
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] mod_sofia.c:407
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Overriding SIP cause 487 with 488 from the other leg
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] mod_sofia.c:413 Channel
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> hanging up, cause: ORIGINATOR_CANCEL
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:58
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Standard HANGUP, cause: ORIGINATOR_CANCEL
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:735
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State HANGUP going to sleep
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:502
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State Change CS_HANGUP -> CS_REPORTING
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_session.c:1387 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:470
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> Running State Change CS_REPORTING
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:821
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State REPORTING
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:102
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Standard REPORTING, cause: ORIGINATOR_CANCEL
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:821
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State REPORTING going to sleep
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:496
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State Change CS_REPORTING -> CS_DESTROY
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_session.c:1387 Send
> signal
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [BREAK]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_session.c:1614
> Session 3762
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> Locked, Waiting on external entities
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [NOTICE] switch_core_session.c:1632
> Session 3762
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> Ended
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [NOTICE] switch_core_session.c:1636
> Close Channel
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> [CS_DESTROY]
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:624
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> Running State Change CS_DESTROY
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:634
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State DESTROY
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] mod_sofia.c:323
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> SOFIA DESTROY
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:109
> sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>
> Standard DESTROY
> 4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>
> 2014-12-02 18:42:41.459474 [DEBUG] switch_core_state_machine.c:634
> (sofia/sipinterface_1/10...@palousetech.com<mailto:10...@palousetech.com>)
> State DESTROY going to sleep 2014-12-02 18:42:41.459474 [NOTICE]
> kazoo_node.c:285
> log|4738178f...@192.168.4.131<mailto:4738178f...@192.168.4.131>|executing
> respond 488 Incompatible destination
>
> On Tue, Dec 2, 2014 at 11:52 AM, Arek Fryz
> <af...@remacservices.net<mailto:af...@remacservices.net>> wrote:
> Did you set device as fax device in kazoo? It may be trying to
> force T38 re-INVITE and that is not acceptable by your carrier or
> ATA. I only see 488 with ATAs when carrier did not accept T38 or
> carrier/kazoo tried to force T38 and adapter did not accept it.
>
> I'd start with freeswitch debug log. Check invite and response to
> it.
>
>
>
> Regards, Arek Fryz
>
> [https://s3.amazonaws.com/remac-cdn/remac-logo.jpg]
>
> REMAC ENTERPRISES, LTD. Phone: (773)
> 888-5395<tel:%28773%29%20888-5395> Fax: (224)
> 655-6556<tel:%28224%29%20655-6556>
> www.remacenterprises.com<http://www.remacenterprises.com>
>
>
>
>
> ---- The information transmitted is intended only for the person or
> entity to which it is addressed and may contain confidential and/or
> privileged material. Any review, retransmission, dissemination or
> other use of, or taking of any action in reliance upon, this
> information by persons or entities other than the intended
> recipient is prohibited. If you received this in error, please
> contact the sender and delete the material from any computer.
>
> On Tue, Dec 2, 2014 at 8:52 AM, Jim Kusznir
> <jkus...@gmail.com<mailto:jkus...@gmail.com>> wrote: Hi all:
>
> I'm configuring a spa-3102 ATA that I had lying around, ultimately
> to be an outbound fax device. However, for starters, I've hooked a
> POTS phone to it, and just want to get regular calling working.
>
> Whenever I dial on-net (local extensions), all works great. When I
> dial off-net (eg, my cell phone), the ATA plays a recording that
> the call could not be completed due to service provider trouble.
> When I dig in further, I see that kazoo has sent sip error 488.
>
> When I researched sip error 488, it seems that generally means
> codec negiotiation failed. My codecs are g711u and g711a, and
> that's enabled on both the ATA and Kazoo (device settings). I'm
> not sure where else to look, or how to further address this. The
> error itself is kinda vague....
>
> How do I fix this?
>
> Thanks!
>
> -- You received this message because you are subscribed to the
> Google Groups "2600hz-users" group. To unsubscribe from this group
> and stop receiving emails from it, send an email to
> 2600hz-users...@googlegroups.com<mailto:2600hz-users...@googlegroups.com>.
>
>
For more options, visit https://groups.google.com/d/optout.
>
>
> -- You received this message because you are subscribed to the
> Google Groups "2600hz-users" group. To unsubscribe from this group
> and stop receiving emails from it, send an email to
> 2600hz-users...@googlegroups.com<mailto:2600hz-users...@googlegroups.com>.
>
>
For more options, visit https://groups.google.com/d/optout.
>
>
> -- You received this message because you are subscribed to the
> Google Groups "2600hz-users" group. To unsubscribe from this group
> and stop receiving emails from it, send an email to
> 2600hz-users...@googlegroups.com<mailto:2600hz-users...@googlegroups.com>.
>
>
For more options, visit https://groups.google.com/d/optout.
>
>
> -- You received this message because you are subscribed to the
> Google Groups "2600hz-users" group. To unsubscribe from this group
> and stop receiving emails from it, send an email to
> 2600hz-users...@googlegroups.com<mailto:2600hz-users...@googlegroups.com>.
>
>
For more options, visit https://groups.google.com/d/optout.
>
>
> -- You received this message because you are subscribed to the
> Google Groups "2600hz-users" group. To unsubscribe from this group
> and stop receiving emails from it, send an email to
> 2600hz-users...@googlegroups.com<mailto:2600hz-users...@googlegroups.com>.
>
>
For more options, visit https://groups.google.com/d/optout.
>


- --
James Aimonetti
Lead Systems Architect / Impressionable Scallywag
"I thought I fixed that"

2600Hz | http://2600hz.com
sip:ja...@2600hz.com
tel:415.886.7905
irc:mc_ @ freenode
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1
Comment: Using GnuPG with Thunderbird - http://www.enigmail.net/

iQEcBAEBAgAGBQJUf07uAAoJENTKa+JPXCVg6BkH/i7Rp1iq2mdfrCKlxc23sq3+
Jd1Nu+bvXRvostfdD4uPHJCiN55kH/kTjHgTFhihOTvjK9O8pVeYB/eyt7KjdVam
u4IZ9HlyrDjGunJMGx2bullhUltmjWlD3BOuXekb68yI7eruKPvQy5EPWNZUsnB4
JQaftK1THIh3bdhAFBLSAGczVQ/DvL5roaPbWxZk8074xbv6myOIz8CK1dT5SVnM
QScvn4Y36ePbPoUr9Wr3CD30hbUQLZrLgKlU0D9ia6FmdprnQ7DJvtMkLuoLaPeL
snSJbpmzd1rYL0rGcBtMDqBayk5oNFBKMWVFIxgZtcLAWU4HUqWpL8BNSTQov2w=
=HXsH
-----END PGP SIGNATURE-----

Arek Fryz

unread,
Dec 3, 2014, 1:14:51 PM12/3/14
to 2600hz...@googlegroups.com
Did you try to change UDP packet size and failover to TCP? I still think 1717 bytes INVITE is the issue. 



Regards,
Arek Fryz



REMAC ENTERPRISES, LTD.




----
The information transmitted is intended only for the person or entity to
which it is addressed and may contain confidential and/or privileged
material. Any review, retransmission, dissemination or other use of, or
taking of any action in reliance upon, this information by persons or
entities other than the intended recipient is prohibited. If you received
this in error, please contact the sender and delete the material from any
computer.

Jim Kusznir

unread,
Dec 3, 2014, 7:30:49 PM12/3/14
to 2600hz...@googlegroups.com
I did not make any changes to freeswitch configs.

I was referring to "kazoo" as the entire end to end VoIP softswitch solution; that was my bad.

I do have kamillio set with the packet size failover, per suggestion on the irc channel.

To be clear, it is now working, after leaving everything alone overnight.  I did change my offnet document to specify the codecs my carrier use (the array was empty previously; yet, it was working with my IP phones).  I attempted to flush everything I could with the sup command after mkaing those changes yesterday, but nothing improved.  Eventually, I went to bed, and when I tested it this morning, it worked....No further explanation available.

This is not the first time that something with the overall kazoo system didn't work, and then the next day, they did (with no further changes made).  I'm just surprised, because this is the only piece of software I've worked with that letting it sit overnight fixes things....Makes me uneasy in general.

--Jim


Reply all
Reply to author
Forward
0 new messages