Audio Sip Trace

From Craig Wilson, 3 Months ago, written in Plain Text, viewed 3 times.
URL https://pastebin.freepbx.org/view/8b885673 Embed
Download Paste or View Raw
  1. From: <sip:101@10.0.1.29>;tag=b60426ec-a0e2-4b17-aef1-1bfae026dbb8
  2. To: <sip:101@92.21.16.93;rinstance=2221716f2fb2b5b6>
  3. Contact: <sip:101@18.130.241.63:5060>
  4. Call-ID: 08585152-1686-475c-8343-11eb9a30e335
  5. CSeq: 9557 OPTIONS
  6. Max-Forwards: 70
  7. User-Agent: FPBX-15.0.17.32(13.38.1)
  8. Content-Length:  0
  9.  
  10.  
  11. [2021-05-24 19:30:03] VERBOSE[2173] res_pjsip_logger.c: <--- Received SIP request (915 bytes) from UDP:185.110.248.22:5060 --->
  12. INVITE sip:03302290443@18.134.227.38 SIP/2.0
  13. Via: SIP/2.0/UDP 185.110.248.22:5060;rport;branch=z9hG4bKPj16a4d0dd-a3a4-4bb7-af0d-a19b08ceda94
  14. From: <sip:01226971834@185.110.248.22>;tag=dcbe9889-bc14-4c08-bd1d-65867b3271e7
  15. To: <sip:03302290443@18.134.227.38>
  16. Contact: <sip:asterisk@185.110.248.22:5060>
  17. Call-ID: 687373c7-7fde-4555-b8f4-3e646ec95148
  18. CSeq: 5690 INVITE
  19. Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, REFER, MESSAGE
  20. Supported: 100rel, timer, replaces, norefersub
  21. Session-Expires: 1800
  22. Min-SE: 90
  23. Max-Forwards: 70
  24. User-Agent: Asterisk PBX 13.33.0
  25. Content-Type: application/sdp
  26. Content-Length:   241
  27.  
  28. v=0
  29. o=- 1013336321 1013336321 IN IP4 185.110.248.22
  30. s=Asterisk
  31. c=IN IP4 185.110.248.22
  32. t=0 0
  33. m=audio 18096 RTP/AVP 8 101
  34. a=rtpmap:8 PCMA/8000
  35. a=rtpmap:101 telephone-event/8000
  36. a=fmtp:101 0-16
  37. a=ptime:20
  38. a=maxptime:150
  39. a=sendrecv
  40.  
  41. [2021-05-24 19:30:03] VERBOSE[21501] pbx_variables.c: Setting global variable 'SIPDOMAIN' to '18.134.227.38'
  42. [2021-05-24 19:30:03] VERBOSE[21501] res_pjsip_logger.c: <--- Transmitting SIP response (386 bytes) to UDP:185.110.248.22:5060 --->
  43. SIP/2.0 100 Trying
  44. Via: SIP/2.0/UDP 185.110.248.22:5060;rport=5060;received=185.110.248.22;branch=z9hG4bKPj16a4d0dd-a3a4-4bb7-af0d-a19b08ceda94
  45. Call-ID: 687373c7-7fde-4555-b8f4-3e646ec95148
  46. From: <sip:01226971834@185.110.248.22>;tag=dcbe9889-bc14-4c08-bd1d-65867b3271e7
  47. To: <sip:03302290443@18.134.227.38>
  48. CSeq: 5690 INVITE
  49. Server: FPBX-15.0.17.32(13.38.1)
  50. Content-Length:  0
  51.  
  52.  
  53. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:1] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__DIRECTION=INBOUND") in new stack
  54. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:2] Gosub("PJSIP/Dial_9_Inbound_copy_1-00000008", "sub-record-check,s,1(in,03302290443,dontcare)") in new stack
  55. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?initialized") in new stack
  56. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__REC_STATUS=INITIALIZED") in new stack
  57. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "NOW=1621881003") in new stack
  58. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__DAY=24") in new stack
  59. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__MONTH=05") in new stack
  60. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__YEAR=2021") in new stack
  61. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__TIMESTR=20210524-193003") in new stack
  62. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__FROMEXTEN=unknown") in new stack
  63. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__MON_FMT=wav") in new stack
  64. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "Recordings initialized") in new stack
  65. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(ARG3=dontcare)") in new stack
  66. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "REC_POLICY_MODE_SAVE=") in new stack
  67. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(REC_STATUS=NO)") in new stack
  68. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "2?checkaction") in new stack
  69. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (sub-record-check,s,17)
  70. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?sub-record-check,in,1") in new stack
  71. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (sub-record-check,in,1)
  72. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [in@sub-record-check:1] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "Inbound Recording Check to 03302290443") in new stack
  73. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [in@sub-record-check:2] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "FROMEXTEN=unknown") in new stack
  74. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [in@sub-record-check:3] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "11?Set(FROMEXTEN=01226971834)") in new stack
  75. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [in@sub-record-check:4] Gosub("PJSIP/Dial_9_Inbound_copy_1-00000008", "recordcheck,1(dontcare,in,03302290443)") in new stack
  76. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "Starting recording check against dontcare") in new stack
  77. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/Dial_9_Inbound_copy_1-00000008", "dontcare") in new stack
  78. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  79. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/Dial_9_Inbound_copy_1-00000008", "") in new stack
  80. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [in@sub-record-check:5] Return("PJSIP/Dial_9_Inbound_copy_1-00000008", "") in new stack
  81. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:3] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CHANNEL(tonezone)=us") in new stack
  82. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:4] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__FROM_DID=03302290443") in new stack
  83. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:5] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "returnhere=1") in new stack
  84. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:6] Gosub("PJSIP/Dial_9_Inbound_copy_1-00000008", "app-blacklist-check,s,1()") in new stack
  85. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?blacklisted") in new stack
  86. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@app-blacklist-check:2] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CALLED_BLACKLIST=1") in new stack
  87. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [s@app-blacklist-check:3] Return("PJSIP/Dial_9_Inbound_copy_1-00000008", "") in new stack
  88. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:7] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CDR(did)=03302290443") in new stack
  89. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:8] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?") in new stack
  90. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:9] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?Set(CALLERID(name)=01226971834)") in new stack
  91. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:10] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__MOHCLASS=") in new stack
  92. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:11] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__REVERSAL_REJECT=FALSE") in new stack
  93. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:12] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?post-reverse-charge") in new stack
  94. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (from-pstn,03302290443,14)
  95. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:14] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "")in new stack
  96. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:15] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
  97. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:16] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
  98. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:17] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CALLERID(name-pres)=allowed_not_screened") in new stack
  99. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:18] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CALLERID(num-pres)=allowed_not_screened") in new stack
  100. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:19] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "CallerID Entry Point") in new stack
  101. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:20] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__CRM_DIRECTION=INBOUND") in new stack
  102. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:21] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__CRM_SOURCE=01226971834") in new stack
  103. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:22] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__CRM_LINKEDID=1621881003.8") in new stack
  104. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:23] AGI("PJSIP/Dial_9_Inbound_copy_1-00000008", "sangomacrm.agi,true") in new stack
  105. [2021-05-24 19:30:03] VERBOSE[21506][C-00000004] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  106. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] res_agi.c: <PJSIP/Dial_9_Inbound_copy_1-00000008>AGI Script sangomacrm.agi completed, returning 0
  107. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:24] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  108. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [03302290443@from-pstn:25] Goto("PJSIP/Dial_9_Inbound_copy_1-00000008", "from-did-direct,101,1") in new stack
  109. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (from-did-direct,101,1)
  110. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [101@from-did-direct:1] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?ext-local,101,1:followme-check,101,1") in new stack
  111. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (ext-local,101,1)
  112. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [101@ext-local:1] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__RINGTIMER=15") in new stack
  113. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [101@ext-local:2] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(__CWIGNORE=)") in new stack
  114. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [101@ext-local:3] Macro("PJSIP/Dial_9_Inbound_copy_1-00000008", "exten-vm,101,101,0,0,0") in new stack
  115. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:1] Macro("PJSIP/Dial_9_Inbound_copy_1-00000008", "user-callerid,") in new stack
  116. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "TOUCH_MONITOR=1621881003.8") in new stack
  117. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "AMPUSER=01226971834") in new stack
  118. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "HOTDESCKCHAN=Dial_9_Inbound_copy_1-00000008") in new stack
  119. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "HOTDESKEXTEN=Dial_9_Inbound_copy_1") in new stack
  120. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "HOTDESKCALL=0") in new stack
  121. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(HOTDESKCALL=1)") in new stack
  122. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(CALLERID(name)=)") in new stack
  123. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?report") in new stack
  124. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?Set(REALCALLERIDNUM=01226971834)") in new stack
  125. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "AMPUSER=") in new stack
  126. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?limit") in new stack
  127. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:12] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "AMPUSERCIDNAME=") in new stack
  128. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  129. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?report") in new stack
  130. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-user-callerid,s,23)
  131. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:23] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "Macro Depth is 2") in new stack
  132. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:24] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?report2:macroerror") in new stack
  133. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-user-callerid,s,25)
  134. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?continue") in new stack
  135. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
  136. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:27] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__TTL=64") in new stack
  137. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:28] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?continue") in new stack
  138. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-user-callerid,s,44)
  139. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:44] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CALLERID(number)=01226971834") in new stack
  140. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:45] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CALLERID(name)=01226971834") in new stack
  141. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:46] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?cnum") in new stack
  142. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:47] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CDR(cnam)=01226971834") in new stack
  143. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:48] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CDR(cnum)=01226971834") in new stack
  144. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:49] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CHANNEL(language)=en") in new stack
  145. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:2] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "RingGroupMethod=none") in new stack
  146. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:3] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__EXTTOCALL=101") in new stack
  147. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:4] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__PICKUPMARK=101") in new stack
  148. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:5] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "RT=15") in new stack
  149. [2021-05-24 19:30:04] WARNING[21506][C-00000004] chan_sip.c: This function can only be used on SIP channels.
  150. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:6] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Macro(vm,101,DIRECTDIAL,)") in new stack
  151. [2021-05-24 19:30:04] WARNING[21506][C-00000004] chan_sip.c: This function can only be used on SIP channels.
  152. [2021-05-24 19:30:04] WARNING[21506][C-00000004] chan_sip.c: This function can only be used on SIP channels.
  153. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:7] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?MacroExit()") in new stack
  154. [2021-05-24 19:30:04] WARNING[21506][C-00000004] chan_sip.c: This function can only be used on SIP channels.
  155. [2021-05-24 19:30:04] WARNING[21506][C-00000004] chan_sip.c: This function can only be used on SIP channels.
  156. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:8] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Gosub(ext-intercom,*80101,1())") in new stack
  157. [2021-05-24 19:30:04] WARNING[21506][C-00000004] chan_sip.c: This function can only be used on SIP channels.
  158. [2021-05-24 19:30:04] WARNING[21506][C-00000004] chan_sip.c: This function can only be used on SIP channels.
  159. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:9] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?MacroExit()") in new stack
  160. [2021-05-24 19:30:04] WARNING[21506][C-00000004] chan_sip.c: This function can only be used on SIP channels.
  161. [2021-05-24 19:30:04] WARNING[21506][C-00000004] chan_sip.c: This function can only be used on SIP channels.
  162. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:10] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?ChanSpy(PJSIP/101,q)") in new stack
  163. [2021-05-24 19:30:04] WARNING[21506][C-00000004] chan_sip.c: This function can only be used on SIP channels.
  164. [2021-05-24 19:30:04] WARNING[21506][C-00000004] chan_sip.c: This function can only be used on SIP channels.
  165. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:11] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?MacroExit()") in new stack
  166. [2021-05-24 19:30:04] WARNING[21506][C-00000004] chan_sip.c: This function can only be used on SIP channels.
  167. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:12] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Macro(vm,101,DIRECTDIAL,)") in new stack
  168. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:13] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?MacroExit()") in new stack
  169. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:14] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Gosub(ext-intercom,*80101,1())") in new stack
  170. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:15] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?MacroExit()") in new stack
  171. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:16] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?ChanSpy(PJSIP/101,q)") in new stack
  172. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:17] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?MacroExit()") in new stack
  173. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:18] Gosub("PJSIP/Dial_9_Inbound_copy_1-00000008", "sub-record-check,s,1(exten,101,dontcare)") in new stack
  174. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "11?initialized") in new stack
  175. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (sub-record-check,s,10)
  176. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "Recordings initialized") in new stack
  177. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(ARG3=dontcare)") in new stack
  178. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "REC_POLICY_MODE_SAVE=") in new stack
  179. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(REC_STATUS=NO)") in new stack
  180. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "5?checkaction") in new stack
  181. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (sub-record-check,s,17)
  182. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?sub-record-check,exten,1") in new stack
  183. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (sub-record-check,exten,1)
  184. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [exten@sub-record-check:1] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "Exten Recording Check between 01226971834 and 101") in new stack
  185. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [exten@sub-record-check:2] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CALLTYPE=external") in new stack
  186. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [exten@sub-record-check:3] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(CALLTYPE=)") in new stack
  187. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [exten@sub-record-check:4] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CALLEE=dontcare") in new stack
  188. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [exten@sub-record-check:5] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(CALLEE=dontcare)") in new stack
  189. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [exten@sub-record-check:6] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?callee") in new stack
  190. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (sub-record-check,exten,11)
  191. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [exten@sub-record-check:11] Gosub("PJSIP/Dial_9_Inbound_copy_1-00000008", "recordcheck,1(dontcare,external,101)") in new stack
  192. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "Starting recording check against dontcare") in new stack
  193. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/Dial_9_Inbound_copy_1-00000008", "dontcare") in new stack
  194. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  195. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/Dial_9_Inbound_copy_1-00000008", "") in new stack
  196. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [exten@sub-record-check:12] Return("PJSIP/Dial_9_Inbound_copy_1-00000008", "") in new stack
  197. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:19] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?macrodial") in new stack
  198. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-exten-vm,s,25)
  199. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:25] GosubIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?clrheader,1()") in new stack
  200. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:26] Macro("PJSIP/Dial_9_Inbound_copy_1-00000008", "dial-one,15,HhTtr,101") in new stack
  201. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:1] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "DEXTEN=101") in new stack
  202. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:2] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__CRM_SOURCE=01226971834") in new stack
  203. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:3] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(__EXTTOCALL=101)") in new stack
  204. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:4] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "DIALSTATUS_CW=") in new stack
  205. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:5] GosubIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?screen,1()") in new stack
  206. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:6] GosubIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?cf,1()") in new stack
  207. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:7] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?skip1") in new stack
  208. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-dial-one,s,10)
  209. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:10] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?nodial") in new stack
  210. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:11] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?continue") in new stack
  211. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:12] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "EXTHASCW=ENABLED") in new stack
  212. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:13] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?next1:cwinusebusy") in new stack
  213. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-dial-one,s,25)
  214. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:25] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?next3:continue") in new stack
  215. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-dial-one,s,27)
  216. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:27] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?nodial") in new stack
  217. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:28] GosubIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?dstring,1():dlocal,1()") in new stack
  218. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:1] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "DSTRING=") in new stack
  219. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:2] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "DEVICES=101") in new stack
  220. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Return()") in new stack
  221. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(DEVICES=01)") in new stack
  222. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:5] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "LOOPCNT=1") in new stack
  223. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:6] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "ITER=1") in new stack
  224. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:7] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "THISDIAL=PJSIP/101") in new stack
  225. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:8] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?docheck") in new stack
  226. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:9] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "Debug: Found PJSIP Destination PJSIP/101") in new stack
  227. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:10] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?doset") in new stack
  228. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:11] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS") in new stack
  229. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:12] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "THISDIAL=") in new stack
  230. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:13] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?Set(DIALSTATUS=CHANUNAVAIL)") in new stack
  231. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?skipset") in new stack
  232. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-dial-one,dstring,16)
  233. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:16] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "ITER=2") in new stack
  234. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?begin") in new stack
  235. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?Return()") in new stack
  236. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:29] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?nodial") in new stack
  237. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-dial-one,s,61)
  238. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:61] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "") in new stack
  239. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:62] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(DIALSTATUS=NOANSWER)") in new stack
  240. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:63] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "Returned from dial-one with nothing to call and DIALSTATUS: CHANUNAVAIL") in new stack
  241. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-dial-one:64] MacroExit("PJSIP/Dial_9_Inbound_copy_1-00000008", "")in new stack
  242. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:27] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "SV_DIALSTATUS=CHANUNAVAIL") in new stack
  243. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:28] GosubIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?docfu,1()") in new stack
  244. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:29] GosubIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?docfb,1()") in new stack
  245. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:30] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "DIALSTATUS=CHANUNAVAIL") in new stack
  246. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:31] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?MacroExit()") in new stack
  247. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:32] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?s-CHANUNAVAIL,1") in new stack
  248. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-exten-vm:33] Macro("PJSIP/Dial_9_Inbound_copy_1-00000008", "vm,101,CHANUNAVAIL,") in new stack
  249. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-vm:1] Macro("PJSIP/Dial_9_Inbound_copy_1-00000008", "user-callerid,SKIPTTL") in new stack
  250. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "TOUCH_MONITOR=1621881003.8") in new stack
  251. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "AMPUSER=01226971834") in new stack
  252. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "HOTDESCKCHAN=Dial_9_Inbound_copy_1-00000008") in new stack
  253. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "HOTDESKEXTEN=Dial_9_Inbound_copy_1") in new stack
  254. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "HOTDESKCALL=0") in new stack
  255. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(HOTDESKCALL=1)") in new stack
  256. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(CALLERID(name)=)") in new stack
  257. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?report") in new stack
  258. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(REALCALLERIDNUM=01226971834)") in new stack
  259. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "AMPUSER=") in new stack
  260. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?limit") in new stack
  261. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:12] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "AMPUSERCIDNAME=") in new stack
  262. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  263. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?report") in new stack
  264. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-user-callerid,s,23)
  265. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:23] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "Macro Depth is 3") in new stack
  266. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:24] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?report2:macroerror") in new stack
  267. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-user-callerid,s,25)
  268. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?continue") in new stack
  269. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-user-callerid,s,44)
  270. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:44] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CALLERID(number)=01226971834") in new stack
  271. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:45] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CALLERID(name)=01226971834") in new stack
  272. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:46] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?cnum") in new stack
  273. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:47] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CDR(cnam)=01226971834") in new stack
  274. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:48] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CDR(cnum)=01226971834") in new stack
  275. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-user-callerid:49] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "CHANNEL(language)=en") in new stack
  276. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-vm:2] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "VMGAIN=") in new stack
  277. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-vm:3] Macro("PJSIP/Dial_9_Inbound_copy_1-00000008", "blkvm-check,") in new stack
  278. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-blkvm-check:1] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "GOSUB_RETVAL=") in new stack
  279. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-blkvm-check:2] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(GOSUB_RETVAL=TRUE)") in new stack
  280. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-blkvm-check:3] MacroExit("PJSIP/Dial_9_Inbound_copy_1-00000008", "") in new stack
  281. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-vm:4] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?vmx,1") innew stack
  282. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-vm,vmx,1)
  283. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [vmx@macro-vm:1] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__EXTTOCALL=101") in new stack
  284. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [vmx@macro-vm:2] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__CRM_VOICEMAIL=101") in new stack
  285. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [vmx@macro-vm:3] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "MEXTEN=101") in new stack
  286. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [vmx@macro-vm:4] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "MMODE=CHANUNAVAIL") in new stack
  287. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [vmx@macro-vm:5] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "RETVM=") in new stack
  288. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [vmx@macro-vm:6] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "MODE=unavail") in new stack
  289. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [vmx@macro-vm:7] Macro("PJSIP/Dial_9_Inbound_copy_1-00000008", "get-vmcontext,101") in new stack
  290. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-get-vmcontext:1] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "VMCONTEXT=default") in new stack
  291. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?200:300") in new stack
  292. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
  293. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "") in new stack
  294. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [vmx@macro-vm:8] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "MODE=unavail") in new stack
  295. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [vmx@macro-vm:9] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "MODE IS: unavail") in new stack
  296. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [vmx@macro-vm:10] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?chknomsg") in new stack
  297. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-vm,vmx,12)
  298. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [vmx@macro-vm:12] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?s-CHANUNAVAIL,1") in new stack
  299. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [vmx@macro-vm:13] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?notdirect") in new stack
  300. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-vm,vmx,15)
  301. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [vmx@macro-vm:15] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "Checking ifext 101 is enabled: ") in new stack
  302. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [vmx@macro-vm:16] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?s-CHANUNAVAIL,1") in new stack
  303. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-vm,s-CHANUNAVAIL,1)
  304. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s-CHANUNAVAIL@macro-vm:1] Macro("PJSIP/Dial_9_Inbound_copy_1-00000008", "get-vmcontext,101") in new stack
  305. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-get-vmcontext:1] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "VMCONTEXT=default") in new stack
  306. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?200:300") in new stack
  307. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
  308. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "") in new stack
  309. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] pbx.c: Executing [s-CHANUNAVAIL@macro-vm:2] VoiceMail("PJSIP/Dial_9_Inbound_copy_1-00000008", "101@default,u") in new stack
  310. [2021-05-24 19:30:04] VERBOSE[21501] res_pjsip_logger.c: <--- Transmitting SIP response (943 bytes) to UDP:185.110.248.22:5060 --->
  311. SIP/2.0 200 OK
  312. Via: SIP/2.0/UDP 185.110.248.22:5060;rport=5060;received=185.110.248.22;branch=z9hG4bKPj16a4d0dd-a3a4-4bb7-af0d-a19b08ceda94
  313. Call-ID: 687373c7-7fde-4555-b8f4-3e646ec95148
  314. From: <sip:01226971834@185.110.248.22>;tag=dcbe9889-bc14-4c08-bd1d-65867b3271e7
  315. To: <sip:03302290443@18.134.227.38>;tag=4a03c265-2ec0-492f-b516-12dbf98a6ce9
  316. CSeq: 5690 INVITE
  317. Server: FPBX-15.0.17.32(13.38.1)
  318. Contact: <sip:18.130.241.63:5060>
  319. Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER
  320. Supported: 100rel, timer, replaces, norefersub
  321. Session-Expires: 1800;refresher=uac
  322. Require: timer
  323. Content-Type: application/sdp
  324. Content-Length:   239
  325.  
  326. v=0
  327. o=- 1013336321 1013336323 IN IP4 18.130.241.63
  328. s=Asterisk
  329. c=IN IP4 18.130.241.63
  330. t=0 0
  331. m=audio 16534 RTP/AVP 8 101
  332. a=rtpmap:8 PCMA/8000
  333. a=rtpmap:101 telephone-event/8000
  334. a=fmtp:101 0-16
  335. a=ptime:20
  336. a=maxptime:150
  337. a=sendrecv
  338.  
  339. [2021-05-24 19:30:04] VERBOSE[2173] res_pjsip_logger.c: <--- Transmitting SIP response (943 bytes) to UDP:185.110.248.22:5060 --->
  340. SIP/2.0 200 OK
  341. Via: SIP/2.0/UDP 185.110.248.22:5060;rport=5060;received=185.110.248.22;branch=z9hG4bKPj16a4d0dd-a3a4-4bb7-af0d-a19b08ceda94
  342. Call-ID: 687373c7-7fde-4555-b8f4-3e646ec95148
  343. From: <sip:01226971834@185.110.248.22>;tag=dcbe9889-bc14-4c08-bd1d-65867b3271e7
  344. To: <sip:03302290443@18.134.227.38>;tag=4a03c265-2ec0-492f-b516-12dbf98a6ce9
  345. CSeq: 5690 INVITE
  346. Server: FPBX-15.0.17.32(13.38.1)
  347. Contact: <sip:18.130.241.63:5060>
  348. Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER
  349. Supported: 100rel, timer, replaces, norefersub
  350. Session-Expires: 1800;refresher=uac
  351. Require: timer
  352. Content-Type: application/sdp
  353. Content-Length:   239
  354.  
  355. v=0
  356. o=- 1013336321 1013336323 IN IP4 18.130.241.63
  357. s=Asterisk
  358. c=IN IP4 18.130.241.63
  359. t=0 0
  360. m=audio 16534 RTP/AVP 8 101
  361. a=rtpmap:8 PCMA/8000
  362. a=rtpmap:101 telephone-event/8000
  363. a=fmtp:101 0-16
  364. a=ptime:20
  365. a=maxptime:150
  366. a=sendrecv
  367.  
  368. [2021-05-24 19:30:04] VERBOSE[21506][C-00000004] file.c: <PJSIP/Dial_9_Inbound_copy_1-00000008> Playing 'vm-theperson.alaw' (language 'en')
  369. [2021-05-24 19:30:05] VERBOSE[2173] res_pjsip_logger.c: <--- Transmitting SIP response (943 bytes) to UDP:185.110.248.22:5060 --->
  370. SIP/2.0 200 OK
  371. Via: SIP/2.0/UDP 185.110.248.22:5060;rport=5060;received=185.110.248.22;branch=z9hG4bKPj16a4d0dd-a3a4-4bb7-af0d-a19b08ceda94
  372. Call-ID: 687373c7-7fde-4555-b8f4-3e646ec95148
  373. From: <sip:01226971834@185.110.248.22>;tag=dcbe9889-bc14-4c08-bd1d-65867b3271e7
  374. To: <sip:03302290443@18.134.227.38>;tag=4a03c265-2ec0-492f-b516-12dbf98a6ce9
  375. CSeq: 5690 INVITE
  376. Server: FPBX-15.0.17.32(13.38.1)
  377. Contact: <sip:18.130.241.63:5060>
  378. Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER
  379. Supported: 100rel, timer, replaces, norefersub
  380. Session-Expires: 1800;refresher=uac
  381. Require: timer
  382. Content-Type: application/sdp
  383. Content-Length:   239
  384.  
  385. v=0
  386. o=- 1013336321 1013336323 IN IP4 18.130.241.63
  387. s=Asterisk
  388. c=IN IP4 18.130.241.63
  389. t=0 0
  390. m=audio 16534 RTP/AVP 8 101
  391. a=rtpmap:8 PCMA/8000
  392. a=rtpmap:101 telephone-event/8000
  393. a=fmtp:101 0-16
  394. a=ptime:20
  395. a=maxptime:150
  396. a=sendrecv
  397.  
  398. [2021-05-24 19:30:06] VERBOSE[21506][C-00000004] file.c: <PJSIP/Dial_9_Inbound_copy_1-00000008> Playing 'digits/1.alaw' (language 'en')
  399. [2021-05-24 19:30:07] VERBOSE[21506][C-00000004] file.c: <PJSIP/Dial_9_Inbound_copy_1-00000008> Playing 'digits/0.alaw' (language 'en')
  400. [2021-05-24 19:30:07] VERBOSE[2173] res_pjsip_logger.c: <--- Transmitting SIP response (943 bytes) to UDP:185.110.248.22:5060 --->
  401. SIP/2.0 200 OK
  402. Via: SIP/2.0/UDP 185.110.248.22:5060;rport=5060;received=185.110.248.22;branch=z9hG4bKPj16a4d0dd-a3a4-4bb7-af0d-a19b08ceda94
  403. Call-ID: 687373c7-7fde-4555-b8f4-3e646ec95148
  404. From: <sip:01226971834@185.110.248.22>;tag=dcbe9889-bc14-4c08-bd1d-65867b3271e7
  405. To: <sip:03302290443@18.134.227.38>;tag=4a03c265-2ec0-492f-b516-12dbf98a6ce9
  406. CSeq: 5690 INVITE
  407. Server: FPBX-15.0.17.32(13.38.1)
  408. Contact: <sip:18.130.241.63:5060>
  409. Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER
  410. Supported: 100rel, timer, replaces, norefersub
  411. Session-Expires: 1800;refresher=uac
  412. Require: timer
  413. Content-Type: application/sdp
  414. Content-Length:   239
  415.  
  416. v=0
  417. o=- 1013336321 1013336323 IN IP4 18.130.241.63
  418. s=Asterisk
  419. c=IN IP4 18.130.241.63
  420. t=0 0
  421. m=audio 16534 RTP/AVP 8 101
  422. a=rtpmap:8 PCMA/8000
  423. a=rtpmap:101 telephone-event/8000
  424. a=fmtp:101 0-16
  425. a=ptime:20
  426. a=maxptime:150
  427. a=sendrecv
  428.  
  429. [2021-05-24 19:30:08] VERBOSE[21506][C-00000004] file.c: <PJSIP/Dial_9_Inbound_copy_1-00000008> Playing 'digits/1.alaw' (language 'en')
  430. [2021-05-24 19:30:09] VERBOSE[21506][C-00000004] file.c: <PJSIP/Dial_9_Inbound_copy_1-00000008> Playing 'vm-isunavail.alaw' (language 'en')
  431. [2021-05-24 19:30:10] VERBOSE[21506][C-00000004] file.c: <PJSIP/Dial_9_Inbound_copy_1-00000008> Playing 'vm-intro.alaw' (language 'en')
  432. [2021-05-24 19:30:11] VERBOSE[2173] res_pjsip_logger.c: <--- Transmitting SIP response (943 bytes) to UDP:185.110.248.22:5060 --->
  433. SIP/2.0 200 OK
  434. Via: SIP/2.0/UDP 185.110.248.22:5060;rport=5060;received=185.110.248.22;branch=z9hG4bKPj16a4d0dd-a3a4-4bb7-af0d-a19b08ceda94
  435. Call-ID: 687373c7-7fde-4555-b8f4-3e646ec95148
  436. From: <sip:01226971834@185.110.248.22>;tag=dcbe9889-bc14-4c08-bd1d-65867b3271e7
  437. To: <sip:03302290443@18.134.227.38>;tag=4a03c265-2ec0-492f-b516-12dbf98a6ce9
  438. CSeq: 5690 INVITE
  439. Server: FPBX-15.0.17.32(13.38.1)
  440. Contact: <sip:18.130.241.63:5060>
  441. Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER
  442. Supported: 100rel, timer, replaces, norefersub
  443. Session-Expires: 1800;refresher=uac
  444. Require: timer
  445. Content-Type: application/sdp
  446. Content-Length:   239
  447.  
  448. v=0
  449. o=- 1013336321 1013336323 IN IP4 18.130.241.63
  450. s=Asterisk
  451. c=IN IP4 18.130.241.63
  452. t=0 0
  453. m=audio 16534 RTP/AVP 8 101
  454. a=rtpmap:8 PCMA/8000
  455. a=rtpmap:101 telephone-event/8000
  456. a=fmtp:101 0-16
  457. a=ptime:20
  458. a=maxptime:150
  459. a=sendrecv
  460.  
  461. [2021-05-24 19:30:15] VERBOSE[2173] res_pjsip_logger.c: <--- Transmitting SIP response (943 bytes) to UDP:185.110.248.22:5060 --->
  462. SIP/2.0 200 OK
  463. Via: SIP/2.0/UDP 185.110.248.22:5060;rport=5060;received=185.110.248.22;branch=z9hG4bKPj16a4d0dd-a3a4-4bb7-af0d-a19b08ceda94
  464. Call-ID: 687373c7-7fde-4555-b8f4-3e646ec95148
  465. From: <sip:01226971834@185.110.248.22>;tag=dcbe9889-bc14-4c08-bd1d-65867b3271e7
  466. To: <sip:03302290443@18.134.227.38>;tag=4a03c265-2ec0-492f-b516-12dbf98a6ce9
  467. CSeq: 5690 INVITE
  468. Server: FPBX-15.0.17.32(13.38.1)
  469. Contact: <sip:18.130.241.63:5060>
  470. Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER
  471. Supported: 100rel, timer, replaces, norefersub
  472. Session-Expires: 1800;refresher=uac
  473. Require: timer
  474. Content-Type: application/sdp
  475. Content-Length:   239
  476.  
  477. v=0
  478. o=- 1013336321 1013336323 IN IP4 18.130.241.63
  479. s=Asterisk
  480. c=IN IP4 18.130.241.63
  481. t=0 0
  482. m=audio 16534 RTP/AVP 8 101
  483. a=rtpmap:8 PCMA/8000
  484. a=rtpmap:101 telephone-event/8000
  485. a=fmtp:101 0-16
  486. a=ptime:20
  487. a=maxptime:150
  488. a=sendrecv
  489.  
  490. [2021-05-24 19:30:16] VERBOSE[21506][C-00000004] file.c: <PJSIP/Dial_9_Inbound_copy_1-00000008> Playing 'beep.alaw' (language 'en')
  491. [2021-05-24 19:30:16] VERBOSE[21506][C-00000004] app_voicemail.c: Recording the message
  492. [2021-05-24 19:30:16] VERBOSE[21506][C-00000004] app.c: x=0, open writing:  /var/spool/asterisk/voicemail/default/101/tmp/Hg1F0t format: wav,0x1c54ab8
  493. [2021-05-24 19:30:19] VERBOSE[2173] res_pjsip_logger.c: <--- Transmitting SIP response (943 bytes) to UDP:185.110.248.22:5060 --->
  494. SIP/2.0 200 OK
  495. Via: SIP/2.0/UDP 185.110.248.22:5060;rport=5060;received=185.110.248.22;branch=z9hG4bKPj16a4d0dd-a3a4-4bb7-af0d-a19b08ceda94
  496. Call-ID: 687373c7-7fde-4555-b8f4-3e646ec95148
  497. From: <sip:01226971834@185.110.248.22>;tag=dcbe9889-bc14-4c08-bd1d-65867b3271e7
  498. To: <sip:03302290443@18.134.227.38>;tag=4a03c265-2ec0-492f-b516-12dbf98a6ce9
  499. CSeq: 5690 INVITE
  500. Server: FPBX-15.0.17.32(13.38.1)
  501. Contact: <sip:18.130.241.63:5060>
  502. Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER
  503. Supported: 100rel, timer, replaces, norefersub
  504. Session-Expires: 1800;refresher=uac
  505. Require: timer
  506. Content-Type: application/sdp
  507. Content-Length:   239
  508.  
  509. v=0
  510. o=- 1013336321 1013336323 IN IP4 18.130.241.63
  511. s=Asterisk
  512. c=IN IP4 18.130.241.63
  513. t=0 0
  514. m=audio 16534 RTP/AVP 8 101
  515. a=rtpmap:8 PCMA/8000
  516. a=rtpmap:101 telephone-event/8000
  517. a=fmtp:101 0-16
  518. a=ptime:20
  519. a=maxptime:150
  520. a=sendrecv
  521.  
  522. [2021-05-24 19:30:23] VERBOSE[2173] res_pjsip_logger.c: <--- Transmitting SIP response (943 bytes) to UDP:185.110.248.22:5060 --->
  523. SIP/2.0 200 OK
  524. Via: SIP/2.0/UDP 185.110.248.22:5060;rport=5060;received=185.110.248.22;branch=z9hG4bKPj16a4d0dd-a3a4-4bb7-af0d-a19b08ceda94
  525. Call-ID: 687373c7-7fde-4555-b8f4-3e646ec95148
  526. From: <sip:01226971834@185.110.248.22>;tag=dcbe9889-bc14-4c08-bd1d-65867b3271e7
  527. To: <sip:03302290443@18.134.227.38>;tag=4a03c265-2ec0-492f-b516-12dbf98a6ce9
  528. CSeq: 5690 INVITE
  529. Server: FPBX-15.0.17.32(13.38.1)
  530. Contact: <sip:18.130.241.63:5060>
  531. Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER
  532. Supported: 100rel, timer, replaces, norefersub
  533. Session-Expires: 1800;refresher=uac
  534. Require: timer
  535. Content-Type: application/sdp
  536. Content-Length:   239
  537.  
  538. v=0
  539. o=- 1013336321 1013336323 IN IP4 18.130.241.63
  540. s=Asterisk
  541. c=IN IP4 18.130.241.63
  542. t=0 0
  543. m=audio 16534 RTP/AVP 8 101
  544. a=rtpmap:8 PCMA/8000
  545. a=rtpmap:101 telephone-event/8000
  546. a=fmtp:101 0-16
  547. a=ptime:20
  548. a=maxptime:150
  549. a=sendrecv
  550.  
  551. [2021-05-24 19:30:27] VERBOSE[2173] res_pjsip_logger.c: <--- Transmitting SIP response (943 bytes) to UDP:185.110.248.22:5060 --->
  552. SIP/2.0 200 OK
  553. Via: SIP/2.0/UDP 185.110.248.22:5060;rport=5060;received=185.110.248.22;branch=z9hG4bKPj16a4d0dd-a3a4-4bb7-af0d-a19b08ceda94
  554. Call-ID: 687373c7-7fde-4555-b8f4-3e646ec95148
  555. From: <sip:01226971834@185.110.248.22>;tag=dcbe9889-bc14-4c08-bd1d-65867b3271e7
  556. To: <sip:03302290443@18.134.227.38>;tag=4a03c265-2ec0-492f-b516-12dbf98a6ce9
  557. CSeq: 5690 INVITE
  558. Server: FPBX-15.0.17.32(13.38.1)
  559. Contact: <sip:18.130.241.63:5060>
  560. Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER
  561. Supported: 100rel, timer, replaces, norefersub
  562. Session-Expires: 1800;refresher=uac
  563. Require: timer
  564. Content-Type: application/sdp
  565. Content-Length:   239
  566.  
  567. v=0
  568. o=- 1013336321 1013336323 IN IP4 18.130.241.63
  569. s=Asterisk
  570. c=IN IP4 18.130.241.63
  571. t=0 0
  572. m=audio 16534 RTP/AVP 8 101
  573. a=rtpmap:8 PCMA/8000
  574. a=rtpmap:101 telephone-event/8000
  575. a=fmtp:101 0-16
  576. a=ptime:20
  577. a=maxptime:150
  578. a=sendrecv
  579.  
  580. [2021-05-24 19:30:31] VERBOSE[2173] res_pjsip_logger.c: <--- Transmitting SIP response (943 bytes) to UDP:185.110.248.22:5060 --->
  581. SIP/2.0 200 OK
  582. Via: SIP/2.0/UDP 185.110.248.22:5060;rport=5060;received=185.110.248.22;branch=z9hG4bKPj16a4d0dd-a3a4-4bb7-af0d-a19b08ceda94
  583. Call-ID: 687373c7-7fde-4555-b8f4-3e646ec95148
  584. From: <sip:01226971834@185.110.248.22>;tag=dcbe9889-bc14-4c08-bd1d-65867b3271e7
  585. To: <sip:03302290443@18.134.227.38>;tag=4a03c265-2ec0-492f-b516-12dbf98a6ce9
  586. CSeq: 5690 INVITE
  587. Server: FPBX-15.0.17.32(13.38.1)
  588. Contact: <sip:18.130.241.63:5060>
  589. Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER
  590. Supported: 100rel, timer, replaces, norefersub
  591. Session-Expires: 1800;refresher=uac
  592. Require: timer
  593. Content-Type: application/sdp
  594. Content-Length:   239
  595.  
  596. v=0
  597. o=- 1013336321 1013336323 IN IP4 18.130.241.63
  598. s=Asterisk
  599. c=IN IP4 18.130.241.63
  600. t=0 0
  601. m=audio 16534 RTP/AVP 8 101
  602. a=rtpmap:8 PCMA/8000
  603. a=rtpmap:101 telephone-event/8000
  604. a=fmtp:101 0-16
  605. a=ptime:20
  606. a=maxptime:150
  607. a=sendrecv
  608.  
  609. [2021-05-24 19:30:35] VERBOSE[2173] res_pjsip_logger.c: <--- Transmitting SIP response (943 bytes) to UDP:185.110.248.22:5060 --->
  610. SIP/2.0 200 OK
  611. Via: SIP/2.0/UDP 185.110.248.22:5060;rport=5060;received=185.110.248.22;branch=z9hG4bKPj16a4d0dd-a3a4-4bb7-af0d-a19b08ceda94
  612. Call-ID: 687373c7-7fde-4555-b8f4-3e646ec95148
  613. From: <sip:01226971834@185.110.248.22>;tag=dcbe9889-bc14-4c08-bd1d-65867b3271e7
  614. To: <sip:03302290443@18.134.227.38>;tag=4a03c265-2ec0-492f-b516-12dbf98a6ce9
  615. CSeq: 5690 INVITE
  616. Server: FPBX-15.0.17.32(13.38.1)
  617. Contact: <sip:18.130.241.63:5060>
  618. Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER
  619. Supported: 100rel, timer, replaces, norefersub
  620. Session-Expires: 1800;refresher=uac
  621. Require: timer
  622. Content-Type: application/sdp
  623. Content-Length:   239
  624.  
  625. v=0
  626. o=- 1013336321 1013336323 IN IP4 18.130.241.63
  627. s=Asterisk
  628. c=IN IP4 18.130.241.63
  629. t=0 0
  630. m=audio 16534 RTP/AVP 8 101
  631. a=rtpmap:8 PCMA/8000
  632. a=rtpmap:101 telephone-event/8000
  633. a=fmtp:101 0-16
  634. a=ptime:20
  635. a=maxptime:150
  636. a=sendrecv
  637.  
  638. [2021-05-24 19:30:36] VERBOSE[2173] res_pjsip_logger.c: <--- Transmitting SIP request (442 bytes) to UDP:185.110.248.22:5060 --->
  639. BYE sip:asterisk@185.110.248.22:5060 SIP/2.0
  640. Via: SIP/2.0/UDP 18.130.241.63:5060;rport;branch=z9hG4bKPjabb3c284-7cc2-4a19-b28d-7c07f827f8c7
  641. From: <sip:03302290443@18.134.227.38>;tag=4a03c265-2ec0-492f-b516-12dbf98a6ce9
  642. To: <sip:01226971834@185.110.248.22>;tag=dcbe9889-bc14-4c08-bd1d-65867b3271e7
  643. Call-ID: 687373c7-7fde-4555-b8f4-3e646ec95148
  644. CSeq: 1937 BYE
  645. Max-Forwards: 70
  646. User-Agent: FPBX-15.0.17.32(13.38.1)
  647. Content-Length:  0
  648.  
  649.  
  650. [2021-05-24 19:30:36] VERBOSE[2173] res_pjsip_logger.c: <--- Received SIP response (414 bytes) from UDP:185.110.248.22:5060 --->
  651. SIP/2.0 200 OK
  652. Via: SIP/2.0/UDP 18.130.241.63:5060;rport=5060;received=18.134.227.38;branch=z9hG4bKPjabb3c284-7cc2-4a19-b28d-7c07f827f8c7
  653. Call-ID: 687373c7-7fde-4555-b8f4-3e646ec95148
  654. From: <sip:03302290443@18.134.227.38>;tag=4a03c265-2ec0-492f-b516-12dbf98a6ce9
  655. To: <sip:01226971834@185.110.248.22>;tag=dcbe9889-bc14-4c08-bd1d-65867b3271e7
  656. CSeq: 1937 BYE
  657. Server: Asterisk PBX 13.33.0
  658. Content-Length:  0
  659.  
  660.  
  661. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] app.c: User hung up
  662. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] app_voicemail.c: Recording was 0 seconds long but needs to be at least 5 - abandoning
  663. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] app_macro.c: Spawn extension (macro-vm, s-CHANUNAVAIL, 2) exited non-zero on 'PJSIP/Dial_9_Inbound_copy_1-00000008' in macro 'vm'
  664. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] app_macro.c: Spawn extension (macro-exten-vm, s, 33) exited non-zero on 'PJSIP/Dial_9_Inbound_copy_1-00000008' in macro 'exten-vm'
  665. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx.c: Spawn extension (ext-local, 101, 3) exited non-zero on 'PJSIP/Dial_9_Inbound_copy_1-00000008'
  666. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx.c: Executing [h@ext-local:1] Macro("PJSIP/Dial_9_Inbound_copy_1-00000008", "hangupcall,") in new stack
  667. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?theend") in new stack
  668. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  669. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?Set(CDR(recordingfile)=)") in new stack
  670. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-hangupcall:4] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", " montior file= ") in new stack
  671. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?skipagi") in new stack
  672. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx_builtins.c: Goto (macro-hangupcall,s,7)
  673. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx.c: Executing [s@macro-hangupcall:7] Hangup("PJSIP/Dial_9_Inbound_copy_1-00000008", "") in new stack
  674. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'PJSIP/Dial_9_Inbound_copy_1-00000008' in macro 'hangupcall'
  675. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'PJSIP/Dial_9_Inbound_copy_1-00000008'
  676. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] app_stack.c: PJSIP/Dial_9_Inbound_copy_1-00000008 Internal Gosub(crm-hangup,s,1) start
  677. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "Sending Hangup to CRM") in new stack
  678. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "HANGUP CAUSE: 18") in new stack
  679. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "1?Set(__CRM_VOICEMAIL=FAILED)") in new stack
  680. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/Dial_9_Inbound_copy_1-00000008", "MASTER CHANNEL: 1621881003.8 = 1621881003.8") in new stack
  681. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/Dial_9_Inbound_copy_1-00000008", "0?return")in new stack
  682. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx.c: Executing [s@crm-hangup:6] Set("PJSIP/Dial_9_Inbound_copy_1-00000008", "__CRM_HANGUP=1") in new stack
  683. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx.c: Executing [s@crm-hangup:7] AGI("PJSIP/Dial_9_Inbound_copy_1-00000008", "sangomacrm.agi") in new stack
  684. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  685. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] res_agi.c: <PJSIP/Dial_9_Inbound_copy_1-00000008>AGI Script sangomacrm.agi completed, returning 0
  686. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/Dial_9_Inbound_copy_1-00000008", "") in new stack
  687. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] app_stack.c: Spawn extension (ext-local, h, 1) exited non-zero on 'PJSIP/Dial_9_Inbound_copy_1-00000008'
  688. [2021-05-24 19:30:36] VERBOSE[21506][C-00000004] app_stack.c: PJSIP/Dial_9_Inbound_copy_1-00000008 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  689.  

Reply to "Audio Sip Trace"

Here you can reply to the paste above