Skip to content

Instantly share code, notes, and snippets.

@jaboutboul
Last active June 8, 2016 20:34
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save jaboutboul/305069b8291f0dec91eec81fdca60f2d to your computer and use it in GitHub Desktop.
Save jaboutboul/305069b8291f0dec91eec81fdca60f2d to your computer and use it in GitHub Desktop.
[2016-06-07 17:07:59] VERBOSE[2016][C-00006c5e] netsock2.c: Using SIP RTP TOS bits 184
[2016-06-07 17:07:59] VERBOSE[2016][C-00006c5e] netsock2.c: Using SIP RTP CoS mark 5
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:1] Set("SIP/vitelity-inbound-000070a7", "__DIRECTION=INBOUND") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:2] Gosub("SIP/vitelity-inbound-000070a7", "sub-record-check,s,1(in,7186671234,dontcare)") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/vitelity-inbound-000070a7", "0?initialized") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@sub-record-check:2] Set("SIP/vitelity-inbound-000070a7", "__REC_STATUS=INITIALIZED") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@sub-record-check:3] Set("SIP/vitelity-inbound-000070a7", "NOW=1465333679") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@sub-record-check:4] Set("SIP/vitelity-inbound-000070a7", "__DAY=07") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@sub-record-check:5] Set("SIP/vitelity-inbound-000070a7", "__MONTH=06") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@sub-record-check:6] Set("SIP/vitelity-inbound-000070a7", "__YEAR=2016") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@sub-record-check:7] Set("SIP/vitelity-inbound-000070a7", "__TIMESTR=20160607-170759") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@sub-record-check:8] Set("SIP/vitelity-inbound-000070a7", "__FROMEXTEN=unknown") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@sub-record-check:9] Set("SIP/vitelity-inbound-000070a7", "__MON_FMT=wav") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/vitelity-inbound-000070a7", "Recordings initialized") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/vitelity-inbound-000070a7", "0?Set(ARG3=dontcare)") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@sub-record-check:12] Set("SIP/vitelity-inbound-000070a7", "REC_POLICY_MODE_SAVE=") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/vitelity-inbound-000070a7", "0?Set(REC_STATUS=NO)") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/vitelity-inbound-000070a7", "2?checkaction") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Goto (sub-record-check,s,17)
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/vitelity-inbound-000070a7", "1?sub-record-check,in,1") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Goto (sub-record-check,in,1)
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/vitelity-inbound-000070a7", "Inbound Recording Check to 7186671234") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [in@sub-record-check:2] Set("SIP/vitelity-inbound-000070a7", "FROMEXTEN=unknown") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/vitelity-inbound-000070a7", "10?Set(FROMEXTEN=7182493243)") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/vitelity-inbound-000070a7", "recordcheck,1(dontcare,in,7186671234)") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/vitelity-inbound-000070a7", "Starting recording check against dontcare") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/vitelity-inbound-000070a7", "dontcare") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Goto (sub-record-check,recordcheck,3)
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/vitelity-inbound-000070a7", "") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [in@sub-record-check:5] Return("SIP/vitelity-inbound-000070a7", "") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:3] Gosub("SIP/vitelity-inbound-000070a7", "app-blacklist-check,s,1()") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/vitelity-inbound-000070a7", "0?blacklisted") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/vitelity-inbound-000070a7", "CALLED_BLACKLIST=1") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/vitelity-inbound-000070a7", "") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:4] Set("SIP/vitelity-inbound-000070a7", "__FROM_DID=7186671234") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:5] Set("SIP/vitelity-inbound-000070a7", "CDR(did)=7186671234") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:6] ExecIf("SIP/vitelity-inbound-000070a7", "0 ?Set(CALLERID(name)=7182493243)") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:7] Set("SIP/vitelity-inbound-000070a7", "__MOHCLASS=") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:8] Set("SIP/vitelity-inbound-000070a7", "__REVERSAL_REJECT=FALSE") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:9] GotoIf("SIP/vitelity-inbound-000070a7", "1?post-reverse-charge") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Goto (from-trunk,7186671234,11)
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:11] NoOp("SIP/vitelity-inbound-000070a7", "") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:12] Set("SIP/vitelity-inbound-000070a7", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:13] Set("SIP/vitelity-inbound-000070a7", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:14] Set("SIP/vitelity-inbound-000070a7", "CALLERID(name-pres)=allowed_not_screened") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:15] Set("SIP/vitelity-inbound-000070a7", "CALLERID(num-pres)=allowed_not_screened") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:16] Set("SIP/vitelity-inbound-000070a7", "__ALERT_INFO=Simple-1") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:17] Set("SIP/vitelity-inbound-000070a7", "CIDSFSCHEME=QUxMfEFMTA==") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:18] AGI("SIP/vitelity-inbound-000070a7", "/var/www/html/admin/modules/superfecta/agi/superfecta.agi") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] res_agi.c: Launched AGI Script /var/www/html/admin/modules/superfecta/agi/superfecta.agi
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] res_agi.c: /var/www/html/admin/modules/superfecta/agi/superfecta.agi: Failed to execute '/var/www/html/admin/modules/superfecta/agi/superfecta.agi': Permission denied
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:19] Set("SIP/vitelity-inbound-000070a7", "CALLERID(name)=") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:20] NoOp("SIP/vitelity-inbound-000070a7", "CallerID Entry Point") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:21] Macro("SIP/vitelity-inbound-000070a7", "prepend-cid,1234:") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@macro-prepend-cid:1] GotoIf("SIP/vitelity-inbound-000070a7", "1?REPCID") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Goto (macro-prepend-cid,s,5)
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@macro-prepend-cid:5] Set("SIP/vitelity-inbound-000070a7", "_RGPREFIX=1234:") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [s@macro-prepend-cid:6] Set("SIP/vitelity-inbound-000070a7", "CALLERID(name)=1234:") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [7186671234@from-trunk:22] Goto("SIP/vitelity-inbound-000070a7", "timeconditions,1,1") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Goto (timeconditions,1,1)
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [1@timeconditions:1] Set("SIP/vitelity-inbound-000070a7", "DB(TC/1/INUSESTATE)=INUSE") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [1@timeconditions:2] Set("SIP/vitelity-inbound-000070a7", "DB(TC/1/NOT_INUSESTATE)=NOT_INUSE") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [1@timeconditions:3] GotoIfTime("SIP/vitelity-inbound-000070a7", "09:00-17:00,mon-fri,*,*,America/New_York?truestate") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [1@timeconditions:4] GotoIf("SIP/vitelity-inbound-000070a7", "0?truegoto") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [1@timeconditions:5] ExecIf("SIP/vitelity-inbound-000070a7", "0?Set(DB(TC/1)=)") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [1@timeconditions:6] Set("SIP/vitelity-inbound-000070a7", "DEVICE_STATE(Custom:TC1)=INUSE") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [1@timeconditions:7] ExecIf("SIP/vitelity-inbound-000070a7", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [1@timeconditions:8] GotoIf("SIP/vitelity-inbound-000070a7", "1?app-parkandannounce,510,1") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Goto (app-parkandannounce,510,1)
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [510@app-parkandannounce:1] NoOp("SIP/vitelity-inbound-000070a7", "Dropping Call into Park and Announce afterhours") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [510@app-parkandannounce:2] Answer("SIP/vitelity-inbound-000070a7", "") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [510@app-parkandannounce:3] Set("SIP/vitelity-inbound-000070a7", "RETRYCOUNT=0") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] pbx.c: Executing [510@app-parkandannounce:4] Playback("SIP/vitelity-inbound-000070a7", "custom/stasiamessage") in new stack
[2016-06-07 17:07:59] VERBOSE[18207][C-00006c5e] file.c: <SIP/vitelity-inbound-000070a7> Playing 'custom/stasiamessage.slin' (language 'en')
[2016-06-07 17:08:01] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP request (414 bytes) to UDP:10.10.43.22:5061 --->
OPTIONS sip:101@10.10.43.22:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.250:5061;rport;branch=z9hG4bKPjOr7JSpzYELT4EbnjwdDqDIxpDI57MvcZ
From: <sip:asterisk@10.10.43.250>;tag=phO7Uz3ellsHrMCsnxcrgsEXFJcFk9D4
To: <sip:101@10.10.43.22>
Contact: <sip:asterisk@10.10.43.250:5061>
Call-ID: YZTa55xWbf5w6U86z6l-D8cIRE9XkInu
CSeq: 35035 OPTIONS
Max-Forwards: 70
User-Agent: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:08:01] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP response (428 bytes) from UDP:10.10.43.22:5061 --->
SIP/2.0 200 OK
To: <sip:101@10.10.43.22>;tag=5af58b649dbcf98i0
From: <sip:asterisk@10.10.43.250>;tag=phO7Uz3ellsHrMCsnxcrgsEXFJcFk9D4
Call-ID: YZTa55xWbf5w6U86z6l-D8cIRE9XkInu
CSeq: 35035 OPTIONS
Via: SIP/2.0/UDP 10.10.43.250:5061;branch=z9hG4bKPjOr7JSpzYELT4EbnjwdDqDIxpDI57MvcZ
Server: Cisco/SPA509G-7.6.2
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
[2016-06-07 17:08:04] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP request (730 bytes) from UDP:10.10.43.18:5061 --->
REGISTER sip:10.10.43.250:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.18:5061;branch=z9hG4bK-5558830f
From: "101" <sip:101@10.10.43.250>;tag=be8654e690bd7fabo0
To: "101" <sip:101@10.10.43.250>
Call-ID: 570a91dc-f08a70d7@10.10.43.18
CSeq: 28622 REGISTER
Max-Forwards: 70
Authorization: Digest username="101",realm="asterisk",nonce="1465333638/80dab9c68c44d884dcdf2b0b5722f277",uri="sip:10.10.43.250:5061",algorithm=MD5,response="63409caea694ff465a245c1c3668e870",opaque="1cae01941dbb4b9c",qop=auth,nc=00000002,cnonce="11d8c41b"
Contact: "101" <sip:101@10.10.43.18:5061>;expires=60
User-Agent: Cisco/SPA509G-7.6.2
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
[2016-06-07 17:08:04] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP response (504 bytes) to UDP:10.10.43.18:5061 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.43.18:5061;rport=5061;received=10.10.43.18;branch=z9hG4bK-5558830f
Call-ID: 570a91dc-f08a70d7@10.10.43.18
From: "101" <sip:101@10.10.43.250>;tag=be8654e690bd7fabo0
To: "101" <sip:101@10.10.43.250>;tag=z9hG4bK-5558830f
CSeq: 28622 REGISTER
WWW-Authenticate: Digest realm="asterisk",nonce="1465333684/0d9a706286e30d418b9586218f9f8f65",opaque="30e8501549628891",stale=true,algorithm=md5,qop="auth"
Server: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:08:04] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP request (729 bytes) from UDP:10.10.43.18:5061 --->
REGISTER sip:10.10.43.250:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.18:5061;branch=z9hG4bK-c64d554
From: "101" <sip:101@10.10.43.250>;tag=be8654e690bd7fabo0
To: "101" <sip:101@10.10.43.250>
Call-ID: 570a91dc-f08a70d7@10.10.43.18
CSeq: 28623 REGISTER
Max-Forwards: 70
Authorization: Digest username="101",realm="asterisk",nonce="1465333684/0d9a706286e30d418b9586218f9f8f65",uri="sip:10.10.43.250:5061",algorithm=MD5,response="6f4dc10f5967c11aa4f5874131364737",opaque="30e8501549628891",qop=auth,nc=00000001,cnonce="8463e634"
Contact: "101" <sip:101@10.10.43.18:5061>;expires=60
User-Agent: Cisco/SPA509G-7.6.2
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
[2016-06-07 17:08:05] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP response (467 bytes) to UDP:10.10.43.18:5061 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.10.43.18:5061;rport=5061;received=10.10.43.18;branch=z9hG4bK-c64d554
Call-ID: 570a91dc-f08a70d7@10.10.43.18
From: "101" <sip:101@10.10.43.250>;tag=be8654e690bd7fabo0
To: "101" <sip:101@10.10.43.250>;tag=z9hG4bK-c64d554
CSeq: 28623 REGISTER
Date: Tue, 07 Jun 2016 21:08:05 GMT
Contact: <sip:101@10.10.43.22:5061>;expires=16
Contact: <sip:101@10.10.43.18:5061>;expires=58
Server: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:08:08] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP request (730 bytes) from UDP:10.10.43.22:5061 --->
REGISTER sip:10.10.43.250:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.22:5061;branch=z9hG4bK-ee143ad6
From: "101" <sip:101@10.10.43.250>;tag=4925f61ea6c39150o0
To: "101" <sip:101@10.10.43.250>
Call-ID: 9e2e1b04-82e439d1@10.10.43.22
CSeq: 43800 REGISTER
Max-Forwards: 70
Authorization: Digest username="101",realm="asterisk",nonce="1465333642/60d506babc94af25d8f34b346d14d3e9",uri="sip:10.10.43.250:5061",algorithm=MD5,response="04ec56a856adf24b5a88ef659a03431d",opaque="39dac7b173b76d3c",qop=auth,nc=00000002,cnonce="9999ab20"
Contact: "101" <sip:101@10.10.43.22:5061>;expires=60
User-Agent: Cisco/SPA509G-7.6.2
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
[2016-06-07 17:08:08] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP response (504 bytes) to UDP:10.10.43.22:5061 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.43.22:5061;rport=5061;received=10.10.43.22;branch=z9hG4bK-ee143ad6
Call-ID: 9e2e1b04-82e439d1@10.10.43.22
From: "101" <sip:101@10.10.43.250>;tag=4925f61ea6c39150o0
To: "101" <sip:101@10.10.43.250>;tag=z9hG4bK-ee143ad6
CSeq: 43800 REGISTER
WWW-Authenticate: Digest realm="asterisk",nonce="1465333688/96032d38b7826d51c1b91566334891e6",opaque="4c30871a03b47c60",stale=true,algorithm=md5,qop="auth"
Server: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:08:08] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP request (730 bytes) from UDP:10.10.43.22:5061 --->
REGISTER sip:10.10.43.250:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.22:5061;branch=z9hG4bK-34cacc3f
From: "101" <sip:101@10.10.43.250>;tag=4925f61ea6c39150o0
To: "101" <sip:101@10.10.43.250>
Call-ID: 9e2e1b04-82e439d1@10.10.43.22
CSeq: 43801 REGISTER
Max-Forwards: 70
Authorization: Digest username="101",realm="asterisk",nonce="1465333688/96032d38b7826d51c1b91566334891e6",uri="sip:10.10.43.250:5061",algorithm=MD5,response="ac019035a24311e69540560ea98df286",opaque="4c30871a03b47c60",qop=auth,nc=00000001,cnonce="cef2b2b8"
Contact: "101" <sip:101@10.10.43.22:5061>;expires=60
User-Agent: Cisco/SPA509G-7.6.2
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
[2016-06-07 17:08:08] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP response (469 bytes) to UDP:10.10.43.22:5061 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.10.43.22:5061;rport=5061;received=10.10.43.22;branch=z9hG4bK-34cacc3f
Call-ID: 9e2e1b04-82e439d1@10.10.43.22
From: "101" <sip:101@10.10.43.250>;tag=4925f61ea6c39150o0
To: "101" <sip:101@10.10.43.250>;tag=z9hG4bK-34cacc3f
CSeq: 43801 REGISTER
Date: Tue, 07 Jun 2016 21:08:08 GMT
Contact: <sip:101@10.10.43.22:5061>;expires=59
Contact: <sip:101@10.10.43.18:5061>;expires=55
Server: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:08:15] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP request (413 bytes) to UDP:10.10.43.18:5061 --->
OPTIONS sip:101@10.10.43.18:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.250:5061;rport;branch=z9hG4bKPjTYdkczQ5LT4Z3j3G-TNe5XCTGjJsoiJf
From: <sip:asterisk@10.10.43.250>;tag=NpZmOdCDdTgo7JzjCvPoQVL4dGiqVTJF
To: <sip:101@10.10.43.18>
Contact: <sip:asterisk@10.10.43.250:5061>
Call-ID: 1A3s1qupLPmowTW0GHWGYZduGhjKq2Ez
CSeq: 3081 OPTIONS
Max-Forwards: 70
User-Agent: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:08:15] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP response (427 bytes) from UDP:10.10.43.18:5061 --->
SIP/2.0 200 OK
To: <sip:101@10.10.43.18>;tag=9394dc2d85d7c7fi0
From: <sip:asterisk@10.10.43.250>;tag=NpZmOdCDdTgo7JzjCvPoQVL4dGiqVTJF
Call-ID: 1A3s1qupLPmowTW0GHWGYZduGhjKq2Ez
CSeq: 3081 OPTIONS
Via: SIP/2.0/UDP 10.10.43.250:5061;branch=z9hG4bKPjTYdkczQ5LT4Z3j3G-TNe5XCTGjJsoiJf
Server: Cisco/SPA509G-7.6.2
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
[2016-06-07 17:08:15] VERBOSE[18207][C-00006c5e] pbx.c: Executing [510@app-parkandannounce:5] Set("SIP/vitelity-inbound-000070a7", "ANNOUNCE_RECORDING_1=/var/spool/asterisk/tmp/ANNOUNCE_RECORDING_1-510-1465333695480") in new stack
[2016-06-07 17:08:15] VERBOSE[18207][C-00006c5e] pbx.c: Executing [510@app-parkandannounce:6] Record("SIP/vitelity-inbound-000070a7", "/var/spool/asterisk/tmp/ANNOUNCE_RECORDING_1-510-1465333695480.sln,3,45,") in new stack
[2016-06-07 17:08:15] VERBOSE[18207][C-00006c5e] file.c: <SIP/vitelity-inbound-000070a7> Playing 'beep.ulaw' (language 'en')
[2016-06-07 17:08:35] VERBOSE[18207][C-00006c5e] pbx.c: Executing [510@app-parkandannounce:7] GotoIf("SIP/vitelity-inbound-000070a7", "1?silence:parkcheck") in new stack
[2016-06-07 17:08:35] VERBOSE[18207][C-00006c5e] pbx.c: Goto (app-parkandannounce,510,8)
[2016-06-07 17:08:35] VERBOSE[18207][C-00006c5e] pbx.c: Executing [510@app-parkandannounce:8] Playback("SIP/vitelity-inbound-000070a7", "thankyoupleasehold") in new stack
[2016-06-07 17:08:35] VERBOSE[18207][C-00006c5e] file.c: <SIP/vitelity-inbound-000070a7> Playing 'thankyoupleasehold.gsm' (language 'en')
[2016-06-07 17:08:36] VERBOSE[18207][C-00006c5e] pbx.c: Executing [510@app-parkandannounce:9] Set("SIP/vitelity-inbound-000070a7", "SILENCE=TRUE") in new stack
[2016-06-07 17:08:36] VERBOSE[18207][C-00006c5e] pbx.c: Executing [510@app-parkandannounce:10] GotoIf("SIP/vitelity-inbound-000070a7", "0?parkthanks:park") in new stack
[2016-06-07 17:08:36] VERBOSE[18207][C-00006c5e] pbx.c: Goto (app-parkandannounce,510,12)
[2016-06-07 17:08:36] VERBOSE[18207][C-00006c5e] pbx.c: Executing [510@app-parkandannounce:12] Set("SIP/vitelity-inbound-000070a7", "PARKINGLOT=default") in new stack
[2016-06-07 17:08:36] VERBOSE[18207][C-00006c5e] pbx.c: Executing [510@app-parkandannounce:13] ExecIf("SIP/vitelity-inbound-000070a7", "0?Set(__ANNOUNCEMENTS=silence/5:custom/beep:/var/spool/asterisk/tmp/ANNOUNCE_RECORDING_1-510-1465333695480):Set(__ANNOUNCEMENTS=silence/5:custom/beep)") in new stack
[2016-06-07 17:08:36] VERBOSE[18207][C-00006c5e] pbx.c: Executing [510@app-parkandannounce:14] ParkAndAnnounce("SIP/vitelity-inbound-000070a7", "default,t(15),silence/5:custom/beep,Local/500@ext-paging,app-parkandannouncetimeout,510,1") in new stack
[2016-06-07 17:08:36] VERBOSE[18207][C-00006c5e] parking/parking_bridge.c: Parking 'SIP/vitelity-inbound-000070a7' in 'default' at space 71
[2016-06-07 17:08:36] VERBOSE[29575] parking/parking_applications.c: Dial Tech,String: (Local,500@ext-paging)
[2016-06-07 17:08:36] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state InUse for Notify User 102
[2016-06-07 17:08:36] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state InUse for Notify User 107
[2016-06-07 17:08:36] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state InUse for Notify User 103
[2016-06-07 17:08:36] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state InUse for Notify User 100
[2016-06-07 17:08:36] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state InUse for Notify User 104
[2016-06-07 17:08:36] VERBOSE[18207][C-00006c5e] bridge_channel.c: Channel SIP/vitelity-inbound-000070a7 joined 'holding_bridge' parking-bridge <64211a9e-ed04-4b70-80c1-dc03719cd8cf>
[2016-06-07 17:08:36] VERBOSE[18207][C-00006c5e] res_musiconhold.c: Started music on hold, class 'default', on channel 'SIP/vitelity-inbound-000070a7'
[2016-06-07 17:08:36] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state InUse for Notify User 105
[2016-06-07 17:08:36] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state InUse for Notify User 106
[2016-06-07 17:08:36] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state InUse for Notify User 108
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@ext-paging:1] Goto("Local/500@ext-paging-00005d5a;2", "app-pagegroups,500,1") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Goto (app-pagegroups,500,1)
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@app-pagegroups:1] Macro("Local/500@ext-paging-00005d5a;2", "user-callerid,") in new stack
[2016-06-07 17:08:36] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state InUse for Notify User 109
[2016-06-07 17:08:36] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state InUse for Notify User 110
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:1] Set("Local/500@ext-paging-00005d5a;2", "TOUCH_MONITOR=1465333716.80772") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:2] Set("Local/500@ext-paging-00005d5a;2", "AMPUSER=7182493243") in new stack
[2016-06-07 17:08:36] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state InUse for Notify User 111
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("Local/500@ext-paging-00005d5a;2", "0?report") in new stack
[2016-06-07 17:08:36] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state InUse for Notify User 112
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("Local/500@ext-paging-00005d5a;2", "1?Set(REALCALLERIDNUM=7182493243)") in new stack
[2016-06-07 17:08:36] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state InUse for Notify User 113
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:5] Set("Local/500@ext-paging-00005d5a;2", "AMPUSER=") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("Local/500@ext-paging-00005d5a;2", "0?limit") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:7] Set("Local/500@ext-paging-00005d5a;2", "AMPUSERCIDNAME=") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("Local/500@ext-paging-00005d5a;2", "1?report") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Goto (macro-user-callerid,s,15)
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:15] GotoIf("Local/500@ext-paging-00005d5a;2", "0?continue") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:16] ExecIf("Local/500@ext-paging-00005d5a;2", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:17] Set("Local/500@ext-paging-00005d5a;2", "__TTL=64") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("Local/500@ext-paging-00005d5a;2", "1?continue") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Goto (macro-user-callerid,s,29)
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:29] Set("Local/500@ext-paging-00005d5a;2", "CALLERID(number)=7182493243") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:30] Set("Local/500@ext-paging-00005d5a;2", "CALLERID(name)=1234:") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:31] Set("Local/500@ext-paging-00005d5a;2", "CDR(cnum)=7182493243") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:32] Set("Local/500@ext-paging-00005d5a;2", "CDR(cnam)=1234:") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:33] Set("Local/500@ext-paging-00005d5a;2", "CHANNEL(language)=en") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [s@macro-user-callerid:34] GosubIf("Local/500@ext-paging-00005d5a;2", "0?app-check-classofservce,s,1()") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@app-pagegroups:2] Set("Local/500@ext-paging-00005d5a;2", "_PAGEGROUP=500") in new stack
[2016-06-07 17:08:36] NOTICE[18254][C-00006c5f] ast_expr2.y: argbuf allocated 3 bytes;
[2016-06-07 17:08:36] NOTICE[18254][C-00006c5f] ast_expr2.y: argbuf uses 2 bytes;
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@app-pagegroups:3] GotoIf("Local/500@ext-paging-00005d5a;2", "1?devstate") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Goto (app-pagegroups,500,5)
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@app-pagegroups:5] Set("Local/500@ext-paging-00005d5a;2", "DEVICE_STATE(Custom:PAGE500)=INUSE") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@app-pagegroups:6] Gosub("Local/500@ext-paging-00005d5a;2", "app-paging,ssetup,1()") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [ssetup@app-paging:1] Set("Local/500@ext-paging-00005d5a;2", "_SIPURI=") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [ssetup@app-paging:2] Set("Local/500@ext-paging-00005d5a;2", "_ALERTINFO=Ring Answer") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [ssetup@app-paging:3] Set("Local/500@ext-paging-00005d5a;2", "_CALLINFO=<uri>;answer-after=0") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [ssetup@app-paging:4] Set("Local/500@ext-paging-00005d5a;2", "_SIPURI=intercom=true") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [ssetup@app-paging:5] Set("Local/500@ext-paging-00005d5a;2", "_DTIME=5") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [ssetup@app-paging:6] Set("Local/500@ext-paging-00005d5a;2", "_ANSWERMACRO=") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [ssetup@app-paging:7] Set("Local/500@ext-paging-00005d5a;2", "PAGE_CONF=1465333716815") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [ssetup@app-paging:8] Return("Local/500@ext-paging-00005d5a;2", "") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@app-pagegroups:7] Set("Local/500@ext-paging-00005d5a;2", "PAGEMODE=PAGE") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@app-pagegroups:8] Set("Local/500@ext-paging-00005d5a;2", "PAGE_MEMBERS=100-101-102-103") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@app-pagegroups:9] Set("Local/500@ext-paging-00005d5a;2", "PAGE_CONF_OPTS=") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@app-pagegroups:10] Set("Local/500@ext-paging-00005d5a;2", "ANNOUNCEMENT=beep") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@app-pagegroups:11] ExecIf("Local/500@ext-paging-00005d5a;2", "1?Set(STREAM=):Set(STREAM=NONE)") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@app-pagegroups:12] AGI("Local/500@ext-paging-00005d5a;2", "page.agi") in new stack
[2016-06-07 17:08:36] VERBOSE[18254][C-00006c5f] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/page.agi
[2016-06-07 17:08:37] VERBOSE[18261][C-00006c60] pbx.c: Executing [s@app-page-stream:1] Wait("Local/s@app-page-stream-00005d5b;2", "1") in new stack
[2016-06-07 17:08:37] VERBOSE[18260] dial.c: Called s@app-page-stream
[2016-06-07 17:08:37] VERBOSE[18263] dial.c: Called PAGE100@app-paging/n
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [PAGE100@app-paging:1] Macro("Local/PAGE100@app-paging-00005d5c;2", "autoanswer,100") in new stack
[2016-06-07 17:08:37] VERBOSE[18266] dial.c: Called PAGE101@app-paging/n
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [PAGE101@app-paging:1] Macro("Local/PAGE101@app-paging-00005d5d;2", "autoanswer,101") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@macro-autoanswer:1] GotoIf("Local/PAGE100@app-paging-00005d5c;2", "1?knowndial") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Goto (macro-autoanswer,s,19)
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@macro-autoanswer:19] Set("Local/PAGE100@app-paging-00005d5c;2", "DIAL=SIP/100") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@macro-autoanswer:1] GotoIf("Local/PAGE101@app-paging-00005d5d;2", "1?knowndial") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Goto (macro-autoanswer,s,19)
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@macro-autoanswer:20] ExecIf("Local/PAGE100@app-paging-00005d5c;2", "0?Set(DIAL=DAHDI/100)") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@macro-autoanswer:19] Set("Local/PAGE101@app-paging-00005d5d;2", "DIAL=PJSIP/101/sip:101@10.10.43.22:5061") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@macro-autoanswer:21] GotoIf("Local/PAGE100@app-paging-00005d5c;2", "0?macro") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@macro-autoanswer:22] GotoIf("Local/PAGE100@app-paging-00005d5c;2", "0?pjsipua") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@macro-autoanswer:20] ExecIf("Local/PAGE101@app-paging-00005d5d;2", "0?Set(DIAL=DAHDIIP/101/sip:101@10.10.43.22:5061)") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@macro-autoanswer:23] Set("Local/PAGE100@app-paging-00005d5c;2", "USERAGENT=Cisco/SPA504G-7.6.2") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@macro-autoanswer:24] Goto("Local/PAGE100@app-paging-00005d5c;2", "uafin") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Goto (macro-autoanswer,s,28)
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@macro-autoanswer:28] ExecIf("Local/PAGE100@app-paging-00005d5c;2", "0?Set(USERAGENT=)") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@macro-autoanswer:21] GotoIf("Local/PAGE101@app-paging-00005d5d;2", "0?macro") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@macro-autoanswer:29] ExecIf("Local/PAGE100@app-paging-00005d5c;2", "0?Set(CALLINFO=<sip:broadworks.net>;answer-after=0)") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@macro-autoanswer:30] ExecIf("Local/PAGE100@app-paging-00005d5c;2", "0?Set(ALERTINFO=Intercom)") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@macro-autoanswer:22] GotoIf("Local/PAGE101@app-paging-00005d5d;2", "1?pjsipua") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Goto (macro-autoanswer,s,25)
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@macro-autoanswer:31] ExecIf("Local/PAGE100@app-paging-00005d5c;2", "0?Set(ALERTINFO=info=Auto Answer)") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@macro-autoanswer:25] Set("Local/PAGE101@app-paging-00005d5d;2", "AOR=101") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@macro-autoanswer:32] ExecIf("Local/PAGE100@app-paging-00005d5c;2", "0?Set(ALERTINFO=ring-answer)") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@macro-autoanswer:33] ExecIf("Local/PAGE100@app-paging-00005d5c;2", "1?Set(__SIP_URI_OPTIONS=intercom=true)") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [PAGE100@app-paging:2] NoOp("Local/PAGE100@app-paging-00005d5c;2", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [PAGE100@app-paging:3] GotoIf("Local/PAGE100@app-paging-00005d5c;2", "1?doptions") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Goto (app-paging,PAGE100,6)
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [PAGE100@app-paging:6] ExecIf("Local/PAGE100@app-paging-00005d5c;2", "1?Set(_DOPTIONS=b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0)))") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [PAGE100@app-paging:7] Dial("Local/PAGE100@app-paging-00005d5c;2", "SIP/100,5,A(beep)b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0))") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@macro-autoanswer:26] Set("Local/PAGE101@app-paging-00005d5d;2", "CONTACT=101;@af87dbffe9430a83aae13700f059c8e5,101;@f1e51e734dab1892b5220fa257d6cb3b") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] netsock2.c: Using SIP RTP TOS bits 184
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] netsock2.c: Using SIP RTP CoS mark 5
[2016-06-07 17:08:37] VERBOSE[18273] dial.c: Called PAGE102@app-paging/n
[2016-06-07 17:08:37] WARNING[18267][C-00006c62] func_pjsip_contact.c: Unknown property '101;@f1e51e734dab1892b5220fa257d6cb3b,user_agent' for PJSIP contact
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@macro-autoanswer:27] Set("Local/PAGE101@app-paging-00005d5d;2", "USERAGENT=") in new stack
[2016-06-07 17:08:37] VERBOSE[18272] dial.c: Called PAGE101@app-paging/n
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@macro-autoanswer:28] ExecIf("Local/PAGE101@app-paging-00005d5d;2", "1?Set(USERAGENT=Cisco/SPA509G-7.6.2)") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@macro-autoanswer:29] ExecIf("Local/PAGE101@app-paging-00005d5d;2", "0?Set(CALLINFO=<sip:broadworks.net>;answer-after=0)") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@macro-autoanswer:30] ExecIf("Local/PAGE101@app-paging-00005d5d;2", "0?Set(ALERTINFO=Intercom)") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@macro-autoanswer:31] ExecIf("Local/PAGE101@app-paging-00005d5d;2", "0?Set(ALERTINFO=info=Auto Answer)") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] app_stack.c: SIP/100-000070a8 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) start
[2016-06-07 17:08:37] VERBOSE[18254][C-00006c5f] res_agi.c: <Local/500@ext-paging-00005d5a;2>AGI Script page.agi completed, returning 0
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [PAGE101@app-paging:1] Macro("Local/PAGE101@app-paging-00005d5e;2", "autoanswer,101") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@macro-autoanswer:32] ExecIf("Local/PAGE101@app-paging-00005d5d;2", "0?Set(ALERTINFO=ring-answer)") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@macro-autoanswer:33] ExecIf("Local/PAGE101@app-paging-00005d5d;2", "1?Set(__SIP_URI_OPTIONS=intercom=true)") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [PAGE101@app-paging:2] NoOp("Local/PAGE101@app-paging-00005d5d;2", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [PAGE101@app-paging:3] GotoIf("Local/PAGE101@app-paging-00005d5d;2", "1?doptions") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Goto (app-paging,PAGE101,6)
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [PAGE101@app-paging:6] ExecIf("Local/PAGE101@app-paging-00005d5d;2", "1?Set(_DOPTIONS=b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0)))") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [PAGE101@app-paging:7] Dial("Local/PAGE101@app-paging-00005d5d;2", "PJSIP/101/sip:101@10.10.43.22:5061,5,A(beep)b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0))") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@autoanswer:1] GosubIf("SIP/100-000070a8", "1?func-set-sipheader,s,1(Alert-Info,Ring Answer)") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-set-sipheader:1] NoOp("SIP/100-000070a8", "Sip Add Header function called. Adding Alert-Info = Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [PAGE102@app-paging:1] Macro("Local/PAGE102@app-paging-00005d5f;2", "autoanswer,102") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-set-sipheader:2] Set("SIP/100-000070a8", "HASH(_SIPHEADERS,Alert-Info)=Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-set-sipheader:3] Return("SIP/100-000070a8", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@macro-autoanswer:1] GotoIf("Local/PAGE102@app-paging-00005d5f;2", "1?knowndial") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Goto (macro-autoanswer,s,19)
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@macro-autoanswer:19] Set("Local/PAGE102@app-paging-00005d5f;2", "DIAL=SIP/102") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@macro-autoanswer:20] ExecIf("Local/PAGE102@app-paging-00005d5f;2", "0?Set(DIAL=DAHDI/102)") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@autoanswer:2] GosubIf("SIP/100-000070a8", "1?func-set-sipheader,s,1(Call-Info,<uri>;answer-after=0)") in new stack
[2016-06-07 17:08:37] VERBOSE[18277] dial.c: Called s@app-page-stream
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@macro-autoanswer:21] GotoIf("Local/PAGE102@app-paging-00005d5f;2", "0?macro") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@macro-autoanswer:22] GotoIf("Local/PAGE102@app-paging-00005d5f;2", "0?pjsipua") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@macro-autoanswer:23] Set("Local/PAGE102@app-paging-00005d5f;2", "USERAGENT=Cisco/SPA509G-7.5.2b") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@macro-autoanswer:24] Goto("Local/PAGE102@app-paging-00005d5f;2", "uafin") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Goto (macro-autoanswer,s,28)
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-set-sipheader:1] NoOp("SIP/100-000070a8", "Sip Add Header function called. Adding Call-Info = <uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18278][C-00006c65] pbx.c: Executing [s@app-page-stream:1] Wait("Local/s@app-page-stream-00005d61;2", "1") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@macro-autoanswer:28] ExecIf("Local/PAGE102@app-paging-00005d5f;2", "0?Set(USERAGENT=)") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@macro-autoanswer:1] GotoIf("Local/PAGE101@app-paging-00005d5e;2", "1?knowndial") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-set-sipheader:2] Set("SIP/100-000070a8", "HASH(_SIPHEADERS,Call-Info)=<uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] app_stack.c: PJSIP/101-00001027 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) start
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-set-sipheader:3] Return("SIP/100-000070a8", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@autoanswer:1] GosubIf("PJSIP/101-00001027", "1?func-set-sipheader,s,1(Alert-Info,Ring Answer)") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Goto (macro-autoanswer,s,19)
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@autoanswer:3] Gosub("SIP/100-000070a8", "func-apply-sipheaders,s,1()") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@macro-autoanswer:19] Set("Local/PAGE101@app-paging-00005d5e;2", "DIAL=PJSIP/101/sip:101@10.10.43.18:5061") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@macro-autoanswer:29] ExecIf("Local/PAGE102@app-paging-00005d5f;2", "0?Set(CALLINFO=<sip:broadworks.net>;answer-after=0)") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/100-000070a8", "Applying SIP Headers to channel") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/100-000070a8", "SIPHEADERKEYS=Call-Info,Alert-Info") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@macro-autoanswer:30] ExecIf("Local/PAGE102@app-paging-00005d5f;2", "0?Set(ALERTINFO=Intercom)") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-set-sipheader:1] NoOp("PJSIP/101-00001027", "Sip Add Header function called. Adding Alert-Info = Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@macro-autoanswer:31] ExecIf("Local/PAGE102@app-paging-00005d5f;2", "0?Set(ALERTINFO=info=Auto Answer)") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-set-sipheader:2] Set("PJSIP/101-00001027", "HASH(_SIPHEADERS,Alert-Info)=Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18274] dial.c: Called PAGE103@app-paging/n
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [PAGE103@app-paging:1] Macro("Local/PAGE103@app-paging-00005d60;2", "autoanswer,103") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@macro-autoanswer:20] ExecIf("Local/PAGE101@app-paging-00005d5e;2", "0?Set(DIAL=DAHDIIP/101/sip:101@10.10.43.18:5061)") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@macro-autoanswer:32] ExecIf("Local/PAGE102@app-paging-00005d5f;2", "0?Set(ALERTINFO=ring-answer)") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@macro-autoanswer:21] GotoIf("Local/PAGE101@app-paging-00005d5e;2", "0?macro") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@macro-autoanswer:22] GotoIf("Local/PAGE101@app-paging-00005d5e;2", "1?pjsipua") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Goto (macro-autoanswer,s,25)
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@macro-autoanswer:33] ExecIf("Local/PAGE102@app-paging-00005d5f;2", "1?Set(__SIP_URI_OPTIONS=intercom=true)") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@macro-autoanswer:25] Set("Local/PAGE101@app-paging-00005d5e;2", "AOR=101") in new stack
[2016-06-07 17:08:37] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@app-pagegroups:13] Set("Local/500@ext-paging-00005d5a;2", "CONFBRIDGE(user,template)=page_user_duplex") in new stack
[2016-06-07 17:08:37] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@app-pagegroups:14] Set("Local/500@ext-paging-00005d5a;2", "CONFBRIDGE(user,admin)=yes") in new stack
[2016-06-07 17:08:37] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@app-pagegroups:15] Set("Local/500@ext-paging-00005d5a;2", "CONFBRIDGE(user,marked)=yes") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-set-sipheader:3] Return("PJSIP/101-00001027", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@app-pagegroups:16] Answer("Local/500@ext-paging-00005d5a;2", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@autoanswer:2] GosubIf("PJSIP/101-00001027", "1?func-set-sipheader,s,1(Call-Info,<uri>;answer-after=0)") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/100-000070a8", "1") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [PAGE102@app-paging:2] NoOp("Local/PAGE102@app-paging-00005d5f;2", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [PAGE102@app-paging:3] GotoIf("Local/PAGE102@app-paging-00005d5f;2", "1?doptions") in new stack
[2016-06-07 17:08:37] VERBOSE[29575] file.c: <Local/500@ext-paging-00005d5a;1> Playing 'silence/5.gsm' (language 'en')
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@macro-autoanswer:1] GotoIf("Local/PAGE103@app-paging-00005d60;2", "1?knowndial") in new stack
[2016-06-07 17:08:37] VERBOSE[18254][C-00006c5f] pbx.c: Executing [500@app-pagegroups:17] ConfBridge("Local/500@ext-paging-00005d5a;2", "1465333716815,,,admin_menu") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Goto (macro-autoanswer,s,19)
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Goto (app-paging,PAGE102,6)
[2016-06-07 17:08:37] VERBOSE[18254][C-00006c5f] bridge_channel.c: Channel Local/500@ext-paging-00005d5a;2 joined 'softmix' base-bridge <c1f0b33f-eba9-4971-a17f-f9078786690e>
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@macro-autoanswer:26] Set("Local/PAGE101@app-paging-00005d5e;2", "CONTACT=101;@af87dbffe9430a83aae13700f059c8e5,101;@f1e51e734dab1892b5220fa257d6cb3b") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-set-sipheader:1] NoOp("PJSIP/101-00001027", "Sip Add Header function called. Adding Call-Info = <uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@macro-autoanswer:19] Set("Local/PAGE103@app-paging-00005d60;2", "DIAL=SIP/103") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/100-000070a8", "sipheader=<uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [PAGE102@app-paging:6] ExecIf("Local/PAGE102@app-paging-00005d5f;2", "1?Set(_DOPTIONS=b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0)))") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-set-sipheader:2] Set("PJSIP/101-00001027", "HASH(_SIPHEADERS,Call-Info)=<uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] WARNING[18276][C-00006c63] func_pjsip_contact.c: Unknown property '101;@f1e51e734dab1892b5220fa257d6cb3b,user_agent' for PJSIP contact
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@macro-autoanswer:27] Set("Local/PAGE101@app-paging-00005d5e;2", "USERAGENT=") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@macro-autoanswer:20] ExecIf("Local/PAGE103@app-paging-00005d60;2", "0?Set(DIAL=DAHDI/103)") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [PAGE102@app-paging:7] Dial("Local/PAGE102@app-paging-00005d5f;2", "SIP/102,5,A(beep)b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0))") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@macro-autoanswer:28] ExecIf("Local/PAGE101@app-paging-00005d5e;2", "1?Set(USERAGENT=Cisco/SPA509G-7.6.2)") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-set-sipheader:3] Return("PJSIP/101-00001027", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] netsock2.c: Using SIP RTP TOS bits 184
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] netsock2.c: Using SIP RTP CoS mark 5
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("SIP/100-000070a8", "Call-Info: <uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/100-000070a8", "PJSIP_HEADER(add,Call-Info)=<uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] ERROR[18264][C-00006c61] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-apply-sipheaders:7] EndWhile("SIP/100-000070a8", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@macro-autoanswer:29] ExecIf("Local/PAGE101@app-paging-00005d5e;2", "0?Set(CALLINFO=<sip:broadworks.net>;answer-after=0)") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@macro-autoanswer:21] GotoIf("Local/PAGE103@app-paging-00005d60;2", "0?macro") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@macro-autoanswer:30] ExecIf("Local/PAGE101@app-paging-00005d5e;2", "0?Set(ALERTINFO=Intercom)") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/100-000070a8", "1") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/100-000070a8", "sipheader=Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@macro-autoanswer:22] GotoIf("Local/PAGE103@app-paging-00005d60;2", "0?pjsipua") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@macro-autoanswer:31] ExecIf("Local/PAGE101@app-paging-00005d5e;2", "0?Set(ALERTINFO=info=Auto Answer)") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@autoanswer:3] Gosub("PJSIP/101-00001027", "func-apply-sipheaders,s,1()") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@macro-autoanswer:23] Set("Local/PAGE103@app-paging-00005d60;2", "USERAGENT=Cisco/SPA509G-7.5.2b") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("SIP/100-000070a8", "Alert-Info: Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@macro-autoanswer:32] ExecIf("Local/PAGE101@app-paging-00005d5e;2", "0?Set(ALERTINFO=ring-answer)") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@macro-autoanswer:24] Goto("Local/PAGE103@app-paging-00005d60;2", "uafin") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("PJSIP/101-00001027", "Applying SIP Headers to channel") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/100-000070a8", "PJSIP_HEADER(add,Alert-Info)=Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@macro-autoanswer:33] ExecIf("Local/PAGE101@app-paging-00005d5e;2", "1?Set(__SIP_URI_OPTIONS=intercom=true)") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Goto (macro-autoanswer,s,28)
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@macro-autoanswer:28] ExecIf("Local/PAGE103@app-paging-00005d60;2", "0?Set(USERAGENT=)") in new stack
[2016-06-07 17:08:37] ERROR[18264][C-00006c61] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-apply-sipheaders:2] Set("PJSIP/101-00001027", "SIPHEADERKEYS=Call-Info,Alert-Info") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-apply-sipheaders:7] EndWhile("SIP/100-000070a8", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@macro-autoanswer:29] ExecIf("Local/PAGE103@app-paging-00005d60;2", "0?Set(CALLINFO=<sip:broadworks.net>;answer-after=0)") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/100-000070a8", "0") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [PAGE101@app-paging:2] NoOp("Local/PAGE101@app-paging-00005d5e;2", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] app_stack.c: SIP/102-000070a9 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) start
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@macro-autoanswer:30] ExecIf("Local/PAGE103@app-paging-00005d60;2", "0?Set(ALERTINFO=Intercom)") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-apply-sipheaders:3] While("PJSIP/101-00001027", "1") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/100-000070a8", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [PAGE101@app-paging:3] GotoIf("Local/PAGE101@app-paging-00005d5e;2", "1?doptions") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Goto (app-paging,PAGE101,6)
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/101-00001027", "sipheader=<uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@macro-autoanswer:31] ExecIf("Local/PAGE103@app-paging-00005d60;2", "0?Set(ALERTINFO=info=Auto Answer)") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@autoanswer:1] GosubIf("SIP/102-000070a9", "1?func-set-sipheader,s,1(Alert-Info,Ring Answer)") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [PAGE101@app-paging:6] ExecIf("Local/PAGE101@app-paging-00005d5e;2", "1?Set(_DOPTIONS=b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0)))") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] pbx.c: Executing [s@autoanswer:4] Return("SIP/100-000070a8", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@macro-autoanswer:32] ExecIf("Local/PAGE103@app-paging-00005d60;2", "0?Set(ALERTINFO=ring-answer)") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("PJSIP/101-00001027", "Call-Info: <uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [PAGE101@app-paging:7] Dial("Local/PAGE101@app-paging-00005d5e;2", "PJSIP/101/sip:101@10.10.43.18:5061,5,A(beep)b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0))") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-set-sipheader:1] NoOp("SIP/102-000070a9", "Sip Add Header function called. Adding Alert-Info = Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-set-sipheader:2] Set("SIP/102-000070a9", "HASH(_SIPHEADERS,Alert-Info)=Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-apply-sipheaders:6] Set("PJSIP/101-00001027", "PJSIP_HEADER(add,Call-Info)=<uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] app_stack.c: Spawn extension (from-internal, PAGE100, 1) exited non-zero on 'SIP/100-000070a8'
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@macro-autoanswer:33] ExecIf("Local/PAGE103@app-paging-00005d60;2", "1?Set(__SIP_URI_OPTIONS=intercom=true)") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] app_stack.c: SIP/100-000070a8 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) complete GOSUB_RETVAL=
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-apply-sipheaders:7] EndWhile("PJSIP/101-00001027", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-set-sipheader:3] Return("SIP/102-000070a9", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@autoanswer:2] GosubIf("SIP/102-000070a9", "1?func-set-sipheader,s,1(Call-Info,<uri>;answer-after=0)") in new stack
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] app_dial.c: Called SIP/100
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-apply-sipheaders:3] While("PJSIP/101-00001027", "1") in new stack
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 100[ext-local] new state Ringing for Notify User 102
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/101-00001027", "sipheader=Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 100[ext-local] new state Ringing for Notify User 103
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("PJSIP/101-00001027", "Alert-Info: Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] app_stack.c: PJSIP/101-00001028 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) start
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-set-sipheader:1] NoOp("SIP/102-000070a9", "Sip Add Header function called. Adding Call-Info = <uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [PAGE103@app-paging:2] NoOp("Local/PAGE103@app-paging-00005d60;2", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-apply-sipheaders:6] Set("PJSIP/101-00001027", "PJSIP_HEADER(add,Alert-Info)=Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-set-sipheader:2] Set("SIP/102-000070a9", "HASH(_SIPHEADERS,Call-Info)=<uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@autoanswer:1] GosubIf("PJSIP/101-00001028", "1?func-set-sipheader,s,1(Alert-Info,Ring Answer)") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-apply-sipheaders:7] EndWhile("PJSIP/101-00001027", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-set-sipheader:3] Return("SIP/102-000070a9", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [PAGE103@app-paging:3] GotoIf("Local/PAGE103@app-paging-00005d60;2", "1?doptions") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-set-sipheader:1] NoOp("PJSIP/101-00001028", "Sip Add Header function called. Adding Alert-Info = Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-set-sipheader:2] Set("PJSIP/101-00001028", "HASH(_SIPHEADERS,Alert-Info)=Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Goto (app-paging,PAGE103,6)
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-set-sipheader:3] Return("PJSIP/101-00001028", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [PAGE103@app-paging:6] ExecIf("Local/PAGE103@app-paging-00005d60;2", "1?Set(_DOPTIONS=b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0)))") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@autoanswer:2] GosubIf("PJSIP/101-00001028", "1?func-set-sipheader,s,1(Call-Info,<uri>;answer-after=0)") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@autoanswer:3] Gosub("SIP/102-000070a9", "func-apply-sipheaders,s,1()") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-apply-sipheaders:3] While("PJSIP/101-00001027", "0") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [PAGE103@app-paging:7] Dial("Local/PAGE103@app-paging-00005d60;2", "SIP/103,5,A(beep)b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0))") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/102-000070a9", "Applying SIP Headers to channel") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/102-000070a9", "SIPHEADERKEYS=Call-Info,Alert-Info") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-set-sipheader:1] NoOp("PJSIP/101-00001028", "Sip Add Header function called. Adding Call-Info = <uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-set-sipheader:2] Set("PJSIP/101-00001028", "HASH(_SIPHEADERS,Call-Info)=<uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-set-sipheader:3] Return("PJSIP/101-00001028", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@autoanswer:3] Gosub("PJSIP/101-00001028", "func-apply-sipheaders,s,1()") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/102-000070a9", "1") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@func-apply-sipheaders:8] Return("PJSIP/101-00001027", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/102-000070a9", "sipheader=<uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("SIP/102-000070a9", "Call-Info: <uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] pbx.c: Executing [s@autoanswer:4] Return("PJSIP/101-00001027", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/102-000070a9", "PJSIP_HEADER(add,Call-Info)=<uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] ERROR[18275][C-00006c64] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-apply-sipheaders:7] EndWhile("SIP/102-000070a9", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] netsock2.c: Using SIP RTP TOS bits 184
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("PJSIP/101-00001028", "Applying SIP Headers to channel") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] netsock2.c: Using SIP RTP CoS mark 5
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-apply-sipheaders:2] Set("PJSIP/101-00001028", "SIPHEADERKEYS=Call-Info,Alert-Info") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] app_stack.c: Spawn extension (from-internal, PAGE101, 1) exited non-zero on 'PJSIP/101-00001027'
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] app_stack.c: PJSIP/101-00001027 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) complete GOSUB_RETVAL=
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/102-000070a9", "1") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-apply-sipheaders:3] While("PJSIP/101-00001028", "1") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/102-000070a9", "sipheader=Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("SIP/102-000070a9", "Alert-Info: Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] app_dial.c: Called PJSIP/101/sip:101@10.10.43.22:5061
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/101-00001028", "sipheader=<uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/102-000070a9", "PJSIP_HEADER(add,Alert-Info)=Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP request (1020 bytes) to UDP:10.10.43.22:5061 --->
INVITE sip:101@10.10.43.22:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.250:5061;rport;branch=z9hG4bKPjngTVJb9A9oJhQL0PwH6-hbWnFL2uFHf-
From: "1234:" <sip:7182493243@10.10.43.250>;tag=vV0UKeOemI7lRENe09GO9ujl0dQl8god
To: <sip:101@10.10.43.22>
Contact: <sip:asterisk@10.10.43.250:5061>
Call-ID: 92skLYphDQaxJEk-RyQoyauK2xfN67gm
CSeq: 8564 INVITE
Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REFER, MESSAGE, REGISTER
Supported: 100rel, timer, replaces, norefersub
Session-Expires: 1800
Min-SE: 90
Call-Info: <uri>;answer-after=0
Alert-Info: Ring Answer
Max-Forwards: 70
User-Agent: FPBX-13.0.124(13.7.2)
Content-Type: application/sdp
Content-Length: 313
v=0
o=- 835752082 835752082 IN IP4 10.10.43.250
s=Asterisk
c=IN IP4 10.10.43.250
t=0 0
m=audio 13012 RTP/AVP 0 8 3 111 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("PJSIP/101-00001028", "Call-Info: <uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] ERROR[18275][C-00006c64] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-apply-sipheaders:7] EndWhile("SIP/102-000070a9", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-apply-sipheaders:6] Set("PJSIP/101-00001028", "PJSIP_HEADER(add,Call-Info)=<uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/102-000070a9", "0") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/102-000070a9", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] pbx.c: Executing [s@autoanswer:4] Return("SIP/102-000070a9", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-apply-sipheaders:7] EndWhile("PJSIP/101-00001028", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] app_stack.c: Spawn extension (from-internal, PAGE102, 1) exited non-zero on 'SIP/102-000070a9'
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] app_stack.c: SIP/102-000070a9 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) complete GOSUB_RETVAL=
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-apply-sipheaders:3] While("PJSIP/101-00001028", "1") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/101-00001028", "sipheader=Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] app_stack.c: SIP/103-000070aa Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) start
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@autoanswer:1] GosubIf("SIP/103-000070aa", "1?func-set-sipheader,s,1(Alert-Info,Ring Answer)") in new stack
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] app_dial.c: Called SIP/102
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 102[ext-local] new state Ringing for Notify User 103
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("PJSIP/101-00001028", "Alert-Info: Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-set-sipheader:1] NoOp("SIP/103-000070aa", "Sip Add Header function called. Adding Alert-Info = Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-set-sipheader:2] Set("SIP/103-000070aa", "HASH(_SIPHEADERS,Alert-Info)=Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-apply-sipheaders:6] Set("PJSIP/101-00001028", "PJSIP_HEADER(add,Alert-Info)=Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-apply-sipheaders:7] EndWhile("PJSIP/101-00001028", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-set-sipheader:3] Return("SIP/103-000070aa", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-apply-sipheaders:3] While("PJSIP/101-00001028", "0") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@func-apply-sipheaders:8] Return("PJSIP/101-00001028", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@autoanswer:2] GosubIf("SIP/103-000070aa", "1?func-set-sipheader,s,1(Call-Info,<uri>;answer-after=0)") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-set-sipheader:1] NoOp("SIP/103-000070aa", "Sip Add Header function called. Adding Call-Info = <uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] pbx.c: Executing [s@autoanswer:4] Return("PJSIP/101-00001028", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] app_stack.c: Spawn extension (from-internal, PAGE101, 1) exited non-zero on 'PJSIP/101-00001028'
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] app_stack.c: PJSIP/101-00001028 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) complete GOSUB_RETVAL=
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-set-sipheader:2] Set("SIP/103-000070aa", "HASH(_SIPHEADERS,Call-Info)=<uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] app_dial.c: Called PJSIP/101/sip:101@10.10.43.18:5061
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-set-sipheader:3] Return("SIP/103-000070aa", "") in new stack
[2016-06-07 17:08:37] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP request (1021 bytes) to UDP:10.10.43.18:5061 --->
INVITE sip:101@10.10.43.18:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.250:5061;rport;branch=z9hG4bKPjRgUVGNhCvAOG0mrNZdXJChe8i6iVrmiQ
From: "1234:" <sip:7182493243@10.10.43.250>;tag=MyTPUgPRStV5tG9qfiauyNS26Rx9SZCQ
To: <sip:101@10.10.43.18>
Contact: <sip:asterisk@10.10.43.250:5061>
Call-ID: Bin9yzLb4anSu.NzD5lXBNDe1FqQwk-H
CSeq: 24110 INVITE
Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REFER, MESSAGE, REGISTER
Supported: 100rel, timer, replaces, norefersub
Session-Expires: 1800
Min-SE: 90
Call-Info: <uri>;answer-after=0
Alert-Info: Ring Answer
Max-Forwards: 70
User-Agent: FPBX-13.0.124(13.7.2)
Content-Type: application/sdp
Content-Length: 313
v=0
o=- 584302085 584302085 IN IP4 10.10.43.250
s=Asterisk
c=IN IP4 10.10.43.250
t=0 0
m=audio 12440 RTP/AVP 0 8 3 111 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@autoanswer:3] Gosub("SIP/103-000070aa", "func-apply-sipheaders,s,1()") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/103-000070aa", "Applying SIP Headers to channel") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/103-000070aa", "SIPHEADERKEYS=Call-Info,Alert-Info") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/103-000070aa", "1") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/103-000070aa", "sipheader=<uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("SIP/103-000070aa", "Call-Info: <uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP response (326 bytes) from UDP:10.10.43.22:5061 --->
SIP/2.0 100 Trying
To: <sip:101@10.10.43.22>
From: "1234:" <sip:7182493243@10.10.43.250>;tag=vV0UKeOemI7lRENe09GO9ujl0dQl8god
Call-ID: 92skLYphDQaxJEk-RyQoyauK2xfN67gm
CSeq: 8564 INVITE
Via: SIP/2.0/UDP 10.10.43.250:5061;branch=z9hG4bKPjngTVJb9A9oJhQL0PwH6-hbWnFL2uFHf-
Server: Cisco/SPA509G-7.6.2
Content-Length: 0
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/103-000070aa", "PJSIP_HEADER(add,Call-Info)=<uri>;answer-after=0") in new stack
[2016-06-07 17:08:37] ERROR[18280][C-00006c66] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-apply-sipheaders:7] EndWhile("SIP/103-000070aa", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/103-000070aa", "1") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/103-000070aa", "sipheader=Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("SIP/103-000070aa", "Alert-Info: Ring Answer") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/103-000070aa", "PJSIP_HEADER(add,Alert-Info)=Ring Answer") in new stack
[2016-06-07 17:08:37] ERROR[18280][C-00006c66] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-apply-sipheaders:7] EndWhile("SIP/103-000070aa", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/103-000070aa", "0") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/103-000070aa", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] pbx.c: Executing [s@autoanswer:4] Return("SIP/103-000070aa", "") in new stack
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] app_stack.c: Spawn extension (from-internal, PAGE103, 1) exited non-zero on 'SIP/103-000070aa'
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] app_stack.c: SIP/103-000070aa Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) complete GOSUB_RETVAL=
[2016-06-07 17:08:37] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP response (327 bytes) from UDP:10.10.43.18:5061 --->
SIP/2.0 100 Trying
To: <sip:101@10.10.43.18>
From: "1234:" <sip:7182493243@10.10.43.250>;tag=MyTPUgPRStV5tG9qfiauyNS26Rx9SZCQ
Call-ID: Bin9yzLb4anSu.NzD5lXBNDe1FqQwk-H
CSeq: 24110 INVITE
Via: SIP/2.0/UDP 10.10.43.250:5061;branch=z9hG4bKPjRgUVGNhCvAOG0mrNZdXJChe8i6iVrmiQ
Server: Cisco/SPA509G-7.6.2
Content-Length: 0
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] app_dial.c: Called SIP/103
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Ringing for Notify User 102
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Ringing for Notify User 107
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Ringing for Notify User 100
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Ringing for Notify User 104
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Ringing for Notify User 105
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Ringing for Notify User 106
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Ringing for Notify User 108
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] app_dial.c: SIP/102-000070a9 answered Local/PAGE102@app-paging-00005d5f;2
[2016-06-07 17:08:37] VERBOSE[18275][C-00006c64] file.c: <SIP/102-000070a9> Playing 'beep.ulaw' (language 'en')
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] app_dial.c: SIP/100-000070a8 answered Local/PAGE100@app-paging-00005d5c;2
[2016-06-07 17:08:37] VERBOSE[18264][C-00006c61] file.c: <SIP/100-000070a8> Playing 'beep.ulaw' (language 'en')
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Ringing for Notify User 109
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Ringing for Notify User 110
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Ringing for Notify User 111
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Ringing for Notify User 112
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Ringing for Notify User 113
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 102[ext-local] new state InUse for Notify User 103
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 100[ext-local] new state InUse for Notify User 102
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 100[ext-local] new state InUse for Notify User 103
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] app_dial.c: SIP/103-000070aa answered Local/PAGE103@app-paging-00005d60;2
[2016-06-07 17:08:37] VERBOSE[18280][C-00006c66] file.c: <SIP/103-000070aa> Playing 'beep.ulaw' (language 'en')
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state InUse for Notify User 102
[2016-06-07 17:08:37] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP response (719 bytes) from UDP:10.10.43.22:5061 --->
SIP/2.0 200 OK
To: <sip:101@10.10.43.22>;tag=c8b8880dfb307a2ci0
From: "1234:" <sip:7182493243@10.10.43.250>;tag=vV0UKeOemI7lRENe09GO9ujl0dQl8god
Call-ID: 92skLYphDQaxJEk-RyQoyauK2xfN67gm
CSeq: 8564 INVITE
Via: SIP/2.0/UDP 10.10.43.250:5061;branch=z9hG4bKPjngTVJb9A9oJhQL0PwH6-hbWnFL2uFHf-
Contact: "101" <sip:101@10.10.43.22:5061>
Server: Cisco/SPA509G-7.6.2
Content-Length: 206
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
Content-Type: application/sdp
v=0
o=- 9664598 9664598 IN IP4 10.10.43.22
s=-
c=IN IP4 10.10.43.22
t=0 0
m=audio 16516 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:30
a=sendrecv
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state InUse for Notify User 107
[2016-06-07 17:08:37] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP request (395 bytes) to UDP:10.10.43.22:5061 --->
ACK sip:101@10.10.43.22:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.250:5061;rport;branch=z9hG4bKPjRyqBJTZnH2v5ddcUApADx.sqWjTZ4K7M
From: "1234:" <sip:7182493243@10.10.43.250>;tag=vV0UKeOemI7lRENe09GO9ujl0dQl8god
To: <sip:101@10.10.43.22>;tag=c8b8880dfb307a2ci0
Call-ID: 92skLYphDQaxJEk-RyQoyauK2xfN67gm
CSeq: 8564 ACK
Max-Forwards: 70
User-Agent: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state InUse for Notify User 100
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] app_dial.c: PJSIP/101-00001027 answered Local/PAGE101@app-paging-00005d5d;2
[2016-06-07 17:08:37] VERBOSE[18267][C-00006c62] file.c: <PJSIP/101-00001027> Playing 'beep.ulaw' (language 'en')
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state InUse for Notify User 104
[2016-06-07 17:08:37] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP response (720 bytes) from UDP:10.10.43.18:5061 --->
SIP/2.0 200 OK
To: <sip:101@10.10.43.18>;tag=c195a220c316771ci0
From: "1234:" <sip:7182493243@10.10.43.250>;tag=MyTPUgPRStV5tG9qfiauyNS26Rx9SZCQ
Call-ID: Bin9yzLb4anSu.NzD5lXBNDe1FqQwk-H
CSeq: 24110 INVITE
Via: SIP/2.0/UDP 10.10.43.250:5061;branch=z9hG4bKPjRgUVGNhCvAOG0mrNZdXJChe8i6iVrmiQ
Contact: "101" <sip:101@10.10.43.18:5061>
Server: Cisco/SPA509G-7.6.2
Content-Length: 206
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
Content-Type: application/sdp
v=0
o=- 8880189 8880189 IN IP4 10.10.43.18
s=-
c=IN IP4 10.10.43.18
t=0 0
m=audio 16524 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:30
a=sendrecv
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state InUse for Notify User 105
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state InUse for Notify User 106
[2016-06-07 17:08:37] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP request (396 bytes) to UDP:10.10.43.18:5061 --->
ACK sip:101@10.10.43.18:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.250:5061;rport;branch=z9hG4bKPjDx6vFs4KPlYu0CSfQnEXs.wNOgUh2.X-
From: "1234:" <sip:7182493243@10.10.43.250>;tag=MyTPUgPRStV5tG9qfiauyNS26Rx9SZCQ
To: <sip:101@10.10.43.18>;tag=c195a220c316771ci0
Call-ID: Bin9yzLb4anSu.NzD5lXBNDe1FqQwk-H
CSeq: 24110 ACK
Max-Forwards: 70
User-Agent: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] app_dial.c: PJSIP/101-00001028 answered Local/PAGE101@app-paging-00005d5e;2
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state InUse for Notify User 108
[2016-06-07 17:08:37] VERBOSE[18276][C-00006c63] file.c: <PJSIP/101-00001028> Playing 'beep.ulaw' (language 'en')
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state InUse for Notify User 109
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state InUse for Notify User 110
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state InUse for Notify User 111
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state InUse for Notify User 112
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state InUse for Notify User 113
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 101[ext-local] new state InUse for Notify User 102
[2016-06-07 17:08:37] VERBOSE[1917] chan_sip.c: Extension Changed 101[ext-local] new state InUse for Notify User 103
[2016-06-07 17:08:38] VERBOSE[18273] dial.c: Local/PAGE102@app-paging-00005d5f;1 answered
[2016-06-07 17:08:38] VERBOSE[18273] bridge_channel.c: Channel Local/PAGE102@app-paging-00005d5f;1 joined 'softmix' base-bridge <c1f0b33f-eba9-4971-a17f-f9078786690e>
[2016-06-07 17:08:38] VERBOSE[18283][C-00006c64] bridge_channel.c: Channel SIP/102-000070a9 joined 'simple_bridge' basic-bridge <343dc334-f9f5-42eb-9776-a3abba68f8a9>
[2016-06-07 17:08:38] VERBOSE[18275][C-00006c64] bridge_channel.c: Channel Local/PAGE102@app-paging-00005d5f;2 joined 'simple_bridge' basic-bridge <343dc334-f9f5-42eb-9776-a3abba68f8a9>
[2016-06-07 17:08:38] VERBOSE[18263] dial.c: Local/PAGE100@app-paging-00005d5c;1 answered
[2016-06-07 17:08:38] VERBOSE[18263] bridge_channel.c: Channel Local/PAGE100@app-paging-00005d5c;1 joined 'softmix' base-bridge <c1f0b33f-eba9-4971-a17f-f9078786690e>
[2016-06-07 17:08:38] VERBOSE[18284][C-00006c61] bridge_channel.c: Channel SIP/100-000070a8 joined 'simple_bridge' basic-bridge <946f35ca-5d5e-4dba-b9f3-5c1542dffc9d>
[2016-06-07 17:08:38] VERBOSE[18264][C-00006c61] bridge_channel.c: Channel Local/PAGE100@app-paging-00005d5c;2 joined 'simple_bridge' basic-bridge <946f35ca-5d5e-4dba-b9f3-5c1542dffc9d>
[2016-06-07 17:08:38] VERBOSE[18274] dial.c: Local/PAGE103@app-paging-00005d60;1 answered
[2016-06-07 17:08:38] VERBOSE[18274] bridge_channel.c: Channel Local/PAGE103@app-paging-00005d60;1 joined 'softmix' base-bridge <c1f0b33f-eba9-4971-a17f-f9078786690e>
[2016-06-07 17:08:38] VERBOSE[18266] dial.c: Local/PAGE101@app-paging-00005d5d;1 answered
[2016-06-07 17:08:38] VERBOSE[18266] bridge_channel.c: Channel Local/PAGE101@app-paging-00005d5d;1 joined 'softmix' base-bridge <c1f0b33f-eba9-4971-a17f-f9078786690e>
[2016-06-07 17:08:38] VERBOSE[18285][C-00006c66] bridge_channel.c: Channel SIP/103-000070aa joined 'simple_bridge' basic-bridge <b2e17703-d6d3-475f-8393-da5f1aec3d02>
[2016-06-07 17:08:38] VERBOSE[18280][C-00006c66] bridge_channel.c: Channel Local/PAGE103@app-paging-00005d60;2 joined 'simple_bridge' basic-bridge <b2e17703-d6d3-475f-8393-da5f1aec3d02>
[2016-06-07 17:08:38] VERBOSE[18272] dial.c: Local/PAGE101@app-paging-00005d5e;1 answered
[2016-06-07 17:08:38] VERBOSE[18272] bridge_channel.c: Channel Local/PAGE101@app-paging-00005d5e;1 joined 'softmix' base-bridge <c1f0b33f-eba9-4971-a17f-f9078786690e>
[2016-06-07 17:08:38] VERBOSE[18286][C-00006c62] bridge_channel.c: Channel PJSIP/101-00001027 joined 'simple_bridge' basic-bridge <331f6b17-b745-4890-8d5c-e47fc75d89fc>
[2016-06-07 17:08:38] VERBOSE[18267][C-00006c62] bridge_channel.c: Channel Local/PAGE101@app-paging-00005d5d;2 joined 'simple_bridge' basic-bridge <331f6b17-b745-4890-8d5c-e47fc75d89fc>
[2016-06-07 17:08:38] VERBOSE[18287][C-00006c63] bridge_channel.c: Channel PJSIP/101-00001028 joined 'simple_bridge' basic-bridge <1f737046-dae2-41b2-a987-39f9cbbdf0e2>
[2016-06-07 17:08:38] VERBOSE[18276][C-00006c63] bridge_channel.c: Channel Local/PAGE101@app-paging-00005d5e;2 joined 'simple_bridge' basic-bridge <1f737046-dae2-41b2-a987-39f9cbbdf0e2>
[2016-06-07 17:08:38] VERBOSE[18261][C-00006c60] pbx.c: Executing [s@app-page-stream:2] Answer("Local/s@app-page-stream-00005d5b;2", "") in new stack
[2016-06-07 17:08:38] VERBOSE[18260] dial.c: Local/s@app-page-stream-00005d5b;1 answered
[2016-06-07 17:08:38] VERBOSE[18261][C-00006c60] pbx.c: Executing [s@app-page-stream:3] Set("Local/s@app-page-stream-00005d5b;2", "CONFBRIDGE(user,template)=page_user_duplex") in new stack
[2016-06-07 17:08:38] VERBOSE[18261][C-00006c60] pbx.c: Executing [s@app-page-stream:4] Set("Local/s@app-page-stream-00005d5b;2", "CONFBRIDGE(user,marked)=yes") in new stack
[2016-06-07 17:08:38] VERBOSE[18261][C-00006c60] pbx.c: Executing [s@app-page-stream:5] ConfBridge("Local/s@app-page-stream-00005d5b;2", "1465333716815,,,") in new stack
[2016-06-07 17:08:38] VERBOSE[18261][C-00006c60] bridge_channel.c: Channel Local/s@app-page-stream-00005d5b;2 joined 'softmix' base-bridge <c1f0b33f-eba9-4971-a17f-f9078786690e>
[2016-06-07 17:08:38] VERBOSE[18278][C-00006c65] pbx.c: Executing [s@app-page-stream:2] Answer("Local/s@app-page-stream-00005d61;2", "") in new stack
[2016-06-07 17:08:38] VERBOSE[18277] dial.c: Local/s@app-page-stream-00005d61;1 answered
[2016-06-07 17:08:38] VERBOSE[18277] file.c: <Local/s@app-page-stream-00005d61;1> Playing 'beep.gsm' (language 'en')
[2016-06-07 17:08:38] VERBOSE[18278][C-00006c65] pbx.c: Executing [s@app-page-stream:3] Set("Local/s@app-page-stream-00005d61;2", "CONFBRIDGE(user,template)=page_user_duplex") in new stack
[2016-06-07 17:08:38] VERBOSE[18278][C-00006c65] pbx.c: Executing [s@app-page-stream:4] Set("Local/s@app-page-stream-00005d61;2", "CONFBRIDGE(user,marked)=yes") in new stack
[2016-06-07 17:08:38] VERBOSE[18278][C-00006c65] pbx.c: Executing [s@app-page-stream:5] ConfBridge("Local/s@app-page-stream-00005d61;2", "1465333716815,,,") in new stack
[2016-06-07 17:08:38] VERBOSE[18278][C-00006c65] bridge_channel.c: Channel Local/s@app-page-stream-00005d61;2 joined 'softmix' base-bridge <c1f0b33f-eba9-4971-a17f-f9078786690e>
[2016-06-07 17:08:38] VERBOSE[18278][C-00006c65] bridge_channel.c: Channel Local/s@app-page-stream-00005d61;2 left 'softmix' base-bridge <c1f0b33f-eba9-4971-a17f-f9078786690e>
[2016-06-07 17:08:42] VERBOSE[29575] file.c: <Local/500@ext-paging-00005d5a;1> Playing 'custom/beep.gsm' (language 'en')
[2016-06-07 17:08:42] VERBOSE[18254][C-00006c5f] bridge_channel.c: Channel Local/500@ext-paging-00005d5a;2 left 'softmix' base-bridge <c1f0b33f-eba9-4971-a17f-f9078786690e>
[2016-06-07 17:08:42] VERBOSE[18254][C-00006c5f] pbx.c: Executing [h@app-pagegroups:1] ExecIf("Local/500@ext-paging-00005d5a;2", "1?Set(DEVICE_STATE(Custom:PAGE500)=NOT_INUSE)") in new stack
[2016-06-07 17:08:42] VERBOSE[18254][C-00006c5f] pbx.c: Executing [h@app-pagegroups:2] GosubIf("Local/500@ext-paging-00005d5a;2", "0?record-page,1()") in new stack
[2016-06-07 17:08:42] VERBOSE[18254][C-00006c5f] pbx.c: Executing [h@app-pagegroups:3] ExecIf("Local/500@ext-paging-00005d5a;2", "0?System(rm .sln)") in new stack
[2016-06-07 17:08:42] VERBOSE[18254][C-00006c5f] pbx.c: Executing [h@app-pagegroups:4] ExecIf("Local/500@ext-paging-00005d5a;2", "0?System(rm -f /var/spool/asterisk/outgoing/)") in new stack
[2016-06-07 17:08:43] VERBOSE[18261][C-00006c60] bridge_channel.c: Channel Local/s@app-page-stream-00005d5b;2 left 'softmix' base-bridge <c1f0b33f-eba9-4971-a17f-f9078786690e>
[2016-06-07 17:08:43] VERBOSE[18273] bridge_channel.c: Channel Local/PAGE102@app-paging-00005d5f;1 left 'softmix' base-bridge <c1f0b33f-eba9-4971-a17f-f9078786690e>
[2016-06-07 17:08:43] VERBOSE[18263] bridge_channel.c: Channel Local/PAGE100@app-paging-00005d5c;1 left 'softmix' base-bridge <c1f0b33f-eba9-4971-a17f-f9078786690e>
[2016-06-07 17:08:43] VERBOSE[18274] bridge_channel.c: Channel Local/PAGE103@app-paging-00005d60;1 left 'softmix' base-bridge <c1f0b33f-eba9-4971-a17f-f9078786690e>
[2016-06-07 17:08:43] VERBOSE[18266] bridge_channel.c: Channel Local/PAGE101@app-paging-00005d5d;1 left 'softmix' base-bridge <c1f0b33f-eba9-4971-a17f-f9078786690e>
[2016-06-07 17:08:43] VERBOSE[18272] bridge_channel.c: Channel Local/PAGE101@app-paging-00005d5e;1 left 'softmix' base-bridge <c1f0b33f-eba9-4971-a17f-f9078786690e>
[2016-06-07 17:08:43] VERBOSE[18275][C-00006c64] bridge_channel.c: Channel Local/PAGE102@app-paging-00005d5f;2 left 'simple_bridge' basic-bridge <343dc334-f9f5-42eb-9776-a3abba68f8a9>
[2016-06-07 17:08:43] VERBOSE[18276][C-00006c63] bridge_channel.c: Channel Local/PAGE101@app-paging-00005d5e;2 left 'simple_bridge' basic-bridge <1f737046-dae2-41b2-a987-39f9cbbdf0e2>
[2016-06-07 17:08:43] VERBOSE[18287][C-00006c63] bridge_channel.c: Channel PJSIP/101-00001028 left 'simple_bridge' basic-bridge <1f737046-dae2-41b2-a987-39f9cbbdf0e2>
[2016-06-07 17:08:43] VERBOSE[18264][C-00006c61] bridge_channel.c: Channel Local/PAGE100@app-paging-00005d5c;2 left 'simple_bridge' basic-bridge <946f35ca-5d5e-4dba-b9f3-5c1542dffc9d>
[2016-06-07 17:08:43] VERBOSE[18284][C-00006c61] bridge_channel.c: Channel SIP/100-000070a8 left 'simple_bridge' basic-bridge <946f35ca-5d5e-4dba-b9f3-5c1542dffc9d>
[2016-06-07 17:08:43] VERBOSE[18283][C-00006c64] bridge_channel.c: Channel SIP/102-000070a9 left 'simple_bridge' basic-bridge <343dc334-f9f5-42eb-9776-a3abba68f8a9>
[2016-06-07 17:08:43] VERBOSE[18267][C-00006c62] bridge_channel.c: Channel Local/PAGE101@app-paging-00005d5d;2 left 'simple_bridge' basic-bridge <331f6b17-b745-4890-8d5c-e47fc75d89fc>
[2016-06-07 17:08:43] VERBOSE[18264][C-00006c61] pbx.c: Spawn extension (app-paging, PAGE100, 7) exited non-zero on 'Local/PAGE100@app-paging-00005d5c;2'
[2016-06-07 17:08:43] VERBOSE[18275][C-00006c64] pbx.c: Spawn extension (app-paging, PAGE102, 7) exited non-zero on 'Local/PAGE102@app-paging-00005d5f;2'
[2016-06-07 17:08:43] VERBOSE[18280][C-00006c66] bridge_channel.c: Channel Local/PAGE103@app-paging-00005d60;2 left 'simple_bridge' basic-bridge <b2e17703-d6d3-475f-8393-da5f1aec3d02>
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 102[ext-local] new state Idle for Notify User 103
[2016-06-07 17:08:43] VERBOSE[18286][C-00006c62] bridge_channel.c: Channel PJSIP/101-00001027 left 'simple_bridge' basic-bridge <331f6b17-b745-4890-8d5c-e47fc75d89fc>
[2016-06-07 17:08:43] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP request (420 bytes) to UDP:10.10.43.18:5061 --->
BYE sip:101@10.10.43.18:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.250:5061;rport;branch=z9hG4bKPjmpkQe4RuvPRmS.WM9fshqtuT2ZuNz.MG
From: "1234:" <sip:7182493243@10.10.43.250>;tag=MyTPUgPRStV5tG9qfiauyNS26Rx9SZCQ
To: <sip:101@10.10.43.18>;tag=c195a220c316771ci0
Call-ID: Bin9yzLb4anSu.NzD5lXBNDe1FqQwk-H
CSeq: 24111 BYE
Reason: Q.850;cause=16
Max-Forwards: 70
User-Agent: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:08:43] VERBOSE[18276][C-00006c63] pbx.c: Spawn extension (app-paging, PAGE101, 7) exited non-zero on 'Local/PAGE101@app-paging-00005d5e;2'
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 100[ext-local] new state Idle for Notify User 102
[2016-06-07 17:08:43] VERBOSE[18285][C-00006c66] bridge_channel.c: Channel SIP/103-000070aa left 'simple_bridge' basic-bridge <b2e17703-d6d3-475f-8393-da5f1aec3d02>
[2016-06-07 17:08:43] VERBOSE[25037] res_pjsip_logger.c: <--- Transmitting SIP request (419 bytes) to UDP:10.10.43.22:5061 --->
BYE sip:101@10.10.43.22:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.250:5061;rport;branch=z9hG4bKPjmtvtmz6jMTRRLf3hvlrASTS4z-kkufPF
From: "1234:" <sip:7182493243@10.10.43.250>;tag=vV0UKeOemI7lRENe09GO9ujl0dQl8god
To: <sip:101@10.10.43.22>;tag=c8b8880dfb307a2ci0
Call-ID: 92skLYphDQaxJEk-RyQoyauK2xfN67gm
CSeq: 8565 BYE
Reason: Q.850;cause=16
Max-Forwards: 70
User-Agent: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 100[ext-local] new state Idle for Notify User 103
[2016-06-07 17:08:43] VERBOSE[18267][C-00006c62] pbx.c: Spawn extension (app-paging, PAGE101, 7) exited non-zero on 'Local/PAGE101@app-paging-00005d5d;2'
[2016-06-07 17:08:43] VERBOSE[18280][C-00006c66] pbx.c: Spawn extension (app-paging, PAGE103, 7) exited non-zero on 'Local/PAGE103@app-paging-00005d60;2'
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Idle for Notify User 102
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Idle for Notify User 107
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Idle for Notify User 100
[2016-06-07 17:08:43] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP response (342 bytes) from UDP:10.10.43.22:5061 --->
SIP/2.0 200 OK
To: <sip:101@10.10.43.22>;tag=c8b8880dfb307a2ci0
From: "1234:" <sip:7182493243@10.10.43.250>;tag=vV0UKeOemI7lRENe09GO9ujl0dQl8god
Call-ID: 92skLYphDQaxJEk-RyQoyauK2xfN67gm
CSeq: 8565 BYE
Via: SIP/2.0/UDP 10.10.43.250:5061;branch=z9hG4bKPjmtvtmz6jMTRRLf3hvlrASTS4z-kkufPF
Server: Cisco/SPA509G-7.6.2
Content-Length: 0
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Idle for Notify User 104
[2016-06-07 17:08:43] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP response (343 bytes) from UDP:10.10.43.18:5061 --->
SIP/2.0 200 OK
To: <sip:101@10.10.43.18>;tag=c195a220c316771ci0
From: "1234:" <sip:7182493243@10.10.43.250>;tag=MyTPUgPRStV5tG9qfiauyNS26Rx9SZCQ
Call-ID: Bin9yzLb4anSu.NzD5lXBNDe1FqQwk-H
CSeq: 24111 BYE
Via: SIP/2.0/UDP 10.10.43.250:5061;branch=z9hG4bKPjmpkQe4RuvPRmS.WM9fshqtuT2ZuNz.MG
Server: Cisco/SPA509G-7.6.2
Content-Length: 0
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Idle for Notify User 105
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Idle for Notify User 106
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Idle for Notify User 108
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Idle for Notify User 109
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Idle for Notify User 110
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Idle for Notify User 111
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Idle for Notify User 112
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 103[ext-local] new state Idle for Notify User 113
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 101[ext-local] new state Idle for Notify User 102
[2016-06-07 17:08:43] VERBOSE[1917] chan_sip.c: Extension Changed 101[ext-local] new state Idle for Notify User 103
[2016-06-07 17:08:49] VERBOSE[2016][C-00006c67] netsock2.c: Using SIP RTP TOS bits 184
[2016-06-07 17:08:49] VERBOSE[2016][C-00006c67] netsock2.c: Using SIP RTP CoS mark 5
[2016-06-07 17:08:49] VERBOSE[1917] chan_sip.c: Extension Changed 102[ext-local] new state InUse for Notify User 103
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [71@from-internal:1] Macro("SIP/102-000070ab", "parked-call,71,default") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-parked-call:1] Macro("SIP/102-000070ab", "user-callerid,") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/102-000070ab", "TOUCH_MONITOR=1465333729.80792") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/102-000070ab", "AMPUSER=102") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/102-000070ab", "0?report") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("SIP/102-000070ab", "1?Set(REALCALLERIDNUM=102)") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/102-000070ab", "AMPUSER=102") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("SIP/102-000070ab", "0?limit") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/102-000070ab", "AMPUSERCIDNAME=Stasia") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/102-000070ab", "0?report") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:9] Set("SIP/102-000070ab", "AMPUSERCID=102") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/102-000070ab", "__DIAL_OPTIONS=Ttr") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:11] Set("SIP/102-000070ab", "CALLERID(all)="Stasia" <102>") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:12] GotoIf("SIP/102-000070ab", "0?limit") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/102-000070ab", "0?Set(GROUP(concurrency_limit)=102)") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("SIP/102-000070ab", "0?Set(CHANNEL(language)=)") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:15] GotoIf("SIP/102-000070ab", "0?continue") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:16] ExecIf("SIP/102-000070ab", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/102-000070ab", "__TTL=64") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("SIP/102-000070ab", "1?continue") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Goto (macro-user-callerid,s,29)
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:29] Set("SIP/102-000070ab", "CALLERID(number)=102") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:30] Set("SIP/102-000070ab", "CALLERID(name)=Stasia") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:31] Set("SIP/102-000070ab", "CDR(cnum)=102") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:32] Set("SIP/102-000070ab", "CDR(cnam)=Stasia") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:33] Set("SIP/102-000070ab", "CHANNEL(language)=en") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-user-callerid:34] GosubIf("SIP/102-000070ab", "0?app-check-classofservce,s,1()") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-parked-call:2] Gosub("SIP/102-000070ab", "sub-record-check,s,1(parking,102,102)") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/102-000070ab", "0?initialized") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@sub-record-check:2] Set("SIP/102-000070ab", "__REC_STATUS=INITIALIZED") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@sub-record-check:3] Set("SIP/102-000070ab", "NOW=1465333729") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@sub-record-check:4] Set("SIP/102-000070ab", "__DAY=07") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@sub-record-check:5] Set("SIP/102-000070ab", "__MONTH=06") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@sub-record-check:6] Set("SIP/102-000070ab", "__YEAR=2016") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@sub-record-check:7] Set("SIP/102-000070ab", "__TIMESTR=20160607-170849") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@sub-record-check:8] Set("SIP/102-000070ab", "__FROMEXTEN=102") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@sub-record-check:9] Set("SIP/102-000070ab", "__MON_FMT=wav") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/102-000070ab", "Recordings initialized") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/102-000070ab", "0?Set(ARG3=dontcare)") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@sub-record-check:12] Set("SIP/102-000070ab", "REC_POLICY_MODE_SAVE=") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/102-000070ab", "0?Set(REC_STATUS=NO)") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/102-000070ab", "7?checkaction") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Goto (sub-record-check,s,17)
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/102-000070ab", "1?sub-record-check,parking,1") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Goto (sub-record-check,parking,1)
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [parking@sub-record-check:1] NoOp("SIP/102-000070ab", "User 102 picked up a parked call") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [parking@sub-record-check:2] Set("SIP/102-000070ab", "USER=102") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [parking@sub-record-check:3] ExecIf("SIP/102-000070ab", "0?Set(USER=unknown)") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [parking@sub-record-check:4] Set("SIP/102-000070ab", "RECMODE=dontcare") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [parking@sub-record-check:5] ExecIf("SIP/102-000070ab", "0?Set(RECMODE=dontcare)") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [parking@sub-record-check:6] Gosub("SIP/102-000070ab", "recordcheck,1(dontcare,parked,102)") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/102-000070ab", "Starting recording check against dontcare") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/102-000070ab", "dontcare") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Goto (sub-record-check,recordcheck,3)
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/102-000070ab", "") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [parking@sub-record-check:7] Return("SIP/102-000070ab", "") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-parked-call:3] NoOp("SIP/102-000070ab", "PARKRETURNTO: ") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-parked-call:4] GotoIf("SIP/102-000070ab", "0?backtosender") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-parked-call:5] GotoIf("SIP/102-000070ab", "0?attemptpark") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-parked-call:6] AGI("SIP/102-000070ab", "parkfetch.agi,71,default") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/parkfetch.agi
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] res_agi.c: <SIP/102-000070ab>AGI Script parkfetch.agi completed, returning 0
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-parked-call:7] GotoIf("SIP/102-000070ab", "1?next") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Goto (macro-parked-call,s,10)
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-parked-call:10] Set("SIP/102-000070ab", "CCSS_SETUP=TRUE") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-parked-call:11] GotoIf("SIP/102-000070ab", "1?pcall") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Goto (macro-parked-call,s,18)
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-parked-call:18] NoOp("SIP/102-000070ab", "User: "Stasia" <102> attempting to pick up Parked Call Slot 71") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-parked-call:19] GotoIf("SIP/102-000070ab", "1?privatelotcheck") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Goto (macro-parked-call,s,22)
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-parked-call:22] NoOp("SIP/102-000070ab", "End Force Private Lot Detection") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-parked-call:23] NoOp("SIP/102-000070ab", "PARKIE: SIP/vitelity-inbound-000070a7") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-parked-call:24] Set("SIP/102-000070ab", "SHARED(PARKRETURNTO,SIP/vitelity-inbound-000070a7)=") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-parked-call:25] Set("SIP/102-000070ab", "PARKOWNER=1") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-parked-call:26] ParkedCall("SIP/102-000070ab", "default,71") in new stack
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] bridge_channel.c: Channel SIP/vitelity-inbound-000070a7 left 'holding_bridge' parking-bridge <64211a9e-ed04-4b70-80c1-dc03719cd8cf>
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] res_musiconhold.c: Stopped music on hold on SIP/vitelity-inbound-000070a7
[2016-06-07 17:08:49] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state Idle for Notify User 102
[2016-06-07 17:08:49] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state Idle for Notify User 107
[2016-06-07 17:08:49] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state Idle for Notify User 103
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] bridge_channel.c: Channel SIP/vitelity-inbound-000070a7 joined 'simple_bridge' basic-bridge <f8ffc3e9-f3bc-4381-814c-9f72403b28f8>
[2016-06-07 17:08:49] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state Idle for Notify User 100
[2016-06-07 17:08:49] VERBOSE[18207][C-00006c5e] file.c: <SIP/vitelity-inbound-000070a7> Playing 'beep.ulaw' (language 'en')
[2016-06-07 17:08:49] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state Idle for Notify User 104
[2016-06-07 17:08:49] VERBOSE[18300][C-00006c67] file.c: <SIP/102-000070ab> Playing 'beep.ulaw' (language 'en')
[2016-06-07 17:08:49] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state Idle for Notify User 105
[2016-06-07 17:08:49] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state Idle for Notify User 106
[2016-06-07 17:08:49] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state Idle for Notify User 108
[2016-06-07 17:08:49] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state Idle for Notify User 109
[2016-06-07 17:08:49] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state Idle for Notify User 110
[2016-06-07 17:08:49] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state Idle for Notify User 111
[2016-06-07 17:08:49] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state Idle for Notify User 112
[2016-06-07 17:08:49] VERBOSE[1917] chan_sip.c: Extension Changed 71[park-hints] new state Idle for Notify User 113
[2016-06-07 17:08:50] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP request (730 bytes) from UDP:10.10.43.18:5061 --->
REGISTER sip:10.10.43.250:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.18:5061;branch=z9hG4bK-c7936c08
From: "101" <sip:101@10.10.43.250>;tag=be8654e690bd7fabo0
To: "101" <sip:101@10.10.43.250>
Call-ID: 570a91dc-f08a70d7@10.10.43.18
CSeq: 28624 REGISTER
Max-Forwards: 70
Authorization: Digest username="101",realm="asterisk",nonce="1465333684/0d9a706286e30d418b9586218f9f8f65",uri="sip:10.10.43.250:5061",algorithm=MD5,response="490fcc4414c122d32046a4503328865b",opaque="30e8501549628891",qop=auth,nc=00000002,cnonce="8463e634"
Contact: "101" <sip:101@10.10.43.18:5061>;expires=60
User-Agent: Cisco/SPA509G-7.6.2
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
[2016-06-07 17:08:50] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP response (504 bytes) to UDP:10.10.43.18:5061 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.43.18:5061;rport=5061;received=10.10.43.18;branch=z9hG4bK-c7936c08
Call-ID: 570a91dc-f08a70d7@10.10.43.18
From: "101" <sip:101@10.10.43.250>;tag=be8654e690bd7fabo0
To: "101" <sip:101@10.10.43.250>;tag=z9hG4bK-c7936c08
CSeq: 28624 REGISTER
WWW-Authenticate: Digest realm="asterisk",nonce="1465333730/256205cb2a3cdfc4ee6a0590f189717b",opaque="114db99a541d860a",stale=true,algorithm=md5,qop="auth"
Server: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:08:50] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP request (730 bytes) from UDP:10.10.43.18:5061 --->
REGISTER sip:10.10.43.250:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.18:5061;branch=z9hG4bK-a9efa611
From: "101" <sip:101@10.10.43.250>;tag=be8654e690bd7fabo0
To: "101" <sip:101@10.10.43.250>
Call-ID: 570a91dc-f08a70d7@10.10.43.18
CSeq: 28625 REGISTER
Max-Forwards: 70
Authorization: Digest username="101",realm="asterisk",nonce="1465333730/256205cb2a3cdfc4ee6a0590f189717b",uri="sip:10.10.43.250:5061",algorithm=MD5,response="8c09f7cc2c802747c8ac17498c18de6d",opaque="114db99a541d860a",qop=auth,nc=00000001,cnonce="bb0cfb32"
Contact: "101" <sip:101@10.10.43.18:5061>;expires=60
User-Agent: Cisco/SPA509G-7.6.2
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
[2016-06-07 17:08:50] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP response (469 bytes) to UDP:10.10.43.18:5061 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.10.43.18:5061;rport=5061;received=10.10.43.18;branch=z9hG4bK-a9efa611
Call-ID: 570a91dc-f08a70d7@10.10.43.18
From: "101" <sip:101@10.10.43.250>;tag=be8654e690bd7fabo0
To: "101" <sip:101@10.10.43.250>;tag=z9hG4bK-a9efa611
CSeq: 28625 REGISTER
Date: Tue, 07 Jun 2016 21:08:50 GMT
Contact: <sip:101@10.10.43.22:5061>;expires=17
Contact: <sip:101@10.10.43.18:5061>;expires=59
Server: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:08:50] VERBOSE[18300][C-00006c67] bridge_channel.c: Channel SIP/102-000070ab joined 'simple_bridge' basic-bridge <f8ffc3e9-f3bc-4381-814c-9f72403b28f8>
[2016-06-07 17:08:54] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP request (730 bytes) from UDP:10.10.43.22:5061 --->
REGISTER sip:10.10.43.250:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.22:5061;branch=z9hG4bK-baeaf1a9
From: "101" <sip:101@10.10.43.250>;tag=4925f61ea6c39150o0
To: "101" <sip:101@10.10.43.250>
Call-ID: 9e2e1b04-82e439d1@10.10.43.22
CSeq: 43802 REGISTER
Max-Forwards: 70
Authorization: Digest username="101",realm="asterisk",nonce="1465333688/96032d38b7826d51c1b91566334891e6",uri="sip:10.10.43.250:5061",algorithm=MD5,response="972a3ff8cc71452fc93daa73c94144f6",opaque="4c30871a03b47c60",qop=auth,nc=00000002,cnonce="cef2b2b8"
Contact: "101" <sip:101@10.10.43.22:5061>;expires=60
User-Agent: Cisco/SPA509G-7.6.2
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
[2016-06-07 17:08:54] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP response (504 bytes) to UDP:10.10.43.22:5061 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.43.22:5061;rport=5061;received=10.10.43.22;branch=z9hG4bK-baeaf1a9
Call-ID: 9e2e1b04-82e439d1@10.10.43.22
From: "101" <sip:101@10.10.43.250>;tag=4925f61ea6c39150o0
To: "101" <sip:101@10.10.43.250>;tag=z9hG4bK-baeaf1a9
CSeq: 43802 REGISTER
WWW-Authenticate: Digest realm="asterisk",nonce="1465333734/35dbb4755a6c3af47c4c775968658058",opaque="5b1cc46a40fa4146",stale=true,algorithm=md5,qop="auth"
Server: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:08:54] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP request (730 bytes) from UDP:10.10.43.22:5061 --->
REGISTER sip:10.10.43.250:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.22:5061;branch=z9hG4bK-f979bd96
From: "101" <sip:101@10.10.43.250>;tag=4925f61ea6c39150o0
To: "101" <sip:101@10.10.43.250>
Call-ID: 9e2e1b04-82e439d1@10.10.43.22
CSeq: 43803 REGISTER
Max-Forwards: 70
Authorization: Digest username="101",realm="asterisk",nonce="1465333734/35dbb4755a6c3af47c4c775968658058",uri="sip:10.10.43.250:5061",algorithm=MD5,response="a107132fd8003f00bd066a37667e3b16",opaque="5b1cc46a40fa4146",qop=auth,nc=00000001,cnonce="fc89c400"
Contact: "101" <sip:101@10.10.43.22:5061>;expires=60
User-Agent: Cisco/SPA509G-7.6.2
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
[2016-06-07 17:08:54] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP response (469 bytes) to UDP:10.10.43.22:5061 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.10.43.22:5061;rport=5061;received=10.10.43.22;branch=z9hG4bK-f979bd96
Call-ID: 9e2e1b04-82e439d1@10.10.43.22
From: "101" <sip:101@10.10.43.250>;tag=4925f61ea6c39150o0
To: "101" <sip:101@10.10.43.250>;tag=z9hG4bK-f979bd96
CSeq: 43803 REGISTER
Date: Tue, 07 Jun 2016 21:08:54 GMT
Contact: <sip:101@10.10.43.22:5061>;expires=59
Contact: <sip:101@10.10.43.18:5061>;expires=55
Server: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:09:01] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP request (414 bytes) to UDP:10.10.43.22:5061 --->
OPTIONS sip:101@10.10.43.22:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.250:5061;rport;branch=z9hG4bKPjapuEf.Ei-zs4wh00sfiezkVbEehUYIC6
From: <sip:asterisk@10.10.43.250>;tag=fe1zP77W9t5bWXAN2tHg-zXgNoPzuLgf
To: <sip:101@10.10.43.22>
Contact: <sip:asterisk@10.10.43.250:5061>
Call-ID: CnfIVQypuJdVALa1idURqFgwSg16ulmP
CSeq: 38592 OPTIONS
Max-Forwards: 70
User-Agent: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:09:01] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP response (428 bytes) from UDP:10.10.43.22:5061 --->
SIP/2.0 200 OK
To: <sip:101@10.10.43.22>;tag=5af58b649dbcf98i0
From: <sip:asterisk@10.10.43.250>;tag=fe1zP77W9t5bWXAN2tHg-zXgNoPzuLgf
Call-ID: CnfIVQypuJdVALa1idURqFgwSg16ulmP
CSeq: 38592 OPTIONS
Via: SIP/2.0/UDP 10.10.43.250:5061;branch=z9hG4bKPjapuEf.Ei-zs4wh00sfiezkVbEehUYIC6
Server: Cisco/SPA509G-7.6.2
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
[2016-06-07 17:09:15] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP request (414 bytes) to UDP:10.10.43.18:5061 --->
OPTIONS sip:101@10.10.43.18:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.250:5061;rport;branch=z9hG4bKPjhp8jpQjhbnu3tCFDblbSEDd5OA20iDBe
From: <sip:asterisk@10.10.43.250>;tag=VdW1rSS.0pC2RD5--izCnbd3gjJiCaei
To: <sip:101@10.10.43.18>
Contact: <sip:asterisk@10.10.43.250:5061>
Call-ID: 9fQGnCU7Mpv1Qh2qrTerk5U3ZXneonDG
CSeq: 11324 OPTIONS
Max-Forwards: 70
User-Agent: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:09:15] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP response (428 bytes) from UDP:10.10.43.18:5061 --->
SIP/2.0 200 OK
To: <sip:101@10.10.43.18>;tag=9394dc2d85d7c7fi0
From: <sip:asterisk@10.10.43.250>;tag=VdW1rSS.0pC2RD5--izCnbd3gjJiCaei
Call-ID: 9fQGnCU7Mpv1Qh2qrTerk5U3ZXneonDG
CSeq: 11324 OPTIONS
Via: SIP/2.0/UDP 10.10.43.250:5061;branch=z9hG4bKPjhp8jpQjhbnu3tCFDblbSEDd5OA20iDBe
Server: Cisco/SPA509G-7.6.2
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
[2016-06-07 17:09:36] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP request (730 bytes) from UDP:10.10.43.18:5061 --->
REGISTER sip:10.10.43.250:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.18:5061;branch=z9hG4bK-58795200
From: "101" <sip:101@10.10.43.250>;tag=be8654e690bd7fabo0
To: "101" <sip:101@10.10.43.250>
Call-ID: 570a91dc-f08a70d7@10.10.43.18
CSeq: 28626 REGISTER
Max-Forwards: 70
Authorization: Digest username="101",realm="asterisk",nonce="1465333730/256205cb2a3cdfc4ee6a0590f189717b",uri="sip:10.10.43.250:5061",algorithm=MD5,response="b42c3644920cb8d46e05475ed4b48815",opaque="114db99a541d860a",qop=auth,nc=00000002,cnonce="bb0cfb32"
Contact: "101" <sip:101@10.10.43.18:5061>;expires=60
User-Agent: Cisco/SPA509G-7.6.2
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
[2016-06-07 17:09:36] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP response (504 bytes) to UDP:10.10.43.18:5061 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.43.18:5061;rport=5061;received=10.10.43.18;branch=z9hG4bK-58795200
Call-ID: 570a91dc-f08a70d7@10.10.43.18
From: "101" <sip:101@10.10.43.250>;tag=be8654e690bd7fabo0
To: "101" <sip:101@10.10.43.250>;tag=z9hG4bK-58795200
CSeq: 28626 REGISTER
WWW-Authenticate: Digest realm="asterisk",nonce="1465333776/dc9ef16f46cd6f9ba5ce3e8da0eadcc1",opaque="5d608d6045b37f68",stale=true,algorithm=md5,qop="auth"
Server: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:09:36] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP request (730 bytes) from UDP:10.10.43.18:5061 --->
REGISTER sip:10.10.43.250:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.18:5061;branch=z9hG4bK-9b89168d
From: "101" <sip:101@10.10.43.250>;tag=be8654e690bd7fabo0
To: "101" <sip:101@10.10.43.250>
Call-ID: 570a91dc-f08a70d7@10.10.43.18
CSeq: 28627 REGISTER
Max-Forwards: 70
Authorization: Digest username="101",realm="asterisk",nonce="1465333776/dc9ef16f46cd6f9ba5ce3e8da0eadcc1",uri="sip:10.10.43.250:5061",algorithm=MD5,response="7ced7acc3c2a4493e967314cb7f610e4",opaque="5d608d6045b37f68",qop=auth,nc=00000001,cnonce="64939f9f"
Contact: "101" <sip:101@10.10.43.18:5061>;expires=60
User-Agent: Cisco/SPA509G-7.6.2
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
[2016-06-07 17:09:36] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP response (469 bytes) to UDP:10.10.43.18:5061 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.10.43.18:5061;rport=5061;received=10.10.43.18;branch=z9hG4bK-9b89168d
Call-ID: 570a91dc-f08a70d7@10.10.43.18
From: "101" <sip:101@10.10.43.250>;tag=be8654e690bd7fabo0
To: "101" <sip:101@10.10.43.250>;tag=z9hG4bK-9b89168d
CSeq: 28627 REGISTER
Date: Tue, 07 Jun 2016 21:09:36 GMT
Contact: <sip:101@10.10.43.22:5061>;expires=17
Contact: <sip:101@10.10.43.18:5061>;expires=59
Server: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:09:40] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP request (730 bytes) from UDP:10.10.43.22:5061 --->
REGISTER sip:10.10.43.250:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.22:5061;branch=z9hG4bK-95190b2c
From: "101" <sip:101@10.10.43.250>;tag=4925f61ea6c39150o0
To: "101" <sip:101@10.10.43.250>
Call-ID: 9e2e1b04-82e439d1@10.10.43.22
CSeq: 43804 REGISTER
Max-Forwards: 70
Authorization: Digest username="101",realm="asterisk",nonce="1465333734/35dbb4755a6c3af47c4c775968658058",uri="sip:10.10.43.250:5061",algorithm=MD5,response="0c1a0841b14d1e0f02deff23da4882d7",opaque="5b1cc46a40fa4146",qop=auth,nc=00000002,cnonce="fc89c400"
Contact: "101" <sip:101@10.10.43.22:5061>;expires=60
User-Agent: Cisco/SPA509G-7.6.2
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
[2016-06-07 17:09:40] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP response (504 bytes) to UDP:10.10.43.22:5061 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.10.43.22:5061;rport=5061;received=10.10.43.22;branch=z9hG4bK-95190b2c
Call-ID: 9e2e1b04-82e439d1@10.10.43.22
From: "101" <sip:101@10.10.43.250>;tag=4925f61ea6c39150o0
To: "101" <sip:101@10.10.43.250>;tag=z9hG4bK-95190b2c
CSeq: 43804 REGISTER
WWW-Authenticate: Digest realm="asterisk",nonce="1465333780/78ef923538b0eb89b11f7fc5ddd1caa0",opaque="2374fb3c2817b1c5",stale=true,algorithm=md5,qop="auth"
Server: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:09:40] VERBOSE[1935] res_pjsip_logger.c: <--- Received SIP request (730 bytes) from UDP:10.10.43.22:5061 --->
REGISTER sip:10.10.43.250:5061 SIP/2.0
Via: SIP/2.0/UDP 10.10.43.22:5061;branch=z9hG4bK-8164a535
From: "101" <sip:101@10.10.43.250>;tag=4925f61ea6c39150o0
To: "101" <sip:101@10.10.43.250>
Call-ID: 9e2e1b04-82e439d1@10.10.43.22
CSeq: 43805 REGISTER
Max-Forwards: 70
Authorization: Digest username="101",realm="asterisk",nonce="1465333780/78ef923538b0eb89b11f7fc5ddd1caa0",uri="sip:10.10.43.250:5061",algorithm=MD5,response="04cfcc298e385c673c0d5fb2fc37e56e",opaque="2374fb3c2817b1c5",qop=auth,nc=00000001,cnonce="5ad95256"
Contact: "101" <sip:101@10.10.43.22:5061>;expires=60
User-Agent: Cisco/SPA509G-7.6.2
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces
[2016-06-07 17:09:41] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP response (469 bytes) to UDP:10.10.43.22:5061 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.10.43.22:5061;rport=5061;received=10.10.43.22;branch=z9hG4bK-8164a535
Call-ID: 9e2e1b04-82e439d1@10.10.43.22
From: "101" <sip:101@10.10.43.250>;tag=4925f61ea6c39150o0
To: "101" <sip:101@10.10.43.250>;tag=z9hG4bK-8164a535
CSeq: 43805 REGISTER
Date: Tue, 07 Jun 2016 21:09:41 GMT
Contact: <sip:101@10.10.43.22:5061>;expires=58
Contact: <sip:101@10.10.43.18:5061>;expires=54
Server: FPBX-13.0.124(13.7.2)
Content-Length: 0
[2016-06-07 17:09:41] VERBOSE[18300][C-00006c67] bridge_channel.c: Channel SIP/102-000070ab left 'simple_bridge' basic-bridge <f8ffc3e9-f3bc-4381-814c-9f72403b28f8>
[2016-06-07 17:09:41] VERBOSE[18207][C-00006c5e] bridge_channel.c: Channel SIP/vitelity-inbound-000070a7 left 'simple_bridge' basic-bridge <f8ffc3e9-f3bc-4381-814c-9f72403b28f8>
[2016-06-07 17:09:41] VERBOSE[18300][C-00006c67] app_macro.c: Spawn extension (macro-parked-call, s, 26) exited non-zero on 'SIP/102-000070ab' in macro 'parked-call'
[2016-06-07 17:09:41] VERBOSE[18207][C-00006c5e] pbx.c: Spawn extension (app-parkandannounce, 510, 14) exited non-zero on 'SIP/vitelity-inbound-000070a7'
[2016-06-07 17:09:41] VERBOSE[18300][C-00006c67] pbx.c: Spawn extension (from-internal, 71, 1) exited non-zero on 'SIP/102-000070ab'
[2016-06-07 17:09:41] VERBOSE[18300][C-00006c67] pbx.c: Executing [h@from-internal:1] Macro("SIP/102-000070ab", "hangupcall") in new stack
[2016-06-07 17:09:41] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/102-000070ab", "1?theend") in new stack
[2016-06-07 17:09:41] VERBOSE[18300][C-00006c67] pbx.c: Goto (macro-hangupcall,s,3)
[2016-06-07 17:09:41] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/102-000070ab", "0?Set(CDR(recordingfile)=)") in new stack
[2016-06-07 17:09:41] VERBOSE[18300][C-00006c67] pbx.c: Executing [s@macro-hangupcall:4] Hangup("SIP/102-000070ab", "") in new stack
[2016-06-07 17:09:41] VERBOSE[18300][C-00006c67] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/102-000070ab' in macro 'hangupcall'
[2016-06-07 17:09:41] VERBOSE[18300][C-00006c67] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/102-000070ab'
[2016-06-07 17:09:41] VERBOSE[1917] chan_sip.c: Extension Changed 102[ext-local] new state Idle for Notify User 103
[2016-06-07 17:10:01] VERBOSE[25036] res_pjsip_logger.c: <--- Transmitting SIP request (414 bytes) to UDP:10.10.43.22:5061 --->
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment