SIP Debugging Enabled for IP: 10.249.11.6 <--- SIP read from WS:10.249.11.6:60226 ---> INVITE sip:951XXXXXXX@10.249.1.5 SIP/2.0 Via: SIP/2.0/WS t16b7je9cm36.invalid;branch=z9hG4bK5544789 Max-Forwards: 69 To: From: ;tag=29r2h7kvh8 Call-ID: 62nevoe9rji599s60jt1 CSeq: 5808 INVITE Contact: Allow: ACK,CANCEL,BYE,OPTIONS,INVITE Content-Type: application/sdp Supported: path, outbound, gruu User-Agent: JsSIP 0.3.7 Content-Length: 2136 v=0 o=- 1869935017634277597 2 IN IP4 127.0.0.1 s=- t=0 0 a=group:BUNDLE audio a=msid-semantic: WMS CZYNkRez1wkazHsSwkKcA4ydgCkl5CDC0HuN m=audio 60463 RTP/SAVPF 111 103 104 0 8 106 105 13 126 c=IN IP4 108.XX.XXX.XX a=rtcp:60463 IN IP4 108.XX.XXX.XX a=candidate:3460887983 1 udp 2113937151 192.168.1.64 60463 typ host generation 0 a=candidate:3460887983 2 udp 2113937151 192.168.1.64 60463 typ host generation 0 a=candidate:647593020 1 udp 2113937151 10.249.11.6 50068 typ host generation 0 a=candidate:647593020 2 udp 2113937151 10.249.11.6 50068 typ host generation 0 a=candidate:2160789855 1 tcp 1509957375 192.168.1.64 0 typ host generation 0 a=candidate:2160789855 2 tcp 1509957375 192.168.1.64 0 typ host generation 0 a=candidate:1746567372 1 tcp 1509957375 10.249.11.6 0 typ host generation 0 a=candidate:1746567372 2 tcp 1509957375 10.249.11.6 0 typ host generation 0 a=candidate:945327227 1 udp 1845501695 108.XX.XXX.XX 60463 typ srflx raddr 192.168.1.64 rport 60463 generation 0 a=candidate:945327227 2 udp 1845501695 108.XX.XXX.XX 60463 typ srflx raddr 192.168.1.64 rport 60463 generation 0 a=ice-ufrag:XmKASgq9hJwVzRlD a=ice-pwd:PdyilKLZt/mTU1dMD1N4sZN7 a=ice-options:google-ice a=fingerprint:sha-256 3A:ED:A7:A6:78:B7:6E:31:4A:08:09:96:5F:CB:59:BE:A4:FC:C8:1C:2A:AA:28:6F:33:36:5B:91:5A:E9:DF:B6 a=setup:actpass a=mid:audio a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level a=sendrecv a=rtcp-mux a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:UyVac1NXaAshGlRtodegx8CBVCcwAV+cMY18ANyI a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:o7VzlwrEYSag/NAONlEnkPgf4G62okh1CRh7lntp a=rtpmap:111 opus/48000/2 a=fmtp:111 minptime=10 a=rtpmap:103 ISAC/16000 a=rtpmap:104 ISAC/32000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:106 CN/32000 a=rtpmap:105 CN/16000 a=rtpmap:13 CN/8000 a=rtpmap:126 telephone-event/8000 a=maxptime:60 a=ssrc:2255398680 cname:MrlWZtW/GnqjrJL1 a=ssrc:2255398680 msid:CZYNkRez1wkazHsSwkKcA4ydgCkl5CDC0HuN b4250d7e-0273-4dcf-9a51-95b1a95288cd a=ssrc:2255398680 mslabel:CZYNkRez1wkazHsSwkKcA4ydgCkl5CDC0HuN a=ssrc:2255398680 label:b4250d7e-0273-4dcf-9a51-95b1a95288cd <-------------> --- (13 headers 45 lines) --- Using INVITE request as basis request - 62nevoe9rji599s60jt1 Found peer '99220' for '99220' from 10.249.11.6:60226 <--- Reliably Transmitting (no NAT) to 10.249.11.6:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/WS t16b7je9cm36.invalid;branch=z9hG4bK5544789;received=10.249.11.6 From: ;tag=29r2h7kvh8 To: ;tag=as6e464ea3 Call-ID: 62nevoe9rji599s60jt1 CSeq: 5808 INVITE Server: FPBX-2.11.0(11.7.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="53f7d9ab" Content-Length: 0 <------------> Scheduling destruction of SIP dialog '62nevoe9rji599s60jt1' in 6400 ms (Method: INVITE) <--- SIP read from WS:10.249.11.6:60226 ---> ACK sip:951XXXXXXX@10.249.1.5 SIP/2.0 Via: SIP/2.0/WS t16b7je9cm36.invalid;branch=z9hG4bK5544789 To: ;tag=as6e464ea3 From: ;tag=29r2h7kvh8 Call-ID: 62nevoe9rji599s60jt1 CSeq: 5808 ACK <-------------> --- (6 headers 0 lines) --- <--- SIP read from WS:10.249.11.6:60226 ---> INVITE sip:951XXXXXXX@10.249.1.5 SIP/2.0 Via: SIP/2.0/WS t16b7je9cm36.invalid;branch=z9hG4bK6083033 Max-Forwards: 69 To: From: ;tag=29r2h7kvh8 Call-ID: 62nevoe9rji599s60jt1 CSeq: 5809 INVITE Authorization: Digest algorithm=MD5, username="99220", realm="asterisk", nonce="53f7d9ab", uri="sip:951XXXXXXX@10.249.1.5", response="3fac7ededf676d957e3793c874b2caf3" Contact: Allow: ACK,CANCEL,BYE,OPTIONS,INVITE Content-Type: application/sdp Supported: path, outbound, gruu User-Agent: JsSIP 0.3.7 Content-Length: 2136 v=0 o=- 1869935017634277597 2 IN IP4 127.0.0.1 s=- t=0 0 a=group:BUNDLE audio a=msid-semantic: WMS CZYNkRez1wkazHsSwkKcA4ydgCkl5CDC0HuN m=audio 60463 RTP/SAVPF 111 103 104 0 8 106 105 13 126 c=IN IP4 108.XX.XXX.XX a=rtcp:60463 IN IP4 108.XX.XXX.XX a=candidate:3460887983 1 udp 2113937151 192.168.1.64 60463 typ host generation 0 a=candidate:3460887983 2 udp 2113937151 192.168.1.64 60463 typ host generation 0 a=candidate:647593020 1 udp 2113937151 10.249.11.6 50068 typ host generation 0 a=candidate:647593020 2 udp 2113937151 10.249.11.6 50068 typ host generation 0 a=candidate:2160789855 1 tcp 1509957375 192.168.1.64 0 typ host generation 0 a=candidate:2160789855 2 tcp 1509957375 192.168.1.64 0 typ host generation 0 a=candidate:1746567372 1 tcp 1509957375 10.249.11.6 0 typ host generation 0 a=candidate:1746567372 2 tcp 1509957375 10.249.11.6 0 typ host generation 0 a=candidate:945327227 1 udp 1845501695 108.XX.XXX.XX 60463 typ srflx raddr 192.168.1.64 rport 60463 generation 0 a=candidate:945327227 2 udp 1845501695 108.XX.XXX.XX 60463 typ srflx raddr 192.168.1.64 rport 60463 generation 0 a=ice-ufrag:XmKASgq9hJwVzRlD a=ice-pwd:PdyilKLZt/mTU1dMD1N4sZN7 a=ice-options:google-ice a=fingerprint:sha-256 3A:ED:A7:A6:78:B7:6E:31:4A:08:09:96:5F:CB:59:BE:A4:FC:C8:1C:2A:AA:28:6F:33:36:5B:91:5A:E9:DF:B6 a=setup:actpass a=mid:audio a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level a=sendrecv a=rtcp-mux a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:UyVac1NXaAshGlRtodegx8CBVCcwAV+cMY18ANyI a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:o7VzlwrEYSag/NAONlEnkPgf4G62okh1CRh7lntp a=rtpmap:111 opus/48000/2 a=fmtp:111 minptime=10 a=rtpmap:103 ISAC/16000 a=rtpmap:104 ISAC/32000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:106 CN/32000 a=rtpmap:105 CN/16000 a=rtpmap:13 CN/8000 a=rtpmap:126 telephone-event/8000 a=maxptime:60 a=ssrc:2255398680 cname:MrlWZtW/GnqjrJL1 a=ssrc:2255398680 msid:CZYNkRez1wkazHsSwkKcA4ydgCkl5CDC0HuN b4250d7e-0273-4dcf-9a51-95b1a95288cd a=ssrc:2255398680 mslabel:CZYNkRez1wkazHsSwkKcA4ydgCkl5CDC0HuN a=ssrc:2255398680 label:b4250d7e-0273-4dcf-9a51-95b1a95288cd <-------------> --- (14 headers 45 lines) --- Using INVITE request as basis request - 62nevoe9rji599s60jt1 Found peer '99220' for '99220' from 10.249.11.6:60226 == Using SIP RTP TOS bits 184 == Using SIP RTP CoS mark 5 Found RTP audio format 111 Found RTP audio format 103 Found RTP audio format 104 Found RTP audio format 0 Found RTP audio format 8 Found RTP audio format 106 Found RTP audio format 105 Found RTP audio format 13 Found RTP audio format 126 [2014-01-24 19:47:40] DEBUG[5243][C-00004ed2]: sip/sdp_crypto.c:285 sdp_crypto_process: Accepting crypto tag 0 [2014-01-24 19:47:40] DEBUG[5243][C-00004ed2]: sip/sdp_crypto.c:310 sdp_crypto_offer: Crypto line: a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:WoaicpKHdFd/l5IvTFeQ+4cVbk/vNdjf91b2XuuJ Found unknown media description format opus for ID 111 Found unknown media description format ISAC for ID 103 Found unknown media description format ISAC for ID 104 Found audio description format PCMU for ID 0 Found audio description format PCMA for ID 8 Found unknown media description format CN for ID 106 Found unknown media description format CN for ID 105 Found audio description format CN for ID 13 Found audio description format telephone-event for ID 126 Capabilities: us - (gsm|ulaw|alaw), peer - audio=(ulaw|alaw)/video=(nothing)/text=(nothing), combined - (ulaw|alaw) Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x3 (telephone-event|CN|), combined - 0x1 (telephone-event|) Peer audio RTP is at port 108.XX.XXX.XX:60463 Looking for 951XXXXXXX in from-internal (domain 10.249.1.5) <--- Transmitting (no NAT) to 10.249.11.6:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/WS t16b7je9cm36.invalid;branch=z9hG4bK6083033;received=10.249.11.6 From: ;tag=29r2h7kvh8 To: Call-ID: 62nevoe9rji599s60jt1 CSeq: 5809 INVITE Server: FPBX-2.11.0(11.7.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Length: 0 <------------> -- Executing [951XXXXXXX@from-internal:1] Macro("SIP/99220-00000653", "user-callerid,LIMIT,EXTERNAL,") in new stack -- Executing [s@macro-user-callerid:1] Set("SIP/99220-00000653", "TOUCH_MONITOR=1390621660.40909") in new stack -- Executing [s@macro-user-callerid:2] Set("SIP/99220-00000653", "AMPUSER=99220") in new stack -- Executing [s@macro-user-callerid:3] GotoIf("SIP/99220-00000653", "0?report") in new stack -- Executing [s@macro-user-callerid:4] ExecIf("SIP/99220-00000653", "1?Set(REALCALLERIDNUM=99220)") in new stack -- Executing [s@macro-user-callerid:5] Set("SIP/99220-00000653", "AMPUSER=220") in new stack -- Executing [s@macro-user-callerid:6] Set("SIP/99220-00000653", "AMPUSERCIDNAME=Andrew Nagy") in new stack -- Executing [s@macro-user-callerid:7] GotoIf("SIP/99220-00000653", "0?report") in new stack -- Executing [s@macro-user-callerid:8] Set("SIP/99220-00000653", "AMPUSERCID=220") in new stack -- Executing [s@macro-user-callerid:9] Set("SIP/99220-00000653", "__DIAL_OPTIONS=Ttr") in new stack -- Executing [s@macro-user-callerid:10] Set("SIP/99220-00000653", "CALLERID(all)="Andrew Nagy" <220>") in new stack -- Executing [s@macro-user-callerid:11] GotoIf("SIP/99220-00000653", "0?limit") in new stack -- Executing [s@macro-user-callerid:12] ExecIf("SIP/99220-00000653", "1?Set(GROUP(concurrency_limit)=220)") in new stack -- Executing [s@macro-user-callerid:13] ExecIf("SIP/99220-00000653", "0?Set(CHANNEL(language)=)") in new stack -- Executing [s@macro-user-callerid:14] GosubIf("SIP/99220-00000653", "7?sub-ccss,s,1(from-internal,951XXXXXXX)") in new stack -- Executing [s@sub-ccss:1] ExecIf("SIP/99220-00000653", "0?Return()") in new stack -- Executing [s@sub-ccss:2] Set("SIP/99220-00000653", "CCSS_SETUP=TRUE") in new stack -- Executing [s@sub-ccss:3] GosubIf("SIP/99220-00000653", "0?monitor_config,1(from-internal,951XXXXXXX):monitor_default,1(from-internal,951XXXXXXX)") in new stack -- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/99220-00000653", "0?is_exten") in new stack -- Executing [monitor_default@sub-ccss:2] StackPop("SIP/99220-00000653", "") in new stack -- Executing [monitor_default@sub-ccss:3] Return("SIP/99220-00000653", "FALSE") in new stack -- Executing [s@macro-user-callerid:15] GotoIf("SIP/99220-00000653", "1?continue") in new stack -- Goto (macro-user-callerid,s,28) -- Executing [s@macro-user-callerid:28] Set("SIP/99220-00000653", "CALLERID(number)=220") in new stack -- Executing [s@macro-user-callerid:29] Set("SIP/99220-00000653", "CALLERID(name)=Andrew Nagy") in new stack -- Executing [s@macro-user-callerid:30] Set("SIP/99220-00000653", "CDR(cnum)=220") in new stack -- Executing [s@macro-user-callerid:31] Set("SIP/99220-00000653", "CDR(cnam)=Andrew Nagy") in new stack -- Executing [s@macro-user-callerid:32] Set("SIP/99220-00000653", "CHANNEL(language)=en") in new stack -- Executing [951XXXXXXX@from-internal:2] Set("SIP/99220-00000653", "MOHCLASS=default") in new stack -- Executing [951XXXXXXX@from-internal:3] ExecIf("SIP/99220-00000653", "1?Set(TRUNKCIDOVERRIDE=9516782759)") in new stack -- Executing [951XXXXXXX@from-internal:4] Set("SIP/99220-00000653", "_NODEST=") in new stack -- Executing [951XXXXXXX@from-internal:5] Gosub("SIP/99220-00000653", "sub-record-check,s,1(out,951XXXXXXX,)") in new stack -- Executing [s@sub-record-check:1] Set("SIP/99220-00000653", "REC_POLICY_MODE_SAVE=") in new stack -- Executing [s@sub-record-check:2] GotoIf("SIP/99220-00000653", "1?check") in new stack -- Goto (sub-record-check,s,7) -- Executing [s@sub-record-check:7] Set("SIP/99220-00000653", "__MON_FMT=wav") in new stack -- Executing [s@sub-record-check:8] GotoIf("SIP/99220-00000653", "1?next") in new stack -- Goto (sub-record-check,s,11) -- Executing [s@sub-record-check:11] ExecIf("SIP/99220-00000653", "0?Return()") in new stack -- Executing [s@sub-record-check:12] ExecIf("SIP/99220-00000653", "0?Set(__REC_POLICY_MODE=)") in new stack -- Executing [s@sub-record-check:13] GotoIf("SIP/99220-00000653", "0?out,1") in new stack -- Executing [s@sub-record-check:14] Set("SIP/99220-00000653", "__REC_STATUS=INITIALIZED") in new stack -- Executing [s@sub-record-check:15] Set("SIP/99220-00000653", "NOW=1390621660") in new stack -- Executing [s@sub-record-check:16] Set("SIP/99220-00000653", "__DAY=24") in new stack -- Executing [s@sub-record-check:17] Set("SIP/99220-00000653", "__MONTH=01") in new stack -- Executing [s@sub-record-check:18] Set("SIP/99220-00000653", "__YEAR=2014") in new stack -- Executing [s@sub-record-check:19] Set("SIP/99220-00000653", "__TIMESTR=20140124-194740") in new stack -- Executing [s@sub-record-check:20] Set("SIP/99220-00000653", "__FROMEXTEN=220") in new stack -- Executing [s@sub-record-check:21] Set("SIP/99220-00000653", "__CALLFILENAME=out-951XXXXXXX-220-20140124-194740-1390621660.40909") in new stack -- Executing [s@sub-record-check:22] Goto("SIP/99220-00000653", "out,1") in new stack -- Goto (sub-record-check,out,1) -- Executing [out@sub-record-check:1] ExecIf("SIP/99220-00000653", "1?Set(__REC_POLICY_MODE=dontcare)") in new stack -- Executing [out@sub-record-check:2] GosubIf("SIP/99220-00000653", "0?record,1(exten,951XXXXXXX,220)") in new stack -- Executing [out@sub-record-check:3] Return("SIP/99220-00000653", "") in new stack -- Executing [951XXXXXXX@from-internal:6] Macro("SIP/99220-00000653", "dialout-trunk,2,951XXXXXXX,,off") in new stack -- Executing [s@macro-dialout-trunk:1] Set("SIP/99220-00000653", "DIAL_TRUNK=2") in new stack -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/99220-00000653", "0?sub-pincheck,s,1()") in new stack -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/99220-00000653", "0?disabletrunk,1") in new stack -- Executing [s@macro-dialout-trunk:4] Set("SIP/99220-00000653", "DIAL_NUMBER=951XXXXXXX") in new stack -- Executing [s@macro-dialout-trunk:5] Set("SIP/99220-00000653", "DIAL_TRUNK_OPTIONS=Ttr") in new stack -- Executing [s@macro-dialout-trunk:6] Set("SIP/99220-00000653", "OUTBOUND_GROUP=OUT_2") in new stack -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/99220-00000653", "1?nomax") in new stack -- Goto (macro-dialout-trunk,s,9) -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/99220-00000653", "0?skipoutcid") in new stack -- Executing [s@macro-dialout-trunk:10] Set("SIP/99220-00000653", "DIAL_TRUNK_OPTIONS=Tt") in new stack -- Executing [s@macro-dialout-trunk:11] Macro("SIP/99220-00000653", "outbound-callerid,2") in new stack -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/99220-00000653", "0?Set(CALLERPRES()=)") in new stack -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/99220-00000653", "0?Set(REALCALLERIDNUM=220)") in new stack -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/99220-00000653", "1?normcid") in new stack -- Goto (macro-outbound-callerid,s,6) -- Executing [s@macro-outbound-callerid:6] Set("SIP/99220-00000653", "USEROUTCID=") in new stack -- Executing [s@macro-outbound-callerid:7] Set("SIP/99220-00000653", "EMERGENCYCID=") in new stack -- Executing [s@macro-outbound-callerid:8] Set("SIP/99220-00000653", "TRUNKOUTCID=9512907260") in new stack -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/99220-00000653", "1?trunkcid") in new stack -- Goto (macro-outbound-callerid,s,14) -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/99220-00000653", "1?Set(CALLERID(all)=9512907260)") in new stack -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/99220-00000653", "0?Set(CALLERID(all)=)") in new stack -- Executing [s@macro-outbound-callerid:16] ExecIf("SIP/99220-00000653", "1?Set(CALLERID(all)=9516782759)") in new stack -- Executing [s@macro-outbound-callerid:17] ExecIf("SIP/99220-00000653", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack -- Executing [s@macro-outbound-callerid:18] Set("SIP/99220-00000653", "CDR(outbound_cnum)=9516782759") in new stack -- Executing [s@macro-outbound-callerid:19] Set("SIP/99220-00000653", "CDR(outbound_cnam)=") in new stack -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/99220-00000653", "1?sub-flp-2,s,1()") in new stack -- Executing [s@sub-flp-2:1] ExecIf("SIP/99220-00000653", "0?Set(TARGET_FLP_2=951951XXXXXXX)") in new stack -- Executing [s@sub-flp-2:2] GotoIf("SIP/99220-00000653", "0?match") in new stack -- Executing [s@sub-flp-2:3] Return("SIP/99220-00000653", "") in new stack -- Executing [s@macro-dialout-trunk:13] Set("SIP/99220-00000653", "OUTNUM=951XXXXXXX") in new stack -- Executing [s@macro-dialout-trunk:14] Set("SIP/99220-00000653", "custom=SIP/fpbx-1-ec5a407e") in new stack -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/99220-00000653", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/99220-00000653", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack -- Executing [s@macro-dialout-trunk:17] Macro("SIP/99220-00000653", "dialout-trunk-predial-hook,") in new stack -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/99220-00000653", "") in new stack -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/99220-00000653", "0?bypass,1") in new stack -- Executing [s@macro-dialout-trunk:19] ExecIf("SIP/99220-00000653", "1?Set(CONNECTEDLINE(num,i)=951XXXXXXX)") in new stack -- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/99220-00000653", "1?Set(CONNECTEDLINE(name,i)=CID:9516782759)") in new stack -- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/99220-00000653", "0?customtrunk") in new stack -- Executing [s@macro-dialout-trunk:22] Dial("SIP/99220-00000653", "SIP/fpbx-1-ec5a407e/951XXXXXXX,300,Tt") in new stack == Using SIP RTP TOS bits 184 == Using SIP RTP CoS mark 5 -- Called SIP/fpbx-1-ec5a407e/951XXXXXXX > 0x91cc1f8 -- Probation passed - setting RTP source address to 4.55.18.198:6176 > 0x91cc1f8 -- Probation passed - setting RTP source address to 4.55.18.198:6176 -- SIP/fpbx-1-ec5a407e-00000654 is making progress passing it to SIP/99220-00000653 Audio is at 19754 [2014-01-24 19:47:43] DEBUG[5254][C-00004ed2]: sip/sdp_crypto.c:310 sdp_crypto_offer: Crypto line: a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:WoaicpKHdFd/l5IvTFeQ+4cVbk/vNdjf91b2XuuJ Adding codec 100003 (ulaw) to SDP Adding codec 100004 (alaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP <--- Transmitting (no NAT) to 10.249.11.6:5060 ---> SIP/2.0 183 Session Progress Via: SIP/2.0/WS t16b7je9cm36.invalid;branch=z9hG4bK6083033;received=10.249.11.6 From: ;tag=29r2h7kvh8 To: ;tag=as6560e643 Call-ID: 62nevoe9rji599s60jt1 CSeq: 5809 INVITE Server: FPBX-2.11.0(11.7.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 561 v=0 o=root 401918844 401918844 IN IP4 72.67.88.28 s=Asterisk PBX 11.7.0 c=IN IP4 72.67.88.28 t=0 0 m=audio 19754 RTP/SAVPF 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=ice-ufrag:31194dce2fd07d91559cecab7605453e a=ice-pwd:07fea93e43e84dad274e199a1bd2cfdd a=candidate:Haf90105 1 UDP 2130706431 10.249.1.5 19754 typ host a=candidate:Haf90105 2 UDP 2130706430 10.249.1.5 19755 typ host a=sendrecv a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:WoaicpKHdFd/l5IvTFeQ+4cVbk/vNdjf91b2XuuJ <------------> > 0x91cc1f8 -- Probation passed - setting RTP source address to 4.55.18.198:6176 -- SIP/fpbx-1-ec5a407e-00000654 answered SIP/99220-00000653 Audio is at 19754 [2014-01-24 19:47:51] DEBUG[5254][C-00004ed2]: sip/sdp_crypto.c:310 sdp_crypto_offer: Crypto line: a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:WoaicpKHdFd/l5IvTFeQ+4cVbk/vNdjf91b2XuuJ Adding codec 100003 (ulaw) to SDP Adding codec 100004 (alaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP <--- Reliably Transmitting (no NAT) to 10.249.11.6:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/WS t16b7je9cm36.invalid;branch=z9hG4bK6083033;received=10.249.11.6 From: ;tag=29r2h7kvh8 To: ;tag=as6560e643 Call-ID: 62nevoe9rji599s60jt1 CSeq: 5809 INVITE Server: FPBX-2.11.0(11.7.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 561 v=0 o=root 401918844 401918844 IN IP4 72.67.88.28 s=Asterisk PBX 11.7.0 c=IN IP4 72.67.88.28 t=0 0 m=audio 19754 RTP/SAVPF 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=ice-ufrag:31194dce2fd07d91559cecab7605453e a=ice-pwd:07fea93e43e84dad274e199a1bd2cfdd a=candidate:Haf90105 1 UDP 2130706431 10.249.1.5 19754 typ host a=candidate:Haf90105 2 UDP 2130706430 10.249.1.5 19755 typ host a=sendrecv a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:WoaicpKHdFd/l5IvTFeQ+4cVbk/vNdjf91b2XuuJ <------------> <--- SIP read from WS:10.249.11.6:60226 ---> ACK sip:951XXXXXXX@72.67.88.28:0;transport=ws SIP/2.0 Via: SIP/2.0/WS t16b7je9cm36.invalid;branch=z9hG4bK3633544 Max-Forwards: 69 To: ;tag=as6560e643 From: ;tag=29r2h7kvh8 Call-ID: 62nevoe9rji599s60jt1 CSeq: 5809 ACK Supported: path, outbound, gruu User-Agent: JsSIP 0.3.7 Content-Length: 0 <-------------> --- (10 headers 0 lines) --- -- Attempting call on Local/s@tc-maint for application NoCDR() (Retry 1) -- Executing [s@tc-maint:1] NoCDR("Local/s@tc-maint-00004ca0;2", "") in new stack -- Executing [s@tc-maint:2] Set("Local/s@tc-maint-00004ca0;2", "TCMAINT=RETURN") in new stack -- Executing [s@tc-maint:3] GosubIf("Local/s@tc-maint-00004ca0;2", "0?timeconditions,1,1()") in new stack -- Executing [s@tc-maint:4] System("Local/s@tc-maint-00004ca0;2", "/var/lib/asterisk/bin/schedtc.php 60 /var/spool/asterisk/outgoing 0") in new stack -- Executing [s@tc-maint:5] Answer("Local/s@tc-maint-00004ca0;2", "") in new stack > Channel Local/s@tc-maint-00004ca0;1 was answered. > Launching NoCDR() on Local/s@tc-maint-00004ca0;1 [2014-01-24 19:48:00] NOTICE[5266]: pbx_spool.c:402 attempt_thread: Call completed to Local/s@tc-maint [2014-01-24 19:48:00] NOTICE[5266]: pbx_spool.c:402 attempt_thread: Call completed to Local/s@tc-maint == Spawn extension (tc-maint, s, 5) exited non-zero on 'Local/s@tc-maint-00004ca0;2' -- Executing [h@macro-dialout-trunk:1] Macro("SIP/99220-00000653", "hangupcall,") in new stack -- Executing [s@macro-hangupcall:1] GotoIf("SIP/99220-00000653", "1?theend") in new stack -- Goto (macro-hangupcall,s,3) -- Executing [s@macro-hangupcall:3] ExecIf("SIP/99220-00000653", "0?Set(CDR(recordingfile)=)") in new stack -- Executing [s@macro-hangupcall:4] Hangup("SIP/99220-00000653", "") in new stack == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/99220-00000653' in macro 'hangupcall' == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on 'SIP/99220-00000653' == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on 'SIP/99220-00000653' in macro 'dialout-trunk' == Spawn extension (from-internal, 951XXXXXXX, 6) exited non-zero on 'SIP/99220-00000653' Scheduling destruction of SIP dialog '62nevoe9rji599s60jt1' in 6400 ms (Method: INVITE) set_destination: Parsing for address/port to send to set_destination: URI is for WebSocket, we can't set destination Reliably Transmitting (no NAT) to 10.249.11.6:5060: BYE sip:14jrcebp@t16b7je9cm36.invalid;transport=ws;ob SIP/2.0 Via: SIP/2.0/WS 72.67.88.28:0;branch=z9hG4bK72f632de Max-Forwards: 70 From: ;tag=as6560e643 To: ;tag=29r2h7kvh8 Call-ID: 62nevoe9rji599s60jt1 CSeq: 102 BYE User-Agent: FPBX-2.11.0(11.7.0) Proxy-Authorization: Digest username="99220", realm="asterisk", algorithm=MD5, uri="sip:10.249.1.5", nonce="53f7d9ab", response="63b76729e75972a46d38c8f2fe235f2a" X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 Content-Length: 0 --- <--- SIP read from WS:10.249.11.6:60226 ---> SIP/2.0 200 OK Via: SIP/2.0/WS 72.67.88.28:0;branch=z9hG4bK72f632de To: ;tag=29r2h7kvh8 From: ;tag=as6560e643 Call-ID: 62nevoe9rji599s60jt1 CSeq: 102 BYE Content-Length: 0 <-------------> --- (7 headers 0 lines) --- SIP Response message for INCOMING dialog BYE arrived Really destroying SIP dialog '62nevoe9rji599s60jt1' Method: INVITE Reliably Transmitting (no NAT) to 10.249.11.6:60226: OPTIONS sip:14jrcebp@t16b7je9cm36.invalid;transport=ws SIP/2.0 Via: SIP/2.0/WS 72.67.88.28:0;branch=z9hG4bK03d5c38e Max-Forwards: 70 From: "Unknown" ;tag=as73d344a8 To: Contact: Call-ID: 525e2a06782935a92a277d09609457cd@72.67.88.28:0 CSeq: 102 OPTIONS User-Agent: FPBX-2.11.0(11.7.0) Date: Sat, 25 Jan 2014 03:48:16 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 --- <--- SIP read from WS:10.249.11.6:60226 ---> SIP/2.0 200 OK Via: SIP/2.0/WS 72.67.88.28:0;branch=z9hG4bK03d5c38e To: ;tag=g45m8pstkd From: "Unknown" ;tag=as73d344a8 Call-ID: 525e2a06782935a92a277d09609457cd@72.67.88.28:0 CSeq: 102 OPTIONS Allow: ACK,CANCEL,BYE,OPTIONS,INVITE Accept: application/sdp,application/dtmf-relay Content-Length: 0