debug2

From danny, 11 Months ago, written in Plain Text, viewed 3 times.
URL https://pastebin.freepbx.org/view/2fd8b590 Embed
Download Paste or View Raw
  1. <--- SIP read from UDP:192.168.88.250:44352 --->
  2. INVITE sip:888@192.168.88.234:5160;transport=UDP SIP/2.0
  3. Via: SIP/2.0/UDP 192.168.88.250:44352;branch=z9hG4bK-524287-1---76358adf11209448;rport
  4. Max-Forwards: 70
  5. Contact: <sip:789@192.168.88.250:44352;transport=UDP>
  6. To: <sip:888@192.168.88.234:5160;transport=UDP>
  7. From: <sip:789@192.168.88.234:5160;transport=UDP>;tag=a607a542
  8. Call-ID: g_VyhNMB3iX0YKRn1vLWPA..
  9. CSeq: 1 INVITE
  10. Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  11. Content-Type: application/sdp
  12. User-Agent: Z 5.2.16 rv2.8.95
  13. Allow-Events: presence, kpml, talk
  14. Content-Length: 604
  15.  
  16. v=0
  17. o=Z 0 0 IN IP4 192.168.88.250
  18. s=Z
  19. c=IN IP4 192.168.88.250
  20. t=0 0
  21. m=audio 8000 RTP/AVP 106 9 3 111 0 8 97 110 112 98 101 100 99 102
  22. a=rtpmap:106 opus/48000/2
  23. a=fmtp:106 minptime=20; cbr=1; maxaveragebitrate=40000; useinbandfec=1
  24. a=rtpmap:111 speex/16000
  25. a=rtpmap:97 iLBC/8000
  26. a=fmtp:97 mode=20
  27. a=rtpmap:110 speex/8000
  28. a=rtpmap:112 speex/32000
  29. a=rtpmap:98 telephone-event/48000
  30. a=fmtp:98 0-16
  31. a=rtpmap:101 telephone-event/8000
  32. a=fmtp:101 0-16
  33. a=rtpmap:100 telephone-event/16000
  34. a=fmtp:100 0-16
  35. a=rtpmap:99 telephone-event/32000
  36. a=fmtp:99 0-16
  37. a=rtpmap:102 G726-32/8000
  38. a=sendrecv
  39. <------------->
  40. --- (13 headers 23 lines) ---
  41. Sending to 192.168.88.250:44352 (NAT)
  42. Sending to 192.168.88.250:44352 (NAT)
  43. Using INVITE request as basis request - g_VyhNMB3iX0YKRn1vLWPA..
  44. Found peer '789' for '789' from 192.168.88.250:44352
  45.  
  46. <--- Reliably Transmitting (no NAT) to 192.168.88.250:44352 --->
  47. SIP/2.0 401 Unauthorized
  48. Via: SIP/2.0/UDP 192.168.88.250:44352;branch=z9hG4bK-524287-1---76358adf11209448;received=192.168.88.250;rport=44352
  49. From: <sip:789@192.168.88.234:5160;transport=UDP>;tag=a607a542
  50. To: <sip:888@192.168.88.234:5160;transport=UDP>;tag=as42ca0704
  51. Call-ID: g_VyhNMB3iX0YKRn1vLWPA..
  52. CSeq: 1 INVITE
  53. Server: FPBX-14.0.2.10(13.20.0)
  54. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  55. Supported: replaces, timer
  56. WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="7890251b"
  57. Content-Length: 0
  58.  
  59.  
  60. <------------>
  61. Scheduling destruction of SIP dialog 'g_VyhNMB3iX0YKRn1vLWPA..' in 6400 ms (Method: INVITE)
  62.  
  63. <--- SIP read from UDP:192.168.88.250:44352 --->
  64. ACK sip:888@192.168.88.234:5160;transport=UDP SIP/2.0
  65. Via: SIP/2.0/UDP 192.168.88.250:44352;branch=z9hG4bK-524287-1---76358adf11209448;rport
  66. Max-Forwards: 70
  67. To: <sip:888@192.168.88.234:5160;transport=UDP>;tag=as42ca0704
  68. From: <sip:789@192.168.88.234:5160;transport=UDP>;tag=a607a542
  69. Call-ID: g_VyhNMB3iX0YKRn1vLWPA..
  70. CSeq: 1 ACK
  71. Content-Length: 0
  72.  
  73. <------------->
  74. --- (8 headers 0 lines) ---
  75.  
  76. <--- SIP read from UDP:192.168.88.250:44352 --->
  77. INVITE sip:888@192.168.88.234:5160;transport=UDP SIP/2.0
  78. Via: SIP/2.0/UDP 192.168.88.250:44352;branch=z9hG4bK-524287-1---596b3aa8e4d4c6a1;rport
  79. Max-Forwards: 70
  80. Contact: <sip:789@192.168.88.250:44352;transport=UDP>
  81. To: <sip:888@192.168.88.234:5160;transport=UDP>
  82. From: <sip:789@192.168.88.234:5160;transport=UDP>;tag=a607a542
  83. Call-ID: g_VyhNMB3iX0YKRn1vLWPA..
  84. CSeq: 2 INVITE
  85. Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  86. Content-Type: application/sdp
  87. User-Agent: Z 5.2.16 rv2.8.95
  88. Authorization: Digest username="789",realm="asterisk",nonce="7890251b",uri="sip:888@192.168.88.234:5160;transport=UDP",response="877c406308501bd60e1d38fc5cccf3d7",algorithm=MD5
  89. Allow-Events: presence, kpml, talk
  90. Content-Length: 604
  91.  
  92. v=0
  93. o=Z 0 0 IN IP4 192.168.88.250
  94. s=Z
  95. c=IN IP4 192.168.88.250
  96. t=0 0
  97. m=audio 8000 RTP/AVP 106 9 3 111 0 8 97 110 112 98 101 100 99 102
  98. a=rtpmap:106 opus/48000/2
  99. a=fmtp:106 minptime=20; cbr=1; maxaveragebitrate=40000; useinbandfec=1
  100. a=rtpmap:111 speex/16000
  101. a=rtpmap:97 iLBC/8000
  102. a=fmtp:97 mode=20
  103. a=rtpmap:110 speex/8000
  104. a=rtpmap:112 speex/32000
  105. a=rtpmap:98 telephone-event/48000
  106. a=fmtp:98 0-16
  107. a=rtpmap:101 telephone-event/8000
  108. a=fmtp:101 0-16
  109. a=rtpmap:100 telephone-event/16000
  110. a=fmtp:100 0-16
  111. a=rtpmap:99 telephone-event/32000
  112. a=fmtp:99 0-16
  113. a=rtpmap:102 G726-32/8000
  114. a=sendrecv
  115. <------------->
  116. --- (14 headers 23 lines) ---
  117. Sending to 192.168.88.250:44352 (no NAT)
  118. Using INVITE request as basis request - g_VyhNMB3iX0YKRn1vLWPA..
  119. Found peer '789' for '789' from 192.168.88.250:44352
  120.   == Using SIP RTP TOS bits 184
  121.   == Using SIP RTP CoS mark 5
  122. Found RTP audio format 106
  123. Found RTP audio format 9
  124. Found RTP audio format 3
  125. Found RTP audio format 111
  126. Found RTP audio format 0
  127. Found RTP audio format 8
  128. Found RTP audio format 97
  129. Found RTP audio format 110
  130. Found RTP audio format 112
  131. Found RTP audio format 98
  132. Found RTP audio format 101
  133. Found RTP audio format 100
  134. Found RTP audio format 99
  135. Found RTP audio format 102
  136. Found audio description format opus for ID 106
  137. Found audio description format speex for ID 111
  138. Found audio description format iLBC for ID 97
  139. Found audio description format speex for ID 110
  140. Found audio description format speex for ID 112
  141. Found unknown media description format telephone-event for ID 98
  142. Found audio description format telephone-event for ID 101
  143. Found unknown media description format telephone-event for ID 100
  144. Found unknown media description format telephone-event for ID 99
  145. Found audio description format G726-32 for ID 102
  146. Capabilities: us - (ulaw|alaw|gsm|g726|g722), peer - audio=(ulaw|gsm|alaw|g722|ilbc|g726|opus|speex|speex16|speex32)/video=(nothing)/text=(nothing), combined - (ulaw|alaw|gsm|g726|g722)
  147. Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
  148.        > 0x73e125b0 -- Strict RTP learning after remote address set to: 192.168.88.250:8000
  149. Peer audio RTP is at port 192.168.88.250:8000
  150. Looking for 888 in from-internal (domain 192.168.88.234)
  151. sip_route_dump: route/path hop: <sip:789@192.168.88.250:44352;transport=UDP>
  152.  
  153. <--- Transmitting (no NAT) to 192.168.88.250:44352 --->
  154. SIP/2.0 100 Trying
  155. Via: SIP/2.0/UDP 192.168.88.250:44352;branch=z9hG4bK-524287-1---596b3aa8e4d4c6a1;received=192.168.88.250;rport=44352
  156. From: <sip:789@192.168.88.234:5160;transport=UDP>;tag=a607a542
  157. To: <sip:888@192.168.88.234:5160;transport=UDP>
  158. Call-ID: g_VyhNMB3iX0YKRn1vLWPA..
  159. CSeq: 2 INVITE
  160. Server: FPBX-14.0.2.10(13.20.0)
  161. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  162. Supported: replaces, timer
  163. Contact: <sip:888@192.168.88.234:5160>
  164. Content-Length: 0
  165.  
  166.  
  167. <------------>
  168.     -- Executing [888@from-internal:1] Macro("SIP/789-0000000d", "user-callerid,LIMIT,EXTERNAL,") in new stack
  169.     -- Executing [s@macro-user-callerid:1] Set("SIP/789-0000000d", "TOUCH_MONITOR=1529535133.13") in new stack
  170.     -- Executing [s@macro-user-callerid:2] Set("SIP/789-0000000d", "AMPUSER=789") in new stack
  171.     -- Executing [s@macro-user-callerid:3] GotoIf("SIP/789-0000000d", "0?report") in new stack
  172.     -- Executing [s@macro-user-callerid:4] ExecIf("SIP/789-0000000d", "1?Set(REALCALLERIDNUM=789)") in new stack
  173.     -- Executing [s@macro-user-callerid:5] Set("SIP/789-0000000d", "AMPUSER=789") in new stack
  174.     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/789-0000000d", "0?limit") in new stack
  175.     -- Executing [s@macro-user-callerid:7] Set("SIP/789-0000000d", "AMPUSERCIDNAME=789") in new stack
  176.     -- Executing [s@macro-user-callerid:8] ExecIf("SIP/789-0000000d", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  177.     -- Executing [s@macro-user-callerid:9] GotoIf("SIP/789-0000000d", "0?report") in new stack
  178.     -- Executing [s@macro-user-callerid:10] Set("SIP/789-0000000d", "AMPUSERCID=789") in new stack
  179.     -- Executing [s@macro-user-callerid:11] Set("SIP/789-0000000d", "__DIAL_OPTIONS=Ttr") in new stack
  180.     -- Executing [s@macro-user-callerid:12] Set("SIP/789-0000000d", "CALLERID(all)="789" <789>") in new stack
  181.     -- Executing [s@macro-user-callerid:13] GotoIf("SIP/789-0000000d", "0?limit") in new stack
  182.     -- Executing [s@macro-user-callerid:14] ExecIf("SIP/789-0000000d", "1?Set(GROUP(concurrency_limit)=789)") in new stack
  183.     -- Executing [s@macro-user-callerid:15] NoOp("SIP/789-0000000d", "Macro Depth is 1") in new stack
  184.     -- Executing [s@macro-user-callerid:16] GotoIf("SIP/789-0000000d", "1?report2:macroerror") in new stack
  185.     -- Goto (macro-user-callerid,s,17)
  186.     -- Executing [s@macro-user-callerid:17] GotoIf("SIP/789-0000000d", "1?continue") in new stack
  187.     -- Goto (macro-user-callerid,s,35)
  188.     -- Executing [s@macro-user-callerid:35] Set("SIP/789-0000000d", "CALLERID(number)=789") in new stack
  189.     -- Executing [s@macro-user-callerid:36] Set("SIP/789-0000000d", "CALLERID(name)=789") in new stack
  190.     -- Executing [s@macro-user-callerid:37] GotoIf("SIP/789-0000000d", "0?cnum") in new stack
  191.     -- Executing [s@macro-user-callerid:38] Set("SIP/789-0000000d", "CDR(cnam)=789") in new stack
  192.     -- Executing [s@macro-user-callerid:39] Set("SIP/789-0000000d", "CDR(cnum)=789") in new stack
  193.     -- Executing [s@macro-user-callerid:40] Set("SIP/789-0000000d", "CHANNEL(language)=en") in new stack
  194.     -- Executing [888@from-internal:2] Gosub("SIP/789-0000000d", "sub-record-check,s,1(out,888,dontcare)") in new stack
  195.     -- Executing [s@sub-record-check:1] GotoIf("SIP/789-0000000d", "0?initialized") in new stack
  196.     -- Executing [s@sub-record-check:2] Set("SIP/789-0000000d", "__REC_STATUS=INITIALIZED") in new stack
  197.     -- Executing [s@sub-record-check:3] Set("SIP/789-0000000d", "NOW=1529535133") in new stack
  198.     -- Executing [s@sub-record-check:4] Set("SIP/789-0000000d", "__DAY=20") in new stack
  199.     -- Executing [s@sub-record-check:5] Set("SIP/789-0000000d", "__MONTH=06") in new stack
  200.     -- Executing [s@sub-record-check:6] Set("SIP/789-0000000d", "__YEAR=2018") in new stack
  201.     -- Executing [s@sub-record-check:7] Set("SIP/789-0000000d", "__TIMESTR=20180620-225213") in new stack
  202.     -- Executing [s@sub-record-check:8] Set("SIP/789-0000000d", "__FROMEXTEN=789") in new stack
  203.     -- Executing [s@sub-record-check:9] Set("SIP/789-0000000d", "__MON_FMT=wav") in new stack
  204.     -- Executing [s@sub-record-check:10] NoOp("SIP/789-0000000d", "Recordings initialized") in new stack
  205.     -- Executing [s@sub-record-check:11] ExecIf("SIP/789-0000000d", "0?Set(ARG3=dontcare)") in new stack
  206.     -- Executing [s@sub-record-check:12] Set("SIP/789-0000000d", "REC_POLICY_MODE_SAVE=") in new stack
  207.     -- Executing [s@sub-record-check:13] ExecIf("SIP/789-0000000d", "0?Set(REC_STATUS=NO)") in new stack
  208.     -- Executing [s@sub-record-check:14] GotoIf("SIP/789-0000000d", "3?checkaction") in new stack
  209.     -- Goto (sub-record-check,s,17)
  210.     -- Executing [s@sub-record-check:17] GotoIf("SIP/789-0000000d", "1?sub-record-check,out,1") in new stack
  211.     -- Goto (sub-record-check,out,1)
  212.     -- Executing [out@sub-record-check:1] NoOp("SIP/789-0000000d", "Outbound Recording Check from 789 to 888") in new stack
  213.     -- Executing [out@sub-record-check:2] Set("SIP/789-0000000d", "RECMODE=dontcare") in new stack
  214.     -- Executing [out@sub-record-check:3] ExecIf("SIP/789-0000000d", "1?Goto(routewins)") in new stack
  215.     -- Goto (sub-record-check,out,7)
  216.     -- Executing [out@sub-record-check:7] Gosub("SIP/789-0000000d", "recordcheck,1(dontcare,out,888)") in new stack
  217.     -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/789-0000000d", "Starting recording check against dontcare") in new stack
  218.     -- Executing [recordcheck@sub-record-check:2] Goto("SIP/789-0000000d", "dontcare") in new stack
  219.     -- Goto (sub-record-check,recordcheck,3)
  220.     -- Executing [recordcheck@sub-record-check:3] Return("SIP/789-0000000d", "") in new stack
  221.     -- Executing [out@sub-record-check:8] Return("SIP/789-0000000d", "") in new stack
  222.     -- Executing [888@from-internal:3] Set("SIP/789-0000000d", "MOHCLASS=default") in new stack
  223.     -- Executing [888@from-internal:4] ExecIf("SIP/789-0000000d", "0?Set(TRUNKCIDOVERRIDE=admin)") in new stack
  224.     -- Executing [888@from-internal:5] Set("SIP/789-0000000d", "_NODEST=") in new stack
  225.     -- Executing [888@from-internal:6] Macro("SIP/789-0000000d", "dialout-trunk,1,888,,off") in new stack
  226.     -- Executing [s@macro-dialout-trunk:1] Set("SIP/789-0000000d", "DIAL_TRUNK=1") in new stack
  227.     -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/789-0000000d", "0?sub-pincheck,s,1()") in new stack
  228.     -- Executing [s@macro-dialout-trunk:3] ExecIf("SIP/789-0000000d", "0?Set(CALLERID(num)=789)") in new stack
  229.     -- Executing [s@macro-dialout-trunk:4] GotoIf("SIP/789-0000000d", "0?disabletrunk,1") in new stack
  230.     -- Executing [s@macro-dialout-trunk:5] Set("SIP/789-0000000d", "DIAL_NUMBER=888") in new stack
  231.     -- Executing [s@macro-dialout-trunk:6] Set("SIP/789-0000000d", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
  232.     -- Executing [s@macro-dialout-trunk:7] Set("SIP/789-0000000d", "OUTBOUND_GROUP=OUT_1") in new stack
  233.     -- Executing [s@macro-dialout-trunk:8] Set("SIP/789-0000000d", "DIAL_TRUNK_OPTIONS=T") in new stack
  234.     -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/789-0000000d", "1?nomax") in new stack
  235.     -- Goto (macro-dialout-trunk,s,11)
  236.     -- Executing [s@macro-dialout-trunk:11] GotoIf("SIP/789-0000000d", "0?skipoutcid") in new stack
  237.     -- Executing [s@macro-dialout-trunk:12] Macro("SIP/789-0000000d", "outbound-callerid,1") in new stack
  238.     -- Executing [s@macro-outbound-callerid:1] NoOp("SIP/789-0000000d", "789") in new stack
  239.     -- Executing [s@macro-outbound-callerid:2] NoOp("SIP/789-0000000d", "") in new stack
  240.     -- Executing [s@macro-outbound-callerid:3] NoOp("SIP/789-0000000d", "off") in new stack
  241.     -- Executing [s@macro-outbound-callerid:4] ExecIf("SIP/789-0000000d", "0?Set(CALLERPRES(name-pres)=)") in new stack
  242.     -- Executing [s@macro-outbound-callerid:5] ExecIf("SIP/789-0000000d", "0?Set(CALLERPRES(num-pres)=)") in new stack
  243.     -- Executing [s@macro-outbound-callerid:6] ExecIf("SIP/789-0000000d", "0?Set(REALCALLERIDNUM=789)") in new stack
  244.     -- Executing [s@macro-outbound-callerid:7] GotoIf("SIP/789-0000000d", "1?normcid") in new stack
  245.     -- Goto (macro-outbound-callerid,s,11)
  246.     -- Executing [s@macro-outbound-callerid:11] Set("SIP/789-0000000d", "USEROUTCID=789") in new stack
  247.     -- Executing [s@macro-outbound-callerid:12] Set("SIP/789-0000000d", "EMERGENCYCID=") in new stack
  248.     -- Executing [s@macro-outbound-callerid:13] Set("SIP/789-0000000d", "TRUNKOUTCID=123") in new stack
  249.     -- Executing [s@macro-outbound-callerid:14] GotoIf("SIP/789-0000000d", "1?trunkcid") in new stack
  250.     -- Goto (macro-outbound-callerid,s,19)
  251.     -- Executing [s@macro-outbound-callerid:19] ExecIf("SIP/789-0000000d", "1?Set(CALLERID(all)=123)") in new stack
  252.     -- Executing [s@macro-outbound-callerid:20] ExecIf("SIP/789-0000000d", "1?Set(CALLERID(all)=789)") in new stack
  253.     -- Executing [s@macro-outbound-callerid:21] ExecIf("SIP/789-0000000d", "0?Set(CALLERID(all)=)") in new stack
  254.     -- Executing [s@macro-outbound-callerid:22] ExecIf("SIP/789-0000000d", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
  255.     -- Executing [s@macro-outbound-callerid:23] ExecIf("SIP/789-0000000d", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
  256.     -- Executing [s@macro-outbound-callerid:24] Set("SIP/789-0000000d", "CDR(outbound_cnum)=789") in new stack
  257.     -- Executing [s@macro-outbound-callerid:25] Set("SIP/789-0000000d", "CDR(outbound_cnam)=") in new stack
  258.     -- Executing [s@macro-dialout-trunk:13] GosubIf("SIP/789-0000000d", "0?sub-flp-1,s,1()") in new stack
  259.     -- Executing [s@macro-dialout-trunk:14] Set("SIP/789-0000000d", "OUTNUM=888") in new stack
  260.     -- Executing [s@macro-dialout-trunk:15] Set("SIP/789-0000000d", "custom=SIP/twilio") in new stack
  261.     -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/789-0000000d", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
  262.     -- Executing [s@macro-dialout-trunk:17] ExecIf("SIP/789-0000000d", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
  263.     -- Executing [s@macro-dialout-trunk:18] Macro("SIP/789-0000000d", "dialout-trunk-predial-hook,") in new stack
  264.     -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/789-0000000d", "") in new stack
  265.     -- Executing [s@macro-dialout-trunk:19] GotoIf("SIP/789-0000000d", "0?bypass,1") in new stack
  266.     -- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/789-0000000d", "1?Set(CONNECTEDLINE(num,i)=888)") in new stack
  267.     -- Executing [s@macro-dialout-trunk:21] ExecIf("SIP/789-0000000d", "1?Set(CONNECTEDLINE(name,i)=CID:789)") in new stack
  268.     -- Executing [s@macro-dialout-trunk:22] ExecIf("SIP/789-0000000d", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)789)") in new stack
  269.     -- Executing [s@macro-dialout-trunk:23] GotoIf("SIP/789-0000000d", "0?customtrunk") in new stack
  270.     -- Executing [s@macro-dialout-trunk:24] Dial("SIP/789-0000000d", "SIP/twilio/888,300,Tb(func-apply-sipheaders^s^1)") in new stack
  271.   == Using SIP RTP TOS bits 184
  272.   == Using SIP RTP CoS mark 5
  273.     -- SIP/twilio-0000000e Internal Gosub(func-apply-sipheaders,s,1) start
  274.     -- Executing [s@func-apply-sipheaders:1] NoOp("SIP/twilio-0000000e", "Applying SIP Headers to channel") in new stack
  275.     -- Executing [s@func-apply-sipheaders:2] Set("SIP/twilio-0000000e", "SIPHEADERKEYS=") in new stack
  276.     -- Executing [s@func-apply-sipheaders:3] While("SIP/twilio-0000000e", "0") in new stack
  277.     -- Jumping to priority 7
  278.     -- Executing [s@func-apply-sipheaders:8] Return("SIP/twilio-0000000e", "") in new stack
  279.   == Spawn extension (from-trunk-sip-twilio, 888, 1) exited non-zero on 'SIP/twilio-0000000e'
  280.     -- SIP/twilio-0000000e Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  281. Audio is at 12996
  282. Adding codec ulaw to SDP
  283. Adding codec alaw to SDP
  284. Adding codec gsm to SDP
  285. Adding codec g726 to SDP
  286. Adding codec g722 to SDP
  287. Adding non-codec 0x1 (telephone-event) to SDP
  288. Reliably Transmitting (NAT) to 54.172.60.1:5060:
  289. INVITE sip:888@87654321.sip.us1.twilio.com SIP/2.0
  290. Via: SIP/2.0/UDP 192.168.88.234:5160;branch=z9hG4bK72c45bda;rport
  291. Max-Forwards: 70
  292. From: <sip:789@192.168.88.234:5160>;tag=as6bfcd003
  293. To: <sip:888@87654321.sip.us1.twilio.com>
  294. Contact: <sip:789@192.168.88.234:5160>
  295. Call-ID: 4411fe4161d3f0067d40b46f28d4c2f0@192.168.88.234:5160
  296. CSeq: 102 INVITE
  297. User-Agent: FPBX-14.0.2.10(13.20.0)
  298. Date: Wed, 20 Jun 2018 22:52:13 GMT
  299. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  300. Supported: replaces, timer
  301. Content-Type: application/sdp
  302. Content-Length: 356
  303.  
  304. v=0
  305. o=root 506940171 506940171 IN IP4 192.168.88.234
  306. s=Asterisk PBX 13.20.0
  307. c=IN IP4 192.168.88.234
  308. t=0 0
  309. m=audio 12996 RTP/AVP 0 8 3 111 9 101
  310. a=rtpmap:0 PCMU/8000
  311. a=rtpmap:8 PCMA/8000
  312. a=rtpmap:3 GSM/8000
  313. a=rtpmap:111 G726-32/8000
  314. a=rtpmap:9 G722/8000
  315. a=rtpmap:101 telephone-event/8000
  316. a=fmtp:101 0-16
  317. a=ptime:20
  318. a=maxptime:150
  319. a=sendrecv
  320.  
  321. ---
  322.     -- Called SIP/twilio/888
  323.  
  324. <--- SIP read from UDP:54.172.60.1:5060 --->
  325. SIP/2.0 100 Giving a try
  326. Via: SIP/2.0/UDP 192.168.88.234:5160;received=192.168.88.234;branch=z9hG4bK72c45bda;rport=5160
  327. From: <sip:789@192.168.88.234:5160>;tag=as6bfcd003
  328. To: <sip:888@87654321.sip.us1.twilio.com>
  329. Call-ID: 4411fe4161d3f0067d40b46f28d4c2f0@192.168.88.234:5160
  330. CSeq: 102 INVITE
  331. Server: Twilio Gateway
  332. Content-Length: 0
  333.  
  334. <------------->
  335. --- (8 headers 0 lines) ---
  336.  
  337. <--- SIP read from UDP:54.172.60.1:5060 --->
  338. SIP/2.0 407 Proxy Authentication required
  339. CSeq: 102 INVITE
  340. Call-ID: 4411fe4161d3f0067d40b46f28d4c2f0@192.168.88.234:5160
  341. From: <sip:789@192.168.88.234:5160>;tag=as6bfcd003
  342. To: <sip:888@87654321.sip.us1.twilio.com>;tag=20926574_6772d868_55bef462-7715-4ed5-9f84-39e27fca880c
  343. Via: SIP/2.0/UDP 192.168.88.234:5160;received=192.168.88.234;branch=z9hG4bK72c45bda;rport=5160
  344. Server: Twilio
  345. Contact: <sip:172.18.10.70:5060>
  346. Proxy-Authenticate: Digest realm="sip.twilio.com",qop="auth",nonce="wluBMJVZaW37t3_ppfH-sNXztmBjOxQzr4im-5HA5lwGYiA-",opaque="de9a186562d2d8ca72387ec6c1b8015e"
  347. Content-Length: 0
  348.  
  349. <------------->
  350. --- (10 headers 0 lines) ---
  351. Transmitting (NAT) to 54.172.60.1:5060:
  352. ACK sip:888@87654321.sip.us1.twilio.com SIP/2.0
  353. Via: SIP/2.0/UDP 192.168.88.234:5160;branch=z9hG4bK72c45bda;rport
  354. Max-Forwards: 70
  355. From: <sip:789@192.168.88.234:5160>;tag=as6bfcd003
  356. To: <sip:888@87654321.sip.us1.twilio.com>;tag=20926574_6772d868_55bef462-7715-4ed5-9f84-39e27fca880c
  357. Contact: <sip:789@192.168.88.234:5160>
  358. Call-ID: 4411fe4161d3f0067d40b46f28d4c2f0@192.168.88.234:5160
  359. CSeq: 102 ACK
  360. User-Agent: FPBX-14.0.2.10(13.20.0)
  361. Content-Length: 0
  362.  
  363.  
  364. ---
  365. Audio is at 12996
  366. Adding codec ulaw to SDP
  367. Adding codec alaw to SDP
  368. Adding codec gsm to SDP
  369. Adding codec g726 to SDP
  370. Adding codec g722 to SDP
  371. Adding non-codec 0x1 (telephone-event) to SDP
  372. Reliably Transmitting (NAT) to 54.172.60.1:5060:
  373. INVITE sip:888@87654321.sip.us1.twilio.com SIP/2.0
  374. Via: SIP/2.0/UDP 192.168.88.234:5160;branch=z9hG4bK5b960f48;rport
  375. Max-Forwards: 70
  376. From: <sip:789@192.168.88.234:5160>;tag=as6bfcd003
  377. To: <sip:888@87654321.sip.us1.twilio.com>
  378. Contact: <sip:789@192.168.88.234:5160>
  379. Call-ID: 4411fe4161d3f0067d40b46f28d4c2f0@192.168.88.234:5160
  380. CSeq: 103 INVITE
  381. User-Agent: FPBX-14.0.2.10(13.20.0)
  382. Proxy-Authorization: Digest username="osmosis311", realm="sip.twilio.com", algorithm=MD5, uri="sip:888@87654321.sip.us1.twilio.com", nonce="wluBMJVZaW37t3_ppfH-sNXztmBjOxQzr4im-5HA5lwGYiA-", response="bd46089684c244c17f81d901d109c690", opaque="de9a186562d2d8ca72387ec6c1b8015e", qop=auth, cnonce="01b23387", nc=00000001
  383. Date: Wed, 20 Jun 2018 22:52:13 GMT
  384. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  385. Supported: replaces, timer
  386. Content-Type: application/sdp
  387. Content-Length: 356
  388.  
  389. v=0
  390. o=root 506940171 506940172 IN IP4 192.168.88.234
  391. s=Asterisk PBX 13.20.0
  392. c=IN IP4 192.168.88.234
  393. t=0 0
  394. m=audio 12996 RTP/AVP 0 8 3 111 9 101
  395. a=rtpmap:0 PCMU/8000
  396. a=rtpmap:8 PCMA/8000
  397. a=rtpmap:3 GSM/8000
  398. a=rtpmap:111 G726-32/8000
  399. a=rtpmap:9 G722/8000
  400. a=rtpmap:101 telephone-event/8000
  401. a=fmtp:101 0-16
  402. a=ptime:20
  403. a=maxptime:150
  404. a=sendrecv
  405.  
  406. ---
  407.  
  408. <--- SIP read from UDP:54.172.60.1:5060 --->
  409. SIP/2.0 100 Giving a try
  410. Via: SIP/2.0/UDP 192.168.88.234:5160;received=192.168.88.234;branch=z9hG4bK5b960f48;rport=5160
  411. From: <sip:789@192.168.88.234:5160>;tag=as6bfcd003
  412. To: <sip:888@87654321.sip.us1.twilio.com>
  413. Call-ID: 4411fe4161d3f0067d40b46f28d4c2f0@192.168.88.234:5160
  414. CSeq: 103 INVITE
  415. Server: Twilio Gateway
  416. Content-Length: 0
  417.  
  418. <------------->
  419. --- (8 headers 0 lines) ---
  420.  
  421. <--- SIP read from UDP:54.172.60.1:5060 --->
  422. SIP/2.0 403 Forbidden
  423. CSeq: 103 INVITE
  424. Call-ID: 4411fe4161d3f0067d40b46f28d4c2f0@192.168.88.234:5160
  425. From: <sip:789@192.168.88.234:5160>;tag=as6bfcd003
  426. To: <sip:888@87654321.sip.us1.twilio.com>;tag=99315612_6772d868_01b40750-d4d1-440f-ad0c-cef4b1102ebe
  427. Via: SIP/2.0/UDP 192.168.88.234:5160;received=192.168.88.234;branch=z9hG4bK5b960f48;rport=5160
  428. Server: Twilio
  429. Contact: <sip:172.18.6.25:5060>
  430. X-Twilio-Error: 32202 Authentication failure - bad user credentials.
  431. Content-Length: 0
  432.  
  433. <------------->
  434. --- (10 headers 0 lines) ---
  435. Transmitting (NAT) to 54.172.60.1:5060:
  436. ACK sip:888@87654321.sip.us1.twilio.com SIP/2.0
  437. Via: SIP/2.0/UDP 192.168.88.234:5160;branch=z9hG4bK5b960f48;rport
  438. Max-Forwards: 70
  439. From: <sip:789@192.168.88.234:5160>;tag=as6bfcd003
  440. To: <sip:888@87654321.sip.us1.twilio.com>;tag=99315612_6772d868_01b40750-d4d1-440f-ad0c-cef4b1102ebe
  441. Contact: <sip:789@192.168.88.234:5160>
  442. Call-ID: 4411fe4161d3f0067d40b46f28d4c2f0@192.168.88.234:5160
  443. CSeq: 103 ACK
  444. User-Agent: FPBX-14.0.2.10(13.20.0)
  445. Content-Length: 0
  446.  
  447.  
  448. ---
  449. [2018-06-20 22:52:13] WARNING[1112][C-0000000a]: chan_sip.c:24048 handle_response_invite: Received response: "Forbidden" from '<sip:789@192.168.88.234:5160>;tag=as6bfcd003'
  450. Scheduling destruction of SIP dialog '4411fe4161d3f0067d40b46f28d4c2f0@192.168.88.234:5160' in 32000 ms (Method: INVITE)
  451.   == Everyone is busy/congested at this time (1:0/0/1)
  452.     -- Executing [s@macro-dialout-trunk:25] NoOp("SIP/789-0000000d", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 21") in new stack
  453.     -- Executing [s@macro-dialout-trunk:26] GotoIf("SIP/789-0000000d", "0?continue,1:s-CHANUNAVAIL,1") in new stack
  454.     -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
  455.     -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("SIP/789-0000000d", "RC=21") in new stack
  456.     -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("SIP/789-0000000d", "21,1") in new stack
  457.     -- Goto (macro-dialout-trunk,21,1)
  458.     -- Executing [21@macro-dialout-trunk:1] Goto("SIP/789-0000000d", "continue,1") in new stack
  459.     -- Goto (macro-dialout-trunk,continue,1)
  460.     -- Executing [continue@macro-dialout-trunk:1] NoOp("SIP/789-0000000d", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 21 - failing through to other trunks") in new stack
  461.     -- Executing [continue@macro-dialout-trunk:2] ExecIf("SIP/789-0000000d", "1?Set(CALLERID(number)=789)") in new stack
  462.     -- Executing [888@from-internal:7] Macro("SIP/789-0000000d", "outisbusy,") in new stack
  463. [2018-06-20 22:52:13] WARNING[3080][C-0000000a]: app_macro.c:310 _macro_exec: No such context 'macro-outisbusy' for macro 'outisbusy'. Was called by 888@from-internal
  464.     -- Executing [888@from-internal:8] Hangup("SIP/789-0000000d", "") in new stack
  465.   == Spawn extension (from-internal, 888, 8) exited non-zero on 'SIP/789-0000000d'
  466.     -- Executing [h@from-internal:1] Macro("SIP/789-0000000d", "hangupcall") in new stack
  467.     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/789-0000000d", "1?theend") in new stack
  468.     -- Goto (macro-hangupcall,s,3)
  469.     -- Executing [s@macro-hangupcall:3] ExecIf("SIP/789-0000000d", "0?Set(CDR(recordingfile)=)") in new stack
  470.     -- Executing [s@macro-hangupcall:4] NoOp("SIP/789-0000000d", " monior file= ") in new stack
  471.     -- Executing [s@macro-hangupcall:5] AGI("SIP/789-0000000d", "attendedtransfer-rec-restart.php,,") in new stack
  472.     -- Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
  473.     -- <SIP/789-0000000d>AGI Script attendedtransfer-rec-restart.php completed, returning 0
  474.     -- Executing [s@macro-hangupcall:6] Hangup("SIP/789-0000000d", "") in new stack
  475.   == Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'SIP/789-0000000d' in macro 'hangupcall'
  476.   == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/789-0000000d'
  477. Scheduling destruction of SIP dialog 'g_VyhNMB3iX0YKRn1vLWPA..' in 6400 ms (Method: INVITE)
  478.  
  479. <--- Reliably Transmitting (no NAT) to 192.168.88.250:44352 --->
  480. SIP/2.0 403 Forbidden
  481. Via: SIP/2.0/UDP 192.168.88.250:44352;branch=z9hG4bK-524287-1---596b3aa8e4d4c6a1;received=192.168.88.250;rport=44352
  482. From: <sip:789@192.168.88.234:5160;transport=UDP>;tag=a607a542
  483. To: <sip:888@192.168.88.234:5160;transport=UDP>;tag=as4a94999e
  484. Call-ID: g_VyhNMB3iX0YKRn1vLWPA..
  485. CSeq: 2 INVITE
  486. Server: FPBX-14.0.2.10(13.20.0)
  487. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  488. Supported: replaces, timer
  489. Content-Length: 0
  490.  
  491.  
  492. <------------>
  493.  
  494. <--- SIP read from UDP:192.168.88.250:44352 --->
  495. ACK sip:888@192.168.88.234:5160;transport=UDP SIP/2.0
  496. Via: SIP/2.0/UDP 192.168.88.250:44352;branch=z9hG4bK-524287-1---596b3aa8e4d4c6a1;rport
  497. Max-Forwards: 70
  498. To: <sip:888@192.168.88.234:5160;transport=UDP>;tag=as4a94999e
  499. From: <sip:789@192.168.88.234:5160;transport=UDP>;tag=a607a542
  500. Call-ID: g_VyhNMB3iX0YKRn1vLWPA..
  501. CSeq: 2 ACK
  502. Content-Length: 0
  503.  
  504. <------------->
  505. --- (8 headers 0 lines) ---
  506. Really destroying SIP dialog 'g_VyhNMB3iX0YKRn1vLWPA..' Method: ACK
  507. raspbx*CLI>

Reply to "debug2"

Here you can reply to the paste above