Local 7 digit not working

From Abhishek Thomas, 3 Months ago, written in Plain Text, viewed 3 times.
URL https://pastebin.freepbx.org/view/4d30295a Embed
Download Paste or View Raw
  1. Filter
  2. Text to filter
  3.  
  4. Highlight (None)
  5. Text to Highlight
  6.  
  7. Auto-Scroll    
  8.  
  9. 25688   [2021-02-25 11:57:25] WARNING[30993] pbx.c: Context 'sub-record-hh-check' tries to include nonexistent context 'sub-record-hh-check-custom'    
  10. 25689   [2021-02-25 11:57:25] WARNING[30993] pbx.c: Context 'sub-record-check' tries to include nonexistent context 'sub-record-check-custom'  
  11. 25690   [2021-02-25 11:57:25] WARNING[30993] pbx.c: Context 'sub-record-cancel' tries to include nonexistent context 'sub-record-cancel-custom'
  12. 25691   [2021-02-25 11:57:25] WARNING[30993] pbx.c: Context 'ext-cf-hints' tries to include nonexistent context 'ext-cf-hints-custom'  
  13. 25692   [2021-02-25 11:57:25] WARNING[30993] pbx.c: Context 'app-cf-prompting-on' tries to include nonexistent context 'app-cf-prompting-on-custom'    
  14. 25693   [2021-02-25 11:57:25] WARNING[30993] pbx.c: Context 'app-cf-on' tries to include nonexistent context 'app-cf-on-custom'
  15. 25694   [2021-02-25 11:57:25] WARNING[30993] pbx.c: Context 'app-cf-unavailable-off' tries to include nonexistent context 'app-cf-unavailable-off-custom'      
  16. 25695   [2021-02-25 11:57:25] WARNING[30993] pbx.c: Context 'app-cf-unavailable-on' tries to include nonexistent context 'app-cf-unavailable-on-custom'
  17. 25696   [2021-02-25 11:57:25] WARNING[30993] pbx.c: Context 'app-cf-unavailable-prompt-on' tries to include nonexistent context 'app-cf-unavailable-prompt-on-custom'  
  18. 25697   [2021-02-25 11:57:25] WARNING[30993] pbx.c: Context 'app-cf-off-any' tries to include nonexistent context 'app-cf-off-any-custom'      
  19. 25698   [2021-02-25 11:57:25] WARNING[30993] pbx.c: Context 'app-cf-off' tries to include nonexistent context 'app-cf-off-custom'      
  20. 25699   [2021-02-25 11:57:25] WARNING[30993] pbx.c: Context 'app-cf-busy-off' tries to include nonexistent context 'app-cf-busy-off-custom'    
  21. 25700   [2021-02-25 11:57:25] WARNING[30993] pbx.c: Context 'app-cf-busy-off-any' tries to include nonexistent context 'app-cf-busy-off-any-custom'    
  22. 25701   [2021-02-25 11:57:25] WARNING[30993] pbx.c: Context 'app-cf-busy-on' tries to include nonexistent context 'app-cf-busy-on-custom'      
  23. 25702   [2021-02-25 11:57:25] WARNING[30993] pbx.c: Context 'app-cf-busy-prompting-on' tries to include nonexistent context 'app-cf-busy-prompting-on-custom'  
  24. 25703   [2021-02-25 11:57:25] WARNING[30993] pbx.c: Context 'app-cf-toggle' tries to include nonexistent context 'app-cf-toggle-custom'
  25. 25704   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'logger' (Logger)      
  26. 25705   [2021-02-25 11:57:25] Asterisk 16.13.0 built by mockbuild @ jenkins7 on a x86_64 running Linux on 2020-09-10 17:59:26 UTC      
  27. 25706   [2021-02-25 11:57:25] VERBOSE[30993] logger.c: Asterisk Queue Logger restarted 
  28. 25707   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_config_mysql.so' (MySQL RealTime Configuration Driver)    
  29. 25708   [2021-02-25 11:57:25] VERBOSE[30993] res_config_mysql.c: MySQL RealTime reloaded.      
  30. 25709   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_config_curl.so' (Realtime Curl configuration)     
  31. 25710   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_statsd.so' (StatsD client support)
  32. 25711   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'cel' (CEL Engine)     
  33. 25712   [2021-02-25 11:57:25] VERBOSE[30993] cel.c: CEL logging enabled.       
  34. 25713   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'dnsmgr' (DNS Manager) 
  35. 25714   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'dsp' (DSP)    
  36. 25715   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'enum' (ENUM Support)  
  37. 25716   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'features' (Call Features)     
  38. 25717   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'http' (Built-in HTTP Server)  
  39. 25718   [2021-02-25 11:57:25] VERBOSE[30993] http.c: Bound HTTP server to address [::]:0       
  40. 25719   [2021-02-25 11:57:25] VERBOSE[30993] tcptls.c: TLS/SSL ECDH initialized (automatic), faster PFS ciphers enabled
  41. 25720   [2021-02-25 11:57:25] VERBOSE[30993] tcptls.c: TLS/SSL certificate ok  
  42. 25721   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'indications' (Indication Tone Handling)       
  43. 25722   [2021-02-25 11:57:25] VERBOSE[30993] indications.c: Setting default indication country to 'us' 
  44. 25723   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'acl' (Named ACL system)       
  45. 25724   [2021-02-25 11:57:25] ERROR[30993] config_options.c: Unable to load config file 'acl.conf'     
  46. 25725   [2021-02-25 11:57:25] WARNING[30993] named_acl.c: Could not reload ACL config  
  47. 25726   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'manager' (Asterisk Manager Interface) 
  48. 25727   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'plc' (PLC)    
  49. 25728   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'udptl' (UDPTL)
  50. 25729   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_pjproject.so' (PJPROJECT Log and Utility Support) 
  51. 25730   [2021-02-25 11:57:25] ERROR[30993] res_sorcery_config.c: Unable to load config file 'pjproject.conf'   
  52. 25731   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_pjsip.so' (Basic SIP resource)    
  53. 25732   [2021-02-25 11:57:25] ERROR[22651] res_pjsip_config_wizard.c: Unable to load config file 'pjsip_wizard.conf'   
  54. 25733   [2021-02-25 11:57:25] ERROR[22651] res_pjsip_config_wizard.c: Unable to load config file 'pjsip_wizard.conf'   
  55. 25734   [2021-02-25 11:57:25] ERROR[22651] res_pjsip_config_wizard.c: Unable to load config file 'pjsip_wizard.conf'   
  56. 25735   [2021-02-25 11:57:25] ERROR[22651] res_pjsip_config_wizard.c: Unable to load config file 'pjsip_wizard.conf'   
  57. 25736   [2021-02-25 11:57:25] NOTICE[22651] sorcery.c: Type 'system' is not reloadable, maintaining previous values    
  58. 25737   [2021-02-25 11:57:25] ERROR[22651] res_pjsip_config_wizard.c: Unable to load config file 'pjsip_wizard.conf'   
  59. 25738   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_pjsip_authenticator_digest.so' (PJSIP authentication resource)    
  60. 25739   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_resolver_unbound.so' (Unbound DNS Resolver Support)       
  61. 25740   [2021-02-25 11:57:25] ERROR[30993] config_options.c: Unable to load config file 'resolver_unbound.conf'
  62. 25741   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_pjsip_endpoint_identifier_ip.so' (PJSIP IP endpoint identifier)   
  63. 25742   [2021-02-25 11:57:25] ERROR[30993] res_pjsip_config_wizard.c: Unable to load config file 'pjsip_wizard.conf'   
  64. 25743   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_musiconhold.so' (Music On Hold Resource)  
  65. 25744   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_smdi.so' (Simplified Message Desk Interface (SMDI) Resource)      
  66. 25745   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_crypto.so' (Cryptographic Digital Signatures)     
  67. 25746   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_rtp_asterisk.so' (Asterisk RTP Stack)     
  68. 25747   [2021-02-25 11:57:25] VERBOSE[30993] res_rtp_asterisk.c: RTP Allocating from port range 10000 -> 20000 
  69. 25748   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_pjsip_outbound_publish.so' (PJSIP Outbound Publish Support)       
  70. 25749   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_pjsip_mwi.so' (PJSIP MWI resource)
  71. 25750   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_pjsip_publish_asterisk.so' (PJSIP Asterisk Event PUBLISH Support) 
  72. 25751   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'chan_iax2.so' (Inter Asterisk eXchange (Ver 2))       
  73. 25752   [2021-02-25 11:57:25] WARNING[30993] iax2/firmware.c: Error opening firmware directory '/var/lib/asterisk/firmware/iax': No such file or directory     
  74. 25753   [2021-02-25 11:57:25] NOTICE[30993] iax2/provision.c: No IAX provisioning configuration found, IAX provisioning disabled.      
  75. 25754   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'chan_sip.so' (Session Initiation Protocol (SIP))      
  76. 25755   [2021-02-25 11:57:25] VERBOSE[22680] chan_sip.c: Reloading SIP 
  77. 25756   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'chan_dahdi.so' (DAHDI Telephony w/PRI & SS7 & MFC/R2) 
  78. 25757   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_adsi.so' (ADSI Resource)  
  79. 25758   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_fax.so' (Generic FAX Applications)
  80. 25759   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_ari.so' (Asterisk RESTful Interface)      
  81. 25760   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_pjsip_notify.so' (CLI/AMI PJSIP NOTIFY Support)   
  82. 25761   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_pjsip_outbound_registration.so' (PJSIP Outbound Registration Support)     
  83. 25762   [2021-02-25 11:57:25] VERBOSE[22680] netsock2.c: Using SIP TOS bits 96 
  84. 25763   [2021-02-25 11:57:25] VERBOSE[22680] netsock2.c: Using SIP CoS mark 4  
  85. 25764   [2021-02-25 11:57:25] ERROR[30993] res_pjsip_config_wizard.c: Unable to load config file 'pjsip_wizard.conf'   
  86. 25765   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'app_confbridge.so' (Conference Bridge Application)    
  87. 25766   [2021-02-25 11:57:25] NOTICE[30993] confbridge/conf_config_parser.c: Adding default_menu menu to app_confbridge
  88. 25767   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'res_parking.so' (Call Parking Resource)       
  89. 25768   [2021-02-25 11:57:25] VERBOSE[30993] pbx.c: Remove parkedcalls/71/1, registrar=res_parking/default; con=<nil>((nil)); con->root=(nil)  
  90. 25769   [2021-02-25 11:57:25] VERBOSE[30993] pbx.c: Remove parkedcalls/72/1, registrar=res_parking/default; con=<nil>((nil)); con->root=(nil)  
  91. 25770   [2021-02-25 11:57:25] VERBOSE[30993] pbx.c: Remove parkedcalls/73/1, registrar=res_parking/default; con=<nil>((nil)); con->root=(nil)  
  92. 25771   [2021-02-25 11:57:25] VERBOSE[30993] pbx.c: Remove parkedcalls/74/1, registrar=res_parking/default; con=<nil>((nil)); con->root=(nil)  
  93. 25772   [2021-02-25 11:57:25] VERBOSE[30993] pbx.c: Remove parkedcalls/75/1, registrar=res_parking/default; con=<nil>((nil)); con->root=(nil)  
  94. 25773   [2021-02-25 11:57:25] VERBOSE[30993] pbx.c: Remove parkedcalls/76/1, registrar=res_parking/default; con=<nil>((nil)); con->root=(nil)  
  95. 25774   [2021-02-25 11:57:25] VERBOSE[30993] pbx.c: Remove parkedcalls/77/1, registrar=res_parking/default; con=<nil>((nil)); con->root=(nil)  
  96. 25775   [2021-02-25 11:57:25] VERBOSE[30993] pbx.c: Remove parkedcalls/78/1, registrar=res_parking/default; con=<nil>((nil)); con->root=(nil)  
  97. 25776   [2021-02-25 11:57:25] VERBOSE[30993] pbx.c: Remove parkedcalls/70/1, registrar=res_parking; con=<nil>((nil)); con->root=(nil)  
  98. 25777   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'app_meetme.so' (MeetMe conference bridge)     
  99. 25778   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'cel_manager.so' (Asterisk Manager Interface CEL Backend)      
  100. 25779   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'cel_odbc.so' (ODBC CEL backend)       
  101. 25780   [2021-02-25 11:57:25] VERBOSE[30993] cel_odbc.c: Found CEL table cel@asteriskcdrdb.    
  102. 25781   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'app_amd.so' (Answering Machine Detection Application) 
  103. 25782   [2021-02-25 11:57:25] VERBOSE[30993] app_amd.c: AMD defaults: initialSilence [2500] greeting [1500] afterGreetingSilence [800] totalAnalysisTime [5000] minimumWordLength [100] betweenWordsSilence [50] maximumNumberOfWords [3] silenceThreshold [256] maximumWordLength [5000]      
  104. 25783   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'app_playback.so' (Sound File Playback Application)    
  105. 25784   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'app_flite.so' (Flite TTS Interface)   
  106. 25785   [2021-02-25 11:57:25] WARNING[30993] app_flite.c: Flite: Unable to read config file flite.conf. Using default settings 
  107. 25786   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'codec_dahdi.so' (Generic DAHDI Transcoder Codec Translator)   
  108. 25787   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'app_voicemail.so' (Comedian Mail (Voicemail System))  
  109. 25788   [2021-02-25 11:57:25] WARNING[30993] app_voicemail.c: maxsilence should be less than minsecs or you may get empty messages     
  110. 25789   [2021-02-25 11:57:25] VERBOSE[30993] loader.c: Reloading module 'app_queue.so' (True Call Queueing)    
  111. 25790   [2021-02-25 11:57:25] NOTICE[30993] app_queue.c: queuerules.conf has not changed since it was last loaded. Not taking any action.      
  112. 25791   [2021-02-25 11:57:25] VERBOSE[30993] asterisk.c: Remote UNIX connection disconnected   
  113. 25792   [2021-02-25 11:57:47] VERBOSE[22651] pbx_variables.c: Setting global variable 'SIPDOMAIN' to '10.100.51.227'   
  114. 25793   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [01357116464@from-internal:1] Macro("PJSIP/U1981-0000000a", "user-callerid,LIMIT,EXTERNAL,") in new stack    
  115. 25794   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/U1981-0000000a", "TOUCH_MONITOR=1614254267.20") in new stack    
  116. 25795   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/U1981-0000000a", "AMPUSER=6614111") in new stack
  117. 25796   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/U1981-0000000a", "HOTDESCKCHAN=U1981-0000000a") in new stack    
  118. 25797   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/U1981-0000000a", "HOTDESKEXTEN=U1981") in new stack     
  119. 25798   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/U1981-0000000a", "HOTDESKCALL=0") in new stack  
  120. 25799   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("PJSIP/U1981-0000000a", "0?Set(HOTDESKCALL=1)") in new stack
  121. 25800   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("PJSIP/U1981-0000000a", "0?Set(CALLERID(name)=)") in new stack      
  122. 25801   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("PJSIP/U1981-0000000a", "0?report") in new stack    
  123. 25802   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("PJSIP/U1981-0000000a", "1?Set(REALCALLERIDNUM=6614111)") in new stack      
  124. 25803   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/U1981-0000000a", "AMPUSER=") in new stack      
  125. 25804   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("PJSIP/U1981-0000000a", "0?limit") in new stack    
  126. 25805   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:12] Set("PJSIP/U1981-0000000a", "AMPUSERCIDNAME=") in new stack       
  127. 25806   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/U1981-0000000a", "0?Set(__CIDMASQUERADING=TRUE)") in new stack      
  128. 25807   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("PJSIP/U1981-0000000a", "1?report") in new stack   
  129. 25808   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx_builtins.c: Goto (macro-user-callerid,s,23)       
  130. 25809   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:23] NoOp("PJSIP/U1981-0000000a", "Macro Depth is 1") in new stack     
  131. 25810   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:24] GotoIf("PJSIP/U1981-0000000a", "1?report2:macroerror") in new stack       
  132. 25811   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx_builtins.c: Goto (macro-user-callerid,s,25)       
  133. 25812   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/U1981-0000000a", "1?continue") in new stack 
  134. 25813   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx_builtins.c: Goto (macro-user-callerid,s,44)       
  135. 25814   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:44] Set("PJSIP/U1981-0000000a", "CALLERID(number)=6614111") in new stack      
  136. 25815   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:45] Set("PJSIP/U1981-0000000a", "CALLERID(name)=") in new stack       
  137. 25816   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:46] GotoIf("PJSIP/U1981-0000000a", "1?cnum") in new stack     
  138. 25817   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx_builtins.c: Goto (macro-user-callerid,s,48)       
  139. 25818   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:48] Set("PJSIP/U1981-0000000a", "CDR(cnum)=6614111") in new stack     
  140. 25819   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-user-callerid:49] Set("PJSIP/U1981-0000000a", "CHANNEL(language)=en") in new stack  
  141. 25820   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [01357116464@from-internal:2] Gosub("PJSIP/U1981-0000000a", "sub-record-check,s,1(out,01357116464,dontcare)") in new stack   
  142. 25821   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/U1981-0000000a", "0?initialized") in new stack  
  143. 25822   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/U1981-0000000a", "__REC_STATUS=INITIALIZED") in new stack  
  144. 25823   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/U1981-0000000a", "NOW=1614254267") in new stack    
  145. 25824   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/U1981-0000000a", "__DAY=25") in new stack  
  146. 25825   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/U1981-0000000a", "__MONTH=02") in new stack
  147. 25826   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/U1981-0000000a", "__YEAR=2021") in new stack       
  148. 25827   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/U1981-0000000a", "__TIMESTR=20210225-115747") in new stack 
  149. 25828   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/U1981-0000000a", "__FROMEXTEN=6614111") in new stack       
  150. 25829   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/U1981-0000000a", "__MON_FMT=wav") in new stack     
  151. 25830   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/U1981-0000000a", "Recordings initialized") in new stack  
  152. 25831   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/U1981-0000000a", "0?Set(ARG3=dontcare)") in new stack  
  153. 25832   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/U1981-0000000a", "REC_POLICY_MODE_SAVE=") in new stack    
  154. 25833   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/U1981-0000000a", "0?Set(REC_STATUS=NO)") in new stack  
  155. 25834   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/U1981-0000000a", "3?checkaction") in new stack 
  156. 25835   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx_builtins.c: Goto (sub-record-check,s,17)  
  157. 25836   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/U1981-0000000a", "1?sub-record-check,out,1") in new stack      
  158. 25837   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx_builtins.c: Goto (sub-record-check,out,1) 
  159. 25838   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [out@sub-record-check:1] NoOp("PJSIP/U1981-0000000a", "Outbound Recording Check from 6614111 to 01357116464") in new stack   
  160. 25839   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [out@sub-record-check:2] Set("PJSIP/U1981-0000000a", "RECMODE=") in new stack
  161. 25840   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [out@sub-record-check:3] ExecIf("PJSIP/U1981-0000000a", "1?Goto(routewins)") in new stack    
  162. 25841   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx_builtins.c: Goto (sub-record-check,out,7) 
  163. 25842   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [out@sub-record-check:7] Gosub("PJSIP/U1981-0000000a", "recordcheck,1(dontcare,out,01357116464)") in new stack       
  164. 25843   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/U1981-0000000a", "Starting recording check against dontcare") in new stack      
  165. 25844   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/U1981-0000000a", "dontcare") in new stack       
  166. 25845   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx_builtins.c: Goto (sub-record-check,recordcheck,3) 
  167. 25846   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/U1981-0000000a", "") in new stack     
  168. 25847   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [out@sub-record-check:8] Return("PJSIP/U1981-0000000a", "") in new stack     
  169. 25848   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [01357116464@from-internal:3] ExecIf("PJSIP/U1981-0000000a", "0 ?Set(CDR(accountcode)=)") in new stack       
  170. 25849   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [01357116464@from-internal:4] Set("PJSIP/U1981-0000000a", "_ROUTEID=7") in new stack 
  171. 25850   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [01357116464@from-internal:5] Set("PJSIP/U1981-0000000a", "_ROUTENAME=outgoing") in new stack
  172. 25851   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [01357116464@from-internal:6] Set("PJSIP/U1981-0000000a", "MOHCLASS=default") in new stack   
  173. 25852   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [01357116464@from-internal:7] Set("PJSIP/U1981-0000000a", "_CALLERIDNAMEINTERNAL=") in new stack     
  174. 25853   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [01357116464@from-internal:8] Set("PJSIP/U1981-0000000a", "_CALLERIDNUMINTERNAL=6614111") in new stack       
  175. 25854   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [01357116464@from-internal:9] Set("PJSIP/U1981-0000000a", "_EMAILNOTIFICATION=FALSE") in new stack   
  176. 25855   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [01357116464@from-internal:10] Set("PJSIP/U1981-0000000a", "_NODEST=") in new stack  
  177. 25856   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [01357116464@from-internal:11] Macro("PJSIP/U1981-0000000a", "dialout-trunk,1,01357116464,,off") in new stack
  178. 25857   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:1] Set("PJSIP/U1981-0000000a", "DIAL_TRUNK=1") in new stack   
  179. 25858   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("PJSIP/U1981-0000000a", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack    
  180. 25859   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("PJSIP/U1981-0000000a", "0?sub-pincheck,s,1()") in new stack       
  181. 25860   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("PJSIP/U1981-0000000a", "0?Set(CALLERID(num)=)") in new stack       
  182. 25861   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("PJSIP/U1981-0000000a", "0?disabletrunk,1") in new stack    
  183. 25862   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:6] Set("PJSIP/U1981-0000000a", "DIAL_NUMBER=01357116464") in new stack
  184. 25863   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:7] Set("PJSIP/U1981-0000000a", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack       
  185. 25864   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:8] Set("PJSIP/U1981-0000000a", "OUTBOUND_GROUP=OUT_1") in new stack   
  186. 25865   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:9] Set("PJSIP/U1981-0000000a", "DIAL_TRUNK_OPTIONS=T") in new stack   
  187. 25866   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("PJSIP/U1981-0000000a", "0?nomax") in new stack    
  188. 25867   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf("PJSIP/U1981-0000000a", "0?chanfull") in new stack 
  189. 25868   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("PJSIP/U1981-0000000a", "0?skipoutcid") in new stack       
  190. 25869   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("PJSIP/U1981-0000000a", "outbound-callerid,1") in new stack 
  191. 25870   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("PJSIP/U1981-0000000a", "6614111") in new stack   
  192. 25871   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("PJSIP/U1981-0000000a", "") in new stack  
  193. 25872   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("PJSIP/U1981-0000000a", "off") in new stack       
  194. 25873   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("PJSIP/U1981-0000000a", "0?Set(CALLERPRES(name-pres)=)") in new stack   
  195. 25874   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("PJSIP/U1981-0000000a", "0?Set(CALLERPRES(num-pres)=)") in new stack    
  196. 25875   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:6] Set("PJSIP/U1981-0000000a", "HOTDESCKCHAN=U1981-0000000a") in new stack
  197. 25876   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:7] Set("PJSIP/U1981-0000000a", "HOTDESKEXTEN=U1981") in new stack 
  198. 25877   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:8] Set("PJSIP/U1981-0000000a", "HOTDESKCALL=0") in new stack      
  199. 25878   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("PJSIP/U1981-0000000a", "0?Set(HOTDESKCALL=1)") in new stack    
  200. 25879   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("PJSIP/U1981-0000000a", "0?Set(CALLERID(name)=)") in new stack 
  201. 25880   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:11] Set("PJSIP/U1981-0000000a", "ALLOWTHISROUTE=NO") in new stack 
  202. 25881   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("PJSIP/U1981-0000000a", "0?Set(ALLOWTHISROUTE=YES)") in new stack      
  203. 25882   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("PJSIP/U1981-0000000a", "0?Hangup()") in new stack     
  204. 25883   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("PJSIP/U1981-0000000a", "0?Set(REALCALLERIDNUM=6614111)") in new stack 
  205. 25884   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("PJSIP/U1981-0000000a", "0?Set(AMPUSER=6614111)") in new stack 
  206. 25885   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("PJSIP/U1981-0000000a", "1?normcid") in new stack      
  207. 25886   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)   
  208. 25887   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:20] Set("PJSIP/U1981-0000000a", "USEROUTCID=") in new stack       
  209. 25888   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:21] Set("PJSIP/U1981-0000000a", "EMERGENCYCID=") in new stack     
  210. 25889   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("PJSIP/U1981-0000000a", "0?Set(EMERGENCYCID=)") in new stack   
  211. 25890   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:23] Set("PJSIP/U1981-0000000a", "TRUNKOUTCID=") in new stack      
  212. 25891   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf("PJSIP/U1981-0000000a", "1?trunkcid") in new stack     
  213. 25892   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx_builtins.c: Goto (macro-outbound-callerid,s,30)   
  214. 25893   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf("PJSIP/U1981-0000000a", "0?Set(CALLERID(all)=)") in new stack  
  215. 25894   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("PJSIP/U1981-0000000a", "0?Set(CALLERID(all)=)") in new stack  
  216. 25895   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf("PJSIP/U1981-0000000a", "0?Set(CALLERID(all)=)") in new stack  
  217. 25896   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("PJSIP/U1981-0000000a", "0?Set(CALLERID(all)=6614111)") in new stack   
  218. 25897   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:34] Set("PJSIP/U1981-0000000a", "TIOHIDE=no") in new stack
  219. 25898   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:35] ExecIf("PJSIP/U1981-0000000a", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack      
  220. 25899   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("PJSIP/U1981-0000000a", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack       
  221. 25900   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("PJSIP/U1981-0000000a", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack      
  222. 25901   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf("PJSIP/U1981-0000000a", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack       
  223. 25902   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:39] Set("PJSIP/U1981-0000000a", "CDR(outbound_cnum)=6614111") in new stack
  224. 25903   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-outbound-callerid:40] Set("PJSIP/U1981-0000000a", "CDR(outbound_cnam)=") in new stack       
  225. 25904   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("PJSIP/U1981-0000000a", "0?sub-flp-1,s,1()") in new stack 
  226. 25905   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:15] Set("PJSIP/U1981-0000000a", "OUTNUM=01357116464") in new stack    
  227. 25906   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:16] Set("PJSIP/U1981-0000000a", "custom=SIP/tatasip") in new stack    
  228. 25907   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("PJSIP/U1981-0000000a", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack     
  229. 25908   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("PJSIP/U1981-0000000a", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack      
  230. 25909   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("PJSIP/U1981-0000000a", "dialout-trunk-predial-hook,") in new stack 
  231. 25910   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] NoOp("PJSIP/U1981-0000000a", "Adding caller ID for TATA") in new stack
  232. 25911   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:2] SIPAddHeader("PJSIP/U1981-0000000a", "P-Preferred-Identity: <sip:6614100@10.50.185.2>") in new stack  
  233. 25912   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:3] MacroExit("PJSIP/U1981-0000000a", "") in new stack    
  234. 25913   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("PJSIP/U1981-0000000a", "0?skipcrm") in new stack  
  235. 25914   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:21] Set("PJSIP/U1981-0000000a", "__CRM_DIRECTION=OUTBOUND") in new stack      
  236. 25915   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:22] Set("PJSIP/U1981-0000000a", "__CRM_DESTINATION=01357116464") in new stack 
  237. 25916   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:23] Set("PJSIP/U1981-0000000a", "__CRM_SOURCE=") in new stack 
  238. 25917   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:24] AGI("PJSIP/U1981-0000000a", "agi://127.0.0.1/sangomacrm.agi") in new stack
  239. 25918   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] res_agi.c: <PJSIP/U1981-0000000a>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0     
  240. 25919   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:25] Set("PJSIP/U1981-0000000a", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack   
  241. 25920   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp("PJSIP/U1981-0000000a", "CRM Finished") in new stack 
  242. 25921   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf("PJSIP/U1981-0000000a", "0?bypass,1") in new stack 
  243. 25922   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf("PJSIP/U1981-0000000a", "0?Set(CONNECTEDLINE(num,i)=01357116464)") in new stack    
  244. 25923   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("PJSIP/U1981-0000000a", "0?Set(CONNECTEDLINE(name,i)=CID:6614111)") in new stack   
  245. 25924   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("PJSIP/U1981-0000000a", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)6614111)") in new stack   
  246. 25925   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("PJSIP/U1981-0000000a", "0?customtrunk") in new stack      
  247. 25926   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf("PJSIP/U1981-0000000a", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack 
  248. 25927   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:33] Set("PJSIP/U1981-0000000a", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack   
  249. 25928   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:34] Dial("PJSIP/U1981-0000000a", "SIP/tatasip/01357116464,300,Tb(func-apply-sipheaders^s^1,(1))U(sub-send-obroute-email^01357116464^01357116464^1^1614254267^^6614111)") in new stack 
  250. 25929   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] netsock2.c: Using SIP RTP TOS bits 184
  251. 25930   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] netsock2.c: Using SIP RTP CoS mark 5  
  252. 25931   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] app_stack.c: SIP/tatasip-0000000a Internal Gosub(func-apply-sipheaders,s,1(1)) start  
  253. 25932   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/tatasip-0000000a", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  254. 25933   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/tatasip-0000000a", "Applying SIP Headers to channel SIP/tatasip-0000000a") in new stack
  255. 25934   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/tatasip-0000000a", "TECH=SIP") in new stack     
  256. 25935   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/tatasip-0000000a", "SIPHEADERKEYS=Alert-Info") in new stack     
  257. 25936   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/tatasip-0000000a", "1") in new stack  
  258. 25937   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/tatasip-0000000a", "sipheader=unset") in new stack      
  259. 25938   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("SIP/tatasip-0000000a", "1?SIPRemoveHeader(Alert-Info:)") in new stack    
  260. 25939   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("SIP/tatasip-0000000a", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack   
  261. 25940   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("SIP/tatasip-0000000a", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack    
  262. 25941   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("SIP/tatasip-0000000a", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack 
  263. 25942   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("SIP/tatasip-0000000a", "0?SIPAddHeader(Alert-Info:unset)") in new stack 
  264. 25943   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("SIP/tatasip-0000000a", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack
  265. 25944   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile("SIP/tatasip-0000000a", "") in new stack       
  266. 25945   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/tatasip-0000000a", "0") in new stack  
  267. 25946   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/tatasip-0000000a", "") in new stack 
  268. 25947   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] app_stack.c: Spawn extension (tata, 01357116464, 1) exited non-zero on 'SIP/tatasip-0000000a' 
  269. 25948   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] app_stack.c: SIP/tatasip-0000000a Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL= 
  270. 25949   [2021-02-25 11:57:47] VERBOSE[31227][C-0000000b] app_dial.c: Called SIP/tatasip/01357116464    
  271. 25950   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] app_dial.c: SIP/tatasip-0000000a redirecting info has changed, passing it to PJSIP/U1981-0000000a     
  272. 25951   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] app_dial.c: SIP/tatasip-0000000a is busy      
  273. 25952   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] app_dial.c: Everyone is busy/congested at this time (1:1/0/0) 
  274. 25953   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:35] NoOp("PJSIP/U1981-0000000a", "Dial failed for some reason with DIALSTATUS = BUSY and HANGUPCAUSE = 19") in new stack      
  275. 25954   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-dialout-trunk:36] GotoIf("PJSIP/U1981-0000000a", "0?continue,1:s-BUSY,1") in new stack      
  276. 25955   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx_builtins.c: Goto (macro-dialout-trunk,s-BUSY,1)   
  277. 25956   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s-BUSY@macro-dialout-trunk:1] NoOp("PJSIP/U1981-0000000a", "Dial failed due to trunk reporting BUSY - giving up") in new stack      
  278. 25957   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s-BUSY@macro-dialout-trunk:2] PlayTones("PJSIP/U1981-0000000a", "busy") in new stack
  279. 25958   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s-BUSY@macro-dialout-trunk:3] Busy("PJSIP/U1981-0000000a", "20") in new stack       
  280. 25959   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] app_macro.c: Spawn extension (macro-dialout-trunk, s-BUSY, 3) exited non-zero on 'PJSIP/U1981-0000000a' in macro 'dialout-trunk'      
  281. 25960   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Spawn extension (from-internal, 01357116464, 11) exited non-zero on 'PJSIP/U1981-0000000a'     
  282. 25961   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [h@from-internal:1] Macro("PJSIP/U1981-0000000a", "hangupcall") in new stack 
  283. 25962   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/U1981-0000000a", "1?theend") in new stack       
  284. 25963   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx_builtins.c: Goto (macro-hangupcall,s,3)   
  285. 25964   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/U1981-0000000a", "0?Set(CDR(recordingfile)=)") in new stack     
  286. 25965   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-hangupcall:4] NoOp("PJSIP/U1981-0000000a", " montior file= ") in new stack  
  287. 25966   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("PJSIP/U1981-0000000a", "1?skipagi") in new stack      
  288. 25967   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx_builtins.c: Goto (macro-hangupcall,s,7)   
  289. 25968   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@macro-hangupcall:7] Hangup("PJSIP/U1981-0000000a", "") in new stack       
  290. 25969   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'PJSIP/U1981-0000000a' in macro 'hangupcall' 
  291. 25970   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/U1981-0000000a'
  292. 25971   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] app_stack.c: PJSIP/U1981-0000000a Internal Gosub(crm-hangup,s,1) start
  293. 25972   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/U1981-0000000a", "Sending Hangup to CRM") in new stack  
  294. 25973   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/U1981-0000000a", "HANGUP CAUSE: 17") in new stack       
  295. 25974   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/U1981-0000000a", "0?Set(__CRM_VOICEMAIL=)") in new stack      
  296. 25975   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/U1981-0000000a", "MASTER CHANNEL: 1614254267.20 = 1614254267.20") in new stack  
  297. 25976   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/U1981-0000000a", "0?return") in new stack     
  298. 25977   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@crm-hangup:6] Set("PJSIP/U1981-0000000a", "__CRM_HANGUP=1") in new stack  
  299. 25978   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@crm-hangup:7] AGI("PJSIP/U1981-0000000a", "agi://127.0.0.1/sangomacrm.agi") in new stack  
  300. 25979   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] res_agi.c: <PJSIP/U1981-0000000a>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0     
  301. 25980   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/U1981-0000000a", "") in new stack     
  302. 25981   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/U1981-0000000a'  
  303. 25982   [2021-02-25 11:57:48] VERBOSE[31227][C-0000000b] app_stack.c: PJSIP/U1981-0000000a Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=       
  304. 25983   [2021-02-25 11:58:07] VERBOSE[22680] chan_sip.c: Registered SIP '2002' at 10.100.50.228:61342  
  305. 25984   [2021-02-25 11:58:07] NOTICE[22680] chan_sip.c: Peer '2002' is now Reachable. (2ms / 2000ms)   
  306. 25985   [2021-02-25 11:58:14] VERBOSE[22680][C-0000000c] netsock2.c: Using SIP RTP TOS bits 184
  307. 25986   [2021-02-25 11:58:14] VERBOSE[22680][C-0000000c] netsock2.c: Using SIP RTP CoS mark 5  
  308. 25987   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [01357116464@from-internal:1] Macro("SIP/2002-0000000b", "user-callerid,LIMIT,EXTERNAL,") in new stack       
  309. 25988   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/2002-0000000b", "TOUCH_MONITOR=1614254294.22") in new stack       
  310. 25989   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/2002-0000000b", "AMPUSER=2002") in new stack      
  311. 25990   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/2002-0000000b", "HOTDESCKCHAN=2002-0000000b") in new stack
  312. 25991   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/2002-0000000b", "HOTDESKEXTEN=2002") in new stack 
  313. 25992   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/2002-0000000b", "HOTDESKCALL=0") in new stack     
  314. 25993   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/2002-0000000b", "0?Set(HOTDESKCALL=1)") in new stack   
  315. 25994   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/2002-0000000b", "0?Set(CALLERID(name)=)") in new stack 
  316. 25995   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/2002-0000000b", "0?report") in new stack       
  317. 25996   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/2002-0000000b", "1?Set(REALCALLERIDNUM=2002)") in new stack    
  318. 25997   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/2002-0000000b", "AMPUSER=2002") in new stack     
  319. 25998   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/2002-0000000b", "0?limit") in new stack       
  320. 25999   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/2002-0000000b", "AMPUSERCIDNAME=6614100") in new stack   
  321. 26000   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/2002-0000000b", "0?Set(__CIDMASQUERADING=TRUE)") in new stack 
  322. 26001   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/2002-0000000b", "0?report") in new stack      
  323. 26002   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/2002-0000000b", "AMPUSERCID=2002") in new stack  
  324. 26003   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:16] Set("SIP/2002-0000000b", "__DIAL_OPTIONS=HhTtr") in new stack     
  325. 26004   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/2002-0000000b", "CALLERID(all)="6614100" <2002>") in new stack   
  326. 26005   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("SIP/2002-0000000b", "0?Set(CUSDIAL=)") in new stack       
  327. 26006   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("SIP/2002-0000000b", "0?Set(CALLERID(all)="6614100" <2002>)") in new stack 
  328. 26007   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:20] GotoIf("SIP/2002-0000000b", "0?limit") in new stack       
  329. 26008   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:21] ExecIf("SIP/2002-0000000b", "1?Set(GROUP(concurrency_limit)=2002)") in new stack  
  330. 26009   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:22] ExecIf("SIP/2002-0000000b", "0?Set(CHANNEL(language)=)") in new stack     
  331. 26010   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:23] NoOp("SIP/2002-0000000b", "Macro Depth is 1") in new stack
  332. 26011   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:24] GotoIf("SIP/2002-0000000b", "1?report2:macroerror") in new stack  
  333. 26012   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx_builtins.c: Goto (macro-user-callerid,s,25)       
  334. 26013   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("SIP/2002-0000000b", "1?continue") in new stack    
  335. 26014   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx_builtins.c: Goto (macro-user-callerid,s,44)       
  336. 26015   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:44] Set("SIP/2002-0000000b", "CALLERID(number)=2002") in new stack    
  337. 26016   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:45] Set("SIP/2002-0000000b", "CALLERID(name)=6614100") in new stack   
  338. 26017   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:46] GotoIf("SIP/2002-0000000b", "0?cnum") in new stack
  339. 26018   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:47] Set("SIP/2002-0000000b", "CDR(cnam)=6614100") in new stack
  340. 26019   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:48] Set("SIP/2002-0000000b", "CDR(cnum)=2002") in new stack   
  341. 26020   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/2002-0000000b", "CHANNEL(language)=en") in new stack     
  342. 26021   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [01357116464@from-internal:2] Gosub("SIP/2002-0000000b", "sub-record-check,s,1(out,01357116464,dontcare)") in new stack      
  343. 26022   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/2002-0000000b", "0?initialized") in new stack     
  344. 26023   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@sub-record-check:2] Set("SIP/2002-0000000b", "__REC_STATUS=INITIALIZED") in new stack     
  345. 26024   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@sub-record-check:3] Set("SIP/2002-0000000b", "NOW=1614254294") in new stack       
  346. 26025   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@sub-record-check:4] Set("SIP/2002-0000000b", "__DAY=25") in new stack     
  347. 26026   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@sub-record-check:5] Set("SIP/2002-0000000b", "__MONTH=02") in new stack   
  348. 26027   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@sub-record-check:6] Set("SIP/2002-0000000b", "__YEAR=2021") in new stack  
  349. 26028   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@sub-record-check:7] Set("SIP/2002-0000000b", "__TIMESTR=20210225-115814") in new stack    
  350. 26029   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@sub-record-check:8] Set("SIP/2002-0000000b", "__FROMEXTEN=2002") in new stack     
  351. 26030   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@sub-record-check:9] Set("SIP/2002-0000000b", "__MON_FMT=wav") in new stack
  352. 26031   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/2002-0000000b", "Recordings initialized") in new stack     
  353. 26032   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/2002-0000000b", "0?Set(ARG3=dontcare)") in new stack     
  354. 26033   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@sub-record-check:12] Set("SIP/2002-0000000b", "REC_POLICY_MODE_SAVE=") in new stack       
  355. 26034   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/2002-0000000b", "0?Set(REC_STATUS=NO)") in new stack     
  356. 26035   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/2002-0000000b", "3?checkaction") in new stack    
  357. 26036   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx_builtins.c: Goto (sub-record-check,s,17)  
  358. 26037   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/2002-0000000b", "1?sub-record-check,out,1") in new stack 
  359. 26038   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx_builtins.c: Goto (sub-record-check,out,1) 
  360. 26039   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [out@sub-record-check:1] NoOp("SIP/2002-0000000b", "Outbound Recording Check from 2002 to 01357116464") in new stack 
  361. 26040   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [out@sub-record-check:2] Set("SIP/2002-0000000b", "RECMODE=dontcare") in new stack   
  362. 26041   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [out@sub-record-check:3] ExecIf("SIP/2002-0000000b", "1?Goto(routewins)") in new stack       
  363. 26042   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx_builtins.c: Goto (sub-record-check,out,7) 
  364. 26043   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [out@sub-record-check:7] Gosub("SIP/2002-0000000b", "recordcheck,1(dontcare,out,01357116464)") in new stack  
  365. 26044   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/2002-0000000b", "Starting recording check against dontcare") in new stack 
  366. 26045   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/2002-0000000b", "dontcare") in new stack  
  367. 26046   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx_builtins.c: Goto (sub-record-check,recordcheck,3) 
  368. 26047   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/2002-0000000b", "") in new stack
  369. 26048   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [out@sub-record-check:8] Return("SIP/2002-0000000b", "") in new stack
  370. 26049   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [01357116464@from-internal:3] ExecIf("SIP/2002-0000000b", "0 ?Set(CDR(accountcode)=)") in new stack  
  371. 26050   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [01357116464@from-internal:4] Set("SIP/2002-0000000b", "_ROUTEID=7") in new stack    
  372. 26051   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [01357116464@from-internal:5] Set("SIP/2002-0000000b", "_ROUTENAME=outgoing") in new stack   
  373. 26052   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [01357116464@from-internal:6] Set("SIP/2002-0000000b", "MOHCLASS=default") in new stack      
  374. 26053   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [01357116464@from-internal:7] Set("SIP/2002-0000000b", "_CALLERIDNAMEINTERNAL=6614100") in new stack 
  375. 26054   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [01357116464@from-internal:8] Set("SIP/2002-0000000b", "_CALLERIDNUMINTERNAL=2002") in new stack     
  376. 26055   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [01357116464@from-internal:9] Set("SIP/2002-0000000b", "_EMAILNOTIFICATION=FALSE") in new stack      
  377. 26056   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [01357116464@from-internal:10] Set("SIP/2002-0000000b", "_NODEST=") in new stack     
  378. 26057   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [01357116464@from-internal:11] Macro("SIP/2002-0000000b", "dialout-trunk,1,01357116464,,off") in new stack   
  379. 26058   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:1] Set("SIP/2002-0000000b", "DIAL_TRUNK=1") in new stack      
  380. 26059   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("SIP/2002-0000000b", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack       
  381. 26060   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("SIP/2002-0000000b", "0?sub-pincheck,s,1()") in new stack  
  382. 26061   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("SIP/2002-0000000b", "0?Set(CALLERID(num)=2002)") in new stack      
  383. 26062   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("SIP/2002-0000000b", "0?disabletrunk,1") in new stack       
  384. 26063   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:6] Set("SIP/2002-0000000b", "DIAL_NUMBER=01357116464") in new stack   
  385. 26064   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:7] Set("SIP/2002-0000000b", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack  
  386. 26065   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:8] Set("SIP/2002-0000000b", "OUTBOUND_GROUP=OUT_1") in new stack      
  387. 26066   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:9] Set("SIP/2002-0000000b", "DIAL_TRUNK_OPTIONS=T") in new stack      
  388. 26067   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("SIP/2002-0000000b", "0?nomax") in new stack       
  389. 26068   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf("SIP/2002-0000000b", "0?chanfull") in new stack    
  390. 26069   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("SIP/2002-0000000b", "0?skipoutcid") in new stack  
  391. 26070   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("SIP/2002-0000000b", "outbound-callerid,1") in new stack    
  392. 26071   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("SIP/2002-0000000b", "2002") in new stack 
  393. 26072   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("SIP/2002-0000000b", "") in new stack     
  394. 26073   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("SIP/2002-0000000b", "off") in new stack  
  395. 26074   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("SIP/2002-0000000b", "0?Set(CALLERPRES(name-pres)=)") in new stack      
  396. 26075   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("SIP/2002-0000000b", "0?Set(CALLERPRES(num-pres)=)") in new stack       
  397. 26076   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:6] Set("SIP/2002-0000000b", "HOTDESCKCHAN=2002-0000000b") in new stack    
  398. 26077   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:7] Set("SIP/2002-0000000b", "HOTDESKEXTEN=2002") in new stack     
  399. 26078   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:8] Set("SIP/2002-0000000b", "HOTDESKCALL=0") in new stack 
  400. 26079   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("SIP/2002-0000000b", "0?Set(HOTDESKCALL=1)") in new stack       
  401. 26080   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("SIP/2002-0000000b", "0?Set(CALLERID(name)=)") in new stack    
  402. 26081   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:11] Set("SIP/2002-0000000b", "ALLOWTHISROUTE=NO") in new stack    
  403. 26082   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("SIP/2002-0000000b", "0?Set(ALLOWTHISROUTE=YES)") in new stack 
  404. 26083   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("SIP/2002-0000000b", "0?Hangup()") in new stack
  405. 26084   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("SIP/2002-0000000b", "0?Set(REALCALLERIDNUM=2002)") in new stack       
  406. 26085   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("SIP/2002-0000000b", "0?Set(AMPUSER=2002)") in new stack       
  407. 26086   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("SIP/2002-0000000b", "1?normcid") in new stack 
  408. 26087   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)   
  409. 26088   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:20] Set("SIP/2002-0000000b", "USEROUTCID=6614100") in new stack   
  410. 26089   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:21] Set("SIP/2002-0000000b", "EMERGENCYCID=") in new stack
  411. 26090   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("SIP/2002-0000000b", "0?Set(EMERGENCYCID=)") in new stack      
  412. 26091   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:23] Set("SIP/2002-0000000b", "TRUNKOUTCID=") in new stack 
  413. 26092   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf("SIP/2002-0000000b", "1?trunkcid") in new stack
  414. 26093   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx_builtins.c: Goto (macro-outbound-callerid,s,30)   
  415. 26094   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf("SIP/2002-0000000b", "0?Set(CALLERID(all)=)") in new stack     
  416. 26095   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("SIP/2002-0000000b", "1?Set(CALLERID(all)=6614100)") in new stack      
  417. 26096   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf("SIP/2002-0000000b", "0?Set(CALLERID(all)=)") in new stack     
  418. 26097   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("SIP/2002-0000000b", "0?Set(CALLERID(all)=2002)") in new stack 
  419. 26098   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:34] Set("SIP/2002-0000000b", "TIOHIDE=no") in new stack   
  420. 26099   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:35] ExecIf("SIP/2002-0000000b", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack 
  421. 26100   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("SIP/2002-0000000b", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack  
  422. 26101   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("SIP/2002-0000000b", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack 
  423. 26102   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf("SIP/2002-0000000b", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack  
  424. 26103   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:39] Set("SIP/2002-0000000b", "CDR(outbound_cnum)=6614100") in new stack   
  425. 26104   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-outbound-callerid:40] Set("SIP/2002-0000000b", "CDR(outbound_cnam)=") in new stack  
  426. 26105   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("SIP/2002-0000000b", "0?sub-flp-1,s,1()") in new stack    
  427. 26106   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:15] Set("SIP/2002-0000000b", "OUTNUM=01357116464") in new stack       
  428. 26107   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:16] Set("SIP/2002-0000000b", "custom=SIP/tatasip") in new stack       
  429. 26108   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("SIP/2002-0000000b", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
  430. 26109   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("SIP/2002-0000000b", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack 
  431. 26110   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("SIP/2002-0000000b", "dialout-trunk-predial-hook,") in new stack    
  432. 26111   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] NoOp("SIP/2002-0000000b", "Adding caller ID for TATA") in new stack   
  433. 26112   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:2] SIPAddHeader("SIP/2002-0000000b", "P-Preferred-Identity: <sip:6614100@10.50.185.2>") in new stack     
  434. 26113   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:3] MacroExit("SIP/2002-0000000b", "") in new stack       
  435. 26114   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("SIP/2002-0000000b", "0?skipcrm") in new stack     
  436. 26115   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:21] Set("SIP/2002-0000000b", "__CRM_DIRECTION=OUTBOUND") in new stack 
  437. 26116   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:22] Set("SIP/2002-0000000b", "__CRM_DESTINATION=01357116464") in new stack    
  438. 26117   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:23] Set("SIP/2002-0000000b", "__CRM_SOURCE=2002") in new stack
  439. 26118   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:24] AGI("SIP/2002-0000000b", "agi://127.0.0.1/sangomacrm.agi") in new stack   
  440. 26119   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] res_agi.c: <SIP/2002-0000000b>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
  441. 26120   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:25] Set("SIP/2002-0000000b", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack      
  442. 26121   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp("SIP/2002-0000000b", "CRM Finished") in new stack    
  443. 26122   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf("SIP/2002-0000000b", "0?bypass,1") in new stack    
  444. 26123   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf("SIP/2002-0000000b", "1?Set(CONNECTEDLINE(num,i)=01357116464)") in new stack       
  445. 26124   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("SIP/2002-0000000b", "1?Set(CONNECTEDLINE(name,i)=CID:6614100)") in new stack      
  446. 26125   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("SIP/2002-0000000b", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)6614100)") in new stack      
  447. 26126   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("SIP/2002-0000000b", "0?customtrunk") in new stack 
  448. 26127   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf("SIP/2002-0000000b", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack    
  449. 26128   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:33] Set("SIP/2002-0000000b", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack      
  450. 26129   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:34] Dial("SIP/2002-0000000b", "SIP/tatasip/01357116464,300,Tb(func-apply-sipheaders^s^1,(1))U(sub-send-obroute-email^01357116464^01357116464^1^1614254294^^6614100)") in new stack    
  451. 26130   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] netsock2.c: Using SIP RTP TOS bits 184
  452. 26131   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] netsock2.c: Using SIP RTP CoS mark 5  
  453. 26132   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] app_stack.c: SIP/tatasip-0000000c Internal Gosub(func-apply-sipheaders,s,1(1)) start  
  454. 26133   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/tatasip-0000000c", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  455. 26134   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/tatasip-0000000c", "Applying SIP Headers to channel SIP/tatasip-0000000c") in new stack
  456. 26135   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/tatasip-0000000c", "TECH=SIP") in new stack     
  457. 26136   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/tatasip-0000000c", "SIPHEADERKEYS=Alert-Info") in new stack     
  458. 26137   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/tatasip-0000000c", "1") in new stack  
  459. 26138   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/tatasip-0000000c", "sipheader=unset") in new stack      
  460. 26139   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("SIP/tatasip-0000000c", "1?SIPRemoveHeader(Alert-Info:)") in new stack    
  461. 26140   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("SIP/tatasip-0000000c", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack   
  462. 26141   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("SIP/tatasip-0000000c", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack    
  463. 26142   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("SIP/tatasip-0000000c", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack 
  464. 26143   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("SIP/tatasip-0000000c", "0?SIPAddHeader(Alert-Info:unset)") in new stack 
  465. 26144   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("SIP/tatasip-0000000c", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack
  466. 26145   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile("SIP/tatasip-0000000c", "") in new stack       
  467. 26146   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/tatasip-0000000c", "0") in new stack  
  468. 26147   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/tatasip-0000000c", "") in new stack 
  469. 26148   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] app_stack.c: Spawn extension (tata, 01357116464, 1) exited non-zero on 'SIP/tatasip-0000000c' 
  470. 26149   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] app_stack.c: SIP/tatasip-0000000c Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL= 
  471. 26150   [2021-02-25 11:58:14] VERBOSE[31348][C-0000000c] app_dial.c: Called SIP/tatasip/01357116464    
  472. 26151   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] app_dial.c: SIP/tatasip-0000000c redirecting info has changed, passing it to SIP/2002-0000000b
  473. 26152   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] app_dial.c: SIP/tatasip-0000000c is busy      
  474. 26153   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] app_dial.c: Everyone is busy/congested at this time (1:1/0/0) 
  475. 26154   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:35] NoOp("SIP/2002-0000000b", "Dial failed for some reason with DIALSTATUS = BUSY and HANGUPCAUSE = 19") in new stack 
  476. 26155   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-dialout-trunk:36] GotoIf("SIP/2002-0000000b", "0?continue,1:s-BUSY,1") in new stack 
  477. 26156   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx_builtins.c: Goto (macro-dialout-trunk,s-BUSY,1)   
  478. 26157   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s-BUSY@macro-dialout-trunk:1] NoOp("SIP/2002-0000000b", "Dial failed due to trunk reporting BUSY - giving up") in new stack 
  479. 26158   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s-BUSY@macro-dialout-trunk:2] PlayTones("SIP/2002-0000000b", "busy") in new stack   
  480. 26159   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s-BUSY@macro-dialout-trunk:3] Busy("SIP/2002-0000000b", "20") in new stack  
  481. 26160   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] app_macro.c: Spawn extension (macro-dialout-trunk, s-BUSY, 3) exited non-zero on 'SIP/2002-0000000b' in macro 'dialout-trunk' 
  482. 26161   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Spawn extension (from-internal, 01357116464, 11) exited non-zero on 'SIP/2002-0000000b'
  483. 26162   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [h@from-internal:1] Macro("SIP/2002-0000000b", "hangupcall") in new stack    
  484. 26163   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/2002-0000000b", "1?theend") in new stack  
  485. 26164   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx_builtins.c: Goto (macro-hangupcall,s,3)   
  486. 26165   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/2002-0000000b", "0?Set(CDR(recordingfile)=)") in new stack
  487. 26166   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/2002-0000000b", " montior file= ") in new stack     
  488. 26167   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/2002-0000000b", "1?skipagi") in new stack 
  489. 26168   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx_builtins.c: Goto (macro-hangupcall,s,7)   
  490. 26169   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/2002-0000000b", "") in new stack  
  491. 26170   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/2002-0000000b' in macro 'hangupcall'    
  492. 26171   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/2002-0000000b'   
  493. 26172   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] app_stack.c: SIP/2002-0000000b Internal Gosub(crm-hangup,s,1) start   
  494. 26173   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/2002-0000000b", "Sending Hangup to CRM") in new stack     
  495. 26174   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/2002-0000000b", "HANGUP CAUSE: 17") in new stack  
  496. 26175   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/2002-0000000b", "0?Set(__CRM_VOICEMAIL=)") in new stack 
  497. 26176   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/2002-0000000b", "MASTER CHANNEL: 1614254294.22 = 1614254294.22") in new stack     
  498. 26177   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/2002-0000000b", "0?return") in new stack
  499. 26178   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@crm-hangup:6] Set("SIP/2002-0000000b", "__CRM_HANGUP=1") in new stack     
  500. 26179   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/2002-0000000b", "agi://127.0.0.1/sangomacrm.agi") in new stack     
  501. 26180   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] res_agi.c: <SIP/2002-0000000b>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
  502. 26181   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] pbx.c: Executing [s@crm-hangup:8] Return("SIP/2002-0000000b", "") in new stack
  503. 26182   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/2002-0000000b'     
  504. 26183   [2021-02-25 11:58:15] VERBOSE[31348][C-0000000c] app_stack.c: SIP/2002-0000000b Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=  
  505. 26184   [2021-02-25 11:58:52] VERBOSE[22680] chan_sip.c:       
  506. 26185   <--- SIP read from UDP:10.100.50.228:61342 --->
  507. 26186          
  508. 26187   <------------->
  509. 26188   [2021-02-25 11:59:01] VERBOSE[22680] chan_sip.c:       
  510. 26189   <--- SIP read from UDP:10.100.50.228:61342 --->
  511. 26190   INVITE sip:01357116464@10.100.51.227 SIP/2.0   
  512. 26191   Via: SIP/2.0/UDP 10.100.50.228:61342;rport;branch=z9hG4bKPja0bd1c8dfef34a018077fbbe0bd7a9f4    
  513. 26192   Max-Forwards: 70       
  514. 26193   From: <sip:2002@10.100.51.227>;tag=31716915f93d4e5dbeab781c6d204f4a    
  515. 26194   To: <sip:01357116464@10.100.51.227>    
  516. 26195   Contact: <sip:2002@10.100.50.228:61342;ob>     
  517. 26196   Call-ID: a8e57e02b54441c2a92d7c6aeef2d2b5      
  518. 26197   CSeq: 26279 INVITE     
  519. 26198   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS       
  520. 26199   Supported: replaces, 100rel, timer, norefersub 
  521. 26200   Session-Expires: 1800  
  522. 26201   Min-SE: 90     
  523. 26202   User-Agent: MicroSIP/3.20.3    
  524. 26203   Content-Type: application/sdp  
  525. 26204   Content-Length: 368    
  526. 26205          
  527. 26206   v=0    
  528. 26207   o=- 3823262935 3823262935 IN IP4 10.100.50.228 
  529. 26208   s=pjmedia      
  530. 26209   b=AS:84
  531. 26210   t=0 0  
  532. 26211   a=X-nat:0      
  533. 26212   m=audio 4002 RTP/AVP 18 8 0 101
  534. 26213   c=IN IP4 10.100.50.228 
  535. 26214   b=TIAS:64000   
  536. 26215   a=rtcp:4003 IN IP4 10.100.50.228       
  537. 26216   a=sendrecv     
  538. 26217   a=rtpmap:18 G729/8000  
  539. 26218   a=rtpmap:8 PCMA/8000   
  540. 26219   a=rtpmap:0 PCMU/8000   
  541. 26220   a=rtpmap:101 telephone-event/8000      
  542. 26221   a=fmtp:101 0-16
  543. 26222   a=ssrc:799809659 cname:72065af822af4f55
  544. 26223   <------------->
  545. 26224   [2021-02-25 11:59:01] VERBOSE[22680] chan_sip.c: --- (15 headers 17 lines) --- 
  546. 26225   [2021-02-25 11:59:01] VERBOSE[22680] chan_sip.c: Sending to 10.100.50.228:61342 (NAT)  
  547. 26226   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Sending to 10.100.50.228:61342 (NAT)      
  548. 26227   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Using INVITE request as basis request - a8e57e02b54441c2a92d7c6aeef2d2b5  
  549. 26228   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Found peer '2002' for '2002' from 10.100.50.228:61342     
  550. 26229   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c:   
  551. 26230   <--- Reliably Transmitting (NAT) to 10.100.50.228:61342 --->   
  552. 26231   SIP/2.0 401 Unauthorized       
  553. 26232   Via: SIP/2.0/UDP 10.100.50.228:61342;branch=z9hG4bKPja0bd1c8dfef34a018077fbbe0bd7a9f4;received=10.100.50.228;rport=61342       
  554. 26233   From: <sip:2002@10.100.51.227>;tag=31716915f93d4e5dbeab781c6d204f4a    
  555. 26234   To: <sip:01357116464@10.100.51.227>;tag=as647995d8     
  556. 26235   Call-ID: a8e57e02b54441c2a92d7c6aeef2d2b5      
  557. 26236   CSeq: 26279 INVITE     
  558. 26237   Server: FPBX-15.0.16.81(16.13.0)       
  559. 26238   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE     
  560. 26239   Supported: replaces, timer     
  561. 26240   WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="08a85a10"     
  562. 26241   Content-Length: 0      
  563. 26242          
  564. 26243          
  565. 26244   <------------> 
  566. 26245   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Scheduling destruction of SIP dialog 'a8e57e02b54441c2a92d7c6aeef2d2b5' in 6400 ms (Method: INVITE)       
  567. 26246   [2021-02-25 11:59:01] VERBOSE[22680] chan_sip.c:       
  568. 26247   <--- SIP read from UDP:10.100.50.228:61342 --->
  569. 26248   ACK sip:01357116464@10.100.51.227 SIP/2.0      
  570. 26249   Via: SIP/2.0/UDP 10.100.50.228:61342;rport;branch=z9hG4bKPja0bd1c8dfef34a018077fbbe0bd7a9f4    
  571. 26250   Max-Forwards: 70       
  572. 26251   From: <sip:2002@10.100.51.227>;tag=31716915f93d4e5dbeab781c6d204f4a    
  573. 26252   To: <sip:01357116464@10.100.51.227>;tag=as647995d8     
  574. 26253   Call-ID: a8e57e02b54441c2a92d7c6aeef2d2b5      
  575. 26254   CSeq: 26279 ACK
  576. 26255   Content-Length: 0      
  577. 26256          
  578. 26257   <------------->
  579. 26258   [2021-02-25 11:59:01] VERBOSE[22680] chan_sip.c: --- (8 headers 0 lines) ---   
  580. 26259   [2021-02-25 11:59:01] VERBOSE[22680] chan_sip.c:       
  581. 26260   <--- SIP read from UDP:10.100.50.228:61342 --->
  582. 26261   INVITE sip:01357116464@10.100.51.227 SIP/2.0   
  583. 26262   Via: SIP/2.0/UDP 10.100.50.228:61342;rport;branch=z9hG4bKPj29c2af3167ea41c294572c1d125f174a    
  584. 26263   Max-Forwards: 70       
  585. 26264   From: <sip:2002@10.100.51.227>;tag=31716915f93d4e5dbeab781c6d204f4a    
  586. 26265   To: <sip:01357116464@10.100.51.227>    
  587. 26266   Contact: <sip:2002@10.100.50.228:61342;ob>     
  588. 26267   Call-ID: a8e57e02b54441c2a92d7c6aeef2d2b5      
  589. 26268   CSeq: 26280 INVITE     
  590. 26269   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS       
  591. 26270   Supported: replaces, 100rel, timer, norefersub 
  592. 26271   Session-Expires: 1800  
  593. 26272   Min-SE: 90     
  594. 26273   User-Agent: MicroSIP/3.20.3    
  595. 26274   Authorization: Digest username="2002", realm="asterisk", nonce="08a85a10", uri="sip:01357116464@10.100.51.227", response="2927b0f590d255760212f7ecc4444e78", algorithm=MD5     
  596. 26275   Content-Type: application/sdp  
  597. 26276   Content-Length: 368    
  598. 26277          
  599. 26278   v=0    
  600. 26279   o=- 3823262935 3823262935 IN IP4 10.100.50.228 
  601. 26280   s=pjmedia      
  602. 26281   b=AS:84
  603. 26282   t=0 0  
  604. 26283   a=X-nat:0      
  605. 26284   m=audio 4002 RTP/AVP 18 8 0 101
  606. 26285   c=IN IP4 10.100.50.228 
  607. 26286   b=TIAS:64000   
  608. 26287   a=rtcp:4003 IN IP4 10.100.50.228       
  609. 26288   a=sendrecv     
  610. 26289   a=rtpmap:18 G729/8000  
  611. 26290   a=rtpmap:8 PCMA/8000   
  612. 26291   a=rtpmap:0 PCMU/8000   
  613. 26292   a=rtpmap:101 telephone-event/8000      
  614. 26293   a=fmtp:101 0-16
  615. 26294   a=ssrc:799809659 cname:72065af822af4f55
  616. 26295   <------------->
  617. 26296   [2021-02-25 11:59:01] VERBOSE[22680] chan_sip.c: --- (16 headers 17 lines) --- 
  618. 26297   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Sending to 10.100.50.228:61342 (NAT)      
  619. 26298   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Using INVITE request as basis request - a8e57e02b54441c2a92d7c6aeef2d2b5  
  620. 26299   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Found peer '2002' for '2002' from 10.100.50.228:61342     
  621. 26300   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] netsock2.c: Using SIP RTP TOS bits 184
  622. 26301   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] netsock2.c: Using SIP RTP CoS mark 5  
  623. 26302   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Got SDP version 3823262935 and unique parts [- 3823262935 IN IP4 10.100.50.228]   
  624. 26303   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Found RTP audio format 18 
  625. 26304   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Found RTP audio format 8  
  626. 26305   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Found RTP audio format 0  
  627. 26306   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Found RTP audio format 101
  628. 26307   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Found audio description format G729 for ID 18     
  629. 26308   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Found audio description format PCMA for ID 8      
  630. 26309   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Found audio description format PCMU for ID 0      
  631. 26310   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Found audio description format telephone-event for ID 101 
  632. 26311   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Capabilities: us - (ulaw|alaw|gsm|g726|g722), peer - audio=(ulaw|alaw|g729)/video=(nothing)/text=(nothing), combined - (ulaw|alaw)
  633. 26312   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)      
  634. 26313   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Peer audio RTP is at port 10.100.50.228:4002      
  635. 26314   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Looking for 01357116464 in from-internal (domain 10.100.51.227)   
  636. 26315   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] sip/route.c: sip_route_dump: route/path hop: <sip:2002@10.100.50.228:61342;ob>
  637. 26316   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c:   
  638. 26317   <--- Transmitting (NAT) to 10.100.50.228:61342 --->    
  639. 26318   SIP/2.0 100 Trying     
  640. 26319   Via: SIP/2.0/UDP 10.100.50.228:61342;branch=z9hG4bKPj29c2af3167ea41c294572c1d125f174a;received=10.100.50.228;rport=61342       
  641. 26320   From: <sip:2002@10.100.51.227>;tag=31716915f93d4e5dbeab781c6d204f4a    
  642. 26321   To: <sip:01357116464@10.100.51.227>    
  643. 26322   Call-ID: a8e57e02b54441c2a92d7c6aeef2d2b5      
  644. 26323   CSeq: 26280 INVITE     
  645. 26324   Server: FPBX-15.0.16.81(16.13.0)       
  646. 26325   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE     
  647. 26326   Supported: replaces, timer     
  648. 26327   Session-Expires: 1800;refresher=uas    
  649. 26328   Contact: <sip:01357116464@10.100.51.227:5060>  
  650. 26329   Content-Length: 0      
  651. 26330          
  652. 26331          
  653. 26332   <------------> 
  654. 26333   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [01357116464@from-internal:1] Macro("SIP/2002-0000000d", "user-callerid,LIMIT,EXTERNAL,") in new stack       
  655. 26334   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/2002-0000000d", "TOUCH_MONITOR=1614254341.24") in new stack       
  656. 26335   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/2002-0000000d", "AMPUSER=2002") in new stack      
  657. 26336   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/2002-0000000d", "HOTDESCKCHAN=2002-0000000d") in new stack
  658. 26337   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/2002-0000000d", "HOTDESKEXTEN=2002") in new stack 
  659. 26338   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/2002-0000000d", "HOTDESKCALL=0") in new stack     
  660. 26339   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/2002-0000000d", "0?Set(HOTDESKCALL=1)") in new stack   
  661. 26340   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/2002-0000000d", "0?Set(CALLERID(name)=)") in new stack 
  662. 26341   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/2002-0000000d", "0?report") in new stack       
  663. 26342   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/2002-0000000d", "1?Set(REALCALLERIDNUM=2002)") in new stack    
  664. 26343   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/2002-0000000d", "AMPUSER=2002") in new stack     
  665. 26344   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/2002-0000000d", "0?limit") in new stack       
  666. 26345   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/2002-0000000d", "AMPUSERCIDNAME=6614100") in new stack   
  667. 26346   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/2002-0000000d", "0?Set(__CIDMASQUERADING=TRUE)") in new stack 
  668. 26347   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/2002-0000000d", "0?report") in new stack      
  669. 26348   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/2002-0000000d", "AMPUSERCID=2002") in new stack  
  670. 26349   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:16] Set("SIP/2002-0000000d", "__DIAL_OPTIONS=HhTtr") in new stack     
  671. 26350   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/2002-0000000d", "CALLERID(all)="6614100" <2002>") in new stack   
  672. 26351   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("SIP/2002-0000000d", "0?Set(CUSDIAL=)") in new stack       
  673. 26352   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("SIP/2002-0000000d", "0?Set(CALLERID(all)="6614100" <2002>)") in new stack 
  674. 26353   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:20] GotoIf("SIP/2002-0000000d", "0?limit") in new stack       
  675. 26354   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:21] ExecIf("SIP/2002-0000000d", "1?Set(GROUP(concurrency_limit)=2002)") in new stack  
  676. 26355   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:22] ExecIf("SIP/2002-0000000d", "0?Set(CHANNEL(language)=)") in new stack     
  677. 26356   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:23] NoOp("SIP/2002-0000000d", "Macro Depth is 1") in new stack
  678. 26357   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:24] GotoIf("SIP/2002-0000000d", "1?report2:macroerror") in new stack  
  679. 26358   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx_builtins.c: Goto (macro-user-callerid,s,25)       
  680. 26359   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("SIP/2002-0000000d", "1?continue") in new stack    
  681. 26360   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx_builtins.c: Goto (macro-user-callerid,s,44)       
  682. 26361   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:44] Set("SIP/2002-0000000d", "CALLERID(number)=2002") in new stack    
  683. 26362   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:45] Set("SIP/2002-0000000d", "CALLERID(name)=6614100") in new stack   
  684. 26363   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:46] GotoIf("SIP/2002-0000000d", "0?cnum") in new stack
  685. 26364   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:47] Set("SIP/2002-0000000d", "CDR(cnam)=6614100") in new stack
  686. 26365   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:48] Set("SIP/2002-0000000d", "CDR(cnum)=2002") in new stack   
  687. 26366   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/2002-0000000d", "CHANNEL(language)=en") in new stack     
  688. 26367   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [01357116464@from-internal:2] Gosub("SIP/2002-0000000d", "sub-record-check,s,1(out,01357116464,dontcare)") in new stack      
  689. 26368   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/2002-0000000d", "0?initialized") in new stack     
  690. 26369   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@sub-record-check:2] Set("SIP/2002-0000000d", "__REC_STATUS=INITIALIZED") in new stack     
  691. 26370   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@sub-record-check:3] Set("SIP/2002-0000000d", "NOW=1614254341") in new stack       
  692. 26371   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@sub-record-check:4] Set("SIP/2002-0000000d", "__DAY=25") in new stack     
  693. 26372   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@sub-record-check:5] Set("SIP/2002-0000000d", "__MONTH=02") in new stack   
  694. 26373   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@sub-record-check:6] Set("SIP/2002-0000000d", "__YEAR=2021") in new stack  
  695. 26374   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@sub-record-check:7] Set("SIP/2002-0000000d", "__TIMESTR=20210225-115901") in new stack    
  696. 26375   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@sub-record-check:8] Set("SIP/2002-0000000d", "__FROMEXTEN=2002") in new stack     
  697. 26376   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@sub-record-check:9] Set("SIP/2002-0000000d", "__MON_FMT=wav") in new stack
  698. 26377   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/2002-0000000d", "Recordings initialized") in new stack     
  699. 26378   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/2002-0000000d", "0?Set(ARG3=dontcare)") in new stack     
  700. 26379   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@sub-record-check:12] Set("SIP/2002-0000000d", "REC_POLICY_MODE_SAVE=") in new stack       
  701. 26380   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/2002-0000000d", "0?Set(REC_STATUS=NO)") in new stack     
  702. 26381   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/2002-0000000d", "3?checkaction") in new stack    
  703. 26382   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx_builtins.c: Goto (sub-record-check,s,17)  
  704. 26383   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/2002-0000000d", "1?sub-record-check,out,1") in new stack 
  705. 26384   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx_builtins.c: Goto (sub-record-check,out,1) 
  706. 26385   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [out@sub-record-check:1] NoOp("SIP/2002-0000000d", "Outbound Recording Check from 2002 to 01357116464") in new stack 
  707. 26386   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [out@sub-record-check:2] Set("SIP/2002-0000000d", "RECMODE=dontcare") in new stack   
  708. 26387   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [out@sub-record-check:3] ExecIf("SIP/2002-0000000d", "1?Goto(routewins)") in new stack       
  709. 26388   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx_builtins.c: Goto (sub-record-check,out,7) 
  710. 26389   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [out@sub-record-check:7] Gosub("SIP/2002-0000000d", "recordcheck,1(dontcare,out,01357116464)") in new stack  
  711. 26390   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/2002-0000000d", "Starting recording check against dontcare") in new stack 
  712. 26391   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/2002-0000000d", "dontcare") in new stack  
  713. 26392   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx_builtins.c: Goto (sub-record-check,recordcheck,3) 
  714. 26393   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/2002-0000000d", "") in new stack
  715. 26394   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [out@sub-record-check:8] Return("SIP/2002-0000000d", "") in new stack
  716. 26395   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [01357116464@from-internal:3] ExecIf("SIP/2002-0000000d", "0 ?Set(CDR(accountcode)=)") in new stack  
  717. 26396   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [01357116464@from-internal:4] Set("SIP/2002-0000000d", "_ROUTEID=7") in new stack    
  718. 26397   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [01357116464@from-internal:5] Set("SIP/2002-0000000d", "_ROUTENAME=outgoing") in new stack   
  719. 26398   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [01357116464@from-internal:6] Set("SIP/2002-0000000d", "MOHCLASS=default") in new stack      
  720. 26399   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [01357116464@from-internal:7] Set("SIP/2002-0000000d", "_CALLERIDNAMEINTERNAL=6614100") in new stack 
  721. 26400   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [01357116464@from-internal:8] Set("SIP/2002-0000000d", "_CALLERIDNUMINTERNAL=2002") in new stack     
  722. 26401   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [01357116464@from-internal:9] Set("SIP/2002-0000000d", "_EMAILNOTIFICATION=FALSE") in new stack      
  723. 26402   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [01357116464@from-internal:10] Set("SIP/2002-0000000d", "_NODEST=") in new stack     
  724. 26403   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [01357116464@from-internal:11] Macro("SIP/2002-0000000d", "dialout-trunk,1,01357116464,,off") in new stack   
  725. 26404   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:1] Set("SIP/2002-0000000d", "DIAL_TRUNK=1") in new stack      
  726. 26405   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("SIP/2002-0000000d", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack       
  727. 26406   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("SIP/2002-0000000d", "0?sub-pincheck,s,1()") in new stack  
  728. 26407   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("SIP/2002-0000000d", "0?Set(CALLERID(num)=2002)") in new stack      
  729. 26408   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("SIP/2002-0000000d", "0?disabletrunk,1") in new stack       
  730. 26409   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:6] Set("SIP/2002-0000000d", "DIAL_NUMBER=01357116464") in new stack   
  731. 26410   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:7] Set("SIP/2002-0000000d", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack  
  732. 26411   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:8] Set("SIP/2002-0000000d", "OUTBOUND_GROUP=OUT_1") in new stack      
  733. 26412   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:9] Set("SIP/2002-0000000d", "DIAL_TRUNK_OPTIONS=T") in new stack      
  734. 26413   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("SIP/2002-0000000d", "0?nomax") in new stack       
  735. 26414   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf("SIP/2002-0000000d", "0?chanfull") in new stack    
  736. 26415   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("SIP/2002-0000000d", "0?skipoutcid") in new stack  
  737. 26416   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("SIP/2002-0000000d", "outbound-callerid,1") in new stack    
  738. 26417   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("SIP/2002-0000000d", "2002") in new stack 
  739. 26418   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("SIP/2002-0000000d", "") in new stack     
  740. 26419   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("SIP/2002-0000000d", "off") in new stack  
  741. 26420   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("SIP/2002-0000000d", "0?Set(CALLERPRES(name-pres)=)") in new stack      
  742. 26421   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("SIP/2002-0000000d", "0?Set(CALLERPRES(num-pres)=)") in new stack       
  743. 26422   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:6] Set("SIP/2002-0000000d", "HOTDESCKCHAN=2002-0000000d") in new stack    
  744. 26423   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:7] Set("SIP/2002-0000000d", "HOTDESKEXTEN=2002") in new stack     
  745. 26424   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:8] Set("SIP/2002-0000000d", "HOTDESKCALL=0") in new stack 
  746. 26425   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("SIP/2002-0000000d", "0?Set(HOTDESKCALL=1)") in new stack       
  747. 26426   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("SIP/2002-0000000d", "0?Set(CALLERID(name)=)") in new stack    
  748. 26427   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:11] Set("SIP/2002-0000000d", "ALLOWTHISROUTE=NO") in new stack    
  749. 26428   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("SIP/2002-0000000d", "0?Set(ALLOWTHISROUTE=YES)") in new stack 
  750. 26429   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("SIP/2002-0000000d", "0?Hangup()") in new stack
  751. 26430   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("SIP/2002-0000000d", "0?Set(REALCALLERIDNUM=2002)") in new stack       
  752. 26431   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("SIP/2002-0000000d", "0?Set(AMPUSER=2002)") in new stack       
  753. 26432   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("SIP/2002-0000000d", "1?normcid") in new stack 
  754. 26433   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)   
  755. 26434   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:20] Set("SIP/2002-0000000d", "USEROUTCID=6614100") in new stack   
  756. 26435   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:21] Set("SIP/2002-0000000d", "EMERGENCYCID=") in new stack
  757. 26436   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("SIP/2002-0000000d", "0?Set(EMERGENCYCID=)") in new stack      
  758. 26437   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:23] Set("SIP/2002-0000000d", "TRUNKOUTCID=") in new stack 
  759. 26438   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf("SIP/2002-0000000d", "1?trunkcid") in new stack
  760. 26439   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx_builtins.c: Goto (macro-outbound-callerid,s,30)   
  761. 26440   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf("SIP/2002-0000000d", "0?Set(CALLERID(all)=)") in new stack     
  762. 26441   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("SIP/2002-0000000d", "1?Set(CALLERID(all)=6614100)") in new stack      
  763. 26442   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf("SIP/2002-0000000d", "0?Set(CALLERID(all)=)") in new stack     
  764. 26443   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("SIP/2002-0000000d", "0?Set(CALLERID(all)=2002)") in new stack 
  765. 26444   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:34] Set("SIP/2002-0000000d", "TIOHIDE=no") in new stack   
  766. 26445   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:35] ExecIf("SIP/2002-0000000d", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack 
  767. 26446   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("SIP/2002-0000000d", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack  
  768. 26447   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("SIP/2002-0000000d", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack 
  769. 26448   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf("SIP/2002-0000000d", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack  
  770. 26449   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:39] Set("SIP/2002-0000000d", "CDR(outbound_cnum)=6614100") in new stack   
  771. 26450   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-outbound-callerid:40] Set("SIP/2002-0000000d", "CDR(outbound_cnam)=") in new stack  
  772. 26451   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("SIP/2002-0000000d", "0?sub-flp-1,s,1()") in new stack    
  773. 26452   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:15] Set("SIP/2002-0000000d", "OUTNUM=01357116464") in new stack       
  774. 26453   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:16] Set("SIP/2002-0000000d", "custom=SIP/tatasip") in new stack       
  775. 26454   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("SIP/2002-0000000d", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
  776. 26455   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("SIP/2002-0000000d", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack 
  777. 26456   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("SIP/2002-0000000d", "dialout-trunk-predial-hook,") in new stack    
  778. 26457   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] NoOp("SIP/2002-0000000d", "Adding caller ID for TATA") in new stack   
  779. 26458   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:2] SIPAddHeader("SIP/2002-0000000d", "P-Preferred-Identity: <sip:6614100@10.50.185.2>") in new stack     
  780. 26459   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:3] MacroExit("SIP/2002-0000000d", "") in new stack       
  781. 26460   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("SIP/2002-0000000d", "0?skipcrm") in new stack     
  782. 26461   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:21] Set("SIP/2002-0000000d", "__CRM_DIRECTION=OUTBOUND") in new stack 
  783. 26462   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:22] Set("SIP/2002-0000000d", "__CRM_DESTINATION=01357116464") in new stack    
  784. 26463   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:23] Set("SIP/2002-0000000d", "__CRM_SOURCE=2002") in new stack
  785. 26464   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:24] AGI("SIP/2002-0000000d", "agi://127.0.0.1/sangomacrm.agi") in new stack   
  786. 26465   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] res_agi.c: <SIP/2002-0000000d>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
  787. 26466   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:25] Set("SIP/2002-0000000d", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack      
  788. 26467   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp("SIP/2002-0000000d", "CRM Finished") in new stack    
  789. 26468   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf("SIP/2002-0000000d", "0?bypass,1") in new stack    
  790. 26469   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf("SIP/2002-0000000d", "1?Set(CONNECTEDLINE(num,i)=01357116464)") in new stack       
  791. 26470   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("SIP/2002-0000000d", "1?Set(CONNECTEDLINE(name,i)=CID:6614100)") in new stack      
  792. 26471   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("SIP/2002-0000000d", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)6614100)") in new stack      
  793. 26472   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("SIP/2002-0000000d", "0?customtrunk") in new stack 
  794. 26473   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf("SIP/2002-0000000d", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack    
  795. 26474   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:33] Set("SIP/2002-0000000d", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack      
  796. 26475   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:34] Dial("SIP/2002-0000000d", "SIP/tatasip/01357116464,300,Tb(func-apply-sipheaders^s^1,(1))U(sub-send-obroute-email^01357116464^01357116464^1^1614254341^^6614100)") in new stack    
  797. 26476   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] netsock2.c: Using SIP RTP TOS bits 184
  798. 26477   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] netsock2.c: Using SIP RTP CoS mark 5  
  799. 26478   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] app_stack.c: SIP/tatasip-0000000e Internal Gosub(func-apply-sipheaders,s,1(1)) start  
  800. 26479   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/tatasip-0000000e", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  801. 26480   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/tatasip-0000000e", "Applying SIP Headers to channel SIP/tatasip-0000000e") in new stack
  802. 26481   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/tatasip-0000000e", "TECH=SIP") in new stack     
  803. 26482   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/tatasip-0000000e", "SIPHEADERKEYS=Alert-Info") in new stack     
  804. 26483   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/tatasip-0000000e", "1") in new stack  
  805. 26484   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/tatasip-0000000e", "sipheader=unset") in new stack      
  806. 26485   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("SIP/tatasip-0000000e", "1?SIPRemoveHeader(Alert-Info:)") in new stack    
  807. 26486   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("SIP/tatasip-0000000e", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack   
  808. 26487   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("SIP/tatasip-0000000e", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack    
  809. 26488   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("SIP/tatasip-0000000e", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack 
  810. 26489   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("SIP/tatasip-0000000e", "0?SIPAddHeader(Alert-Info:unset)") in new stack 
  811. 26490   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("SIP/tatasip-0000000e", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack
  812. 26491   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile("SIP/tatasip-0000000e", "") in new stack       
  813. 26492   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/tatasip-0000000e", "0") in new stack  
  814. 26493   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/tatasip-0000000e", "") in new stack 
  815. 26494   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] app_stack.c: Spawn extension (tata, 01357116464, 1) exited non-zero on 'SIP/tatasip-0000000e' 
  816. 26495   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] app_stack.c: SIP/tatasip-0000000e Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL= 
  817. 26496   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] chan_sip.c: Audio is at 12664 
  818. 26497   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] chan_sip.c: Adding codec ulaw to SDP  
  819. 26498   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] chan_sip.c: Adding codec alaw to SDP  
  820. 26499   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] chan_sip.c: Adding codec g729 to SDP  
  821. 26500   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP     
  822. 26501   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] chan_sip.c: Reliably Transmitting (no NAT) to 10.50.185.2:5060:       
  823. 26502   INVITE sip:01357116464@10.50.185.2 SIP/2.0     
  824. 26503   Via: SIP/2.0/UDP 10.50.192.54:5060;branch=z9hG4bK2a6e7ee1      
  825. 26504   Max-Forwards: 70       
  826. 26505   From: <sip:6614100@10.50.192.54>;tag=as5e200798
  827. 26506   To: <sip:01357116464@10.50.185.2>      
  828. 26507   Contact: <sip:6614100@10.50.192.54:5060>       
  829. 26508   Call-ID: 23c74a604fb7f13c42c8b4347079b4a6@10.50.192.54:5060    
  830. 26509   CSeq: 102 INVITE       
  831. 26510   User-Agent: FPBX-15.0.16.81(16.13.0)   
  832. 26511   Date: Thu, 25 Feb 2021 11:59:01 GMT    
  833. 26512   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE     
  834. 26513   Supported: replaces, timer     
  835. 26514   P-Preferred-Identity: <sip:6614100@10.50.185.2>
  836. 26515   Content-Type: application/sdp  
  837. 26516   Content-Length: 321    
  838. 26517          
  839. 26518   v=0    
  840. 26519   o=root 215643744 215643744 IN IP4 10.50.192.54 
  841. 26520   s=Asterisk PBX 16.13.0 
  842. 26521   c=IN IP4 10.50.192.54  
  843. 26522   t=0 0  
  844. 26523   m=audio 12664 RTP/AVP 0 8 18 101       
  845. 26524   a=rtpmap:0 PCMU/8000   
  846. 26525   a=rtpmap:8 PCMA/8000   
  847. 26526   a=rtpmap:18 G729/8000  
  848. 26527   a=fmtp:18 annexb=no    
  849. 26528   a=rtpmap:101 telephone-event/8000      
  850. 26529   a=fmtp:101 0-16
  851. 26530   a=ptime:20     
  852. 26531   a=maxptime:150 
  853. 26532   a=sendrecv     
  854. 26533          
  855. 26534   ---    
  856. 26535   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] app_dial.c: Called SIP/tatasip/01357116464    
  857. 26536   [2021-02-25 11:59:01] VERBOSE[22680] chan_sip.c:       
  858. 26537   <--- SIP read from UDP:10.50.185.2:5060 --->   
  859. 26538   SIP/2.0 100 Trying     
  860. 26539   From: <sip:6614100@10.50.192.54>;tag=as5e200798
  861. 26540   To: <sip:01357116464@10.50.185.2>      
  862. 26541   Call-ID: 23c74a604fb7f13c42c8b4347079b4a6@10.50.192.54:5060    
  863. 26542   CSeq: 102 INVITE       
  864. 26543   Via: SIP/2.0/UDP 10.50.192.54:5060;branch=z9hG4bK2a6e7ee1      
  865. 26544   Contact: <sip:01357116464@10.50.185.2:5060;transport=UDP>      
  866. 26545   Content-Length: 0      
  867. 26546          
  868. 26547   <------------->
  869. 26548   [2021-02-25 11:59:01] VERBOSE[22680] chan_sip.c: --- (8 headers 0 lines) ---   
  870. 26549   [2021-02-25 11:59:01] VERBOSE[22680] chan_sip.c:       
  871. 26550   <--- SIP read from UDP:10.50.185.2:5060 --->   
  872. 26551   SIP/2.0 480 Temporarily Unavailable    
  873. 26552   From: <sip:6614100@10.50.192.54>;tag=as5e200798
  874. 26553   To: <sip:01357116464@10.50.185.2>;tag=BN1727092270-0-1614254336-1255859405     
  875. 26554   Call-ID: 23c74a604fb7f13c42c8b4347079b4a6@10.50.192.54:5060    
  876. 26555   CSeq: 102 INVITE       
  877. 26556   Reason: SIP;cause=480;text="Subsystem Id: ECALLLEG Cause Code: TemporarilyUnavailable_480"     
  878. 26557   Via: SIP/2.0/UDP 10.50.192.54:5060;branch=z9hG4bK2a6e7ee1      
  879. 26558   Content-Length: 0      
  880. 26559          
  881. 26560   <------------->
  882. 26561   [2021-02-25 11:59:01] VERBOSE[22680] chan_sip.c: --- (8 headers 0 lines) ---   
  883. 26562   [2021-02-25 11:59:01] VERBOSE[22680][C-0000000d] chan_sip.c: Transmitting (no NAT) to 10.50.185.2:5060:
  884. 26563   ACK sip:01357116464@10.50.185.2 SIP/2.0
  885. 26564   Via: SIP/2.0/UDP 10.50.192.54:5060;branch=z9hG4bK2a6e7ee1      
  886. 26565   Max-Forwards: 70       
  887. 26566   From: <sip:6614100@10.50.192.54>;tag=as5e200798
  888. 26567   To: <sip:01357116464@10.50.185.2>;tag=BN1727092270-0-1614254336-1255859405     
  889. 26568   Contact: <sip:6614100@10.50.192.54:5060>       
  890. 26569   Call-ID: 23c74a604fb7f13c42c8b4347079b4a6@10.50.192.54:5060    
  891. 26570   CSeq: 102 ACK  
  892. 26571   User-Agent: FPBX-15.0.16.81(16.13.0)   
  893. 26572   Content-Length: 0      
  894. 26573          
  895. 26574          
  896. 26575   ---    
  897. 26576   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] app_dial.c: SIP/tatasip-0000000e redirecting info has changed, passing it to SIP/2002-0000000d
  898. 26577   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] chan_sip.c:   
  899. 26578   <--- Transmitting (NAT) to 10.100.50.228:61342 --->    
  900. 26579   SIP/2.0 181 Call is being forwarded    
  901. 26580   Via: SIP/2.0/UDP 10.100.50.228:61342;branch=z9hG4bKPj29c2af3167ea41c294572c1d125f174a;received=10.100.50.228;rport=61342       
  902. 26581   From: <sip:2002@10.100.51.227>;tag=31716915f93d4e5dbeab781c6d204f4a    
  903. 26582   To: <sip:01357116464@10.100.51.227>;tag=as15024ff4     
  904. 26583   Call-ID: a8e57e02b54441c2a92d7c6aeef2d2b5      
  905. 26584   CSeq: 26280 INVITE     
  906. 26585   Server: FPBX-15.0.16.81(16.13.0)       
  907. 26586   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE     
  908. 26587   Supported: replaces, timer     
  909. 26588   Session-Expires: 1800;refresher=uas    
  910. 26589   Contact: <sip:01357116464@10.100.51.227:5060>  
  911. 26590   Content-Length: 0      
  912. 26591          
  913. 26592          
  914. 26593   <------------> 
  915. 26594   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] app_dial.c: SIP/tatasip-0000000e is busy      
  916. 26595   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] chan_sip.c: Scheduling destruction of SIP dialog '23c74a604fb7f13c42c8b4347079b4a6@10.50.192.54:5060' in 32000 ms (Method: INVITE)    
  917. 26596   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] app_dial.c: Everyone is busy/congested at this time (1:1/0/0) 
  918. 26597   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:35] NoOp("SIP/2002-0000000d", "Dial failed for some reason with DIALSTATUS = BUSY and HANGUPCAUSE = 19") in new stack 
  919. 26598   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-dialout-trunk:36] GotoIf("SIP/2002-0000000d", "0?continue,1:s-BUSY,1") in new stack 
  920. 26599   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx_builtins.c: Goto (macro-dialout-trunk,s-BUSY,1)   
  921. 26600   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s-BUSY@macro-dialout-trunk:1] NoOp("SIP/2002-0000000d", "Dial failed due to trunk reporting BUSY - giving up") in new stack 
  922. 26601   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s-BUSY@macro-dialout-trunk:2] PlayTones("SIP/2002-0000000d", "busy") in new stack   
  923. 26602   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s-BUSY@macro-dialout-trunk:3] Busy("SIP/2002-0000000d", "20") in new stack  
  924. 26603   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] chan_sip.c:   
  925. 26604   <--- Reliably Transmitting (NAT) to 10.100.50.228:61342 --->   
  926. 26605   SIP/2.0 486 Busy Here  
  927. 26606   Via: SIP/2.0/UDP 10.100.50.228:61342;branch=z9hG4bKPj29c2af3167ea41c294572c1d125f174a;received=10.100.50.228;rport=61342       
  928. 26607   From: <sip:2002@10.100.51.227>;tag=31716915f93d4e5dbeab781c6d204f4a    
  929. 26608   To: <sip:01357116464@10.100.51.227>;tag=as15024ff4     
  930. 26609   Call-ID: a8e57e02b54441c2a92d7c6aeef2d2b5      
  931. 26610   CSeq: 26280 INVITE     
  932. 26611   Server: FPBX-15.0.16.81(16.13.0)       
  933. 26612   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE     
  934. 26613   Supported: replaces, timer     
  935. 26614   Session-Expires: 1800;refresher=uas    
  936. 26615   X-Asterisk-HangupCause: User alerting, no answer       
  937. 26616   X-Asterisk-HangupCauseCode: 19 
  938. 26617   Content-Length: 0      
  939. 26618          
  940. 26619          
  941. 26620   <------------> 
  942. 26621   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] app_macro.c: Spawn extension (macro-dialout-trunk, s-BUSY, 3) exited non-zero on 'SIP/2002-0000000d' in macro 'dialout-trunk' 
  943. 26622   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Spawn extension (from-internal, 01357116464, 11) exited non-zero on 'SIP/2002-0000000d'
  944. 26623   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [h@from-internal:1] Macro("SIP/2002-0000000d", "hangupcall") in new stack    
  945. 26624   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/2002-0000000d", "1?theend") in new stack  
  946. 26625   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx_builtins.c: Goto (macro-hangupcall,s,3)   
  947. 26626   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/2002-0000000d", "0?Set(CDR(recordingfile)=)") in new stack
  948. 26627   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/2002-0000000d", " montior file= ") in new stack     
  949. 26628   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/2002-0000000d", "1?skipagi") in new stack 
  950. 26629   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx_builtins.c: Goto (macro-hangupcall,s,7)   
  951. 26630   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/2002-0000000d", "") in new stack  
  952. 26631   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/2002-0000000d' in macro 'hangupcall'    
  953. 26632   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/2002-0000000d'   
  954. 26633   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] app_stack.c: SIP/2002-0000000d Internal Gosub(crm-hangup,s,1) start   
  955. 26634   [2021-02-25 11:59:01] VERBOSE[22680] chan_sip.c:       
  956. 26635   <--- SIP read from UDP:10.100.50.228:61342 --->
  957. 26636   ACK sip:01357116464@10.100.51.227 SIP/2.0      
  958. 26637   Via: SIP/2.0/UDP 10.100.50.228:61342;rport;branch=z9hG4bKPj29c2af3167ea41c294572c1d125f174a    
  959. 26638   Max-Forwards: 70       
  960. 26639   From: <sip:2002@10.100.51.227>;tag=31716915f93d4e5dbeab781c6d204f4a    
  961. 26640   To: <sip:01357116464@10.100.51.227>;tag=as15024ff4     
  962. 26641   Call-ID: a8e57e02b54441c2a92d7c6aeef2d2b5      
  963. 26642   CSeq: 26280 ACK
  964. 26643   Content-Length: 0      
  965. 26644          
  966. 26645   <------------->
  967. 26646   [2021-02-25 11:59:01] VERBOSE[22680] chan_sip.c: --- (8 headers 0 lines) ---   
  968. 26647   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/2002-0000000d", "Sending Hangup to CRM") in new stack     
  969. 26648   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/2002-0000000d", "HANGUP CAUSE: 17") in new stack  
  970. 26649   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/2002-0000000d", "0?Set(__CRM_VOICEMAIL=)") in new stack 
  971. 26650   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/2002-0000000d", "MASTER CHANNEL: 1614254341.24 = 1614254341.24") in new stack     
  972. 26651   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/2002-0000000d", "0?return") in new stack
  973. 26652   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@crm-hangup:6] Set("SIP/2002-0000000d", "__CRM_HANGUP=1") in new stack     
  974. 26653   [2021-02-25 11:59:01] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/2002-0000000d", "agi://127.0.0.1/sangomacrm.agi") in new stack     
  975. 26654   [2021-02-25 11:59:02] VERBOSE[31620][C-0000000d] res_agi.c: <SIP/2002-0000000d>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
  976. 26655   [2021-02-25 11:59:02] VERBOSE[31620][C-0000000d] pbx.c: Executing [s@crm-hangup:8] Return("SIP/2002-0000000d", "") in new stack
  977. 26656   [2021-02-25 11:59:02] VERBOSE[31620][C-0000000d] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/2002-0000000d'     
  978. 26657   [2021-02-25 11:59:02] VERBOSE[31620][C-0000000d] app_stack.c: SIP/2002-0000000d Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=  
  979. 26658   [2021-02-25 11:59:02] VERBOSE[22680] chan_sip.c: Really destroying SIP dialog 'a8e57e02b54441c2a92d7c6aeef2d2b5' Method: ACK   
  980. 26659   [2021-02-25 11:59:07] VERBOSE[22680] chan_sip.c: Reliably Transmitting (NAT) to 10.100.50.228:61342:   
  981. 26660   OPTIONS sip:2002@10.100.50.228:61342;ob SIP/2.0
  982. 26661   Via: SIP/2.0/UDP 10.100.51.227:5060;branch=z9hG4bK18bf0fd2;rport       
  983. 26662   Max-Forwards: 70       
  984. 26663   From: "Unknown" <sip:Unknown@10.100.51.227>;tag=as28176829     
  985. 26664   To: <sip:2002@10.100.50.228:61342;ob>  
  986. 26665   Contact: <sip:Unknown@10.100.51.227:5060>      
  987. 26666   Call-ID: 75efc82b3e35859d674eebc300a8dbb7@10.100.51.227:5060   
  988. 26667   CSeq: 102 OPTIONS      
  989. 26668   User-Agent: FPBX-15.0.16.81(16.13.0)   
  990. 26669   Date: Thu, 25 Feb 2021 11:59:07 GMT    
  991. 26670   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE     
  992. 26671   Supported: replaces, timer     
  993. 26672   Content-Length: 0      
  994. 26673          
  995. 26674          
  996. 26675   ---    
  997. 26676   [2021-02-25 11:59:07] VERBOSE[22680] chan_sip.c:       
  998. 26677   <--- SIP read from UDP:10.100.50.228:61342 --->
  999. 26678   SIP/2.0 200 OK 
  1000. 26679   Via: SIP/2.0/UDP 10.100.51.227:5060;rport=5060;received=10.100.51.227;branch=z9hG4bK18bf0fd2   
  1001. 26680   Call-ID: 75efc82b3e35859d674eebc300a8dbb7@10.100.51.227:5060   
  1002. 26681   From: "Unknown" <sip:Unknown@10.100.51.227>;tag=as28176829     
  1003. 26682   To: <sip:2002@10.100.50.228;ob>;tag=z9hG4bK18bf0fd2    
  1004. 26683   CSeq: 102 OPTIONS      
  1005. 26684   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS       
  1006. 26685   Accept: application/sdp, application/pidf+xml, application/xpidf+xml, application/simple-message-summary, message/sipfrag;version=2.0, application/im-iscomposing+xml, text/plain      
  1007. 26686   Supported: replaces, 100rel, timer, norefersub 
  1008. 26687   Allow-Events: presence, message-summary, refer 
  1009. 26688   User-Agent: MicroSIP/3.20.3    
  1010. 26689   Content-Length: 0      
  1011. 26690          
  1012. 26691   <------------->
  1013. 26692   [2021-02-25 11:59:07] VERBOSE[22680] chan_sip.c: --- (12 headers 0 lines) ---  
  1014. 26693   [2021-02-25 11:59:07] VERBOSE[22680] chan_sip.c: Really destroying SIP dialog '75efc82b3e35859d674eebc300a8dbb7@10.100.51.227:5060' Method: OPTIONS    
  1015. 26694   [2021-02-25 11:59:07] VERBOSE[22680] chan_sip.c:       
  1016. 26695   <--- SIP read from UDP:10.100.50.228:61342 --->
  1017. 26696          
  1018. 26697   <------------->
  1019. 26698   [2021-02-25 11:59:22] VERBOSE[22680] chan_sip.c:       
  1020. 26699   <--- SIP read from UDP:10.100.50.228:61342 --->
  1021. 26700          
  1022. 26701   <------------->
  1023. 26702   [2021-02-25 11:59:33] VERBOSE[22680] chan_sip.c: Really destroying SIP dialog '23c74a604fb7f13c42c8b4347079b4a6@10.50.192.54:5060' Method: INVITE      
  1024. 26703   [2021-02-25 11:59:37] VERBOSE[22680] chan_sip.c:       
  1025. 26704   <--- SIP read from UDP:10.100.50.228:61342 --->
  1026. 26705          
  1027. 26706   <------------->
  1028. 26707   [2021-02-25 11:59:52] VERBOSE[22680] chan_sip.c:       
  1029. 26708   <--- SIP read from UDP:10.100.50.228:61342 --->
  1030. 26709          
  1031. 26710   <------------->
  1032. 26711   [2021-02-25 12:00:07] VERBOSE[22680] chan_sip.c: Reliably Transmitting (NAT) to 10.100.50.228:61342:   
  1033. 26712   OPTIONS sip:2002@10.100.50.228:61342;ob SIP/2.0
  1034. 26713   Via: SIP/2.0/UDP 10.100.51.227:5060;branch=z9hG4bK6d42b315;rport       
  1035. 26714   Max-Forwards: 70       
  1036. 26715   From: "Unknown" <sip:Unknown@10.100.51.227>;tag=as250340d3     
  1037. 26716   To: <sip:2002@10.100.50.228:61342;ob>  
  1038. 26717   Contact: <sip:Unknown@10.100.51.227:5060>      
  1039. 26718   Call-ID: 52259dca1df59abb582945e010283a59@10.100.51.227:5060   
  1040. 26719   CSeq: 102 OPTIONS      
  1041. 26720   User-Agent: FPBX-15.0.16.81(16.13.0)   
  1042. 26721   Date: Thu, 25 Feb 2021 12:00:07 GMT    
  1043. 26722   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE     
  1044. 26723   Supported: replaces, timer     
  1045. 26724   Content-Length: 0      
  1046. 26725          
  1047. 26726          
  1048. 26727   ---    
  1049. 26728   [2021-02-25 12:00:07] VERBOSE[22680] chan_sip.c:       
  1050. 26729   <--- SIP read from UDP:10.100.50.228:61342 --->
  1051. 26730          
  1052. 26731   <------------->
  1053. 26732   [2021-02-25 12:00:07] VERBOSE[22680] chan_sip.c:       
  1054. 26733   <--- SIP read from UDP:10.100.50.228:61342 --->
  1055. 26734   SIP/2.0 200 OK 
  1056. 26735   Via: SIP/2.0/UDP 10.100.51.227:5060;rport=5060;received=10.100.51.227;branch=z9hG4bK6d42b315   
  1057. 26736   Call-ID: 52259dca1df59abb582945e010283a59@10.100.51.227:5060   
  1058. 26737   From: "Unknown" <sip:Unknown@10.100.51.227>;tag=as250340d3     
  1059. 26738   To: <sip:2002@10.100.50.228;ob>;tag=z9hG4bK6d42b315    
  1060. 26739   CSeq: 102 OPTIONS      
  1061. 26740   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS       
  1062. 26741   Accept: application/sdp, application/pidf+xml, application/xpidf+xml, application/simple-message-summary, message/sipfrag;version=2.0, application/im-iscomposing+xml, text/plain      
  1063. 26742   Supported: replaces, 100rel, timer, norefersub 
  1064. 26743   Allow-Events: presence, message-summary, refer 
  1065. 26744   User-Agent: MicroSIP/3.20.3    
  1066. 26745   Content-Length: 0      
  1067. 26746          
  1068. 26747   <------------->
  1069. 26748   [2021-02-25 12:00:07] VERBOSE[22680] chan_sip.c: --- (12 headers 0 lines) ---  
  1070. 26749   [2021-02-25 12:00:07] VERBOSE[22680] chan_sip.c: Really destroying SIP dialog '52259dca1df59abb582945e010283a59@10.100.51.227:5060' Method: OPTIONS    
  1071. 26750   [2021-02-25 12:00:22] VERBOSE[22680] chan_sip.c:       
  1072. 26751   <--- SIP read from UDP:10.100.50.228:61342 --->
  1073. 26752          
  1074. 26753   <------------->
  1075. 26754   [2021-02-25 12:00:37] VERBOSE[22680] chan_sip.c:       
  1076. 26755   <--- SIP read from UDP:10.100.50.228:61342 --->
  1077. 26756          
  1078. 26757   <------------->
  1079. 26758   [2021-02-25 12:00:47] VERBOSE[22680] chan_sip.c:       
  1080. 26759   <--- SIP read from UDP:10.100.50.228:61342 --->
  1081. 26760   INVITE sip:01356615433@10.100.51.227 SIP/2.0   
  1082. 26761   Via: SIP/2.0/UDP 10.100.50.228:61342;rport;branch=z9hG4bKPj89689c48e3c6400c92bd1a92274b2be5    
  1083. 26762   Max-Forwards: 70       
  1084. 26763   From: <sip:2002@10.100.51.227>;tag=8dbcab3391164252891c110626228d2e    
  1085. 26764   To: <sip:01356615433@10.100.51.227>    
  1086. 26765   Contact: <sip:2002@10.100.50.228:61342;ob>     
  1087. 26766   Call-ID: 99b335fa210d44168b869d54dd5665b6      
  1088. 26767   CSeq: 28301 INVITE     
  1089. 26768   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS       
  1090. 26769   Supported: replaces, 100rel, timer, norefersub 
  1091. 26770   Session-Expires: 1800  
  1092. 26771   Min-SE: 90     
  1093. 26772   User-Agent: MicroSIP/3.20.3    
  1094. 26773   Content-Type: application/sdp  
  1095. 26774   Content-Length: 369    
  1096. 26775          
  1097. 26776   v=0    
  1098. 26777   o=- 3823263042 3823263042 IN IP4 10.100.50.228 
  1099. 26778   s=pjmedia      
  1100. 26779   b=AS:84
  1101. 26780   t=0 0  
  1102. 26781   a=X-nat:0      
  1103. 26782   m=audio 4004 RTP/AVP 18 8 0 101
  1104. 26783   c=IN IP4 10.100.50.228 
  1105. 26784   b=TIAS:64000   
  1106. 26785   a=rtcp:4005 IN IP4 10.100.50.228       
  1107. 26786   a=sendrecv     
  1108. 26787   a=rtpmap:18 G729/8000  
  1109. 26788   a=rtpmap:8 PCMA/8000   
  1110. 26789   a=rtpmap:0 PCMU/8000   
  1111. 26790   a=rtpmap:101 telephone-event/8000      
  1112. 26791   a=fmtp:101 0-16
  1113. 26792   a=ssrc:1087922039 cname:34383afd67314afe       
  1114. 26793   <------------->
  1115. 26794   [2021-02-25 12:00:47] VERBOSE[22680] chan_sip.c: --- (15 headers 17 lines) --- 
  1116. 26795   [2021-02-25 12:00:47] VERBOSE[22680] chan_sip.c: Sending to 10.100.50.228:61342 (NAT)  
  1117. 26796   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Sending to 10.100.50.228:61342 (NAT)      
  1118. 26797   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Using INVITE request as basis request - 99b335fa210d44168b869d54dd5665b6  
  1119. 26798   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Found peer '2002' for '2002' from 10.100.50.228:61342     
  1120. 26799   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c:   
  1121. 26800   <--- Reliably Transmitting (NAT) to 10.100.50.228:61342 --->   
  1122. 26801   SIP/2.0 401 Unauthorized       
  1123. 26802   Via: SIP/2.0/UDP 10.100.50.228:61342;branch=z9hG4bKPj89689c48e3c6400c92bd1a92274b2be5;received=10.100.50.228;rport=61342       
  1124. 26803   From: <sip:2002@10.100.51.227>;tag=8dbcab3391164252891c110626228d2e    
  1125. 26804   To: <sip:01356615433@10.100.51.227>;tag=as50b13c8b     
  1126. 26805   Call-ID: 99b335fa210d44168b869d54dd5665b6      
  1127. 26806   CSeq: 28301 INVITE     
  1128. 26807   Server: FPBX-15.0.16.81(16.13.0)       
  1129. 26808   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE     
  1130. 26809   Supported: replaces, timer     
  1131. 26810   WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="6af736ec"     
  1132. 26811   Content-Length: 0      
  1133. 26812          
  1134. 26813          
  1135. 26814   <------------> 
  1136. 26815   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Scheduling destruction of SIP dialog '99b335fa210d44168b869d54dd5665b6' in 6400 ms (Method: INVITE)       
  1137. 26816   [2021-02-25 12:00:47] VERBOSE[22680] chan_sip.c:       
  1138. 26817   <--- SIP read from UDP:10.100.50.228:61342 --->
  1139. 26818   ACK sip:01356615433@10.100.51.227 SIP/2.0      
  1140. 26819   Via: SIP/2.0/UDP 10.100.50.228:61342;rport;branch=z9hG4bKPj89689c48e3c6400c92bd1a92274b2be5    
  1141. 26820   Max-Forwards: 70       
  1142. 26821   From: <sip:2002@10.100.51.227>;tag=8dbcab3391164252891c110626228d2e    
  1143. 26822   To: <sip:01356615433@10.100.51.227>;tag=as50b13c8b     
  1144. 26823   Call-ID: 99b335fa210d44168b869d54dd5665b6      
  1145. 26824   CSeq: 28301 ACK
  1146. 26825   Content-Length: 0      
  1147. 26826          
  1148. 26827   <------------->
  1149. 26828   [2021-02-25 12:00:47] VERBOSE[22680] chan_sip.c: --- (8 headers 0 lines) ---   
  1150. 26829   [2021-02-25 12:00:47] VERBOSE[22680] chan_sip.c:       
  1151. 26830   <--- SIP read from UDP:10.100.50.228:61342 --->
  1152. 26831   INVITE sip:01356615433@10.100.51.227 SIP/2.0   
  1153. 26832   Via: SIP/2.0/UDP 10.100.50.228:61342;rport;branch=z9hG4bKPj352c7e7ba9fc436a9927ae256ef558fa    
  1154. 26833   Max-Forwards: 70       
  1155. 26834   From: <sip:2002@10.100.51.227>;tag=8dbcab3391164252891c110626228d2e    
  1156. 26835   To: <sip:01356615433@10.100.51.227>    
  1157. 26836   Contact: <sip:2002@10.100.50.228:61342;ob>     
  1158. 26837   Call-ID: 99b335fa210d44168b869d54dd5665b6      
  1159. 26838   CSeq: 28302 INVITE     
  1160. 26839   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS       
  1161. 26840   Supported: replaces, 100rel, timer, norefersub 
  1162. 26841   Session-Expires: 1800  
  1163. 26842   Min-SE: 90     
  1164. 26843   User-Agent: MicroSIP/3.20.3    
  1165. 26844   Authorization: Digest username="2002", realm="asterisk", nonce="6af736ec", uri="sip:01356615433@10.100.51.227", response="d3456c2870e4e22d21b1d44909a99abd", algorithm=MD5     
  1166. 26845   Content-Type: application/sdp  
  1167. 26846   Content-Length: 369    
  1168. 26847          
  1169. 26848   v=0    
  1170. 26849   o=- 3823263042 3823263042 IN IP4 10.100.50.228 
  1171. 26850   s=pjmedia      
  1172. 26851   b=AS:84
  1173. 26852   t=0 0  
  1174. 26853   a=X-nat:0      
  1175. 26854   m=audio 4004 RTP/AVP 18 8 0 101
  1176. 26855   c=IN IP4 10.100.50.228 
  1177. 26856   b=TIAS:64000   
  1178. 26857   a=rtcp:4005 IN IP4 10.100.50.228       
  1179. 26858   a=sendrecv     
  1180. 26859   a=rtpmap:18 G729/8000  
  1181. 26860   a=rtpmap:8 PCMA/8000   
  1182. 26861   a=rtpmap:0 PCMU/8000   
  1183. 26862   a=rtpmap:101 telephone-event/8000      
  1184. 26863   a=fmtp:101 0-16
  1185. 26864   a=ssrc:1087922039 cname:34383afd67314afe       
  1186. 26865   <------------->
  1187. 26866   [2021-02-25 12:00:47] VERBOSE[22680] chan_sip.c: --- (16 headers 17 lines) --- 
  1188. 26867   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Sending to 10.100.50.228:61342 (NAT)      
  1189. 26868   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Using INVITE request as basis request - 99b335fa210d44168b869d54dd5665b6  
  1190. 26869   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Found peer '2002' for '2002' from 10.100.50.228:61342     
  1191. 26870   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] netsock2.c: Using SIP RTP TOS bits 184
  1192. 26871   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] netsock2.c: Using SIP RTP CoS mark 5  
  1193. 26872   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Got SDP version 3823263042 and unique parts [- 3823263042 IN IP4 10.100.50.228]   
  1194. 26873   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Found RTP audio format 18 
  1195. 26874   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Found RTP audio format 8  
  1196. 26875   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Found RTP audio format 0  
  1197. 26876   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Found RTP audio format 101
  1198. 26877   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Found audio description format G729 for ID 18     
  1199. 26878   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Found audio description format PCMA for ID 8      
  1200. 26879   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Found audio description format PCMU for ID 0      
  1201. 26880   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Found audio description format telephone-event for ID 101 
  1202. 26881   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Capabilities: us - (ulaw|alaw|gsm|g726|g722), peer - audio=(ulaw|alaw|g729)/video=(nothing)/text=(nothing), combined - (ulaw|alaw)
  1203. 26882   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)      
  1204. 26883   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Peer audio RTP is at port 10.100.50.228:4004      
  1205. 26884   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c: Looking for 01356615433 in from-internal (domain 10.100.51.227)   
  1206. 26885   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] sip/route.c: sip_route_dump: route/path hop: <sip:2002@10.100.50.228:61342;ob>
  1207. 26886   [2021-02-25 12:00:47] VERBOSE[22680][C-0000000e] chan_sip.c:   
  1208. 26887   <--- Transmitting (NAT) to 10.100.50.228:61342 --->    
  1209. 26888   SIP/2.0 100 Trying     
  1210. 26889   Via: SIP/2.0/UDP 10.100.50.228:61342;branch=z9hG4bKPj352c7e7ba9fc436a9927ae256ef558fa;received=10.100.50.228;rport=61342       
  1211. 26890   From: <sip:2002@10.100.51.227>;tag=8dbcab3391164252891c110626228d2e    
  1212. 26891   To: <sip:01356615433@10.100.51.227>    
  1213. 26892   Call-ID: 99b335fa210d44168b869d54dd5665b6      
  1214. 26893   CSeq: 28302 INVITE     
  1215. 26894   Server: FPBX-15.0.16.81(16.13.0)       
  1216. 26895   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE     
  1217. 26896   Supported: replaces, timer     
  1218. 26897   Session-Expires: 1800;refresher=uas    
  1219. 26898   Contact: <sip:01356615433@10.100.51.227:5060>  
  1220. 26899   Content-Length: 0      
  1221. 26900          
  1222. 26901          
  1223. 26902   <------------> 
  1224. 26903   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [01356615433@from-internal:1] Macro("SIP/2002-0000000f", "user-callerid,LIMIT,EXTERNAL,") in new stack       
  1225. 26904   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/2002-0000000f", "TOUCH_MONITOR=1614254447.26") in new stack       
  1226. 26905   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/2002-0000000f", "AMPUSER=2002") in new stack      
  1227. 26906   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/2002-0000000f", "HOTDESCKCHAN=2002-0000000f") in new stack
  1228. 26907   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/2002-0000000f", "HOTDESKEXTEN=2002") in new stack 
  1229. 26908   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/2002-0000000f", "HOTDESKCALL=0") in new stack     
  1230. 26909   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/2002-0000000f", "0?Set(HOTDESKCALL=1)") in new stack   
  1231. 26910   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/2002-0000000f", "0?Set(CALLERID(name)=)") in new stack 
  1232. 26911   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/2002-0000000f", "0?report") in new stack       
  1233. 26912   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/2002-0000000f", "1?Set(REALCALLERIDNUM=2002)") in new stack    
  1234. 26913   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/2002-0000000f", "AMPUSER=2002") in new stack     
  1235. 26914   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/2002-0000000f", "0?limit") in new stack       
  1236. 26915   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/2002-0000000f", "AMPUSERCIDNAME=6614100") in new stack   
  1237. 26916   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/2002-0000000f", "0?Set(__CIDMASQUERADING=TRUE)") in new stack 
  1238. 26917   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/2002-0000000f", "0?report") in new stack      
  1239. 26918   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/2002-0000000f", "AMPUSERCID=2002") in new stack  
  1240. 26919   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:16] Set("SIP/2002-0000000f", "__DIAL_OPTIONS=HhTtr") in new stack     
  1241. 26920   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/2002-0000000f", "CALLERID(all)="6614100" <2002>") in new stack   
  1242. 26921   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("SIP/2002-0000000f", "0?Set(CUSDIAL=)") in new stack       
  1243. 26922   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("SIP/2002-0000000f", "0?Set(CALLERID(all)="6614100" <2002>)") in new stack 
  1244. 26923   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:20] GotoIf("SIP/2002-0000000f", "0?limit") in new stack       
  1245. 26924   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:21] ExecIf("SIP/2002-0000000f", "1?Set(GROUP(concurrency_limit)=2002)") in new stack  
  1246. 26925   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:22] ExecIf("SIP/2002-0000000f", "0?Set(CHANNEL(language)=)") in new stack     
  1247. 26926   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:23] NoOp("SIP/2002-0000000f", "Macro Depth is 1") in new stack
  1248. 26927   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:24] GotoIf("SIP/2002-0000000f", "1?report2:macroerror") in new stack  
  1249. 26928   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx_builtins.c: Goto (macro-user-callerid,s,25)       
  1250. 26929   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("SIP/2002-0000000f", "1?continue") in new stack    
  1251. 26930   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx_builtins.c: Goto (macro-user-callerid,s,44)       
  1252. 26931   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:44] Set("SIP/2002-0000000f", "CALLERID(number)=2002") in new stack    
  1253. 26932   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:45] Set("SIP/2002-0000000f", "CALLERID(name)=6614100") in new stack   
  1254. 26933   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:46] GotoIf("SIP/2002-0000000f", "0?cnum") in new stack
  1255. 26934   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:47] Set("SIP/2002-0000000f", "CDR(cnam)=6614100") in new stack
  1256. 26935   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:48] Set("SIP/2002-0000000f", "CDR(cnum)=2002") in new stack   
  1257. 26936   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/2002-0000000f", "CHANNEL(language)=en") in new stack     
  1258. 26937   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [01356615433@from-internal:2] Gosub("SIP/2002-0000000f", "sub-record-check,s,1(out,01356615433,dontcare)") in new stack      
  1259. 26938   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/2002-0000000f", "0?initialized") in new stack     
  1260. 26939   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@sub-record-check:2] Set("SIP/2002-0000000f", "__REC_STATUS=INITIALIZED") in new stack     
  1261. 26940   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@sub-record-check:3] Set("SIP/2002-0000000f", "NOW=1614254447") in new stack       
  1262. 26941   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@sub-record-check:4] Set("SIP/2002-0000000f", "__DAY=25") in new stack     
  1263. 26942   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@sub-record-check:5] Set("SIP/2002-0000000f", "__MONTH=02") in new stack   
  1264. 26943   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@sub-record-check:6] Set("SIP/2002-0000000f", "__YEAR=2021") in new stack  
  1265. 26944   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@sub-record-check:7] Set("SIP/2002-0000000f", "__TIMESTR=20210225-120047") in new stack    
  1266. 26945   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@sub-record-check:8] Set("SIP/2002-0000000f", "__FROMEXTEN=2002") in new stack     
  1267. 26946   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@sub-record-check:9] Set("SIP/2002-0000000f", "__MON_FMT=wav") in new stack
  1268. 26947   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/2002-0000000f", "Recordings initialized") in new stack     
  1269. 26948   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/2002-0000000f", "0?Set(ARG3=dontcare)") in new stack     
  1270. 26949   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@sub-record-check:12] Set("SIP/2002-0000000f", "REC_POLICY_MODE_SAVE=") in new stack       
  1271. 26950   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/2002-0000000f", "0?Set(REC_STATUS=NO)") in new stack     
  1272. 26951   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/2002-0000000f", "3?checkaction") in new stack    
  1273. 26952   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx_builtins.c: Goto (sub-record-check,s,17)  
  1274. 26953   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/2002-0000000f", "1?sub-record-check,out,1") in new stack 
  1275. 26954   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx_builtins.c: Goto (sub-record-check,out,1) 
  1276. 26955   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [out@sub-record-check:1] NoOp("SIP/2002-0000000f", "Outbound Recording Check from 2002 to 01356615433") in new stack 
  1277. 26956   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [out@sub-record-check:2] Set("SIP/2002-0000000f", "RECMODE=dontcare") in new stack   
  1278. 26957   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [out@sub-record-check:3] ExecIf("SIP/2002-0000000f", "1?Goto(routewins)") in new stack       
  1279. 26958   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx_builtins.c: Goto (sub-record-check,out,7) 
  1280. 26959   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [out@sub-record-check:7] Gosub("SIP/2002-0000000f", "recordcheck,1(dontcare,out,01356615433)") in new stack  
  1281. 26960   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/2002-0000000f", "Starting recording check against dontcare") in new stack 
  1282. 26961   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/2002-0000000f", "dontcare") in new stack  
  1283. 26962   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx_builtins.c: Goto (sub-record-check,recordcheck,3) 
  1284. 26963   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/2002-0000000f", "") in new stack
  1285. 26964   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [out@sub-record-check:8] Return("SIP/2002-0000000f", "") in new stack
  1286. 26965   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [01356615433@from-internal:3] ExecIf("SIP/2002-0000000f", "0 ?Set(CDR(accountcode)=)") in new stack  
  1287. 26966   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [01356615433@from-internal:4] Set("SIP/2002-0000000f", "_ROUTEID=7") in new stack    
  1288. 26967   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [01356615433@from-internal:5] Set("SIP/2002-0000000f", "_ROUTENAME=outgoing") in new stack   
  1289. 26968   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [01356615433@from-internal:6] Set("SIP/2002-0000000f", "MOHCLASS=default") in new stack      
  1290. 26969   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [01356615433@from-internal:7] Set("SIP/2002-0000000f", "_CALLERIDNAMEINTERNAL=6614100") in new stack 
  1291. 26970   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [01356615433@from-internal:8] Set("SIP/2002-0000000f", "_CALLERIDNUMINTERNAL=2002") in new stack     
  1292. 26971   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [01356615433@from-internal:9] Set("SIP/2002-0000000f", "_EMAILNOTIFICATION=FALSE") in new stack      
  1293. 26972   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [01356615433@from-internal:10] Set("SIP/2002-0000000f", "_NODEST=") in new stack     
  1294. 26973   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [01356615433@from-internal:11] Macro("SIP/2002-0000000f", "dialout-trunk,1,01356615433,,off") in new stack   
  1295. 26974   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:1] Set("SIP/2002-0000000f", "DIAL_TRUNK=1") in new stack      
  1296. 26975   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("SIP/2002-0000000f", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack       
  1297. 26976   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("SIP/2002-0000000f", "0?sub-pincheck,s,1()") in new stack  
  1298. 26977   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("SIP/2002-0000000f", "0?Set(CALLERID(num)=2002)") in new stack      
  1299. 26978   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("SIP/2002-0000000f", "0?disabletrunk,1") in new stack       
  1300. 26979   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:6] Set("SIP/2002-0000000f", "DIAL_NUMBER=01356615433") in new stack   
  1301. 26980   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:7] Set("SIP/2002-0000000f", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack  
  1302. 26981   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:8] Set("SIP/2002-0000000f", "OUTBOUND_GROUP=OUT_1") in new stack      
  1303. 26982   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:9] Set("SIP/2002-0000000f", "DIAL_TRUNK_OPTIONS=T") in new stack      
  1304. 26983   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("SIP/2002-0000000f", "0?nomax") in new stack       
  1305. 26984   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf("SIP/2002-0000000f", "0?chanfull") in new stack    
  1306. 26985   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("SIP/2002-0000000f", "0?skipoutcid") in new stack  
  1307. 26986   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("SIP/2002-0000000f", "outbound-callerid,1") in new stack    
  1308. 26987   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("SIP/2002-0000000f", "2002") in new stack 
  1309. 26988   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("SIP/2002-0000000f", "") in new stack     
  1310. 26989   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("SIP/2002-0000000f", "off") in new stack  
  1311. 26990   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("SIP/2002-0000000f", "0?Set(CALLERPRES(name-pres)=)") in new stack      
  1312. 26991   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("SIP/2002-0000000f", "0?Set(CALLERPRES(num-pres)=)") in new stack       
  1313. 26992   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:6] Set("SIP/2002-0000000f", "HOTDESCKCHAN=2002-0000000f") in new stack    
  1314. 26993   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:7] Set("SIP/2002-0000000f", "HOTDESKEXTEN=2002") in new stack     
  1315. 26994   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:8] Set("SIP/2002-0000000f", "HOTDESKCALL=0") in new stack 
  1316. 26995   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("SIP/2002-0000000f", "0?Set(HOTDESKCALL=1)") in new stack       
  1317. 26996   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("SIP/2002-0000000f", "0?Set(CALLERID(name)=)") in new stack    
  1318. 26997   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:11] Set("SIP/2002-0000000f", "ALLOWTHISROUTE=NO") in new stack    
  1319. 26998   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("SIP/2002-0000000f", "0?Set(ALLOWTHISROUTE=YES)") in new stack 
  1320. 26999   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("SIP/2002-0000000f", "0?Hangup()") in new stack
  1321. 27000   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("SIP/2002-0000000f", "0?Set(REALCALLERIDNUM=2002)") in new stack       
  1322. 27001   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("SIP/2002-0000000f", "0?Set(AMPUSER=2002)") in new stack       
  1323. 27002   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("SIP/2002-0000000f", "1?normcid") in new stack 
  1324. 27003   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)   
  1325. 27004   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:20] Set("SIP/2002-0000000f", "USEROUTCID=6614100") in new stack   
  1326. 27005   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:21] Set("SIP/2002-0000000f", "EMERGENCYCID=") in new stack
  1327. 27006   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("SIP/2002-0000000f", "0?Set(EMERGENCYCID=)") in new stack      
  1328. 27007   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:23] Set("SIP/2002-0000000f", "TRUNKOUTCID=") in new stack 
  1329. 27008   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf("SIP/2002-0000000f", "1?trunkcid") in new stack
  1330. 27009   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx_builtins.c: Goto (macro-outbound-callerid,s,30)   
  1331. 27010   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf("SIP/2002-0000000f", "0?Set(CALLERID(all)=)") in new stack     
  1332. 27011   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("SIP/2002-0000000f", "1?Set(CALLERID(all)=6614100)") in new stack      
  1333. 27012   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf("SIP/2002-0000000f", "0?Set(CALLERID(all)=)") in new stack     
  1334. 27013   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("SIP/2002-0000000f", "0?Set(CALLERID(all)=2002)") in new stack 
  1335. 27014   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:34] Set("SIP/2002-0000000f", "TIOHIDE=no") in new stack   
  1336. 27015   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:35] ExecIf("SIP/2002-0000000f", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack 
  1337. 27016   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("SIP/2002-0000000f", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack  
  1338. 27017   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("SIP/2002-0000000f", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack 
  1339. 27018   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf("SIP/2002-0000000f", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack  
  1340. 27019   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:39] Set("SIP/2002-0000000f", "CDR(outbound_cnum)=6614100") in new stack   
  1341. 27020   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outbound-callerid:40] Set("SIP/2002-0000000f", "CDR(outbound_cnam)=") in new stack  
  1342. 27021   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("SIP/2002-0000000f", "0?sub-flp-1,s,1()") in new stack    
  1343. 27022   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:15] Set("SIP/2002-0000000f", "OUTNUM=01356615433") in new stack       
  1344. 27023   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:16] Set("SIP/2002-0000000f", "custom=SIP/tatasip") in new stack       
  1345. 27024   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("SIP/2002-0000000f", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
  1346. 27025   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("SIP/2002-0000000f", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack 
  1347. 27026   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("SIP/2002-0000000f", "dialout-trunk-predial-hook,") in new stack    
  1348. 27027   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] NoOp("SIP/2002-0000000f", "Adding caller ID for TATA") in new stack   
  1349. 27028   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:2] SIPAddHeader("SIP/2002-0000000f", "P-Preferred-Identity: <sip:6614100@10.50.185.2>") in new stack     
  1350. 27029   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:3] MacroExit("SIP/2002-0000000f", "") in new stack       
  1351. 27030   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("SIP/2002-0000000f", "0?skipcrm") in new stack     
  1352. 27031   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:21] Set("SIP/2002-0000000f", "__CRM_DIRECTION=OUTBOUND") in new stack 
  1353. 27032   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:22] Set("SIP/2002-0000000f", "__CRM_DESTINATION=01356615433") in new stack    
  1354. 27033   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:23] Set("SIP/2002-0000000f", "__CRM_SOURCE=2002") in new stack
  1355. 27034   [2021-02-25 12:00:47] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:24] AGI("SIP/2002-0000000f", "agi://127.0.0.1/sangomacrm.agi") in new stack   
  1356. 27035   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] res_agi.c: <SIP/2002-0000000f>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
  1357. 27036   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:25] Set("SIP/2002-0000000f", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack      
  1358. 27037   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp("SIP/2002-0000000f", "CRM Finished") in new stack    
  1359. 27038   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf("SIP/2002-0000000f", "0?bypass,1") in new stack    
  1360. 27039   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf("SIP/2002-0000000f", "1?Set(CONNECTEDLINE(num,i)=01356615433)") in new stack       
  1361. 27040   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("SIP/2002-0000000f", "1?Set(CONNECTEDLINE(name,i)=CID:6614100)") in new stack      
  1362. 27041   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("SIP/2002-0000000f", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)6614100)") in new stack      
  1363. 27042   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("SIP/2002-0000000f", "0?customtrunk") in new stack 
  1364. 27043   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf("SIP/2002-0000000f", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack    
  1365. 27044   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:33] Set("SIP/2002-0000000f", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack      
  1366. 27045   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:34] Dial("SIP/2002-0000000f", "SIP/tatasip/01356615433,300,Tb(func-apply-sipheaders^s^1,(1))U(sub-send-obroute-email^01356615433^01356615433^1^1614254447^^6614100)") in new stack    
  1367. 27046   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] netsock2.c: Using SIP RTP TOS bits 184
  1368. 27047   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] netsock2.c: Using SIP RTP CoS mark 5  
  1369. 27048   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] app_stack.c: SIP/tatasip-00000010 Internal Gosub(func-apply-sipheaders,s,1(1)) start  
  1370. 27049   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/tatasip-00000010", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  1371. 27050   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/tatasip-00000010", "Applying SIP Headers to channel SIP/tatasip-00000010") in new stack
  1372. 27051   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/tatasip-00000010", "TECH=SIP") in new stack     
  1373. 27052   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/tatasip-00000010", "SIPHEADERKEYS=Alert-Info") in new stack     
  1374. 27053   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/tatasip-00000010", "1") in new stack  
  1375. 27054   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/tatasip-00000010", "sipheader=unset") in new stack      
  1376. 27055   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("SIP/tatasip-00000010", "1?SIPRemoveHeader(Alert-Info:)") in new stack    
  1377. 27056   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("SIP/tatasip-00000010", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack   
  1378. 27057   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("SIP/tatasip-00000010", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack    
  1379. 27058   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("SIP/tatasip-00000010", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack 
  1380. 27059   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("SIP/tatasip-00000010", "0?SIPAddHeader(Alert-Info:unset)") in new stack 
  1381. 27060   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("SIP/tatasip-00000010", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack
  1382. 27061   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile("SIP/tatasip-00000010", "") in new stack       
  1383. 27062   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/tatasip-00000010", "0") in new stack  
  1384. 27063   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/tatasip-00000010", "") in new stack 
  1385. 27064   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] app_stack.c: Spawn extension (tata, 01356615433, 1) exited non-zero on 'SIP/tatasip-00000010' 
  1386. 27065   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] app_stack.c: SIP/tatasip-00000010 Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL= 
  1387. 27066   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] chan_sip.c: Audio is at 12802 
  1388. 27067   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] chan_sip.c: Adding codec ulaw to SDP  
  1389. 27068   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] chan_sip.c: Adding codec alaw to SDP  
  1390. 27069   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] chan_sip.c: Adding codec g729 to SDP  
  1391. 27070   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP     
  1392. 27071   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] chan_sip.c: Reliably Transmitting (no NAT) to 10.50.185.2:5060:       
  1393. 27072   INVITE sip:01356615433@10.50.185.2 SIP/2.0     
  1394. 27073   Via: SIP/2.0/UDP 10.50.192.54:5060;branch=z9hG4bK7c6e48a5      
  1395. 27074   Max-Forwards: 70       
  1396. 27075   From: <sip:6614100@10.50.192.54>;tag=as407b06cb
  1397. 27076   To: <sip:01356615433@10.50.185.2>      
  1398. 27077   Contact: <sip:6614100@10.50.192.54:5060>       
  1399. 27078   Call-ID: 44d0055126983b877565fa392478d31d@10.50.192.54:5060    
  1400. 27079   CSeq: 102 INVITE       
  1401. 27080   User-Agent: FPBX-15.0.16.81(16.13.0)   
  1402. 27081   Date: Thu, 25 Feb 2021 12:00:48 GMT    
  1403. 27082   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE     
  1404. 27083   Supported: replaces, timer     
  1405. 27084   P-Preferred-Identity: <sip:6614100@10.50.185.2>
  1406. 27085   Content-Type: application/sdp  
  1407. 27086   Content-Length: 323    
  1408. 27087          
  1409. 27088   v=0    
  1410. 27089   o=root 1914936683 1914936683 IN IP4 10.50.192.54       
  1411. 27090   s=Asterisk PBX 16.13.0 
  1412. 27091   c=IN IP4 10.50.192.54  
  1413. 27092   t=0 0  
  1414. 27093   m=audio 12802 RTP/AVP 0 8 18 101       
  1415. 27094   a=rtpmap:0 PCMU/8000   
  1416. 27095   a=rtpmap:8 PCMA/8000   
  1417. 27096   a=rtpmap:18 G729/8000  
  1418. 27097   a=fmtp:18 annexb=no    
  1419. 27098   a=rtpmap:101 telephone-event/8000      
  1420. 27099   a=fmtp:101 0-16
  1421. 27100   a=ptime:20     
  1422. 27101   a=maxptime:150 
  1423. 27102   a=sendrecv     
  1424. 27103          
  1425. 27104   ---    
  1426. 27105   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] app_dial.c: Called SIP/tatasip/01356615433    
  1427. 27106   [2021-02-25 12:00:48] VERBOSE[22680] chan_sip.c:       
  1428. 27107   <--- SIP read from UDP:10.50.185.2:5060 --->   
  1429. 27108   SIP/2.0 100 Trying     
  1430. 27109   From: <sip:6614100@10.50.192.54>;tag=as407b06cb
  1431. 27110   To: <sip:01356615433@10.50.185.2>      
  1432. 27111   Call-ID: 44d0055126983b877565fa392478d31d@10.50.192.54:5060    
  1433. 27112   CSeq: 102 INVITE       
  1434. 27113   Via: SIP/2.0/UDP 10.50.192.54:5060;branch=z9hG4bK7c6e48a5      
  1435. 27114   Contact: <sip:01356615433@10.50.185.2:5060;transport=UDP>      
  1436. 27115   Content-Length: 0      
  1437. 27116          
  1438. 27117   <------------->
  1439. 27118   [2021-02-25 12:00:48] VERBOSE[22680] chan_sip.c: --- (8 headers 0 lines) ---   
  1440. 27119   [2021-02-25 12:00:48] VERBOSE[22680] chan_sip.c:       
  1441. 27120   <--- SIP read from UDP:10.50.185.2:5060 --->   
  1442. 27121   SIP/2.0 503 Service Unavailable
  1443. 27122   From: <sip:6614100@10.50.192.54>;tag=as407b06cb
  1444. 27123   To: <sip:01356615433@10.50.185.2>;tag=BN1727104467-0-1614254442-1931446915     
  1445. 27124   Call-ID: 44d0055126983b877565fa392478d31d@10.50.192.54:5060    
  1446. 27125   CSeq: 102 INVITE       
  1447. 27126   Reason: SIP;cause=503;text="Subsystem Id: ECALLLEG Cause Code: ServiceUnavailable_503" 
  1448. 27127   Via: SIP/2.0/UDP 10.50.192.54:5060;branch=z9hG4bK7c6e48a5      
  1449. 27128   Content-Length: 0      
  1450. 27129          
  1451. 27130   <------------->
  1452. 27131   [2021-02-25 12:00:48] VERBOSE[22680] chan_sip.c: --- (8 headers 0 lines) ---   
  1453. 27132   [2021-02-25 12:00:48] VERBOSE[22680][C-0000000e] chan_sip.c: Got SIP response 503 "Service Unavailable" back from 10.50.185.2:5060     
  1454. 27133   [2021-02-25 12:00:48] VERBOSE[22680][C-0000000e] chan_sip.c: Transmitting (no NAT) to 10.50.185.2:5060:
  1455. 27134   ACK sip:01356615433@10.50.185.2 SIP/2.0
  1456. 27135   Via: SIP/2.0/UDP 10.50.192.54:5060;branch=z9hG4bK7c6e48a5      
  1457. 27136   Max-Forwards: 70       
  1458. 27137   From: <sip:6614100@10.50.192.54>;tag=as407b06cb
  1459. 27138   To: <sip:01356615433@10.50.185.2>;tag=BN1727104467-0-1614254442-1931446915     
  1460. 27139   Contact: <sip:6614100@10.50.192.54:5060>       
  1461. 27140   Call-ID: 44d0055126983b877565fa392478d31d@10.50.192.54:5060    
  1462. 27141   CSeq: 102 ACK  
  1463. 27142   User-Agent: FPBX-15.0.16.81(16.13.0)   
  1464. 27143   Content-Length: 0      
  1465. 27144          
  1466. 27145          
  1467. 27146   ---    
  1468. 27147   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] app_dial.c: SIP/tatasip-00000010 is circuit-busy      
  1469. 27148   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] app_dial.c: Everyone is busy/congested at this time (1:0/1/0) 
  1470. 27149   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:35] NoOp("SIP/2002-0000000f", "Dial failed for some reason with DIALSTATUS = CONGESTION and HANGUPCAUSE = 34") in new stack   
  1471. 27150   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-dialout-trunk:36] GotoIf("SIP/2002-0000000f", "0?continue,1:s-CONGESTION,1") in new stack   
  1472. 27151   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx_builtins.c: Goto (macro-dialout-trunk,s-CONGESTION,1)     
  1473. 27152   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s-CONGESTION@macro-dialout-trunk:1] Set("SIP/2002-0000000f", "RC=34") in new stack  
  1474. 27153   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s-CONGESTION@macro-dialout-trunk:2] Goto("SIP/2002-0000000f", "34,1") in new stack  
  1475. 27154   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx_builtins.c: Goto (macro-dialout-trunk,34,1)       
  1476. 27155   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [34@macro-dialout-trunk:1] Goto("SIP/2002-0000000f", "continue,1") in new stack      
  1477. 27156   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1) 
  1478. 27157   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp("SIP/2002-0000000f", "TRUNK Dial failed due to CONGESTION HANGUPCAUSE: 34 - failing through to other trunks") in new stack     
  1479. 27158   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf("SIP/2002-0000000f", "1?Set(CALLERID(number)=2002)") in new stack    
  1480. 27159   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [01356615433@from-internal:12] Macro("SIP/2002-0000000f", "outisbusy,") in new stack 
  1481. 27160   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outisbusy:1] Progress("SIP/2002-0000000f", "") in new stack 
  1482. 27161   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] chan_sip.c: Audio is at 15564 
  1483. 27162   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] chan_sip.c: Adding codec ulaw to SDP  
  1484. 27163   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] chan_sip.c: Adding codec alaw to SDP  
  1485. 27164   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP     
  1486. 27165   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] chan_sip.c:   
  1487. 27166   <--- Transmitting (NAT) to 10.100.50.228:61342 --->    
  1488. 27167   SIP/2.0 183 Session Progress   
  1489. 27168   Via: SIP/2.0/UDP 10.100.50.228:61342;branch=z9hG4bKPj352c7e7ba9fc436a9927ae256ef558fa;received=10.100.50.228;rport=61342       
  1490. 27169   From: <sip:2002@10.100.51.227>;tag=8dbcab3391164252891c110626228d2e    
  1491. 27170   To: <sip:01356615433@10.100.51.227>;tag=as6604affa     
  1492. 27171   Call-ID: 99b335fa210d44168b869d54dd5665b6      
  1493. 27172   CSeq: 28302 INVITE     
  1494. 27173   Server: FPBX-15.0.16.81(16.13.0)       
  1495. 27174   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE     
  1496. 27175   Supported: replaces, timer     
  1497. 27176   Session-Expires: 1800;refresher=uas    
  1498. 27177   Contact: <sip:01356615433@10.100.51.227:5060>  
  1499. 27178   Content-Type: application/sdp  
  1500. 27179   Require: timer 
  1501. 27180   Content-Length: 278    
  1502. 27181          
  1503. 27182   v=0    
  1504. 27183   o=root 1440365534 1440365534 IN IP4 10.100.51.227      
  1505. 27184   s=Asterisk PBX 16.13.0 
  1506. 27185   c=IN IP4 10.100.51.227 
  1507. 27186   t=0 0  
  1508. 27187   m=audio 15564 RTP/AVP 0 8 101  
  1509. 27188   a=rtpmap:0 PCMU/8000   
  1510. 27189   a=rtpmap:8 PCMA/8000   
  1511. 27190   a=rtpmap:101 telephone-event/8000      
  1512. 27191   a=fmtp:101 0-16
  1513. 27192   a=ptime:20     
  1514. 27193   a=maxptime:150 
  1515. 27194   a=sendrecv     
  1516. 27195          
  1517. 27196   <------------> 
  1518. 27197   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("SIP/2002-0000000f", "0?emergency,1") in new stack      
  1519. 27198   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("SIP/2002-0000000f", "0?intracompany,1") in new stack   
  1520. 27199   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outisbusy:4] Playback("SIP/2002-0000000f", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack    
  1521. 27200   [2021-02-25 12:00:48] VERBOSE[32126][C-0000000e] file.c: <SIP/2002-0000000f> Playing 'all-circuits-busy-now.ulaw' (language 'en')      
  1522. 27201   [2021-02-25 12:00:49] VERBOSE[22680] chan_sip.c: Really destroying SIP dialog '44d0055126983b877565fa392478d31d@10.50.192.54:5060' Method: INVITE      
  1523. 27202   [2021-02-25 12:00:49] VERBOSE[32126][C-0000000e] file.c: <SIP/2002-0000000f> Playing 'please-try-call-later.ulaw' (language 'en')      
  1524. 27203   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-outisbusy:5] Congestion("SIP/2002-0000000f", "20") in new stack     
  1525. 27204   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] chan_sip.c:   
  1526. 27205   <--- Reliably Transmitting (NAT) to 10.100.50.228:61342 --->   
  1527. 27206   SIP/2.0 503 Service Unavailable
  1528. 27207   Via: SIP/2.0/UDP 10.100.50.228:61342;branch=z9hG4bKPj352c7e7ba9fc436a9927ae256ef558fa;received=10.100.50.228;rport=61342       
  1529. 27208   From: <sip:2002@10.100.51.227>;tag=8dbcab3391164252891c110626228d2e    
  1530. 27209   To: <sip:01356615433@10.100.51.227>;tag=as6604affa     
  1531. 27210   Call-ID: 99b335fa210d44168b869d54dd5665b6      
  1532. 27211   CSeq: 28302 INVITE     
  1533. 27212   Server: FPBX-15.0.16.81(16.13.0)       
  1534. 27213   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE     
  1535. 27214   Supported: replaces, timer     
  1536. 27215   Session-Expires: 1800;refresher=uas    
  1537. 27216   X-Asterisk-HangupCause: Circuit/channel congestion     
  1538. 27217   X-Asterisk-HangupCauseCode: 34 
  1539. 27218   Content-Length: 0      
  1540. 27219          
  1541. 27220          
  1542. 27221   <------------> 
  1543. 27222   [2021-02-25 12:00:52] WARNING[32126][C-0000000e] channel.c: Prodding channel 'SIP/2002-0000000f' failed
  1544. 27223   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] app_macro.c: Spawn extension (macro-outisbusy, s, 5) exited non-zero on 'SIP/2002-0000000f' in macro 'outisbusy'      
  1545. 27224   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Spawn extension (from-internal, 01356615433, 12) exited non-zero on 'SIP/2002-0000000f'
  1546. 27225   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Executing [h@from-internal:1] Macro("SIP/2002-0000000f", "hangupcall") in new stack    
  1547. 27226   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/2002-0000000f", "1?theend") in new stack  
  1548. 27227   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx_builtins.c: Goto (macro-hangupcall,s,3)   
  1549. 27228   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/2002-0000000f", "0?Set(CDR(recordingfile)=)") in new stack
  1550. 27229   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/2002-0000000f", " montior file= ") in new stack     
  1551. 27230   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/2002-0000000f", "1?skipagi") in new stack 
  1552. 27231   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx_builtins.c: Goto (macro-hangupcall,s,7)   
  1553. 27232   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/2002-0000000f", "") in new stack  
  1554. 27233   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/2002-0000000f' in macro 'hangupcall'    
  1555. 27234   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/2002-0000000f'   
  1556. 27235   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] app_stack.c: SIP/2002-0000000f Internal Gosub(crm-hangup,s,1) start   
  1557. 27236   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/2002-0000000f", "Sending Hangup to CRM") in new stack     
  1558. 27237   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/2002-0000000f", "HANGUP CAUSE: 34") in new stack  
  1559. 27238   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/2002-0000000f", "0?Set(__CRM_VOICEMAIL=)") in new stack 
  1560. 27239   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/2002-0000000f", "MASTER CHANNEL: 1614254447.26 = 1614254447.26") in new stack     
  1561. 27240   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/2002-0000000f", "0?return") in new stack
  1562. 27241   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@crm-hangup:6] Set("SIP/2002-0000000f", "__CRM_HANGUP=1") in new stack     
  1563. 27242   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/2002-0000000f", "agi://127.0.0.1/sangomacrm.agi") in new stack     
  1564. 27243   [2021-02-25 12:00:52] VERBOSE[22680] chan_sip.c:       
  1565. 27244   <--- SIP read from UDP:10.100.50.228:61342 --->
  1566. 27245   ACK sip:01356615433@10.100.51.227 SIP/2.0      
  1567. 27246   Via: SIP/2.0/UDP 10.100.50.228:61342;rport;branch=z9hG4bKPj352c7e7ba9fc436a9927ae256ef558fa    
  1568. 27247   Max-Forwards: 70       
  1569. 27248   From: <sip:2002@10.100.51.227>;tag=8dbcab3391164252891c110626228d2e    
  1570. 27249   To: <sip:01356615433@10.100.51.227>;tag=as6604affa     
  1571. 27250   Call-ID: 99b335fa210d44168b869d54dd5665b6      
  1572. 27251   CSeq: 28302 ACK
  1573. 27252   Content-Length: 0      
  1574. 27253          
  1575. 27254   <------------->
  1576. 27255   [2021-02-25 12:00:52] VERBOSE[22680] chan_sip.c: --- (8 headers 0 lines) ---   
  1577. 27256   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] res_agi.c: <SIP/2002-0000000f>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
  1578. 27257   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] pbx.c: Executing [s@crm-hangup:8] Return("SIP/2002-0000000f", "") in new stack
  1579. 27258   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/2002-0000000f'     
  1580. 27259   [2021-02-25 12:00:52] VERBOSE[32126][C-0000000e] app_stack.c: SIP/2002-0000000f Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=  
  1581. 27260   [2021-02-25 12:00:52] VERBOSE[22680] chan_sip.c:       
  1582. 27261   <--- SIP read from UDP:10.100.50.228:61342 --->
  1583. 27262          
  1584. 27263   <------------->
  1585. 27264   [2021-02-25 12:00:52] VERBOSE[22680] chan_sip.c: Really destroying SIP dialog '99b335fa210d44168b869d54dd5665b6' Method: ACK   
  1586. 27265   [2021-02-25 12:01:07] VERBOSE[22680] chan_sip.c:       
  1587. 27266   <--- SIP read from UDP:10.100.50.228:61342 --->
  1588. 27267          
  1589. 27268   <------------->
  1590. 27269   [2021-02-25 12:01:07] VERBOSE[22680] chan_sip.c: Reliably Transmitting (NAT) to 10.100.50.228:61342:   
  1591. 27270   OPTIONS sip:2002@10.100.50.228:61342;ob SIP/2.0
  1592. 27271   Via: SIP/2.0/UDP 10.100.51.227:5060;branch=z9hG4bK7152c55c;rport       
  1593. 27272   Max-Forwards: 70       
  1594. 27273   From: "Unknown" <sip:Unknown@10.100.51.227>;tag=as2da04116     
  1595. 27274   To: <sip:2002@10.100.50.228:61342;ob>  
  1596. 27275   Contact: <sip:Unknown@10.100.51.227:5060>      
  1597. 27276   Call-ID: 27cf3dc4134f6ec5378538572d43df05@10.100.51.227:5060   
  1598. 27277   CSeq: 102 OPTIONS      
  1599. 27278   User-Agent: FPBX-15.0.16.81(16.13.0)   
  1600. 27279   Date: Thu, 25 Feb 2021 12:01:07 GMT    
  1601. 27280   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE     
  1602. 27281   Supported: replaces, timer     
  1603. 27282   Content-Length: 0      
  1604. 27283          
  1605. 27284          
  1606. 27285   ---    
  1607. 27286   [2021-02-25 12:01:07] VERBOSE[22680] chan_sip.c:       
  1608. 27287   <--- SIP read from UDP:10.100.50.228:61342 --->
  1609. 27288   SIP/2.0 200 OK 
  1610. 27289   Via: SIP/2.0/UDP 10.100.51.227:5060;rport=5060;received=10.100.51.227;branch=z9hG4bK7152c55c   
  1611. 27290   Call-ID: 27cf3dc4134f6ec5378538572d43df05@10.100.51.227:5060   
  1612. 27291   From: "Unknown" <sip:Unknown@10.100.51.227>;tag=as2da04116     
  1613. 27292   To: <sip:2002@10.100.50.228;ob>;tag=z9hG4bK7152c55c    
  1614. 27293   CSeq: 102 OPTIONS      
  1615. 27294   Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS       
  1616. 27295   Accept: application/sdp, application/pidf+xml, application/xpidf+xml, application/simple-message-summary, message/sipfrag;version=2.0, application/im-iscomposing+xml, text/plain      
  1617. 27296   Supported: replaces, 100rel, timer, norefersub 
  1618. 27297   Allow-Events: presence, message-summary, refer 
  1619. 27298   User-Agent: MicroSIP/3.20.3    
  1620. 27299   Content-Length: 0      
  1621. 27300          
  1622. 27301   <------------->
  1623. 27302   [2021-02-25 12:01:07] VERBOSE[22680] chan_sip.c: --- (12 headers 0 lines) ---  
  1624. 27303   [2021-02-25 12:01:07] VERBOSE[22680] chan_sip.c: Really destroying SIP dialog '27cf3dc4134f6ec5378538572d43df05@10.100.51.227:5060' Method: OPTIONS

Reply to "Local 7 digit not working"

Here you can reply to the paste above