logs

From tapmetags42, 1 Week ago, written in Plain Text, viewed 3 times. This paste will go to its last resting place in 2 Weeks.
URL https://pastebin.freepbx.org/view/501553b8 Embed
Download Paste or View Raw
  1.  
  2. OLD Asterisk 13.12.1
  3. NEW Asterisk 16.20.0
  4.  
  5. Both the OLD and NEW servers share the same firewall and network.
  6.  
  7. We physically turn off one server before starting the other server.
  8. They are never both started at the same time.
  9. we use the same Internal IP address on both the OLD and NEW, since we did not want two Asterisk servers attempting to connect to the one (same) SIPSTATION account.
  10.  
  11.  
  12.  
  13. [root@pbx ~]# asterisk -r
  14. Asterisk 16.20.0, Copyright (C) 1999 - 2021, Sangoma Technologies Corporation and others.
  15. Created by Mark Spencer <markster@digium.com>
  16. Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for details.
  17. This is free software, with components licensed under the GNU General Public
  18. License version 2 and other licenses; you are welcome to redistribute it under
  19. certain conditions. Type 'core show license' for details.
  20. =========================================================================
  21. Connected to Asterisk 16.20.0 currently running on pbx (pid = 2515)
  22. pbx*CLI> core set verbose 5
  23. Console verbose was OFF and is now 5.
  24. pbx*CLI> core set debug 5
  25. Core debug was OFF and is now 5.
  26. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip/pjsip_options.c:927 sip_options_qualify_aor: Qualifying all contacts on AOR 'fpbx-2-UNu72jBj8bHw'
  27. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip/pjsip_options.c:857 sip_options_qualify_contact: Qualifying contact 'fpbx-2-UNu72jBj8bHw@@7efc6bea1913fda6e12fd87645a7edf2' on AOR 'fpbx-2-UNu72jBj8bHw'
  28. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip.c:4411 endpt_send_request: 0x7f10cc00c080: Wrapper created
  29. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip.c:4426 endpt_send_request: 0x7f10cc00c080: Set timer to 3000 msec
  30. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip/pjsip_resolver.c:479 sip_resolve: Performing SIP DNS resolution of target 'trunk2.freepbx.com'
  31. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip/pjsip_resolver.c:506 sip_resolve: Transport type for target 'trunk2.freepbx.com' is 'UDP transport'
  32. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip/pjsip_resolver.c:549 sip_resolve: [0x7f10cc00a8c8] Created resolution tracking for target 'trunk2.freepbx.com'
  33. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip/pjsip_resolver.c:177 sip_resolve_add: [0x7f10cc00a8c8] Added target 'trunk2.freepbx.com' with record type '1', transport 'UDP transport', and port '5060'
  34. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip/pjsip_resolver.c:620 sip_resolve: [0x7f10cc00a8c8] Starting initial resolution using parallel queries for target 'trunk2.freepbx.com'
  35. [2022-01-11 23:54:13] DEBUG[2731]: res_pjsip/pjsip_resolver.c:277 sip_resolve_callback: [0x7f10cc00a8c8] All parallel queries completed
  36. [2022-01-11 23:54:13] DEBUG[2731]: res_pjsip/pjsip_resolver.c:326 sip_resolve_callback: [0x7f10cc00a8c8] A record received on target 'trunk2.freepbx.com'
  37. [2022-01-11 23:54:13] DEBUG[2731]: res_pjsip/pjsip_resolver.c:421 sip_resolve_callback: [0x7f10cc00a8c8] Resolution completed - 1 viable targets
  38. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip/pjsip_resolver.c:201 sip_resolve_invoke_user_callback: [0x7f10cc00a8c8] Address '0' is 162.253.134.142:5060 with transport 'UDP transport'
  39. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip/pjsip_resolver.c:207 sip_resolve_invoke_user_callback: [0x7f10cc00a8c8] Invoking user callback with '1' addresses
  40. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip/pjsip_message_filter.c:296 filter_on_tx_message: Re-wrote Contact URI host/port to 10.42.58.120:5060 (this may be re-written again later)
  41. [2022-01-11 23:54:13] DEBUG[2699]: res_pjsip/pjsip_distributor.c:520 distributor: No dialog serializer for Response msg 200/OPTIONS/cseq=29615 (rdata0x7f1178012848).  Using request transaction as basis.
  42. [2022-01-11 23:54:13] DEBUG[2699]: res_pjsip/pjsip_distributor.c:128 find_request_serializer: Found transaction tsx0x7f10cc302858 for Response msg 200/OPTIONS/cseq=29615 (rdata0x7f1178012848).
  43. [2022-01-11 23:54:13] DEBUG[2699]: res_pjsip/pjsip_distributor.c:138 find_request_serializer: Found serializer pjsip/options/fpbx-2-UNu72jBj8bHw-00000040 on transaction tsx0x7f10cc302858
  44. [2022-01-11 23:54:13] DEBUG[2701]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
  45. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip.c:4289 endpt_send_request_cb: 0x7f10cc00c080: PJSIP tsx response received
  46. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip.c:4302 endpt_send_request_cb: 0x7f10cc00c080: Cancelling timer
  47. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip.c:4311 endpt_send_request_cb: 0x7f10cc00c080: Timer cancelled
  48. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip.c:4332 endpt_send_request_cb: 0x7f10cc00c080: Callbacks executed
  49. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip.c:4388 send_request_wrapper_destructor: 0x7f10cc00c080: wrapper destroyed
  50. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip/pjsip_options.c:759 sip_options_contact_status_notify_task: Contact fpbx-2-UNu72jBj8bHw/sip:UNu72jBj8bHw@trunk2.freepbx.com:5060 status didn't change: Reachable, RTT: 61.834 msec
  51. [2022-01-11 23:54:13] DEBUG[2700]: res_pjsip/pjsip_options.c:777 sip_options_contact_status_notify_task: AOR 'fpbx-2-UNu72jBj8bHw' now has 1 available contacts
  52. [2022-01-11 23:54:13] DEBUG[2696]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 6
  53. pbx*CLI> module reload logger
  54. Module 'logger' reloaded successfully.
  55.     -- Reloading module 'logger' (Logger)
  56. [2022-01-11 23:54:18] DEBUG[2956]: config.c:2207 config_text_file_load: Parsing /etc/asterisk/logger.conf
  57. [2022-01-11 23:54:18] DEBUG[2956]: config.c:2207 config_text_file_load: Parsing /etc/asterisk/logger_general_additional.conf
  58. [2022-01-11 23:54:18] DEBUG[2956]: config.c:2207 config_text_file_load: Parsing /etc/asterisk/logger_general_custom.conf
  59. [2022-01-11 23:54:18] DEBUG[2956]: config.c:2207 config_text_file_load: Parsing /etc/asterisk/logger_logfiles_additional.conf
  60. [2022-01-11 23:54:18] DEBUG[2956]: config.c:2207 config_text_file_load: Parsing /etc/asterisk/logger_logfiles_custom.conf
  61.  Asterisk Queue Logger restarted
  62. [2022-01-11 23:54:18] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  63. Event: Reload
  64. Privilege: system,all
  65. Module: logger
  66. Status: 0
  67.  
  68.  
  69. [2022-01-11 23:54:18] DEBUG[3227]: manager.c:6200 match_filter: Examining AMI event:
  70. Event: Reload
  71. Privilege: system,all
  72. Module: logger
  73. Status: 0
  74.  
  75.  
  76. [2022-01-11 23:54:18] DEBUG[3226]: manager.c:6200 match_filter: Examining AMI event:
  77. Event: Reload
  78. Privilege: system,all
  79. Module: logger
  80. Status: 0
  81.  
  82.  
  83. [2022-01-11 23:54:18] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  84. Event: Reload
  85. Privilege: system,all
  86. Module: logger
  87. Status: 0
  88.  
  89.  
  90. [2022-01-11 23:54:18] DEBUG[3225]: manager.c:6200 match_filter: Examining AMI event:
  91. Event: Reload
  92. Privilege: system,all
  93. Module: logger
  94. Status: 0
  95.  
  96.  
  97. [2022-01-11 23:54:18] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  98. Event: Reload
  99. Privilege: system,all
  100. Module: logger
  101. Status: 0
  102.  
  103.  
  104. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip_outbound_registration.c:608 handle_client_registration: Outbound REGISTER attempt 1 to 'sip:trunk1.freepbx.com:5060' with client 'sip:UNu72jBj8bHw@trunk1.freepbx.com:5060'
  105. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip/pjsip_resolver.c:479 sip_resolve: Performing SIP DNS resolution of target 'trunk1.freepbx.com'
  106. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip/pjsip_resolver.c:506 sip_resolve: Transport type for target 'trunk1.freepbx.com' is 'UDP transport'
  107. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip/pjsip_resolver.c:549 sip_resolve: [0x7f10cc00ad58] Created resolution tracking for target 'trunk1.freepbx.com'
  108. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip/pjsip_resolver.c:177 sip_resolve_add: [0x7f10cc00ad58] Added target 'trunk1.freepbx.com' with record type '1', transport 'UDP transport', and port '5060'
  109. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip/pjsip_resolver.c:620 sip_resolve: [0x7f10cc00ad58] Starting initial resolution using parallel queries for target 'trunk1.freepbx.com'
  110. [2022-01-11 23:54:22] DEBUG[2731]: res_pjsip/pjsip_resolver.c:277 sip_resolve_callback: [0x7f10cc00ad58] All parallel queries completed
  111. [2022-01-11 23:54:22] DEBUG[2731]: res_pjsip/pjsip_resolver.c:326 sip_resolve_callback: [0x7f10cc00ad58] A record received on target 'trunk1.freepbx.com'
  112. [2022-01-11 23:54:22] DEBUG[2731]: res_pjsip/pjsip_resolver.c:421 sip_resolve_callback: [0x7f10cc00ad58] Resolution completed - 1 viable targets
  113. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip/pjsip_resolver.c:201 sip_resolve_invoke_user_callback: [0x7f10cc00ad58] Address '0' is 192.159.66.3:5060 with transport 'UDP transport'
  114. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip/pjsip_resolver.c:207 sip_resolve_invoke_user_callback: [0x7f10cc00ad58] Invoking user callback with '1' addresses
  115. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip/pjsip_message_filter.c:296 filter_on_tx_message: Re-wrote Contact URI host/port to 10.42.58.120:5060 (this may be re-written again later)
  116. [2022-01-11 23:54:22] DEBUG[2699]: res_pjsip/pjsip_distributor.c:520 distributor: No dialog serializer for Response msg 401/REGISTER/cseq=3683 (rdata0x7f1178002c08).  Using request transaction as basis.
  117. [2022-01-11 23:54:22] DEBUG[2699]: res_pjsip/pjsip_distributor.c:128 find_request_serializer: Found transaction tsx0x7f10cc302858 for Response msg 401/REGISTER/cseq=3683 (rdata0x7f1178002c08).
  118. [2022-01-11 23:54:22] DEBUG[2699]: res_pjsip/pjsip_distributor.c:138 find_request_serializer: Found serializer pjsip/outreg/fpbx-1-UNu72jBj8bHw-00000058 on transaction tsx0x7f10cc302858
  119. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip_outbound_registration.c:1111 sip_outbound_registration_response_cb: Received REGISTER response 401(Unauthorized)
  120. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip_outbound_registration.c:951 handle_registration_response: Processing REGISTER response 401 from server 'sip:trunk1.freepbx.com:5060' for client 'sip:UNu72jBj8bHw@trunk1.freepbx.com:5060'
  121. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip_outbound_authenticator_digest.c:229 set_outbound_authentication_credentials: Searching auths to find matching ones for header with realm 'trunk1.freepbx.com' and algorithm 'MD5'
  122. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip_outbound_authenticator_digest.c:272 set_outbound_authentication_credentials: Found wildcard auth 'fpbx-1-UNu72jBj8bHw' for realm 'trunk1.freepbx.com'
  123. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip_outbound_authenticator_digest.c:302 set_outbound_authentication_credentials: Using wildcard auth 'fpbx-1-UNu72jBj8bHw' for realm 'trunk1.freepbx.com'
  124. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip_outbound_authenticator_digest.c:384 set_outbound_authentication_credentials: Set 1 credentials in auth session
  125. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip_outbound_registration.c:974 handle_registration_response: Sending authenticated REGISTER to server 'sip:trunk1.freepbx.com:5060' from client 'sip:UNu72jBj8bHw@trunk1.freepbx.com:5060'
  126. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip/pjsip_message_filter.c:296 filter_on_tx_message: Re-wrote Contact URI host/port to 10.42.58.120:5060 (this may be re-written again later)
  127. [2022-01-11 23:54:22] DEBUG[2699]: res_pjsip/pjsip_distributor.c:520 distributor: No dialog serializer for Response msg 200/REGISTER/cseq=3684 (rdata0x7f1178002c08).  Using request transaction as basis.
  128. [2022-01-11 23:54:22] DEBUG[2699]: res_pjsip/pjsip_distributor.c:128 find_request_serializer: Found transaction tsx0x7f10cc300808 for Response msg 200/REGISTER/cseq=3684 (rdata0x7f1178002c08).
  129. [2022-01-11 23:54:22] DEBUG[2699]: res_pjsip/pjsip_distributor.c:138 find_request_serializer: Found serializer pjsip/outreg/fpbx-1-UNu72jBj8bHw-00000058 on transaction tsx0x7f10cc300808
  130. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip_outbound_registration.c:1111 sip_outbound_registration_response_cb: Received REGISTER response 200(OK)
  131. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip_outbound_registration.c:951 handle_registration_response: Processing REGISTER response 200 from server 'sip:trunk1.freepbx.com:5060' for client 'sip:UNu72jBj8bHw@trunk1.freepbx.com:5060'
  132. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip_outbound_registration.c:1002 handle_registration_response: Outbound registration to 'sip:trunk1.freepbx.com:5060' with client 'sip:UNu72jBj8bHw@trunk1.freepbx.com:5060' successful
  133. [2022-01-11 23:54:22] DEBUG[2700]: res_pjsip_outbound_registration.c:680 schedule_registration: Scheduling outbound registration to server 'sip:trunk1.freepbx.com:5060' from client 'sip:UNu72jBj8bHw@trunk1.freepbx.com:5060' in 110 seconds
  134. [2022-01-11 23:54:22] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  135. Event: Registry
  136. Privilege: system,all
  137. ChannelType: PJSIP
  138. Username: sip:UNu72jBj8bHw@trunk1.freepbx.com:5060
  139. Domain: sip:trunk1.freepbx.com:5060
  140. Status: Registered
  141.  
  142.  
  143. [2022-01-11 23:54:22] DEBUG[3225]: manager.c:6200 match_filter: Examining AMI event:
  144. Event: Registry
  145. Privilege: system,all
  146. ChannelType: PJSIP
  147. Username: sip:UNu72jBj8bHw@trunk1.freepbx.com:5060
  148. Domain: sip:trunk1.freepbx.com:5060
  149. Status: Registered
  150.  
  151.  
  152. [2022-01-11 23:54:22] DEBUG[3226]: manager.c:6200 match_filter: Examining AMI event:
  153. Event: Registry
  154. Privilege: system,all
  155. ChannelType: PJSIP
  156. Username: sip:UNu72jBj8bHw@trunk1.freepbx.com:5060
  157. Domain: sip:trunk1.freepbx.com:5060
  158. Status: Registered
  159.  
  160.  
  161. [2022-01-11 23:54:22] DEBUG[3227]: manager.c:6200 match_filter: Examining AMI event:
  162. Event: Registry
  163. Privilege: system,all
  164. ChannelType: PJSIP
  165. Username: sip:UNu72jBj8bHw@trunk1.freepbx.com:5060
  166. Domain: sip:trunk1.freepbx.com:5060
  167. Status: Registered
  168.  
  169.  
  170. [2022-01-11 23:54:22] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  171. Event: Registry
  172. Privilege: system,all
  173. ChannelType: PJSIP
  174. Username: sip:UNu72jBj8bHw@trunk1.freepbx.com:5060
  175. Domain: sip:trunk1.freepbx.com:5060
  176. Status: Registered
  177.  
  178.  
  179. [2022-01-11 23:54:22] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  180. Event: Registry
  181. Privilege: system,all
  182. ChannelType: PJSIP
  183. Username: sip:UNu72jBj8bHw@trunk1.freepbx.com:5060
  184. Domain: sip:trunk1.freepbx.com:5060
  185. Status: Registered
  186.  
  187.  
  188. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip_outbound_registration.c:608 handle_client_registration: Outbound REGISTER attempt 1 to 'sip:trunk2.freepbx.com:5060' with client 'sip:UNu72jBj8bHw@trunk2.freepbx.com:5060'
  189. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip/pjsip_resolver.c:479 sip_resolve: Performing SIP DNS resolution of target 'trunk2.freepbx.com'
  190. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip/pjsip_resolver.c:506 sip_resolve: Transport type for target 'trunk2.freepbx.com' is 'UDP transport'
  191. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip/pjsip_resolver.c:549 sip_resolve: [0x7f10cc301798] Created resolution tracking for target 'trunk2.freepbx.com'
  192. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip/pjsip_resolver.c:177 sip_resolve_add: [0x7f10cc301798] Added target 'trunk2.freepbx.com' with record type '1', transport 'UDP transport', and port '5060'
  193. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip/pjsip_resolver.c:620 sip_resolve: [0x7f10cc301798] Starting initial resolution using parallel queries for target 'trunk2.freepbx.com'
  194. [2022-01-11 23:54:24] DEBUG[2731]: res_pjsip/pjsip_resolver.c:277 sip_resolve_callback: [0x7f10cc301798] All parallel queries completed
  195. [2022-01-11 23:54:24] DEBUG[2731]: res_pjsip/pjsip_resolver.c:326 sip_resolve_callback: [0x7f10cc301798] A record received on target 'trunk2.freepbx.com'
  196. [2022-01-11 23:54:24] DEBUG[2731]: res_pjsip/pjsip_resolver.c:421 sip_resolve_callback: [0x7f10cc301798] Resolution completed - 1 viable targets
  197. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip/pjsip_resolver.c:201 sip_resolve_invoke_user_callback: [0x7f10cc301798] Address '0' is 162.253.134.142:5060 with transport 'UDP transport'
  198. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip/pjsip_resolver.c:207 sip_resolve_invoke_user_callback: [0x7f10cc301798] Invoking user callback with '1' addresses
  199. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip/pjsip_message_filter.c:296 filter_on_tx_message: Re-wrote Contact URI host/port to 10.42.58.120:5060 (this may be re-written again later)
  200. [2022-01-11 23:54:24] DEBUG[2699]: res_pjsip/pjsip_distributor.c:520 distributor: No dialog serializer for Response msg 401/REGISTER/cseq=17161 (rdata0x7f1178002c08).  Using request transaction as basis.
  201. [2022-01-11 23:54:24] DEBUG[2699]: res_pjsip/pjsip_distributor.c:128 find_request_serializer: Found transaction tsx0x7f10cc310618 for Response msg 401/REGISTER/cseq=17161 (rdata0x7f1178002c08).
  202. [2022-01-11 23:54:24] DEBUG[2699]: res_pjsip/pjsip_distributor.c:138 find_request_serializer: Found serializer pjsip/outreg/fpbx-2-UNu72jBj8bHw-00000059 on transaction tsx0x7f10cc310618
  203. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip_outbound_registration.c:1111 sip_outbound_registration_response_cb: Received REGISTER response 401(Unauthorized)
  204. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip_outbound_registration.c:951 handle_registration_response: Processing REGISTER response 401 from server 'sip:trunk2.freepbx.com:5060' for client 'sip:UNu72jBj8bHw@trunk2.freepbx.com:5060'
  205. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip_outbound_authenticator_digest.c:229 set_outbound_authentication_credentials: Searching auths to find matching ones for header with realm 'trunk2.freepbx.com' and algorithm 'MD5'
  206. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip_outbound_authenticator_digest.c:272 set_outbound_authentication_credentials: Found wildcard auth 'fpbx-2-UNu72jBj8bHw' for realm 'trunk2.freepbx.com'
  207. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip_outbound_authenticator_digest.c:302 set_outbound_authentication_credentials: Using wildcard auth 'fpbx-2-UNu72jBj8bHw' for realm 'trunk2.freepbx.com'
  208. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip_outbound_authenticator_digest.c:384 set_outbound_authentication_credentials: Set 1 credentials in auth session
  209. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip_outbound_registration.c:974 handle_registration_response: Sending authenticated REGISTER to server 'sip:trunk2.freepbx.com:5060' from client 'sip:UNu72jBj8bHw@trunk2.freepbx.com:5060'
  210. [2022-01-11 23:54:24] DEBUG[2700]: res_pjsip/pjsip_message_filter.c:296 filter_on_tx_message: Re-wrote Contact URI host/port to 10.42.58.120:5060 (this may be re-written again later)
  211. [2022-01-11 23:54:24] DEBUG[2699]: res_pjsip/pjsip_distributor.c:520 distributor: No dialog serializer for Response msg 200/REGISTER/cseq=17162 (rdata0x7f1178002c08).  Using request transaction as basis.
  212. [2022-01-11 23:54:24] DEBUG[2699]: res_pjsip/pjsip_distributor.c:128 find_request_serializer: Found transaction tsx0x7f10cc00b558 for Response msg 200/REGISTER/cseq=17162 (rdata0x7f1178002c08).
  213. [2022-01-11 23:54:25] DEBUG[2699]: res_pjsip/pjsip_distributor.c:138 find_request_serializer: Found serializer pjsip/outreg/fpbx-2-UNu72jBj8bHw-00000059 on transaction tsx0x7f10cc00b558
  214. [2022-01-11 23:54:25] DEBUG[2700]: res_pjsip_outbound_registration.c:1111 sip_outbound_registration_response_cb: Received REGISTER response 200(OK)
  215. [2022-01-11 23:54:25] DEBUG[2700]: res_pjsip_outbound_registration.c:951 handle_registration_response: Processing REGISTER response 200 from server 'sip:trunk2.freepbx.com:5060' for client 'sip:UNu72jBj8bHw@trunk2.freepbx.com:5060'
  216. [2022-01-11 23:54:25] DEBUG[2700]: res_pjsip_outbound_registration.c:1002 handle_registration_response: Outbound registration to 'sip:trunk2.freepbx.com:5060' with client 'sip:UNu72jBj8bHw@trunk2.freepbx.com:5060' successful
  217. [2022-01-11 23:54:25] DEBUG[2700]: res_pjsip_outbound_registration.c:680 schedule_registration: Scheduling outbound registration to server 'sip:trunk2.freepbx.com:5060' from client 'sip:UNu72jBj8bHw@trunk2.freepbx.com:5060' in 110 seconds
  218. [2022-01-11 23:54:25] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  219. Event: Registry
  220. Privilege: system,all
  221. ChannelType: PJSIP
  222. Username: sip:UNu72jBj8bHw@trunk2.freepbx.com:5060
  223. Domain: sip:trunk2.freepbx.com:5060
  224. Status: Registered
  225.  
  226.  
  227. [2022-01-11 23:54:25] DEBUG[3225]: manager.c:6200 match_filter: Examining AMI event:
  228. Event: Registry
  229. Privilege: system,all
  230. ChannelType: PJSIP
  231. Username: sip:UNu72jBj8bHw@trunk2.freepbx.com:5060
  232. Domain: sip:trunk2.freepbx.com:5060
  233. Status: Registered
  234.  
  235.  
  236. [2022-01-11 23:54:25] DEBUG[3227]: manager.c:6200 match_filter: Examining AMI event:
  237. Event: Registry
  238. Privilege: system,all
  239. ChannelType: PJSIP
  240. Username: sip:UNu72jBj8bHw@trunk2.freepbx.com:5060
  241. Domain: sip:trunk2.freepbx.com:5060
  242. Status: Registered
  243.  
  244.  
  245. [2022-01-11 23:54:25] DEBUG[3226]: manager.c:6200 match_filter: Examining AMI event:
  246. Event: Registry
  247. Privilege: system,all
  248. ChannelType: PJSIP
  249. Username: sip:UNu72jBj8bHw@trunk2.freepbx.com:5060
  250. Domain: sip:trunk2.freepbx.com:5060
  251. Status: Registered
  252.  
  253.  
  254. [2022-01-11 23:54:25] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  255. Event: Registry
  256. Privilege: system,all
  257. ChannelType: PJSIP
  258. Username: sip:UNu72jBj8bHw@trunk2.freepbx.com:5060
  259. Domain: sip:trunk2.freepbx.com:5060
  260. Status: Registered
  261.  
  262.  
  263. [2022-01-11 23:54:25] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  264. Event: Registry
  265. Privilege: system,all
  266. ChannelType: PJSIP
  267. Username: sip:UNu72jBj8bHw@trunk2.freepbx.com:5060
  268. Domain: sip:trunk2.freepbx.com:5060
  269. Status: Registered
  270.  
  271.  
  272. [2022-01-11 23:54:27] DEBUG[2752]: res_pjsip_registrar.c:1278 check_expiration_thread: Woke up at 1641963267  Interval: 30
  273. [2022-01-11 23:54:27] DEBUG[2752]: res_pjsip_registrar.c:1285 check_expiration_thread: Expiring 0 contacts
  274. [2022-01-11 23:54:31] DEBUG[3515]: manager.c:6688 process_message: Running action 'Login'
  275. [2022-01-11 23:54:32] DEBUG[3515]: manager.c:6688 process_message: Running action 'Command'
  276. [2022-01-11 23:54:32] DEBUG[3515]: manager.c:6688 process_message: Running action 'Command'
  277. [2022-01-11 23:54:32] DEBUG[3515]: manager.c:6688 process_message: Running action 'Command'
  278. [2022-01-11 23:54:32] DEBUG[3515]: manager.c:6688 process_message: Running action 'Command'
  279. [2022-01-11 23:54:32] DEBUG[3515]: manager.c:6688 process_message: Running action 'Command'
  280. [2022-01-11 23:54:32] DEBUG[3515]: manager.c:6688 process_message: Running action 'Command'
  281. [2022-01-11 23:54:32] DEBUG[3515]: manager.c:6688 process_message: Running action 'Command'
  282. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip/pjsip_options.c:927 sip_options_qualify_aor: Qualifying all contacts on AOR 'fpbx-1-UNu72jBj8bHw'
  283. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip/pjsip_options.c:857 sip_options_qualify_contact: Qualifying contact 'fpbx-1-UNu72jBj8bHw@@b25d97dfe7b5e2f68cb1a3aef422dd6e' on AOR 'fpbx-1-UNu72jBj8bHw'
  284. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip.c:4411 endpt_send_request: 0x7f10cc00c630: Wrapper created
  285. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip.c:4426 endpt_send_request: 0x7f10cc00c630: Set timer to 3000 msec
  286. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip/pjsip_resolver.c:479 sip_resolve: Performing SIP DNS resolution of target 'trunk1.freepbx.com'
  287. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip/pjsip_resolver.c:506 sip_resolve: Transport type for target 'trunk1.freepbx.com' is 'UDP transport'
  288. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip/pjsip_resolver.c:549 sip_resolve: [0x7f10cc00ad58] Created resolution tracking for target 'trunk1.freepbx.com'
  289. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip/pjsip_resolver.c:177 sip_resolve_add: [0x7f10cc00ad58] Added target 'trunk1.freepbx.com' with record type '1', transport 'UDP transport', and port '5060'
  290. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip/pjsip_resolver.c:620 sip_resolve: [0x7f10cc00ad58] Starting initial resolution using parallel queries for target 'trunk1.freepbx.com'
  291. [2022-01-11 23:54:45] DEBUG[2731]: res_pjsip/pjsip_resolver.c:277 sip_resolve_callback: [0x7f10cc00ad58] All parallel queries completed
  292. [2022-01-11 23:54:45] DEBUG[2731]: res_pjsip/pjsip_resolver.c:326 sip_resolve_callback: [0x7f10cc00ad58] A record received on target 'trunk1.freepbx.com'
  293. [2022-01-11 23:54:45] DEBUG[2731]: res_pjsip/pjsip_resolver.c:421 sip_resolve_callback: [0x7f10cc00ad58] Resolution completed - 1 viable targets
  294. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip/pjsip_resolver.c:201 sip_resolve_invoke_user_callback: [0x7f10cc00ad58] Address '0' is 192.159.66.3:5060 with transport 'UDP transport'
  295. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip/pjsip_resolver.c:207 sip_resolve_invoke_user_callback: [0x7f10cc00ad58] Invoking user callback with '1' addresses
  296. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip/pjsip_message_filter.c:296 filter_on_tx_message: Re-wrote Contact URI host/port to 10.42.58.120:5060 (this may be re-written again later)
  297. [2022-01-11 23:54:45] DEBUG[2699]: res_pjsip/pjsip_distributor.c:520 distributor: No dialog serializer for Response msg 200/OPTIONS/cseq=12111 (rdata0x7f1178002c08).  Using request transaction as basis.
  298. [2022-01-11 23:54:45] DEBUG[2699]: res_pjsip/pjsip_distributor.c:128 find_request_serializer: Found transaction tsx0x7f10cc00b558 for Response msg 200/OPTIONS/cseq=12111 (rdata0x7f1178002c08).
  299. [2022-01-11 23:54:45] DEBUG[2699]: res_pjsip/pjsip_distributor.c:138 find_request_serializer: Found serializer pjsip/options/fpbx-1-UNu72jBj8bHw-0000003f on transaction tsx0x7f10cc00b558
  300. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip.c:4289 endpt_send_request_cb: 0x7f10cc00c630: PJSIP tsx response received
  301. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip.c:4302 endpt_send_request_cb: 0x7f10cc00c630: Cancelling timer
  302. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip.c:4311 endpt_send_request_cb: 0x7f10cc00c630: Timer cancelled
  303. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip.c:4332 endpt_send_request_cb: 0x7f10cc00c630: Callbacks executed
  304. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip.c:4388 send_request_wrapper_destructor: 0x7f10cc00c630: wrapper destroyed
  305. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip/pjsip_options.c:759 sip_options_contact_status_notify_task: Contact fpbx-1-UNu72jBj8bHw/sip:UNu72jBj8bHw@trunk1.freepbx.com:5060 status didn't change: Reachable, RTT: 20.027 msec
  306. [2022-01-11 23:54:45] DEBUG[2700]: res_pjsip/pjsip_options.c:777 sip_options_contact_status_notify_task: AOR 'fpbx-1-UNu72jBj8bHw' now has 1 available contacts
  307. [2022-01-11 23:54:57] DEBUG[2752]: res_pjsip_registrar.c:1278 check_expiration_thread: Woke up at 1641963297  Interval: 30
  308. [2022-01-11 23:54:57] DEBUG[2752]: res_pjsip_registrar.c:1285 check_expiration_thread: Expiring 0 contacts
  309.  
  310.  
  311.  
  312.  
  313.  
  314. Inbound Call Started
  315. --------------------
  316.  
  317. [2022-01-11 23:55:09] DEBUG[3571]: manager.c:6688 process_message: Running action 'Login'
  318. [2022-01-11 23:55:09] DEBUG[2699]: res_pjsip/pjsip_distributor.c:394 find_dialog: Could not find matching transaction for Request msg INVITE/cseq=46427862 (rdata0x7f1178002c08)
  319. [2022-01-11 23:55:09] DEBUG[2699]: res_pjsip/pjsip_distributor.c:472 ast_sip_get_distributor_serializer: Calculated serializer pjsip/distributor-0000002f to use for Request msg INVITE/cseq=46427862 (rdata0x7f1178002c08)
  320. [2022-01-11 23:55:09] DEBUG[2700]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '192.159.66.3' into...
  321. [2022-01-11 23:55:09] DEBUG[2700]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '192.159.66.3' and port ''.
  322. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_endpoint_identifier_ip.c:255 ip_identify_match_check: Source address 192.159.66.3:5060 matches identify 'fpbx-1-UNu72jBj8bHw'
  323. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_endpoint_identifier_ip.c:288 common_identify: Identify 'fpbx-1-UNu72jBj8bHw' SIP message matched to endpoint fpbx-1-UNu72jBj8bHw
  324. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip/pjsip_distributor.c:472 ast_sip_get_distributor_serializer: Calculated serializer pjsip/distributor-0000002f to use for Request msg INVITE/cseq=46427862 (rdata0x7f11780135b8)
  325. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:3924 new_invite:  fpbx-1-UNu72jBj8bHw
  326. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4008 new_invite:  fpbx-1-UNu72jBj8bHw: Call (UDP:192.159.66.3:5060) to extension '4166404500' sending 100 Trying
  327. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4457 handle_outgoing_response:  fpbx-1-UNu72jBj8bHw: Method is INVITE, Response is 100 Trying
  328. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4471 handle_outgoing_response:  fpbx-1-UNu72jBj8bHw
  329. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4310 __print_debug_details: Function session_inv_on_state_changed called on event TSX_STATE
  330. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4324 __print_debug_details: The state change pertains to the endpoint 'fpbx-1-UNu72jBj8bHw()'
  331. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4329 __print_debug_details: The inv session still has an invite_tsx (0x7f10cc310618)
  332. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4344 __print_debug_details: There is no transaction involved in this state change
  333. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4346 __print_debug_details: The current inv state is INCOMING
  334. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4593 session_inv_on_state_changed: fpbx-1-UNu72jBj8bHw: Source of transaction state change is TX_MSG
  335. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4310 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
  336. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4324 __print_debug_details: The state change pertains to the endpoint 'fpbx-1-UNu72jBj8bHw()'
  337. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4329 __print_debug_details: The inv session still has an invite_tsx (0x7f10cc310618)
  338. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4335 __print_debug_details: The UAS INVITE transaction involved in this state change is 0x7f10cc310618
  339. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4339 __print_debug_details: The current transaction state is Proceeding
  340. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4341 __print_debug_details: The transaction state change event is TX_MSG
  341. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4346 __print_debug_details: The current inv state is INCOMING
  342. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:768 handle_incoming_sdp:  fpbx-1-UNu72jBj8bHw: Media count: 1
  343. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:794 handle_incoming_sdp:  fpbx-1-UNu72jBj8bHw: Processing stream 0
  344. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:832 handle_incoming_sdp:  fpbx-1-UNu72jBj8bHw: Using audio-0 for new stream name
  345. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:876 handle_incoming_sdp:  fpbx-1-UNu72jBj8bHw: Using new stream 0:audio-0:audio:sendrecv (nothing)
  346. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:494 ast_sip_session_media_state_add:  fpbx-1-UNu72jBj8bHw Adding position 0
  347. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:540 ast_sip_session_media_state_add:  Creating new media session
  348. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:583 ast_sip_session_media_state_add:  Setting media session as default for audio
  349. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:588 ast_sip_session_media_state_add:  Done
  350. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:930 handle_incoming_sdp:  fpbx-1-UNu72jBj8bHw: Negotiating incoming SDP media stream 0:audio-0:audio:sendrecv (nothing) using audio SDP handler
  351. [2022-01-11 23:55:09] DEBUG[2700]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '67.231.9.12' into...
  352. [2022-01-11 23:55:09] DEBUG[2700]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '67.231.9.12' and port ''.
  353. [2022-01-11 23:55:09] DEBUG[2700]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '0.0.0.0' into...
  354. [2022-01-11 23:55:09] DEBUG[2700]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port ''.
  355. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_sdp_rtp.c:260 create_rtp: Transport 0.0.0.0-udp bound to 0.0.0.0: Using it for RTP media.
  356. [2022-01-11 23:55:09] DEBUG[2700]: rtp_engine.c:526 ast_rtp_instance_new: Using engine 'asterisk' for RTP instance '0x7f10cc3087d0'
  357. [2022-01-11 23:55:09] DEBUG[2700]: res_rtp_asterisk.c:3852 rtp_allocate_transport: (0x7f10cc3087d0) RTP allocated port 10882
  358. [2022-01-11 23:55:09] DEBUG[2700]: res_rtp_asterisk.c:3883 rtp_allocate_transport: (0x7f10cc3087d0) ICE creating session 0.0.0.0:10882 (10882)
  359. [2022-01-11 23:55:09] DEBUG[2700]: res_rtp_asterisk.c:3764 ice_create: (0x7f10cc3087d0) ICE create
  360. [2022-01-11 23:55:09] DEBUG[2700]: res_rtp_asterisk.c:3545 rtp_add_candidates_to_ice: (0x7f10cc3087d0) ICE add system candidates
  361. [2022-01-11 23:55:09] DEBUG[2700]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.42.58.120' into...
  362. [2022-01-11 23:55:09] DEBUG[2700]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.42.58.120' and port ''.
  363. [2022-01-11 23:55:09] DEBUG[2700]: res_rtp_asterisk.c:1315 ast_rtp_ice_add_cand: (0x7f10cc3087d0) ICE add candidate: 10.42.58.120:10882, 2130706431
  364. [2022-01-11 23:55:09] DEBUG[2700]: rtp_engine.c:543 ast_rtp_instance_new: RTP instance '0x7f10cc3087d0' is setup and ready to go
  365. [2022-01-11 23:55:09] DEBUG[2700]: res_rtp_asterisk.c:903 ast_rtp_ice_stop: (0x7f10cc3087d0) ICE stopped
  366. [2022-01-11 23:55:09] DEBUG[2700]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting 'pbx.mysentry.com' into...
  367. [2022-01-11 23:55:09] DEBUG[2700]: netsock2.c:224 ast_sockaddr_split_hostport: ...host 'pbx.mysentry.com' and port ''.
  368. [2022-01-11 23:55:09] DEBUG[2700]: acl.c:885 resolve_first: Multiple addresses. Using the first only
  369. [2022-01-11 23:55:09] DEBUG[2700]: res_rtp_asterisk.c:8413 ast_rtp_prop_set: (0x7f10cc3087d0) RTCP setup on RTP instance
  370. [2022-01-11 23:55:09] DEBUG[2700]: rtp_engine.c:1319 ast_rtp_codecs_payloads_set_m_type: Setting tx payload type 0 based on m type on 0x7f1115158f10
  371. [2022-01-11 23:55:09] DEBUG[2700]: rtp_engine.c:1319 ast_rtp_codecs_payloads_set_m_type: Setting tx payload type 18 based on m type on 0x7f1115158f10
  372. [2022-01-11 23:55:09] DEBUG[2700]: rtp_engine.c:1283 ast_rtp_codecs_payloads_xover: Crossover copying tx to rx payload mapping 0 (0x7f10cc009718) from 0x7f1115158f10 to 0x7f1115158f10
  373. [2022-01-11 23:55:09] DEBUG[2700]: rtp_engine.c:1283 ast_rtp_codecs_payloads_xover: Crossover copying tx to rx payload mapping 18 (0x7f10cc303d98) from 0x7f1115158f10 to 0x7f1115158f10
  374. [2022-01-11 23:55:09] DEBUG[2700]: rtp_engine.c:1283 ast_rtp_codecs_payloads_xover: Crossover copying tx to rx payload mapping 101 (0x7f10cc0098a8) from 0x7f1115158f10 to 0x7f1115158f10
  375. [2022-01-11 23:55:09] DEBUG[2700]: rtp_engine.c:1120 rtp_codecs_payloads_copy_rx: Copying rx payload mapping 0 (0x7f10cc009718) from 0x7f1115158f10 to 0x7f10cc3089a8
  376. [2022-01-11 23:55:09] DEBUG[2700]: rtp_engine.c:1120 rtp_codecs_payloads_copy_rx: Copying rx payload mapping 18 (0x7f10cc303d98) from 0x7f1115158f10 to 0x7f10cc3089a8
  377. [2022-01-11 23:55:09] DEBUG[2700]: rtp_engine.c:1120 rtp_codecs_payloads_copy_rx: Copying rx payload mapping 101 (0x7f10cc0098a8) from 0x7f1115158f10 to 0x7f10cc3089a8
  378. [2022-01-11 23:55:09] DEBUG[2700]: rtp_engine.c:1205 rtp_codecs_payloads_copy_tx: Copying tx payload mapping 0 (0x7f10cc009718) from 0x7f1115158f10 to 0x7f10cc3089a8
  379. [2022-01-11 23:55:09] DEBUG[2700]: rtp_engine.c:1205 rtp_codecs_payloads_copy_tx: Copying tx payload mapping 18 (0x7f10cc303d98) from 0x7f1115158f10 to 0x7f10cc3089a8
  380. [2022-01-11 23:55:09] DEBUG[2700]: rtp_engine.c:1205 rtp_codecs_payloads_copy_tx: Copying tx payload mapping 101 (0x7f10cc0098a8) from 0x7f1115158f10 to 0x7f10cc3089a8
  381. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:949 handle_incoming_sdp:  fpbx-1-UNu72jBj8bHw: Media stream 0:audio-0:audio:sendrecv (ulaw) handled by audio
  382. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:955 handle_incoming_sdp:  fpbx-1-UNu72jBj8bHw: Done with stream 0:audio-0:audio:sendrecv (ulaw)
  383. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:960 handle_incoming_sdp:  fpbx-1-UNu72jBj8bHw: Handled? yes
  384. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:5027 create_local_sdp:  fpbx-1-UNu72jBj8bHw
  385. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:5064 create_local_sdp:  fpbx-1-UNu72jBj8bHw: Processing streams
  386. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:5071 create_local_sdp:  fpbx-1-UNu72jBj8bHw: Processing stream 0:audio-0:audio:sendrecv (ulaw)
  387. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:494 ast_sip_session_media_state_add:  fpbx-1-UNu72jBj8bHw Adding position 0
  388. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:502 ast_sip_session_media_state_add:  Using existing media_session
  389. [2022-01-11 23:55:09] DEBUG[2700]: res_rtp_asterisk.c:8312 ast_rtp_prop_set: (0x7f10cc3087d0) RTCP ignoring duplicate property
  390. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:5104 create_local_sdp:  fpbx-1-UNu72jBj8bHw: Stream 0:audio-0:audio:sendrecv (ulaw) added
  391. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:5116 create_local_sdp:  fpbx-1-UNu72jBj8bHw: Done with 0:audio-0:audio:sendrecv (ulaw)
  392. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:5121 create_local_sdp:  fpbx-1-UNu72jBj8bHw: Adding bundle groups (if available)
  393. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:5127 create_local_sdp:  fpbx-1-UNu72jBj8bHw: Copying connection details
  394. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:5130 create_local_sdp:  fpbx-1-UNu72jBj8bHw: Processing media 0
  395. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:5148 create_local_sdp:  fpbx-1-UNu72jBj8bHw: Media 0 reset
  396. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:5171 create_local_sdp:  fpbx-1-UNu72jBj8bHw
  397. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4355 handle_incoming_request:  fpbx-1-UNu72jBj8bHw: Method is INVITE
  398. [2022-01-11 23:55:09] DEBUG[2700]: chan_pjsip.c:2976 chan_pjsip_incoming_request:  fpbx-1-UNu72jBj8bHw
  399. [2022-01-11 23:55:09] DEBUG[2700]: stasis.c:579 stasis_topic_create_with_detail: Creating topic. name: channel:1641963309.0, detail:
  400. [2022-01-11 23:55:09] DEBUG[2700]: stasis.c:613 stasis_topic_create_with_detail: Topic 'channel:1641963309.0': 0x7f10cc3084b0 created
  401. [2022-01-11 23:55:09] DEBUG[2700]: stasis.c:579 stasis_topic_create_with_detail: Creating topic. name: cache:8/channel:1641963309.0, detail:
  402. [2022-01-11 23:55:09] DEBUG[2700]: stasis.c:613 stasis_topic_create_with_detail: Topic 'cache:8/channel:1641963309.0': 0x7f10cc31b590 created
  403. [2022-01-11 23:55:09] DEBUG[2700]: channel.c:989 __ast_channel_alloc_ap: Channel 0x7f10cc318e50 'PJSIP/fpbx-1-UNu72jBj8bHw-00000000' allocated
  404. [2022-01-11 23:55:09] DEBUG[2700]: chan_pjsip.c:3026 chan_pjsip_incoming_request:  PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  405. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  406. Event: Newchannel
  407. Privilege: call,all
  408. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  409. ChannelState: 4
  410. ChannelStateDesc: Ring
  411. CallerIDNum: 14165390110
  412. CallerIDName: TORONTO      ON
  413. ConnectedLineNum: <unknown>
  414. ConnectedLineName: <unknown>
  415. Language: en
  416. AccountCode:
  417. Context: from-pstn
  418. Exten: 4166404500
  419. Priority: 1
  420. Uniqueid: 1641963309.0
  421. Linkedid: 1641963309.0
  422.  
  423.  
  424. [2022-01-11 23:55:09] DEBUG[3227]: manager.c:6200 match_filter: Examining AMI event:
  425. Event: Newchannel
  426. Privilege: call,all
  427. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  428. ChannelState: 4
  429. ChannelStateDesc: Ring
  430. CallerIDNum: 14165390110
  431. CallerIDName: TORONTO      ON
  432. ConnectedLineNum: <unknown>
  433. ConnectedLineName: <unknown>
  434. Language: en
  435. AccountCode:
  436. Context: from-pstn
  437. Exten: 4166404500
  438. Priority: 1
  439. Uniqueid: 1641963309.0
  440. Linkedid: 1641963309.0
  441.  
  442.  
  443. [2022-01-11 23:55:09] DEBUG[2700]: chan_pjsip.c:3107 pbx_start_incoming_request: Started PBX on new PJSIP channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  444. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4365 handle_incoming_request:  PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  445. [2022-01-11 23:55:09] DEBUG[2700]: res_pjsip_session.c:4053 new_invite:  PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  446. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  447. Event: Newchannel
  448. Privilege: call,all
  449. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  450. ChannelState: 4
  451. ChannelStateDesc: Ring
  452. CallerIDNum: 14165390110
  453. CallerIDName: TORONTO      ON
  454. ConnectedLineNum: <unknown>
  455. ConnectedLineName: <unknown>
  456. Language: en
  457. AccountCode:
  458. Context: from-pstn
  459. Exten: 4166404500
  460. Priority: 1
  461. Uniqueid: 1641963309.0
  462. Linkedid: 1641963309.0
  463.  
  464.  
  465. [2022-01-11 23:55:09] DEBUG[3226]: manager.c:6200 match_filter: Examining AMI event:
  466. Event: Newchannel
  467. Privilege: call,all
  468. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  469. ChannelState: 4
  470. ChannelStateDesc: Ring
  471. CallerIDNum: 14165390110
  472. CallerIDName: TORONTO      ON
  473. ConnectedLineNum: <unknown>
  474. ConnectedLineName: <unknown>
  475. Language: en
  476. AccountCode:
  477. Context: from-pstn
  478. Exten: 4166404500
  479. Priority: 1
  480. Uniqueid: 1641963309.0
  481. Linkedid: 1641963309.0
  482.  
  483.  
  484. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  485. Event: Newchannel
  486. Privilege: call,all
  487. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  488. ChannelState: 4
  489. ChannelStateDesc: Ring
  490. CallerIDNum: 14165390110
  491. CallerIDName: TORONTO      ON
  492. ConnectedLineNum: <unknown>
  493. ConnectedLineName: <unknown>
  494. Language: en
  495. AccountCode:
  496. Context: from-pstn
  497. Exten: 4166404500
  498. Priority: 1
  499. Uniqueid: 1641963309.0
  500. Linkedid: 1641963309.0
  501.  
  502.  
  503. [2022-01-11 23:55:09] DEBUG[3225]: manager.c:6200 match_filter: Examining AMI event:
  504. Event: Newchannel
  505. Privilege: call,all
  506. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  507. ChannelState: 4
  508. ChannelStateDesc: Ring
  509. CallerIDNum: 14165390110
  510. CallerIDName: TORONTO      ON
  511. ConnectedLineNum: <unknown>
  512. ConnectedLineName: <unknown>
  513. Language: en
  514. AccountCode:
  515. Context: from-pstn
  516. Exten: 4166404500
  517. Priority: 1
  518. Uniqueid: 1641963309.0
  519. Linkedid: 1641963309.0
  520.  
  521.  
  522. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  523. Event: VarSet
  524. Privilege: dialplan,all
  525. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  526. ChannelState: 4
  527. ChannelStateDesc: Ring
  528. CallerIDNum: 14165390110
  529. CallerIDName: TORONTO      ON
  530. ConnectedLineNum: <unknown>
  531. ConnectedLineName: <unknown>
  532. Language: en
  533. AccountCode:
  534. Context: from-pstn
  535. Exten: 4166404500
  536. Priority: 1
  537. Uniqueid: 1641963309.0
  538. Linkedid: 1641963309.0
  539. Variable: SIPDOMAIN
  540. Value: 10.42.58.120
  541.  
  542.  
  543. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  544.     -- Executing [4166404500@from-pstn:1] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "__DIRECTION=INBOUND") in new stack
  545. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  546. Event: VarSet
  547. Privilege: dialplan,all
  548. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  549. ChannelState: 4
  550. ChannelStateDesc: Ring
  551. CallerIDNum: 14165390110
  552. CallerIDName: TORONTO      ON
  553. ConnectedLineNum: <unknown>
  554. ConnectedLineName: <unknown>
  555. Language: en
  556. AccountCode:
  557. Context: from-pstn
  558. Exten: 4166404500
  559. Priority: 1
  560. Uniqueid: 1641963309.0
  561. Linkedid: 1641963309.0
  562. Variable: SIPDOMAIN
  563. Value: 10.42.58.120
  564.  
  565.  
  566. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  567.     -- Executing [4166404500@from-pstn:2] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "CHANNEL(tonezone)=us") in new stack
  568. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  569. Event: Newexten
  570. Privilege: dialplan,all
  571. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  572. ChannelState: 4
  573. ChannelStateDesc: Ring
  574. CallerIDNum: 14165390110
  575. CallerIDName: TORONTO      ON
  576. ConnectedLineNum: <unknown>
  577. ConnectedLineName: <unknown>
  578. Language: en
  579. AccountCode:
  580. Context: from-pstn
  581. Exten: 4166404500
  582. Priority: 1
  583. Uniqueid: 1641963309.0
  584. Linkedid: 1641963309.0
  585. Extension: 4166404500
  586. Application: Set
  587. AppData: __DIRECTION=INBOUND
  588.  
  589.  
  590. [2022-01-11 23:55:09] DEBUG[2663]: res_odbc.c:974 _ast_odbc_request_obj2: Reusing ODBC handle 0x1d915f0 from class 'asteriskcdrdb'
  591. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  592. Event: Newexten
  593. Privilege: dialplan,all
  594. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  595. ChannelState: 4
  596. ChannelStateDesc: Ring
  597. CallerIDNum: 14165390110
  598. CallerIDName: TORONTO      ON
  599. ConnectedLineNum: <unknown>
  600. ConnectedLineName: <unknown>
  601. Language: en
  602. AccountCode:
  603. Context: from-pstn
  604. Exten: 4166404500
  605. Priority: 1
  606. Uniqueid: 1641963309.0
  607. Linkedid: 1641963309.0
  608. Extension: 4166404500
  609. Application: Set
  610. AppData: __DIRECTION=INBOUND
  611.  
  612.  
  613. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  614. Event: VarSet
  615. Privilege: dialplan,all
  616. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  617. ChannelState: 4
  618. ChannelStateDesc: Ring
  619. CallerIDNum: 14165390110
  620. CallerIDName: TORONTO      ON
  621. ConnectedLineNum: <unknown>
  622. ConnectedLineName: <unknown>
  623. Language: en
  624. AccountCode:
  625. Context: from-pstn
  626. Exten: 4166404500
  627. Priority: 1
  628. Uniqueid: 1641963309.0
  629. Linkedid: 1641963309.0
  630. Variable: __DIRECTION
  631. Value: INBOUND
  632.  
  633.  
  634. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  635. Event: Newexten
  636. Privilege: dialplan,all
  637. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  638. ChannelState: 4
  639. ChannelStateDesc: Ring
  640. CallerIDNum: 14165390110
  641. CallerIDName: TORONTO      ON
  642. ConnectedLineNum: <unknown>
  643. ConnectedLineName: <unknown>
  644. Language: en
  645. AccountCode:
  646. Context: from-pstn
  647. Exten: 4166404500
  648. Priority: 2
  649. Uniqueid: 1641963309.0
  650. Linkedid: 1641963309.0
  651. Extension: 4166404500
  652. Application: Set
  653. AppData: CHANNEL(tonezone)=us
  654.  
  655.  
  656. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  657. Event: VarSet
  658. Privilege: dialplan,all
  659. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  660. ChannelState: 4
  661. ChannelStateDesc: Ring
  662. CallerIDNum: 14165390110
  663. CallerIDName: TORONTO      ON
  664. ConnectedLineNum: <unknown>
  665. ConnectedLineName: <unknown>
  666. Language: en
  667. AccountCode:
  668. Context: from-pstn
  669. Exten: 4166404500
  670. Priority: 1
  671. Uniqueid: 1641963309.0
  672. Linkedid: 1641963309.0
  673. Variable: __DIRECTION
  674. Value: INBOUND
  675.  
  676.  
  677. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  678. Event: Newexten
  679. Privilege: dialplan,all
  680. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  681. ChannelState: 4
  682. ChannelStateDesc: Ring
  683. CallerIDNum: 14165390110
  684. CallerIDName: TORONTO      ON
  685. ConnectedLineNum: <unknown>
  686. ConnectedLineName: <unknown>
  687. Language: en
  688. AccountCode:
  689. Context: from-pstn
  690. Exten: 4166404500
  691. Priority: 2
  692. Uniqueid: 1641963309.0
  693. Linkedid: 1641963309.0
  694. Extension: 4166404500
  695. Application: Set
  696. AppData: CHANNEL(tonezone)=us
  697.  
  698.  
  699. [2022-01-11 23:55:09] DEBUG[2663]: cel_odbc.c:781 odbc_log: Executing SQL statement: [INSERT INTO cel (eventtype, eventtime, cid_name, cid_num, cid_ani, cid_rdnis, cid_dnid, exten, context, channame, appname, appdata, amaflags, accountcode, uniqueid, linkedid, peer, userdeftype, extra) VALUES ('CHAN_START', {ts '2022-01-11 23:55:09.900224'}, 'TORONTO      ON', '14165390110', '', '', '', '4166404500', 'from-pstn', 'PJSIP/fpbx-1-UNu72jBj8bHw-00000000', '', '', 3, '', '1641963309.0', '1641963309.0', '', '', '')]
  700. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_variables.c:377 ast_str_retrieve_variable: Result of 'EXTEN' is '4166404500'
  701. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  702.     -- Executing [4166404500@from-pstn:3] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "__FROM_DID=4166404500") in new stack
  703. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  704. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  705. Event: Newexten
  706. Privilege: dialplan,all
  707. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  708. ChannelState: 4
  709. ChannelStateDesc: Ring
  710. CallerIDNum: 14165390110
  711. CallerIDName: TORONTO      ON
  712. ConnectedLineNum: <unknown>
  713. ConnectedLineName: <unknown>
  714. Language: en
  715. AccountCode:
  716. Context: from-pstn
  717. Exten: 4166404500
  718. Priority: 3
  719. Uniqueid: 1641963309.0
  720. Linkedid: 1641963309.0
  721. Extension: 4166404500
  722. Application: Set
  723. AppData: __FROM_DID=4166404500
  724.  
  725.  
  726.     -- Executing [4166404500@from-pstn:4] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "returnhere=1") in new stack
  727. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  728. Event: Newexten
  729. Privilege: dialplan,all
  730. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  731. ChannelState: 4
  732. ChannelStateDesc: Ring
  733. CallerIDNum: 14165390110
  734. CallerIDName: TORONTO      ON
  735. ConnectedLineNum: <unknown>
  736. ConnectedLineName: <unknown>
  737. Language: en
  738. AccountCode:
  739. Context: from-pstn
  740. Exten: 4166404500
  741. Priority: 3
  742. Uniqueid: 1641963309.0
  743. Linkedid: 1641963309.0
  744. Extension: 4166404500
  745. Application: Set
  746. AppData: __FROM_DID=4166404500
  747.  
  748.  
  749. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  750. Event: VarSet
  751. Privilege: dialplan,all
  752. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  753. ChannelState: 4
  754. ChannelStateDesc: Ring
  755. CallerIDNum: 14165390110
  756. CallerIDName: TORONTO      ON
  757. ConnectedLineNum: <unknown>
  758. ConnectedLineName: <unknown>
  759. Language: en
  760. AccountCode:
  761. Context: from-pstn
  762. Exten: 4166404500
  763. Priority: 3
  764. Uniqueid: 1641963309.0
  765. Linkedid: 1641963309.0
  766. Variable: __FROM_DID
  767. Value: 4166404500
  768.  
  769.  
  770. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  771. Event: VarSet
  772. Privilege: dialplan,all
  773. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  774. ChannelState: 4
  775. ChannelStateDesc: Ring
  776. CallerIDNum: 14165390110
  777. CallerIDName: TORONTO      ON
  778. ConnectedLineNum: <unknown>
  779. ConnectedLineName: <unknown>
  780. Language: en
  781. AccountCode:
  782. Context: from-pstn
  783. Exten: 4166404500
  784. Priority: 3
  785. Uniqueid: 1641963309.0
  786. Linkedid: 1641963309.0
  787. Variable: __FROM_DID
  788. Value: 4166404500
  789.  
  790.  
  791. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Gosub'
  792.     -- Executing [4166404500@from-pstn:5] Gosub("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "app-blacklist-check,s,1()") in new stack
  793. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  794. Event: Newexten
  795. Privilege: dialplan,all
  796. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  797. ChannelState: 4
  798. ChannelStateDesc: Ring
  799. CallerIDNum: 14165390110
  800. CallerIDName: TORONTO      ON
  801. ConnectedLineNum: <unknown>
  802. ConnectedLineName: <unknown>
  803. Language: en
  804. AccountCode:
  805. Context: from-pstn
  806. Exten: 4166404500
  807. Priority: 4
  808. Uniqueid: 1641963309.0
  809. Linkedid: 1641963309.0
  810. Extension: 4166404500
  811. Application: Set
  812. AppData: returnhere=1
  813.  
  814.  
  815. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  816. Event: Newexten
  817. Privilege: dialplan,all
  818. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  819. ChannelState: 4
  820. ChannelStateDesc: Ring
  821. CallerIDNum: 14165390110
  822. CallerIDName: TORONTO      ON
  823. ConnectedLineNum: <unknown>
  824. ConnectedLineName: <unknown>
  825. Language: en
  826. AccountCode:
  827. Context: from-pstn
  828. Exten: 4166404500
  829. Priority: 4
  830. Uniqueid: 1641963309.0
  831. Linkedid: 1641963309.0
  832. Extension: 4166404500
  833. Application: Set
  834. AppData: returnhere=1
  835.  
  836.  
  837. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  838. Event: VarSet
  839. Privilege: dialplan,all
  840. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  841. ChannelState: 4
  842. ChannelStateDesc: Ring
  843. CallerIDNum: 14165390110
  844. CallerIDName: TORONTO      ON
  845. ConnectedLineNum: <unknown>
  846. ConnectedLineName: <unknown>
  847. Language: en
  848. AccountCode:
  849. Context: from-pstn
  850. Exten: 4166404500
  851. Priority: 4
  852. Uniqueid: 1641963309.0
  853. Linkedid: 1641963309.0
  854. Variable: returnhere
  855. Value: 1
  856.  
  857.  
  858. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  859. Event: VarSet
  860. Privilege: dialplan,all
  861. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  862. ChannelState: 4
  863. ChannelStateDesc: Ring
  864. CallerIDNum: 14165390110
  865. CallerIDName: TORONTO      ON
  866. ConnectedLineNum: <unknown>
  867. ConnectedLineName: <unknown>
  868. Language: en
  869. AccountCode:
  870. Context: from-pstn
  871. Exten: 4166404500
  872. Priority: 4
  873. Uniqueid: 1641963309.0
  874. Linkedid: 1641963309.0
  875. Variable: returnhere
  876. Value: 1
  877.  
  878.  
  879. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: app_stack.c:605 gosub_exec: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 has no datastore, so we're allocating one.
  880. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  881. Event: Newexten
  882. Privilege: dialplan,all
  883. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  884. ChannelState: 4
  885. ChannelStateDesc: Ring
  886. CallerIDNum: 14165390110
  887. CallerIDName: TORONTO      ON
  888. ConnectedLineNum: <unknown>
  889. ConnectedLineName: <unknown>
  890. Language: en
  891. AccountCode:
  892. Context: from-pstn
  893. Exten: 4166404500
  894. Priority: 5
  895. Uniqueid: 1641963309.0
  896. Linkedid: 1641963309.0
  897. Extension: 4166404500
  898. Application: Gosub
  899. AppData: app-blacklist-check,s,1()
  900.  
  901.  
  902. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  903. Event: Newexten
  904. Privilege: dialplan,all
  905. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  906. ChannelState: 4
  907. ChannelStateDesc: Ring
  908. CallerIDNum: 14165390110
  909. CallerIDName: TORONTO      ON
  910. ConnectedLineNum: <unknown>
  911. ConnectedLineName: <unknown>
  912. Language: en
  913. AccountCode:
  914. Context: from-pstn
  915. Exten: 4166404500
  916. Priority: 5
  917. Uniqueid: 1641963309.0
  918. Linkedid: 1641963309.0
  919. Extension: 4166404500
  920. Application: Gosub
  921. AppData: app-blacklist-check,s,1()
  922.  
  923.  
  924. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  925. Event: VarSet
  926. Privilege: dialplan,all
  927. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  928. ChannelState: 4
  929. ChannelStateDesc: Ring
  930. CallerIDNum: 14165390110
  931. CallerIDName: TORONTO      ON
  932. ConnectedLineNum: <unknown>
  933. ConnectedLineName: <unknown>
  934. Language: en
  935. AccountCode:
  936. Context: from-pstn
  937. Exten: 4166404500
  938. Priority: 5
  939. Uniqueid: 1641963309.0
  940. Linkedid: 1641963309.0
  941. Variable: LOCAL(ARGC)
  942. Value: 0
  943.  
  944.  
  945. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  946. Event: VarSet
  947. Privilege: dialplan,all
  948. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  949. ChannelState: 4
  950. ChannelStateDesc: Ring
  951. CallerIDNum: 14165390110
  952. CallerIDName: TORONTO      ON
  953. ConnectedLineNum: <unknown>
  954. ConnectedLineName: <unknown>
  955. Language: en
  956. AccountCode:
  957. Context: from-pstn
  958. Exten: 4166404500
  959. Priority: 1
  960. Uniqueid: 1641963309.0
  961. Linkedid: 1641963309.0
  962. Variable: SIPDOMAIN
  963. Value: 10.42.58.120
  964.  
  965.  
  966. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  967. Event: VarSet
  968. Privilege: dialplan,all
  969. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  970. ChannelState: 4
  971. ChannelStateDesc: Ring
  972. CallerIDNum: 14165390110
  973. CallerIDName: TORONTO      ON
  974. ConnectedLineNum: <unknown>
  975. ConnectedLineName: <unknown>
  976. Language: en
  977. AccountCode:
  978. Context: from-pstn
  979. Exten: 4166404500
  980. Priority: 5
  981. Uniqueid: 1641963309.0
  982. Linkedid: 1641963309.0
  983. Variable: LOCAL(ARGC)
  984. Value: 0
  985.  
  986.  
  987. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: db.c:392 db_get_common: Unable to find key '14165390110' in family 'blacklist'
  988. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: db.c:392 db_get_common: Unable to find key 'TORONTO      ON' in family 'blacklist'
  989. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  990. Event: Newexten
  991. Privilege: dialplan,all
  992. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  993. ChannelState: 4
  994. ChannelStateDesc: Ring
  995. CallerIDNum: 14165390110
  996. CallerIDName: TORONTO      ON
  997. ConnectedLineNum: <unknown>
  998. ConnectedLineName: <unknown>
  999. Language: en
  1000. AccountCode:
  1001. Context: from-pstn
  1002. Exten: 4166404500
  1003. Priority: 1
  1004. Uniqueid: 1641963309.0
  1005. Linkedid: 1641963309.0
  1006. Extension: 4166404500
  1007. Application: Set
  1008. AppData: __DIRECTION=INBOUND
  1009.  
  1010.  
  1011. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_variables.c:759 pbx_substitute_variables_helper_full: Function BLACKLIST() result is '0'
  1012. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  1013. Event: VarSet
  1014. Privilege: dialplan,all
  1015. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1016. ChannelState: 4
  1017. ChannelStateDesc: Ring
  1018. CallerIDNum: 14165390110
  1019. CallerIDName: TORONTO      ON
  1020. ConnectedLineNum: <unknown>
  1021. ConnectedLineName: <unknown>
  1022. Language: en
  1023. AccountCode:
  1024. Context: from-pstn
  1025. Exten: 4166404500
  1026. Priority: 1
  1027. Uniqueid: 1641963309.0
  1028. Linkedid: 1641963309.0
  1029. Variable: __DIRECTION
  1030. Value: INBOUND
  1031.  
  1032.  
  1033. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  1034. Event: Newexten
  1035. Privilege: dialplan,all
  1036. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1037. ChannelState: 4
  1038. ChannelStateDesc: Ring
  1039. CallerIDNum: 14165390110
  1040. CallerIDName: TORONTO      ON
  1041. ConnectedLineNum: <unknown>
  1042. ConnectedLineName: <unknown>
  1043. Language: en
  1044. AccountCode:
  1045. Context: from-pstn
  1046. Exten: 4166404500
  1047. Priority: 2
  1048. Uniqueid: 1641963309.0
  1049. Linkedid: 1641963309.0
  1050. Extension: 4166404500
  1051. Application: Set
  1052. AppData: CHANNEL(tonezone)=us
  1053.  
  1054.  
  1055. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  1056. Event: Newexten
  1057. Privilege: dialplan,all
  1058. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1059. ChannelState: 4
  1060. ChannelStateDesc: Ring
  1061. CallerIDNum: 14165390110
  1062. CallerIDName: TORONTO      ON
  1063. ConnectedLineNum: <unknown>
  1064. ConnectedLineName: <unknown>
  1065. Language: en
  1066. AccountCode:
  1067. Context: from-pstn
  1068. Exten: 4166404500
  1069. Priority: 3
  1070. Uniqueid: 1641963309.0
  1071. Linkedid: 1641963309.0
  1072. Extension: 4166404500
  1073. Application: Set
  1074. AppData: __FROM_DID=4166404500
  1075.  
  1076.  
  1077. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  1078. Event: VarSet
  1079. Privilege: dialplan,all
  1080. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1081. ChannelState: 4
  1082. ChannelStateDesc: Ring
  1083. CallerIDNum: 14165390110
  1084. CallerIDName: TORONTO      ON
  1085. ConnectedLineNum: <unknown>
  1086. ConnectedLineName: <unknown>
  1087. Language: en
  1088. AccountCode:
  1089. Context: from-pstn
  1090. Exten: 4166404500
  1091. Priority: 3
  1092. Uniqueid: 1641963309.0
  1093. Linkedid: 1641963309.0
  1094. Variable: __FROM_DID
  1095. Value: 4166404500
  1096.  
  1097.  
  1098. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  1099. Event: Newexten
  1100. Privilege: dialplan,all
  1101. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1102. ChannelState: 4
  1103. ChannelStateDesc: Ring
  1104. CallerIDNum: 14165390110
  1105. CallerIDName: TORONTO      ON
  1106. ConnectedLineNum: <unknown>
  1107. ConnectedLineName: <unknown>
  1108. Language: en
  1109. AccountCode:
  1110. Context: from-pstn
  1111. Exten: 4166404500
  1112. Priority: 4
  1113. Uniqueid: 1641963309.0
  1114. Linkedid: 1641963309.0
  1115. Extension: 4166404500
  1116. Application: Set
  1117. AppData: returnhere=1
  1118.  
  1119.  
  1120. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  1121. Event: VarSet
  1122. Privilege: dialplan,all
  1123. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1124. ChannelState: 4
  1125. ChannelStateDesc: Ring
  1126. CallerIDNum: 14165390110
  1127. CallerIDName: TORONTO      ON
  1128. ConnectedLineNum: <unknown>
  1129. ConnectedLineName: <unknown>
  1130. Language: en
  1131. AccountCode:
  1132. Context: from-pstn
  1133. Exten: 4166404500
  1134. Priority: 4
  1135. Uniqueid: 1641963309.0
  1136. Linkedid: 1641963309.0
  1137. Variable: returnhere
  1138. Value: 1
  1139.  
  1140.  
  1141. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  1142. Event: Newexten
  1143. Privilege: dialplan,all
  1144. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1145. ChannelState: 4
  1146. ChannelStateDesc: Ring
  1147. CallerIDNum: 14165390110
  1148. CallerIDName: TORONTO      ON
  1149. ConnectedLineNum: <unknown>
  1150. ConnectedLineName: <unknown>
  1151. Language: en
  1152. AccountCode:
  1153. Context: from-pstn
  1154. Exten: 4166404500
  1155. Priority: 5
  1156. Uniqueid: 1641963309.0
  1157. Linkedid: 1641963309.0
  1158. Extension: 4166404500
  1159. Application: Gosub
  1160. AppData: app-blacklist-check,s,1()
  1161.  
  1162.  
  1163. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  1164. Event: VarSet
  1165. Privilege: dialplan,all
  1166. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1167. ChannelState: 4
  1168. ChannelStateDesc: Ring
  1169. CallerIDNum: 14165390110
  1170. CallerIDName: TORONTO      ON
  1171. ConnectedLineNum: <unknown>
  1172. ConnectedLineName: <unknown>
  1173. Language: en
  1174. AccountCode:
  1175. Context: from-pstn
  1176. Exten: 4166404500
  1177. Priority: 5
  1178. Uniqueid: 1641963309.0
  1179. Linkedid: 1641963309.0
  1180. Variable: LOCAL(ARGC)
  1181. Value: 0
  1182.  
  1183.  
  1184. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_variables.c:823 pbx_substitute_variables_helper_full: Expression result is '0'
  1185. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'GotoIf'
  1186.     -- Executing [s@app-blacklist-check:1] GotoIf("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "0?blacklisted") in new stack
  1187. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_builtins.c:1255 pbx_builtin_gotoif: Not taking any branch
  1188. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  1189.     -- Executing [s@app-blacklist-check:2] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "CALLED_BLACKLIST=1") in new stack
  1190. [2022-01-11 23:55:09] DEBUG[2663]: res_odbc.c:817 ast_odbc_release_obj: Releasing ODBC handle 0x1d915f0 into pool
  1191. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Return'
  1192.     -- Executing [s@app-blacklist-check:3] Return("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "") in new stack
  1193. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_variables.c:377 ast_str_retrieve_variable: Result of 'FROM_DID' is '4166404500'
  1194. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  1195.     -- Executing [4166404500@from-pstn:6] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "CDR(did)=4166404500") in new stack
  1196. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1197. Event: Newexten
  1198. Privilege: dialplan,all
  1199. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1200. ChannelState: 4
  1201. ChannelStateDesc: Ring
  1202. CallerIDNum: 14165390110
  1203. CallerIDName: TORONTO      ON
  1204. ConnectedLineNum: <unknown>
  1205. ConnectedLineName: <unknown>
  1206. Language: en
  1207. AccountCode:
  1208. Context: app-blacklist-check
  1209. Exten: s
  1210. Priority: 1
  1211. Uniqueid: 1641963309.0
  1212. Linkedid: 1641963309.0
  1213. Extension: s
  1214. Application: GotoIf
  1215. AppData: 0?blacklisted
  1216.  
  1217.  
  1218. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1219. Event: Newexten
  1220. Privilege: dialplan,all
  1221. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1222. ChannelState: 4
  1223. ChannelStateDesc: Ring
  1224. CallerIDNum: 14165390110
  1225. CallerIDName: TORONTO      ON
  1226. ConnectedLineNum: <unknown>
  1227. ConnectedLineName: <unknown>
  1228. Language: en
  1229. AccountCode:
  1230. Context: app-blacklist-check
  1231. Exten: s
  1232. Priority: 2
  1233. Uniqueid: 1641963309.0
  1234. Linkedid: 1641963309.0
  1235. Extension: s
  1236. Application: Set
  1237. AppData: CALLED_BLACKLIST=1
  1238.  
  1239.  
  1240. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1241. Event: VarSet
  1242. Privilege: dialplan,all
  1243. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1244. ChannelState: 4
  1245. ChannelStateDesc: Ring
  1246. CallerIDNum: 14165390110
  1247. CallerIDName: TORONTO      ON
  1248. ConnectedLineNum: <unknown>
  1249. ConnectedLineName: <unknown>
  1250. Language: en
  1251. AccountCode:
  1252. Context: app-blacklist-check
  1253. Exten: s
  1254. Priority: 2
  1255. Uniqueid: 1641963309.0
  1256. Linkedid: 1641963309.0
  1257. Variable: CALLED_BLACKLIST
  1258. Value: 1
  1259.  
  1260.  
  1261. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1262. Event: Newexten
  1263. Privilege: dialplan,all
  1264. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1265. ChannelState: 4
  1266. ChannelStateDesc: Ring
  1267. CallerIDNum: 14165390110
  1268. CallerIDName: TORONTO      ON
  1269. ConnectedLineNum: <unknown>
  1270. ConnectedLineName: <unknown>
  1271. Language: en
  1272. AccountCode:
  1273. Context: app-blacklist-check
  1274. Exten: s
  1275. Priority: 3
  1276. Uniqueid: 1641963309.0
  1277. Linkedid: 1641963309.0
  1278. Extension: s
  1279. Application: Return
  1280. AppData:
  1281.  
  1282.  
  1283. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1284. Event: VarSet
  1285. Privilege: dialplan,all
  1286. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1287. ChannelState: 4
  1288. ChannelStateDesc: Ring
  1289. CallerIDNum: 14165390110
  1290. CallerIDName: TORONTO      ON
  1291. ConnectedLineNum: <unknown>
  1292. ConnectedLineName: <unknown>
  1293. Language: en
  1294. AccountCode:
  1295. Context: app-blacklist-check
  1296. Exten: s
  1297. Priority: 3
  1298. Uniqueid: 1641963309.0
  1299. Linkedid: 1641963309.0
  1300. Variable: ARGC
  1301. Value:
  1302.  
  1303.  
  1304. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  1305. Event: Newexten
  1306. Privilege: dialplan,all
  1307. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1308. ChannelState: 4
  1309. ChannelStateDesc: Ring
  1310. CallerIDNum: 14165390110
  1311. CallerIDName: TORONTO      ON
  1312. ConnectedLineNum: <unknown>
  1313. ConnectedLineName: <unknown>
  1314. Language: en
  1315. AccountCode:
  1316. Context: app-blacklist-check
  1317. Exten: s
  1318. Priority: 1
  1319. Uniqueid: 1641963309.0
  1320. Linkedid: 1641963309.0
  1321. Extension: s
  1322. Application: GotoIf
  1323. AppData: 0?blacklisted
  1324.  
  1325.  
  1326. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1327. Event: VarSet
  1328. Privilege: dialplan,all
  1329. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1330. ChannelState: 4
  1331. ChannelStateDesc: Ring
  1332. CallerIDNum: 14165390110
  1333. CallerIDName: TORONTO      ON
  1334. ConnectedLineNum: <unknown>
  1335. ConnectedLineName: <unknown>
  1336. Language: en
  1337. AccountCode:
  1338. Context: app-blacklist-check
  1339. Exten: s
  1340. Priority: 3
  1341. Uniqueid: 1641963309.0
  1342. Linkedid: 1641963309.0
  1343. Variable: GOSUB_RETVAL
  1344. Value:
  1345.  
  1346.  
  1347. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1348. Event: Newexten
  1349. Privilege: dialplan,all
  1350. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1351. ChannelState: 4
  1352. ChannelStateDesc: Ring
  1353. CallerIDNum: 14165390110
  1354. CallerIDName: TORONTO      ON
  1355. ConnectedLineNum: <unknown>
  1356. ConnectedLineName: <unknown>
  1357. Language: en
  1358. AccountCode:
  1359. Context: from-pstn
  1360. Exten: 4166404500
  1361. Priority: 6
  1362. Uniqueid: 1641963309.0
  1363. Linkedid: 1641963309.0
  1364. Extension: 4166404500
  1365. Application: Set
  1366. AppData: CDR(did)=4166404500
  1367.  
  1368.  
  1369. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  1370. Event: Newexten
  1371. Privilege: dialplan,all
  1372. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1373. ChannelState: 4
  1374. ChannelStateDesc: Ring
  1375. CallerIDNum: 14165390110
  1376. CallerIDName: TORONTO      ON
  1377. ConnectedLineNum: <unknown>
  1378. ConnectedLineName: <unknown>
  1379. Language: en
  1380. AccountCode:
  1381. Context: app-blacklist-check
  1382. Exten: s
  1383. Priority: 2
  1384. Uniqueid: 1641963309.0
  1385. Linkedid: 1641963309.0
  1386. Extension: s
  1387. Application: Set
  1388. AppData: CALLED_BLACKLIST=1
  1389.  
  1390.  
  1391. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  1392. Event: VarSet
  1393. Privilege: dialplan,all
  1394. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1395. ChannelState: 4
  1396. ChannelStateDesc: Ring
  1397. CallerIDNum: 14165390110
  1398. CallerIDName: TORONTO      ON
  1399. ConnectedLineNum: <unknown>
  1400. ConnectedLineName: <unknown>
  1401. Language: en
  1402. AccountCode:
  1403. Context: app-blacklist-check
  1404. Exten: s
  1405. Priority: 2
  1406. Uniqueid: 1641963309.0
  1407. Linkedid: 1641963309.0
  1408. Variable: CALLED_BLACKLIST
  1409. Value: 1
  1410.  
  1411.  
  1412. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  1413. Event: Newexten
  1414. Privilege: dialplan,all
  1415. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1416. ChannelState: 4
  1417. ChannelStateDesc: Ring
  1418. CallerIDNum: 14165390110
  1419. CallerIDName: TORONTO      ON
  1420. ConnectedLineNum: <unknown>
  1421. ConnectedLineName: <unknown>
  1422. Language: en
  1423. AccountCode:
  1424. Context: app-blacklist-check
  1425. Exten: s
  1426. Priority: 3
  1427. Uniqueid: 1641963309.0
  1428. Linkedid: 1641963309.0
  1429. Extension: s
  1430. Application: Return
  1431. AppData:
  1432.  
  1433.  
  1434. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  1435. Event: VarSet
  1436. Privilege: dialplan,all
  1437. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1438. ChannelState: 4
  1439. ChannelStateDesc: Ring
  1440. CallerIDNum: 14165390110
  1441. CallerIDName: TORONTO      ON
  1442. ConnectedLineNum: <unknown>
  1443. ConnectedLineName: <unknown>
  1444. Language: en
  1445. AccountCode:
  1446. Context: app-blacklist-check
  1447. Exten: s
  1448. Priority: 3
  1449. Uniqueid: 1641963309.0
  1450. Linkedid: 1641963309.0
  1451. Variable: ARGC
  1452. Value:
  1453.  
  1454.  
  1455. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  1456. Event: VarSet
  1457. Privilege: dialplan,all
  1458. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1459. ChannelState: 4
  1460. ChannelStateDesc: Ring
  1461. CallerIDNum: 14165390110
  1462. CallerIDName: TORONTO      ON
  1463. ConnectedLineNum: <unknown>
  1464. ConnectedLineName: <unknown>
  1465. Language: en
  1466. AccountCode:
  1467. Context: app-blacklist-check
  1468. Exten: s
  1469. Priority: 3
  1470. Uniqueid: 1641963309.0
  1471. Linkedid: 1641963309.0
  1472. Variable: GOSUB_RETVAL
  1473. Value:
  1474.  
  1475.  
  1476. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  1477. Event: Newexten
  1478. Privilege: dialplan,all
  1479. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1480. ChannelState: 4
  1481. ChannelStateDesc: Ring
  1482. CallerIDNum: 14165390110
  1483. CallerIDName: TORONTO      ON
  1484. ConnectedLineNum: <unknown>
  1485. ConnectedLineName: <unknown>
  1486. Language: en
  1487. AccountCode:
  1488. Context: app-blacklist-check
  1489. Exten: s
  1490. Priority: 1
  1491. Uniqueid: 1641963309.0
  1492. Linkedid: 1641963309.0
  1493. Extension: s
  1494. Application: GotoIf
  1495. AppData: 0?blacklisted
  1496.  
  1497.  
  1498. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  1499. Event: Newexten
  1500. Privilege: dialplan,all
  1501. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1502. ChannelState: 4
  1503. ChannelStateDesc: Ring
  1504. CallerIDNum: 14165390110
  1505. CallerIDName: TORONTO      ON
  1506. ConnectedLineNum: <unknown>
  1507. ConnectedLineName: <unknown>
  1508. Language: en
  1509. AccountCode:
  1510. Context: from-pstn
  1511. Exten: 4166404500
  1512. Priority: 6
  1513. Uniqueid: 1641963309.0
  1514. Linkedid: 1641963309.0
  1515. Extension: 4166404500
  1516. Application: Set
  1517. AppData: CDR(did)=4166404500
  1518.  
  1519.  
  1520. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  1521. Event: Newexten
  1522. Privilege: dialplan,all
  1523. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1524. ChannelState: 4
  1525. ChannelStateDesc: Ring
  1526. CallerIDNum: 14165390110
  1527. CallerIDName: TORONTO      ON
  1528. ConnectedLineNum: <unknown>
  1529. ConnectedLineName: <unknown>
  1530. Language: en
  1531. AccountCode:
  1532. Context: app-blacklist-check
  1533. Exten: s
  1534. Priority: 2
  1535. Uniqueid: 1641963309.0
  1536. Linkedid: 1641963309.0
  1537. Extension: s
  1538. Application: Set
  1539. AppData: CALLED_BLACKLIST=1
  1540.  
  1541.  
  1542. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  1543. Event: VarSet
  1544. Privilege: dialplan,all
  1545. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1546. ChannelState: 4
  1547. ChannelStateDesc: Ring
  1548. CallerIDNum: 14165390110
  1549. CallerIDName: TORONTO      ON
  1550. ConnectedLineNum: <unknown>
  1551. ConnectedLineName: <unknown>
  1552. Language: en
  1553. AccountCode:
  1554. Context: app-blacklist-check
  1555. Exten: s
  1556. Priority: 2
  1557. Uniqueid: 1641963309.0
  1558. Linkedid: 1641963309.0
  1559. Variable: CALLED_BLACKLIST
  1560. Value: 1
  1561.  
  1562.  
  1563. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  1564. Event: Newexten
  1565. Privilege: dialplan,all
  1566. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1567. ChannelState: 4
  1568. ChannelStateDesc: Ring
  1569. CallerIDNum: 14165390110
  1570. CallerIDName: TORONTO      ON
  1571. ConnectedLineNum: <unknown>
  1572. ConnectedLineName: <unknown>
  1573. Language: en
  1574. AccountCode:
  1575. Context: app-blacklist-check
  1576. Exten: s
  1577. Priority: 3
  1578. Uniqueid: 1641963309.0
  1579. Linkedid: 1641963309.0
  1580. Extension: s
  1581. Application: Return
  1582. AppData:
  1583.  
  1584.  
  1585. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  1586. Event: VarSet
  1587. Privilege: dialplan,all
  1588. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1589. ChannelState: 4
  1590. ChannelStateDesc: Ring
  1591. CallerIDNum: 14165390110
  1592. CallerIDName: TORONTO      ON
  1593. ConnectedLineNum: <unknown>
  1594. ConnectedLineName: <unknown>
  1595. Language: en
  1596. AccountCode:
  1597. Context: app-blacklist-check
  1598. Exten: s
  1599. Priority: 3
  1600. Uniqueid: 1641963309.0
  1601. Linkedid: 1641963309.0
  1602. Variable: ARGC
  1603. Value:
  1604.  
  1605.  
  1606. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  1607. Event: VarSet
  1608. Privilege: dialplan,all
  1609. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1610. ChannelState: 4
  1611. ChannelStateDesc: Ring
  1612. CallerIDNum: 14165390110
  1613. CallerIDName: TORONTO      ON
  1614. ConnectedLineNum: <unknown>
  1615. ConnectedLineName: <unknown>
  1616. Language: en
  1617. AccountCode:
  1618. Context: app-blacklist-check
  1619. Exten: s
  1620. Priority: 3
  1621. Uniqueid: 1641963309.0
  1622. Linkedid: 1641963309.0
  1623. Variable: GOSUB_RETVAL
  1624. Value:
  1625.  
  1626.  
  1627. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_variables.c:374 ast_str_retrieve_variable: Result of 'BLDEST' is NULL
  1628. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  1629. Event: Newexten
  1630. Privilege: dialplan,all
  1631. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1632. ChannelState: 4
  1633. ChannelStateDesc: Ring
  1634. CallerIDNum: 14165390110
  1635. CallerIDName: TORONTO      ON
  1636. ConnectedLineNum: <unknown>
  1637. ConnectedLineName: <unknown>
  1638. Language: en
  1639. AccountCode:
  1640. Context: from-pstn
  1641. Exten: 4166404500
  1642. Priority: 6
  1643. Uniqueid: 1641963309.0
  1644. Linkedid: 1641963309.0
  1645. Extension: 4166404500
  1646. Application: Set
  1647. AppData: CDR(did)=4166404500
  1648.  
  1649.  
  1650. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_variables.c:759 pbx_substitute_variables_helper_full: Function LEN() result is '0'
  1651. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_variables.c:374 ast_str_retrieve_variable: Result of 'BLDEST' is NULL
  1652. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'GotoIf'
  1653.     -- Executing [4166404500@from-pstn:7] GotoIf("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "0?") in new stack
  1654. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_builtins.c:1255 pbx_builtin_gotoif: Not taking any branch
  1655. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_variables.c:759 pbx_substitute_variables_helper_full: Function CALLERID(name) result is 'TORONTO      ON'
  1656. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_variables.c:823 pbx_substitute_variables_helper_full: Expression result is '0'
  1657. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_variables.c:759 pbx_substitute_variables_helper_full: Function CALLERID(num) result is '14165390110'
  1658. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'ExecIf'
  1659.     -- Executing [4166404500@from-pstn:8] ExecIf("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "0 ?Set(CALLERID(name)=14165390110)") in new stack
  1660. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  1661.     -- Executing [4166404500@from-pstn:9] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "__MOHCLASS=") in new stack
  1662. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  1663.     -- Executing [4166404500@from-pstn:10] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "__REVERSAL_REJECT=FALSE") in new stack
  1664. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_variables.c:377 ast_str_retrieve_variable: Result of 'REVERSAL_REJECT' is 'FALSE'
  1665. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_variables.c:823 pbx_substitute_variables_helper_full: Expression result is '1'
  1666. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'GotoIf'
  1667.     -- Executing [4166404500@from-pstn:11] GotoIf("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "1?post-reverse-charge") in new stack
  1668.     -- Goto (from-pstn,4166404500,13)
  1669. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'NoOp'
  1670.     -- Executing [4166404500@from-pstn:13] NoOp("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "") in new stack
  1671. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_variables.c:759 pbx_substitute_variables_helper_full: Function CALLERID(name-pres) result is 'allowed_not_screened'
  1672. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  1673.     -- Executing [4166404500@from-pstn:14] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
  1674. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_variables.c:759 pbx_substitute_variables_helper_full: Function CALLERID(num-pres) result is 'allowed_not_screened'
  1675. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  1676.     -- Executing [4166404500@from-pstn:15] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
  1677. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  1678.     -- Executing [4166404500@from-pstn:16] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "CALLERID(name-pres)=allowed_not_screened") in new stack
  1679. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  1680.     -- Executing [4166404500@from-pstn:17] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "CALLERID(num-pres)=allowed_not_screened") in new stack
  1681. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'NoOp'
  1682.     -- Executing [4166404500@from-pstn:18] NoOp("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "CallerID Entry Point") in new stack
  1683. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  1684.     -- Executing [4166404500@from-pstn:19] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "__CRM_DIRECTION=INBOUND") in new stack
  1685. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_variables.c:759 pbx_substitute_variables_helper_full: Function CALLERID(num) result is '14165390110'
  1686. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  1687.     -- Executing [4166404500@from-pstn:20] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "__CRM_SOURCE=14165390110") in new stack
  1688. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx_variables.c:759 pbx_substitute_variables_helper_full: Function CHANNEL(LINKEDID) result is '1641963309.0'
  1689. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  1690.     -- Executing [4166404500@from-pstn:21] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "__CRM_LINKEDID=1641963309.0") in new stack
  1691. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'AGI'
  1692.     -- Executing [4166404500@from-pstn:22] AGI("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "agi://127.0.0.1/sangomacrm.agi,true") in new stack
  1693. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '127.0.0.1' into...
  1694. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '127.0.0.1' and port ''.
  1695. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1696. Event: Newexten
  1697. Privilege: dialplan,all
  1698. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1699. ChannelState: 4
  1700. ChannelStateDesc: Ring
  1701. CallerIDNum: 14165390110
  1702. CallerIDName: TORONTO      ON
  1703. ConnectedLineNum: <unknown>
  1704. ConnectedLineName: <unknown>
  1705. Language: en
  1706. AccountCode:
  1707. Context: from-pstn
  1708. Exten: 4166404500
  1709. Priority: 7
  1710. Uniqueid: 1641963309.0
  1711. Linkedid: 1641963309.0
  1712. Extension: 4166404500
  1713. Application: GotoIf
  1714. AppData: 0?
  1715.  
  1716.  
  1717. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1718. Event: Newexten
  1719. Privilege: dialplan,all
  1720. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1721. ChannelState: 4
  1722. ChannelStateDesc: Ring
  1723. CallerIDNum: 14165390110
  1724. CallerIDName: TORONTO      ON
  1725. ConnectedLineNum: <unknown>
  1726. ConnectedLineName: <unknown>
  1727. Language: en
  1728. AccountCode:
  1729. Context: from-pstn
  1730. Exten: 4166404500
  1731. Priority: 8
  1732. Uniqueid: 1641963309.0
  1733. Linkedid: 1641963309.0
  1734. Extension: 4166404500
  1735. Application: ExecIf
  1736. AppData: 0 ?Set(CALLERID(name)=14165390110)
  1737.  
  1738.  
  1739. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1740. Event: Newexten
  1741. Privilege: dialplan,all
  1742. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1743. ChannelState: 4
  1744. ChannelStateDesc: Ring
  1745. CallerIDNum: 14165390110
  1746. CallerIDName: TORONTO      ON
  1747. ConnectedLineNum: <unknown>
  1748. ConnectedLineName: <unknown>
  1749. Language: en
  1750. AccountCode:
  1751. Context: from-pstn
  1752. Exten: 4166404500
  1753. Priority: 9
  1754. Uniqueid: 1641963309.0
  1755. Linkedid: 1641963309.0
  1756. Extension: 4166404500
  1757. Application: Set
  1758. AppData: __MOHCLASS=
  1759.  
  1760.  
  1761. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1762. Event: VarSet
  1763. Privilege: dialplan,all
  1764. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1765. ChannelState: 4
  1766. ChannelStateDesc: Ring
  1767. CallerIDNum: 14165390110
  1768. CallerIDName: TORONTO      ON
  1769. ConnectedLineNum: <unknown>
  1770. ConnectedLineName: <unknown>
  1771. Language: en
  1772. AccountCode:
  1773. Context: from-pstn
  1774. Exten: 4166404500
  1775. Priority: 9
  1776. Uniqueid: 1641963309.0
  1777. Linkedid: 1641963309.0
  1778. Variable: __MOHCLASS
  1779. Value:
  1780.  
  1781.  
  1782. [2022-01-11 23:55:09] DEBUG[3572][C-00000001]: res_agi.c:2121 launch_netscript: Wow, connected!
  1783. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1784. Event: Newexten
  1785. Privilege: dialplan,all
  1786. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1787. ChannelState: 4
  1788. ChannelStateDesc: Ring
  1789. CallerIDNum: 14165390110
  1790. CallerIDName: TORONTO      ON
  1791. ConnectedLineNum: <unknown>
  1792. ConnectedLineName: <unknown>
  1793. Language: en
  1794. AccountCode:
  1795. Context: from-pstn
  1796. Exten: 4166404500
  1797. Priority: 10
  1798. Uniqueid: 1641963309.0
  1799. Linkedid: 1641963309.0
  1800. Extension: 4166404500
  1801. Application: Set
  1802. AppData: __REVERSAL_REJECT=FALSE
  1803.  
  1804.  
  1805. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1806. Event: VarSet
  1807. Privilege: dialplan,all
  1808. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1809. ChannelState: 4
  1810. ChannelStateDesc: Ring
  1811. CallerIDNum: 14165390110
  1812. CallerIDName: TORONTO      ON
  1813. ConnectedLineNum: <unknown>
  1814. ConnectedLineName: <unknown>
  1815. Language: en
  1816. AccountCode:
  1817. Context: from-pstn
  1818. Exten: 4166404500
  1819. Priority: 10
  1820. Uniqueid: 1641963309.0
  1821. Linkedid: 1641963309.0
  1822. Variable: __REVERSAL_REJECT
  1823. Value: FALSE
  1824.  
  1825.  
  1826. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1827. Event: Newexten
  1828. Privilege: dialplan,all
  1829. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1830. ChannelState: 4
  1831. ChannelStateDesc: Ring
  1832. CallerIDNum: 14165390110
  1833. CallerIDName: TORONTO      ON
  1834. ConnectedLineNum: <unknown>
  1835. ConnectedLineName: <unknown>
  1836. Language: en
  1837. AccountCode:
  1838. Context: from-pstn
  1839. Exten: 4166404500
  1840. Priority: 11
  1841. Uniqueid: 1641963309.0
  1842. Linkedid: 1641963309.0
  1843. Extension: 4166404500
  1844. Application: GotoIf
  1845. AppData: 1?post-reverse-charge
  1846.  
  1847.  
  1848. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1849. Event: Newexten
  1850. Privilege: dialplan,all
  1851. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1852. ChannelState: 4
  1853. ChannelStateDesc: Ring
  1854. CallerIDNum: 14165390110
  1855. CallerIDName: TORONTO      ON
  1856. ConnectedLineNum: <unknown>
  1857. ConnectedLineName: <unknown>
  1858. Language: en
  1859. AccountCode:
  1860. Context: from-pstn
  1861. Exten: 4166404500
  1862. Priority: 13
  1863. Uniqueid: 1641963309.0
  1864. Linkedid: 1641963309.0
  1865. Extension: 4166404500
  1866. Application: NoOp
  1867. AppData:
  1868.  
  1869.  
  1870. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1871. Event: Newexten
  1872. Privilege: dialplan,all
  1873. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1874. ChannelState: 4
  1875. ChannelStateDesc: Ring
  1876. CallerIDNum: 14165390110
  1877. CallerIDName: TORONTO      ON
  1878. ConnectedLineNum: <unknown>
  1879. ConnectedLineName: <unknown>
  1880. Language: en
  1881. AccountCode:
  1882. Context: from-pstn
  1883. Exten: 4166404500
  1884. Priority: 14
  1885. Uniqueid: 1641963309.0
  1886. Linkedid: 1641963309.0
  1887. Extension: 4166404500
  1888. Application: Set
  1889. AppData: __CALLINGNAMEPRES_SV=allowed_not_screened
  1890.  
  1891.  
  1892. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1893. Event: VarSet
  1894. Privilege: dialplan,all
  1895. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1896. ChannelState: 4
  1897. ChannelStateDesc: Ring
  1898. CallerIDNum: 14165390110
  1899. CallerIDName: TORONTO      ON
  1900. ConnectedLineNum: <unknown>
  1901. ConnectedLineName: <unknown>
  1902. Language: en
  1903. AccountCode:
  1904. Context: from-pstn
  1905. Exten: 4166404500
  1906. Priority: 14
  1907. Uniqueid: 1641963309.0
  1908. Linkedid: 1641963309.0
  1909. Variable: __CALLINGNAMEPRES_SV
  1910. Value: allowed_not_screened
  1911.  
  1912.  
  1913. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1914. Event: Newexten
  1915. Privilege: dialplan,all
  1916. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1917. ChannelState: 4
  1918. ChannelStateDesc: Ring
  1919. CallerIDNum: 14165390110
  1920. CallerIDName: TORONTO      ON
  1921. ConnectedLineNum: <unknown>
  1922. ConnectedLineName: <unknown>
  1923. Language: en
  1924. AccountCode:
  1925. Context: from-pstn
  1926. Exten: 4166404500
  1927. Priority: 15
  1928. Uniqueid: 1641963309.0
  1929. Linkedid: 1641963309.0
  1930. Extension: 4166404500
  1931. Application: Set
  1932. AppData: __CALLINGNUMPRES_SV=allowed_not_screened
  1933.  
  1934.  
  1935. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1936. Event: VarSet
  1937. Privilege: dialplan,all
  1938. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1939. ChannelState: 4
  1940. ChannelStateDesc: Ring
  1941. CallerIDNum: 14165390110
  1942. CallerIDName: TORONTO      ON
  1943. ConnectedLineNum: <unknown>
  1944. ConnectedLineName: <unknown>
  1945. Language: en
  1946. AccountCode:
  1947. Context: from-pstn
  1948. Exten: 4166404500
  1949. Priority: 15
  1950. Uniqueid: 1641963309.0
  1951. Linkedid: 1641963309.0
  1952. Variable: __CALLINGNUMPRES_SV
  1953. Value: allowed_not_screened
  1954.  
  1955.  
  1956. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1957. Event: Newexten
  1958. Privilege: dialplan,all
  1959. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1960. ChannelState: 4
  1961. ChannelStateDesc: Ring
  1962. CallerIDNum: 14165390110
  1963. CallerIDName: TORONTO      ON
  1964. ConnectedLineNum: <unknown>
  1965. ConnectedLineName: <unknown>
  1966. Language: en
  1967. AccountCode:
  1968. Context: from-pstn
  1969. Exten: 4166404500
  1970. Priority: 16
  1971. Uniqueid: 1641963309.0
  1972. Linkedid: 1641963309.0
  1973. Extension: 4166404500
  1974. Application: Set
  1975. AppData: CALLERID(name-pres)=allowed_not_screened
  1976.  
  1977.  
  1978. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  1979. Event: Newexten
  1980. Privilege: dialplan,all
  1981. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  1982. ChannelState: 4
  1983. ChannelStateDesc: Ring
  1984. CallerIDNum: 14165390110
  1985. CallerIDName: TORONTO      ON
  1986. ConnectedLineNum: <unknown>
  1987. ConnectedLineName: <unknown>
  1988. Language: en
  1989. AccountCode:
  1990. Context: from-pstn
  1991. Exten: 4166404500
  1992. Priority: 17
  1993. Uniqueid: 1641963309.0
  1994. Linkedid: 1641963309.0
  1995. Extension: 4166404500
  1996. Application: Set
  1997. AppData: CALLERID(num-pres)=allowed_not_screened
  1998.  
  1999.  
  2000. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  2001. Event: Newexten
  2002. Privilege: dialplan,all
  2003. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2004. ChannelState: 4
  2005. ChannelStateDesc: Ring
  2006. CallerIDNum: 14165390110
  2007. CallerIDName: TORONTO      ON
  2008. ConnectedLineNum: <unknown>
  2009. ConnectedLineName: <unknown>
  2010. Language: en
  2011. AccountCode:
  2012. Context: from-pstn
  2013. Exten: 4166404500
  2014. Priority: 18
  2015. Uniqueid: 1641963309.0
  2016. Linkedid: 1641963309.0
  2017. Extension: 4166404500
  2018. Application: NoOp
  2019. AppData: CallerID Entry Point
  2020.  
  2021.  
  2022. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  2023. Event: Newexten
  2024. Privilege: dialplan,all
  2025. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2026. ChannelState: 4
  2027. ChannelStateDesc: Ring
  2028. CallerIDNum: 14165390110
  2029. CallerIDName: TORONTO      ON
  2030. ConnectedLineNum: <unknown>
  2031. ConnectedLineName: <unknown>
  2032. Language: en
  2033. AccountCode:
  2034. Context: from-pstn
  2035. Exten: 4166404500
  2036. Priority: 19
  2037. Uniqueid: 1641963309.0
  2038. Linkedid: 1641963309.0
  2039. Extension: 4166404500
  2040. Application: Set
  2041. AppData: __CRM_DIRECTION=INBOUND
  2042.  
  2043.  
  2044. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  2045. Event: VarSet
  2046. Privilege: dialplan,all
  2047. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2048. ChannelState: 4
  2049. ChannelStateDesc: Ring
  2050. CallerIDNum: 14165390110
  2051. CallerIDName: TORONTO      ON
  2052. ConnectedLineNum: <unknown>
  2053. ConnectedLineName: <unknown>
  2054. Language: en
  2055. AccountCode:
  2056. Context: from-pstn
  2057. Exten: 4166404500
  2058. Priority: 19
  2059. Uniqueid: 1641963309.0
  2060. Linkedid: 1641963309.0
  2061. Variable: __CRM_DIRECTION
  2062. Value: INBOUND
  2063.  
  2064.  
  2065. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  2066. Event: Newexten
  2067. Privilege: dialplan,all
  2068. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2069. ChannelState: 4
  2070. ChannelStateDesc: Ring
  2071. CallerIDNum: 14165390110
  2072. CallerIDName: TORONTO      ON
  2073. ConnectedLineNum: <unknown>
  2074. ConnectedLineName: <unknown>
  2075. Language: en
  2076. AccountCode:
  2077. Context: from-pstn
  2078. Exten: 4166404500
  2079. Priority: 20
  2080. Uniqueid: 1641963309.0
  2081. Linkedid: 1641963309.0
  2082. Extension: 4166404500
  2083. Application: Set
  2084. AppData: __CRM_SOURCE=14165390110
  2085.  
  2086.  
  2087. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  2088. Event: VarSet
  2089. Privilege: dialplan,all
  2090. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2091. ChannelState: 4
  2092. ChannelStateDesc: Ring
  2093. CallerIDNum: 14165390110
  2094. CallerIDName: TORONTO      ON
  2095. ConnectedLineNum: <unknown>
  2096. ConnectedLineName: <unknown>
  2097. Language: en
  2098. AccountCode:
  2099. Context: from-pstn
  2100. Exten: 4166404500
  2101. Priority: 20
  2102. Uniqueid: 1641963309.0
  2103. Linkedid: 1641963309.0
  2104. Variable: __CRM_SOURCE
  2105. Value: 14165390110
  2106.  
  2107.  
  2108. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  2109. Event: Newexten
  2110. Privilege: dialplan,all
  2111. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2112. ChannelState: 4
  2113. ChannelStateDesc: Ring
  2114. CallerIDNum: 14165390110
  2115. CallerIDName: TORONTO      ON
  2116. ConnectedLineNum: <unknown>
  2117. ConnectedLineName: <unknown>
  2118. Language: en
  2119. AccountCode:
  2120. Context: from-pstn
  2121. Exten: 4166404500
  2122. Priority: 21
  2123. Uniqueid: 1641963309.0
  2124. Linkedid: 1641963309.0
  2125. Extension: 4166404500
  2126. Application: Set
  2127. AppData: __CRM_LINKEDID=1641963309.0
  2128.  
  2129.  
  2130. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  2131. Event: VarSet
  2132. Privilege: dialplan,all
  2133. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2134. ChannelState: 4
  2135. ChannelStateDesc: Ring
  2136. CallerIDNum: 14165390110
  2137. CallerIDName: TORONTO      ON
  2138. ConnectedLineNum: <unknown>
  2139. ConnectedLineName: <unknown>
  2140. Language: en
  2141. AccountCode:
  2142. Context: from-pstn
  2143. Exten: 4166404500
  2144. Priority: 21
  2145. Uniqueid: 1641963309.0
  2146. Linkedid: 1641963309.0
  2147. Variable: __CRM_LINKEDID
  2148. Value: 1641963309.0
  2149.  
  2150.  
  2151. [2022-01-11 23:55:09] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  2152. Event: Newexten
  2153. Privilege: dialplan,all
  2154. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2155. ChannelState: 4
  2156. ChannelStateDesc: Ring
  2157. CallerIDNum: 14165390110
  2158. CallerIDName: TORONTO      ON
  2159. ConnectedLineNum: <unknown>
  2160. ConnectedLineName: <unknown>
  2161. Language: en
  2162. AccountCode:
  2163. Context: from-pstn
  2164. Exten: 4166404500
  2165. Priority: 22
  2166. Uniqueid: 1641963309.0
  2167. Linkedid: 1641963309.0
  2168. Extension: 4166404500
  2169. Application: AGI
  2170. AppData: agi://127.0.0.1/sangomacrm.agi,true
  2171.  
  2172.  
  2173. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2174. Event: Newexten
  2175. Privilege: dialplan,all
  2176. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2177. ChannelState: 4
  2178. ChannelStateDesc: Ring
  2179. CallerIDNum: 14165390110
  2180. CallerIDName: TORONTO      ON
  2181. ConnectedLineNum: <unknown>
  2182. ConnectedLineName: <unknown>
  2183. Language: en
  2184. AccountCode:
  2185. Context: from-pstn
  2186. Exten: 4166404500
  2187. Priority: 7
  2188. Uniqueid: 1641963309.0
  2189. Linkedid: 1641963309.0
  2190. Extension: 4166404500
  2191. Application: GotoIf
  2192. AppData: 0?
  2193.  
  2194.  
  2195. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2196. Event: Newexten
  2197. Privilege: dialplan,all
  2198. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2199. ChannelState: 4
  2200. ChannelStateDesc: Ring
  2201. CallerIDNum: 14165390110
  2202. CallerIDName: TORONTO      ON
  2203. ConnectedLineNum: <unknown>
  2204. ConnectedLineName: <unknown>
  2205. Language: en
  2206. AccountCode:
  2207. Context: from-pstn
  2208. Exten: 4166404500
  2209. Priority: 8
  2210. Uniqueid: 1641963309.0
  2211. Linkedid: 1641963309.0
  2212. Extension: 4166404500
  2213. Application: ExecIf
  2214. AppData: 0 ?Set(CALLERID(name)=14165390110)
  2215.  
  2216.  
  2217. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2218. Event: Newexten
  2219. Privilege: dialplan,all
  2220. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2221. ChannelState: 4
  2222. ChannelStateDesc: Ring
  2223. CallerIDNum: 14165390110
  2224. CallerIDName: TORONTO      ON
  2225. ConnectedLineNum: <unknown>
  2226. ConnectedLineName: <unknown>
  2227. Language: en
  2228. AccountCode:
  2229. Context: from-pstn
  2230. Exten: 4166404500
  2231. Priority: 9
  2232. Uniqueid: 1641963309.0
  2233. Linkedid: 1641963309.0
  2234. Extension: 4166404500
  2235. Application: Set
  2236. AppData: __MOHCLASS=
  2237.  
  2238.  
  2239. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2240. Event: VarSet
  2241. Privilege: dialplan,all
  2242. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2243. ChannelState: 4
  2244. ChannelStateDesc: Ring
  2245. CallerIDNum: 14165390110
  2246. CallerIDName: TORONTO      ON
  2247. ConnectedLineNum: <unknown>
  2248. ConnectedLineName: <unknown>
  2249. Language: en
  2250. AccountCode:
  2251. Context: from-pstn
  2252. Exten: 4166404500
  2253. Priority: 9
  2254. Uniqueid: 1641963309.0
  2255. Linkedid: 1641963309.0
  2256. Variable: __MOHCLASS
  2257. Value:
  2258.  
  2259.  
  2260. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2261. Event: Newexten
  2262. Privilege: dialplan,all
  2263. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2264. ChannelState: 4
  2265. ChannelStateDesc: Ring
  2266. CallerIDNum: 14165390110
  2267. CallerIDName: TORONTO      ON
  2268. ConnectedLineNum: <unknown>
  2269. ConnectedLineName: <unknown>
  2270. Language: en
  2271. AccountCode:
  2272. Context: from-pstn
  2273. Exten: 4166404500
  2274. Priority: 10
  2275. Uniqueid: 1641963309.0
  2276. Linkedid: 1641963309.0
  2277. Extension: 4166404500
  2278. Application: Set
  2279. AppData: __REVERSAL_REJECT=FALSE
  2280.  
  2281.  
  2282. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2283. Event: VarSet
  2284. Privilege: dialplan,all
  2285. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2286. ChannelState: 4
  2287. ChannelStateDesc: Ring
  2288. CallerIDNum: 14165390110
  2289. CallerIDName: TORONTO      ON
  2290. ConnectedLineNum: <unknown>
  2291. ConnectedLineName: <unknown>
  2292. Language: en
  2293. AccountCode:
  2294. Context: from-pstn
  2295. Exten: 4166404500
  2296. Priority: 10
  2297. Uniqueid: 1641963309.0
  2298. Linkedid: 1641963309.0
  2299. Variable: __REVERSAL_REJECT
  2300. Value: FALSE
  2301.  
  2302.  
  2303. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2304. Event: Newexten
  2305. Privilege: dialplan,all
  2306. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2307. ChannelState: 4
  2308. ChannelStateDesc: Ring
  2309. CallerIDNum: 14165390110
  2310. CallerIDName: TORONTO      ON
  2311. ConnectedLineNum: <unknown>
  2312. ConnectedLineName: <unknown>
  2313. Language: en
  2314. AccountCode:
  2315. Context: from-pstn
  2316. Exten: 4166404500
  2317. Priority: 11
  2318. Uniqueid: 1641963309.0
  2319. Linkedid: 1641963309.0
  2320. Extension: 4166404500
  2321. Application: GotoIf
  2322. AppData: 1?post-reverse-charge
  2323.  
  2324.  
  2325. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2326. Event: Newexten
  2327. Privilege: dialplan,all
  2328. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2329. ChannelState: 4
  2330. ChannelStateDesc: Ring
  2331. CallerIDNum: 14165390110
  2332. CallerIDName: TORONTO      ON
  2333. ConnectedLineNum: <unknown>
  2334. ConnectedLineName: <unknown>
  2335. Language: en
  2336. AccountCode:
  2337. Context: from-pstn
  2338. Exten: 4166404500
  2339. Priority: 13
  2340. Uniqueid: 1641963309.0
  2341. Linkedid: 1641963309.0
  2342. Extension: 4166404500
  2343. Application: NoOp
  2344. AppData:
  2345.  
  2346.  
  2347. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2348. Event: Newexten
  2349. Privilege: dialplan,all
  2350. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2351. ChannelState: 4
  2352. ChannelStateDesc: Ring
  2353. CallerIDNum: 14165390110
  2354. CallerIDName: TORONTO      ON
  2355. ConnectedLineNum: <unknown>
  2356. ConnectedLineName: <unknown>
  2357. Language: en
  2358. AccountCode:
  2359. Context: from-pstn
  2360. Exten: 4166404500
  2361. Priority: 14
  2362. Uniqueid: 1641963309.0
  2363. Linkedid: 1641963309.0
  2364. Extension: 4166404500
  2365. Application: Set
  2366. AppData: __CALLINGNAMEPRES_SV=allowed_not_screened
  2367.  
  2368.  
  2369. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2370. Event: VarSet
  2371. Privilege: dialplan,all
  2372. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2373. ChannelState: 4
  2374. ChannelStateDesc: Ring
  2375. CallerIDNum: 14165390110
  2376. CallerIDName: TORONTO      ON
  2377. ConnectedLineNum: <unknown>
  2378. ConnectedLineName: <unknown>
  2379. Language: en
  2380. AccountCode:
  2381. Context: from-pstn
  2382. Exten: 4166404500
  2383. Priority: 14
  2384. Uniqueid: 1641963309.0
  2385. Linkedid: 1641963309.0
  2386. Variable: __CALLINGNAMEPRES_SV
  2387. Value: allowed_not_screened
  2388.  
  2389.  
  2390. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2391. Event: Newexten
  2392. Privilege: dialplan,all
  2393. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2394. ChannelState: 4
  2395. ChannelStateDesc: Ring
  2396. CallerIDNum: 14165390110
  2397. CallerIDName: TORONTO      ON
  2398. ConnectedLineNum: <unknown>
  2399. ConnectedLineName: <unknown>
  2400. Language: en
  2401. AccountCode:
  2402. Context: from-pstn
  2403. Exten: 4166404500
  2404. Priority: 15
  2405. Uniqueid: 1641963309.0
  2406. Linkedid: 1641963309.0
  2407. Extension: 4166404500
  2408. Application: Set
  2409. AppData: __CALLINGNUMPRES_SV=allowed_not_screened
  2410.  
  2411.  
  2412. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2413. Event: VarSet
  2414. Privilege: dialplan,all
  2415. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2416. ChannelState: 4
  2417. ChannelStateDesc: Ring
  2418. CallerIDNum: 14165390110
  2419. CallerIDName: TORONTO      ON
  2420. ConnectedLineNum: <unknown>
  2421. ConnectedLineName: <unknown>
  2422. Language: en
  2423. AccountCode:
  2424. Context: from-pstn
  2425. Exten: 4166404500
  2426. Priority: 15
  2427. Uniqueid: 1641963309.0
  2428. Linkedid: 1641963309.0
  2429. Variable: __CALLINGNUMPRES_SV
  2430. Value: allowed_not_screened
  2431.  
  2432.  
  2433. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2434. Event: Newexten
  2435. Privilege: dialplan,all
  2436. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2437. ChannelState: 4
  2438. ChannelStateDesc: Ring
  2439. CallerIDNum: 14165390110
  2440. CallerIDName: TORONTO      ON
  2441. ConnectedLineNum: <unknown>
  2442. ConnectedLineName: <unknown>
  2443. Language: en
  2444. AccountCode:
  2445. Context: from-pstn
  2446. Exten: 4166404500
  2447. Priority: 16
  2448. Uniqueid: 1641963309.0
  2449. Linkedid: 1641963309.0
  2450. Extension: 4166404500
  2451. Application: Set
  2452. AppData: CALLERID(name-pres)=allowed_not_screened
  2453.  
  2454.  
  2455. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2456. Event: Newexten
  2457. Privilege: dialplan,all
  2458. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2459. ChannelState: 4
  2460. ChannelStateDesc: Ring
  2461. CallerIDNum: 14165390110
  2462. CallerIDName: TORONTO      ON
  2463. ConnectedLineNum: <unknown>
  2464. ConnectedLineName: <unknown>
  2465. Language: en
  2466. AccountCode:
  2467. Context: from-pstn
  2468. Exten: 4166404500
  2469. Priority: 17
  2470. Uniqueid: 1641963309.0
  2471. Linkedid: 1641963309.0
  2472. Extension: 4166404500
  2473. Application: Set
  2474. AppData: CALLERID(num-pres)=allowed_not_screened
  2475.  
  2476.  
  2477. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2478. Event: Newexten
  2479. Privilege: dialplan,all
  2480. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2481. ChannelState: 4
  2482. ChannelStateDesc: Ring
  2483. CallerIDNum: 14165390110
  2484. CallerIDName: TORONTO      ON
  2485. ConnectedLineNum: <unknown>
  2486. ConnectedLineName: <unknown>
  2487. Language: en
  2488. AccountCode:
  2489. Context: from-pstn
  2490. Exten: 4166404500
  2491. Priority: 18
  2492. Uniqueid: 1641963309.0
  2493. Linkedid: 1641963309.0
  2494. Extension: 4166404500
  2495. Application: NoOp
  2496. AppData: CallerID Entry Point
  2497.  
  2498.  
  2499. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2500. Event: Newexten
  2501. Privilege: dialplan,all
  2502. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2503. ChannelState: 4
  2504. ChannelStateDesc: Ring
  2505. CallerIDNum: 14165390110
  2506. CallerIDName: TORONTO      ON
  2507. ConnectedLineNum: <unknown>
  2508. ConnectedLineName: <unknown>
  2509. Language: en
  2510. AccountCode:
  2511. Context: from-pstn
  2512. Exten: 4166404500
  2513. Priority: 19
  2514. Uniqueid: 1641963309.0
  2515. Linkedid: 1641963309.0
  2516. Extension: 4166404500
  2517. Application: Set
  2518. AppData: __CRM_DIRECTION=INBOUND
  2519.  
  2520.  
  2521. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2522. Event: VarSet
  2523. Privilege: dialplan,all
  2524. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2525. ChannelState: 4
  2526. ChannelStateDesc: Ring
  2527. CallerIDNum: 14165390110
  2528. CallerIDName: TORONTO      ON
  2529. ConnectedLineNum: <unknown>
  2530. ConnectedLineName: <unknown>
  2531. Language: en
  2532. AccountCode:
  2533. Context: from-pstn
  2534. Exten: 4166404500
  2535. Priority: 19
  2536. Uniqueid: 1641963309.0
  2537. Linkedid: 1641963309.0
  2538. Variable: __CRM_DIRECTION
  2539. Value: INBOUND
  2540.  
  2541.  
  2542. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2543. Event: Newexten
  2544. Privilege: dialplan,all
  2545. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2546. ChannelState: 4
  2547. ChannelStateDesc: Ring
  2548. CallerIDNum: 14165390110
  2549. CallerIDName: TORONTO      ON
  2550. ConnectedLineNum: <unknown>
  2551. ConnectedLineName: <unknown>
  2552. Language: en
  2553. AccountCode:
  2554. Context: from-pstn
  2555. Exten: 4166404500
  2556. Priority: 20
  2557. Uniqueid: 1641963309.0
  2558. Linkedid: 1641963309.0
  2559. Extension: 4166404500
  2560. Application: Set
  2561. AppData: __CRM_SOURCE=14165390110
  2562.  
  2563.  
  2564. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2565. Event: VarSet
  2566. Privilege: dialplan,all
  2567. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2568. ChannelState: 4
  2569. ChannelStateDesc: Ring
  2570. CallerIDNum: 14165390110
  2571. CallerIDName: TORONTO      ON
  2572. ConnectedLineNum: <unknown>
  2573. ConnectedLineName: <unknown>
  2574. Language: en
  2575. AccountCode:
  2576. Context: from-pstn
  2577. Exten: 4166404500
  2578. Priority: 20
  2579. Uniqueid: 1641963309.0
  2580. Linkedid: 1641963309.0
  2581. Variable: __CRM_SOURCE
  2582. Value: 14165390110
  2583.  
  2584.  
  2585. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2586. Event: Newexten
  2587. Privilege: dialplan,all
  2588. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2589. ChannelState: 4
  2590. ChannelStateDesc: Ring
  2591. CallerIDNum: 14165390110
  2592. CallerIDName: TORONTO      ON
  2593. ConnectedLineNum: <unknown>
  2594. ConnectedLineName: <unknown>
  2595. Language: en
  2596. AccountCode:
  2597. Context: from-pstn
  2598. Exten: 4166404500
  2599. Priority: 21
  2600. Uniqueid: 1641963309.0
  2601. Linkedid: 1641963309.0
  2602. Extension: 4166404500
  2603. Application: Set
  2604. AppData: __CRM_LINKEDID=1641963309.0
  2605.  
  2606.  
  2607. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2608. Event: VarSet
  2609. Privilege: dialplan,all
  2610. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2611. ChannelState: 4
  2612. ChannelStateDesc: Ring
  2613. CallerIDNum: 14165390110
  2614. CallerIDName: TORONTO      ON
  2615. ConnectedLineNum: <unknown>
  2616. ConnectedLineName: <unknown>
  2617. Language: en
  2618. AccountCode:
  2619. Context: from-pstn
  2620. Exten: 4166404500
  2621. Priority: 21
  2622. Uniqueid: 1641963309.0
  2623. Linkedid: 1641963309.0
  2624. Variable: __CRM_LINKEDID
  2625. Value: 1641963309.0
  2626.  
  2627.  
  2628. [2022-01-11 23:55:09] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  2629. Event: Newexten
  2630. Privilege: dialplan,all
  2631. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2632. ChannelState: 4
  2633. ChannelStateDesc: Ring
  2634. CallerIDNum: 14165390110
  2635. CallerIDName: TORONTO      ON
  2636. ConnectedLineNum: <unknown>
  2637. ConnectedLineName: <unknown>
  2638. Language: en
  2639. AccountCode:
  2640. Context: from-pstn
  2641. Exten: 4166404500
  2642. Priority: 22
  2643. Uniqueid: 1641963309.0
  2644. Linkedid: 1641963309.0
  2645. Extension: 4166404500
  2646. Application: AGI
  2647. AppData: agi://127.0.0.1/sangomacrm.agi,true
  2648.  
  2649.  
  2650. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2651. Event: Newexten
  2652. Privilege: dialplan,all
  2653. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2654. ChannelState: 4
  2655. ChannelStateDesc: Ring
  2656. CallerIDNum: 14165390110
  2657. CallerIDName: TORONTO      ON
  2658. ConnectedLineNum: <unknown>
  2659. ConnectedLineName: <unknown>
  2660. Language: en
  2661. AccountCode:
  2662. Context: from-pstn
  2663. Exten: 4166404500
  2664. Priority: 7
  2665. Uniqueid: 1641963309.0
  2666. Linkedid: 1641963309.0
  2667. Extension: 4166404500
  2668. Application: GotoIf
  2669. AppData: 0?
  2670.  
  2671.  
  2672. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2673. Event: Newexten
  2674. Privilege: dialplan,all
  2675. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2676. ChannelState: 4
  2677. ChannelStateDesc: Ring
  2678. CallerIDNum: 14165390110
  2679. CallerIDName: TORONTO      ON
  2680. ConnectedLineNum: <unknown>
  2681. ConnectedLineName: <unknown>
  2682. Language: en
  2683. AccountCode:
  2684. Context: from-pstn
  2685. Exten: 4166404500
  2686. Priority: 8
  2687. Uniqueid: 1641963309.0
  2688. Linkedid: 1641963309.0
  2689. Extension: 4166404500
  2690. Application: ExecIf
  2691. AppData: 0 ?Set(CALLERID(name)=14165390110)
  2692.  
  2693.  
  2694. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2695. Event: Newexten
  2696. Privilege: dialplan,all
  2697. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2698. ChannelState: 4
  2699. ChannelStateDesc: Ring
  2700. CallerIDNum: 14165390110
  2701. CallerIDName: TORONTO      ON
  2702. ConnectedLineNum: <unknown>
  2703. ConnectedLineName: <unknown>
  2704. Language: en
  2705. AccountCode:
  2706. Context: from-pstn
  2707. Exten: 4166404500
  2708. Priority: 9
  2709. Uniqueid: 1641963309.0
  2710. Linkedid: 1641963309.0
  2711. Extension: 4166404500
  2712. Application: Set
  2713. AppData: __MOHCLASS=
  2714.  
  2715.  
  2716. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2717. Event: VarSet
  2718. Privilege: dialplan,all
  2719. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2720. ChannelState: 4
  2721. ChannelStateDesc: Ring
  2722. CallerIDNum: 14165390110
  2723. CallerIDName: TORONTO      ON
  2724. ConnectedLineNum: <unknown>
  2725. ConnectedLineName: <unknown>
  2726. Language: en
  2727. AccountCode:
  2728. Context: from-pstn
  2729. Exten: 4166404500
  2730. Priority: 9
  2731. Uniqueid: 1641963309.0
  2732. Linkedid: 1641963309.0
  2733. Variable: __MOHCLASS
  2734. Value:
  2735.  
  2736.  
  2737. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2738. Event: Newexten
  2739. Privilege: dialplan,all
  2740. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2741. ChannelState: 4
  2742. ChannelStateDesc: Ring
  2743. CallerIDNum: 14165390110
  2744. CallerIDName: TORONTO      ON
  2745. ConnectedLineNum: <unknown>
  2746. ConnectedLineName: <unknown>
  2747. Language: en
  2748. AccountCode:
  2749. Context: from-pstn
  2750. Exten: 4166404500
  2751. Priority: 10
  2752. Uniqueid: 1641963309.0
  2753. Linkedid: 1641963309.0
  2754. Extension: 4166404500
  2755. Application: Set
  2756. AppData: __REVERSAL_REJECT=FALSE
  2757.  
  2758.  
  2759. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2760. Event: VarSet
  2761. Privilege: dialplan,all
  2762. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2763. ChannelState: 4
  2764. ChannelStateDesc: Ring
  2765. CallerIDNum: 14165390110
  2766. CallerIDName: TORONTO      ON
  2767. ConnectedLineNum: <unknown>
  2768. ConnectedLineName: <unknown>
  2769. Language: en
  2770. AccountCode:
  2771. Context: from-pstn
  2772. Exten: 4166404500
  2773. Priority: 10
  2774. Uniqueid: 1641963309.0
  2775. Linkedid: 1641963309.0
  2776. Variable: __REVERSAL_REJECT
  2777. Value: FALSE
  2778.  
  2779.  
  2780. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2781. Event: Newexten
  2782. Privilege: dialplan,all
  2783. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2784. ChannelState: 4
  2785. ChannelStateDesc: Ring
  2786. CallerIDNum: 14165390110
  2787. CallerIDName: TORONTO      ON
  2788. ConnectedLineNum: <unknown>
  2789. ConnectedLineName: <unknown>
  2790. Language: en
  2791. AccountCode:
  2792. Context: from-pstn
  2793. Exten: 4166404500
  2794. Priority: 11
  2795. Uniqueid: 1641963309.0
  2796. Linkedid: 1641963309.0
  2797. Extension: 4166404500
  2798. Application: GotoIf
  2799. AppData: 1?post-reverse-charge
  2800.  
  2801.  
  2802. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2803. Event: Newexten
  2804. Privilege: dialplan,all
  2805. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2806. ChannelState: 4
  2807. ChannelStateDesc: Ring
  2808. CallerIDNum: 14165390110
  2809. CallerIDName: TORONTO      ON
  2810. ConnectedLineNum: <unknown>
  2811. ConnectedLineName: <unknown>
  2812. Language: en
  2813. AccountCode:
  2814. Context: from-pstn
  2815. Exten: 4166404500
  2816. Priority: 13
  2817. Uniqueid: 1641963309.0
  2818. Linkedid: 1641963309.0
  2819. Extension: 4166404500
  2820. Application: NoOp
  2821. AppData:
  2822.  
  2823.  
  2824. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2825. Event: Newexten
  2826. Privilege: dialplan,all
  2827. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2828. ChannelState: 4
  2829. ChannelStateDesc: Ring
  2830. CallerIDNum: 14165390110
  2831. CallerIDName: TORONTO      ON
  2832. ConnectedLineNum: <unknown>
  2833. ConnectedLineName: <unknown>
  2834. Language: en
  2835. AccountCode:
  2836. Context: from-pstn
  2837. Exten: 4166404500
  2838. Priority: 14
  2839. Uniqueid: 1641963309.0
  2840. Linkedid: 1641963309.0
  2841. Extension: 4166404500
  2842. Application: Set
  2843. AppData: __CALLINGNAMEPRES_SV=allowed_not_screened
  2844.  
  2845.  
  2846. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2847. Event: VarSet
  2848. Privilege: dialplan,all
  2849. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2850. ChannelState: 4
  2851. ChannelStateDesc: Ring
  2852. CallerIDNum: 14165390110
  2853. CallerIDName: TORONTO      ON
  2854. ConnectedLineNum: <unknown>
  2855. ConnectedLineName: <unknown>
  2856. Language: en
  2857. AccountCode:
  2858. Context: from-pstn
  2859. Exten: 4166404500
  2860. Priority: 14
  2861. Uniqueid: 1641963309.0
  2862. Linkedid: 1641963309.0
  2863. Variable: __CALLINGNAMEPRES_SV
  2864. Value: allowed_not_screened
  2865.  
  2866.  
  2867. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2868. Event: Newexten
  2869. Privilege: dialplan,all
  2870. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2871. ChannelState: 4
  2872. ChannelStateDesc: Ring
  2873. CallerIDNum: 14165390110
  2874. CallerIDName: TORONTO      ON
  2875. ConnectedLineNum: <unknown>
  2876. ConnectedLineName: <unknown>
  2877. Language: en
  2878. AccountCode:
  2879. Context: from-pstn
  2880. Exten: 4166404500
  2881. Priority: 15
  2882. Uniqueid: 1641963309.0
  2883. Linkedid: 1641963309.0
  2884. Extension: 4166404500
  2885. Application: Set
  2886. AppData: __CALLINGNUMPRES_SV=allowed_not_screened
  2887.  
  2888.  
  2889. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2890. Event: VarSet
  2891. Privilege: dialplan,all
  2892. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2893. ChannelState: 4
  2894. ChannelStateDesc: Ring
  2895. CallerIDNum: 14165390110
  2896. CallerIDName: TORONTO      ON
  2897. ConnectedLineNum: <unknown>
  2898. ConnectedLineName: <unknown>
  2899. Language: en
  2900. AccountCode:
  2901. Context: from-pstn
  2902. Exten: 4166404500
  2903. Priority: 15
  2904. Uniqueid: 1641963309.0
  2905. Linkedid: 1641963309.0
  2906. Variable: __CALLINGNUMPRES_SV
  2907. Value: allowed_not_screened
  2908.  
  2909.  
  2910. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2911. Event: Newexten
  2912. Privilege: dialplan,all
  2913. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2914. ChannelState: 4
  2915. ChannelStateDesc: Ring
  2916. CallerIDNum: 14165390110
  2917. CallerIDName: TORONTO      ON
  2918. ConnectedLineNum: <unknown>
  2919. ConnectedLineName: <unknown>
  2920. Language: en
  2921. AccountCode:
  2922. Context: from-pstn
  2923. Exten: 4166404500
  2924. Priority: 16
  2925. Uniqueid: 1641963309.0
  2926. Linkedid: 1641963309.0
  2927. Extension: 4166404500
  2928. Application: Set
  2929. AppData: CALLERID(name-pres)=allowed_not_screened
  2930.  
  2931.  
  2932. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2933. Event: Newexten
  2934. Privilege: dialplan,all
  2935. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2936. ChannelState: 4
  2937. ChannelStateDesc: Ring
  2938. CallerIDNum: 14165390110
  2939. CallerIDName: TORONTO      ON
  2940. ConnectedLineNum: <unknown>
  2941. ConnectedLineName: <unknown>
  2942. Language: en
  2943. AccountCode:
  2944. Context: from-pstn
  2945. Exten: 4166404500
  2946. Priority: 17
  2947. Uniqueid: 1641963309.0
  2948. Linkedid: 1641963309.0
  2949. Extension: 4166404500
  2950. Application: Set
  2951. AppData: CALLERID(num-pres)=allowed_not_screened
  2952.  
  2953.  
  2954. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2955. Event: Newexten
  2956. Privilege: dialplan,all
  2957. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2958. ChannelState: 4
  2959. ChannelStateDesc: Ring
  2960. CallerIDNum: 14165390110
  2961. CallerIDName: TORONTO      ON
  2962. ConnectedLineNum: <unknown>
  2963. ConnectedLineName: <unknown>
  2964. Language: en
  2965. AccountCode:
  2966. Context: from-pstn
  2967. Exten: 4166404500
  2968. Priority: 18
  2969. Uniqueid: 1641963309.0
  2970. Linkedid: 1641963309.0
  2971. Extension: 4166404500
  2972. Application: NoOp
  2973. AppData: CallerID Entry Point
  2974.  
  2975.  
  2976. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2977. Event: Newexten
  2978. Privilege: dialplan,all
  2979. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  2980. ChannelState: 4
  2981. ChannelStateDesc: Ring
  2982. CallerIDNum: 14165390110
  2983. CallerIDName: TORONTO      ON
  2984. ConnectedLineNum: <unknown>
  2985. ConnectedLineName: <unknown>
  2986. Language: en
  2987. AccountCode:
  2988. Context: from-pstn
  2989. Exten: 4166404500
  2990. Priority: 19
  2991. Uniqueid: 1641963309.0
  2992. Linkedid: 1641963309.0
  2993. Extension: 4166404500
  2994. Application: Set
  2995. AppData: __CRM_DIRECTION=INBOUND
  2996.  
  2997.  
  2998. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  2999. Event: VarSet
  3000. Privilege: dialplan,all
  3001. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3002. ChannelState: 4
  3003. ChannelStateDesc: Ring
  3004. CallerIDNum: 14165390110
  3005. CallerIDName: TORONTO      ON
  3006. ConnectedLineNum: <unknown>
  3007. ConnectedLineName: <unknown>
  3008. Language: en
  3009. AccountCode:
  3010. Context: from-pstn
  3011. Exten: 4166404500
  3012. Priority: 19
  3013. Uniqueid: 1641963309.0
  3014. Linkedid: 1641963309.0
  3015. Variable: __CRM_DIRECTION
  3016. Value: INBOUND
  3017.  
  3018.  
  3019. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3020. Event: Newexten
  3021. Privilege: dialplan,all
  3022. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3023. ChannelState: 4
  3024. ChannelStateDesc: Ring
  3025. CallerIDNum: 14165390110
  3026. CallerIDName: TORONTO      ON
  3027. ConnectedLineNum: <unknown>
  3028. ConnectedLineName: <unknown>
  3029. Language: en
  3030. AccountCode:
  3031. Context: from-pstn
  3032. Exten: 4166404500
  3033. Priority: 20
  3034. Uniqueid: 1641963309.0
  3035. Linkedid: 1641963309.0
  3036. Extension: 4166404500
  3037. Application: Set
  3038. AppData: __CRM_SOURCE=14165390110
  3039.  
  3040.  
  3041. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3042. Event: VarSet
  3043. Privilege: dialplan,all
  3044. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3045. ChannelState: 4
  3046. ChannelStateDesc: Ring
  3047. CallerIDNum: 14165390110
  3048. CallerIDName: TORONTO      ON
  3049. ConnectedLineNum: <unknown>
  3050. ConnectedLineName: <unknown>
  3051. Language: en
  3052. AccountCode:
  3053. Context: from-pstn
  3054. Exten: 4166404500
  3055. Priority: 20
  3056. Uniqueid: 1641963309.0
  3057. Linkedid: 1641963309.0
  3058. Variable: __CRM_SOURCE
  3059. Value: 14165390110
  3060.  
  3061.  
  3062. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3063. Event: Newexten
  3064. Privilege: dialplan,all
  3065. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3066. ChannelState: 4
  3067. ChannelStateDesc: Ring
  3068. CallerIDNum: 14165390110
  3069. CallerIDName: TORONTO      ON
  3070. ConnectedLineNum: <unknown>
  3071. ConnectedLineName: <unknown>
  3072. Language: en
  3073. AccountCode:
  3074. Context: from-pstn
  3075. Exten: 4166404500
  3076. Priority: 21
  3077. Uniqueid: 1641963309.0
  3078. Linkedid: 1641963309.0
  3079. Extension: 4166404500
  3080. Application: Set
  3081. AppData: __CRM_LINKEDID=1641963309.0
  3082.  
  3083.  
  3084. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3085. Event: VarSet
  3086. Privilege: dialplan,all
  3087. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3088. ChannelState: 4
  3089. ChannelStateDesc: Ring
  3090. CallerIDNum: 14165390110
  3091. CallerIDName: TORONTO      ON
  3092. ConnectedLineNum: <unknown>
  3093. ConnectedLineName: <unknown>
  3094. Language: en
  3095. AccountCode:
  3096. Context: from-pstn
  3097. Exten: 4166404500
  3098. Priority: 21
  3099. Uniqueid: 1641963309.0
  3100. Linkedid: 1641963309.0
  3101. Variable: __CRM_LINKEDID
  3102. Value: 1641963309.0
  3103.  
  3104.  
  3105. [2022-01-11 23:55:09] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3106. Event: Newexten
  3107. Privilege: dialplan,all
  3108. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3109. ChannelState: 4
  3110. ChannelStateDesc: Ring
  3111. CallerIDNum: 14165390110
  3112. CallerIDName: TORONTO      ON
  3113. ConnectedLineNum: <unknown>
  3114. ConnectedLineName: <unknown>
  3115. Language: en
  3116. AccountCode:
  3117. Context: from-pstn
  3118. Exten: 4166404500
  3119. Priority: 22
  3120. Uniqueid: 1641963309.0
  3121. Linkedid: 1641963309.0
  3122. Extension: 4166404500
  3123. Application: AGI
  3124. AppData: agi://127.0.0.1/sangomacrm.agi,true
  3125.  
  3126.  
  3127.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
  3128. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx_variables.c:377 ast_str_retrieve_variable: Result of 'CRM_DIRECTION' is 'INBOUND'
  3129. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx_variables.c:823 pbx_substitute_variables_helper_full: Expression result is '1'
  3130. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'ExecIf'
  3131.     -- Executing [4166404500@from-pstn:23] ExecIf("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  3132. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: app_stack.c:511 expand_gosub_args: Gosub args:crm-hangup,s,1 new_args:crm-hangup,s,1
  3133. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx_variables.c:377 ast_str_retrieve_variable: Result of 'EXTEN' is '4166404500'
  3134. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Goto'
  3135.     -- Executing [4166404500@from-pstn:24] Goto("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "sipstation-welcome,4166404500,1") in new stack
  3136.     -- Goto (sipstation-welcome,4166404500,1)
  3137. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx_variables.c:759 pbx_substitute_variables_helper_full: Function CALLERID(number) result is '14165390110'
  3138. [2022-01-11 23:55:10] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3139. Event: VarSet
  3140. Privilege: dialplan,all
  3141. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3142. ChannelState: 4
  3143. ChannelStateDesc: Ring
  3144. CallerIDNum: 14165390110
  3145. CallerIDName: TORONTO      ON
  3146. ConnectedLineNum: <unknown>
  3147. ConnectedLineName: <unknown>
  3148. Language: en
  3149. AccountCode:
  3150. Context: from-pstn
  3151. Exten: 4166404500
  3152. Priority: 22
  3153. Uniqueid: 1641963309.0
  3154. Linkedid: 1641963309.0
  3155. Variable: AGISTATUS
  3156. Value: SUCCESS
  3157.  
  3158.  
  3159. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: func_strings.c:970 regex: FUNCTION REGEX ([0-9])(14165390110)
  3160. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx_variables.c:759 pbx_substitute_variables_helper_full: Function REGEX("[0-9]" 14165390110) result is '1'
  3161. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  3162.     -- Executing [4166404500@sipstation-welcome:1] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "ISNUM=1") in new stack
  3163. [2022-01-11 23:55:10] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3164. Event: Newexten
  3165. Privilege: dialplan,all
  3166. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3167. ChannelState: 4
  3168. ChannelStateDesc: Ring
  3169. CallerIDNum: 14165390110
  3170. CallerIDName: TORONTO      ON
  3171. ConnectedLineNum: <unknown>
  3172. ConnectedLineName: <unknown>
  3173. Language: en
  3174. AccountCode:
  3175. Context: from-pstn
  3176. Exten: 4166404500
  3177. Priority: 23
  3178. Uniqueid: 1641963309.0
  3179. Linkedid: 1641963309.0
  3180. Extension: 4166404500
  3181. Application: ExecIf
  3182. AppData: 1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)
  3183.  
  3184.  
  3185. [2022-01-11 23:55:10] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3186. Event: HangupHandlerPush
  3187. Privilege: dialplan,all
  3188. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3189. ChannelState: 4
  3190. ChannelStateDesc: Ring
  3191. CallerIDNum: 14165390110
  3192. CallerIDName: TORONTO      ON
  3193. ConnectedLineNum: <unknown>
  3194. ConnectedLineName: <unknown>
  3195. Language: en
  3196. AccountCode:
  3197. Context: from-pstn
  3198. Exten: 4166404500
  3199. Priority: 23
  3200. Uniqueid: 1641963309.0
  3201. Linkedid: 1641963309.0
  3202. Handler: crm-hangup,s,1
  3203.  
  3204.  
  3205. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx_variables.c:377 ast_str_retrieve_variable: Result of 'EXTEN' is '4166404500'
  3206. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx_variables.c:759 pbx_substitute_variables_helper_full: Function CALLERID(number) result is '14165390110'
  3207. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  3208.     -- Executing [4166404500@sipstation-welcome:2] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "DB(sipstation/4166404500/lastcall/cnum)=14165390110") in new stack
  3209. [2022-01-11 23:55:10] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3210. Event: Newexten
  3211. Privilege: dialplan,all
  3212. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3213. ChannelState: 4
  3214. ChannelStateDesc: Ring
  3215. CallerIDNum: 14165390110
  3216. CallerIDName: TORONTO      ON
  3217. ConnectedLineNum: <unknown>
  3218. ConnectedLineName: <unknown>
  3219. Language: en
  3220. AccountCode:
  3221. Context: from-pstn
  3222. Exten: 4166404500
  3223. Priority: 24
  3224. Uniqueid: 1641963309.0
  3225. Linkedid: 1641963309.0
  3226. Extension: 4166404500
  3227. Application: Goto
  3228. AppData: sipstation-welcome,4166404500,1
  3229.  
  3230.  
  3231. [2022-01-11 23:55:10] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3232. Event: Newexten
  3233. Privilege: dialplan,all
  3234. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3235. ChannelState: 4
  3236. ChannelStateDesc: Ring
  3237. CallerIDNum: 14165390110
  3238. CallerIDName: TORONTO      ON
  3239. ConnectedLineNum: <unknown>
  3240. ConnectedLineName: <unknown>
  3241. Language: en
  3242. AccountCode:
  3243. Context: sipstation-welcome
  3244. Exten: 4166404500
  3245. Priority: 1
  3246. Uniqueid: 1641963309.0
  3247. Linkedid: 1641963309.0
  3248. Extension: 4166404500
  3249. Application: Set
  3250. AppData: ISNUM=1
  3251.  
  3252.  
  3253. [2022-01-11 23:55:10] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3254. Event: VarSet
  3255. Privilege: dialplan,all
  3256. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3257. ChannelState: 4
  3258. ChannelStateDesc: Ring
  3259. CallerIDNum: 14165390110
  3260. CallerIDName: TORONTO      ON
  3261. ConnectedLineNum: <unknown>
  3262. ConnectedLineName: <unknown>
  3263. Language: en
  3264. AccountCode:
  3265. Context: sipstation-welcome
  3266. Exten: 4166404500
  3267. Priority: 1
  3268. Uniqueid: 1641963309.0
  3269. Linkedid: 1641963309.0
  3270. Variable: ISNUM
  3271. Value: 1
  3272.  
  3273.  
  3274. [2022-01-11 23:55:10] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3275. Event: Newexten
  3276. Privilege: dialplan,all
  3277. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3278. ChannelState: 4
  3279. ChannelStateDesc: Ring
  3280. CallerIDNum: 14165390110
  3281. CallerIDName: TORONTO      ON
  3282. ConnectedLineNum: <unknown>
  3283. ConnectedLineName: <unknown>
  3284. Language: en
  3285. AccountCode:
  3286. Context: sipstation-welcome
  3287. Exten: 4166404500
  3288. Priority: 2
  3289. Uniqueid: 1641963309.0
  3290. Linkedid: 1641963309.0
  3291. Extension: 4166404500
  3292. Application: Set
  3293. AppData: DB(sipstation/4166404500/lastcall/cnum)=14165390110
  3294.  
  3295.  
  3296. [2022-01-11 23:55:10] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  3297. Event: VarSet
  3298. Privilege: dialplan,all
  3299. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3300. ChannelState: 4
  3301. ChannelStateDesc: Ring
  3302. CallerIDNum: 14165390110
  3303. CallerIDName: TORONTO      ON
  3304. ConnectedLineNum: <unknown>
  3305. ConnectedLineName: <unknown>
  3306. Language: en
  3307. AccountCode:
  3308. Context: from-pstn
  3309. Exten: 4166404500
  3310. Priority: 22
  3311. Uniqueid: 1641963309.0
  3312. Linkedid: 1641963309.0
  3313. Variable: AGISTATUS
  3314. Value: SUCCESS
  3315.  
  3316.  
  3317. [2022-01-11 23:55:10] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  3318. Event: Newexten
  3319. Privilege: dialplan,all
  3320. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3321. ChannelState: 4
  3322. ChannelStateDesc: Ring
  3323. CallerIDNum: 14165390110
  3324. CallerIDName: TORONTO      ON
  3325. ConnectedLineNum: <unknown>
  3326. ConnectedLineName: <unknown>
  3327. Language: en
  3328. AccountCode:
  3329. Context: from-pstn
  3330. Exten: 4166404500
  3331. Priority: 23
  3332. Uniqueid: 1641963309.0
  3333. Linkedid: 1641963309.0
  3334. Extension: 4166404500
  3335. Application: ExecIf
  3336. AppData: 1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)
  3337.  
  3338.  
  3339. [2022-01-11 23:55:10] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  3340. Event: HangupHandlerPush
  3341. Privilege: dialplan,all
  3342. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3343. ChannelState: 4
  3344. ChannelStateDesc: Ring
  3345. CallerIDNum: 14165390110
  3346. CallerIDName: TORONTO      ON
  3347. ConnectedLineNum: <unknown>
  3348. ConnectedLineName: <unknown>
  3349. Language: en
  3350. AccountCode:
  3351. Context: from-pstn
  3352. Exten: 4166404500
  3353. Priority: 23
  3354. Uniqueid: 1641963309.0
  3355. Linkedid: 1641963309.0
  3356. Handler: crm-hangup,s,1
  3357.  
  3358.  
  3359. [2022-01-11 23:55:10] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  3360. Event: Newexten
  3361. Privilege: dialplan,all
  3362. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3363. ChannelState: 4
  3364. ChannelStateDesc: Ring
  3365. CallerIDNum: 14165390110
  3366. CallerIDName: TORONTO      ON
  3367. ConnectedLineNum: <unknown>
  3368. ConnectedLineName: <unknown>
  3369. Language: en
  3370. AccountCode:
  3371. Context: from-pstn
  3372. Exten: 4166404500
  3373. Priority: 24
  3374. Uniqueid: 1641963309.0
  3375. Linkedid: 1641963309.0
  3376. Extension: 4166404500
  3377. Application: Goto
  3378. AppData: sipstation-welcome,4166404500,1
  3379.  
  3380.  
  3381. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx_variables.c:377 ast_str_retrieve_variable: Result of 'EXTEN' is '4166404500'
  3382. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx_variables.c:759 pbx_substitute_variables_helper_full: Function CALLERID(name) result is 'TORONTO      ON'
  3383. [2022-01-11 23:55:10] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  3384. Event: Newexten
  3385. Privilege: dialplan,all
  3386. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3387. ChannelState: 4
  3388. ChannelStateDesc: Ring
  3389. CallerIDNum: 14165390110
  3390. CallerIDName: TORONTO      ON
  3391. ConnectedLineNum: <unknown>
  3392. ConnectedLineName: <unknown>
  3393. Language: en
  3394. AccountCode:
  3395. Context: sipstation-welcome
  3396. Exten: 4166404500
  3397. Priority: 1
  3398. Uniqueid: 1641963309.0
  3399. Linkedid: 1641963309.0
  3400. Extension: 4166404500
  3401. Application: Set
  3402. AppData: ISNUM=1
  3403.  
  3404.  
  3405. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  3406.     -- Executing [4166404500@sipstation-welcome:3] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "DB(sipstation/4166404500/lastcall/cnam)=TORONTO      ON") in new stack
  3407. [2022-01-11 23:55:10] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  3408. Event: VarSet
  3409. Privilege: dialplan,all
  3410. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3411. ChannelState: 4
  3412. ChannelStateDesc: Ring
  3413. CallerIDNum: 14165390110
  3414. CallerIDName: TORONTO      ON
  3415. ConnectedLineNum: <unknown>
  3416. ConnectedLineName: <unknown>
  3417. Language: en
  3418. AccountCode:
  3419. Context: sipstation-welcome
  3420. Exten: 4166404500
  3421. Priority: 1
  3422. Uniqueid: 1641963309.0
  3423. Linkedid: 1641963309.0
  3424. Variable: ISNUM
  3425. Value: 1
  3426.  
  3427.  
  3428. [2022-01-11 23:55:10] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  3429. Event: Newexten
  3430. Privilege: dialplan,all
  3431. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3432. ChannelState: 4
  3433. ChannelStateDesc: Ring
  3434. CallerIDNum: 14165390110
  3435. CallerIDName: TORONTO      ON
  3436. ConnectedLineNum: <unknown>
  3437. ConnectedLineName: <unknown>
  3438. Language: en
  3439. AccountCode:
  3440. Context: sipstation-welcome
  3441. Exten: 4166404500
  3442. Priority: 2
  3443. Uniqueid: 1641963309.0
  3444. Linkedid: 1641963309.0
  3445. Extension: 4166404500
  3446. Application: Set
  3447. AppData: DB(sipstation/4166404500/lastcall/cnum)=14165390110
  3448.  
  3449.  
  3450. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx_variables.c:377 ast_str_retrieve_variable: Result of 'EXTEN' is '4166404500'
  3451. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx_variables.c:377 ast_str_retrieve_variable: Result of 'EPOCH' is '1641963310'
  3452. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Set'
  3453.     -- Executing [4166404500@sipstation-welcome:4] Set("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "DB(sipstation/4166404500/lastcall/time)=1641963310") in new stack
  3454. [2022-01-11 23:55:10] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  3455. Event: Newexten
  3456. Privilege: dialplan,all
  3457. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3458. ChannelState: 4
  3459. ChannelStateDesc: Ring
  3460. CallerIDNum: 14165390110
  3461. CallerIDName: TORONTO      ON
  3462. ConnectedLineNum: <unknown>
  3463. ConnectedLineName: <unknown>
  3464. Language: en
  3465. AccountCode:
  3466. Context: sipstation-welcome
  3467. Exten: 4166404500
  3468. Priority: 3
  3469. Uniqueid: 1641963309.0
  3470. Linkedid: 1641963309.0
  3471. Extension: 4166404500
  3472. Application: Set
  3473. AppData: DB(sipstation/4166404500/lastcall/cnam)=TORONTO      ON
  3474.  
  3475.  
  3476. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Answer'
  3477.     -- Executing [4166404500@sipstation-welcome:5] Answer("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "") in new stack
  3478. [2022-01-11 23:55:10] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  3479. Event: Newexten
  3480. Privilege: dialplan,all
  3481. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3482. ChannelState: 4
  3483. ChannelStateDesc: Ring
  3484. CallerIDNum: 14165390110
  3485. CallerIDName: TORONTO      ON
  3486. ConnectedLineNum: <unknown>
  3487. ConnectedLineName: <unknown>
  3488. Language: en
  3489. AccountCode:
  3490. Context: sipstation-welcome
  3491. Exten: 4166404500
  3492. Priority: 4
  3493. Uniqueid: 1641963309.0
  3494. Linkedid: 1641963309.0
  3495. Extension: 4166404500
  3496. Application: Set
  3497. AppData: DB(sipstation/4166404500/lastcall/time)=1641963310
  3498.  
  3499.  
  3500. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:5276 session_inv_on_media_update:  PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3501. [2022-01-11 23:55:10] DEBUG[3227]: manager.c:6200 match_filter: Examining AMI event:
  3502. Event: Newstate
  3503. Privilege: call,all
  3504. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3505. ChannelState: 6
  3506. ChannelStateDesc: Up
  3507. CallerIDNum: 14165390110
  3508. CallerIDName: TORONTO      ON
  3509. ConnectedLineNum: <unknown>
  3510. ConnectedLineName: <unknown>
  3511. Language: en
  3512. AccountCode:
  3513. Context: sipstation-welcome
  3514. Exten: 4166404500
  3515. Priority: 5
  3516. Uniqueid: 1641963309.0
  3517. Linkedid: 1641963309.0
  3518.  
  3519.  
  3520. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:1005 handle_negotiated_sdp_session_media: PJSIP/fpbx-1-UNu72jBj8bHw-00000000: Applying negotiated SDP media stream 'audio' using audio SDP handler
  3521. [2022-01-11 23:55:10] DEBUG[2700]: res_rtp_asterisk.c:8312 ast_rtp_prop_set: (0x7f10cc3087d0) RTCP ignoring duplicate property
  3522. [2022-01-11 23:55:10] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  3523. Event: Newexten
  3524. Privilege: dialplan,all
  3525. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3526. ChannelState: 4
  3527. ChannelStateDesc: Ring
  3528. CallerIDNum: 14165390110
  3529. CallerIDName: TORONTO      ON
  3530. ConnectedLineNum: <unknown>
  3531. ConnectedLineName: <unknown>
  3532. Language: en
  3533. AccountCode:
  3534. Context: sipstation-welcome
  3535. Exten: 4166404500
  3536. Priority: 5
  3537. Uniqueid: 1641963309.0
  3538. Linkedid: 1641963309.0
  3539. Extension: 4166404500
  3540. Application: Answer
  3541. AppData:
  3542.  
  3543.  
  3544. [2022-01-11 23:55:10] DEBUG[2700]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '67.231.9.12' into...
  3545. [2022-01-11 23:55:10] DEBUG[2700]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '67.231.9.12' and port ''.
  3546. [2022-01-11 23:55:10] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  3547. Event: Newstate
  3548. Privilege: call,all
  3549. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3550. ChannelState: 6
  3551. ChannelStateDesc: Up
  3552. CallerIDNum: 14165390110
  3553. CallerIDName: TORONTO      ON
  3554. ConnectedLineNum: <unknown>
  3555. ConnectedLineName: <unknown>
  3556. Language: en
  3557. AccountCode:
  3558. Context: sipstation-welcome
  3559. Exten: 4166404500
  3560. Priority: 5
  3561. Uniqueid: 1641963309.0
  3562. Linkedid: 1641963309.0
  3563.  
  3564.  
  3565. [2022-01-11 23:55:10] DEBUG[2700]: acl.c:1047 ast_ouraddrfor: For destination '67.231.9.12', our source address is '10.42.58.120'.
  3566. [2022-01-11 23:55:10] DEBUG[2700]: res_rtp_asterisk.c:8515 ast_rtp_remote_address_set: (0x7f10cc3087d0) RTCP setting address on RTP instance
  3567. [2022-01-11 23:55:10] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3568. Event: Newexten
  3569. Privilege: dialplan,all
  3570. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3571. ChannelState: 4
  3572. ChannelStateDesc: Ring
  3573. CallerIDNum: 14165390110
  3574. CallerIDName: TORONTO      ON
  3575. ConnectedLineNum: <unknown>
  3576. ConnectedLineName: <unknown>
  3577. Language: en
  3578. AccountCode:
  3579. Context: sipstation-welcome
  3580. Exten: 4166404500
  3581. Priority: 3
  3582. Uniqueid: 1641963309.0
  3583. Linkedid: 1641963309.0
  3584. Extension: 4166404500
  3585. Application: Set
  3586. AppData: DB(sipstation/4166404500/lastcall/cnam)=TORONTO      ON
  3587.  
  3588.  
  3589.        > 0x7f10cc310f90 -- Strict RTP learning after remote address set to: 67.231.9.12:34878
  3590. [2022-01-11 23:55:10] DEBUG[2700]: rtp_engine.c:1319 ast_rtp_codecs_payloads_set_m_type: Setting tx payload type 0 based on m type on 0x7f11151591a0
  3591. [2022-01-11 23:55:10] DEBUG[3226]: manager.c:6200 match_filter: Examining AMI event:
  3592. Event: Newstate
  3593. Privilege: call,all
  3594. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3595. ChannelState: 6
  3596. ChannelStateDesc: Up
  3597. CallerIDNum: 14165390110
  3598. CallerIDName: TORONTO      ON
  3599. ConnectedLineNum: <unknown>
  3600. ConnectedLineName: <unknown>
  3601. Language: en
  3602. AccountCode:
  3603. Context: sipstation-welcome
  3604. Exten: 4166404500
  3605. Priority: 5
  3606. Uniqueid: 1641963309.0
  3607. Linkedid: 1641963309.0
  3608.  
  3609.  
  3610. [2022-01-11 23:55:10] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3611. Event: Newexten
  3612. Privilege: dialplan,all
  3613. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3614. ChannelState: 4
  3615. ChannelStateDesc: Ring
  3616. CallerIDNum: 14165390110
  3617. CallerIDName: TORONTO      ON
  3618. ConnectedLineNum: <unknown>
  3619. ConnectedLineName: <unknown>
  3620. Language: en
  3621. AccountCode:
  3622. Context: sipstation-welcome
  3623. Exten: 4166404500
  3624. Priority: 4
  3625. Uniqueid: 1641963309.0
  3626. Linkedid: 1641963309.0
  3627. Extension: 4166404500
  3628. Application: Set
  3629. AppData: DB(sipstation/4166404500/lastcall/time)=1641963310
  3630.  
  3631.  
  3632. [2022-01-11 23:55:10] DEBUG[2700]: rtp_engine.c:1319 ast_rtp_codecs_payloads_set_m_type: Setting tx payload type 18 based on m type on 0x7f11151591a0
  3633. [2022-01-11 23:55:10] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3634. Event: Newexten
  3635. Privilege: dialplan,all
  3636. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3637. ChannelState: 4
  3638. ChannelStateDesc: Ring
  3639. CallerIDNum: 14165390110
  3640. CallerIDName: TORONTO      ON
  3641. ConnectedLineNum: <unknown>
  3642. ConnectedLineName: <unknown>
  3643. Language: en
  3644. AccountCode:
  3645. Context: sipstation-welcome
  3646. Exten: 4166404500
  3647. Priority: 5
  3648. Uniqueid: 1641963309.0
  3649. Linkedid: 1641963309.0
  3650. Extension: 4166404500
  3651. Application: Answer
  3652. AppData:
  3653.  
  3654.  
  3655. [2022-01-11 23:55:10] DEBUG[2700]: rtp_engine.c:1205 rtp_codecs_payloads_copy_tx: Copying tx payload mapping 0 (0x7f10cc31c9f8) from 0x7f11151591a0 to 0x7f10cc3089a8
  3656. [2022-01-11 23:55:10] DEBUG[2700]: rtp_engine.c:1205 rtp_codecs_payloads_copy_tx: Copying tx payload mapping 18 (0x7f10cc31d398) from 0x7f11151591a0 to 0x7f10cc3089a8
  3657. [2022-01-11 23:55:10] DEBUG[2700]: rtp_engine.c:1205 rtp_codecs_payloads_copy_tx: Copying tx payload mapping 101 (0x7f10cc31d348) from 0x7f11151591a0 to 0x7f10cc3089a8
  3658. [2022-01-11 23:55:10] DEBUG[3225]: manager.c:6200 match_filter: Examining AMI event:
  3659. Event: Newstate
  3660. Privilege: call,all
  3661. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3662. ChannelState: 6
  3663. ChannelStateDesc: Up
  3664. CallerIDNum: 14165390110
  3665. CallerIDName: TORONTO      ON
  3666. ConnectedLineNum: <unknown>
  3667. ConnectedLineName: <unknown>
  3668. Language: en
  3669. AccountCode:
  3670. Context: sipstation-welcome
  3671. Exten: 4166404500
  3672. Priority: 5
  3673. Uniqueid: 1641963309.0
  3674. Linkedid: 1641963309.0
  3675.  
  3676.  
  3677. [2022-01-11 23:55:10] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3678. Event: Newstate
  3679. Privilege: call,all
  3680. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3681. ChannelState: 6
  3682. ChannelStateDesc: Up
  3683. CallerIDNum: 14165390110
  3684. CallerIDName: TORONTO      ON
  3685. ConnectedLineNum: <unknown>
  3686. ConnectedLineName: <unknown>
  3687. Language: en
  3688. AccountCode:
  3689. Context: sipstation-welcome
  3690. Exten: 4166404500
  3691. Priority: 5
  3692. Uniqueid: 1641963309.0
  3693. Linkedid: 1641963309.0
  3694.  
  3695.  
  3696. [2022-01-11 23:55:10] DEBUG[2700]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting read format path: ulaw -> ulaw
  3697. [2022-01-11 23:55:10] DEBUG[2700]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  3698. [2022-01-11 23:55:10] DEBUG[2700]: res_rtp_asterisk.c:9024 ast_rtp_activate: (0x7f10cc3087d0) DTLS - ast_rtp_activate rtp=0x7f10cc310f90 - setup and perform DTLS'
  3699. [2022-01-11 23:55:10] DEBUG[2700]: res_rtp_asterisk.c:2534 dtls_perform_handshake: (0x7f10cc310f90) DTLS perform handshake - ssl = (nil), setup = 0
  3700. [2022-01-11 23:55:10] DEBUG[2700]: res_rtp_asterisk.c:2534 dtls_perform_handshake: (0x7f10cc310f90) DTLS perform handshake - ssl = (nil), setup = 0
  3701. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:1010 handle_negotiated_sdp_session_media: PJSIP/fpbx-1-UNu72jBj8bHw-00000000: Applied negotiated SDP media stream 'audio' using audio SDP handler
  3702. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:5340 session_inv_on_media_update:  PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3703. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4457 handle_outgoing_response:  PJSIP/fpbx-1-UNu72jBj8bHw-00000000: Method is INVITE, Response is 200 OK
  3704. [2022-01-11 23:55:10] DEBUG[2550]: devicestate.c:361 _ast_device_state: No provider found, checking channel drivers for PJSIP - fpbx-1-UNu72jBj8bHw
  3705. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4471 handle_outgoing_response:  PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3706. [2022-01-11 23:55:10] DEBUG[2550]: devicestate.c:466 do_state_change: Changing state for PJSIP/fpbx-1-UNu72jBj8bHw - state 2 (In use)
  3707. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip/pjsip_message_filter.c:296 filter_on_tx_message: Re-wrote Contact URI host/port to 10.42.58.120:5060 (this may be re-written again later)
  3708. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4310 __print_debug_details: Function session_inv_on_state_changed called on event TSX_STATE
  3709. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4324 __print_debug_details: The state change pertains to the endpoint 'fpbx-1-UNu72jBj8bHw(PJSIP/fpbx-1-UNu72jBj8bHw-00000000)'
  3710. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4329 __print_debug_details: The inv session still has an invite_tsx (0x7f10cc310618)
  3711. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4344 __print_debug_details: There is no transaction involved in this state change
  3712. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4346 __print_debug_details: The current inv state is CONNECTING
  3713. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4593 session_inv_on_state_changed: PJSIP/fpbx-1-UNu72jBj8bHw-00000000: Source of transaction state change is TX_MSG
  3714. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4310 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
  3715. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4324 __print_debug_details: The state change pertains to the endpoint 'fpbx-1-UNu72jBj8bHw(PJSIP/fpbx-1-UNu72jBj8bHw-00000000)'
  3716. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4329 __print_debug_details: The inv session still has an invite_tsx (0x7f10cc310618)
  3717. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4335 __print_debug_details: The UAS INVITE transaction involved in this state change is 0x7f10cc310618
  3718. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4339 __print_debug_details: The current transaction state is Completed
  3719. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4341 __print_debug_details: The transaction state change event is TX_MSG
  3720. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4346 __print_debug_details: The current inv state is CONNECTING
  3721. [2022-01-11 23:55:10] DEBUG[2876]: app_queue.c:2601 device_state_cb: Device 'PJSIP/fpbx-1-UNu72jBj8bHw' changed to state '2' (In use) but we don't care because they're not a member of any queue.
  3722. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: chan_pjsip.c:1616 chan_pjsip_indicate:  PJSIP/fpbx-1-UNu72jBj8bHw-00000000: Indicated Stop generators
  3723. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: chan_pjsip.c:1826 chan_pjsip_indicate:  PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3724. [2022-01-11 23:55:10] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  3725. Event: DeviceStateChange
  3726. Privilege: call,all
  3727. Device: PJSIP/fpbx-1-UNu72jBj8bHw
  3728. State: INUSE
  3729.  
  3730.  
  3731. [2022-01-11 23:55:10] DEBUG[3226]: manager.c:6200 match_filter: Examining AMI event:
  3732. Event: DeviceStateChange
  3733. Privilege: call,all
  3734. Device: PJSIP/fpbx-1-UNu72jBj8bHw
  3735. State: INUSE
  3736.  
  3737.  
  3738. [2022-01-11 23:55:10] DEBUG[3227]: manager.c:6200 match_filter: Examining AMI event:
  3739. Event: DeviceStateChange
  3740. Privilege: call,all
  3741. Device: PJSIP/fpbx-1-UNu72jBj8bHw
  3742. State: INUSE
  3743.  
  3744.  
  3745. [2022-01-11 23:55:10] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  3746. Event: DeviceStateChange
  3747. Privilege: call,all
  3748. Device: PJSIP/fpbx-1-UNu72jBj8bHw
  3749. State: INUSE
  3750.  
  3751.  
  3752. [2022-01-11 23:55:10] DEBUG[3225]: manager.c:6200 match_filter: Examining AMI event:
  3753. Event: DeviceStateChange
  3754. Privilege: call,all
  3755. Device: PJSIP/fpbx-1-UNu72jBj8bHw
  3756. State: INUSE
  3757.  
  3758.  
  3759. [2022-01-11 23:55:10] DEBUG[2663]: res_odbc.c:974 _ast_odbc_request_obj2: Reusing ODBC handle 0x1d915f0 from class 'asteriskcdrdb'
  3760. [2022-01-11 23:55:10] DEBUG[2663]: cel_odbc.c:781 odbc_log: Executing SQL statement: [INSERT INTO cel (eventtype, eventtime, cid_name, cid_num, cid_ani, cid_rdnis, cid_dnid, exten, context, channame, appname, appdata, amaflags, accountcode, uniqueid, linkedid, peer, userdeftype, extra) VALUES ('ANSWER', {ts '2022-01-11 23:55:10.225356'}, 'TORONTO      ON', '14165390110', '14165390110', '', '4166404500', '4166404500', 'sipstation-welcome', 'PJSIP/fpbx-1-UNu72jBj8bHw-00000000', 'Answer', '', 3, '', '1641963309.0', '1641963309.0', '', '', '')]
  3761. [2022-01-11 23:55:10] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  3762. Event: VarSet
  3763. Privilege: dialplan,all
  3764. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3765. ChannelState: 4
  3766. ChannelStateDesc: Ring
  3767. CallerIDNum: 14165390110
  3768. CallerIDName: TORONTO      ON
  3769. ConnectedLineNum: <unknown>
  3770. ConnectedLineName: <unknown>
  3771. Language: en
  3772. AccountCode:
  3773. Context: from-pstn
  3774. Exten: 4166404500
  3775. Priority: 22
  3776. Uniqueid: 1641963309.0
  3777. Linkedid: 1641963309.0
  3778. Variable: AGISTATUS
  3779. Value: SUCCESS
  3780.  
  3781.  
  3782. [2022-01-11 23:55:10] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  3783. Event: Newexten
  3784. Privilege: dialplan,all
  3785. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3786. ChannelState: 4
  3787. ChannelStateDesc: Ring
  3788. CallerIDNum: 14165390110
  3789. CallerIDName: TORONTO      ON
  3790. ConnectedLineNum: <unknown>
  3791. ConnectedLineName: <unknown>
  3792. Language: en
  3793. AccountCode:
  3794. Context: from-pstn
  3795. Exten: 4166404500
  3796. Priority: 23
  3797. Uniqueid: 1641963309.0
  3798. Linkedid: 1641963309.0
  3799. Extension: 4166404500
  3800. Application: ExecIf
  3801. AppData: 1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)
  3802.  
  3803.  
  3804. [2022-01-11 23:55:10] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  3805. Event: HangupHandlerPush
  3806. Privilege: dialplan,all
  3807. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3808. ChannelState: 4
  3809. ChannelStateDesc: Ring
  3810. CallerIDNum: 14165390110
  3811. CallerIDName: TORONTO      ON
  3812. ConnectedLineNum: <unknown>
  3813. ConnectedLineName: <unknown>
  3814. Language: en
  3815. AccountCode:
  3816. Context: from-pstn
  3817. Exten: 4166404500
  3818. Priority: 23
  3819. Uniqueid: 1641963309.0
  3820. Linkedid: 1641963309.0
  3821. Handler: crm-hangup,s,1
  3822.  
  3823.  
  3824. [2022-01-11 23:55:10] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  3825. Event: Newexten
  3826. Privilege: dialplan,all
  3827. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3828. ChannelState: 4
  3829. ChannelStateDesc: Ring
  3830. CallerIDNum: 14165390110
  3831. CallerIDName: TORONTO      ON
  3832. ConnectedLineNum: <unknown>
  3833. ConnectedLineName: <unknown>
  3834. Language: en
  3835. AccountCode:
  3836. Context: from-pstn
  3837. Exten: 4166404500
  3838. Priority: 24
  3839. Uniqueid: 1641963309.0
  3840. Linkedid: 1641963309.0
  3841. Extension: 4166404500
  3842. Application: Goto
  3843. AppData: sipstation-welcome,4166404500,1
  3844.  
  3845.  
  3846. [2022-01-11 23:55:10] DEBUG[2663]: res_odbc.c:817 ast_odbc_release_obj: Releasing ODBC handle 0x1d915f0 into pool
  3847. [2022-01-11 23:55:10] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  3848. Event: Newexten
  3849. Privilege: dialplan,all
  3850. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3851. ChannelState: 4
  3852. ChannelStateDesc: Ring
  3853. CallerIDNum: 14165390110
  3854. CallerIDName: TORONTO      ON
  3855. ConnectedLineNum: <unknown>
  3856. ConnectedLineName: <unknown>
  3857. Language: en
  3858. AccountCode:
  3859. Context: sipstation-welcome
  3860. Exten: 4166404500
  3861. Priority: 1
  3862. Uniqueid: 1641963309.0
  3863. Linkedid: 1641963309.0
  3864. Extension: 4166404500
  3865. Application: Set
  3866. AppData: ISNUM=1
  3867.  
  3868.  
  3869. [2022-01-11 23:55:10] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  3870. Event: VarSet
  3871. Privilege: dialplan,all
  3872. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3873. ChannelState: 4
  3874. ChannelStateDesc: Ring
  3875. CallerIDNum: 14165390110
  3876. CallerIDName: TORONTO      ON
  3877. ConnectedLineNum: <unknown>
  3878. ConnectedLineName: <unknown>
  3879. Language: en
  3880. AccountCode:
  3881. Context: sipstation-welcome
  3882. Exten: 4166404500
  3883. Priority: 1
  3884. Uniqueid: 1641963309.0
  3885. Linkedid: 1641963309.0
  3886. Variable: ISNUM
  3887. Value: 1
  3888.  
  3889.  
  3890. [2022-01-11 23:55:10] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  3891. Event: Newexten
  3892. Privilege: dialplan,all
  3893. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3894. ChannelState: 4
  3895. ChannelStateDesc: Ring
  3896. CallerIDNum: 14165390110
  3897. CallerIDName: TORONTO      ON
  3898. ConnectedLineNum: <unknown>
  3899. ConnectedLineName: <unknown>
  3900. Language: en
  3901. AccountCode:
  3902. Context: sipstation-welcome
  3903. Exten: 4166404500
  3904. Priority: 2
  3905. Uniqueid: 1641963309.0
  3906. Linkedid: 1641963309.0
  3907. Extension: 4166404500
  3908. Application: Set
  3909. AppData: DB(sipstation/4166404500/lastcall/cnum)=14165390110
  3910.  
  3911.  
  3912. [2022-01-11 23:55:10] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  3913. Event: Newexten
  3914. Privilege: dialplan,all
  3915. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3916. ChannelState: 4
  3917. ChannelStateDesc: Ring
  3918. CallerIDNum: 14165390110
  3919. CallerIDName: TORONTO      ON
  3920. ConnectedLineNum: <unknown>
  3921. ConnectedLineName: <unknown>
  3922. Language: en
  3923. AccountCode:
  3924. Context: sipstation-welcome
  3925. Exten: 4166404500
  3926. Priority: 3
  3927. Uniqueid: 1641963309.0
  3928. Linkedid: 1641963309.0
  3929. Extension: 4166404500
  3930. Application: Set
  3931. AppData: DB(sipstation/4166404500/lastcall/cnam)=TORONTO      ON
  3932.  
  3933.  
  3934. [2022-01-11 23:55:10] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  3935. Event: Newexten
  3936. Privilege: dialplan,all
  3937. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3938. ChannelState: 4
  3939. ChannelStateDesc: Ring
  3940. CallerIDNum: 14165390110
  3941. CallerIDName: TORONTO      ON
  3942. ConnectedLineNum: <unknown>
  3943. ConnectedLineName: <unknown>
  3944. Language: en
  3945. AccountCode:
  3946. Context: sipstation-welcome
  3947. Exten: 4166404500
  3948. Priority: 4
  3949. Uniqueid: 1641963309.0
  3950. Linkedid: 1641963309.0
  3951. Extension: 4166404500
  3952. Application: Set
  3953. AppData: DB(sipstation/4166404500/lastcall/time)=1641963310
  3954.  
  3955.  
  3956. [2022-01-11 23:55:10] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  3957. Event: Newexten
  3958. Privilege: dialplan,all
  3959. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3960. ChannelState: 4
  3961. ChannelStateDesc: Ring
  3962. CallerIDNum: 14165390110
  3963. CallerIDName: TORONTO      ON
  3964. ConnectedLineNum: <unknown>
  3965. ConnectedLineName: <unknown>
  3966. Language: en
  3967. AccountCode:
  3968. Context: sipstation-welcome
  3969. Exten: 4166404500
  3970. Priority: 5
  3971. Uniqueid: 1641963309.0
  3972. Linkedid: 1641963309.0
  3973. Extension: 4166404500
  3974. Application: Answer
  3975. AppData:
  3976.  
  3977.  
  3978. [2022-01-11 23:55:10] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  3979. Event: Newstate
  3980. Privilege: call,all
  3981. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  3982. ChannelState: 6
  3983. ChannelStateDesc: Up
  3984. CallerIDNum: 14165390110
  3985. CallerIDName: TORONTO      ON
  3986. ConnectedLineNum: <unknown>
  3987. ConnectedLineName: <unknown>
  3988. Language: en
  3989. AccountCode:
  3990. Context: sipstation-welcome
  3991. Exten: 4166404500
  3992. Priority: 5
  3993. Uniqueid: 1641963309.0
  3994. Linkedid: 1641963309.0
  3995.  
  3996.  
  3997. [2022-01-11 23:55:10] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  3998. Event: DeviceStateChange
  3999. Privilege: call,all
  4000. Device: PJSIP/fpbx-1-UNu72jBj8bHw
  4001. State: INUSE
  4002.  
  4003.  
  4004. [2022-01-11 23:55:10] DEBUG[2699]: res_pjsip/pjsip_distributor.c:503 distributor: Searching for serializer associated with dialog dlg0x7f10cc00b558 for Request msg ACK/cseq=46427862 (rdata0x7f1178002c08)
  4005. [2022-01-11 23:55:10] DEBUG[2699]: res_pjsip/pjsip_distributor.c:511 distributor: Found serializer pjsip/distributor-0000002f associated with dialog dlg0x7f10cc00b558
  4006. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4310 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
  4007. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4324 __print_debug_details: The state change pertains to the endpoint 'fpbx-1-UNu72jBj8bHw(PJSIP/fpbx-1-UNu72jBj8bHw-00000000)'
  4008. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4332 __print_debug_details: The inv session does NOT have an invite_tsx
  4009. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4335 __print_debug_details: The UAS INVITE transaction involved in this state change is 0x7f10cc310618
  4010. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4339 __print_debug_details: The current transaction state is Terminated
  4011. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4341 __print_debug_details: The transaction state change event is USER
  4012. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4346 __print_debug_details: The current inv state is CONNECTING
  4013. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4310 __print_debug_details: Function session_inv_on_state_changed called on event RX_MSG
  4014. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4324 __print_debug_details: The state change pertains to the endpoint 'fpbx-1-UNu72jBj8bHw(PJSIP/fpbx-1-UNu72jBj8bHw-00000000)'
  4015. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4332 __print_debug_details: The inv session does NOT have an invite_tsx
  4016. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4344 __print_debug_details: There is no transaction involved in this state change
  4017. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4346 __print_debug_details: The current inv state is CONFIRMED
  4018. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4537 handle_incoming_before_media: PJSIP/fpbx-1-UNu72jBj8bHw-00000000: Received request
  4019. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4355 handle_incoming_request:  PJSIP/fpbx-1-UNu72jBj8bHw-00000000: Method is ACK
  4020. [2022-01-11 23:55:10] DEBUG[2700]: chan_pjsip.c:3202 chan_pjsip_incoming_ack:  PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4021. [2022-01-11 23:55:10] DEBUG[2700]: chan_pjsip.c:3206 chan_pjsip_incoming_ack:  PJSIP/fpbx-1-UNu72jBj8bHw-00000000: Queueing SRCCHANGE
  4022. [2022-01-11 23:55:10] DEBUG[2700]: chan_pjsip.c:3210 chan_pjsip_incoming_ack:  PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4023. [2022-01-11 23:55:10] DEBUG[2700]: res_pjsip_session.c:4365 handle_incoming_request:  PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4024. [2022-01-11 23:55:10] DEBUG[3600]: manager.c:6688 process_message: Running action 'Login'
  4025. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: channel.c:2750 __ast_answer: Didn't receive a media frame from PJSIP/fpbx-1-UNu72jBj8bHw-00000000 within 500 ms of answering. Continuing anyway
  4026. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Wait'
  4027.     -- Executing [4166404500@sipstation-welcome:6] Wait("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "1") in new stack
  4028. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: slin -> ulaw
  4029. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4030. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: channel.c:8203 ast_channel_start_silence_generator: Started silence generator on 'PJSIP/fpbx-1-UNu72jBj8bHw-00000000'
  4031. [2022-01-11 23:55:10] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  4032. Event: Newexten
  4033. Privilege: dialplan,all
  4034. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4035. ChannelState: 6
  4036. ChannelStateDesc: Up
  4037. CallerIDNum: 14165390110
  4038. CallerIDName: TORONTO      ON
  4039. ConnectedLineNum: <unknown>
  4040. ConnectedLineName: <unknown>
  4041. Language: en
  4042. AccountCode:
  4043. Context: sipstation-welcome
  4044. Exten: 4166404500
  4045. Priority: 6
  4046. Uniqueid: 1641963309.0
  4047. Linkedid: 1641963309.0
  4048. Extension: 4166404500
  4049. Application: Wait
  4050. AppData: 1
  4051.  
  4052.  
  4053. [2022-01-11 23:55:10] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  4054. Event: Newexten
  4055. Privilege: dialplan,all
  4056. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4057. ChannelState: 6
  4058. ChannelStateDesc: Up
  4059. CallerIDNum: 14165390110
  4060. CallerIDName: TORONTO      ON
  4061. ConnectedLineNum: <unknown>
  4062. ConnectedLineName: <unknown>
  4063. Language: en
  4064. AccountCode:
  4065. Context: sipstation-welcome
  4066. Exten: 4166404500
  4067. Priority: 6
  4068. Uniqueid: 1641963309.0
  4069. Linkedid: 1641963309.0
  4070. Extension: 4166404500
  4071. Application: Wait
  4072. AppData: 1
  4073.  
  4074.  
  4075. [2022-01-11 23:55:10] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  4076. Event: Newexten
  4077. Privilege: dialplan,all
  4078. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4079. ChannelState: 6
  4080. ChannelStateDesc: Up
  4081. CallerIDNum: 14165390110
  4082. CallerIDName: TORONTO      ON
  4083. ConnectedLineNum: <unknown>
  4084. ConnectedLineName: <unknown>
  4085. Language: en
  4086. AccountCode:
  4087. Context: sipstation-welcome
  4088. Exten: 4166404500
  4089. Priority: 6
  4090. Uniqueid: 1641963309.0
  4091. Linkedid: 1641963309.0
  4092. Extension: 4166404500
  4093. Application: Wait
  4094. AppData: 1
  4095.  
  4096.  
  4097. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: res_rtp_asterisk.c:5351 ast_rtp_write: (0x7f10cc3087d0) RTP ooh, format changed from none to ulaw
  4098. [2022-01-11 23:55:10] DEBUG[3572][C-00000001]: res_rtp_asterisk.c:5091 rtp_raw_write: (0x7f10cc3087d0) RTCP starting transmission
  4099. [2022-01-11 23:55:11] DEBUG[3614]: manager.c:6688 process_message: Running action 'Login'
  4100. [2022-01-11 23:55:11] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4101. [2022-01-11 23:55:11] DEBUG[3572][C-00000001]: channel.c:8238 ast_channel_stop_silence_generator: Stopped silence generator on 'PJSIP/fpbx-1-UNu72jBj8bHw-00000000'
  4102. [2022-01-11 23:55:11] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4103. [2022-01-11 23:55:11] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Playback'
  4104.     -- Executing [4166404500@sipstation-welcome:7] Playback("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "you-have-reached-a-test-number&silence/1") in new stack
  4105. [2022-01-11 23:55:11] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  4106. Event: Newexten
  4107. Privilege: dialplan,all
  4108. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4109. ChannelState: 6
  4110. ChannelStateDesc: Up
  4111. CallerIDNum: 14165390110
  4112. CallerIDName: TORONTO      ON
  4113. ConnectedLineNum: <unknown>
  4114. ConnectedLineName: <unknown>
  4115. Language: en
  4116. AccountCode:
  4117. Context: sipstation-welcome
  4118. Exten: 4166404500
  4119. Priority: 7
  4120. Uniqueid: 1641963309.0
  4121. Linkedid: 1641963309.0
  4122. Extension: 4166404500
  4123. Application: Playback
  4124. AppData: you-have-reached-a-test-number&silence/1
  4125.  
  4126.  
  4127. [2022-01-11 23:55:11] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  4128. Event: Newexten
  4129. Privilege: dialplan,all
  4130. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4131. ChannelState: 6
  4132. ChannelStateDesc: Up
  4133. CallerIDNum: 14165390110
  4134. CallerIDName: TORONTO      ON
  4135. ConnectedLineNum: <unknown>
  4136. ConnectedLineName: <unknown>
  4137. Language: en
  4138. AccountCode:
  4139. Context: sipstation-welcome
  4140. Exten: 4166404500
  4141. Priority: 7
  4142. Uniqueid: 1641963309.0
  4143. Linkedid: 1641963309.0
  4144. Extension: 4166404500
  4145. Application: Playback
  4146. AppData: you-have-reached-a-test-number&silence/1
  4147.  
  4148.  
  4149. [2022-01-11 23:55:11] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  4150. Event: Newexten
  4151. Privilege: dialplan,all
  4152. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4153. ChannelState: 6
  4154. ChannelStateDesc: Up
  4155. CallerIDNum: 14165390110
  4156. CallerIDName: TORONTO      ON
  4157. ConnectedLineNum: <unknown>
  4158. ConnectedLineName: <unknown>
  4159. Language: en
  4160. AccountCode:
  4161. Context: sipstation-welcome
  4162. Exten: 4166404500
  4163. Priority: 7
  4164. Uniqueid: 1641963309.0
  4165. Linkedid: 1641963309.0
  4166. Extension: 4166404500
  4167. Application: Playback
  4168. AppData: you-have-reached-a-test-number&silence/1
  4169.  
  4170.  
  4171. [2022-01-11 23:55:11] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4172. [2022-01-11 23:55:11] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4173.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000> Playing 'you-have-reached-a-test-number.ulaw' (language 'en')
  4174. [2022-01-11 23:55:13] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4175. [2022-01-11 23:55:13] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4176. [2022-01-11 23:55:13] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4177. [2022-01-11 23:55:13] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4178. [2022-01-11 23:55:13] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4179. [2022-01-11 23:55:13] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4180.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000> Playing 'silence/1.ulaw' (language 'en')
  4181. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip/pjsip_options.c:927 sip_options_qualify_aor: Qualifying all contacts on AOR 'fpbx-2-UNu72jBj8bHw'
  4182. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip/pjsip_options.c:857 sip_options_qualify_contact: Qualifying contact 'fpbx-2-UNu72jBj8bHw@@7efc6bea1913fda6e12fd87645a7edf2' on AOR 'fpbx-2-UNu72jBj8bHw'
  4183. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip.c:4411 endpt_send_request: 0x7f10cc309400: Wrapper created
  4184. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip.c:4426 endpt_send_request: 0x7f10cc309400: Set timer to 3000 msec
  4185. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip/pjsip_resolver.c:479 sip_resolve: Performing SIP DNS resolution of target 'trunk2.freepbx.com'
  4186. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip/pjsip_resolver.c:506 sip_resolve: Transport type for target 'trunk2.freepbx.com' is 'UDP transport'
  4187. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip/pjsip_resolver.c:549 sip_resolve: [0x7f10cc30dfa8] Created resolution tracking for target 'trunk2.freepbx.com'
  4188. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip/pjsip_resolver.c:177 sip_resolve_add: [0x7f10cc30dfa8] Added target 'trunk2.freepbx.com' with record type '1', transport 'UDP transport', and port '5060'
  4189. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip/pjsip_resolver.c:620 sip_resolve: [0x7f10cc30dfa8] Starting initial resolution using parallel queries for target 'trunk2.freepbx.com'
  4190. [2022-01-11 23:55:13] DEBUG[2731]: res_pjsip/pjsip_resolver.c:277 sip_resolve_callback: [0x7f10cc30dfa8] All parallel queries completed
  4191. [2022-01-11 23:55:13] DEBUG[2731]: res_pjsip/pjsip_resolver.c:326 sip_resolve_callback: [0x7f10cc30dfa8] A record received on target 'trunk2.freepbx.com'
  4192. [2022-01-11 23:55:13] DEBUG[2731]: res_pjsip/pjsip_resolver.c:421 sip_resolve_callback: [0x7f10cc30dfa8] Resolution completed - 1 viable targets
  4193. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip/pjsip_resolver.c:201 sip_resolve_invoke_user_callback: [0x7f10cc30dfa8] Address '0' is 162.253.134.142:5060 with transport 'UDP transport'
  4194. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip/pjsip_resolver.c:207 sip_resolve_invoke_user_callback: [0x7f10cc30dfa8] Invoking user callback with '1' addresses
  4195. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip/pjsip_message_filter.c:296 filter_on_tx_message: Re-wrote Contact URI host/port to 10.42.58.120:5060 (this may be re-written again later)
  4196. [2022-01-11 23:55:13] DEBUG[2699]: res_pjsip/pjsip_distributor.c:520 distributor: No dialog serializer for Response msg 200/OPTIONS/cseq=46126 (rdata0x7f1178002808).  Using request transaction as basis.
  4197. [2022-01-11 23:55:13] DEBUG[2699]: res_pjsip/pjsip_distributor.c:128 find_request_serializer: Found transaction tsx0x7f10cc310618 for Response msg 200/OPTIONS/cseq=46126 (rdata0x7f1178002808).
  4198. [2022-01-11 23:55:13] DEBUG[2699]: res_pjsip/pjsip_distributor.c:138 find_request_serializer: Found serializer pjsip/options/fpbx-2-UNu72jBj8bHw-00000040 on transaction tsx0x7f10cc310618
  4199. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip.c:4289 endpt_send_request_cb: 0x7f10cc309400: PJSIP tsx response received
  4200. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip.c:4302 endpt_send_request_cb: 0x7f10cc309400: Cancelling timer
  4201. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip.c:4311 endpt_send_request_cb: 0x7f10cc309400: Timer cancelled
  4202. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip.c:4332 endpt_send_request_cb: 0x7f10cc309400: Callbacks executed
  4203. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip.c:4388 send_request_wrapper_destructor: 0x7f10cc309400: wrapper destroyed
  4204. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip/pjsip_options.c:759 sip_options_contact_status_notify_task: Contact fpbx-2-UNu72jBj8bHw/sip:UNu72jBj8bHw@trunk2.freepbx.com:5060 status didn't change: Reachable, RTT: 60.346 msec
  4205. [2022-01-11 23:55:13] DEBUG[2700]: res_pjsip/pjsip_options.c:777 sip_options_contact_status_notify_task: AOR 'fpbx-2-UNu72jBj8bHw' now has 1 available contacts
  4206. [2022-01-11 23:55:14] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4207. [2022-01-11 23:55:14] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4208. [2022-01-11 23:55:14] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4209. [2022-01-11 23:55:14] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4210. [2022-01-11 23:55:14] DEBUG[3572][C-00000001]: pbx_variables.c:377 ast_str_retrieve_variable: Result of 'EXTEN' is '4166404500'
  4211. [2022-01-11 23:55:14] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'SayDigits'
  4212.     -- Executing [4166404500@sipstation-welcome:8] SayDigits("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "4166404500") in new stack
  4213. [2022-01-11 23:55:14] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  4214. Event: VarSet
  4215. Privilege: dialplan,all
  4216. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4217. ChannelState: 6
  4218. ChannelStateDesc: Up
  4219. CallerIDNum: 14165390110
  4220. CallerIDName: TORONTO      ON
  4221. ConnectedLineNum: <unknown>
  4222. ConnectedLineName: <unknown>
  4223. Language: en
  4224. AccountCode:
  4225. Context: sipstation-welcome
  4226. Exten: 4166404500
  4227. Priority: 7
  4228. Uniqueid: 1641963309.0
  4229. Linkedid: 1641963309.0
  4230. Variable: PLAYBACKSTATUS
  4231. Value: SUCCESS
  4232.  
  4233.  
  4234. [2022-01-11 23:55:14] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  4235. Event: VarSet
  4236. Privilege: dialplan,all
  4237. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4238. ChannelState: 6
  4239. ChannelStateDesc: Up
  4240. CallerIDNum: 14165390110
  4241. CallerIDName: TORONTO      ON
  4242. ConnectedLineNum: <unknown>
  4243. ConnectedLineName: <unknown>
  4244. Language: en
  4245. AccountCode:
  4246. Context: sipstation-welcome
  4247. Exten: 4166404500
  4248. Priority: 7
  4249. Uniqueid: 1641963309.0
  4250. Linkedid: 1641963309.0
  4251. Variable: PLAYBACKSTATUS
  4252. Value: SUCCESS
  4253.  
  4254.  
  4255. [2022-01-11 23:55:14] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  4256. Event: Newexten
  4257. Privilege: dialplan,all
  4258. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4259. ChannelState: 6
  4260. ChannelStateDesc: Up
  4261. CallerIDNum: 14165390110
  4262. CallerIDName: TORONTO      ON
  4263. ConnectedLineNum: <unknown>
  4264. ConnectedLineName: <unknown>
  4265. Language: en
  4266. AccountCode:
  4267. Context: sipstation-welcome
  4268. Exten: 4166404500
  4269. Priority: 8
  4270. Uniqueid: 1641963309.0
  4271. Linkedid: 1641963309.0
  4272. Extension: 4166404500
  4273. Application: SayDigits
  4274. AppData: 4166404500
  4275.  
  4276.  
  4277. [2022-01-11 23:55:14] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  4278. Event: Newexten
  4279. Privilege: dialplan,all
  4280. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4281. ChannelState: 6
  4282. ChannelStateDesc: Up
  4283. CallerIDNum: 14165390110
  4284. CallerIDName: TORONTO      ON
  4285. ConnectedLineNum: <unknown>
  4286. ConnectedLineName: <unknown>
  4287. Language: en
  4288. AccountCode:
  4289. Context: sipstation-welcome
  4290. Exten: 4166404500
  4291. Priority: 8
  4292. Uniqueid: 1641963309.0
  4293. Linkedid: 1641963309.0
  4294. Extension: 4166404500
  4295. Application: SayDigits
  4296. AppData: 4166404500
  4297.  
  4298.  
  4299. [2022-01-11 23:55:14] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  4300. Event: VarSet
  4301. Privilege: dialplan,all
  4302. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4303. ChannelState: 6
  4304. ChannelStateDesc: Up
  4305. CallerIDNum: 14165390110
  4306. CallerIDName: TORONTO      ON
  4307. ConnectedLineNum: <unknown>
  4308. ConnectedLineName: <unknown>
  4309. Language: en
  4310. AccountCode:
  4311. Context: sipstation-welcome
  4312. Exten: 4166404500
  4313. Priority: 7
  4314. Uniqueid: 1641963309.0
  4315. Linkedid: 1641963309.0
  4316. Variable: PLAYBACKSTATUS
  4317. Value: SUCCESS
  4318.  
  4319.  
  4320. [2022-01-11 23:55:14] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  4321. Event: Newexten
  4322. Privilege: dialplan,all
  4323. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4324. ChannelState: 6
  4325. ChannelStateDesc: Up
  4326. CallerIDNum: 14165390110
  4327. CallerIDName: TORONTO      ON
  4328. ConnectedLineNum: <unknown>
  4329. ConnectedLineName: <unknown>
  4330. Language: en
  4331. AccountCode:
  4332. Context: sipstation-welcome
  4333. Exten: 4166404500
  4334. Priority: 8
  4335. Uniqueid: 1641963309.0
  4336. Linkedid: 1641963309.0
  4337. Extension: 4166404500
  4338. Application: SayDigits
  4339. AppData: 4166404500
  4340.  
  4341.  
  4342. [2022-01-11 23:55:14] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4343. [2022-01-11 23:55:14] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4344.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000> Playing 'digits/4.ulaw' (language 'en')
  4345. [2022-01-11 23:55:15] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (533 requested / 533 actual) timer ticks per second
  4346. [2022-01-11 23:55:15] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4347. [2022-01-11 23:55:15] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4348. [2022-01-11 23:55:15] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4349. [2022-01-11 23:55:15] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4350. [2022-01-11 23:55:15] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4351. [2022-01-11 23:55:15] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4352.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000> Playing 'digits/1.ulaw' (language 'en')
  4353. [2022-01-11 23:55:15] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  4354. Event: RTCPSent
  4355. Privilege: reporting,all
  4356. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4357. ChannelState: 6
  4358. ChannelStateDesc: Up
  4359. CallerIDNum: 14165390110
  4360. CallerIDName: TORONTO      ON
  4361. ConnectedLineNum: <unknown>
  4362. ConnectedLineName: <unknown>
  4363. Language: en
  4364. AccountCode:
  4365. Context: sipstation-welcome
  4366. Exten: 4166404500
  4367. Priority: 8
  4368. Uniqueid: 1641963309.0
  4369. Linkedid: 1641963309.0
  4370. To: 67.231.9.12:34879
  4371. From: 10.42.58.120:10883
  4372. SSRC: 0x157e2307
  4373. PT: 200(SR)
  4374. ReportCount: 0
  4375. SentNTP: 1641963315.747595
  4376. SentRTP: 39855
  4377. SentPackets: 250
  4378. SentOctets: 39855
  4379.  
  4380.  
  4381. [2022-01-11 23:55:15] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  4382. Event: RTCPSent
  4383. Privilege: reporting,all
  4384. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4385. ChannelState: 6
  4386. ChannelStateDesc: Up
  4387. CallerIDNum: 14165390110
  4388. CallerIDName: TORONTO      ON
  4389. ConnectedLineNum: <unknown>
  4390. ConnectedLineName: <unknown>
  4391. Language: en
  4392. AccountCode:
  4393. Context: sipstation-welcome
  4394. Exten: 4166404500
  4395. Priority: 8
  4396. Uniqueid: 1641963309.0
  4397. Linkedid: 1641963309.0
  4398. To: 67.231.9.12:34879
  4399. From: 10.42.58.120:10883
  4400. SSRC: 0x157e2307
  4401. PT: 200(SR)
  4402. ReportCount: 0
  4403. SentNTP: 1641963315.747595
  4404. SentRTP: 39855
  4405. SentPackets: 250
  4406. SentOctets: 39855
  4407.  
  4408.  
  4409. [2022-01-11 23:55:16] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (89 requested / 89 actual) timer ticks per second
  4410. [2022-01-11 23:55:16] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4411. [2022-01-11 23:55:16] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4412. [2022-01-11 23:55:16] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4413. [2022-01-11 23:55:16] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4414. [2022-01-11 23:55:16] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4415. [2022-01-11 23:55:16] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4416.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000> Playing 'digits/6.ulaw' (language 'en')
  4417. [2022-01-11 23:55:17] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (1143 requested / 1000 actual) timer ticks per second
  4418. [2022-01-11 23:55:17] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4419. [2022-01-11 23:55:17] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4420. [2022-01-11 23:55:17] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4421. [2022-01-11 23:55:17] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4422. [2022-01-11 23:55:17] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4423. [2022-01-11 23:55:17] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4424.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000> Playing 'digits/6.ulaw' (language 'en')
  4425. [2022-01-11 23:55:18] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (1143 requested / 1000 actual) timer ticks per second
  4426. [2022-01-11 23:55:18] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4427. [2022-01-11 23:55:18] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4428. [2022-01-11 23:55:18] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4429. [2022-01-11 23:55:18] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4430. [2022-01-11 23:55:18] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4431. [2022-01-11 23:55:18] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4432.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000> Playing 'digits/4.ulaw' (language 'en')
  4433. [2022-01-11 23:55:18] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (533 requested / 533 actual) timer ticks per second
  4434. [2022-01-11 23:55:18] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4435. [2022-01-11 23:55:18] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4436. [2022-01-11 23:55:18] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4437. [2022-01-11 23:55:18] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4438. [2022-01-11 23:55:18] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4439. [2022-01-11 23:55:18] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4440.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000> Playing 'digits/0.ulaw' (language 'en')
  4441. [2022-01-11 23:55:19] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (68 requested / 68 actual) timer ticks per second
  4442. [2022-01-11 23:55:19] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4443. [2022-01-11 23:55:19] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4444. [2022-01-11 23:55:19] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4445. [2022-01-11 23:55:19] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4446. [2022-01-11 23:55:19] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4447. [2022-01-11 23:55:19] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4448.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000> Playing 'digits/4.ulaw' (language 'en')
  4449. [2022-01-11 23:55:20] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (533 requested / 533 actual) timer ticks per second
  4450. [2022-01-11 23:55:20] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4451. [2022-01-11 23:55:20] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4452. [2022-01-11 23:55:20] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4453. [2022-01-11 23:55:20] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4454. [2022-01-11 23:55:20] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4455. [2022-01-11 23:55:20] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4456.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000> Playing 'digits/5.ulaw' (language 'en')
  4457. [2022-01-11 23:55:20] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  4458. Event: RTCPSent
  4459. Privilege: reporting,all
  4460. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4461. ChannelState: 6
  4462. ChannelStateDesc: Up
  4463. CallerIDNum: 14165390110
  4464. CallerIDName: TORONTO      ON
  4465. ConnectedLineNum: <unknown>
  4466. ConnectedLineName: <unknown>
  4467. Language: en
  4468. AccountCode:
  4469. Context: sipstation-welcome
  4470. Exten: 4166404500
  4471. Priority: 8
  4472. Uniqueid: 1641963309.0
  4473. Linkedid: 1641963309.0
  4474. To: 67.231.9.12:34879
  4475. From: 10.42.58.120:10883
  4476. SSRC: 0x157e2307
  4477. PT: 200(SR)
  4478. ReportCount: 0
  4479. SentNTP: 1641963320.747919
  4480. SentRTP: 79627
  4481. SentPackets: 503
  4482. SentOctets: 79627
  4483.  
  4484.  
  4485. [2022-01-11 23:55:20] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  4486. Event: RTCPSent
  4487. Privilege: reporting,all
  4488. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4489. ChannelState: 6
  4490. ChannelStateDesc: Up
  4491. CallerIDNum: 14165390110
  4492. CallerIDName: TORONTO      ON
  4493. ConnectedLineNum: <unknown>
  4494. ConnectedLineName: <unknown>
  4495. Language: en
  4496. AccountCode:
  4497. Context: sipstation-welcome
  4498. Exten: 4166404500
  4499. Priority: 8
  4500. Uniqueid: 1641963309.0
  4501. Linkedid: 1641963309.0
  4502. To: 67.231.9.12:34879
  4503. From: 10.42.58.120:10883
  4504. SSRC: 0x157e2307
  4505. PT: 200(SR)
  4506. ReportCount: 0
  4507. SentNTP: 1641963320.747919
  4508. SentRTP: 79627
  4509. SentPackets: 503
  4510. SentOctets: 79627
  4511.  
  4512.  
  4513. [2022-01-11 23:55:21] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (8000 requested / 1000 actual) timer ticks per second
  4514. [2022-01-11 23:55:21] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4515. [2022-01-11 23:55:21] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4516. [2022-01-11 23:55:21] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4517. [2022-01-11 23:55:21] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4518. [2022-01-11 23:55:21] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4519. [2022-01-11 23:55:21] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4520.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000> Playing 'digits/0.ulaw' (language 'en')
  4521. [2022-01-11 23:55:22] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (68 requested / 68 actual) timer ticks per second
  4522. [2022-01-11 23:55:22] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4523. [2022-01-11 23:55:22] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4524. [2022-01-11 23:55:22] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4525. [2022-01-11 23:55:22] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4526. [2022-01-11 23:55:22] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4527. [2022-01-11 23:55:22] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4528.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000> Playing 'digits/0.ulaw' (language 'en')
  4529. [2022-01-11 23:55:23] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (68 requested / 68 actual) timer ticks per second
  4530. [2022-01-11 23:55:23] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4531. [2022-01-11 23:55:23] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4532. [2022-01-11 23:55:23] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4533. [2022-01-11 23:55:23] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4534. [2022-01-11 23:55:23] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'Playback'
  4535.     -- Executing [4166404500@sipstation-welcome:9] Playback("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "your&calling&from&silence/1") in new stack
  4536. [2022-01-11 23:55:23] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  4537. Event: Newexten
  4538. Privilege: dialplan,all
  4539. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4540. ChannelState: 6
  4541. ChannelStateDesc: Up
  4542. CallerIDNum: 14165390110
  4543. CallerIDName: TORONTO      ON
  4544. ConnectedLineNum: <unknown>
  4545. ConnectedLineName: <unknown>
  4546. Language: en
  4547. AccountCode:
  4548. Context: sipstation-welcome
  4549. Exten: 4166404500
  4550. Priority: 9
  4551. Uniqueid: 1641963309.0
  4552. Linkedid: 1641963309.0
  4553. Extension: 4166404500
  4554. Application: Playback
  4555. AppData: your&calling&from&silence/1
  4556.  
  4557.  
  4558. [2022-01-11 23:55:23] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  4559. Event: Newexten
  4560. Privilege: dialplan,all
  4561. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4562. ChannelState: 6
  4563. ChannelStateDesc: Up
  4564. CallerIDNum: 14165390110
  4565. CallerIDName: TORONTO      ON
  4566. ConnectedLineNum: <unknown>
  4567. ConnectedLineName: <unknown>
  4568. Language: en
  4569. AccountCode:
  4570. Context: sipstation-welcome
  4571. Exten: 4166404500
  4572. Priority: 9
  4573. Uniqueid: 1641963309.0
  4574. Linkedid: 1641963309.0
  4575. Extension: 4166404500
  4576. Application: Playback
  4577. AppData: your&calling&from&silence/1
  4578.  
  4579.  
  4580. [2022-01-11 23:55:23] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  4581. Event: Newexten
  4582. Privilege: dialplan,all
  4583. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4584. ChannelState: 6
  4585. ChannelStateDesc: Up
  4586. CallerIDNum: 14165390110
  4587. CallerIDName: TORONTO      ON
  4588. ConnectedLineNum: <unknown>
  4589. ConnectedLineName: <unknown>
  4590. Language: en
  4591. AccountCode:
  4592. Context: sipstation-welcome
  4593. Exten: 4166404500
  4594. Priority: 9
  4595. Uniqueid: 1641963309.0
  4596. Linkedid: 1641963309.0
  4597. Extension: 4166404500
  4598. Application: Playback
  4599. AppData: your&calling&from&silence/1
  4600.  
  4601.  
  4602. [2022-01-11 23:55:23] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4603. [2022-01-11 23:55:23] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4604.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000> Playing 'your.ulaw' (language 'en')
  4605. [2022-01-11 23:55:23] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (471 requested / 471 actual) timer ticks per second
  4606. [2022-01-11 23:55:23] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4607. [2022-01-11 23:55:23] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4608. [2022-01-11 23:55:23] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4609. [2022-01-11 23:55:23] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4610. [2022-01-11 23:55:23] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4611. [2022-01-11 23:55:23] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4612.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000> Playing 'calling.ulaw' (language 'en')
  4613. [2022-01-11 23:55:24] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (133 requested / 133 actual) timer ticks per second
  4614. [2022-01-11 23:55:24] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4615. [2022-01-11 23:55:24] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4616. [2022-01-11 23:55:24] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4617. [2022-01-11 23:55:24] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4618. [2022-01-11 23:55:24] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4619. [2022-01-11 23:55:24] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4620.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000> Playing 'from.ulaw' (language 'en')
  4621. [2022-01-11 23:55:25] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4622. [2022-01-11 23:55:25] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4623. [2022-01-11 23:55:25] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4624. [2022-01-11 23:55:25] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4625. [2022-01-11 23:55:25] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4626. [2022-01-11 23:55:25] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  4627.     -- <PJSIP/fpbx-1-UNu72jBj8bHw-00000000> Playing 'silence/1.ulaw' (language 'en')
  4628. [2022-01-11 23:55:25] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  4629. Event: RTCPSent
  4630. Privilege: reporting,all
  4631. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4632. ChannelState: 6
  4633. ChannelStateDesc: Up
  4634. CallerIDNum: 14165390110
  4635. CallerIDName: TORONTO      ON
  4636. ConnectedLineNum: <unknown>
  4637. ConnectedLineName: <unknown>
  4638. Language: en
  4639. AccountCode:
  4640. Context: sipstation-welcome
  4641. Exten: 4166404500
  4642. Priority: 9
  4643. Uniqueid: 1641963309.0
  4644. Linkedid: 1641963309.0
  4645. To: 67.231.9.12:34879
  4646. From: 10.42.58.120:10883
  4647. SSRC: 0x157e2307
  4648. PT: 200(SR)
  4649. ReportCount: 0
  4650. SentNTP: 1641963325.748208
  4651. SentRTP: 119301
  4652. SentPackets: 754
  4653. SentOctets: 119301
  4654.  
  4655.  
  4656. [2022-01-11 23:55:25] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  4657. Event: RTCPSent
  4658. Privilege: reporting,all
  4659. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4660. ChannelState: 6
  4661. ChannelStateDesc: Up
  4662. CallerIDNum: 14165390110
  4663. CallerIDName: TORONTO      ON
  4664. ConnectedLineNum: <unknown>
  4665. ConnectedLineName: <unknown>
  4666. Language: en
  4667. AccountCode:
  4668. Context: sipstation-welcome
  4669. Exten: 4166404500
  4670. Priority: 9
  4671. Uniqueid: 1641963309.0
  4672. Linkedid: 1641963309.0
  4673. To: 67.231.9.12:34879
  4674. From: 10.42.58.120:10883
  4675. SSRC: 0x157e2307
  4676. PT: 200(SR)
  4677. ReportCount: 0
  4678. SentNTP: 1641963325.748208
  4679. SentRTP: 119301
  4680. SentPackets: 754
  4681. SentOctets: 119301
  4682.  
  4683.  
  4684. [2022-01-11 23:55:26] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4685. [2022-01-11 23:55:26] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4686. [2022-01-11 23:55:26] DEBUG[3572][C-00000001]: channel.c:3223 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  4687. [2022-01-11 23:55:26] DEBUG[3572][C-00000001]: channel.c:5764 set_format: Channel PJSIP/fpbx-1-UNu72jBj8bHw-00000000 setting write format path: ulaw -> ulaw
  4688. [2022-01-11 23:55:26] DEBUG[3572][C-00000001]: pbx_variables.c:377 ast_str_retrieve_variable: Result of 'ISNUM' is '1'
  4689. [2022-01-11 23:55:26] DEBUG[3572][C-00000001]: pbx_variables.c:823 pbx_substitute_variables_helper_full: Expression result is '1'
  4690. [2022-01-11 23:55:26] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'GotoIf'
  4691.     -- Executing [4166404500@sipstation-welcome:10] GotoIf("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "1?valid:notvalid") in new stack
  4692. [2022-01-11 23:55:26] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  4693. Event: VarSet
  4694. Privilege: dialplan,all
  4695. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4696. ChannelState: 6
  4697. ChannelStateDesc: Up
  4698. CallerIDNum: 14165390110
  4699. CallerIDName: TORONTO      ON
  4700. ConnectedLineNum: <unknown>
  4701. ConnectedLineName: <unknown>
  4702. Language: en
  4703. AccountCode:
  4704. Context: sipstation-welcome
  4705. Exten: 4166404500
  4706. Priority: 9
  4707. Uniqueid: 1641963309.0
  4708. Linkedid: 1641963309.0
  4709. Variable: PLAYBACKSTATUS
  4710. Value: SUCCESS
  4711.  
  4712.  
  4713. [2022-01-11 23:55:26] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  4714. Event: VarSet
  4715. Privilege: dialplan,all
  4716. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4717. ChannelState: 6
  4718. ChannelStateDesc: Up
  4719. CallerIDNum: 14165390110
  4720. CallerIDName: TORONTO      ON
  4721. ConnectedLineNum: <unknown>
  4722. ConnectedLineName: <unknown>
  4723. Language: en
  4724. AccountCode:
  4725. Context: sipstation-welcome
  4726. Exten: 4166404500
  4727. Priority: 9
  4728. Uniqueid: 1641963309.0
  4729. Linkedid: 1641963309.0
  4730. Variable: PLAYBACKSTATUS
  4731. Value: SUCCESS
  4732.  
  4733.  
  4734.     -- Goto (sipstation-welcome,4166404500,11)
  4735. [2022-01-11 23:55:26] DEBUG[3572][C-00000001]: pbx_variables.c:759 pbx_substitute_variables_helper_full: Function CALLERID(number) result is '14165390110'
  4736. [2022-01-11 23:55:26] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event:
  4737. Event: Newexten
  4738. Privilege: dialplan,all
  4739. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4740. ChannelState: 6
  4741. ChannelStateDesc: Up
  4742. CallerIDNum: 14165390110
  4743. CallerIDName: TORONTO      ON
  4744. ConnectedLineNum: <unknown>
  4745. ConnectedLineName: <unknown>
  4746. Language: en
  4747. AccountCode:
  4748. Context: sipstation-welcome
  4749. Exten: 4166404500
  4750. Priority: 10
  4751. Uniqueid: 1641963309.0
  4752. Linkedid: 1641963309.0
  4753. Extension: 4166404500
  4754. Application: GotoIf
  4755. AppData: 1?valid:notvalid
  4756.  
  4757.  
  4758. [2022-01-11 23:55:26] DEBUG[3572][C-00000001]: pbx.c:2938 pbx_extension_helper: Launching 'SayDigits'
  4759.     -- Executing [4166404500@sipstation-welcome:11] SayDigits("PJSIP/fpbx-1-UNu72jBj8bHw-00000000", "14165390110") in new stack
  4760. [2022-01-11 23:55:26] DEBUG[3228]: manager.c:6200 match_filter: Examining AMI event:
  4761. Event: Newexten
  4762. Privilege: dialplan,all
  4763. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4764. ChannelState: 6
  4765. ChannelStateDesc: Up
  4766. CallerIDNum: 14165390110
  4767. CallerIDName: TORONTO      ON
  4768. ConnectedLineNum: <unknown>
  4769. ConnectedLineName: <unknown>
  4770. Language: en
  4771. AccountCode:
  4772. Context: sipstation-welcome
  4773. Exten: 4166404500
  4774. Priority: 10
  4775. Uniqueid: 1641963309.0
  4776. Linkedid: 1641963309.0
  4777. Extension: 4166404500
  4778. Application: GotoIf
  4779. AppData: 1?valid:notvalid
  4780.  
  4781.  
  4782. [2022-01-11 23:55:26] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  4783. Event: VarSet
  4784. Privilege: dialplan,all
  4785. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4786. ChannelState: 6
  4787. ChannelStateDesc: Up
  4788. CallerIDNum: 14165390110
  4789. CallerIDName: TORONTO      ON
  4790. ConnectedLineNum: <unknown>
  4791. ConnectedLineName: <unknown>
  4792. Language: en
  4793. AccountCode:
  4794. Context: sipstation-welcome
  4795. Exten: 4166404500
  4796. Priority: 9
  4797. Uniqueid: 1641963309.0
  4798. Linkedid: 1641963309.0
  4799. Variable: PLAYBACKSTATUS
  4800. Value: SUCCESS
  4801.  
  4802.  
  4803. [2022-01-11 23:55:26] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  4804. Event: Newexten
  4805. Privilege: dialplan,all
  4806. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4807. ChannelState: 6
  4808. ChannelStateDesc: Up
  4809. CallerIDNum: 14165390110
  4810. CallerIDName: TORONTO      ON
  4811. ConnectedLineNum: <unknown>
  4812. ConnectedLineName: <unknown>
  4813. Language: en
  4814. AccountCode:
  4815. Context: sipstation-welcome
  4816. Exten: 4166404500
  4817. Priority: 10
  4818. Uniqueid: 1641963309.0
  4819. Linkedid: 1641963309.0
  4820. Extension: 4166404500
  4821. Application: GotoIf
  4822. AppData: 1?valid:notvalid
  4823.  
  4824.  
  4825. [2022-01-11 23:55:26] DEBUG[3296]: manager.c:6200 match_filter: Examining AMI event:
  4826. Event: Newexten
  4827. Privilege: dialplan,all
  4828. Channel: PJSIP/fpbx-1-UNu72jBj8bHw-00000000
  4829. ChannelState: 6
  4830. ChannelStateDesc: Up
  4831. CallerIDNum: 14165390110
  4832. CallerIDName: TORONTO      ON
  4833. ConnectedLineNum: <unknown>
  4834. ConnectedLineName: <unknown>
  4835. Language: en
  4836. AccountCode:
  4837. Context: sipstation-welcome
  4838. Exten: 4166404500
  4839. Priority: 11
  4840. Uniqueid: 1641963309.0
  4841. Linkedid: 1641963309.0
  4842. Extension: 4166404500
  4843. Application: SayDigits
  4844. AppData: 14165390110
  4845.  
  4846.  
  4847. [2022-01-11 23:55:26] DEBUG[3103]: manager.c:6200 match_filter: Examining AMI event: