Softphone

From Ashd, 4 Months ago, written in Plain Text, viewed 28 times.
URL http://pastebin.freepbx.org/view/60246de1 Embed
Download Paste or View Raw
  1. <--- SIP read from UDP:172.16.8.98:62836 --->
  2. INVITE sip:227@172.16.8.16 SIP/2.0
  3. Via: SIP/2.0/UDP 172.16.8.98:62836;branch=z9hG4bK-524287-1---bb205b592714b067;rport
  4. Max-Forwards: 70
  5. Contact: <sip:222@172.16.8.98:62836;rinstance=2569393737167d18>
  6. To: <sip:227@172.16.8.16>
  7. From: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=14aeed5a
  8. Call-ID: 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  9. CSeq: 1 INVITE
  10. Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  11. Content-Type: application/sdp
  12. Supported: replaces
  13. User-Agent: Bria 5 release 5.0.0 stamp 85993
  14. Content-Length: 685
  15.  
  16. v=0
  17. o=- 1503583365276682 1 IN IP4 172.16.8.98
  18. s=Bria 5 release 5.0.0 stamp 85993
  19. c=IN IP4 172.16.8.98
  20. t=0 0
  21. m=audio 62516 RTP/AVP 9 8 18 0 3 101
  22. a=rtpmap:18 G729/8000
  23. a=fmtp:18 annexb=yes
  24. a=rtpmap:101 telephone-event/8000
  25. a=fmtp:101 0-15
  26. a=sendrecv
  27. m=video 55890 RTP/AVP 34 115 100 127 126
  28. a=rtpmap:34 H263/90000
  29. a=fmtp:34 CIF=2;QCIF=2;VGA=2;CIF4=2
  30. a=rtpmap:115 H263-1998/90000
  31. a=fmtp:115 VGA=2;CIF=1;QCIF=1;CIF4=2;I=1;J=1;T=1
  32. a=rtpmap:100 VP8/90000
  33. a=rtpmap:127 H264/90000
  34. a=fmtp:127 profile-level-id=428016;packetization-mode=0
  35. a=rtpmap:126 H264/90000
  36. a=fmtp:126 profile-level-id=428016;packetization-mode=1
  37. a=rtcp-fb:* nack
  38. a=rtcp-fb:* nack pli
  39. a=sendrecv
  40. <------------->
  41. [2017-08-24 15:02:45] VERBOSE[2992] chan_sip.c: --- (13 headers 24 lines) ---
  42. [2017-08-24 15:02:45] VERBOSE[2992] chan_sip.c: Sending to 172.16.8.98:62836 (NAT)
  43. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Sending to 172.16.8.98:62836 (NAT)
  44. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Using INVITE request as basis request - 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  45. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found peer '222' for '222' from 172.16.8.98:62836
  46. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c:
  47. <--- Reliably Transmitting (no NAT) to 172.16.8.98:62836 --->
  48. SIP/2.0 401 Unauthorized
  49. Via: SIP/2.0/UDP 172.16.8.98:62836;branch=z9hG4bK-524287-1---bb205b592714b067;received=172.16.8.98;rport=62836
  50. From: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=14aeed5a
  51. To: <sip:227@172.16.8.16>;tag=as08e58281
  52. Call-ID: 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  53. CSeq: 1 INVITE
  54. Server: FPBX-14.0.1.4(13.17.0)
  55. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  56. Supported: replaces, timer
  57. WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="02b1fb6e"
  58. Content-Length: 0
  59.  
  60.  
  61. <------------>
  62. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Scheduling destruction of SIP dialog '85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI' in 6400 ms (Method: INVITE)
  63. [2017-08-24 15:02:45] VERBOSE[2992] chan_sip.c:
  64. <--- SIP read from UDP:172.16.8.98:62836 --->
  65. ACK sip:227@172.16.8.16 SIP/2.0
  66. Via: SIP/2.0/UDP 172.16.8.98:62836;branch=z9hG4bK-524287-1---bb205b592714b067;rport
  67. Max-Forwards: 70
  68. To: <sip:227@172.16.8.16>;tag=as08e58281
  69. From: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=14aeed5a
  70. Call-ID: 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  71. CSeq: 1 ACK
  72. Content-Length: 0
  73.  
  74. <------------->
  75. [2017-08-24 15:02:45] VERBOSE[2992] chan_sip.c: --- (8 headers 0 lines) ---
  76. [2017-08-24 15:02:45] VERBOSE[2992] chan_sip.c:
  77. <--- SIP read from UDP:172.16.8.98:62836 --->
  78. INVITE sip:227@172.16.8.16 SIP/2.0
  79. Via: SIP/2.0/UDP 172.16.8.98:62836;branch=z9hG4bK-524287-1---0f0ec13f1feca214;rport
  80. Max-Forwards: 70
  81. Contact: <sip:222@172.16.8.98:62836;rinstance=2569393737167d18>
  82. To: <sip:227@172.16.8.16>
  83. From: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=14aeed5a
  84. Call-ID: 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  85. CSeq: 2 INVITE
  86. Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  87. Content-Type: application/sdp
  88. Supported: replaces
  89. User-Agent: Bria 5 release 5.0.0 stamp 85993
  90. Authorization: Digest username="222",realm="asterisk",nonce="02b1fb6e",uri="sip:227@172.16.8.16",response="d93ccc87e2635a32f8d2650fb1335bda",algorithm=MD5
  91. Content-Length: 685
  92.  
  93. v=0
  94. o=- 1503583365276682 1 IN IP4 172.16.8.98
  95. s=Bria 5 release 5.0.0 stamp 85993
  96. c=IN IP4 172.16.8.98
  97. t=0 0
  98. m=audio 62516 RTP/AVP 9 8 18 0 3 101
  99. a=rtpmap:18 G729/8000
  100. a=fmtp:18 annexb=yes
  101. a=rtpmap:101 telephone-event/8000
  102. a=fmtp:101 0-15
  103. a=sendrecv
  104. m=video 55890 RTP/AVP 34 115 100 127 126
  105. a=rtpmap:34 H263/90000
  106. a=fmtp:34 CIF=2;QCIF=2;VGA=2;CIF4=2
  107. a=rtpmap:115 H263-1998/90000
  108. a=fmtp:115 VGA=2;CIF=1;QCIF=1;CIF4=2;I=1;J=1;T=1
  109. a=rtpmap:100 VP8/90000
  110. a=rtpmap:127 H264/90000
  111. a=fmtp:127 profile-level-id=428016;packetization-mode=0
  112. a=rtpmap:126 H264/90000
  113. a=fmtp:126 profile-level-id=428016;packetization-mode=1
  114. a=rtcp-fb:* nack
  115. a=rtcp-fb:* nack pli
  116. a=sendrecv
  117. <------------->
  118. [2017-08-24 15:02:45] VERBOSE[2992] chan_sip.c: --- (14 headers 24 lines) ---
  119. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Sending to 172.16.8.98:62836 (no NAT)
  120. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Using INVITE request as basis request - 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  121. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found peer '222' for '222' from 172.16.8.98:62836
  122. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] netsock2.c: Using SIP VIDEO TOS bits 136
  123. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] netsock2.c: Using SIP VIDEO CoS mark 6
  124. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] netsock2.c: Using SIP RTP TOS bits 184
  125. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] netsock2.c: Using SIP RTP CoS mark 5
  126. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found RTP audio format 9
  127. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found RTP audio format 8
  128. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found RTP audio format 18
  129. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found RTP audio format 0
  130. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found RTP audio format 3
  131. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found RTP audio format 101
  132. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found audio description format G729 for ID 18
  133. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found audio description format telephone-event for ID 101
  134. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found RTP video format 34
  135. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found RTP video format 115
  136. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found RTP video format 100
  137. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found RTP video format 127
  138. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found RTP video format 126
  139. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found video description format H263 for ID 34
  140. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found video description format H263-1998 for ID 115
  141. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found video description format VP8 for ID 100
  142. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found video description format H264 for ID 127
  143. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Found video description format H264 for ID 126
  144. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Capabilities: us - (ulaw|alaw|gsm|g726|g722|g723|g719|g729|h264|mpeg4|h263p|h261|h263), peer - audio=(ulaw|gsm|alaw|g722|g729)/video=(h263|vp8|h263p|h264)/text=(nothing), combined - (ulaw|alaw|gsm|g722|g729|h264|h263p|h263)
  145. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
  146. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Peer audio RTP is at port 172.16.8.98:62516
  147. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Peer video RTP is at port 172.16.8.98:55890
  148. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c: Looking for 227 in from-internal (domain 172.16.8.16)
  149. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] sip/route.c: sip_route_dump: route/path hop: <sip:222@172.16.8.98:62836;rinstance=2569393737167d18>
  150. [2017-08-24 15:02:45] VERBOSE[2992][C-0000002c] chan_sip.c:
  151. <--- Transmitting (no NAT) to 172.16.8.98:62836 --->
  152. SIP/2.0 100 Trying
  153. Via: SIP/2.0/UDP 172.16.8.98:62836;branch=z9hG4bK-524287-1---0f0ec13f1feca214;received=172.16.8.98;rport=62836
  154. From: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=14aeed5a
  155. To: <sip:227@172.16.8.16>
  156. Call-ID: 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  157. CSeq: 2 INVITE
  158. Server: FPBX-14.0.1.4(13.17.0)
  159. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  160. Supported: replaces, timer
  161. Contact: <sip:227@172.16.8.16:5060>
  162. Content-Length: 0
  163.  
  164.  
  165. <------------>
  166. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [227@from-internal:1] GotoIf("SIP/222-00000046", "1?ext-local,227,1:followme-check,227,1") in new stack
  167. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (ext-local,227,1)
  168. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [227@ext-local:1] Set("SIP/222-00000046", "__RINGTIMER=15") in new stack
  169. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [227@ext-local:2] Macro("SIP/222-00000046", "exten-vm,227,227,0,0,0") in new stack
  170. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-exten-vm:1] Macro("SIP/222-00000046", "user-callerid,") in new stack
  171. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/222-00000046", "TOUCH_MONITOR=1503583365.89") in new stack
  172. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/222-00000046", "AMPUSER=222") in new stack
  173. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/222-00000046", "0?report") in new stack
  174. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("SIP/222-00000046", "1?Set(__REALCALLERIDNUM=222)") in new stack
  175. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/222-00000046", "AMPUSER=222") in new stack
  176. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("SIP/222-00000046", "0?limit") in new stack
  177. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/222-00000046", "AMPUSERCIDNAME=Ash (MBP)") in new stack
  178. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/222-00000046", "0?report") in new stack
  179. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:9] Set("SIP/222-00000046", "AMPUSERCID=222") in new stack
  180. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/222-00000046", "__DIAL_OPTIONS=Ttr") in new stack
  181. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:11] Set("SIP/222-00000046", "CALLERID(all)="Ash (MBP)" <222>") in new stack
  182. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:12] GotoIf("SIP/222-00000046", "0?limit") in new stack
  183. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/222-00000046", "0?Set(GROUP(concurrency_limit)=222)") in new stack
  184. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:14] GosubIf("SIP/222-00000046", "7?sub-ccss,s,1(macro-exten-vm,227)") in new stack
  185. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-ccss:1] ExecIf("SIP/222-00000046", "0?Return()") in new stack
  186. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-ccss:2] Set("SIP/222-00000046", "CCSS_SETUP=TRUE") in new stack
  187. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-ccss:3] GosubIf("SIP/222-00000046", "0?monitor_config,1(macro-exten-vm,227):monitor_default,1(macro-exten-vm,227)") in new stack
  188. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [monitor_default@sub-ccss:1] GotoIf("SIP/222-00000046", "1?is_exten") in new stack
  189. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (sub-ccss,monitor_default,4)
  190. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [monitor_default@sub-ccss:4] Set("SIP/222-00000046", "CALLCOMPLETION(cc_monitor_policy)=generic") in new stack
  191. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [monitor_default@sub-ccss:5] Set("SIP/222-00000046", "CALLCOMPLETION(cc_max_monitors)=5") in new stack
  192. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [monitor_default@sub-ccss:6] Return("SIP/222-00000046", "TRUE") in new stack
  193. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-ccss:4] GosubIf("SIP/222-00000046", "7?agent_config,1():agent_default,1()") in new stack
  194. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [agent_config@sub-ccss:1] Set("SIP/222-00000046", "CALLCOMPLETION(cc_agent_policy)=generic") in new stack
  195. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [agent_config@sub-ccss:2] Set("SIP/222-00000046", "CALLCOMPLETION(cc_offer_timer)=30") in new stack
  196. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [agent_config@sub-ccss:3] Set("SIP/222-00000046", "CALLCOMPLETION(ccbs_available_timer)=") in new stack
  197. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [agent_config@sub-ccss:4] Set("SIP/222-00000046", "CALLCOMPLETION(ccnr_available_timer)=") in new stack
  198. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [agent_config@sub-ccss:5] Set("SIP/222-00000046", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
  199. [2017-08-24 15:02:45] WARNING[28583][C-0000002c] ccss.c: Usage of cc_callback_macro is deprecated.  Please use cc_callback_sub instead.
  200. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [agent_config@sub-ccss:6] ExecIf("SIP/222-00000046", "1?Set(CALLCOMPLETION(cc_recall_timer)=)") in new stack
  201. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [agent_config@sub-ccss:7] ExecIf("SIP/222-00000046", "1?Set(CALLCOMPLETION(cc_max_agents)=)") in new stack
  202. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [agent_config@sub-ccss:8] ExecIf("SIP/222-00000046", "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/222_227@from-ccss-)") in new stack
  203. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [agent_config@sub-ccss:9] Set("SIP/222-00000046", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
  204. [2017-08-24 15:02:45] WARNING[28583][C-0000002c] ccss.c: Usage of cc_callback_macro is deprecated.  Please use cc_callback_sub instead.
  205. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [agent_config@sub-ccss:10] Return("SIP/222-00000046", "") in new stack
  206. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-ccss:5] Set("SIP/222-00000046", "DB(AMPUSER/222/ccss/last_number)=227") in new stack
  207. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-ccss:6] Return("SIP/222-00000046", "") in new stack
  208. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:15] ExecIf("SIP/222-00000046", "0?Set(CHANNEL(language)=)") in new stack
  209. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("SIP/222-00000046", "0?continue") in new stack
  210. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:17] ExecIf("SIP/222-00000046", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
  211. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:18] Set("SIP/222-00000046", "__TTL=6") in new stack
  212. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("SIP/222-00000046", "1?continue") in new stack
  213. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (macro-user-callerid,s,30)
  214. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:30] Set("SIP/222-00000046", "CALLERID(number)=222") in new stack
  215. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:31] Set("SIP/222-00000046", "CALLERID(name)=Ash (MBP)") in new stack
  216. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:32] GotoIf("SIP/222-00000046", "0?cnum") in new stack
  217. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:33] Set("SIP/222-00000046", "CDR(cnam)=Ash (MBP)") in new stack
  218. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:34] Set("SIP/222-00000046", "CDR(cnum)=222") in new stack
  219. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:35] Set("SIP/222-00000046", "CHANNEL(language)=en_GB") in new stack
  220. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-exten-vm:2] Set("SIP/222-00000046", "RingGroupMethod=none") in new stack
  221. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-exten-vm:3] Set("SIP/222-00000046", "__EXTTOCALL=227") in new stack
  222. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-exten-vm:4] Set("SIP/222-00000046", "__PICKUPMARK=227") in new stack
  223. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-exten-vm:5] Set("SIP/222-00000046", "RT=15") in new stack
  224. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-exten-vm:6] Gosub("SIP/222-00000046", "sub-record-check,s,1(exten,227,dontcare)") in new stack
  225. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/222-00000046", "0?initialized") in new stack
  226. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:2] Set("SIP/222-00000046", "__REC_STATUS=INITIALIZED") in new stack
  227. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:3] Set("SIP/222-00000046", "NOW=1503583365") in new stack
  228. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:4] Set("SIP/222-00000046", "__DAY=24") in new stack
  229. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:5] Set("SIP/222-00000046", "__MONTH=08") in new stack
  230. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:6] Set("SIP/222-00000046", "__YEAR=2017") in new stack
  231. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:7] Set("SIP/222-00000046", "__TIMESTR=20170824-150245") in new stack
  232. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:8] Set("SIP/222-00000046", "__FROMEXTEN=222") in new stack
  233. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:9] Set("SIP/222-00000046", "__MON_FMT=wav") in new stack
  234. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/222-00000046", "Recordings initialized") in new stack
  235. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/222-00000046", "0?Set(ARG3=dontcare)") in new stack
  236. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:12] Set("SIP/222-00000046", "REC_POLICY_MODE_SAVE=") in new stack
  237. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/222-00000046", "0?Set(REC_STATUS=NO)") in new stack
  238. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/222-00000046", "5?checkaction") in new stack
  239. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (sub-record-check,s,17)
  240. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/222-00000046", "1?sub-record-check,exten,1") in new stack
  241. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (sub-record-check,exten,1)
  242. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/222-00000046", "Exten Recording Check between 222 and 227") in new stack
  243. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/222-00000046", "CALLTYPE=internal") in new stack
  244. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/222-00000046", "0?Set(CALLTYPE=)") in new stack
  245. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/222-00000046", "CALLEE=dontcare") in new stack
  246. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/222-00000046", "0?Set(CALLEE=dontcare)") in new stack
  247. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/222-00000046", "0?callee") in new stack
  248. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:7] GotoIf("SIP/222-00000046", "1?caller") in new stack
  249. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (sub-record-check,exten,13)
  250. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:13] Set("SIP/222-00000046", "RECMODE=dontcare") in new stack
  251. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:14] ExecIf("SIP/222-00000046", "0?Set(RECMODE=dontcare)") in new stack
  252. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:15] ExecIf("SIP/222-00000046", "1?Set(RECMODE=dontcare)") in new stack
  253. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:16] Gosub("SIP/222-00000046", "recordcheck,1(dontcare,internal,227)") in new stack
  254. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/222-00000046", "Starting recording check against dontcare") in new stack
  255. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/222-00000046", "dontcare") in new stack
  256. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  257. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/222-00000046", "") in new stack
  258. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:17] Return("SIP/222-00000046", "") in new stack
  259. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-exten-vm:7] GotoIf("SIP/222-00000046", "0?macrodial") in new stack
  260. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-exten-vm:8] Set("SIP/222-00000046", "INTERCOM_EXT_DOPTIONS=Ttr") in new stack
  261. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-exten-vm:9] Set("SIP/222-00000046", "INTERCOM_RETURN=TRUE") in new stack
  262. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-exten-vm:10] Gosub("SIP/222-00000046", "ext-intercom,*80227,1()") in new stack
  263. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:1] Macro("SIP/222-00000046", "user-callerid,") in new stack
  264. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/222-00000046", "TOUCH_MONITOR=1503583365.89") in new stack
  265. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/222-00000046", "AMPUSER=222") in new stack
  266. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/222-00000046", "9?report") in new stack
  267. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (macro-user-callerid,s,16)
  268. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("SIP/222-00000046", "0?continue") in new stack
  269. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:17] ExecIf("SIP/222-00000046", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
  270. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:18] Set("SIP/222-00000046", "__TTL=5") in new stack
  271. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("SIP/222-00000046", "1?continue") in new stack
  272. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (macro-user-callerid,s,30)
  273. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:30] Set("SIP/222-00000046", "CALLERID(number)=222") in new stack
  274. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:31] Set("SIP/222-00000046", "CALLERID(name)=Ash (MBP)") in new stack
  275. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:32] GotoIf("SIP/222-00000046", "0?cnum") in new stack
  276. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:33] Set("SIP/222-00000046", "CDR(cnam)=Ash (MBP)") in new stack
  277. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:34] Set("SIP/222-00000046", "CDR(cnum)=222") in new stack
  278. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-user-callerid:35] Set("SIP/222-00000046", "CHANNEL(language)=en_GB") in new stack
  279. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:2] Set("SIP/222-00000046", "dialnumber=227") in new stack
  280. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:3] Set("SIP/222-00000046", "INTERCOM_CALL=TRUE") in new stack
  281. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:4] Gosub("SIP/222-00000046", "sub-record-check,s,1(exten,227)") in new stack
  282. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/222-00000046", "3?initialized") in new stack
  283. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (sub-record-check,s,10)
  284. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/222-00000046", "Recordings initialized") in new stack
  285. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/222-00000046", "0?Set(ARG3=dontcare)") in new stack
  286. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:12] Set("SIP/222-00000046", "REC_POLICY_MODE_SAVE=") in new stack
  287. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/222-00000046", "0?Set(REC_STATUS=NO)") in new stack
  288. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/222-00000046", "5?checkaction") in new stack
  289. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (sub-record-check,s,17)
  290. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/222-00000046", "1?sub-record-check,exten,1") in new stack
  291. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (sub-record-check,exten,1)
  292. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/222-00000046", "Exten Recording Check between 222 and 227") in new stack
  293. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/222-00000046", "CALLTYPE=internal") in new stack
  294. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/222-00000046", "0?Set(CALLTYPE=)") in new stack
  295. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/222-00000046", "CALLEE=dontcare") in new stack
  296. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/222-00000046", "0?Set(CALLEE=dontcare)") in new stack
  297. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/222-00000046", "0?callee") in new stack
  298. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:7] GotoIf("SIP/222-00000046", "1?caller") in new stack
  299. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (sub-record-check,exten,13)
  300. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:13] Set("SIP/222-00000046", "RECMODE=dontcare") in new stack
  301. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:14] ExecIf("SIP/222-00000046", "0?Set(RECMODE=dontcare)") in new stack
  302. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:15] ExecIf("SIP/222-00000046", "1?Set(RECMODE=dontcare)") in new stack
  303. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:16] Gosub("SIP/222-00000046", "recordcheck,1(dontcare,internal,227)") in new stack
  304. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/222-00000046", "Starting recording check against dontcare") in new stack
  305. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/222-00000046", "dontcare") in new stack
  306. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  307. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/222-00000046", "") in new stack
  308. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [exten@sub-record-check:17] Return("SIP/222-00000046", "") in new stack
  309. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:5] GotoIf("SIP/222-00000046", "0?end") in new stack
  310. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:6] GotoIf("SIP/222-00000046", "0?end") in new stack
  311. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:7] GotoIf("SIP/222-00000046", "0?allow") in new stack
  312. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:8] GotoIf("SIP/222-00000046", "0?nointercom") in new stack
  313. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:9] GotoIf("SIP/222-00000046", "0?nointercom") in new stack
  314. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:10] Set("SIP/222-00000046", "DEVICES=227") in new stack
  315. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:11] GotoIf("SIP/222-00000046", "0?end") in new stack
  316. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:12] Set("SIP/222-00000046", "OVERRIDE=reject") in new stack
  317. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:13] Set("SIP/222-00000046", "LOOPCNT=1") in new stack
  318. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:14] Set("SIP/222-00000046", "_SIPURI=") in new stack
  319. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:15] Set("SIP/222-00000046", "_ALERTINFO=Ring Answer") in new stack
  320. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:16] Set("SIP/222-00000046", "_CALLINFO=<uri>;answer-after=0") in new stack
  321. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:17] Set("SIP/222-00000046", "_SIPURI=intercom=true") in new stack
  322. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:18] Set("SIP/222-00000046", "_DTIME=5") in new stack
  323. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:19] Set("SIP/222-00000046", "_ANSWERMACRO=") in new stack
  324. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:20] GotoIf("SIP/222-00000046", "0?pagemode") in new stack
  325. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:21] Macro("SIP/222-00000046", "autoanswer,227") in new stack
  326. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:1] GotoIf("SIP/222-00000046", "0?knowndial") in new stack
  327. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:2] Set("SIP/222-00000046", "DEVICE=SIP/227") in new stack
  328. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:3] GotoIf("SIP/222-00000046", "0?dopjsip") in new stack
  329. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:4] Set("SIP/222-00000046", "KNOWNDIAL=SIP/227") in new stack
  330. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:5] Goto("SIP/222-00000046", "knowndial") in new stack
  331. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (macro-autoanswer,s,19)
  332. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:19] Set("SIP/222-00000046", "DIAL=SIP/227") in new stack
  333. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:20] ExecIf("SIP/222-00000046", "0?Set(DIAL=DAHDI/227)") in new stack
  334. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:21] GotoIf("SIP/222-00000046", "0?macro") in new stack
  335. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:22] GotoIf("SIP/222-00000046", "0?pjsipua") in new stack
  336. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:23] Set("SIP/222-00000046", "USERAGENT=Cisco-CP8941/9.2.1") in new stack
  337. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:24] Goto("SIP/222-00000046", "uafin") in new stack
  338. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (macro-autoanswer,s,28)
  339. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:28] ExecIf("SIP/222-00000046", "0?Set(USERAGENT=)") in new stack
  340. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:29] ExecIf("SIP/222-00000046", "0?Set(PAGE_VOL=;volume=)") in new stack
  341. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:30] ExecIf("SIP/222-00000046", "0?Set(ALERTINFO=ring-answer)") in new stack
  342. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:31] ExecIf("SIP/222-00000046", "0?Set(CALLINFO=<sip:broadworks.net>;answer-after=0)") in new stack
  343. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:32] ExecIf("SIP/222-00000046", "0?Set(ALERTINFO=<http://example.com>;info=alert-autoanswer)") in new stack
  344. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:33] ExecIf("SIP/222-00000046", "0?Set(ALERTINFO=Intercom)") in new stack
  345. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:34] ExecIf("SIP/222-00000046", "0?Set(ALERTINFO=info=Auto Answer)") in new stack
  346. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:35] ExecIf("SIP/222-00000046", "0?Set(ALERTINFO=<http://www.sangoma.com>;info=external)") in new stack
  347. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@macro-autoanswer:36] ExecIf("SIP/222-00000046", "1?Set(__SIP_URI_OPTIONS=intercom=true)") in new stack
  348. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:22] Set("SIP/222-00000046", "_DOPTIONS=A(beep)b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0))") in new stack
  349. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:23] ChanIsAvail("SIP/222-00000046", "SIP/227,s") in new stack
  350. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] netsock2.c: Using SIP VIDEO TOS bits 136
  351. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] netsock2.c: Using SIP VIDEO CoS mark 6
  352. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] netsock2.c: Using SIP RTP TOS bits 184
  353. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] netsock2.c: Using SIP RTP CoS mark 5
  354. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:24] ExecIf("SIP/222-00000046", "0?Set(AVAILORIGCHAN=SIP/227)") in new stack
  355. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:25] GotoIf("SIP/222-00000046", "1?continue") in new stack
  356. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx_builtins.c: Goto (ext-intercom,*80227,30)
  357. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:30] NoOp("SIP/222-00000046", "Continuing with page") in new stack
  358. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:31] GotoIf("SIP/222-00000046", "0?godial") in new stack
  359. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:32] Set("SIP/222-00000046", "CONNECTEDLINE(name,i)=Sucha (Desk)") in new stack
  360. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:33] Set("SIP/222-00000046", "CONNECTEDLINE(num)=227") in new stack
  361. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [*80227@ext-intercom:34] Dial("SIP/222-00000046", "SIP/227,5,IA(beep)b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0))Ttr") in new stack
  362. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] netsock2.c: Using SIP VIDEO TOS bits 136
  363. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] netsock2.c: Using SIP VIDEO CoS mark 6
  364. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] netsock2.c: Using SIP RTP TOS bits 184
  365. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] netsock2.c: Using SIP RTP CoS mark 5
  366. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] app_stack.c: SIP/227-00000048 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) start
  367. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@autoanswer:1] GosubIf("SIP/227-00000048", "1?func-set-sipheader,s,1(Alert-Info,Ring Answer)") in new stack
  368. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-set-sipheader:1] NoOp("SIP/227-00000048", "Sip Add Header function called. Adding Alert-Info = Ring Answer") in new stack
  369. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-set-sipheader:2] Set("SIP/227-00000048", "HASH(_SIPHEADERS,Alert-Info)=Ring Answer") in new stack
  370. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-set-sipheader:3] Return("SIP/227-00000048", "") in new stack
  371. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@autoanswer:2] GosubIf("SIP/227-00000048", "1?func-set-sipheader,s,1(Call-Info,<uri>;answer-after=0)") in new stack
  372. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-set-sipheader:1] NoOp("SIP/227-00000048", "Sip Add Header function called. Adding Call-Info = <uri>;answer-after=0") in new stack
  373. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-set-sipheader:2] Set("SIP/227-00000048", "HASH(_SIPHEADERS,Call-Info)=<uri>;answer-after=0") in new stack
  374. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-set-sipheader:3] Return("SIP/227-00000048", "") in new stack
  375. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@autoanswer:3] Gosub("SIP/227-00000048", "func-apply-sipheaders,s,1()") in new stack
  376. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/227-00000048", "Applying SIP Headers to channel") in new stack
  377. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/227-00000048", "SIPHEADERKEYS=Call-Info,Alert-Info") in new stack
  378. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/227-00000048", "1") in new stack
  379. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/227-00000048", "sipheader=<uri>;answer-after=0") in new stack
  380. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("SIP/227-00000048", "Call-Info: <uri>;answer-after=0") in new stack
  381. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:6] EndWhile("SIP/227-00000048", "") in new stack
  382. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/227-00000048", "1") in new stack
  383. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/227-00000048", "sipheader=Ring Answer") in new stack
  384. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("SIP/227-00000048", "Alert-Info: Ring Answer") in new stack
  385. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:6] EndWhile("SIP/227-00000048", "") in new stack
  386. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/227-00000048", "0") in new stack
  387. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@func-apply-sipheaders:7] Return("SIP/227-00000048", "") in new stack
  388. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] pbx.c: Executing [s@autoanswer:4] Return("SIP/227-00000048", "") in new stack
  389. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] app_stack.c: Spawn extension (from-internal, 227, 1) exited non-zero on 'SIP/227-00000048'
  390. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] app_stack.c: SIP/227-00000048 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) complete GOSUB_RETVAL=
  391. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] app_dial.c: Called SIP/227
  392. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] chan_sip.c:
  393. <--- Transmitting (no NAT) to 172.16.8.98:62836 --->
  394. SIP/2.0 180 Ringing
  395. Via: SIP/2.0/UDP 172.16.8.98:62836;branch=z9hG4bK-524287-1---0f0ec13f1feca214;received=172.16.8.98;rport=62836
  396. From: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=14aeed5a
  397. To: <sip:227@172.16.8.16>;tag=as120c1fea
  398. Call-ID: 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  399. CSeq: 2 INVITE
  400. Server: FPBX-14.0.1.4(13.17.0)
  401. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  402. Supported: replaces, timer
  403. Contact: <sip:227@172.16.8.16:5060>
  404. P-Asserted-Identity: "Sucha (Desk)" <sip:227@pbx.domain.tld>
  405. Content-Length: 0
  406.  
  407.  
  408. <------------>
  409. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] app_dial.c: Connected line update to SIP/222-00000046 prevented.
  410. [2017-08-24 15:02:45] VERBOSE[2972] chan_sip.c: Extension Changed 227[ext-local] new state Ringing for Notify User 320
  411. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] app_dial.c: Connected line update to SIP/222-00000046 prevented.
  412. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] app_dial.c: SIP/227-00000048 is ringing
  413. [2017-08-24 15:02:45] VERBOSE[28583][C-0000002c] chan_sip.c:
  414. <--- Transmitting (no NAT) to 172.16.8.98:62836 --->
  415. SIP/2.0 180 Ringing
  416. Via: SIP/2.0/UDP 172.16.8.98:62836;branch=z9hG4bK-524287-1---0f0ec13f1feca214;received=172.16.8.98;rport=62836
  417. From: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=14aeed5a
  418. To: <sip:227@172.16.8.16>;tag=as120c1fea
  419. Call-ID: 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  420. CSeq: 2 INVITE
  421. Server: FPBX-14.0.1.4(13.17.0)
  422. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  423. Supported: replaces, timer
  424. Contact: <sip:227@172.16.8.16:5060>
  425. Content-Length: 0
  426.  
  427.  
  428. <------------>
  429. [2017-08-24 15:02:45] VERBOSE[2992] chan_sip.c:
  430. <--- SIP read from UDP:172.16.8.98:62836 --->
  431. PUBLISH sip:222@pbx.domain.tld SIP/2.0
  432. Via: SIP/2.0/UDP 172.16.8.98:62836;branch=z9hG4bK-524287-1---d5da747429fd9f25;rport
  433. Max-Forwards: 70
  434. Contact: <sip:222@172.16.8.98:62836;rinstance=2569393737167d18>
  435. To: <sip:222@pbx.domain.tld>
  436. From: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=d99feb76
  437. Call-ID: 85993MmU4YzQ1ZGY0YzgwZjFkMzk2ZTBjYzJlMGY5ZDM1ZTA
  438. CSeq: 1 PUBLISH
  439. Expires: 3600
  440. Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  441. Content-Type: application/pidf+xml
  442. User-Agent: Bria 5 release 5.0.0 stamp 85993
  443. Event: presence
  444. Content-Length: 447
  445.  
  446. <?xml version="1.0" encoding="UTF-8"?>
  447. <presence xmlns="urn:ietf:params:xml:ns:pidf"
  448. xmlns:dm="urn:ietf:params:xml:ns:pidf:data-model"
  449. xmlns:rpid="urn:ietf:params:xml:ns:pidf:rpid" entity="sip:222@pbx.domain.tld">
  450. <tuple id="ef7a1cbc5c6ed1ca07">
  451. <status>
  452. <basic>open</basic>
  453. </status>
  454. </tuple>
  455. <dm:person id="65a687001470c404fb">
  456. <dm:note>On the phone</dm:note>
  457. <rpid:activities>
  458. <rpid:on-the-phone/>
  459. </rpid:activities>
  460. </dm:person>
  461. </presence>
  462. <------------->
  463. [2017-08-24 15:02:45] VERBOSE[2992] chan_sip.c: --- (14 headers 16 lines) ---
  464. [2017-08-24 15:02:45] VERBOSE[2992] chan_sip.c: Sending to 172.16.8.98:62836 (NAT)
  465. [2017-08-24 15:02:45] VERBOSE[2992] chan_sip.c:
  466. <--- Transmitting (NAT) to 172.16.8.98:62836 --->
  467. SIP/2.0 489 Bad Event
  468. Via: SIP/2.0/UDP 172.16.8.98:62836;branch=z9hG4bK-524287-1---d5da747429fd9f25;received=172.16.8.98;rport=62836
  469. From: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=d99feb76
  470. To: <sip:222@pbx.domain.tld>;tag=as4aa0daa1
  471. Call-ID: 85993MmU4YzQ1ZGY0YzgwZjFkMzk2ZTBjYzJlMGY5ZDM1ZTA
  472. CSeq: 1 PUBLISH
  473. Server: FPBX-14.0.1.4(13.17.0)
  474. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  475. Supported: replaces, timer
  476. Content-Length: 0
  477.  
  478.  
  479. <------------>
  480. [2017-08-24 15:02:45] VERBOSE[2992] chan_sip.c: Really destroying SIP dialog '85993MmU4YzQ1ZGY0YzgwZjFkMzk2ZTBjYzJlMGY5ZDM1ZTA' Method: PUBLISH
  481. [2017-08-24 15:02:47] VERBOSE[28583][C-0000002c] app_dial.c: Connected line update to SIP/222-00000046 prevented.
  482. [2017-08-24 15:02:47] VERBOSE[28583][C-0000002c] app_dial.c: SIP/227-00000048 answered SIP/222-00000046
  483. [2017-08-24 15:02:47] VERBOSE[28583][C-0000002c] file.c: <SIP/227-00000048> Playing 'beep.ulaw' (language 'en_GB')
  484. [2017-08-24 15:02:47] VERBOSE[2972] chan_sip.c: Extension Changed 227[ext-local] new state InUse for Notify User 320
  485. [2017-08-24 15:02:48] VERBOSE[28583][C-0000002c] chan_sip.c: Audio is at 14718
  486. [2017-08-24 15:02:48] VERBOSE[28583][C-0000002c] chan_sip.c: Video is at 172.16.8.16:12508
  487. [2017-08-24 15:02:48] VERBOSE[28583][C-0000002c] chan_sip.c: Adding codec ulaw to SDP
  488. [2017-08-24 15:02:48] VERBOSE[28583][C-0000002c] chan_sip.c: Adding codec alaw to SDP
  489. [2017-08-24 15:02:48] VERBOSE[28583][C-0000002c] chan_sip.c: Adding codec gsm to SDP
  490. [2017-08-24 15:02:48] VERBOSE[28583][C-0000002c] chan_sip.c: Adding codec g722 to SDP
  491. [2017-08-24 15:02:48] VERBOSE[28583][C-0000002c] chan_sip.c: Adding codec g729 to SDP
  492. [2017-08-24 15:02:48] VERBOSE[28583][C-0000002c] chan_sip.c: Adding video codec h264 to SDP
  493. [2017-08-24 15:02:48] VERBOSE[28583][C-0000002c] chan_sip.c: Adding video codec h263p to SDP
  494. [2017-08-24 15:02:48] VERBOSE[28583][C-0000002c] chan_sip.c: Adding video codec h263 to SDP
  495. [2017-08-24 15:02:48] VERBOSE[28583][C-0000002c] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
  496. [2017-08-24 15:02:48] VERBOSE[28583][C-0000002c] chan_sip.c:
  497. <--- Reliably Transmitting (no NAT) to 172.16.8.98:62836 --->
  498. SIP/2.0 200 OK
  499. Via: SIP/2.0/UDP 172.16.8.98:62836;branch=z9hG4bK-524287-1---0f0ec13f1feca214;received=172.16.8.98;rport=62836
  500. From: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=14aeed5a
  501. To: <sip:227@172.16.8.16>;tag=as120c1fea
  502. Call-ID: 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  503. CSeq: 2 INVITE
  504. Server: FPBX-14.0.1.4(13.17.0)
  505. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  506. Supported: replaces, timer
  507. Contact: <sip:227@172.16.8.16:5060>
  508. P-Asserted-Identity: "Sucha (Desk)" <sip:227@pbx.domain.tld>
  509. Content-Type: application/sdp
  510. Content-Length: 1181
  511.  
  512. v=0
  513. o=root 1854900769 1854900769 IN IP4 172.16.8.16
  514. s=Asterisk PBX 13.17.0
  515. c=IN IP4 172.16.8.16
  516. b=CT:384
  517. t=0 0
  518. m=audio 14718 RTP/AVP 0 8 3 9 18 101
  519. a=rtpmap:0 PCMU/8000
  520. a=rtpmap:8 PCMA/8000
  521. a=rtpmap:3 GSM/8000
  522. a=rtpmap:9 G722/8000
  523. a=rtpmap:18 G729/8000
  524. a=fmtp:18 annexb=no
  525. a=rtpmap:101 telephone-event/8000
  526. a=fmtp:101 0-16
  527. a=maxptime:150
  528. a=ice-ufrag:4da23f7472ea7c1b4660bda72411782d
  529. a=ice-pwd:5e5b7ca473ea6f1216dd6f22482557d3
  530. a=candidate:Hc0a80010 1 UDP 2130706431 172.16.8.16 14718 typ host
  531. a=candidate:Hc0a80010 2 UDP 2130706430 172.16.8.16 14719 typ host
  532. a=sendrecv
  533. m=video 12508 RTP/AVP 126 115 34
  534. a=ice-ufrag:716acac279bb64df62b9fcdc2c44c514
  535. a=ice-pwd:63a3e2d52952446a444b830728191cfa
  536. a=candidate:Hc0a80010 1 UDP 2130706431 172.16.8.16 12508 typ host
  537. a=candidate:Hc0a80010 2 UDP 2130706430 172.16.8.16 12509 typ host
  538. a=rtpmap:126 H264/90000
  539. a=fmtp:126 packetization-mode=1;profile-level-id=428016
  540. a=rtpmap:115 h263-1998/90000
  541. a=fmtp:115 SQCIF=0;QCIF=1;CIF=1;CIF4=2;CIF16=0;VGA=2;F=0;I=1;J=1;T=1;K=0;N=0;BPP=0;HRD=0
  542. a=rtpmap:34 H263/90000
  543. a=fmtp:34 SQCIF=0;QCIF=2;CIF=2;CIF4=2;CIF16=0;VGA=2;F=0;I=0;J=0;T=0;K=0;N=0;BPP=0;HRD=0
  544. a=sendrecv
  545.  
  546. <------------>
  547. [2017-08-24 15:02:48] VERBOSE[28585][C-0000002c] bridge_channel.c: Channel SIP/227-00000048 joined 'simple_bridge' basic-bridge <cb7e8e7a-a4e1-4825-ba48-0cf083f1683b>
  548. [2017-08-24 15:02:48] VERBOSE[28583][C-0000002c] bridge_channel.c: Channel SIP/222-00000046 joined 'simple_bridge' basic-bridge <cb7e8e7a-a4e1-4825-ba48-0cf083f1683b>
  549. [2017-08-24 15:02:48] VERBOSE[2992] chan_sip.c:
  550. <--- SIP read from UDP:172.16.8.98:62836 --->
  551. ACK sip:227@172.16.8.16:5060 SIP/2.0
  552. Via: SIP/2.0/UDP 172.16.8.98:62836;branch=z9hG4bK-524287-1---997d6d0fae466065;rport
  553. Max-Forwards: 70
  554. Contact: <sip:222@172.16.8.98:62836;rinstance=2569393737167d18>
  555. To: <sip:227@172.16.8.16>;tag=as120c1fea
  556. From: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=14aeed5a
  557. Call-ID: 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  558. CSeq: 2 ACK
  559. User-Agent: Bria 5 release 5.0.0 stamp 85993
  560. Content-Length: 0
  561.  
  562. <------------->
  563. [2017-08-24 15:02:48] VERBOSE[2992] chan_sip.c: --- (10 headers 0 lines) ---
  564. [2017-08-24 15:02:48] VERBOSE[2992] chan_sip.c:
  565. <--- SIP read from UDP:172.16.8.98:62836 --->
  566. INFO sip:227@172.16.8.16:5060 SIP/2.0
  567. Via: SIP/2.0/UDP 172.16.8.98:62836;branch=z9hG4bK-524287-1---003e321830b8f648;rport
  568. Max-Forwards: 70
  569. Contact: <sip:222@172.16.8.98:62836;rinstance=2569393737167d18>
  570. To: <sip:227@172.16.8.16>;tag=as120c1fea
  571. From: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=14aeed5a
  572. Call-ID: 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  573. CSeq: 3 INFO
  574. Content-Type: application/media_control+xml
  575. User-Agent: Bria 5 release 5.0.0 stamp 85993
  576. Authorization: Digest username="222",realm="asterisk",nonce="02b1fb6e",uri="sip:227@172.16.8.16:5060",response="c1a86022d87fc5292c69f5a3a3520128",algorithm=MD5
  577. Content-Length: 181
  578.  
  579. <?xml version="1.0" encoding="utf-8" ?>
  580. <media_control> <vc_primitive> <to_encoder> <picture_fast_update /> </to_encoder> </vc_primitive>
  581. </media_control>
  582. <------------->
  583. [2017-08-24 15:02:48] VERBOSE[2992] chan_sip.c: --- (12 headers 3 lines) ---
  584. [2017-08-24 15:02:48] VERBOSE[2992][C-0000002c] chan_sip.c: Receiving INFO!
  585. [2017-08-24 15:02:48] VERBOSE[2992][C-0000002c] chan_sip.c:
  586. <--- Transmitting (no NAT) to 172.16.8.98:62836 --->
  587. SIP/2.0 200 OK
  588. Via: SIP/2.0/UDP 172.16.8.98:62836;branch=z9hG4bK-524287-1---003e321830b8f648;received=172.16.8.98;rport=62836
  589. From: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=14aeed5a
  590. To: <sip:227@172.16.8.16>;tag=as120c1fea
  591. Call-ID: 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  592. CSeq: 3 INFO
  593. Server: FPBX-14.0.1.4(13.17.0)
  594. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  595. Supported: replaces, timer
  596. Content-Length: 0
  597.  
  598.  
  599. <------------>
  600. [2017-08-24 15:02:48] WARNING[28585][C-0000002c] res_rtp_asterisk.c: RTP Read too short
  601. [2017-08-24 15:02:59] VERBOSE[2992] chan_sip.c:
  602. <--- SIP read from UDP:172.16.8.98:62836 --->
  603.  
  604.  
  605. <------------->
  606. [2017-08-24 15:03:00] VERBOSE[2992] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.8.98:62836:
  607. OPTIONS sip:222@172.16.8.98:62836;rinstance=2569393737167d18 SIP/2.0
  608. Via: SIP/2.0/UDP 172.16.8.16:5060;branch=z9hG4bK31472f9f
  609. Max-Forwards: 70
  610. From: "Unknown" <sip:Unknown@172.16.8.16>;tag=as0816a4d0
  611. To: <sip:222@172.16.8.98:62836;rinstance=2569393737167d18>
  612. Contact: <sip:Unknown@172.16.8.16:5060>
  613. Call-ID: 0dd63199033112a579c51c9126f9043c@172.16.8.16:5060
  614. CSeq: 102 OPTIONS
  615. User-Agent: FPBX-14.0.1.4(13.17.0)
  616. Date: Thu, 24 Aug 2017 14:03:00 GMT
  617. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  618. Supported: replaces, timer
  619. Content-Length: 0
  620.  
  621.  
  622. ---
  623. [2017-08-24 15:03:00] VERBOSE[2992] chan_sip.c:
  624. <--- SIP read from UDP:172.16.8.98:62836 --->
  625. SIP/2.0 200 OK
  626. Via: SIP/2.0/UDP 172.16.8.16:5060;branch=z9hG4bK31472f9f
  627. Contact: <sip:172.16.8.98:62836>
  628. To: <sip:222@172.16.8.98:62836;rinstance=2569393737167d18>;tag=07806f0f
  629. From: "Unknown" <sip:Unknown@172.16.8.16>;tag=as0816a4d0
  630. Call-ID: 0dd63199033112a579c51c9126f9043c@172.16.8.16:5060
  631. CSeq: 102 OPTIONS
  632. Accept: application/sdp
  633. Accept-Language: en
  634. Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  635. Supported: replaces
  636. User-Agent: Bria 5 release 5.0.0 stamp 85993
  637. Allow-Events: talk, hold
  638. Content-Length: 0
  639.  
  640. <------------->
  641. [2017-08-24 15:03:00] VERBOSE[2992] chan_sip.c: --- (14 headers 0 lines) ---
  642. [2017-08-24 15:03:00] VERBOSE[2992] chan_sip.c: Really destroying SIP dialog '0dd63199033112a579c51c9126f9043c@172.16.8.16:5060' Method: OPTIONS
  643. [2017-08-24 15:03:09] VERBOSE[28585][C-0000002c] bridge_channel.c: Channel SIP/227-00000048 left 'simple_bridge' basic-bridge <cb7e8e7a-a4e1-4825-ba48-0cf083f1683b>
  644. [2017-08-24 15:03:09] VERBOSE[28583][C-0000002c] bridge_channel.c: Channel SIP/222-00000046 left 'simple_bridge' basic-bridge <cb7e8e7a-a4e1-4825-ba48-0cf083f1683b>
  645. [2017-08-24 15:03:09] VERBOSE[2972] chan_sip.c: Extension Changed 227[ext-local] new state Idle for Notify User 320
  646. [2017-08-24 15:03:09] VERBOSE[28583][C-0000002c] app_macro.c: Spawn extension (ext-intercom, *80227, 34) exited non-zero on 'SIP/222-00000046' in macro 'exten-vm'
  647. [2017-08-24 15:03:09] VERBOSE[28583][C-0000002c] pbx.c: Spawn extension (ext-intercom, *80227, 34) exited non-zero on 'SIP/222-00000046'
  648. [2017-08-24 15:03:09] VERBOSE[28583][C-0000002c] chan_sip.c: Scheduling destruction of SIP dialog '85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI' in 6400 ms (Method: INFO)
  649. [2017-08-24 15:03:09] VERBOSE[28583][C-0000002c] chan_sip.c: set_destination: Parsing <sip:222@172.16.8.98:62836;rinstance=2569393737167d18> for address/port to send to
  650. [2017-08-24 15:03:09] VERBOSE[28583][C-0000002c] chan_sip.c: set_destination: set destination to 172.16.8.98:62836
  651. [2017-08-24 15:03:09] VERBOSE[28583][C-0000002c] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.8.98:62836:
  652. BYE sip:222@172.16.8.98:62836;rinstance=2569393737167d18 SIP/2.0
  653. Via: SIP/2.0/UDP 172.16.8.16:5060;branch=z9hG4bK3829cd72;rport
  654. Max-Forwards: 70
  655. From: <sip:227@172.16.8.16>;tag=as120c1fea
  656. To: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=14aeed5a
  657. Call-ID: 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  658. CSeq: 102 BYE
  659. User-Agent: FPBX-14.0.1.4(13.17.0)
  660. Proxy-Authorization: Digest username="222", realm="asterisk", algorithm=MD5, uri="sip:172.16.8.16", nonce="02b1fb6e", response="3a0d59932e397a636956f9a6cacb3725"
  661. X-Asterisk-HangupCause: Normal Clearing
  662. X-Asterisk-HangupCauseCode: 16
  663. Content-Length: 0
  664.  
  665.  
  666. ---
  667. [2017-08-24 15:03:09] VERBOSE[2992] chan_sip.c: Retransmitting #1 (no NAT) to 172.16.8.98:62836:
  668. BYE sip:222@172.16.8.98:62836;rinstance=2569393737167d18 SIP/2.0
  669. Via: SIP/2.0/UDP 172.16.8.16:5060;branch=z9hG4bK3829cd72;rport
  670. Max-Forwards: 70
  671. From: <sip:227@172.16.8.16>;tag=as120c1fea
  672. To: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=14aeed5a
  673. Call-ID: 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  674. CSeq: 102 BYE
  675. User-Agent: FPBX-14.0.1.4(13.17.0)
  676. Proxy-Authorization: Digest username="222", realm="asterisk", algorithm=MD5, uri="sip:172.16.8.16", nonce="02b1fb6e", response="3a0d59932e397a636956f9a6cacb3725"
  677. X-Asterisk-HangupCause: Normal Clearing
  678. X-Asterisk-HangupCauseCode: 16
  679. Content-Length: 0
  680.  
  681.  
  682. ---
  683. [2017-08-24 15:03:09] VERBOSE[2992] chan_sip.c:
  684. <--- SIP read from UDP:172.16.8.98:62836 --->
  685. SIP/2.0 200 OK
  686. Via: SIP/2.0/UDP 172.16.8.16:5060;branch=z9hG4bK3829cd72;rport=5060
  687. Contact: <sip:222@172.16.8.98:62836;rinstance=2569393737167d18>
  688. To: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=14aeed5a
  689. From: <sip:227@172.16.8.16>;tag=as120c1fea
  690. Call-ID: 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  691. CSeq: 102 BYE
  692. User-Agent: Bria 5 release 5.0.0 stamp 85993
  693. Content-Length: 0
  694.  
  695. <------------->
  696. [2017-08-24 15:03:09] VERBOSE[2992] chan_sip.c: --- (9 headers 0 lines) ---
  697. [2017-08-24 15:03:09] VERBOSE[2992][C-0000002c] chan_sip.c: SIP Response message for INCOMING dialog BYE arrived
  698. [2017-08-24 15:03:09] VERBOSE[2992] chan_sip.c:
  699. <--- SIP read from UDP:172.16.8.98:62836 --->
  700. SIP/2.0 200 OK
  701. Via: SIP/2.0/UDP 172.16.8.16:5060;branch=z9hG4bK3829cd72;rport=5060
  702. Contact: <sip:222@172.16.8.98:62836;rinstance=2569393737167d18>
  703. To: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=14aeed5a
  704. From: <sip:227@172.16.8.16>;tag=as120c1fea
  705. Call-ID: 85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI
  706. CSeq: 102 BYE
  707. User-Agent: Bria 5 release 5.0.0 stamp 85993
  708. Content-Length: 0
  709.  
  710. <------------->
  711. [2017-08-24 15:03:09] VERBOSE[2992] chan_sip.c: --- (9 headers 0 lines) ---
  712. [2017-08-24 15:03:09] VERBOSE[2992] chan_sip.c: Really destroying SIP dialog '85993NDRkNjhlNTU0YzdkYmVhMjAyNWQ5OGNjMWNhYjIxMWI' Method: INFO
  713. [2017-08-24 15:03:09] VERBOSE[2992] chan_sip.c:
  714. <--- SIP read from UDP:172.16.8.98:62836 --->
  715. PUBLISH sip:222@pbx.domain.tld SIP/2.0
  716. Via: SIP/2.0/UDP 172.16.8.98:62836;branch=z9hG4bK-524287-1---27c2cb7e3602ae6e;rport
  717. Max-Forwards: 70
  718. Contact: <sip:222@172.16.8.98:62836;rinstance=2569393737167d18>
  719. To: <sip:222@pbx.domain.tld>
  720. From: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=8cb38950
  721. Call-ID: 85993Mjk3NzRiNTE0ODYzZDYwNTg3M2I4ZTY5MDc5ODc0OWI
  722. CSeq: 1 PUBLISH
  723. Expires: 3600
  724. Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  725. Content-Type: application/pidf+xml
  726. User-Agent: Bria 5 release 5.0.0 stamp 85993
  727. Event: presence
  728. Content-Length: 386
  729.  
  730. <?xml version="1.0" encoding="UTF-8"?>
  731. <presence xmlns="urn:ietf:params:xml:ns:pidf"
  732. xmlns:dm="urn:ietf:params:xml:ns:pidf:data-model"
  733. xmlns:rpid="urn:ietf:params:xml:ns:pidf:rpid" entity="sip:222@pbx.domain.tld">
  734. <tuple id="ef7a1cbc5c6ed1ca07">
  735. <status>
  736. <basic>open</basic>
  737. </status>
  738. </tuple>
  739. <dm:person id="65a687001470c404fb">
  740. <dm:note>Available</dm:note>
  741. </dm:person>
  742. </presence>
  743. <------------->
  744. [2017-08-24 15:03:09] VERBOSE[2992] chan_sip.c: --- (14 headers 13 lines) ---
  745. [2017-08-24 15:03:09] VERBOSE[2992] chan_sip.c: Sending to 172.16.8.98:62836 (NAT)
  746. [2017-08-24 15:03:09] VERBOSE[2992] chan_sip.c:
  747. <--- Transmitting (NAT) to 172.16.8.98:62836 --->
  748. SIP/2.0 489 Bad Event
  749. Via: SIP/2.0/UDP 172.16.8.98:62836;branch=z9hG4bK-524287-1---27c2cb7e3602ae6e;received=172.16.8.98;rport=62836
  750. From: "Sucha Retard"<sip:222@pbx.domain.tld>;tag=8cb38950
  751. To: <sip:222@pbx.domain.tld>;tag=as49b08bd7
  752. Call-ID: 85993Mjk3NzRiNTE0ODYzZDYwNTg3M2I4ZTY5MDc5ODc0OWI
  753. CSeq: 1 PUBLISH
  754. Server: FPBX-14.0.1.4(13.17.0)
  755. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  756. Supported: replaces, timer
  757. Content-Length: 0
  758.  
  759.  
  760. <------------>
  761. [2017-08-24 15:03:09] VERBOSE[2992] chan_sip.c: Really destroying SIP dialog '85993Mjk3NzRiNTE0ODYzZDYwNTg3M2I4ZTY5MDc5ODc0OWI' Method: PUBLISH
  762.  

Reply to "Softphone"

Here you can reply to the paste above