[2020-11-30 23:52:26] Asterisk 13.36.0 built by mockbuild @ jenkins7 on a x86_64 running Linux on 2020-09-16 16:27:58 UTC [2020-11-30 23:52:40] VERBOSE[3050] chan_sip.c: Reliably Transmitting (NAT) to sip.trunk.sbc.ip:5060: OPTIONS sip:ims.airtel.in SIP/2.0 Via: SIP/2.0/UDP our.server.ext.ip:5060;branch=z9hG4bK2bef4b09;rport Max-Forwards: 70 From: "Unknown" ;tag=as6002b846 To: Contact: Call-ID: 092bc9fd29f4499139cfe9a433cfaa15@our.server.ext.ip:5060 CSeq: 102 OPTIONS User-Agent: FPBX-14.0.13.40(13.36.0) Date: Mon, 30 Nov 2020 18:22:40 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 --- [2020-11-30 23:52:40] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:sip.trunk.sbc.ip:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP our.server.ext.ip:5060;branch=z9hG4bK2bef4b09;rport=5060 Call-ID: 092bc9fd29f4499139cfe9a433cfaa15@our.server.ext.ip:5060 From: "Unknown";tag=as6002b846 To: ;tag=hz8uw3vn CSeq: 102 OPTIONS Allow: OPTIONS,NOTIFY,SUBSCRIBE,INFO,REGISTER,MESSAGE,REFER,UPDATE,PRACK,BYE,CANCEL,ACK,INVITE Supported: privacy,precondition,100rel Content-Length: 0 <-------------> [2020-11-30 23:52:40] VERBOSE[3050] chan_sip.c: --- (9 headers 0 lines) --- [2020-11-30 23:52:40] VERBOSE[3050] chan_sip.c: Really destroying SIP dialog '092bc9fd29f4499139cfe9a433cfaa15@our.server.ext.ip:5060' Method: OPTIONS [2020-11-30 23:52:44] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:192.168.3.148:60000 ---> SUBSCRIBE sip:MAC001fc11bd7bf@224.0.1.75 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.148:60000;branch=z9hG4bK1820698314 From: ;tag=1969758766 To: Call-ID: 1614498688@192.168.3.148 CSeq: 20 SUBSCRIBE Contact: Max-Forwards: 70 User-Agent: UC802T 2.0.3.98 Expires: 0 Event: ua-profile;profile-type="device";vendor="Htek";model="UC802T";version="2.0.3.98" Accept: application/url Content-Length: 0 <-------------> [2020-11-30 23:52:44] VERBOSE[3050] chan_sip.c: --- (13 headers 0 lines) --- [2020-11-30 23:52:44] VERBOSE[3050] chan_sip.c: Sending to 192.168.3.148:60000 (NAT) [2020-11-30 23:52:44] VERBOSE[3050] chan_sip.c: Creating new subscription [2020-11-30 23:52:44] VERBOSE[3050] chan_sip.c: Sending to 192.168.3.148:60000 (NAT) [2020-11-30 23:52:44] VERBOSE[3050] sip/route.c: sip_route_dump: route/path hop: [2020-11-30 23:52:44] VERBOSE[3050] chan_sip.c: No matching peer for 'MAC001fc11bd7bf' from '192.168.3.148:60000' [2020-11-30 23:52:44] VERBOSE[3050] chan_sip.c: <--- Transmitting (NAT) to 192.168.3.148:60000 ---> SIP/2.0 489 Bad Event Via: SIP/2.0/UDP 192.168.3.148:60000;branch=z9hG4bK1820698314;received=192.168.3.148;rport=60000 From: ;tag=1969758766 To: ;tag=as2fbcac5e Call-ID: 1614498688@192.168.3.148 CSeq: 20 SUBSCRIBE Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 <------------> [2020-11-30 23:52:44] VERBOSE[3050] chan_sip.c: Really destroying SIP dialog '1614498688@192.168.3.148' Method: SUBSCRIBE [2020-11-30 23:52:46] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:55346 ---> <-------------> [2020-11-30 23:52:48] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:192.168.3.139:60000 ---> SUBSCRIBE sip:MAC001fc11b8633@224.0.1.75 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.139:60000;branch=z9hG4bK1820698314 From: ;tag=1969758766 To: Call-ID: 1614498688@192.168.3.139 CSeq: 20 SUBSCRIBE Contact: Max-Forwards: 70 User-Agent: UC802T 2.0.3.98 Expires: 0 Event: ua-profile;profile-type="device";vendor="Htek";model="UC802T";version="2.0.3.98" Accept: application/url Content-Length: 0 <-------------> [2020-11-30 23:52:48] VERBOSE[3050] chan_sip.c: --- (13 headers 0 lines) --- [2020-11-30 23:52:48] VERBOSE[3050] chan_sip.c: Sending to 192.168.3.139:60000 (NAT) [2020-11-30 23:52:48] VERBOSE[3050] chan_sip.c: Creating new subscription [2020-11-30 23:52:48] VERBOSE[3050] chan_sip.c: Sending to 192.168.3.139:60000 (NAT) [2020-11-30 23:52:48] VERBOSE[3050] sip/route.c: sip_route_dump: route/path hop: [2020-11-30 23:52:48] VERBOSE[3050] chan_sip.c: No matching peer for 'MAC001fc11b8633' from '192.168.3.139:60000' [2020-11-30 23:52:48] VERBOSE[3050] chan_sip.c: <--- Transmitting (NAT) to 192.168.3.139:60000 ---> SIP/2.0 489 Bad Event Via: SIP/2.0/UDP 192.168.3.139:60000;branch=z9hG4bK1820698314;received=192.168.3.139;rport=60000 From: ;tag=1969758766 To: ;tag=as161ae9e1 Call-ID: 1614498688@192.168.3.139 CSeq: 20 SUBSCRIBE Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 <------------> [2020-11-30 23:52:48] VERBOSE[3050] chan_sip.c: Really destroying SIP dialog '1614498688@192.168.3.139' Method: SUBSCRIBE [2020-11-30 23:52:55] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:192.168.3.136:60000 ---> SUBSCRIBE sip:MAC001fc11c2b09@224.0.1.75 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.136:60000;branch=z9hG4bK1820698314 From: ;tag=1969758766 To: Call-ID: 1614498688@192.168.3.136 CSeq: 20 SUBSCRIBE Contact: Max-Forwards: 70 User-Agent: UC902 2.0.4.4.54 Expires: 0 Event: ua-profile;profile-type="device";vendor="Htek";model="UC902";version="2.0.4.4.54" Accept: application/url Content-Length: 0 <-------------> [2020-11-30 23:52:55] VERBOSE[3050] chan_sip.c: --- (13 headers 0 lines) --- [2020-11-30 23:52:55] VERBOSE[3050] chan_sip.c: Sending to 192.168.3.136:60000 (NAT) [2020-11-30 23:52:55] VERBOSE[3050] chan_sip.c: Creating new subscription [2020-11-30 23:52:55] VERBOSE[3050] chan_sip.c: Sending to 192.168.3.136:60000 (NAT) [2020-11-30 23:52:55] VERBOSE[3050] sip/route.c: sip_route_dump: route/path hop: [2020-11-30 23:52:55] VERBOSE[3050] chan_sip.c: No matching peer for 'MAC001fc11c2b09' from '192.168.3.136:60000' [2020-11-30 23:52:55] VERBOSE[3050] chan_sip.c: <--- Transmitting (NAT) to 192.168.3.136:60000 ---> SIP/2.0 489 Bad Event Via: SIP/2.0/UDP 192.168.3.136:60000;branch=z9hG4bK1820698314;received=192.168.3.136;rport=60000 From: ;tag=1969758766 To: ;tag=as69a876f5 Call-ID: 1614498688@192.168.3.136 CSeq: 20 SUBSCRIBE Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 <------------> [2020-11-30 23:52:55] VERBOSE[3050] chan_sip.c: Really destroying SIP dialog '1614498688@192.168.3.136' Method: SUBSCRIBE [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:55346 ---> REGISTER sip:192.168.3.229 SIP/2.0 Via: SIP/2.0/UDP 172.16.10.2:55346;branch=z9hG4bK-d87543-2432ac1f1a2f9709-1--d87543-;rport Max-Forwards: 70 Contact: ;expires=0 To: "102" From: "102";tag=ca0f714f Call-ID: ZTgxNTk3ZWExNmNlYjM4ZDQzOTczNzk0ODkyZjdjZTI. CSeq: 3 REGISTER Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO User-Agent: X-Lite release 1011s stamp 41150 Authorization: Digest username="102",realm="asterisk",nonce="3f0ad21b",uri="sip:192.168.3.229",response="32d823884b31776f6f7375830f41116e",algorithm=MD5 Content-Length: 0 <-------------> [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: --- (12 headers 0 lines) --- [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Sending to 172.16.10.2:55346 (NAT) [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Sending to 172.16.10.2:55346 (NAT) [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: <--- Transmitting (no NAT) to 172.16.10.2:55346 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 172.16.10.2:55346;branch=z9hG4bK-d87543-2432ac1f1a2f9709-1--d87543-;received=172.16.10.2;rport=55346 From: "102";tag=ca0f714f To: "102";tag=as2bc388d2 Call-ID: ZTgxNTk3ZWExNmNlYjM4ZDQzOTczNzk0ODkyZjdjZTI. CSeq: 3 REGISTER Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="2f097dc6" Content-Length: 0 <------------> [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Scheduling destruction of SIP dialog 'ZTgxNTk3ZWExNmNlYjM4ZDQzOTczNzk0ODkyZjdjZTI.' in 32000 ms (Method: REGISTER) [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:55346 ---> REGISTER sip:192.168.3.229 SIP/2.0 Via: SIP/2.0/UDP 172.16.10.2:55346;branch=z9hG4bK-d87543-a5694a2f0b358f73-1--d87543-;rport Max-Forwards: 70 Contact: ;expires=0 To: "102" From: "102";tag=ca0f714f Call-ID: ZTgxNTk3ZWExNmNlYjM4ZDQzOTczNzk0ODkyZjdjZTI. CSeq: 4 REGISTER Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO User-Agent: X-Lite release 1011s stamp 41150 Authorization: Digest username="102",realm="asterisk",nonce="2f097dc6",uri="sip:192.168.3.229",response="052fd3b915c8d2fb63c17f022f7372a0",algorithm=MD5 Content-Length: 0 <-------------> [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: --- (12 headers 0 lines) --- [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Sending to 172.16.10.2:55346 (no NAT) [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Unregistered SIP '102' [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: <--- Transmitting (no NAT) to 172.16.10.2:55346 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.10.2:55346;branch=z9hG4bK-d87543-a5694a2f0b358f73-1--d87543-;received=172.16.10.2;rport=55346 From: "102";tag=ca0f714f To: "102";tag=as2bc388d2 Call-ID: ZTgxNTk3ZWExNmNlYjM4ZDQzOTczNzk0ODkyZjdjZTI. CSeq: 4 REGISTER Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Expires: 0 Date: Mon, 30 Nov 2020 18:23:01 GMT Content-Length: 0 <------------> [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Scheduling destruction of SIP dialog 'ZTgxNTk3ZWExNmNlYjM4ZDQzOTczNzk0ODkyZjdjZTI.' in 32000 ms (Method: REGISTER) [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:63226 ---> REGISTER sip:192.168.3.229 SIP/2.0 Via: SIP/2.0/UDP 172.16.10.2:63226;branch=z9hG4bK-d87543-017c0454a0613d02-1--d87543-;rport Max-Forwards: 70 Contact: To: "101" From: "101";tag=8b25f645 Call-ID: YjZkZWIxODY1YWRiYTc1ZjY2NTg2YzBlMzQwNjk5YjM. CSeq: 1 REGISTER Expires: 3600 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO User-Agent: X-Lite release 1011s stamp 41150 Content-Length: 0 <-------------> [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: --- (12 headers 0 lines) --- [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Sending to 172.16.10.2:63226 (NAT) [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Sending to 172.16.10.2:63226 (NAT) [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: <--- Transmitting (no NAT) to 172.16.10.2:63226 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 172.16.10.2:63226;branch=z9hG4bK-d87543-017c0454a0613d02-1--d87543-;received=172.16.10.2;rport=63226 From: "101";tag=8b25f645 To: "101";tag=as14a56383 Call-ID: YjZkZWIxODY1YWRiYTc1ZjY2NTg2YzBlMzQwNjk5YjM. CSeq: 1 REGISTER Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="2b68589b" Content-Length: 0 <------------> [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Scheduling destruction of SIP dialog 'YjZkZWIxODY1YWRiYTc1ZjY2NTg2YzBlMzQwNjk5YjM.' in 32000 ms (Method: REGISTER) [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:63226 ---> REGISTER sip:192.168.3.229 SIP/2.0 Via: SIP/2.0/UDP 172.16.10.2:63226;branch=z9hG4bK-d87543-130f650d9c62c71c-1--d87543-;rport Max-Forwards: 70 Contact: To: "101" From: "101";tag=8b25f645 Call-ID: YjZkZWIxODY1YWRiYTc1ZjY2NTg2YzBlMzQwNjk5YjM. CSeq: 2 REGISTER Expires: 3600 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO User-Agent: X-Lite release 1011s stamp 41150 Authorization: Digest username="101",realm="asterisk",nonce="2b68589b",uri="sip:192.168.3.229",response="acf3d17fd404e12398aa9a717c9ff731",algorithm=MD5 Content-Length: 0 <-------------> [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: --- (13 headers 0 lines) --- [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Sending to 172.16.10.2:63226 (no NAT) [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Registered SIP '101' at 172.16.10.2:63226 [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.10.2:63226: OPTIONS sip:101@172.16.10.2:63226;rinstance=467b3688927cc123 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK3aedaae4 Max-Forwards: 70 From: "Unknown" ;tag=as14379275 To: Contact: Call-ID: 6de33bb47a03db3531657ab018056afd@192.168.3.229:5060 CSeq: 102 OPTIONS User-Agent: FPBX-14.0.13.40(13.36.0) Date: Mon, 30 Nov 2020 18:23:01 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 --- [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: <--- Transmitting (no NAT) to 172.16.10.2:63226 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.10.2:63226;branch=z9hG4bK-d87543-130f650d9c62c71c-1--d87543-;received=172.16.10.2;rport=63226 From: "101";tag=8b25f645 To: "101";tag=as14a56383 Call-ID: YjZkZWIxODY1YWRiYTc1ZjY2NTg2YzBlMzQwNjk5YjM. CSeq: 2 REGISTER Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Expires: 3600 Contact: ;expires=3600 Date: Mon, 30 Nov 2020 18:23:01 GMT Content-Length: 0 <------------> [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Scheduling destruction of SIP dialog '6fd9a47070c4ff411ed029da7e760adc@192.168.3.229:5060' in 32000 ms (Method: NOTIFY) [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.10.2:63226: NOTIFY sip:101@172.16.10.2:63226;rinstance=467b3688927cc123 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK2061dd96 Max-Forwards: 70 From: "Unknown" ;tag=as2b15571d To: Contact: Call-ID: 6fd9a47070c4ff411ed029da7e760adc@192.168.3.229:5060 CSeq: 102 NOTIFY User-Agent: FPBX-14.0.13.40(13.36.0) Event: message-summary Content-Type: application/simple-message-summary Content-Length: 88 Messages-Waiting: no Message-Account: sip:*97@192.168.3.229 Voice-Message: 0/0 (0/0) --- [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Scheduling destruction of SIP dialog 'YjZkZWIxODY1YWRiYTc1ZjY2NTg2YzBlMzQwNjk5YjM.' in 32000 ms (Method: REGISTER) [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:63226 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK3aedaae4 Contact: To: ;tag=9f1ddf69 From: "Unknown";tag=as14379275 Call-ID: 6de33bb47a03db3531657ab018056afd@192.168.3.229:5060 CSeq: 102 OPTIONS Accept: application/sdp Accept-Language: en Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO User-Agent: X-Lite release 1011s stamp 41150 Content-Length: 0 <-------------> [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: --- (12 headers 0 lines) --- [2020-11-30 23:53:01] NOTICE[3050] chan_sip.c: Peer '101' is now Reachable. (8ms / 2000ms) [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Really destroying SIP dialog '6de33bb47a03db3531657ab018056afd@192.168.3.229:5060' Method: OPTIONS [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:63226 ---> SUBSCRIBE sip:101@192.168.3.229 SIP/2.0 Via: SIP/2.0/UDP 172.16.10.2:63226;branch=z9hG4bK-d87543-945518650e14742e-1--d87543-;rport Max-Forwards: 70 Contact: To: "101" From: "101";tag=b53d2667 Call-ID: OTQwODc5MTAxNDQ5YTQ2OGJhMjI3YzNlMTc3OGMyMzA. CSeq: 1 SUBSCRIBE Expires: 300 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO User-Agent: X-Lite release 1011s stamp 41150 Event: message-summary Content-Length: 0 <-------------> [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: --- (13 headers 0 lines) --- [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Sending to 172.16.10.2:63226 (NAT) [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Creating new subscription [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Sending to 172.16.10.2:63226 (NAT) [2020-11-30 23:53:01] VERBOSE[3050] sip/route.c: sip_route_dump: route/path hop: [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Found peer '101' for '101' from 172.16.10.2:63226 [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: <--- Transmitting (no NAT) to 172.16.10.2:63226 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 172.16.10.2:63226;branch=z9hG4bK-d87543-945518650e14742e-1--d87543-;received=172.16.10.2;rport=63226 From: "101";tag=b53d2667 To: "101";tag=as6d62bd93 Call-ID: OTQwODc5MTAxNDQ5YTQ2OGJhMjI3YzNlMTc3OGMyMzA. CSeq: 1 SUBSCRIBE Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="04fac3f8" Content-Length: 0 <------------> [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Scheduling destruction of SIP dialog 'OTQwODc5MTAxNDQ5YTQ2OGJhMjI3YzNlMTc3OGMyMzA.' in 6400 ms (Method: SUBSCRIBE) [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:63226 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK2061dd96 Contact: To: ;tag=93350005 From: "Unknown";tag=as2b15571d Call-ID: 6fd9a47070c4ff411ed029da7e760adc@192.168.3.229:5060 CSeq: 102 NOTIFY User-Agent: X-Lite release 1011s stamp 41150 Content-Length: 0 <-------------> [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: --- (9 headers 0 lines) --- [2020-11-30 23:53:01] VERBOSE[3050] chan_sip.c: Really destroying SIP dialog '6fd9a47070c4ff411ed029da7e760adc@192.168.3.229:5060' Method: NOTIFY [2020-11-30 23:53:02] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:63226 ---> SUBSCRIBE sip:101@192.168.3.229 SIP/2.0 Via: SIP/2.0/UDP 172.16.10.2:63226;branch=z9hG4bK-d87543-0554423f2112dc24-1--d87543-;rport Max-Forwards: 70 Contact: To: "101" From: "101";tag=b53d2667 Call-ID: OTQwODc5MTAxNDQ5YTQ2OGJhMjI3YzNlMTc3OGMyMzA. CSeq: 2 SUBSCRIBE Expires: 300 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO User-Agent: X-Lite release 1011s stamp 41150 Authorization: Digest username="101",realm="asterisk",nonce="04fac3f8",uri="sip:101@192.168.3.229",response="23c2d4dd8198666c614eba643e8b7d1c",algorithm=MD5 Event: message-summary Content-Length: 0 <-------------> [2020-11-30 23:53:02] VERBOSE[3050] chan_sip.c: --- (14 headers 0 lines) --- [2020-11-30 23:53:02] VERBOSE[3050] chan_sip.c: Creating new subscription [2020-11-30 23:53:02] VERBOSE[3050] chan_sip.c: Sending to 172.16.10.2:63226 (no NAT) [2020-11-30 23:53:02] VERBOSE[3050] chan_sip.c: Found peer '101' for '101' from 172.16.10.2:63226 [2020-11-30 23:53:02] VERBOSE[3050] chan_sip.c: Scheduling destruction of SIP dialog 'OTQwODc5MTAxNDQ5YTQ2OGJhMjI3YzNlMTc3OGMyMzA.' in 310000 ms (Method: SUBSCRIBE) [2020-11-30 23:53:02] VERBOSE[3050] chan_sip.c: <--- Transmitting (no NAT) to 172.16.10.2:63226 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.10.2:63226;branch=z9hG4bK-d87543-0554423f2112dc24-1--d87543-;received=172.16.10.2;rport=63226 From: "101";tag=b53d2667 To: "101";tag=as6d62bd93 Call-ID: OTQwODc5MTAxNDQ5YTQ2OGJhMjI3YzNlMTc3OGMyMzA. CSeq: 2 SUBSCRIBE Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Expires: 300 Contact: ;expires=300 Content-Length: 0 <------------> [2020-11-30 23:53:02] VERBOSE[3050] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.10.2:63226: NOTIFY sip:101@172.16.10.2:63226 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK3f2643c4;rport Max-Forwards: 70 Route: From: "Unknown" ;tag=as6d62bd93 To: ;tag=b53d2667 Contact: Call-ID: OTQwODc5MTAxNDQ5YTQ2OGJhMjI3YzNlMTc3OGMyMzA. CSeq: 102 NOTIFY User-Agent: FPBX-14.0.13.40(13.36.0) Event: message-summary Content-Type: application/simple-message-summary Subscription-State: active Content-Length: 88 Messages-Waiting: no Message-Account: sip:*97@192.168.3.229 Voice-Message: 0/0 (0/0) --- [2020-11-30 23:53:02] VERBOSE[3050] chan_sip.c: Retransmitting #1 (no NAT) to 172.16.10.2:63226: NOTIFY sip:101@172.16.10.2:63226 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK3f2643c4;rport Max-Forwards: 70 Route: From: "Unknown" ;tag=as6d62bd93 To: ;tag=b53d2667 Contact: Call-ID: OTQwODc5MTAxNDQ5YTQ2OGJhMjI3YzNlMTc3OGMyMzA. CSeq: 102 NOTIFY User-Agent: FPBX-14.0.13.40(13.36.0) Event: message-summary Content-Type: application/simple-message-summary Subscription-State: active Content-Length: 88 Messages-Waiting: no Message-Account: sip:*97@192.168.3.229 Voice-Message: 0/0 (0/0) --- [2020-11-30 23:53:02] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:63226 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK3f2643c4;rport=5060 Contact: To: ;tag=b53d2667 From: "Unknown";tag=as6d62bd93 Call-ID: OTQwODc5MTAxNDQ5YTQ2OGJhMjI3YzNlMTc3OGMyMzA. CSeq: 102 NOTIFY User-Agent: X-Lite release 1011s stamp 41150 Content-Length: 0 <-------------> [2020-11-30 23:53:02] VERBOSE[3050] chan_sip.c: --- (9 headers 0 lines) --- [2020-11-30 23:53:02] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:63226 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK3f2643c4;rport=5060 Contact: To: ;tag=b53d2667 From: "Unknown";tag=as6d62bd93 Call-ID: OTQwODc5MTAxNDQ5YTQ2OGJhMjI3YzNlMTc3OGMyMzA. CSeq: 102 NOTIFY User-Agent: X-Lite release 1011s stamp 41150 Content-Length: 0 <-------------> [2020-11-30 23:53:02] VERBOSE[3050] chan_sip.c: --- (9 headers 0 lines) --- [2020-11-30 23:53:10] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:sip.trunk.sbc.ip:5060 ---> INVITE sip:+914xxxxxx101@10.93.170.37:5060 SIP/2.0 Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKz77ptzwzowpuh87opzzmnnohm;Role=3;Hpt=8fa2_36 Call-ID: asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in From: ;tag=lv55tuju To: CSeq: 1 INVITE Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,INFO,PRACK,NOTIFY,MESSAGE,REFER,UPDATE Contact: Max-Forwards: 65 Supported: timer,100rel,histinfo Session-Expires: 1800 Min-SE: 600 P-Asserted-Identity: P-Charging-Vector: icid-value=AE888F23F0256F20201130235310;orig-ioi=sip.trunk.136.242;term-ioi=Abasoft_4844514111 P-Early-Media: gated Content-Length: 380 Content-Type: application/sdp v=0 o=- 233873445 233873445 IN IP4 sip.trunk.140.132 s=SBC call c=IN IP4 sip.trunk.140.132 t=0 0 m=audio 49638 RTP/AVP 108 102 8 0 18 116 a=rtpmap:108 AMR/8000 a=fmtp:108 mode-change-neighbor=1;mode-change-period=2 a=rtpmap:102 AMR/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=rtpmap:116 telephone-event/8000 a=ptime:20 a=maxptime:20 a=3gOoBTC <-------------> [2020-11-30 23:53:10] VERBOSE[3050] chan_sip.c: --- (17 headers 16 lines) --- [2020-11-30 23:53:10] VERBOSE[3050] chan_sip.c: Sending to sip.trunk.sbc.ip:5060 (NAT) [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Sending to sip.trunk.sbc.ip:5060 (NAT) [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Using INVITE request as basis request - asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Found peer 'airtel-60' for '94xxxxxxxx94' from sip.trunk.sbc.ip:5060 [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] netsock2.c: Using SIP RTP TOS bits 184 [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] netsock2.c: Using SIP RTP CoS mark 5 [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Got SDP version 233873445 and unique parts [- 233873445 IN IP4 sip.trunk.140.132] [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Found RTP audio format 108 [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Found RTP audio format 102 [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Found RTP audio format 8 [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Found RTP audio format 0 [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Found RTP audio format 18 [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Found RTP audio format 116 [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Found unknown media description format AMR for ID 108 [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Found unknown media description format AMR for ID 102 [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Found audio description format PCMA for ID 8 [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Found audio description format PCMU for ID 0 [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Found audio description format G729 for ID 18 [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Found audio description format telephone-event for ID 116 [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Capabilities: us - (ulaw|alaw|gsm|g726|g722|g723|g726aal2|adpcm|slin|slin|slin|slin|slin|slin|slin|slin|slin|lpc10|g729|speex|speex|speex|ilbc|siren7|siren14|testlaw|g719|opus|jpeg|png|h261|h263|h263p|h264|mpeg4|vp8|vp9|red|t140|silk|silk|silk|silk), peer - audio=(ulaw|alaw|g729)/video=(nothing)/text=(nothing), combined - (ulaw|alaw|g729) [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Peer audio RTP is at port sip.trunk.140.132:49638 [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: Looking for +914xxxxxx101 in from-trunk (domain 10.93.170.37) [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] sip/route.c: sip_route_dump: route/path hop: [2020-11-30 23:53:10] VERBOSE[3050][C-00000dec] chan_sip.c: <--- Transmitting (NAT) to sip.trunk.sbc.ip:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKz77ptzwzowpuh87opzzmnnohm;Role=3;Hpt=8fa2_36;received=sip.trunk.sbc.ip;rport=5060 From: ;tag=lv55tuju To: Call-ID: asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in CSeq: 1 INVITE Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:1] Set("SIP/airtel-60-00001403", "__DIRECTION=INBOUND") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:2] Gosub("SIP/airtel-60-00001403", "sub-record-check,s,1(in,+914xxxxxx101,yes)") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/airtel-60-00001403", "0?initialized") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:2] Set("SIP/airtel-60-00001403", "__REC_STATUS=INITIALIZED") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:3] Set("SIP/airtel-60-00001403", "NOW=1606760590") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:4] Set("SIP/airtel-60-00001403", "__DAY=30") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:5] Set("SIP/airtel-60-00001403", "__MONTH=11") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:6] Set("SIP/airtel-60-00001403", "__YEAR=2020") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:7] Set("SIP/airtel-60-00001403", "__TIMESTR=20201130-235310") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:8] Set("SIP/airtel-60-00001403", "__FROMEXTEN=unknown") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:9] Set("SIP/airtel-60-00001403", "__MON_FMT=wav") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/airtel-60-00001403", "Recordings initialized") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/airtel-60-00001403", "0?Set(ARG3=dontcare)") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:12] Set("SIP/airtel-60-00001403", "REC_POLICY_MODE_SAVE=") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/airtel-60-00001403", "0?Set(REC_STATUS=NO)") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/airtel-60-00001403", "2?checkaction") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (sub-record-check,s,17) [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/airtel-60-00001403", "1?sub-record-check,in,1") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (sub-record-check,in,1) [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/airtel-60-00001403", "Inbound Recording Check to +914xxxxxx101") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [in@sub-record-check:2] Set("SIP/airtel-60-00001403", "FROMEXTEN=unknown") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/airtel-60-00001403", "10?Set(FROMEXTEN=94xxxxxxxx94)") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/airtel-60-00001403", "recordcheck,1(yes,in,+914xxxxxx101)") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/airtel-60-00001403", "Starting recording check against yes") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/airtel-60-00001403", "yes") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (sub-record-check,recordcheck,9) [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [recordcheck@sub-record-check:9] ExecIf("SIP/airtel-60-00001403", "0?Return()") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [recordcheck@sub-record-check:10] Set("SIP/airtel-60-00001403", "__REC_POLICY_MODE=YES") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [recordcheck@sub-record-check:11] Goto("SIP/airtel-60-00001403", "startrec") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (sub-record-check,recordcheck,16) [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp("SIP/airtel-60-00001403", "Starting recording: in, +914xxxxxx101") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [recordcheck@sub-record-check:17] Set("SIP/airtel-60-00001403", "__CALLFILENAME=in-+914xxxxxx101-94xxxxxxxx94-20201130-235310-1606760590.5123") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [recordcheck@sub-record-check:18] MixMonitor("SIP/airtel-60-00001403", "/var/www/html/inRecs_asRecs/2020/11/30/in-+914xxxxxx101-94xxxxxxxx94-20201130-235310-1606760590.5123.wav,abi(LOCAL_MIXMON_ID),") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [recordcheck@sub-record-check:19] Set("SIP/airtel-60-00001403", "__MIXMON_ID=0x7f5698054fd0") in new stack [2020-11-30 23:53:10] VERBOSE[7695][C-00000dec] app_mixmonitor.c: Begin MixMonitor Recording SIP/airtel-60-00001403 [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [recordcheck@sub-record-check:20] Set("SIP/airtel-60-00001403", "__RECORD_ID=SIP/airtel-60-00001403") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [recordcheck@sub-record-check:21] Set("SIP/airtel-60-00001403", "__REC_STATUS=RECORDING") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [recordcheck@sub-record-check:22] Set("SIP/airtel-60-00001403", "CDR(recordingfile)=in-+914xxxxxx101-94xxxxxxxx94-20201130-235310-1606760590.5123.wav") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [recordcheck@sub-record-check:23] Return("SIP/airtel-60-00001403", "") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [in@sub-record-check:5] Return("SIP/airtel-60-00001403", "") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:3] Set("SIP/airtel-60-00001403", "CHANNEL(tonezone)=us") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:4] Set("SIP/airtel-60-00001403", "__FROM_DID=+914xxxxxx101") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:5] Set("SIP/airtel-60-00001403", "returnhere=1") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:6] Gosub("SIP/airtel-60-00001403", "app-blacklist-check,s,1()") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/airtel-60-00001403", "0?blacklisted") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/airtel-60-00001403", "CALLED_BLACKLIST=1") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/airtel-60-00001403", "") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:7] Set("SIP/airtel-60-00001403", "CDR(did)=+914xxxxxx101") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:8] GotoIf("SIP/airtel-60-00001403", "0?") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:9] ExecIf("SIP/airtel-60-00001403", "1 ?Set(CALLERID(name)=94xxxxxxxx94)") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:10] Set("SIP/airtel-60-00001403", "__MOHCLASS=") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:11] Set("SIP/airtel-60-00001403", "__REVERSAL_REJECT=FALSE") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:12] GotoIf("SIP/airtel-60-00001403", "1?post-reverse-charge") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (from-trunk,+914xxxxxx101,14) [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:14] NoOp("SIP/airtel-60-00001403", "") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:15] Set("SIP/airtel-60-00001403", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:16] Set("SIP/airtel-60-00001403", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:17] Set("SIP/airtel-60-00001403", "CALLERID(name-pres)=allowed_not_screened") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:18] Set("SIP/airtel-60-00001403", "CALLERID(num-pres)=allowed_not_screened") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:19] NoOp("SIP/airtel-60-00001403", "CallerID Entry Point") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:20] Set("SIP/airtel-60-00001403", "__CRM_DIRECTION=INBOUND") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:21] Set("SIP/airtel-60-00001403", "__CRM_SOURCE=94xxxxxxxx94") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:22] Set("SIP/airtel-60-00001403", "__CRM_LINKEDID=1606760590.5123") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:23] AGI("SIP/airtel-60-00001403", "sangomacrm.agi,true") in new stack [2020-11-30 23:53:10] VERBOSE[7694][C-00000dec] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] res_agi.c: AGI Script sangomacrm.agi completed, returning 0 [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:24] ExecIf("SIP/airtel-60-00001403", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [+914xxxxxx101@from-trunk:25] Goto("SIP/airtel-60-00001403", "from-did-direct,101,1") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (from-did-direct,101,1) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [101@from-did-direct:1] GotoIf("SIP/airtel-60-00001403", "1?ext-local,101,1:followme-check,101,1") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (ext-local,101,1) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [101@ext-local:1] Set("SIP/airtel-60-00001403", "__RINGTIMER=15") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [101@ext-local:2] ExecIf("SIP/airtel-60-00001403", "0?Set(__CWIGNORE=)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [101@ext-local:3] Macro("SIP/airtel-60-00001403", "exten-vm,novm,101,0,0,0") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:1] Macro("SIP/airtel-60-00001403", "user-callerid,") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/airtel-60-00001403", "TOUCH_MONITOR=1606760590.5123") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/airtel-60-00001403", "AMPUSER=94xxxxxxxx94") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/airtel-60-00001403", "HOTDESCKCHAN=airtel-60-00001403") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/airtel-60-00001403", "HOTDESKEXTEN=airtel") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/airtel-60-00001403", "HOTDESKCALL=0") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/airtel-60-00001403", "0?Set(HOTDESKCALL=1)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/airtel-60-00001403", "0?Set(CALLERID(name)=)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/airtel-60-00001403", "0?report") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/airtel-60-00001403", "1?Set(REALCALLERIDNUM=94xxxxxxxx94)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/airtel-60-00001403", "AMPUSER=") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/airtel-60-00001403", "0?limit") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/airtel-60-00001403", "AMPUSERCIDNAME=") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/airtel-60-00001403", "0?Set(__CIDMASQUERADING=TRUE)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/airtel-60-00001403", "1?report") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (macro-user-callerid,s,29) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:29] NoOp("SIP/airtel-60-00001403", "Macro Depth is 2") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("SIP/airtel-60-00001403", "1?report2:macroerror") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (macro-user-callerid,s,31) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:31] GotoIf("SIP/airtel-60-00001403", "0?continue") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:32] ExecIf("SIP/airtel-60-00001403", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:33] Set("SIP/airtel-60-00001403", "__TTL=64") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:34] GotoIf("SIP/airtel-60-00001403", "1?continue") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (macro-user-callerid,s,50) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:50] Set("SIP/airtel-60-00001403", "CALLERID(number)=94xxxxxxxx94") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:51] Set("SIP/airtel-60-00001403", "CALLERID(name)=94xxxxxxxx94") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:52] GotoIf("SIP/airtel-60-00001403", "0?cnum") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:53] Set("SIP/airtel-60-00001403", "CDR(cnam)=94xxxxxxxx94") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:54] Set("SIP/airtel-60-00001403", "CDR(cnum)=94xxxxxxxx94") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-user-callerid:55] Set("SIP/airtel-60-00001403", "CHANNEL(language)=en") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:2] Set("SIP/airtel-60-00001403", "RingGroupMethod=none") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:3] Set("SIP/airtel-60-00001403", "__EXTTOCALL=101") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:4] Set("SIP/airtel-60-00001403", "__PICKUPMARK=101") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:5] Set("SIP/airtel-60-00001403", "RT=") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:6] ExecIf("SIP/airtel-60-00001403", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:7] ExecIf("SIP/airtel-60-00001403", "0?MacroExit()") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:8] ExecIf("SIP/airtel-60-00001403", "0?Gosub(ext-intercom,*80101,1())") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:9] ExecIf("SIP/airtel-60-00001403", "0?MacroExit()") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:10] ExecIf("SIP/airtel-60-00001403", "0?ChanSpy(SIP/101,q)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:11] ExecIf("SIP/airtel-60-00001403", "0?MacroExit()") in new stack [2020-11-30 23:53:11] ERROR[7694][C-00000dec] res_pjsip_header_funcs.c: This function requires a PJSIP channel. [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:12] ExecIf("SIP/airtel-60-00001403", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack [2020-11-30 23:53:11] ERROR[7694][C-00000dec] res_pjsip_header_funcs.c: This function requires a PJSIP channel. [2020-11-30 23:53:11] ERROR[7694][C-00000dec] res_pjsip_header_funcs.c: This function requires a PJSIP channel. [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:13] ExecIf("SIP/airtel-60-00001403", "0?MacroExit()") in new stack [2020-11-30 23:53:11] ERROR[7694][C-00000dec] res_pjsip_header_funcs.c: This function requires a PJSIP channel. [2020-11-30 23:53:11] ERROR[7694][C-00000dec] res_pjsip_header_funcs.c: This function requires a PJSIP channel. [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:14] ExecIf("SIP/airtel-60-00001403", "0?Gosub(ext-intercom,*80101,1())") in new stack [2020-11-30 23:53:11] ERROR[7694][C-00000dec] res_pjsip_header_funcs.c: This function requires a PJSIP channel. [2020-11-30 23:53:11] ERROR[7694][C-00000dec] res_pjsip_header_funcs.c: This function requires a PJSIP channel. [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:15] ExecIf("SIP/airtel-60-00001403", "0?MacroExit()") in new stack [2020-11-30 23:53:11] ERROR[7694][C-00000dec] res_pjsip_header_funcs.c: This function requires a PJSIP channel. [2020-11-30 23:53:11] ERROR[7694][C-00000dec] res_pjsip_header_funcs.c: This function requires a PJSIP channel. [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:16] ExecIf("SIP/airtel-60-00001403", "0?ChanSpy(SIP/101,q)") in new stack [2020-11-30 23:53:11] ERROR[7694][C-00000dec] res_pjsip_header_funcs.c: This function requires a PJSIP channel. [2020-11-30 23:53:11] ERROR[7694][C-00000dec] res_pjsip_header_funcs.c: This function requires a PJSIP channel. [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:17] ExecIf("SIP/airtel-60-00001403", "0?MacroExit()") in new stack [2020-11-30 23:53:11] ERROR[7694][C-00000dec] res_pjsip_header_funcs.c: This function requires a PJSIP channel. [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:18] Gosub("SIP/airtel-60-00001403", "sub-record-check,s,1(exten,101,dontcare)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/airtel-60-00001403", "10?initialized") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (sub-record-check,s,10) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/airtel-60-00001403", "Recordings initialized") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/airtel-60-00001403", "0?Set(ARG3=dontcare)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:12] Set("SIP/airtel-60-00001403", "REC_POLICY_MODE_SAVE=YES") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/airtel-60-00001403", "0?Set(REC_STATUS=NO)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/airtel-60-00001403", "5?checkaction") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (sub-record-check,s,17) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/airtel-60-00001403", "1?sub-record-check,exten,1") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (sub-record-check,exten,1) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/airtel-60-00001403", "Exten Recording Check between 94xxxxxxxx94 and 101") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/airtel-60-00001403", "CALLTYPE=external") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/airtel-60-00001403", "0?Set(CALLTYPE=)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/airtel-60-00001403", "CALLEE=dontcare") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/airtel-60-00001403", "0?Set(CALLEE=dontcare)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/airtel-60-00001403", "1?callee") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (sub-record-check,exten,11) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [exten@sub-record-check:11] Gosub("SIP/airtel-60-00001403", "recordcheck,1(dontcare,external,101)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/airtel-60-00001403", "Starting recording check against dontcare") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/airtel-60-00001403", "dontcare") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (sub-record-check,recordcheck,3) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/airtel-60-00001403", "") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [exten@sub-record-check:12] Return("SIP/airtel-60-00001403", "") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:19] GotoIf("SIP/airtel-60-00001403", "1?macrodial") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (macro-exten-vm,s,25) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:25] GosubIf("SIP/airtel-60-00001403", "0?clrheader,1()") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-exten-vm:26] Macro("SIP/airtel-60-00001403", "dial-one,,HhTtr,101") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:1] Set("SIP/airtel-60-00001403", "DEXTEN=101") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:2] Set("SIP/airtel-60-00001403", "__CRM_SOURCE=94xxxxxxxx94") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:3] ExecIf("SIP/airtel-60-00001403", "0?Set(__EXTTOCALL=101)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:4] Set("SIP/airtel-60-00001403", "DIALSTATUS_CW=") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:5] GosubIf("SIP/airtel-60-00001403", "0?screen,1()") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:6] GosubIf("SIP/airtel-60-00001403", "0?cf,1()") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:7] GotoIf("SIP/airtel-60-00001403", "1?skip1") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (macro-dial-one,s,10) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:10] GotoIf("SIP/airtel-60-00001403", "0?nodial") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:11] GotoIf("SIP/airtel-60-00001403", "0?continue") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:12] Set("SIP/airtel-60-00001403", "EXTHASCW=ENABLED") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:13] GotoIf("SIP/airtel-60-00001403", "0?next1:cwinusebusy") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (macro-dial-one,s,25) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:25] GotoIf("SIP/airtel-60-00001403", "0?next3:continue") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (macro-dial-one,s,27) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:27] GotoIf("SIP/airtel-60-00001403", "0?nodial") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:28] GosubIf("SIP/airtel-60-00001403", "1?dstring,1():dlocal,1()") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [dstring@macro-dial-one:1] Set("SIP/airtel-60-00001403", "DSTRING=") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [dstring@macro-dial-one:2] Set("SIP/airtel-60-00001403", "DEVICES=101") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("SIP/airtel-60-00001403", "0?Return()") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("SIP/airtel-60-00001403", "0?Set(DEVICES=01)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [dstring@macro-dial-one:5] Set("SIP/airtel-60-00001403", "LOOPCNT=1") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [dstring@macro-dial-one:6] Set("SIP/airtel-60-00001403", "ITER=1") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [dstring@macro-dial-one:7] Set("SIP/airtel-60-00001403", "THISDIAL=SIP/101") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("SIP/airtel-60-00001403", "1?zap2dahdi,1()") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/airtel-60-00001403", "0?Return()") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("SIP/airtel-60-00001403", "NEWDIAL=") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("SIP/airtel-60-00001403", "LOOPCNT2=1") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("SIP/airtel-60-00001403", "ITER2=1") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("SIP/airtel-60-00001403", "THISPART2=SIP/101") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/airtel-60-00001403", "0?Set(THISPART2=DAHDI/101)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("SIP/airtel-60-00001403", "NEWDIAL=SIP/101&") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("SIP/airtel-60-00001403", "ITER2=2") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/airtel-60-00001403", "0?begin2") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("SIP/airtel-60-00001403", "THISDIAL=SIP/101") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("SIP/airtel-60-00001403", "") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("SIP/airtel-60-00001403", "1?docheck") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (macro-dial-one,dstring,15) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf("SIP/airtel-60-00001403", "0?skipset") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [dstring@macro-dial-one:16] Set("SIP/airtel-60-00001403", "DSTRING=SIP/101&") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [dstring@macro-dial-one:17] Set("SIP/airtel-60-00001403", "ITER=2") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf("SIP/airtel-60-00001403", "0?begin") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf("SIP/airtel-60-00001403", "0?Return()") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [dstring@macro-dial-one:20] Set("SIP/airtel-60-00001403", "DSTRING=SIP/101") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [dstring@macro-dial-one:21] Return("SIP/airtel-60-00001403", "") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:29] GotoIf("SIP/airtel-60-00001403", "0?nodial") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:30] GotoIf("SIP/airtel-60-00001403", "0?skiptrace") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:31] GosubIf("SIP/airtel-60-00001403", "1?ctset,1():ctclear,1()") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [ctset@macro-dial-one:1] Set("SIP/airtel-60-00001403", "DB(CALLTRACE/101)=94xxxxxxxx94") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [ctset@macro-dial-one:2] Return("SIP/airtel-60-00001403", "") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:32] Set("SIP/airtel-60-00001403", "D_OPTIONS=HhTtr") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:33] GosubIf("SIP/airtel-60-00001403", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:34] NoOp("SIP/airtel-60-00001403", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:35] ExecIf("SIP/airtel-60-00001403", "0?Set(ALERT_INFO=)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:36] ExecIf("SIP/airtel-60-00001403", "0?Set(ALERT_INFO=)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:37] ExecIf("SIP/airtel-60-00001403", "0?Set(ALERT_INFO=)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:38] ExecIf("SIP/airtel-60-00001403", "0?Set(ALERT_INFO=Normal;volume=)") in new stack [2020-11-30 23:53:11] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:sip.trunk.sbc.ip:5060 ---> NOTIFY tel:+914xxxxxx101 SIP/2.0 Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKtv8prt7wv9wmuw7mph793h3hn;Role=3;Hpt=8fa2_36;X-HwDim=4 Call-ID: asbcmtytuljxjtvtz2j3tu0tt0jj20l5xxvl@sip.trunk.136.227 From: ;tag=z4m1431u To: CSeq: 1 NOTIFY Contact: Max-Forwards: 67 Supported: 100rel Event: ua-profile Subscription-State: active P-Asserted-Identity: P-Charging-Vector: icid-value=ATS9900-202011302353100779773;term-ioi=Abasoft_4844514111 Content-Length: 158 Content-Type: application/simservs+xml standard-dial-tone <-------------> [2020-11-30 23:53:11] VERBOSE[3050] chan_sip.c: --- (15 headers 6 lines) --- [2020-11-30 23:53:11] VERBOSE[3050] chan_sip.c: Sending to sip.trunk.sbc.ip:5060 (NAT) [2020-11-30 23:53:11] VERBOSE[3050] chan_sip.c: <--- Transmitting (NAT) to sip.trunk.sbc.ip:5060 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKtv8prt7wv9wmuw7mph793h3hn;Role=3;Hpt=8fa2_36;X-HwDim=4;received=sip.trunk.sbc.ip;rport=5060 From: ;tag=z4m1431u To: ;tag=as32b67041 Call-ID: asbcmtytuljxjtvtz2j3tu0tt0jj20l5xxvl@sip.trunk.136.227 CSeq: 1 NOTIFY Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 <------------> [2020-11-30 23:53:11] VERBOSE[3050] chan_sip.c: Scheduling destruction of SIP dialog 'asbcmtytuljxjtvtz2j3tu0tt0jj20l5xxvl@sip.trunk.136.227' in 32000 ms (Method: NOTIFY) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:39] ExecIf("SIP/airtel-60-00001403", "0?Set(ALERT_INFO=Normal;volume=)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:40] GosubIf("SIP/airtel-60-00001403", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:41] ExecIf("SIP/airtel-60-00001403", "0?Set(CHANNEL(musicclass)=)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:42] GosubIf("SIP/airtel-60-00001403", "0?qwait,1()") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:43] Set("SIP/airtel-60-00001403", "__CWIGNORE=") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:44] Set("SIP/airtel-60-00001403", "__KEEPCID=TRUE") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:45] GotoIf("SIP/airtel-60-00001403", "0?usegoto,1") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:46] GotoIf("SIP/airtel-60-00001403", "1?godial") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (macro-dial-one,s,51) [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:51] Macro("SIP/airtel-60-00001403", "dialout-one-predial-hook,") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/airtel-60-00001403", "") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:52] ExecIf("SIP/airtel-60-00001403", "1?Set(D_OPTIONS=HhtrI)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:53] NoOp("SIP/airtel-60-00001403", "") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:54] ExecIf("SIP/airtel-60-00001403", "0?Set(D_OPTIONS=HhtrIg)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-dial-one:55] Dial("SIP/airtel-60-00001403", "SIP/101,,HhtrIb(func-apply-sipheaders^s^1)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] netsock2.c: Using SIP RTP TOS bits 184 [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] netsock2.c: Using SIP RTP CoS mark 5 [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] app_stack.c: SIP/101-00001404 Internal Gosub(func-apply-sipheaders,s,1) start [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/101-00001404", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/101-00001404", "Applying SIP Headers to channel SIP/101-00001404") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/101-00001404", "TECH=SIP") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/101-00001404", "SIPHEADERKEYS=") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/101-00001404", "0") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] app_while.c: Jumping to priority 13 [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/101-00001404", "") in new stack [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] app_stack.c: Spawn extension (from-internal, 101, 1) exited non-zero on 'SIP/101-00001404' [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] app_stack.c: SIP/101-00001404 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL= [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] chan_sip.c: Audio is at 15266 [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] chan_sip.c: Adding codec ulaw to SDP [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] chan_sip.c: Adding codec alaw to SDP [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] chan_sip.c: Adding codec gsm to SDP [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] chan_sip.c: Adding codec g726 to SDP [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] chan_sip.c: Adding codec g722 to SDP [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.10.2:63226: INVITE sip:101@172.16.10.2:63226;rinstance=467b3688927cc123 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK68882c56 Max-Forwards: 70 From: "94xxxxxxxx94" ;tag=as42290cf7 To: Contact: Call-ID: 200f473e37c6eea21bba44af12c5e1ed@192.168.3.229:5060 CSeq: 102 INVITE User-Agent: FPBX-14.0.13.40(13.36.0) Date: Mon, 30 Nov 2020 18:23:11 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer P-Asserted-Identity: "94xxxxxxxx94" Content-Type: application/sdp Content-Length: 354 v=0 o=root 499778517 499778517 IN IP4 192.168.3.229 s=Asterisk PBX 13.36.0 c=IN IP4 192.168.3.229 t=0 0 m=audio 15266 RTP/AVP 0 8 3 111 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:111 G726-32/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv --- [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] app_dial.c: Called SIP/101 [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] chan_sip.c: <--- Transmitting (NAT) to sip.trunk.sbc.ip:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKz77ptzwzowpuh87opzzmnnohm;Role=3;Hpt=8fa2_36;received=sip.trunk.sbc.ip;rport=5060 From: ;tag=lv55tuju To: ;tag=as4b3d6d7e Call-ID: asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in CSeq: 1 INVITE Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] app_dial.c: Connected line update to SIP/airtel-60-00001403 prevented. [2020-11-30 23:53:11] VERBOSE[3050] chan_sip.c: Retransmitting #1 (no NAT) to 172.16.10.2:63226: INVITE sip:101@172.16.10.2:63226;rinstance=467b3688927cc123 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK68882c56 Max-Forwards: 70 From: "94xxxxxxxx94" ;tag=as42290cf7 To: Contact: Call-ID: 200f473e37c6eea21bba44af12c5e1ed@192.168.3.229:5060 CSeq: 102 INVITE User-Agent: FPBX-14.0.13.40(13.36.0) Date: Mon, 30 Nov 2020 18:23:11 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer P-Asserted-Identity: "94xxxxxxxx94" Content-Type: application/sdp Content-Length: 354 v=0 o=root 499778517 499778517 IN IP4 192.168.3.229 s=Asterisk PBX 13.36.0 c=IN IP4 192.168.3.229 t=0 0 m=audio 15266 RTP/AVP 0 8 3 111 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:111 G726-32/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv --- [2020-11-30 23:53:11] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:63226 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK68882c56 To: From: "94xxxxxxxx94" ;tag=as42290cf7 Call-ID: 200f473e37c6eea21bba44af12c5e1ed@192.168.3.229:5060 CSeq: 102 INVITE Content-Length: 0 <-------------> [2020-11-30 23:53:11] VERBOSE[3050] chan_sip.c: --- (7 headers 0 lines) --- [2020-11-30 23:53:11] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:63226 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK68882c56 Contact: To: ;tag=8f472451 From: "94xxxxxxxx94";tag=as42290cf7 Call-ID: 200f473e37c6eea21bba44af12c5e1ed@192.168.3.229:5060 CSeq: 102 INVITE User-Agent: X-Lite release 1011s stamp 41150 Content-Length: 0 <-------------> [2020-11-30 23:53:11] VERBOSE[3050] chan_sip.c: --- (9 headers 0 lines) --- [2020-11-30 23:53:11] VERBOSE[3050][C-00000dec] sip/route.c: sip_route_dump: route/path hop: [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] app_dial.c: SIP/101-00001404 is ringing [2020-11-30 23:53:11] VERBOSE[7694][C-00000dec] chan_sip.c: <--- Transmitting (NAT) to sip.trunk.sbc.ip:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKz77ptzwzowpuh87opzzmnnohm;Role=3;Hpt=8fa2_36;received=sip.trunk.sbc.ip;rport=5060 From: ;tag=lv55tuju To: ;tag=as4b3d6d7e Call-ID: asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in CSeq: 1 INVITE Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> [2020-11-30 23:53:16] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:63226 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK68882c56 Contact: To: ;tag=8f472451 From: "94xxxxxxxx94";tag=as42290cf7 Call-ID: 200f473e37c6eea21bba44af12c5e1ed@192.168.3.229:5060 CSeq: 102 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Content-Type: application/sdp User-Agent: X-Lite release 1011s stamp 41150 Content-Length: 183 v=0 o=- 3 2 IN IP4 172.16.10.2 s=CounterPath X-Lite 3.0 c=IN IP4 172.16.10.2 t=0 0 m=audio 53408 RTP/AVP 0 8 101 a=fmtp:101 0-15 a=rtpmap:101 telephone-event/8000 a=sendrecv <-------------> [2020-11-30 23:53:16] VERBOSE[3050] chan_sip.c: --- (11 headers 9 lines) --- [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Got SDP version 2 and unique parts [- 3 IN IP4 172.16.10.2] [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Found RTP audio format 0 [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Found RTP audio format 8 [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Found RTP audio format 101 [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Found audio description format telephone-event for ID 101 [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Capabilities: us - (ulaw|alaw|gsm|g726|g722), peer - audio=(ulaw|alaw)/video=(nothing)/text=(nothing), combined - (ulaw|alaw) [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Peer audio RTP is at port 172.16.10.2:53408 [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] sip/route.c: sip_route_dump: route/path hop: [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: set_destination: Parsing for address/port to send to [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: set_destination: set destination to 172.16.10.2:63226 [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Transmitting (no NAT) to 172.16.10.2:63226: ACK sip:101@172.16.10.2:63226;rinstance=467b3688927cc123 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK4023e457 Max-Forwards: 70 From: "94xxxxxxxx94" ;tag=as42290cf7 To: ;tag=8f472451 Contact: Call-ID: 200f473e37c6eea21bba44af12c5e1ed@192.168.3.229:5060 CSeq: 102 ACK User-Agent: FPBX-14.0.13.40(13.36.0) Content-Length: 0 --- [2020-11-30 23:53:16] VERBOSE[7694][C-00000dec] app_dial.c: Connected line update to SIP/airtel-60-00001403 prevented. [2020-11-30 23:53:16] VERBOSE[7694][C-00000dec] app_dial.c: SIP/101-00001404 answered SIP/airtel-60-00001403 [2020-11-30 23:53:16] VERBOSE[7694][C-00000dec] chan_sip.c: Audio is at 14348 [2020-11-30 23:53:16] VERBOSE[7694][C-00000dec] chan_sip.c: Adding codec ulaw to SDP [2020-11-30 23:53:16] VERBOSE[7694][C-00000dec] chan_sip.c: Adding codec alaw to SDP [2020-11-30 23:53:16] VERBOSE[7694][C-00000dec] chan_sip.c: Adding codec g729 to SDP [2020-11-30 23:53:16] VERBOSE[7694][C-00000dec] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [2020-11-30 23:53:16] VERBOSE[7694][C-00000dec] chan_sip.c: <--- Reliably Transmitting (NAT) to sip.trunk.sbc.ip:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKz77ptzwzowpuh87opzzmnnohm;Role=3;Hpt=8fa2_36;received=sip.trunk.sbc.ip;rport=5060 From: ;tag=lv55tuju To: ;tag=as4b3d6d7e Call-ID: asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in CSeq: 1 INVITE Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Type: application/sdp Require: timer Content-Length: 311 v=0 o=root 314668089 314668089 IN IP4 our.server.ext.ip s=Asterisk PBX 13.36.0 c=IN IP4 our.server.ext.ip t=0 0 m=audio 14348 RTP/AVP 0 8 18 116 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:116 telephone-event/8000 a=fmtp:116 0-16 a=maxptime:150 a=sendrecv <------------> [2020-11-30 23:53:16] VERBOSE[7715][C-00000dec] bridge_channel.c: Channel SIP/101-00001404 joined 'simple_bridge' basic-bridge [2020-11-30 23:53:16] VERBOSE[7694][C-00000dec] bridge_channel.c: Channel SIP/airtel-60-00001403 joined 'simple_bridge' basic-bridge [2020-11-30 23:53:16] VERBOSE[3050] chan_sip.c: Retransmitting #1 (NAT) to sip.trunk.sbc.ip:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKz77ptzwzowpuh87opzzmnnohm;Role=3;Hpt=8fa2_36;received=sip.trunk.sbc.ip;rport=5060 From: ;tag=lv55tuju To: ;tag=as4b3d6d7e Call-ID: asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in CSeq: 1 INVITE Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Type: application/sdp Require: timer Content-Length: 311 v=0 o=root 314668089 314668089 IN IP4 our.server.ext.ip s=Asterisk PBX 13.36.0 c=IN IP4 our.server.ext.ip t=0 0 m=audio 14348 RTP/AVP 0 8 18 116 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:116 telephone-event/8000 a=fmtp:116 0-16 a=maxptime:150 a=sendrecv --- [2020-11-30 23:53:16] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:sip.trunk.sbc.ip:5060 ---> ACK sip:+914xxxxxx101@our.server.ext.ip:5060 SIP/2.0 Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKp77rzm8o3uww293o8v7wm8p3r;Role=3;Hpt=8fa2_36 Call-ID: asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in From: "094xxxxxxxx94";tag=lv55tuju To: ;tag=as4b3d6d7e CSeq: 1 ACK Max-Forwards: 65 P-Charging-Vector: icid-value=AE888F23F0256F20201130235310;orig-ioi=sip.trunk.136.242;term-ioi=Abasoft_4844514111 Content-Length: 0 <-------------> [2020-11-30 23:53:16] VERBOSE[3050] chan_sip.c: --- (9 headers 0 lines) --- [2020-11-30 23:53:16] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:sip.trunk.sbc.ip:5060 ---> INVITE sip:+914xxxxxx101@our.server.ext.ip:5060 SIP/2.0 Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKw3n8rzzmuvp29t98zr8t7prvm;Role=3;Hpt=8fa2_36 Call-ID: asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in From: ;tag=lv55tuju To: ;tag=as4b3d6d7e CSeq: 2 INVITE Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,INFO,PRACK,NOTIFY,MESSAGE,REFER Contact: Max-Forwards: 65 Supported: timer Session-Expires: 1800;refresher=uas Min-SE: 600 P-Charging-Vector: icid-value=AE888F23F0256F20201130235310;orig-ioi=sip.trunk.136.242;term-ioi=Abasoft_4844514111 Content-Length: 194 Content-Type: application/sdp v=0 o=- 233873445 233873446 IN IP4 sip.trunk.140.132 s=SBC call c=IN IP4 sip.trunk.140.132 t=0 0 m=audio 49638 RTP/AVP 0 116 a=rtpmap:0 PCMU/8000 a=rtpmap:116 telephone-event/8000 a=ptime:20 <-------------> [2020-11-30 23:53:16] VERBOSE[3050] chan_sip.c: --- (15 headers 9 lines) --- [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Sending to sip.trunk.sbc.ip:5060 (NAT) [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Comparing SDP version 233873445 -> 233873446 and unique parts [- 233873445 IN IP4 sip.trunk.140.132] -> [- 233873445 IN IP4 sip.trunk.140.132] [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Found RTP audio format 0 [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Found RTP audio format 116 [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Found audio description format PCMU for ID 0 [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Found audio description format telephone-event for ID 116 [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Capabilities: us - (ulaw|alaw|gsm|g726|g722|g723|g726aal2|adpcm|slin|slin|slin|slin|slin|slin|slin|slin|slin|lpc10|g729|speex|speex|speex|ilbc|siren7|siren14|testlaw|g719|opus|jpeg|png|h261|h263|h263p|h264|mpeg4|vp8|vp9|red|t140|silk|silk|silk|silk), peer - audio=(ulaw)/video=(nothing)/text=(nothing), combined - (ulaw) [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Peer audio RTP is at port sip.trunk.140.132:49638 [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: <--- Transmitting (NAT) to sip.trunk.sbc.ip:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKw3n8rzzmuvp29t98zr8t7prvm;Role=3;Hpt=8fa2_36;received=sip.trunk.sbc.ip;rport=5060 From: ;tag=lv55tuju To: ;tag=as4b3d6d7e Call-ID: asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in CSeq: 2 INVITE Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Audio is at 14348 [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Adding codec ulaw to SDP [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [2020-11-30 23:53:16] VERBOSE[3050][C-00000dec] chan_sip.c: <--- Reliably Transmitting (NAT) to sip.trunk.sbc.ip:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKw3n8rzzmuvp29t98zr8t7prvm;Role=3;Hpt=8fa2_36;received=sip.trunk.sbc.ip;rport=5060 From: ;tag=lv55tuju To: ;tag=as4b3d6d7e Call-ID: asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in CSeq: 2 INVITE Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Type: application/sdp Require: timer Content-Length: 240 v=0 o=root 314668089 314668090 IN IP4 our.server.ext.ip s=Asterisk PBX 13.36.0 c=IN IP4 our.server.ext.ip t=0 0 m=audio 14348 RTP/AVP 0 116 a=rtpmap:0 PCMU/8000 a=rtpmap:116 telephone-event/8000 a=fmtp:116 0-16 a=maxptime:150 a=sendrecv <------------> [2020-11-30 23:53:16] VERBOSE[3050] chan_sip.c: Retransmitting #1 (NAT) to sip.trunk.sbc.ip:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKw3n8rzzmuvp29t98zr8t7prvm;Role=3;Hpt=8fa2_36;received=sip.trunk.sbc.ip;rport=5060 From: ;tag=lv55tuju To: ;tag=as4b3d6d7e Call-ID: asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in CSeq: 2 INVITE Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Type: application/sdp Require: timer Content-Length: 240 v=0 o=root 314668089 314668090 IN IP4 our.server.ext.ip s=Asterisk PBX 13.36.0 c=IN IP4 our.server.ext.ip t=0 0 m=audio 14348 RTP/AVP 0 116 a=rtpmap:0 PCMU/8000 a=rtpmap:116 telephone-event/8000 a=fmtp:116 0-16 a=maxptime:150 a=sendrecv --- [2020-11-30 23:53:16] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:sip.trunk.sbc.ip:5060 ---> ACK sip:+914xxxxxx101@our.server.ext.ip:5060 SIP/2.0 Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKvtw9rt89uroz7hr8mrpmh99v3;Role=3;Hpt=8fa2_36 Call-ID: asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in From: "094xxxxxxxx94";tag=lv55tuju To: ;tag=as4b3d6d7e CSeq: 2 ACK Max-Forwards: 65 P-Charging-Vector: icid-value=AE888F23F0256F20201130235310;orig-ioi=sip.trunk.136.242;term-ioi=Abasoft_4844514111 Content-Length: 0 <-------------> [2020-11-30 23:53:16] VERBOSE[3050] chan_sip.c: --- (9 headers 0 lines) --- [2020-11-30 23:53:17] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:sip.trunk.sbc.ip:5060 ---> NOTIFY tel:+914xxxxxx101 SIP/2.0 Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKtv89m7hn23w993o9rt23r7zor;Role=3;Hpt=8fa2_36;X-HwDim=4 Call-ID: asbcmtytuljxjtvtz2j3tu0tt0jj20l5xxvl@sip.trunk.136.227 From: ;tag=z4m1431u To: CSeq: 2 NOTIFY Contact: Max-Forwards: 66 Supported: 100rel Event: ua-profile Subscription-State: active P-Asserted-Identity: P-Charging-Vector: icid-value=ATS9900-202011302353100779773;term-ioi=Abasoft_4844514111 Content-Length: 158 Content-Type: application/simservs+xml standard-dial-tone <-------------> [2020-11-30 23:53:17] VERBOSE[3050] chan_sip.c: --- (15 headers 6 lines) --- [2020-11-30 23:53:17] VERBOSE[3050] chan_sip.c: <--- Transmitting (NAT) to sip.trunk.sbc.ip:5060 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKtv89m7hn23w993o9rt23r7zor;Role=3;Hpt=8fa2_36;X-HwDim=4;received=sip.trunk.sbc.ip;rport=5060 From: ;tag=z4m1431u To: ;tag=as32b67041 Call-ID: asbcmtytuljxjtvtz2j3tu0tt0jj20l5xxvl@sip.trunk.136.227 CSeq: 2 NOTIFY Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 <------------> [2020-11-30 23:53:17] VERBOSE[3050] chan_sip.c: Scheduling destruction of SIP dialog 'asbcmtytuljxjtvtz2j3tu0tt0jj20l5xxvl@sip.trunk.136.227' in 32000 ms (Method: NOTIFY) [2020-11-30 23:53:20] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:sip.trunk.sbc.ip:5060 ---> BYE sip:+914xxxxxx101@our.server.ext.ip:5060 SIP/2.0 Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKhto3ww7zv9pnz2r299zt3povn;Role=3;Hpt=8fa2_36 Call-ID: asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in From: "094xxxxxxxx94";tag=lv55tuju To: ;tag=as4b3d6d7e CSeq: 3 BYE Max-Forwards: 65 Reason: Q.850;cause=16;text="Normal call clearing" P-Charging-Vector: icid-value=AE888F23F0256F20201130235310;orig-ioi=sip.trunk.136.242;term-ioi=Abasoft_4844514111 Content-Length: 0 <-------------> [2020-11-30 23:53:20] VERBOSE[3050] chan_sip.c: --- (10 headers 0 lines) --- [2020-11-30 23:53:20] VERBOSE[3050][C-00000dec] chan_sip.c: Sending to sip.trunk.sbc.ip:5060 (NAT) [2020-11-30 23:53:20] VERBOSE[3050][C-00000dec] chan_sip.c: Scheduling destruction of SIP dialog 'asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in' in 6400 ms (Method: BYE) [2020-11-30 23:53:20] VERBOSE[3050][C-00000dec] chan_sip.c: <--- Transmitting (NAT) to sip.trunk.sbc.ip:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP sip.trunk.sbc.ip:5060;branch=z9hG4bKhto3ww7zv9pnz2r299zt3povn;Role=3;Hpt=8fa2_36;received=sip.trunk.sbc.ip;rport=5060 From: "094xxxxxxxx94";tag=lv55tuju To: ;tag=as4b3d6d7e Call-ID: asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in CSeq: 3 BYE Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 <------------> [2020-11-30 23:53:20] VERBOSE[7694][C-00000dec] bridge_channel.c: Channel SIP/airtel-60-00001403 left 'simple_bridge' basic-bridge [2020-11-30 23:53:20] VERBOSE[7715][C-00000dec] bridge_channel.c: Channel SIP/101-00001404 left 'simple_bridge' basic-bridge [2020-11-30 23:53:20] VERBOSE[7694][C-00000dec] app_macro.c: Spawn extension (macro-dial-one, s, 55) exited non-zero on 'SIP/airtel-60-00001403' in macro 'dial-one' [2020-11-30 23:53:20] VERBOSE[7715][C-00000dec] app_stack.c: SIP/101-00001404 Internal Gosub(crm-hangup,s,1) start [2020-11-30 23:53:20] VERBOSE[7715][C-00000dec] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/101-00001404", "Sending Hangup to CRM") in new stack [2020-11-30 23:53:20] VERBOSE[7694][C-00000dec] app_macro.c: Spawn extension (macro-exten-vm, s, 26) exited non-zero on 'SIP/airtel-60-00001403' in macro 'exten-vm' [2020-11-30 23:53:20] VERBOSE[7715][C-00000dec] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/101-00001404", "HANGUP CAUSE: 16") in new stack [2020-11-30 23:53:20] VERBOSE[7694][C-00000dec] pbx.c: Spawn extension (ext-local, 101, 3) exited non-zero on 'SIP/airtel-60-00001403' [2020-11-30 23:53:20] VERBOSE[7715][C-00000dec] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/101-00001404", "0?Set(__CRM_VOICEMAIL=)") in new stack [2020-11-30 23:53:20] VERBOSE[7694][C-00000dec] pbx.c: Executing [h@ext-local:1] Macro("SIP/airtel-60-00001403", "hangupcall,") in new stack [2020-11-30 23:53:20] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/airtel-60-00001403", "1?theend") in new stack [2020-11-30 23:53:20] VERBOSE[7694][C-00000dec] pbx_builtins.c: Goto (macro-hangupcall,s,3) [2020-11-30 23:53:20] VERBOSE[7715][C-00000dec] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/101-00001404", "MASTER CHANNEL: 1606760591.5124 = 1606760590.5123") in new stack [2020-11-30 23:53:20] VERBOSE[7715][C-00000dec] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/101-00001404", "1?return") in new stack [2020-11-30 23:53:20] VERBOSE[7715][C-00000dec] pbx_builtins.c: Goto (crm-hangup,s,8) [2020-11-30 23:53:20] VERBOSE[7715][C-00000dec] pbx.c: Executing [s@crm-hangup:8] Return("SIP/101-00001404", "") in new stack [2020-11-30 23:53:20] VERBOSE[7715][C-00000dec] app_stack.c: Spawn extension (from-internal, , 1) exited non-zero on 'SIP/101-00001404' [2020-11-30 23:53:20] VERBOSE[7715][C-00000dec] app_stack.c: SIP/101-00001404 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL= [2020-11-30 23:53:20] VERBOSE[7715][C-00000dec] chan_sip.c: Scheduling destruction of SIP dialog '200f473e37c6eea21bba44af12c5e1ed@192.168.3.229:5060' in 6400 ms (Method: INVITE) [2020-11-30 23:53:20] VERBOSE[7715][C-00000dec] chan_sip.c: set_destination: Parsing for address/port to send to [2020-11-30 23:53:20] VERBOSE[7715][C-00000dec] chan_sip.c: set_destination: set destination to 172.16.10.2:63226 [2020-11-30 23:53:20] VERBOSE[7715][C-00000dec] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.10.2:63226: BYE sip:101@172.16.10.2:63226;rinstance=467b3688927cc123 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK448fdcaf Max-Forwards: 70 From: "94xxxxxxxx94" ;tag=as42290cf7 To: ;tag=8f472451 Call-ID: 200f473e37c6eea21bba44af12c5e1ed@192.168.3.229:5060 CSeq: 103 BYE User-Agent: FPBX-14.0.13.40(13.36.0) X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 Content-Length: 0 --- [2020-11-30 23:53:20] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/airtel-60-00001403", "0?Set(CDR(recordingfile)=)") in new stack [2020-11-30 23:53:20] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/airtel-60-00001403", "SIP/101-00001404 montior file= /var/www/html/inRecs_asRecs/2020/11/30/in-+914xxxxxx101-94xxxxxxxx94-20201130-235310-1606760590.5123.wav") in new stack [2020-11-30 23:53:20] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/airtel-60-00001403", "0?skipagi") in new stack [2020-11-30 23:53:20] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-hangupcall:6] AGI("SIP/airtel-60-00001403", "attendedtransfer-rec-restart.php,SIP/101-00001404,/var/www/html/inRecs_asRecs/2020/11/30/in-+914xxxxxx101-94xxxxxxxx94-20201130-235310-1606760590.5123.wav") in new stack [2020-11-30 23:53:20] VERBOSE[7694][C-00000dec] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] res_agi.c: AGI Script attendedtransfer-rec-restart.php completed, returning 0 [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/airtel-60-00001403", "") in new stack [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/airtel-60-00001403' in macro 'hangupcall' [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/airtel-60-00001403' [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] app_stack.c: SIP/airtel-60-00001403 Internal Gosub(crm-hangup,s,1) start [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/airtel-60-00001403", "Sending Hangup to CRM") in new stack [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/airtel-60-00001403", "HANGUP CAUSE: 16") in new stack [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/airtel-60-00001403", "0?Set(__CRM_VOICEMAIL=)") in new stack [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/airtel-60-00001403", "MASTER CHANNEL: 1606760590.5123 = 1606760590.5123") in new stack [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/airtel-60-00001403", "0?return") in new stack [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@crm-hangup:6] Set("SIP/airtel-60-00001403", "__CRM_HANGUP=1") in new stack [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/airtel-60-00001403", "sangomacrm.agi") in new stack [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi [2020-11-30 23:53:21] VERBOSE[3050] chan_sip.c: Retransmitting #1 (no NAT) to 172.16.10.2:63226: BYE sip:101@172.16.10.2:63226;rinstance=467b3688927cc123 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK448fdcaf Max-Forwards: 70 From: "94xxxxxxxx94" ;tag=as42290cf7 To: ;tag=8f472451 Call-ID: 200f473e37c6eea21bba44af12c5e1ed@192.168.3.229:5060 CSeq: 103 BYE User-Agent: FPBX-14.0.13.40(13.36.0) X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 Content-Length: 0 --- [2020-11-30 23:53:21] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:63226 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK448fdcaf Contact: To: ;tag=8f472451 From: "94xxxxxxxx94";tag=as42290cf7 Call-ID: 200f473e37c6eea21bba44af12c5e1ed@192.168.3.229:5060 CSeq: 103 BYE User-Agent: X-Lite release 1011s stamp 41150 Content-Length: 0 <-------------> [2020-11-30 23:53:21] VERBOSE[3050] chan_sip.c: --- (9 headers 0 lines) --- [2020-11-30 23:53:21] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:63226 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.3.229:5060;branch=z9hG4bK448fdcaf Contact: To: ;tag=8f472451 From: "94xxxxxxxx94";tag=as42290cf7 Call-ID: 200f473e37c6eea21bba44af12c5e1ed@192.168.3.229:5060 CSeq: 103 BYE User-Agent: X-Lite release 1011s stamp 41150 Content-Length: 0 <-------------> [2020-11-30 23:53:21] VERBOSE[3050] chan_sip.c: --- (9 headers 0 lines) --- [2020-11-30 23:53:21] VERBOSE[3050] chan_sip.c: Really destroying SIP dialog '200f473e37c6eea21bba44af12c5e1ed@192.168.3.229:5060' Method: INVITE [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] res_agi.c: AGI Script sangomacrm.agi completed, returning 0 [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] pbx.c: Executing [s@crm-hangup:8] Return("SIP/airtel-60-00001403", "") in new stack [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] app_stack.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/airtel-60-00001403' [2020-11-30 23:53:21] VERBOSE[7694][C-00000dec] app_stack.c: SIP/airtel-60-00001403 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL= [2020-11-30 23:53:21] VERBOSE[7695][C-00000dec] app_mixmonitor.c: MixMonitor close filestream (mixed) [2020-11-30 23:53:21] VERBOSE[7695][C-00000dec] app_mixmonitor.c: End MixMonitor Recording SIP/airtel-60-00001403 [2020-11-30 23:53:27] VERBOSE[3050] chan_sip.c: Really destroying SIP dialog 'asbc3742i2a4ioman3wwr12na437atna1o75@B.5.151.ims.airtel.in' Method: BYE [2020-11-30 23:53:31] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:172.16.10.2:63226 ---> <-------------> [2020-11-30 23:53:33] VERBOSE[3050] chan_sip.c: Really destroying SIP dialog 'ZTgxNTk3ZWExNmNlYjM4ZDQzOTczNzk0ODkyZjdjZTI.' Method: REGISTER [2020-11-30 23:53:33] VERBOSE[3050] chan_sip.c: Really destroying SIP dialog 'YjZkZWIxODY1YWRiYTc1ZjY2NTg2YzBlMzQwNjk5YjM.' Method: REGISTER [2020-11-30 23:53:35] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:192.168.3.106:60000 ---> SUBSCRIBE sip:MAC001fc11b8635@224.0.1.75 SIP/2.0 Via: SIP/2.0/UDP 192.168.3.106:60000;branch=z9hG4bK1820698314 From: ;tag=1969758766 To: Call-ID: 1614498688@192.168.3.106 CSeq: 20 SUBSCRIBE Contact: Max-Forwards: 70 User-Agent: UC802T 2.0.3.98 Expires: 0 Event: ua-profile;profile-type="device";vendor="Htek";model="UC802T";version="2.0.3.98" Accept: application/url Content-Length: 0 <-------------> [2020-11-30 23:53:35] VERBOSE[3050] chan_sip.c: --- (13 headers 0 lines) --- [2020-11-30 23:53:35] VERBOSE[3050] chan_sip.c: Sending to 192.168.3.106:60000 (NAT) [2020-11-30 23:53:35] VERBOSE[3050] chan_sip.c: Creating new subscription [2020-11-30 23:53:35] VERBOSE[3050] chan_sip.c: Sending to 192.168.3.106:60000 (NAT) [2020-11-30 23:53:35] VERBOSE[3050] sip/route.c: sip_route_dump: route/path hop: [2020-11-30 23:53:35] VERBOSE[3050] chan_sip.c: No matching peer for 'MAC001fc11b8635' from '192.168.3.106:60000' [2020-11-30 23:53:35] VERBOSE[3050] chan_sip.c: <--- Transmitting (NAT) to 192.168.3.106:60000 ---> SIP/2.0 489 Bad Event Via: SIP/2.0/UDP 192.168.3.106:60000;branch=z9hG4bK1820698314;received=192.168.3.106;rport=60000 From: ;tag=1969758766 To: ;tag=as66af2b44 Call-ID: 1614498688@192.168.3.106 CSeq: 20 SUBSCRIBE Server: FPBX-14.0.13.40(13.36.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 <------------> [2020-11-30 23:53:35] VERBOSE[3050] chan_sip.c: Really destroying SIP dialog '1614498688@192.168.3.106' Method: SUBSCRIBE [2020-11-30 23:53:40] VERBOSE[3050] chan_sip.c: Reliably Transmitting (NAT) to sip.trunk.sbc.ip:5060: OPTIONS sip:ims.airtel.in SIP/2.0 Via: SIP/2.0/UDP our.server.ext.ip:5060;branch=z9hG4bK3211362a;rport Max-Forwards: 70 From: "Unknown" ;tag=as519fa6ba To: Contact: Call-ID: 4d43b39e0cd631f570965b303ad9823e@our.server.ext.ip:5060 CSeq: 102 OPTIONS User-Agent: FPBX-14.0.13.40(13.36.0) Date: Mon, 30 Nov 2020 18:23:40 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 --- [2020-11-30 23:53:40] VERBOSE[3050] chan_sip.c: <--- SIP read from UDP:sip.trunk.sbc.ip:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP our.server.ext.ip:5060;branch=z9hG4bK3211362a;rport=5060 Call-ID: 4d43b39e0cd631f570965b303ad9823e@our.server.ext.ip:5060 From: "Unknown";tag=as519fa6ba To: ;tag=rmowovop CSeq: 102 OPTIONS Allow: OPTIONS,NOTIFY,SUBSCRIBE,INFO,REGISTER,MESSAGE,REFER,UPDATE,PRACK,BYE,CANCEL,ACK,INVITE Supported: privacy,precondition,100rel Content-Length: 0 <-------------> [2020-11-30 23:53:40] VERBOSE[3050] chan_sip.c: --- (9 headers 0 lines) --- [2020-11-30 23:53:40] VERBOSE[3050] chan_sip.c: Really destroying SIP dialog '4d43b39e0cd631f570965b303ad9823e@our.server.ext.ip:5060' Method: OPTIONS