Skip to content

Instantly share code, notes, and snippets.

@epipenau

epipenau/gist:9bc3d1c87eedad1fb273

Created November 27, 2014 12:55
Show Gist options

  • Select an option

    Learn more about clone URLs
  • Save epipenau/9bc3d1c87eedad1fb273 to your computer and use it in GitHub Desktop.

Select an option

Learn more about clone URLs
Save epipenau/9bc3d1c87eedad1fb273 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
Via: SIP/2.0/UDP 10.1.1.39:5060;branch=z9hG4bK-e758da93
From: Albury iAdrenalin <sip:[email protected]>;tag=ee7e47c9bffde7e6o1
To: Albury iAdrenalin <sip:[email protected]>;tag=93meHpee9S99r
Call-ID: [email protected]
CSeq: 39565 REGISTER
Contact: <sip:[email protected]:5060>;expires=30
Date: Thu, 27 Nov 2014 12:54:14 GMT
User-Agent: FreeSWITCH-mod_sofia/1.4.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Content-Length: 0
------------------------------------------------------------------------
recv 1190 bytes from udp/[110.144.77.148]:2478 at 12:54:28.850764:
------------------------------------------------------------------------
INVITE sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 10.143.196.78:2478;branch=z9hG4bKffyCGGarnGOQzJf4;rport
Contact: <sip:[email protected]:2478;rinstance=EAEC46E4>
Max-Forwards: 70
From: <sip:[email protected]>;tag=D0F5835BF736855DF4DB06335E752CD7
Allow: OPTIONS, INVITE, ACK, REFER, CANCEL, BYE, NOTIFY
Supported: replaces, path
User-Agent: Acrobits Softphone Business/3.1
To: <sip:[email protected]>
Content-Type: application/sdp
Call-ID: BCC69BBD076D884DC683DC43018A5AD7946EECA8
CSeq: 1 INVITE
Content-Length: 611
v=0
o=- 3691731472 2505 IN IP4 172.138.170.170
s=erpsypl
c=IN IP4 110.144.77.148
t=0 0
m=audio 48132 RTP/AVP 105 102 3 106 0 8 9 101
a=rtpmap:101 telephone-event/8000
a=rtpmap:102 iLBC/8000
a=rtpmap:105 OPUS/48000/2
a=rtpmap:106 OPUS/48000/2
a=fmtp:101 0-15
a=fmtp:102 mode=30
a=fmtp:105 maxplaybackrate=8000;useinbandfec=1;usedtx=1
a=fmtp:106 maxplaybackrate=16000;useinbandfec=1;usedtx=1
a=ptime:30
a=sendrecv
m=video 47858 RTP/AVP 108 34 99
a=rtpmap:108 VP8/90000
a=rtpmap:34 H263/90000
a=rtpmap:99 H264/90000
a=fmtp:108 max-fr=30;max-fs=3600
a=fmtp:34 CIF=1;QCIF=2;SQCIF=2
a=sendrecv
------------------------------------------------------------------------
send 382 bytes to udp/[110.144.77.148]:2478 at 12:54:28.851147:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.143.196.78:2478;branch=z9hG4bKffyCGGarnGOQzJf4;rport=2478;received=110.144.77.148
From: <sip:[email protected]>;tag=D0F5835BF736855DF4DB06335E752CD7
To: <sip:[email protected]>
Call-ID: BCC69BBD076D884DC683DC43018A5AD7946EECA8
CSeq: 1 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.4.7~64bit
Content-Length: 0
------------------------------------------------------------------------
2014-11-27 12:54:29.153020 [NOTICE] switch_channel.c:1054 New Channel sofia/internal/[email protected] [6686982c-e300-4916-a9f0-cf2e4141234f]
2014-11-27 12:54:29.153020 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:54:29.153020 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/[email protected]) Running State Change CS_NEW
2014-11-27 12:54:29.153020 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:54:29.153020 [DEBUG] sofia.c:8535 sofia/internal/[email protected] receiving invite from 110.144.77.148:2478 version: 1.4.7 64bit
2014-11-27 12:54:29.153020 [DEBUG] sofia.c:8702 IP 110.144.77.148 Rejected by acl "domains". Falling back to Digest auth.
2014-11-27 12:54:29.153020 [WARNING] sofia_reg.c:1663 SIP auth challenge (INVITE) on sofia profile 'internal' for [[email protected]] from ip 110.144.77.148
2014-11-27 12:54:29.153020 [DEBUG] switch_core_state_machine.c:489 (sofia/internal/[email protected]) State NEW
send 898 bytes to udp/[110.144.77.148]:2478 at 12:54:28.852842:
------------------------------------------------------------------------
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 10.143.196.78:2478;branch=z9hG4bKffyCGGarnGOQzJf4;rport=2478;received=110.144.77.148
From: <sip:[email protected]>;tag=D0F5835BF736855DF4DB06335E752CD7
To: <sip:[email protected]>;tag=aDe7jHZH62Zvm
Call-ID: BCC69BBD076D884DC683DC43018A5AD7946EECA8
CSeq: 1 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.4.7~64bit
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Proxy-Authenticate: Digest realm="sip.therepairgroup.net.au", nonce="50f5d8b3-a27e-4b50-84c1-95de61c0bf78", algorithm=MD5, qop="auth"
Content-Length: 0
------------------------------------------------------------------------
2014-11-27 12:54:29.153020 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:54:29.153020 [DEBUG] sofia.c:2033 detaching session 6686982c-e300-4916-a9f0-cf2e4141234f
recv 367 bytes from udp/[110.144.77.148]:2478 at 12:54:28.956012:
------------------------------------------------------------------------
ACK sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 10.143.196.78:2478;branch=z9hG4bKffyCGGarnGOQzJf4;rport
Max-Forwards: 70
Call-ID: BCC69BBD076D884DC683DC43018A5AD7946EECA8
From: <sip:[email protected]>;tag=D0F5835BF736855DF4DB06335E752CD7
To: <sip:[email protected]>;tag=aDe7jHZH62Zvm
CSeq: 1 ACK
Content-Length: 0
------------------------------------------------------------------------
recv 1478 bytes from udp/[110.144.77.148]:2478 at 12:54:28.965852:
------------------------------------------------------------------------
INVITE sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 10.143.196.78:2478;branch=z9hG4bKxo0mzGtawnLPiIoX;rport
Contact: <sip:[email protected]:2478;rinstance=EAEC46E4>
Max-Forwards: 70
From: <sip:[email protected]>;tag=D0F5835BF736855DF4DB06335E752CD7
Allow: OPTIONS, INVITE, ACK, REFER, CANCEL, BYE, NOTIFY
Supported: replaces, path
User-Agent: Acrobits Softphone Business/3.1
To: <sip:[email protected]>
Content-Type: application/sdp
Call-ID: BCC69BBD076D884DC683DC43018A5AD7946EECA8
CSeq: 2 INVITE
Proxy-Authorization: Digest username="michael",realm="sip.therepairgroup.net.au",algorithm=MD5,uri="sip:[email protected]",nonce="50f5d8b3-a27e-4b50-84c1-95de61c0bf78",qop=auth,cnonce="590099897d639c350b2aa249b9c8b966",nc=00000001,response="15b57c32dfffa22718b47fc45a59e896"
Content-Length: 611
v=0
o=- 3691731472 2505 IN IP4 172.138.170.170
s=erpsypl
c=IN IP4 110.144.77.148
t=0 0
m=audio 48132 RTP/AVP 105 102 3 106 0 8 9 101
a=rtpmap:101 telephone-event/8000
a=rtpmap:102 iLBC/8000
a=rtpmap:105 OPUS/48000/2
a=rtpmap:106 OPUS/48000/2
a=fmtp:101 0-15
a=fmtp:102 mode=30
a=fmtp:105 maxplaybackrate=8000;useinbandfec=1;usedtx=1
a=fmtp:106 maxplaybackrate=16000;useinbandfec=1;usedtx=1
a=ptime:30
a=sendrecv
m=video 47858 RTP/AVP 108 34 99
a=rtpmap:108 VP8/90000
a=rtpmap:34 H263/90000
a=rtpmap:99 H264/90000
a=fmtp:108 max-fr=30;max-fs=3600
a=fmtp:34 CIF=1;QCIF=2;SQCIF=2
a=sendrecv
------------------------------------------------------------------------
send 382 bytes to udp/[110.144.77.148]:2478 at 12:54:28.966244:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.143.196.78:2478;branch=z9hG4bKxo0mzGtawnLPiIoX;rport=2478;received=110.144.77.148
From: <sip:[email protected]>;tag=D0F5835BF736855DF4DB06335E752CD7
To: <sip:[email protected]>
Call-ID: BCC69BBD076D884DC683DC43018A5AD7946EECA8
CSeq: 2 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.4.7~64bit
Content-Length: 0
------------------------------------------------------------------------
2014-11-27 12:54:29.263017 [DEBUG] sofia.c:2138 Re-attaching to session 6686982c-e300-4916-a9f0-cf2e4141234f
2014-11-27 12:54:29.263017 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:54:29.263017 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:54:29.273011 [DEBUG] sofia.c:8535 sofia/internal/[email protected] receiving invite from 110.144.77.148:2478 version: 1.4.7 64bit
2014-11-27 12:54:29.273011 [DEBUG] sofia.c:8702 IP 110.144.77.148 Rejected by acl "domains". Falling back to Digest auth.
2014-11-27 12:54:29.273011 [DEBUG] sofia.c:9791 Setting NAT mode based on nat.auto
2014-11-27 12:54:29.273011 [DEBUG] sofia.c:6373 Channel sofia/internal/[email protected] entering state [received][100]
2014-11-27 12:54:29.273011 [DEBUG] sofia.c:6383 Remote SDP:
v=0
o=- 3691731472 2505 IN IP4 172.138.170.170
s=erpsypl
c=IN IP4 110.144.77.148
t=0 0
m=audio 48132 RTP/AVP 105 102 3 106 0 8 9 101
a=rtpmap:105 OPUS/48000/2
a=fmtp:105 maxplaybackrate=8000;useinbandfec=1;usedtx=1
a=rtpmap:102 iLBC/8000
a=fmtp:102 mode=30
a=rtpmap:106 OPUS/48000/2
a=fmtp:106 maxplaybackrate=16000;useinbandfec=1;usedtx=1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:30
m=video 47858 RTP/AVP 108 34 99
a=rtpmap:108 VP8/90000
a=fmtp:108 max-fr=30;max-fs=3600
a=rtpmap:34 H263/90000
a=fmtp:34 CIF=1;QCIF=2;SQCIF=2
a=rtpmap:99 H264/90000
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [OPUS:105:48000:30:0:1]/[G722:9:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [OPUS:105:48000:30:0:1]/[PCMU:0:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [OPUS:105:48000:30:0:1]/[PCMA:8:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [OPUS:105:48000:30:0:1]/[GSM:3:8000:20:13200:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [OPUS:105:48000:30:0:1]/[iLBC:97:8000:30:13330:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [OPUS:105:48000:30:0:1]/[opus:116:48000:20:0:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3509 Audio Codec Compare [opus:116:48000:20:0:1] is saved as a near-match
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:102:8000:30:0:1]/[G722:9:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:102:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:102:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:102:8000:30:0:1]/[GSM:3:8000:20:13200:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:102:8000:30:0:1]/[iLBC:97:8000:30:13330:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3528 Audio Codec Compare [iLBC:97:8000:30:13330:1] ++++ is saved as a match
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [iLBC:102:8000:30:0:1]/[opus:116:48000:20:0:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:30:13200:1]/[G722:9:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:30:13200:1]/[PCMU:0:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:30:13200:1]/[PCMA:8:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:30:13200:1]/[GSM:3:8000:20:13200:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3509 Audio Codec Compare [GSM:3:8000:20:13200:1] is saved as a near-match
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:30:13200:1]/[iLBC:97:8000:30:13330:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [GSM:3:8000:30:13200:1]/[opus:116:48000:20:0:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [OPUS:106:48000:30:0:1]/[G722:9:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [OPUS:106:48000:30:0:1]/[PCMU:0:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [OPUS:106:48000:30:0:1]/[PCMA:8:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [OPUS:106:48000:30:0:1]/[GSM:3:8000:20:13200:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [OPUS:106:48000:30:0:1]/[iLBC:97:8000:30:13330:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [OPUS:106:48000:30:0:1]/[opus:116:48000:20:0:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3509 Audio Codec Compare [opus:116:48000:20:0:1] is saved as a near-match
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[G722:9:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[PCMU:0:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3509 Audio Codec Compare [PCMU:0:8000:20:64000:1] is saved as a near-match
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[PCMA:8:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[GSM:3:8000:20:13200:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[iLBC:97:8000:30:13330:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[opus:116:48000:20:0:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[G722:9:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[PCMU:0:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[PCMA:8:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3509 Audio Codec Compare [PCMA:8:8000:20:64000:1] is saved as a near-match
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[GSM:3:8000:20:13200:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[iLBC:97:8000:30:13330:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[opus:116:48000:20:0:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [G722:9:8000:30:64000:1]/[G722:9:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3509 Audio Codec Compare [G722:9:8000:20:64000:1] is saved as a near-match
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [G722:9:8000:30:64000:1]/[PCMU:0:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [G722:9:8000:30:64000:1]/[PCMA:8:8000:20:64000:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [G722:9:8000:30:64000:1]/[GSM:3:8000:20:13200:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [G722:9:8000:30:64000:1]/[iLBC:97:8000:30:13330:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [G722:9:8000:30:64000:1]/[opus:116:48000:20:0:1]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3395 Set telephone-event payload to 101
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:2352 Set Codec sofia/internal/[email protected] iLBC/8000 30 ms 240 samples 13330 bits 1 channels
2014-11-27 12:54:29.273011 [DEBUG] switch_core_codec.c:111 sofia/internal/[email protected] Original read codec set to iLBC:97
2014-11-27 12:54:29.273011 [DEBUG] switch_core_media.c:3719 Set 2833 dtmf send/recv payload to 101
2014-11-27 12:54:29.273011 [DEBUG] sofia.c:6658 (sofia/internal/[email protected]) State Change CS_NEW -> CS_INIT
2014-11-27 12:54:29.273011 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/[email protected]) Running State Change CS_INIT
2014-11-27 12:54:29.273011 [DEBUG] switch_core_state_machine.c:510 (sofia/internal/[email protected]) State INIT
2014-11-27 12:54:29.273011 [DEBUG] mod_sofia.c:87 sofia/internal/[email protected] SOFIA INIT
2014-11-27 12:54:29.273011 [DEBUG] switch_core_state_machine.c:40 sofia/internal/[email protected] Standard INIT
2014-11-27 12:54:29.273011 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/[email protected]) State Change CS_INIT -> CS_ROUTING
2014-11-27 12:54:29.273011 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:54:29.273011 [DEBUG] switch_core_state_machine.c:510 (sofia/internal/[email protected]) State INIT going to sleep
2014-11-27 12:54:29.273011 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/[email protected]) Running State Change CS_ROUTING
2014-11-27 12:54:29.273011 [DEBUG] switch_channel.c:2179 (sofia/internal/[email protected]) Callstate Change DOWN -> RINGING
2014-11-27 12:54:29.273011 [DEBUG] switch_core_state_machine.c:526 (sofia/internal/[email protected]) State ROUTING
2014-11-27 12:54:29.273011 [DEBUG] mod_sofia.c:123 sofia/internal/[email protected] SOFIA ROUTING
2014-11-27 12:54:29.273011 [DEBUG] switch_core_state_machine.c:164 sofia/internal/[email protected] Standard ROUTING
2014-11-27 12:54:29.273011 [INFO] mod_dialplan_xml.c:558 Processing michael <michael>->888 in context default
Dialplan: sofia/internal/[email protected] parsing [default->user_exists] continue=true
Dialplan: sofia/internal/[email protected] Absolute Condition [user_exists]
Dialplan: sofia/internal/[email protected] Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
EXECUTE sofia/internal/[email protected] set(user_exists=true)
2014-11-27 12:54:29.273011 [DEBUG] mod_dptools.c:1435 sofia/internal/[email protected] SET [user_exists]=[true]
Dialplan: sofia/internal/[email protected] Regex (PASS) [user_exists] ${user_exists}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/[email protected] Action set(extension_uuid=${user_data ${destination_number}@${domain_name} var extension_uuid})
Dialplan: sofia/internal/[email protected] parsing [default->call-direction] continue=true
Dialplan: sofia/internal/[email protected] Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/internal/[email protected] ANTI-Action set(call_direction=local)
Dialplan: sofia/internal/[email protected] Regex (FAIL) [call-direction] ${user_exists}(true) =~ /^false$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->variables] continue=true
Dialplan: sofia/internal/[email protected] Absolute Condition [variables]
Dialplan: sofia/internal/[email protected] Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/internal/[email protected] Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/[email protected] parsing [default->user_record] continue=true
Dialplan: sofia/internal/[email protected] Absolute Condition [user_record]
Dialplan: sofia/internal/[email protected] Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
EXECUTE sofia/internal/[email protected] set(user_record=)
2014-11-27 12:54:29.273011 [DEBUG] mod_dptools.c:1435 sofia/internal/[email protected] SET [user_record]=[UNDEF]
Dialplan: sofia/internal/[email protected] Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
EXECUTE sofia/internal/[email protected] set(from_user_exists=true)
2014-11-27 12:54:29.273011 [DEBUG] mod_dptools.c:1435 sofia/internal/[email protected] SET [from_user_exists]=[true]
Dialplan: sofia/internal/[email protected] Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/[email protected] Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/[email protected] Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/[email protected] Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/[email protected] Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/[email protected] Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
EXECUTE sofia/internal/[email protected] set(from_user_record=)
2014-11-27 12:54:29.283018 [DEBUG] mod_dptools.c:1435 sofia/internal/[email protected] SET [from_user_record]=[UNDEF]
Dialplan: sofia/internal/[email protected] Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/internal/[email protected] Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/internal/[email protected] Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/internal/[email protected] Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->redial] continue=true
Dialplan: sofia/internal/[email protected] Regex (FAIL) [redial] destination_number(888) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/internal/[email protected] Absolute Condition [redial]
Dialplan: sofia/internal/[email protected] Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/[email protected] parsing [default->user_status] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [user_status] destination_number(888) =~ /^\*22$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->group-intercept] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [group-intercept] destination_number(888) =~ /^\*8$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->eavesdrop] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [eavesdrop] destination_number(888) =~ /^\*88(\d{2,7})$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->call_privacy] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [call_privacy] destination_number(888) =~ /^\*67(\d+)$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->call_return] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [call_return] destination_number(888) =~ /^\*69$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->intercept-ext] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [intercept-ext] destination_number(888) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->dx] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [dx] destination_number(888) =~ /^dx$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->extension-intercom] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [extension-intercom] destination_number(888) =~ /^\*8(\d{2,7})$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->att_xfer] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [att_xfer] destination_number(888) =~ /^att_xfer$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->extension-to-voicemail] continue=false
Dialplan: sofia/internal/[email protected] Regex (PASS) [extension-to-voicemail] username(michael) =~ /^michael$/ break=on-false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [extension-to-voicemail] destination_number(888) =~ /^michael$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->send_to_voicemail] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [send_to_voicemail] destination_number(888) =~ /^\*99(\d{2,7})$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->vmain] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [vmain] destination_number(888) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->xfer_vm] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [xfer_vm] destination_number(888) =~ /^xfer_vm$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->is_transfer] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [is_transfer] destination_number(888) =~ /^is_transfer$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->vmain_user] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [vmain_user] destination_number(888) =~ /^\*97$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->Albury] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [Albury] destination_number(888) =~ /^112$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->Ballarat] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [Ballarat] destination_number(888) =~ /^111$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->Bendigo] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [Bendigo] destination_number(888) =~ /^110$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->Repair-Group-Menu] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [Repair-Group-Menu] destination_number(888) =~ /^999$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->The-Repair-Group] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [The-Repair-Group] destination_number(888) =~ /^3333$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->cf] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [cf] destination_number(888) =~ /^cf$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->delay_echo] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [delay_echo] destination_number(888) =~ /^\*9195$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->echo] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [echo] destination_number(888) =~ /^\*9196$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->is_zrtp_secure] continue=true
Dialplan: sofia/internal/[email protected] Regex (FAIL) [is_zrtp_secure] ${zrtp_secure_media_confirmed}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/[email protected] ANTI-Action eval(not_secure)
Dialplan: sofia/internal/[email protected] parsing [default->milliwatt] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [milliwatt] destination_number(888) =~ /^\*9197$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->is_secure] continue=true
Dialplan: sofia/internal/[email protected] Regex (FAIL) [is_secure] ${sip_via_protocol}(udp) =~ /tls/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->tone_stream] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [tone_stream] destination_number(888) =~ /^\*9198$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->hold_music] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [hold_music] destination_number(888) =~ /^\*9664$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->recordings] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [recordings] destination_number(888) =~ /^\*(732)$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->directory] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [directory] destination_number(888) =~ /^\*411$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->wake-up] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [wake-up] destination_number(888) =~ /^\*(925)$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->valet_park] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [valet_park] destination_number(888) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [valet_park] ${sip_h_Referred-By}() =~ /sip:(.*)@.*/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [valet_park] destination_number(888) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never
Dialplan: sofia/internal/[email protected] Regex (FAIL) [valet_park] destination_number(888) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->operator] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [operator] destination_number(888) =~ /^0$|^operator$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->operator-forward] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [operator-forward] destination_number(888) =~ /^\*000$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->do-not-disturb] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [do-not-disturb] destination_number(888) =~ /^\*78$|\*363$/ break=on-true
Dialplan: sofia/internal/[email protected] Regex (FAIL) [do-not-disturb] destination_number(888) =~ /^\*79$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->call-forward] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [call-forward] destination_number(888) =~ /^\*72$/ break=on-true
Dialplan: sofia/internal/[email protected] Regex (FAIL) [call-forward] destination_number(888) =~ /^\*73$/ break=on-true
Dialplan: sofia/internal/[email protected] Regex (FAIL) [call-forward] destination_number(888) =~ /^\*74$/ break=on-true
Dialplan: sofia/internal/[email protected] parsing [default->follow-me] continue=false
Dialplan: sofia/internal/[email protected] Regex (FAIL) [follow-me] destination_number(888) =~ /^\*21$/ break=on-false
Dialplan: sofia/internal/[email protected] parsing [default->local_extension] continue=false
Dialplan: sofia/internal/[email protected] Regex (PASS) [local_extension] destination_number(888) =~ /(^\d{2,7}|\D+$)/ break=on-false
Dialplan: sofia/internal/[email protected] Action set(dialed_extension=888)
Dialplan: sofia/internal/[email protected] Action export(dialed_extension=888)
Dialplan: sofia/internal/[email protected] Action limit(hash ${domain_name} 888 ${limit_max} ${limit_destination})
Dialplan: sofia/internal/[email protected] Action bind_meta_app(1 ab s execute_extension::dx XML ${context})
Dialplan: sofia/internal/[email protected] Action bind_meta_app(2 ab s record_session::/usr/local/freeswitch/recordings/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.wav)
Dialplan: sofia/internal/[email protected] Action bind_meta_app(3 ab s execute_extension::cf XML ${context})
Dialplan: sofia/internal/[email protected] Action bind_meta_app(4 ab s execute_extension::att_xfer XML ${context})
Dialplan: sofia/internal/[email protected] Action set(hangup_after_bridge=true)
Dialplan: sofia/internal/[email protected] Action set(continue_on_fail=true)
Dialplan: sofia/internal/[email protected] Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
Dialplan: sofia/internal/[email protected] Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
Dialplan: sofia/internal/[email protected] Action set(called_party_call_group=${user_data(${dialed_extension}@${domain_name} var call_group)})
Dialplan: sofia/internal/[email protected] Action hash(insert/${domain_name}-last_dial/${called_party_call_group}/${uuid})
Dialplan: sofia/internal/[email protected] Action bridge(user/${destination_number}@${domain_name})
Dialplan: sofia/internal/[email protected] Action answer()
Dialplan: sofia/internal/[email protected] Action sleep(1000)
Dialplan: sofia/internal/[email protected] Action set(voicemail_action=save)
Dialplan: sofia/internal/[email protected] Action set(voicemail_id=888)
Dialplan: sofia/internal/[email protected] Action set(voicemail_profile=default)
Dialplan: sofia/internal/[email protected] Action lua(app.lua voicemail)
2014-11-27 12:54:29.283018 [DEBUG] switch_core_state_machine.c:214 (sofia/internal/[email protected]) State Change CS_ROUTING -> CS_EXECUTE
2014-11-27 12:54:29.283018 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:54:29.283018 [DEBUG] switch_core_state_machine.c:526 (sofia/internal/[email protected]) State ROUTING going to sleep
2014-11-27 12:54:29.283018 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/[email protected]) Running State Change CS_EXECUTE
2014-11-27 12:54:29.283018 [DEBUG] switch_core_state_machine.c:533 (sofia/internal/[email protected]) State EXECUTE
2014-11-27 12:54:29.283018 [DEBUG] mod_sofia.c:178 sofia/internal/[email protected] SOFIA EXECUTE
2014-11-27 12:54:29.283018 [DEBUG] switch_core_state_machine.c:256 sofia/internal/[email protected] Standard EXECUTE
EXECUTE sofia/internal/[email protected] set(extension_uuid=3d4733c9-2e96-4559-9ca9-cc19e2455418)
2014-11-27 12:54:29.283018 [DEBUG] mod_dptools.c:1435 sofia/internal/[email protected] SET [extension_uuid]=[3d4733c9-2e96-4559-9ca9-cc19e2455418]
EXECUTE sofia/internal/[email protected] set(call_direction=local)
2014-11-27 12:54:29.283018 [DEBUG] mod_dptools.c:1435 sofia/internal/[email protected] SET [call_direction]=[local]
EXECUTE sofia/internal/[email protected] export(origination_callee_id_name=888)
2014-11-27 12:54:29.283018 [DEBUG] switch_channel.c:1246 EXPORT (export_vars) [origination_callee_id_name]=[888]
EXECUTE sofia/internal/[email protected] set(RFC2822_DATE=Thu, 27 Nov 2014 12:54:29 +0000)
2014-11-27 12:54:29.283018 [DEBUG] mod_dptools.c:1435 sofia/internal/[email protected] SET [RFC2822_DATE]=[Thu, 27 Nov 2014 12:54:29 +0000]
EXECUTE sofia/internal/[email protected] hash(insert/sip.therepairgroup.net.au-last_dial/michael/888)
EXECUTE sofia/internal/[email protected] eval(not_secure)
EXECUTE sofia/internal/[email protected] set(dialed_extension=888)
2014-11-27 12:54:29.283018 [DEBUG] mod_dptools.c:1435 sofia/internal/[email protected] SET [dialed_extension]=[888]
EXECUTE sofia/internal/[email protected] export(dialed_extension=888)
2014-11-27 12:54:29.283018 [DEBUG] switch_channel.c:1246 EXPORT (export_vars) [dialed_extension]=[888]
EXECUTE sofia/internal/[email protected] limit(hash sip.therepairgroup.net.au 888 5 )
2014-11-27 12:54:29.283018 [INFO] switch_limit.c:126 incr called: sip.therepairgroup.net.au_888 max:5, interval:0
2014-11-27 12:54:29.283018 [INFO] mod_hash.c:202 Usage for sip.therepairgroup.net.au_888 is now 1/5
EXECUTE sofia/internal/[email protected] bind_meta_app(1 ab s execute_extension::dx XML default)
2014-11-27 12:54:29.283018 [INFO] switch_ivr_async.c:3645 Bound A-Leg: *1 execute_extension::dx XML default
2014-11-27 12:54:29.283018 [INFO] switch_ivr_async.c:3653 Bound B-Leg: *1 execute_extension::dx XML default
EXECUTE sofia/internal/[email protected] bind_meta_app(2 ab s record_session::/usr/local/freeswitch/recordings/sip.therepairgroup.net.au/archive/2014/Nov/27/6686982c-e300-4916-a9f0-cf2e4141234f.wav)
2014-11-27 12:54:29.283018 [INFO] switch_ivr_async.c:3645 Bound A-Leg: *2 record_session::/usr/local/freeswitch/recordings/sip.therepairgroup.net.au/archive/2014/Nov/27/6686982c-e300-4916-a9f0-cf2e4141234f.wav
2014-11-27 12:54:29.283018 [INFO] switch_ivr_async.c:3653 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/sip.therepairgroup.net.au/archive/2014/Nov/27/6686982c-e300-4916-a9f0-cf2e4141234f.wav
EXECUTE sofia/internal/[email protected] bind_meta_app(3 ab s execute_extension::cf XML default)
2014-11-27 12:54:29.283018 [INFO] switch_ivr_async.c:3645 Bound A-Leg: *3 execute_extension::cf XML default
2014-11-27 12:54:29.283018 [INFO] switch_ivr_async.c:3653 Bound B-Leg: *3 execute_extension::cf XML default
EXECUTE sofia/internal/[email protected] bind_meta_app(4 ab s execute_extension::att_xfer XML default)
2014-11-27 12:54:29.283018 [INFO] switch_ivr_async.c:3645 Bound A-Leg: *4 execute_extension::att_xfer XML default
2014-11-27 12:54:29.283018 [INFO] switch_ivr_async.c:3653 Bound B-Leg: *4 execute_extension::att_xfer XML default
EXECUTE sofia/internal/[email protected] set(hangup_after_bridge=true)
2014-11-27 12:54:29.293020 [DEBUG] mod_dptools.c:1435 sofia/internal/[email protected] SET [hangup_after_bridge]=[true]
EXECUTE sofia/internal/[email protected] set(continue_on_fail=true)
2014-11-27 12:54:29.293020 [DEBUG] mod_dptools.c:1435 sofia/internal/[email protected] SET [continue_on_fail]=[true]
EXECUTE sofia/internal/[email protected] hash(insert/sip.therepairgroup.net.au-call_return/888/michael)
EXECUTE sofia/internal/[email protected] hash(insert/sip.therepairgroup.net.au-last_dial_ext/888/6686982c-e300-4916-a9f0-cf2e4141234f)
EXECUTE sofia/internal/[email protected] set(called_party_call_group=)
2014-11-27 12:54:29.293020 [DEBUG] mod_dptools.c:1435 sofia/internal/[email protected] SET [called_party_call_group]=[UNDEF]
EXECUTE sofia/internal/[email protected] hash(insert/sip.therepairgroup.net.au-last_dial//6686982c-e300-4916-a9f0-cf2e4141234f)
EXECUTE sofia/internal/[email protected] bridge(user/[email protected])
2014-11-27 12:54:29.293020 [DEBUG] switch_channel.c:1200 sofia/internal/[email protected] EXPORTING[export_vars] [domain_name]=[sip.therepairgroup.net.au] to event
2014-11-27 12:54:29.293020 [DEBUG] switch_channel.c:1200 sofia/internal/[email protected] EXPORTING[export_vars] [origination_callee_id_name]=[888] to event
2014-11-27 12:54:29.293020 [DEBUG] switch_channel.c:1200 sofia/internal/[email protected] EXPORTING[export_vars] [dialed_extension]=[888] to event
2014-11-27 12:54:29.293020 [DEBUG] switch_ivr_originate.c:2079 Parsing global variables
2014-11-27 12:54:29.293020 [DEBUG] switch_channel.c:1200 sofia/internal/[email protected] EXPORTING[export_vars] [domain_name]=[sip.therepairgroup.net.au] to event
2014-11-27 12:54:29.293020 [DEBUG] switch_channel.c:1200 sofia/internal/[email protected] EXPORTING[export_vars] [origination_callee_id_name]=[888] to event
2014-11-27 12:54:29.293020 [DEBUG] switch_channel.c:1200 sofia/internal/[email protected] EXPORTING[export_vars] [dialed_extension]=[888] to event
2014-11-27 12:54:29.293020 [DEBUG] switch_ivr_originate.c:2079 Parsing global variables
2014-11-27 12:54:29.293020 [DEBUG] switch_event.c:1688 Parsing variable [sip_invite_domain]=[sip.therepairgroup.net.au]
2014-11-27 12:54:29.293020 [DEBUG] switch_event.c:1688 Parsing variable [leg_timeout]=[30]
2014-11-27 12:54:29.293020 [DEBUG] switch_event.c:1688 Parsing variable [presence_id]=[[email protected]]
2014-11-27 12:54:29.293020 [NOTICE] switch_channel.c:1054 New Channel sofia/internal/sip:[email protected]:52073 [cd64e632-33e3-4c54-9eec-e44ef7ceb42a]
2014-11-27 12:54:29.293020 [DEBUG] mod_sofia.c:4564 (sofia/internal/sip:[email protected]:52073) State Change CS_NEW -> CS_INIT
2014-11-27 12:54:29.293020 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/sip:[email protected]:52073 [BREAK]
2014-11-27 12:54:29.293020 [DEBUG] switch_ivr_originate.c:2820 sofia/internal/sip:[email protected]:52073 Setting leg timeout to 30
2014-11-27 12:54:29.293020 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/sip:[email protected]:52073) Running State Change CS_INIT
2014-11-27 12:54:29.293020 [DEBUG] switch_core_state_machine.c:510 (sofia/internal/sip:[email protected]:52073) State INIT
2014-11-27 12:54:29.293020 [DEBUG] mod_sofia.c:87 sofia/internal/sip:[email protected]:52073 SOFIA INIT
2014-11-27 12:54:29.293020 [DEBUG] sofia_glue.c:1197 sip:[email protected]:52073;rinstance=55733CF9 Setting proxy route to sofia/internal/sip:[email protected]:52073
2014-11-27 12:54:29.293020 [DEBUG] sofia_glue.c:1226 sofia/internal/sip:[email protected]:52073 sending invite version: 1.4.7 64bit
Local SDP:
v=0
o=FreeSWITCH 1417069461 1417069462 IN IP4 150.101.217.242
s=FreeSWITCH
c=IN IP4 150.101.217.242
t=0 0
m=audio 23408 RTP/AVP 102 9 0 8 3 105 101 13
a=rtpmap:102 iLBC/8000
a=fmtp:102 mode=30
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:105 opus/48000/2
a=fmtp:105 maxplaybackrate=8000;useinbandfec=1;usedtx=1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2014-11-27 12:54:29.293020 [DEBUG] switch_core_state_machine.c:40 sofia/internal/sip:[email protected]:52073 Standard INIT
2014-11-27 12:54:29.293020 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/sip:[email protected]:52073) State Change CS_INIT -> CS_ROUTING
2014-11-27 12:54:29.293020 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/sip:[email protected]:52073 [BREAK]
2014-11-27 12:54:29.293020 [DEBUG] switch_core_state_machine.c:510 (sofia/internal/sip:[email protected]:52073) State INIT going to sleep
2014-11-27 12:54:29.293020 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/sip:[email protected]:52073) Running State Change CS_ROUTING
send 1471 bytes to udp/[162.243.35.55]:52073 at 12:54:28.997508:
------------------------------------------------------------------------
INVITE sip:[email protected]:52073;rinstance=55733CF9 SIP/2.0
Via: SIP/2.0/UDP 150.101.217.242;rport;branch=z9hG4bK57DDH1Xpjc4KB
Route: <sip:[email protected]:52073>;rinstance=55733CF9
Max-Forwards: 69
From: "michael" <sip:[email protected]>;tag=cZ0rp70r0mc2B
To: <sip:[email protected]:52073;rinstance=55733CF9>
Call-ID: 5d938996-f0d7-1232-1f81-cbe53156afb6
CSeq: 68210370 INVITE
Contact: <sip:[email protected]:5060>
User-Agent: FreeSWITCH-mod_sofia/1.4.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 436
X-FS-Support: update_display,send_info
Remote-Party-ID: "michael" <sip:[email protected]>;party=calling;screen=yes;privacy=off
v=0
o=FreeSWITCH 1417069461 1417069462 IN IP4 150.101.217.242
s=FreeSWITCH
c=IN IP4 150.101.217.242
t=0 0
m=audio 23408 RTP/AVP 102 9 0 8 3 105 101 13
a=rtpmap:102 iLBC/8000
a=fmtp:102 mode=30
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:105 opus/48000/2
a=fmtp:105 maxplaybackrate=8000;useinbandfec=1;usedtx=1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
2014-11-27 12:54:29.293020 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/sip:[email protected]:52073 [BREAK]
2014-11-27 12:54:29.293020 [DEBUG] sofia.c:6373 Channel sofia/internal/sip:[email protected]:52073 entering state [calling][0]
2014-11-27 12:54:29.293020 [DEBUG] switch_core_state_machine.c:526 (sofia/internal/sip:[email protected]:52073) State ROUTING
2014-11-27 12:54:29.293020 [DEBUG] mod_sofia.c:123 sofia/internal/sip:[email protected]:52073 SOFIA ROUTING
2014-11-27 12:54:29.293020 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/sip:[email protected]:52073) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2014-11-27 12:54:29.293020 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/sip:[email protected]:52073 [BREAK]
2014-11-27 12:54:29.293020 [DEBUG] switch_core_state_machine.c:526 (sofia/internal/sip:[email protected]:52073) State ROUTING going to sleep
2014-11-27 12:54:29.293020 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/sip:[email protected]:52073) Running State Change CS_CONSUME_MEDIA
2014-11-27 12:54:29.293020 [DEBUG] switch_core_state_machine.c:545 (sofia/internal/sip:[email protected]:52073) State CONSUME_MEDIA
2014-11-27 12:54:29.293020 [DEBUG] switch_core_state_machine.c:545 (sofia/internal/sip:[email protected]:52073) State CONSUME_MEDIA going to sleep
send 1471 bytes to udp/[162.243.35.55]:52073 at 12:54:29.997784:
------------------------------------------------------------------------
INVITE sip:[email protected]:52073;rinstance=55733CF9 SIP/2.0
Via: SIP/2.0/UDP 150.101.217.242;rport;branch=z9hG4bK57DDH1Xpjc4KB
Route: <sip:[email protected]:52073>;rinstance=55733CF9
Max-Forwards: 69
From: "michael" <sip:[email protected]>;tag=cZ0rp70r0mc2B
To: <sip:[email protected]:52073;rinstance=55733CF9>
Call-ID: 5d938996-f0d7-1232-1f81-cbe53156afb6
CSeq: 68210370 INVITE
Contact: <sip:[email protected]:5060>
User-Agent: FreeSWITCH-mod_sofia/1.4.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 436
X-FS-Support: update_display,send_info
Remote-Party-ID: "michael" <sip:[email protected]>;party=calling;screen=yes;privacy=off
v=0
o=FreeSWITCH 1417069461 1417069462 IN IP4 150.101.217.242
s=FreeSWITCH
c=IN IP4 150.101.217.242
t=0 0
m=audio 23408 RTP/AVP 102 9 0 8 3 105 101 13
a=rtpmap:102 iLBC/8000
a=fmtp:102 mode=30
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:105 opus/48000/2
a=fmtp:105 maxplaybackrate=8000;useinbandfec=1;usedtx=1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 1471 bytes to udp/[162.243.35.55]:52073 at 12:54:31.997749:
------------------------------------------------------------------------
INVITE sip:[email protected]:52073;rinstance=55733CF9 SIP/2.0
Via: SIP/2.0/UDP 150.101.217.242;rport;branch=z9hG4bK57DDH1Xpjc4KB
Route: <sip:[email protected]:52073>;rinstance=55733CF9
Max-Forwards: 69
From: "michael" <sip:[email protected]>;tag=cZ0rp70r0mc2B
To: <sip:[email protected]:52073;rinstance=55733CF9>
Call-ID: 5d938996-f0d7-1232-1f81-cbe53156afb6
CSeq: 68210370 INVITE
Contact: <sip:[email protected]:5060>
User-Agent: FreeSWITCH-mod_sofia/1.4.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 436
X-FS-Support: update_display,send_info
Remote-Party-ID: "michael" <sip:[email protected]>;party=calling;screen=yes;privacy=off
v=0
o=FreeSWITCH 1417069461 1417069462 IN IP4 150.101.217.242
s=FreeSWITCH
c=IN IP4 150.101.217.242
t=0 0
m=audio 23408 RTP/AVP 102 9 0 8 3 105 101 13
a=rtpmap:102 iLBC/8000
a=fmtp:102 mode=30
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:105 opus/48000/2
a=fmtp:105 maxplaybackrate=8000;useinbandfec=1;usedtx=1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
send 1471 bytes to udp/[162.243.35.55]:52073 at 12:54:35.997749:
------------------------------------------------------------------------
INVITE sip:[email protected]:52073;rinstance=55733CF9 SIP/2.0
Via: SIP/2.0/UDP 150.101.217.242;rport;branch=z9hG4bK57DDH1Xpjc4KB
Route: <sip:[email protected]:52073>;rinstance=55733CF9
Max-Forwards: 69
From: "michael" <sip:[email protected]>;tag=cZ0rp70r0mc2B
To: <sip:[email protected]:52073;rinstance=55733CF9>
Call-ID: 5d938996-f0d7-1232-1f81-cbe53156afb6
CSeq: 68210370 INVITE
Contact: <sip:[email protected]:5060>
User-Agent: FreeSWITCH-mod_sofia/1.4.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 436
X-FS-Support: update_display,send_info
Remote-Party-ID: "michael" <sip:[email protected]>;party=calling;screen=yes;privacy=off
v=0
o=FreeSWITCH 1417069461 1417069462 IN IP4 150.101.217.242
s=FreeSWITCH
c=IN IP4 150.101.217.242
t=0 0
m=audio 23408 RTP/AVP 102 9 0 8 3 105 101 13
a=rtpmap:102 iLBC/8000
a=fmtp:102 mode=30
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:105 opus/48000/2
a=fmtp:105 maxplaybackrate=8000;useinbandfec=1;usedtx=1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
recv 805 bytes from udp/[10.1.1.39]:5060 at 12:54:42.090188:
------------------------------------------------------------------------
REGISTER sip:sip.therepairgroup.net.au SIP/2.0
Via: SIP/2.0/UDP 10.1.1.39:5060;branch=z9hG4bK-4f05eef3
From: Albury iAdrenalin <sip:[email protected]>;tag=ee7e47c9bffde7e6o1
To: Albury iAdrenalin <sip:[email protected]>
Call-ID: [email protected]
CSeq: 39566 REGISTER
Max-Forwards: 70
Authorization: Digest username="albury",realm="sip.therepairgroup.net.au",nonce="d09e7b29-4185-41f6-857e-7bf757e82cf7",uri="sip:sip.therepairgroup.net.au",algorithm=MD5,response="476837d02630ed49dd1d7a99bf0b0273",qop=auth,nc=0000010f,cnonce="86174af"
Contact: Albury iAdrenalin <sip:[email protected]:5060>;expires=3600
User-Agent: Linksys/PAP2T-5.1.6(LS)
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER
Supported: x-sipura, replaces
------------------------------------------------------------------------
send 597 bytes to udp/[10.1.1.39]:5060 at 12:54:42.095932:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.1.1.39:5060;branch=z9hG4bK-4f05eef3
From: Albury iAdrenalin <sip:[email protected]>;tag=ee7e47c9bffde7e6o1
To: Albury iAdrenalin <sip:[email protected]>;tag=D8SHr2HvXX2mQ
Call-ID: [email protected]
CSeq: 39566 REGISTER
Contact: <sip:[email protected]:5060>;expires=30
Date: Thu, 27 Nov 2014 12:54:42 GMT
User-Agent: FreeSWITCH-mod_sofia/1.4.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Content-Length: 0
------------------------------------------------------------------------
send 1471 bytes to udp/[162.243.35.55]:52073 at 12:54:43.997780:
------------------------------------------------------------------------
INVITE sip:[email protected]:52073;rinstance=55733CF9 SIP/2.0
Via: SIP/2.0/UDP 150.101.217.242;rport;branch=z9hG4bK57DDH1Xpjc4KB
Route: <sip:[email protected]:52073>;rinstance=55733CF9
Max-Forwards: 69
From: "michael" <sip:[email protected]>;tag=cZ0rp70r0mc2B
To: <sip:[email protected]:52073;rinstance=55733CF9>
Call-ID: 5d938996-f0d7-1232-1f81-cbe53156afb6
CSeq: 68210370 INVITE
Contact: <sip:[email protected]:5060>
User-Agent: FreeSWITCH-mod_sofia/1.4.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 436
X-FS-Support: update_display,send_info
Remote-Party-ID: "michael" <sip:[email protected]>;party=calling;screen=yes;privacy=off
v=0
o=FreeSWITCH 1417069461 1417069462 IN IP4 150.101.217.242
s=FreeSWITCH
c=IN IP4 150.101.217.242
t=0 0
m=audio 23408 RTP/AVP 102 9 0 8 3 105 101 13
a=rtpmap:102 iLBC/8000
a=fmtp:102 mode=30
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:105 opus/48000/2
a=fmtp:105 maxplaybackrate=8000;useinbandfec=1;usedtx=1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
2014-11-27 12:55:00.013012 [NOTICE] switch_ivr_originate.c:329 Hangup sofia/internal/sip:[email protected]:52073 [CS_CONSUME_MEDIA] [ALLOTTED_TIMEOUT]
2014-11-27 12:55:00.013012 [DEBUG] switch_channel.c:3217 Send signal sofia/internal/sip:[email protected]:52073 [KILL]
2014-11-27 12:55:00.013012 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/sip:[email protected]:52073 [BREAK]
2014-11-27 12:55:00.013012 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/sip:[email protected]:52073) Running State Change CS_HANGUP
2014-11-27 12:55:00.013012 [DEBUG] switch_core_state_machine.c:733 (sofia/internal/sip:[email protected]:52073) Callstate Change DOWN -> HANGUP
2014-11-27 12:55:00.013012 [DEBUG] switch_core_state_machine.c:735 (sofia/internal/sip:[email protected]:52073) State HANGUP
2014-11-27 12:55:00.013012 [DEBUG] mod_sofia.c:413 Channel sofia/internal/sip:[email protected]:52073 hanging up, cause: ALLOTTED_TIMEOUT
2014-11-27 12:55:00.013012 [DEBUG] mod_sofia.c:475 Sending CANCEL to sofia/internal/sip:[email protected]:52073
2014-11-27 12:55:00.013012 [DEBUG] switch_core_state_machine.c:58 sofia/internal/sip:[email protected]:52073 Standard HANGUP, cause: ALLOTTED_TIMEOUT
2014-11-27 12:55:00.013012 [DEBUG] switch_core_state_machine.c:735 (sofia/internal/sip:[email protected]:52073) State HANGUP going to sleep
2014-11-27 12:55:00.013012 [DEBUG] switch_core_state_machine.c:502 (sofia/internal/sip:[email protected]:52073) State Change CS_HANGUP -> CS_REPORTING
2014-11-27 12:55:00.013012 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/sip:[email protected]:52073 [BREAK]
2014-11-27 12:55:00.013012 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/sip:[email protected]:52073) Running State Change CS_REPORTING
2014-11-27 12:55:00.013012 [DEBUG] switch_core_state_machine.c:821 (sofia/internal/sip:[email protected]:52073) State REPORTING
2014-11-27 12:55:00.013012 [DEBUG] switch_core_state_machine.c:102 sofia/internal/sip:[email protected]:52073 Standard REPORTING, cause: ALLOTTED_TIMEOUT
2014-11-27 12:55:00.013012 [DEBUG] switch_core_state_machine.c:821 (sofia/internal/sip:[email protected]:52073) State REPORTING going to sleep
2014-11-27 12:55:00.013012 [DEBUG] switch_core_state_machine.c:496 (sofia/internal/sip:[email protected]:52073) State Change CS_REPORTING -> CS_DESTROY
2014-11-27 12:55:00.013012 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/sip:[email protected]:52073 [BREAK]
2014-11-27 12:55:00.013012 [DEBUG] switch_core_session.c:1614 Session 42 (sofia/internal/sip:[email protected]:52073) Locked, Waiting on external entities
2014-11-27 12:55:00.033011 [DEBUG] switch_ivr_originate.c:3695 Originate Resulted in Error Cause: 602 [ALLOTTED_TIMEOUT]
2014-11-27 12:55:00.033011 [NOTICE] switch_core_session.c:1632 Session 42 (sofia/internal/sip:[email protected]:52073) Ended
2014-11-27 12:55:00.033011 [NOTICE] switch_core_session.c:1636 Close Channel sofia/internal/sip:[email protected]:52073 [CS_DESTROY]
2014-11-27 12:55:00.033011 [DEBUG] switch_core_state_machine.c:624 (sofia/internal/sip:[email protected]:52073) Running State Change CS_DESTROY
2014-11-27 12:55:00.033011 [DEBUG] switch_core_state_machine.c:634 (sofia/internal/sip:[email protected]:52073) State DESTROY
2014-11-27 12:55:00.033011 [DEBUG] mod_sofia.c:323 sofia/internal/sip:[email protected]:52073 SOFIA DESTROY
2014-11-27 12:55:00.033011 [DEBUG] switch_core_state_machine.c:109 sofia/internal/sip:[email protected]:52073 Standard DESTROY
2014-11-27 12:55:00.033011 [DEBUG] switch_core_state_machine.c:634 (sofia/internal/sip:[email protected]:52073) State DESTROY going to sleep
2014-11-27 12:55:00.033011 [NOTICE] switch_ivr_originate.c:2708 Cannot create outgoing channel of type [user] cause: [ALLOTTED_TIMEOUT]
2014-11-27 12:55:00.033011 [DEBUG] switch_ivr_originate.c:3695 Originate Resulted in Error Cause: 602 [ALLOTTED_TIMEOUT]
2014-11-27 12:55:00.033011 [INFO] mod_dptools.c:3234 Originate Failed. Cause: ALLOTTED_TIMEOUT
EXECUTE sofia/internal/[email protected] answer()
2014-11-27 12:55:00.043018 [DEBUG] switch_core_media.c:4939 AUDIO RTP [sofia/internal/[email protected]] 150.101.217.242 port 16764 -> 110.144.77.148 port 48132 codec: 102 ms: 30
2014-11-27 12:55:00.043018 [DEBUG] switch_rtp.c:3383 Starting timer [soft] 240 bytes per 30ms
2014-11-27 12:55:00.043018 [DEBUG] switch_core_media.c:5287 Set 2833 dtmf send payload to 101
2014-11-27 12:55:00.043018 [DEBUG] switch_core_media.c:5293 Set 2833 dtmf receive payload to 101
2014-11-27 12:55:00.043018 [DEBUG] mod_sofia.c:778 Local SDP sofia/internal/[email protected]:
v=0
o=FreeSWITCH 1417076136 1417076137 IN IP4 150.101.217.242
s=FreeSWITCH
c=IN IP4 150.101.217.242
t=0 0
m=audio 16764 RTP/AVP 102 101
a=rtpmap:102 iLBC/8000
a=fmtp:102 mode=30
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:30
a=sendrecv
2014-11-27 12:55:00.043018 [DEBUG] switch_core_session.c:907 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:55:00.043018 [NOTICE] mod_dptools.c:1258 Channel [sofia/internal/[email protected]] has been answered
send 1258 bytes to udp/[110.144.77.148]:2478 at 12:54:59.741573:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.143.196.78:2478;branch=z9hG4bKxo0mzGtawnLPiIoX;rport=2478;received=110.144.77.148
From: <sip:[email protected]>;tag=D0F5835BF736855DF4DB06335E752CD7
To: <sip:[email protected]>;tag=Bp7ZmcgN3BpFg
Call-ID: BCC69BBD076D884DC683DC43018A5AD7946EECA8
CSeq: 2 INVITE
Contact: <sip:[email protected]:5060;transport=udp>
User-Agent: FreeSWITCH-mod_sofia/1.4.7~64bit
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Session-Expires: 120;refresher=uas
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 272
Remote-Party-ID: "888" <sip:[email protected]>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1417076136 1417076137 IN IP4 150.101.217.242
s=FreeSWITCH
c=IN IP4 150.101.217.242
t=0 0
m=audio 16764 RTP/AVP 102 101
a=rtpmap:102 iLBC/8000
a=fmtp:102 mode=30
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:30
m=video 0 RTP/AVP 19
------------------------------------------------------------------------
2014-11-27 12:55:00.043018 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:55:00.043018 [DEBUG] switch_channel.c:3684 (sofia/internal/[email protected]) Callstate Change RINGING -> ACTIVE
2014-11-27 12:55:00.043018 [DEBUG] sofia.c:6373 Channel sofia/internal/[email protected] entering state [completed][200]
EXECUTE sofia/internal/[email protected] sleep(1000)
send 1471 bytes to udp/[162.243.35.55]:52073 at 12:54:59.998369:
------------------------------------------------------------------------
INVITE sip:[email protected]:52073;rinstance=55733CF9 SIP/2.0
Via: SIP/2.0/UDP 150.101.217.242;rport;branch=z9hG4bK57DDH1Xpjc4KB
Route: <sip:[email protected]:52073>;rinstance=55733CF9
Max-Forwards: 69
From: "michael" <sip:[email protected]>;tag=cZ0rp70r0mc2B
To: <sip:[email protected]:52073;rinstance=55733CF9>
Call-ID: 5d938996-f0d7-1232-1f81-cbe53156afb6
CSeq: 68210370 INVITE
Contact: <sip:[email protected]:5060>
User-Agent: FreeSWITCH-mod_sofia/1.4.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 436
X-FS-Support: update_display,send_info
Remote-Party-ID: "michael" <sip:[email protected]>;party=calling;screen=yes;privacy=off
v=0
o=FreeSWITCH 1417069461 1417069462 IN IP4 150.101.217.242
s=FreeSWITCH
c=IN IP4 150.101.217.242
t=0 0
m=audio 23408 RTP/AVP 102 9 0 8 3 105 101 13
a=rtpmap:102 iLBC/8000
a=fmtp:102 mode=30
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:105 opus/48000/2
a=fmtp:105 maxplaybackrate=8000;useinbandfec=1;usedtx=1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
recv 376 bytes from udp/[110.144.77.148]:2478 at 12:55:00.166088:
------------------------------------------------------------------------
ACK sip:[email protected]:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.143.196.78:2478;branch=z9hG4bKJq3B3VeI4B5wiU9l;rport
Max-Forwards: 70
To: <sip:[email protected]>;tag=Bp7ZmcgN3BpFg
From: <sip:[email protected]>;tag=D0F5835BF736855DF4DB06335E752CD7
Call-ID: BCC69BBD076D884DC683DC43018A5AD7946EECA8
CSeq: 2 ACK
Content-Length: 0
------------------------------------------------------------------------
2014-11-27 12:55:00.463047 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:55:00.463047 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:55:00.463047 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:55:00.493023 [DEBUG] sofia.c:6373 Channel sofia/internal/[email protected] entering state [ready][200]
2014-11-27 12:55:00.643018 [DEBUG] switch_rtp.c:5617 Correct ip/port confirmed.
EXECUTE sofia/internal/[email protected] set(voicemail_action=save)
2014-11-27 12:55:01.063022 [DEBUG] mod_dptools.c:1435 sofia/internal/[email protected] SET [voicemail_action]=[save]
EXECUTE sofia/internal/[email protected] set(voicemail_id=888)
2014-11-27 12:55:01.063022 [DEBUG] mod_dptools.c:1435 sofia/internal/[email protected] SET [voicemail_id]=[888]
EXECUTE sofia/internal/[email protected] set(voicemail_profile=default)
2014-11-27 12:55:01.063022 [DEBUG] mod_dptools.c:1435 sofia/internal/[email protected] SET [voicemail_profile]=[default]
EXECUTE sofia/internal/[email protected] lua(app.lua voicemail)
2014-11-27 12:55:01.073017 [DEBUG] freeswitch_lua.cpp:360 DBH handle 20e6110 Connected.
EXECUTE sofia/internal/[email protected] unbind_meta_app()
2014-11-27 12:55:01.073017 [INFO] switch_ivr_async.c:3552 UnBound A-Leg: ALL
2014-11-27 12:55:01.083015 [DEBUG] switch_ivr_play_say.c:1305 Codec Activated L16@8000hz 1 channels 30ms
2014-11-27 12:55:02.413034 [DEBUG] switch_ivr_play_say.c:1714 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-person.wav
2014-11-27 12:55:02.413034 [DEBUG] switch_ivr.c:3013 No language specified - Using [en]
2014-11-27 12:55:02.423023 [DEBUG] switch_ivr_play_say.c:1305 Codec Activated L16@8000hz 1 channels 30ms
2014-11-27 12:55:03.703013 [DEBUG] switch_ivr_play_say.c:1714 done playing file file_string://digits/8.wav!digits/8.wav!digits/8.wav
2014-11-27 12:55:03.703013 [DEBUG] switch_ivr_play_say.c:1305 Codec Activated L16@8000hz 1 channels 30ms
2014-11-27 12:55:04.693019 [DEBUG] switch_ivr_play_say.c:1714 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-not_available.wav
2014-11-27 12:55:04.903016 [DEBUG] switch_cpp.cpp:838 getDigits dtmf_buf:
2014-11-27 12:55:04.903016 [DEBUG] switch_ivr_play_say.c:1305 Codec Activated L16@8000hz 1 channels 30ms
recv 567 bytes from udp/[110.144.77.148]:2478 at 12:55:05.008182:
------------------------------------------------------------------------
BYE sip:[email protected]:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.143.196.78:2478;branch=z9hG4bKSZ9m3j7Tz83dreJi;rport
Contact: <sip:[email protected]:2478;rinstance=EAEC46E4>
Max-Forwards: 70
From: <sip:[email protected]>;tag=D0F5835BF736855DF4DB06335E752CD7
Allow: OPTIONS, INVITE, ACK, REFER, CANCEL, BYE, NOTIFY
Supported: replaces, path
User-Agent: Acrobits Softphone Business/3.1
To: <sip:[email protected]>;tag=Bp7ZmcgN3BpFg
Call-ID: BCC69BBD076D884DC683DC43018A5AD7946EECA8
CSeq: 3 BYE
Content-Length: 0
------------------------------------------------------------------------
2014-11-27 12:55:05.303021 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:55:05.323021 [NOTICE] sofia.c:926 Hangup sofia/internal/[email protected] [CS_EXECUTE] [NORMAL_CLEARING]
2014-11-27 12:55:05.323021 [DEBUG] switch_channel.c:3217 Send signal sofia/internal/[email protected] [KILL]
2014-11-27 12:55:05.323021 [INFO] mod_hash.c:304 Usage for sip.therepairgroup.net.au_888 is now 0
2014-11-27 12:55:05.323021 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:55:05.323021 [DEBUG] switch_ivr_play_say.c:1714 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-record_message.wav
send 537 bytes to udp/[110.144.77.148]:2478 at 12:55:05.020715:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.143.196.78:2478;branch=z9hG4bKSZ9m3j7Tz83dreJi;rport=2478;received=110.144.77.148
From: <sip:[email protected]>;tag=D0F5835BF736855DF4DB06335E752CD7
To: <sip:[email protected]>;tag=Bp7ZmcgN3BpFg
Call-ID: BCC69BBD076D884DC683DC43018A5AD7946EECA8
CSeq: 3 BYE
User-Agent: FreeSWITCH-mod_sofia/1.4.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Content-Length: 0
------------------------------------------------------------------------
2014-11-27 12:55:05.333014 [DEBUG] freeswitch_lua.cpp:377 DBH handle 20e6110 released.
2014-11-27 12:55:05.333014 [DEBUG] switch_cpp.cpp:1056 sofia/internal/[email protected] destroy/unlink session from object
2014-11-27 12:55:05.333014 [DEBUG] switch_core_session.c:2887 sofia/internal/[email protected] skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2014-11-27 12:55:05.333014 [DEBUG] switch_core_state_machine.c:533 (sofia/internal/[email protected]) State EXECUTE going to sleep
2014-11-27 12:55:05.333014 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/[email protected]) Running State Change CS_HANGUP
2014-11-27 12:55:05.333014 [DEBUG] switch_core_state_machine.c:733 (sofia/internal/[email protected]) Callstate Change ACTIVE -> HANGUP
2014-11-27 12:55:05.333014 [DEBUG] switch_core_state_machine.c:735 (sofia/internal/[email protected]) State HANGUP
2014-11-27 12:55:05.333014 [DEBUG] mod_sofia.c:413 Channel sofia/internal/[email protected] hanging up, cause: NORMAL_CLEARING
2014-11-27 12:55:05.333014 [DEBUG] switch_core_state_machine.c:58 sofia/internal/[email protected] Standard HANGUP, cause: NORMAL_CLEARING
2014-11-27 12:55:05.333014 [DEBUG] switch_core_state_machine.c:735 (sofia/internal/[email protected]) State HANGUP going to sleep
2014-11-27 12:55:05.333014 [DEBUG] switch_core_state_machine.c:502 (sofia/internal/[email protected]) State Change CS_HANGUP -> CS_REPORTING
2014-11-27 12:55:05.333014 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:55:05.333014 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/[email protected]) Running State Change CS_REPORTING
2014-11-27 12:55:05.333014 [DEBUG] switch_core_state_machine.c:821 (sofia/internal/[email protected]) State REPORTING
2014-11-27 12:55:05.373015 [DEBUG] switch_core_state_machine.c:102 sofia/internal/[email protected] Standard REPORTING, cause: NORMAL_CLEARING
2014-11-27 12:55:05.373015 [DEBUG] switch_core_state_machine.c:821 (sofia/internal/[email protected]) State REPORTING going to sleep
2014-11-27 12:55:05.373015 [DEBUG] switch_core_state_machine.c:496 (sofia/internal/[email protected]) State Change CS_REPORTING -> CS_DESTROY
2014-11-27 12:55:05.373015 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/[email protected] [BREAK]
2014-11-27 12:55:05.373015 [DEBUG] switch_core_session.c:1614 Session 41 (sofia/internal/[email protected]) Locked, Waiting on external entities
2014-11-27 12:55:05.373015 [NOTICE] switch_core_session.c:1632 Session 41 (sofia/internal/[email protected]) Ended
2014-11-27 12:55:05.373015 [NOTICE] switch_core_session.c:1636 Close Channel sofia/internal/[email protected] [CS_DESTROY]
2014-11-27 12:55:05.373015 [DEBUG] switch_core_state_machine.c:624 (sofia/internal/[email protected]) Running State Change CS_DESTROY
2014-11-27 12:55:05.373015 [DEBUG] switch_core_state_machine.c:634 (sofia/internal/[email protected]) State DESTROY
2014-11-27 12:55:05.373015 [DEBUG] mod_sofia.c:323 sofia/internal/[email protected] SOFIA DESTROY
2014-11-27 12:55:05.373015 [DEBUG] switch_core_state_machine.c:109 sofia/internal/[email protected] Standard DESTROY
2014-11-27 12:55:05.373015 [DEBUG] switch_core_state_machine.c:634 (sofia/internal/[email protected]) State DESTROY going to sleep
recv 805 bytes from udp/[10.1.1.39]:5060 at 12:55:10.109684:
------------------------------------------------------------------------
REGISTER sip:sip.therepairgroup.net.au SIP/2.0
Via: SIP/2.0/UDP 10.1.1.39:5060;branch=z9hG4bK-8b74f105
From: Albury iAdrenalin <sip:[email protected]>;tag=ee7e47c9bffde7e6o1
To: Albury iAdrenalin <sip:[email protected]>
Call-ID: [email protected]
CSeq: 39567 REGISTER
Max-Forwards: 70
Authorization: Digest username="albury",realm="sip.therepairgroup.net.au",nonce="d09e7b29-4185-41f6-857e-7bf757e82cf7",uri="sip:sip.therepairgroup.net.au",algorithm=MD5,response="8acc3e2f6c523289dedf5e8633d3e718",qop=auth,nc=00000110,cnonce="86174af"
Contact: Albury iAdrenalin <sip:[email protected]:5060>;expires=3600
User-Agent: Linksys/PAP2T-5.1.6(LS)
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER
Supported: x-sipura, replaces
------------------------------------------------------------------------
send 597 bytes to udp/[10.1.1.39]:5060 at 12:55:10.115254:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.1.1.39:5060;branch=z9hG4bK-8b74f105
From: Albury iAdrenalin <sip:[email protected]>;tag=ee7e47c9bffde7e6o1
To: Albury iAdrenalin <sip:[email protected]>;tag=g35UXK46mr5ca
Call-ID: [email protected]
CSeq: 39567 REGISTER
Contact: <sip:[email protected]:5060>;expires=30
Date: Thu, 27 Nov 2014 12:55:10 GMT
User-Agent: FreeSWITCH-mod_sofia/1.4.7~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Content-Length: 0
------------------------------------------------------------------------
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment