Untitled

From Stark, 5 Months ago, written in Plain Text, viewed 3 times.
URL https://pastebin.freepbx.org/view/a41268c8 Embed
Download Paste or View Raw
  1. 31155   [2022-06-07 10:24:21] VERBOSE[2562] chan_sip.c:
  2. 31156   <--- SIP read from UDP:192.168.1.199:5160 ---> 
  3. 31157   INVITE sip:01217771234@192.168.1.200:5160 SIP/2.0      
  4. 31158   Via: SIP/2.0/UDP 192.168.1.199:5160;branch=z9hG4bK-fcc5cd6b    
  5. 31159   From: BT-POTS <sip:BT-POTS@192.168.1.200:5160>;tag=3837fbd3a5b9792bo1  
  6. 31160   To: <sip:01217771234@192.168.1.200:5160>       
  7. 31161   Remote-Party-ID: BT-POTS <sip:BT-POTS@192.168.1.200:5160>;screen=yes;party=calling     
  8. 31162   Call-ID: c2ef0ef3-7428808b@192.168.1.199       
  9. 31163   CSeq: 101 INVITE       
  10. 31164   Max-Forwards: 70       
  11. 31165   Contact: BT-POTS <sip:BT-POTS@192.168.1.199:5160>      
  12. 31166   Expires: 240   
  13. 31167   User-Agent: Linksys/SPA3102-3.2.6(GWa) 
  14. 31168   Content-Length: 446    
  15. 31169   Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER  
  16. 31170   Supported: x-sipura    
  17. 31171   Content-Type: application/sdp  
  18. 31172          
  19. 31173   v=0    
  20. 31174   o=- 9816163 9816163 IN IP4 192.168.1.199       
  21. 31175   s=-    
  22. 31176   c=IN IP4 192.168.1.199 
  23. 31177   t=0 0  
  24. 31178   m=audio 16464 RTP/AVP 0 2 4 8 18 96 97 98 100 101      
  25. 31179   a=rtpmap:0 PCMU/8000   
  26. 31180   a=rtpmap:2 G726-32/8000
  27. 31181   a=rtpmap:4 G723/8000   
  28. 31182   a=rtpmap:8 PCMA/8000   
  29. 31183   a=rtpmap:18 G729a/8000 
  30. 31184   a=rtpmap:96 G726-40/8000       
  31. 31185   a=rtpmap:97 G726-24/8000       
  32. 31186   a=rtpmap:98 G726-16/8000       
  33. 31187   a=rtpmap:100 NSE/8000  
  34. 31188   a=fmtp:100 192-193     
  35. 31189   a=rtpmap:101 telephone-event/8000      
  36. 31190   a=fmtp:101 0-15
  37. 31191   a=ptime:30     
  38. 31192   a=sendrecv     
  39. 31193   <------------->
  40. 31194   [2022-06-07 10:24:21] VERBOSE[2562] chan_sip.c: --- (15 headers 20 lines) ---  
  41. 31195   [2022-06-07 10:24:21] VERBOSE[2562] chan_sip.c: Sending to 192.168.1.199:5160 (NAT)    
  42. 31196   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Sending to 192.168.1.199:5160 (NAT)
  43. 31197   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Using INVITE request as basis request - c2ef0ef3-7428808b@192.168.1.199    
  44. 31198   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found peer 'BT-POTS' for 'BT-POTS' from 192.168.1.199:5160 
  45. 31199   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c:    
  46. 31200   <--- Reliably Transmitting (NAT) to 192.168.1.199:5160 --->    
  47. 31201   SIP/2.0 401 Unauthorized       
  48. 31202   Via: SIP/2.0/UDP 192.168.1.199:5160;branch=z9hG4bK-fcc5cd6b;received=192.168.1.199;rport=5160  
  49. 31203   From: BT-POTS <sip:BT-POTS@192.168.1.200:5160>;tag=3837fbd3a5b9792bo1  
  50. 31204   To: <sip:01217771234@192.168.1.200:5160>;tag=as6736f1fc
  51. 31205   Call-ID: c2ef0ef3-7428808b@192.168.1.199       
  52. 31206   CSeq: 101 INVITE       
  53. 31207   Server: FPBX-16.0.19(16.25.0)  
  54. 31208   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE     
  55. 31209   Supported: replaces, timer     
  56. 31210   WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="7bb482d9"     
  57. 31211   Content-Length: 0      
  58. 31212          
  59. 31213          
  60. 31214   <------------> 
  61. 31215   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Scheduling destruction of SIP dialog 'c2ef0ef3-7428808b@192.168.1.199' in 6400 ms (Method: INVITE) 
  62. 31216   [2022-06-07 10:24:21] VERBOSE[2562] chan_sip.c:
  63. 31217   <--- SIP read from UDP:192.168.1.199:5160 ---> 
  64. 31218   ACK sip:01217771234@192.168.1.200:5160 SIP/2.0 
  65. 31219   Via: SIP/2.0/UDP 192.168.1.199:5160;branch=z9hG4bK-fcc5cd6b    
  66. 31220   From: BT-POTS <sip:BT-POTS@192.168.1.200:5160>;tag=3837fbd3a5b9792bo1  
  67. 31221   To: <sip:01217771234@192.168.1.200:5160>;tag=as6736f1fc
  68. 31222   Call-ID: c2ef0ef3-7428808b@192.168.1.199       
  69. 31223   CSeq: 101 ACK  
  70. 31224   Max-Forwards: 70       
  71. 31225   Contact: BT-POTS <sip:BT-POTS@192.168.1.199:5160>      
  72. 31226   User-Agent: Linksys/SPA3102-3.2.6(GWa) 
  73. 31227   Content-Length: 0      
  74. 31228          
  75. 31229   <------------->
  76. 31230   [2022-06-07 10:24:21] VERBOSE[2562] chan_sip.c: --- (10 headers 0 lines) ---   
  77. 31231   [2022-06-07 10:24:21] VERBOSE[2562] chan_sip.c:
  78. 31232   <--- SIP read from UDP:192.168.1.199:5160 ---> 
  79. 31233   INVITE sip:01217771234@192.168.1.200:5160 SIP/2.0      
  80. 31234   Via: SIP/2.0/UDP 192.168.1.199:5160;branch=z9hG4bK-a43ad0ff    
  81. 31235   From: BT-POTS <sip:BT-POTS@192.168.1.200:5160>;tag=3837fbd3a5b9792bo1  
  82. 31236   To: <sip:01217771234@192.168.1.200:5160>       
  83. 31237   Remote-Party-ID: BT-POTS <sip:BT-POTS@192.168.1.200:5160>;screen=yes;party=calling     
  84. 31238   Call-ID: c2ef0ef3-7428808b@192.168.1.199       
  85. 31239   CSeq: 102 INVITE       
  86. 31240   Max-Forwards: 70       
  87. 31241   Authorization: Digest username="BT-POTS",realm="asterisk",nonce="7bb482d9",uri="sip:01217771234@192.168.1.200:5160",algorithm=MD5,response="2decc3dcc9a3546776266d7480994480"  
  88. 31242   Contact: BT-POTS <sip:BT-POTS@192.168.1.199:5160>      
  89. 31243   Expires: 240   
  90. 31244   User-Agent: Linksys/SPA3102-3.2.6(GWa) 
  91. 31245   Content-Length: 446    
  92. 31246   Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER  
  93. 31247   Supported: x-sipura    
  94. 31248   Content-Type: application/sdp  
  95. 31249          
  96. 31250   v=0    
  97. 31251   o=- 9816163 9816163 IN IP4 192.168.1.199       
  98. 31252   s=-    
  99. 31253   c=IN IP4 192.168.1.199 
  100. 31254   t=0 0  
  101. 31255   m=audio 16464 RTP/AVP 0 2 4 8 18 96 97 98 100 101      
  102. 31256   a=rtpmap:0 PCMU/8000   
  103. 31257   a=rtpmap:2 G726-32/8000
  104. 31258   a=rtpmap:4 G723/8000   
  105. 31259   a=rtpmap:8 PCMA/8000   
  106. 31260   a=rtpmap:18 G729a/8000 
  107. 31261   a=rtpmap:96 G726-40/8000       
  108. 31262   a=rtpmap:97 G726-24/8000       
  109. 31263   a=rtpmap:98 G726-16/8000       
  110. 31264   a=rtpmap:100 NSE/8000  
  111. 31265   a=fmtp:100 192-193     
  112. 31266   a=rtpmap:101 telephone-event/8000      
  113. 31267   a=fmtp:101 0-15
  114. 31268   a=ptime:30     
  115. 31269   a=sendrecv     
  116. 31270   <------------->
  117. 31271   [2022-06-07 10:24:21] VERBOSE[2562] chan_sip.c: --- (16 headers 20 lines) ---  
  118. 31272   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Sending to 192.168.1.199:5160 (NAT)
  119. 31273   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Using INVITE request as basis request - c2ef0ef3-7428808b@192.168.1.199    
  120. 31274   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found peer 'BT-POTS' for 'BT-POTS' from 192.168.1.199:5160 
  121. 31275   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] netsock2.c: Using SIP RTP TOS bits 184 
  122. 31276   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] netsock2.c: Using SIP RTP CoS mark 5   
  123. 31277   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Got SDP version 9816163 and unique parts [- 9816163 IN IP4 192.168.1.199]  
  124. 31278   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found RTP audio format 0   
  125. 31279   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found RTP audio format 2   
  126. 31280   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found RTP audio format 4   
  127. 31281   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found RTP audio format 8   
  128. 31282   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found RTP audio format 18  
  129. 31283   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found RTP audio format 96  
  130. 31284   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found RTP audio format 97  
  131. 31285   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found RTP audio format 98  
  132. 31286   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found RTP audio format 100 
  133. 31287   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found RTP audio format 101 
  134. 31288   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found audio description format PCMU for ID 0       
  135. 31289   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found audio description format G726-32 for ID 2    
  136. 31290   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found audio description format G723 for ID 4       
  137. 31291   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found audio description format PCMA for ID 8       
  138. 31292   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found audio description format G729a for ID 18     
  139. 31293   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found unknown media description format G726-40 for ID 96   
  140. 31294   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found unknown media description format G726-24 for ID 97   
  141. 31295   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found unknown media description format G726-16 for ID 98   
  142. 31296   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found unknown media description format NSE for ID 100      
  143. 31297   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Found audio description format telephone-event for ID 101  
  144. 31298   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Capabilities: us - (ulaw|alaw|gsm|g726|g722), peer - audio=(ulaw|g726|g723|alaw|g729)/video=(nothing)/text=(nothing), combined - (ulaw|alaw|g726)  
  145. 31299   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)       
  146. 31300   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Peer audio RTP is at port 192.168.1.199:16464      
  147. 31301   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c: Looking for 01217771234 in from-trunk (domain 192.168.1.200)       
  148. 31302   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] sip/route.c: sip_route_dump: route/path hop: <sip:BT-POTS@192.168.1.199:5160>  
  149. 31303   [2022-06-07 10:24:21] VERBOSE[2562][C-0000001e] chan_sip.c:    
  150. 31304   <--- Transmitting (NAT) to 192.168.1.199:5160 --->     
  151. 31305   SIP/2.0 100 Trying     
  152. 31306   Via: SIP/2.0/UDP 192.168.1.199:5160;branch=z9hG4bK-a43ad0ff;received=192.168.1.199;rport=5160  
  153. 31307   From: BT-POTS <sip:BT-POTS@192.168.1.200:5160>;tag=3837fbd3a5b9792bo1  
  154. 31308   To: <sip:01217771234@192.168.1.200:5160>       
  155. 31309   Call-ID: c2ef0ef3-7428808b@192.168.1.199       
  156. 31310   CSeq: 102 INVITE       
  157. 31311   Server: FPBX-16.0.19(16.25.0)  
  158. 31312   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE     
  159. 31313   Supported: replaces, timer     
  160. 31314   Contact: <sip:01217771234@192.168.1.200:5160>  
  161. 31315   Content-Length: 0      
  162. 31316          
  163. 31317          
  164. 31318   <------------> 
  165. 31319   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [01217771234@from-trunk:1] NoOp("SIP/BT-POTS-00000018", "Catch-All DID Match - Found 01217771234 - You probably want a DID for this.") in new stack  
  166. 31320   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [01217771234@from-trunk:2] Set("SIP/BT-POTS-00000018", "__FROM_DID=01217771234") in new stack
  167. 31321   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [01217771234@from-trunk:3] Goto("SIP/BT-POTS-00000018", "ext-did,s,1") in new stack  
  168. 31322   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (ext-did,s,1)    
  169. 31323   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:1] Set("SIP/BT-POTS-00000018", "__DIRECTION=INBOUND") in new stack
  170. 31324   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:2] Gosub("SIP/BT-POTS-00000018", "sub-record-check,s,1(in,s,dontcare)") in new stack      
  171. 31325   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/BT-POTS-00000018", "0?initialized") in new stack  
  172. 31326   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:2] Set("SIP/BT-POTS-00000018", "__REC_STATUS=INITIALIZED") in new stack  
  173. 31327   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:3] Set("SIP/BT-POTS-00000018", "NOW=1654593861") in new stack    
  174. 31328   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:4] Set("SIP/BT-POTS-00000018", "__DAY=07") in new stack  
  175. 31329   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:5] Set("SIP/BT-POTS-00000018", "__MONTH=06") in new stack
  176. 31330   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:6] Set("SIP/BT-POTS-00000018", "__YEAR=2022") in new stack       
  177. 31331   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:7] Set("SIP/BT-POTS-00000018", "__TIMESTR=20220607-102421") in new stack 
  178. 31332   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:8] Set("SIP/BT-POTS-00000018", "__FROMEXTEN=unknown") in new stack       
  179. 31333   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:9] Set("SIP/BT-POTS-00000018", "__MON_FMT=wav") in new stack     
  180. 31334   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/BT-POTS-00000018", "Recordings initialized") in new stack  
  181. 31335   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/BT-POTS-00000018", "0?Set(ARG3=dontcare)") in new stack  
  182. 31336   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:12] Set("SIP/BT-POTS-00000018", "REC_POLICY_MODE_SAVE=") in new stack    
  183. 31337   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/BT-POTS-00000018", "0?Set(REC_STATUS=NO)") in new stack  
  184. 31338   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/BT-POTS-00000018", "2?checkaction") in new stack 
  185. 31339   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (sub-record-check,s,17)  
  186. 31340   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/BT-POTS-00000018", "1?sub-record-check,in,1") in new stack       
  187. 31341   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (sub-record-check,in,1)  
  188. 31342   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/BT-POTS-00000018", "Inbound Recording Check to s") in new stack    
  189. 31343   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [in@sub-record-check:2] Set("SIP/BT-POTS-00000018", "FROMEXTEN=unknown") in new stack
  190. 31344   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/BT-POTS-00000018", "7?Set(FROMEXTEN=BT-POTS)") in new stack      
  191. 31345   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/BT-POTS-00000018", "recordcheck,1(dontcare,in,s)") in new stack   
  192. 31346   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/BT-POTS-00000018", "Starting recording check against dontcare") in new stack      
  193. 31347   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/BT-POTS-00000018", "dontcare") in new stack       
  194. 31348   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (sub-record-check,recordcheck,3) 
  195. 31349   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/BT-POTS-00000018", "") in new stack     
  196. 31350   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [in@sub-record-check:5] Return("SIP/BT-POTS-00000018", "") in new stack      
  197. 31351   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:3] Set("SIP/BT-POTS-00000018", "CHANNEL(tonezone)=us") in new stack       
  198. 31352   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:4] ExecIf("SIP/BT-POTS-00000018", "0?Set(__FROM_DID=s)") in new stack     
  199. 31353   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:5] Set("SIP/BT-POTS-00000018", "returnhere=1") in new stack       
  200. 31354   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:6] Gosub("SIP/BT-POTS-00000018", "app-blacklist-check,s,1()") in new stack
  201. 31355   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/BT-POTS-00000018", "0?blacklisted") in new stack       
  202. 31356   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/BT-POTS-00000018", "CALLED_BLACKLIST=1") in new stack     
  203. 31357   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/BT-POTS-00000018", "") in new stack    
  204. 31358   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:7] Set("SIP/BT-POTS-00000018", "CDR(did)=01217771234") in new stack       
  205. 31359   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:8] GotoIf("SIP/BT-POTS-00000018", "0?") in new stack      
  206. 31360   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:9] ExecIf("SIP/BT-POTS-00000018", "0 ?Set(CALLERID(name)=BT-POTS)") in new stack  
  207. 31361   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:10] Set("SIP/BT-POTS-00000018", "__MOHCLASS=") in new stack       
  208. 31362   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:11] Set("SIP/BT-POTS-00000018", "__REVERSAL_REJECT=FALSE") in new stack   
  209. 31363   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:12] GotoIf("SIP/BT-POTS-00000018", "1?post-reverse-charge") in new stack  
  210. 31364   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (ext-did,s,14)   
  211. 31365   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:14] NoOp("SIP/BT-POTS-00000018", "") in new stack 
  212. 31366   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:15] Set("SIP/BT-POTS-00000018", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack 
  213. 31367   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:16] Set("SIP/BT-POTS-00000018", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack  
  214. 31368   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:17] Set("SIP/BT-POTS-00000018", "CALLERID(name-pres)=allowed_not_screened") in new stack  
  215. 31369   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:18] Set("SIP/BT-POTS-00000018", "CALLERID(num-pres)=allowed_not_screened") in new stack   
  216. 31370   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:19] NoOp("SIP/BT-POTS-00000018", "CallerID Entry Point") in new stack     
  217. 31371   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:20] Set("SIP/BT-POTS-00000018", "__CRM_DIRECTION=INBOUND") in new stack   
  218. 31372   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:21] Set("SIP/BT-POTS-00000018", "__CRM_SOURCE=BT-POTS") in new stack      
  219. 31373   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:22] Set("SIP/BT-POTS-00000018", "__CRM_LINKEDID=1654593861.42") in new stack      
  220. 31374   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:23] AGI("SIP/BT-POTS-00000018", "agi://127.0.0.1/sangomacrm.agi,true") in new stack       
  221. 31375   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] res_agi.c: <SIP/BT-POTS-00000018>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0     
  222. 31376   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:24] ExecIf("SIP/BT-POTS-00000018", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack     
  223. 31377   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@ext-did:25] Goto("SIP/BT-POTS-00000018", "from-did-direct,3,1") in new stack      
  224. 31378   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (from-did-direct,3,1)    
  225. 31379   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [3@from-did-direct:1] GotoIf("SIP/BT-POTS-00000018", "0?ext-local,*3,1") in new stack
  226. 31380   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [3@from-did-direct:2] GotoIf("SIP/BT-POTS-00000018", "1?ext-local,3,1:followme-check,3,1") in new stack      
  227. 31381   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (ext-local,3,1)  
  228. 31382   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [3@ext-local:1] Set("SIP/BT-POTS-00000018", "__RINGTIMER=15") in new stack   
  229. 31383   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [3@ext-local:2] ExecIf("SIP/BT-POTS-00000018", "0?Set(__CWIGNORE=)") in new stack    
  230. 31384   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [3@ext-local:3] Macro("SIP/BT-POTS-00000018", "exten-vm,novm,3,0,0,0") in new stack  
  231. 31385   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:1] Macro("SIP/BT-POTS-00000018", "user-callerid,") in new stack    
  232. 31386   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/BT-POTS-00000018", "TOUCH_MONITOR=1654593861.42") in new stack    
  233. 31387   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/BT-POTS-00000018", "CHANCONTEXT=") in new stack   
  234. 31388   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/BT-POTS-00000018", "CHANCONTEXT=") in new stack   
  235. 31389   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/BT-POTS-00000018", "CHANEXTENCONTEXT=BT-POTS-00000018") in new stack      
  236. 31390   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/BT-POTS-00000018", "CHANEXTEN=BT-POTS-00000018") in new stack     
  237. 31391   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:6] Set("SIP/BT-POTS-00000018", "CALLERID(number)=BT-POTS") in new stack       
  238. 31392   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/BT-POTS-00000018", "AMPUSER=BT-POTS") in new stack
  239. 31393   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:8] Set("SIP/BT-POTS-00000018", "HOTDESCKCHAN=BT-POTS-00000018") in new stack  
  240. 31394   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:9] Set("SIP/BT-POTS-00000018", "HOTDESKEXTEN=BT") in new stack
  241. 31395   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/BT-POTS-00000018", "HOTDESKCALL=0") in new stack 
  242. 31396   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("SIP/BT-POTS-00000018", "0?Set(HOTDESKCALL=1)") in new stack       
  243. 31397   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("SIP/BT-POTS-00000018", "0?Set(CALLERID(name)=)") in new stack     
  244. 31398   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("SIP/BT-POTS-00000018", "0?report") in new stack   
  245. 31399   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("SIP/BT-POTS-00000018", "1?Set(REALCALLERIDNUM=BT-POTS)") in new stack     
  246. 31400   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/BT-POTS-00000018", "AMPUSER=") in new stack      
  247. 31401   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("SIP/BT-POTS-00000018", "0?limit") in new stack    
  248. 31402   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/BT-POTS-00000018", "AMPUSERCIDNAME=") in new stack       
  249. 31403   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("SIP/BT-POTS-00000018", "0?Set(__CIDMASQUERADING=TRUE)") in new stack      
  250. 31404   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("SIP/BT-POTS-00000018", "1?report") in new stack   
  251. 31405   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (macro-user-callerid,s,28)       
  252. 31406   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:28] NoOp("SIP/BT-POTS-00000018", "Macro Depth is 2") in new stack     
  253. 31407   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("SIP/BT-POTS-00000018", "1?report2:macroerror") in new stack       
  254. 31408   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (macro-user-callerid,s,30)       
  255. 31409   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("SIP/BT-POTS-00000018", "0?continue") in new stack 
  256. 31410   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("SIP/BT-POTS-00000018", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
  257. 31411   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:32] Set("SIP/BT-POTS-00000018", "__TTL=64") in new stack      
  258. 31412   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("SIP/BT-POTS-00000018", "1?continue") in new stack 
  259. 31413   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (macro-user-callerid,s,49)       
  260. 31414   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/BT-POTS-00000018", "CALLERID(number)=BT-POTS") in new stack      
  261. 31415   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:50] Set("SIP/BT-POTS-00000018", "CALLERID(name)=BT-POTS") in new stack
  262. 31416   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("SIP/BT-POTS-00000018", "0?cnum") in new stack     
  263. 31417   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:52] Set("SIP/BT-POTS-00000018", "CDR(cnam)=BT-POTS") in new stack     
  264. 31418   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:53] Set("SIP/BT-POTS-00000018", "CDR(cnum)=BT-POTS") in new stack     
  265. 31419   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-user-callerid:54] Set("SIP/BT-POTS-00000018", "CHANNEL(language)=en") in new stack  
  266. 31420   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:2] Set("SIP/BT-POTS-00000018", "RingGroupMethod=none") in new stack
  267. 31421   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:3] Set("SIP/BT-POTS-00000018", "__EXTTOCALL=3") in new stack       
  268. 31422   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:4] Set("SIP/BT-POTS-00000018", "__PICKUPMARK=3") in new stack      
  269. 31423   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:5] Set("SIP/BT-POTS-00000018", "RT=") in new stack 
  270. 31424   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:6] ExecIf("SIP/BT-POTS-00000018", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack     
  271. 31425   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:7] ExecIf("SIP/BT-POTS-00000018", "0?MacroExit()") in new stack    
  272. 31426   [2022-06-07 10:24:21] ERROR[25276][C-0000001e] res_pjsip_header_funcs.c: This function requires a PJSIP channel.       
  273. 31427   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:8] ExecIf("SIP/BT-POTS-00000018", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack     
  274. 31428   [2022-06-07 10:24:21] ERROR[25276][C-0000001e] res_pjsip_header_funcs.c: This function requires a PJSIP channel.       
  275. 31429   [2022-06-07 10:24:21] ERROR[25276][C-0000001e] res_pjsip_header_funcs.c: This function requires a PJSIP channel.       
  276. 31430   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:9] ExecIf("SIP/BT-POTS-00000018", "0?MacroExit()") in new stack    
  277. 31431   [2022-06-07 10:24:21] ERROR[25276][C-0000001e] res_pjsip_header_funcs.c: This function requires a PJSIP channel.       
  278. 31432   [2022-06-07 10:24:21] ERROR[25276][C-0000001e] res_pjsip_header_funcs.c: This function requires a PJSIP channel.       
  279. 31433   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:10] ExecIf("SIP/BT-POTS-00000018", "0?ChanSpy(PJSIP/3,q)") in new stack    
  280. 31434   [2022-06-07 10:24:21] ERROR[25276][C-0000001e] res_pjsip_header_funcs.c: This function requires a PJSIP channel.       
  281. 31435   [2022-06-07 10:24:21] ERROR[25276][C-0000001e] res_pjsip_header_funcs.c: This function requires a PJSIP channel.       
  282. 31436   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:11] ExecIf("SIP/BT-POTS-00000018", "0?MacroExit()") in new stack   
  283. 31437   [2022-06-07 10:24:21] ERROR[25276][C-0000001e] res_pjsip_header_funcs.c: This function requires a PJSIP channel.       
  284. 31438   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:12] ExecIf("SIP/BT-POTS-00000018", "0?ChanSpy(PJSIP/3,q)") in new stack    
  285. 31439   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:13] ExecIf("SIP/BT-POTS-00000018", "0?MacroExit()") in new stack   
  286. 31440   [2022-06-07 10:24:21] ERROR[25276][C-0000001e] res_pjsip_header_funcs.c: This function requires a PJSIP channel.       
  287. 31441   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:14] ExecIf("SIP/BT-POTS-00000018", "0?Gosub(ext-intercom,*803,1())") in new stack  
  288. 31442   [2022-06-07 10:24:21] ERROR[25276][C-0000001e] res_pjsip_header_funcs.c: This function requires a PJSIP channel.       
  289. 31443   [2022-06-07 10:24:21] ERROR[25276][C-0000001e] res_pjsip_header_funcs.c: This function requires a PJSIP channel.       
  290. 31444   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:15] ExecIf("SIP/BT-POTS-00000018", "0?MacroExit()") in new stack   
  291. 31445   [2022-06-07 10:24:21] ERROR[25276][C-0000001e] res_pjsip_header_funcs.c: This function requires a PJSIP channel.       
  292. 31446   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:16] ExecIf("SIP/BT-POTS-00000018", "0?Gosub(ext-intercom,*803,1())") in new stack  
  293. 31447   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:17] ExecIf("SIP/BT-POTS-00000018", "0?MacroExit()") in new stack   
  294. 31448   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:18] Gosub("SIP/BT-POTS-00000018", "sub-record-check,s,1(exten,3,dontcare)") in new stack   
  295. 31449   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/BT-POTS-00000018", "7?initialized") in new stack  
  296. 31450   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (sub-record-check,s,10)  
  297. 31451   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/BT-POTS-00000018", "Recordings initialized") in new stack  
  298. 31452   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/BT-POTS-00000018", "0?Set(ARG3=dontcare)") in new stack  
  299. 31453   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:12] Set("SIP/BT-POTS-00000018", "REC_POLICY_MODE_SAVE=") in new stack    
  300. 31454   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/BT-POTS-00000018", "0?Set(REC_STATUS=NO)") in new stack  
  301. 31455   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/BT-POTS-00000018", "5?checkaction") in new stack 
  302. 31456   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (sub-record-check,s,17)  
  303. 31457   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/BT-POTS-00000018", "1?sub-record-check,exten,1") in new stack    
  304. 31458   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (sub-record-check,exten,1)       
  305. 31459   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/BT-POTS-00000018", "Exten Recording Check between BT-POTS and 3") in new stack  
  306. 31460   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/BT-POTS-00000018", "CALLTYPE=external") in new stack     
  307. 31461   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/BT-POTS-00000018", "0?Set(CALLTYPE=)") in new stack   
  308. 31462   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/BT-POTS-00000018", "CALLEE=dontcare") in new stack       
  309. 31463   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/BT-POTS-00000018", "0?Set(CALLEE=dontcare)") in new stack     
  310. 31464   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/BT-POTS-00000018", "1?callee") in new stack   
  311. 31465   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (sub-record-check,exten,11)      
  312. 31466   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [exten@sub-record-check:11] Gosub("SIP/BT-POTS-00000018", "recordcheck,1(dontcare,external,3)") in new stack 
  313. 31467   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/BT-POTS-00000018", "Starting recording check against dontcare") in new stack      
  314. 31468   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/BT-POTS-00000018", "dontcare") in new stack       
  315. 31469   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (sub-record-check,recordcheck,3) 
  316. 31470   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/BT-POTS-00000018", "") in new stack     
  317. 31471   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [exten@sub-record-check:12] Return("SIP/BT-POTS-00000018", "") in new stack  
  318. 31472   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:19] GotoIf("SIP/BT-POTS-00000018", "1?macrodial") in new stack     
  319. 31473   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (macro-exten-vm,s,25)    
  320. 31474   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:25] GosubIf("SIP/BT-POTS-00000018", "0?clrheader,1()") in new stack
  321. 31475   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:26] Macro("SIP/BT-POTS-00000018", "dial-one,,HhTtr,3") in new stack
  322. 31476   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:1] Set("SIP/BT-POTS-00000018", "DEXTEN=3") in new stack    
  323. 31477   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:2] Set("SIP/BT-POTS-00000018", "__CRM_SOURCE=BT-POTS") in new stack
  324. 31478   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:3] ExecIf("SIP/BT-POTS-00000018", "0?Set(__EXTTOCALL=3)") in new stack     
  325. 31479   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:4] Set("SIP/BT-POTS-00000018", "DIALSTATUS_CW=") in new stack      
  326. 31480   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:5] GosubIf("SIP/BT-POTS-00000018", "0?screen,1()") in new stack    
  327. 31481   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:6] GosubIf("SIP/BT-POTS-00000018", "0?cf,1()") in new stack
  328. 31482   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:7] GotoIf("SIP/BT-POTS-00000018", "1?skip1") in new stack  
  329. 31483   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (macro-dial-one,s,10)    
  330. 31484   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:10] GotoIf("SIP/BT-POTS-00000018", "0?nodial") in new stack
  331. 31485   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:11] GotoIf("SIP/BT-POTS-00000018", "0?continue") in new stack      
  332. 31486   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:12] Set("SIP/BT-POTS-00000018", "EXTHASCW=ENABLED") in new stack   
  333. 31487   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:13] GotoIf("SIP/BT-POTS-00000018", "0?next1:cwinusebusy") in new stack     
  334. 31488   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (macro-dial-one,s,25)    
  335. 31489   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:25] GotoIf("SIP/BT-POTS-00000018", "0?next3:continue") in new stack
  336. 31490   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (macro-dial-one,s,27)    
  337. 31491   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:27] GotoIf("SIP/BT-POTS-00000018", "0?nodial") in new stack
  338. 31492   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:28] GosubIf("SIP/BT-POTS-00000018", "1?dstring,1():dlocal,1()") in new stack       
  339. 31493   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:1] Set("SIP/BT-POTS-00000018", "DSTRING=") in new stack      
  340. 31494   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:2] Set("SIP/BT-POTS-00000018", "DEVICES=3") in new stack     
  341. 31495   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("SIP/BT-POTS-00000018", "0?Return()") in new stack 
  342. 31496   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("SIP/BT-POTS-00000018", "0?Set(DEVICES=)") in new stack    
  343. 31497   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:5] Set("SIP/BT-POTS-00000018", "LOOPCNT=1") in new stack     
  344. 31498   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:6] Set("SIP/BT-POTS-00000018", "ITER=1") in new stack
  345. 31499   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:7] Set("SIP/BT-POTS-00000018", "THISDIAL=PJSIP/3") in new stack      
  346. 31500   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:8] GotoIf("SIP/BT-POTS-00000018", "0?docheck") in new stack  
  347. 31501   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:9] NoOp("SIP/BT-POTS-00000018", "Debug: Found PJSIP Destination PJSIP/3") in new stack       
  348. 31502   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:10] GotoIf("SIP/BT-POTS-00000018", "0?doset") in new stack   
  349. 31503   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:11] NoOp("SIP/BT-POTS-00000018", "Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS") in new stack  
  350. 31504   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:12] Set("SIP/BT-POTS-00000018", "THISDIAL=") in new stack    
  351. 31505   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:13] ExecIf("SIP/BT-POTS-00000018", "1?Set(DIALSTATUS=CHANUNAVAIL)") in new stack     
  352. 31506   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf("SIP/BT-POTS-00000018", "1?skipset") in new stack 
  353. 31507   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (macro-dial-one,dstring,16)      
  354. 31508   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:16] Set("SIP/BT-POTS-00000018", "ITER=2") in new stack       
  355. 31509   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf("SIP/BT-POTS-00000018", "0?begin") in new stack   
  356. 31510   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf("SIP/BT-POTS-00000018", "1?Return()") in new stack
  357. 31511   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:29] GotoIf("SIP/BT-POTS-00000018", "1?nodial") in new stack
  358. 31512   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (macro-dial-one,s,61)    
  359. 31513   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:61] NoOp("SIP/BT-POTS-00000018", "") in new stack  
  360. 31514   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:62] ExecIf("SIP/BT-POTS-00000018", "0?Set(DIALSTATUS=NOANSWER)") in new stack      
  361. 31515   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:63] NoOp("SIP/BT-POTS-00000018", "Returned from dial-one with nothing to call and DIALSTATUS: CHANUNAVAIL") in new stack   
  362. 31516   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-dial-one:64] MacroExit("SIP/BT-POTS-00000018", "") in new stack     
  363. 31517   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:27] Set("SIP/BT-POTS-00000018", "SV_DIALSTATUS=CHANUNAVAIL") in new stack  
  364. 31518   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:28] GosubIf("SIP/BT-POTS-00000018", "0?docfu,1()") in new stack    
  365. 31519   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:29] GosubIf("SIP/BT-POTS-00000018", "0?docfb,1()") in new stack    
  366. 31520   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:30] Set("SIP/BT-POTS-00000018", "DIALSTATUS=CHANUNAVAIL") in new stack     
  367. 31521   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:31] ExecIf("SIP/BT-POTS-00000018", "0?MacroExit()") in new stack   
  368. 31522   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-exten-vm:32] GotoIf("SIP/BT-POTS-00000018", "1?s-CHANUNAVAIL,1") in new stack       
  369. 31523   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (macro-exten-vm,s-CHANUNAVAIL,1) 
  370. 31524   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s-CHANUNAVAIL@macro-exten-vm:1] GotoIf("SIP/BT-POTS-00000018", "0?exit,1") in new stack     
  371. 31525   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s-CHANUNAVAIL@macro-exten-vm:2] PlayTones("SIP/BT-POTS-00000018", "congestion") in new stack
  372. 31526   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s-CHANUNAVAIL@macro-exten-vm:3] Congestion("SIP/BT-POTS-00000018", "10") in new stack       
  373. 31527   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] chan_sip.c:   
  374. 31528   <--- Reliably Transmitting (NAT) to 192.168.1.199:5160 --->    
  375. 31529   SIP/2.0 503 Service Unavailable
  376. 31530   Via: SIP/2.0/UDP 192.168.1.199:5160;branch=z9hG4bK-a43ad0ff;received=192.168.1.199;rport=5160  
  377. 31531   From: BT-POTS <sip:BT-POTS@192.168.1.200:5160>;tag=3837fbd3a5b9792bo1  
  378. 31532   To: <sip:01217771234@192.168.1.200:5160>;tag=as2469d324
  379. 31533   Call-ID: c2ef0ef3-7428808b@192.168.1.199       
  380. 31534   CSeq: 102 INVITE       
  381. 31535   Server: FPBX-16.0.19(16.25.0)  
  382. 31536   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE     
  383. 31537   Supported: replaces, timer     
  384. 31538   Content-Length: 0      
  385. 31539          
  386. 31540          
  387. 31541   <------------> 
  388. 31542   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] app_macro.c: Spawn extension (macro-exten-vm, s-CHANUNAVAIL, 3) exited non-zero on 'SIP/BT-POTS-00000018' in macro 'exten-vm' 
  389. 31543   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Spawn extension (ext-local, 3, 3) exited non-zero on 'SIP/BT-POTS-00000018'    
  390. 31544   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [h@ext-local:1] Macro("SIP/BT-POTS-00000018", "hangupcall,") in new stack    
  391. 31545   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/BT-POTS-00000018", "1?theend") in new stack       
  392. 31546   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx_builtins.c: Goto (macro-hangupcall,s,3)   
  393. 31547   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/BT-POTS-00000018", "0?Set(CDR(recordingfile)=)") in new stack     
  394. 31548   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@macro-hangupcall:4] Hangup("SIP/BT-POTS-00000018", "") in new stack       
  395. 31549   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/BT-POTS-00000018' in macro 'hangupcall' 
  396. 31550   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/BT-POTS-00000018'    
  397. 31551   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] app_stack.c: SIP/BT-POTS-00000018 Internal Gosub(crm-hangup,s,1) start
  398. 31552   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/BT-POTS-00000018", "Sending Hangup to CRM") in new stack  
  399. 31553   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/BT-POTS-00000018", "HANGUP CAUSE: 34") in new stack       
  400. 31554   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/BT-POTS-00000018", "0?Set(__CRM_VOICEMAIL=)") in new stack      
  401. 31555   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/BT-POTS-00000018", "MASTER CHANNEL: 1654593861.42 = 1654593861.42") in new stack  
  402. 31556   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/BT-POTS-00000018", "0?return") in new stack     
  403. 31557   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@crm-hangup:6] Set("SIP/BT-POTS-00000018", "__CRM_HANGUP=1") in new stack  
  404. 31558   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/BT-POTS-00000018", "agi://127.0.0.1/sangomacrm.agi") in new stack  
  405. 31559   [2022-06-07 10:24:21] VERBOSE[2562] chan_sip.c:
  406. 31560   <--- SIP read from UDP:192.168.1.199:5160 ---> 
  407. 31561   ACK sip:01217771234@192.168.1.200:5160 SIP/2.0 
  408. 31562   Via: SIP/2.0/UDP 192.168.1.199:5160;branch=z9hG4bK-a43ad0ff    
  409. 31563   From: BT-POTS <sip:BT-POTS@192.168.1.200:5160>;tag=3837fbd3a5b9792bo1  
  410. 31564   To: <sip:01217771234@192.168.1.200:5160>;tag=as2469d324
  411. 31565   Call-ID: c2ef0ef3-7428808b@192.168.1.199       
  412. 31566   CSeq: 102 ACK  
  413. 31567   Max-Forwards: 70       
  414. 31568   Authorization: Digest username="BT-POTS",realm="asterisk",nonce="7bb482d9",uri="sip:01217771234@192.168.1.200:5160",algorithm=MD5,response="0c3560d1ba55c21801faec51006e39a2"  
  415. 31569   Contact: BT-POTS <sip:BT-POTS@192.168.1.199:5160>      
  416. 31570   User-Agent: Linksys/SPA3102-3.2.6(GWa) 
  417. 31571   Content-Length: 0      
  418. 31572          
  419. 31573   <------------->
  420. 31574   [2022-06-07 10:24:21] VERBOSE[2562] chan_sip.c: --- (11 headers 0 lines) ---   
  421. 31575   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] res_agi.c: <SIP/BT-POTS-00000018>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0     
  422. 31576   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] pbx.c: Executing [s@crm-hangup:8] Return("SIP/BT-POTS-00000018", "") in new stack     
  423. 31577   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] app_stack.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/BT-POTS-00000018'      
  424. 31578   [2022-06-07 10:24:21] VERBOSE[25276][C-0000001e] app_stack.c: SIP/BT-POTS-00000018 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=       
  425. 31579   [2022-06-07 10:24:22] VERBOSE[2562] chan_sip.c: Really destroying SIP dialog 'c2ef0ef3-7428808b@192.168.1.199' Method: ACK     
  426.  

Reply to "Untitled"

Here you can reply to the paste above