1. Reliably Transmitting (no NAT) to 192.168.22.48:54730:
  2. OPTIONS sip:[email protected]:54730;rinstance=fb463844353dc9ec SIP/2.0
  3. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK7df10180
  4. Max-Forwards: 70
  5. From: "Unknown" <sip:[email protected]>;tag=as589f0459
  6. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>
  7. Contact: <sip:[email protected]:5060>
  8. Call-ID: [email protected]:5060
  9. CSeq: 102 OPTIONS
  10. User-Agent: FPBX-2.11.0(10.12.0)
  11. Date: Mon, 11 Jul 2016 05:42:57 GMT
  12. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
  13. Supported: replaces, timer
  14. Content-Length: 0
  17. ---
  19. <--- SIP read from UDP:192.168.22.48:54730 --->
  20. SIP/2.0 405 Method Not Allowed
  21. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK7df10180
  22. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>;tag=6ad37800
  23. From: "Unknown" <sip:[email protected]>;tag=as589f0459
  24. Call-ID: [email protected]:5060
  25. CSeq: 102 OPTIONS
  26. Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, MESSAGE
  27. Content-Length: 0
  29. <------------->
  30. --- (8 headers 0 lines) ---
  31. [2016-07-11 13:42:57] NOTICE[16300]: chan_sip.c:22191 handle_response_peerpoke: Peer '277' is now Reachable. (4ms / 2000ms)
  32. [2016-07-11 13:42:57] NOTICE[16300]: chan_sip.c:22191 handle_response_peerpoke: Peer '277' is now Reachable. (4ms / 2000ms)
  33. Really destroying SIP dialog '[email protected]:5060' Method: OPTIONS
  34. == Using SIP RTP TOS bits 184
  35. == Using SIP RTP CoS mark 5
  36. Audio is at 19940
  37. Adding codec 100003 (ulaw) to SDP
  38. Adding codec 100004 (alaw) to SDP
  39. Adding codec 100002 (gsm) to SDP
  40. Adding non-codec 0x1 (telephone-event) to SDP
  41. Reliably Transmitting (no NAT) to 192.168.22.48:54730:
  42. INVITE sip:[email protected]:54730;rinstance=fb463844353dc9ec SIP/2.0
  43. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  44. Max-Forwards: 70
  45. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  46. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>
  47. Contact: <sip:[email protected]:5060>
  48. Call-ID: [email protected]:5060
  49. CSeq: 102 INVITE
  50. User-Agent: FPBX-2.11.0(10.12.0)
  51. Date: Mon, 11 Jul 2016 05:42:58 GMT
  52. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
  53. Supported: replaces, timer
  54. Call-Info: ;answer-after=0
  55. Content-Type: application/sdp
  56. Content-Length: 285
  58. v=0
  59. o=root 1306888896 1306888896 IN IP4 192.168.22.74
  60. s=Asterisk PBX 10.12.0
  61. c=IN IP4 192.168.22.74
  62. t=0 0
  63. m=audio 19940 RTP/AVP 0 8 3 101
  64. a=rtpmap:0 PCMU/8000
  65. a=rtpmap:8 PCMA/8000
  66. a=rtpmap:3 GSM/8000
  67. a=rtpmap:101 telephone-event/8000
  68. a=fmtp:101 0-16
  69. a=ptime:20
  70. a=sendrecv
  72. ---
  74. <--- SIP read from UDP:192.168.22.48:54730 --->
  75. SIP/2.0 400 /tmp/buildKudu/dev/ua/sdk2/CPCAPI2/shared/sipfoundry/main/rutil/ParseBuffer.cxx:74, Parse failed expected '<' in context: Call-Info
  76. ;answer-after=0
  77. ^
  79. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  80. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>;tag=17913b7e
  81. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  82. Call-ID: [email protected]:5060
  83. CSeq: 102 INVITE
  84. Accept-Language: en
  85. Content-Length: 0
  87. <------------->
  88. --- (3 headers 7 lines) ---
  89. Retransmitting #1 (no NAT) to 192.168.22.48:54730:
  90. INVITE sip:[email protected]:54730;rinstance=fb463844353dc9ec SIP/2.0
  91. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  92. Max-Forwards: 70
  93. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  94. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>
  95. Contact: <sip:[email protected]:5060>
  96. Call-ID: [email protected]:5060
  97. CSeq: 102 INVITE
  98. User-Agent: FPBX-2.11.0(10.12.0)
  99. Date: Mon, 11 Jul 2016 05:42:58 GMT
  100. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
  101. Supported: replaces, timer
  102. Call-Info: ;answer-after=0
  103. Content-Type: application/sdp
  104. Content-Length: 285
  106. v=0
  107. o=root 1306888896 1306888896 IN IP4 192.168.22.74
  108. s=Asterisk PBX 10.12.0
  109. c=IN IP4 192.168.22.74
  110. t=0 0
  111. m=audio 19940 RTP/AVP 0 8 3 101
  112. a=rtpmap:0 PCMU/8000
  113. a=rtpmap:8 PCMA/8000
  114. a=rtpmap:3 GSM/8000
  115. a=rtpmap:101 telephone-event/8000
  116. a=fmtp:101 0-16
  117. a=ptime:20
  118. a=sendrecv
  120. ---
  122. <--- SIP read from UDP:192.168.22.48:54730 --->
  123. SIP/2.0 400 /tmp/buildKudu/dev/ua/sdk2/CPCAPI2/shared/sipfoundry/main/rutil/ParseBuffer.cxx:74, Parse failed expected '<' in context: Call-Info
  124. ;answer-after=0
  125. ^
  127. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  128. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>;tag=17913b7e
  129. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  130. Call-ID: [email protected]:5060
  131. CSeq: 102 INVITE
  132. Accept-Language: en
  133. Content-Length: 0
  135. <------------->
  136. --- (3 headers 7 lines) ---
  137. Retransmitting #2 (no NAT) to 192.168.22.48:54730:
  138. INVITE sip:[email protected]:54730;rinstance=fb463844353dc9ec SIP/2.0
  139. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  140. Max-Forwards: 70
  141. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  142. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>
  143. Contact: <sip:[email protected]:5060>
  144. Call-ID: [email protected]:5060
  145. CSeq: 102 INVITE
  146. User-Agent: FPBX-2.11.0(10.12.0)
  147. Date: Mon, 11 Jul 2016 05:42:58 GMT
  148. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
  149. Supported: replaces, timer
  150. Call-Info: ;answer-after=0
  151. Content-Type: application/sdp
  152. Content-Length: 285
  154. v=0
  155. o=root 1306888896 1306888896 IN IP4 192.168.22.74
  156. s=Asterisk PBX 10.12.0
  157. c=IN IP4 192.168.22.74
  158. t=0 0
  159. m=audio 19940 RTP/AVP 0 8 3 101
  160. a=rtpmap:0 PCMU/8000
  161. a=rtpmap:8 PCMA/8000
  162. a=rtpmap:3 GSM/8000
  163. a=rtpmap:101 telephone-event/8000
  164. a=fmtp:101 0-16
  165. a=ptime:20
  166. a=sendrecv
  168. ---
  170. <--- SIP read from UDP:192.168.22.48:54730 --->
  171. SIP/2.0 400 /tmp/buildKudu/dev/ua/sdk2/CPCAPI2/shared/sipfoundry/main/rutil/ParseBuffer.cxx:74, Parse failed expected '<' in context: Call-Info
  172. ;answer-after=0
  173. ^
  175. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  176. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>;tag=17913b7e
  177. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  178. Call-ID: [email protected]:5060
  179. CSeq: 102 INVITE
  180. Accept-Language: en
  181. Content-Length: 0
  183. <------------->
  184. --- (3 headers 7 lines) ---
  186. <--- SIP read from UDP:192.168.22.48:54730 --->
  187. SIP/2.0 400 /tmp/buildKudu/dev/ua/sdk2/CPCAPI2/shared/sipfoundry/main/rutil/ParseBuffer.cxx:74, Parse failed expected '<' in context: Call-Info
  188. ;answer-after=0
  189. ^
  191. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  192. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>;tag=17913b7e
  193. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  194. Call-ID: [email protected]:5060
  195. CSeq: 102 INVITE
  196. Accept-Language: en
  197. Content-Length: 0
  199. <------------->
  200. --- (3 headers 7 lines) ---
  201. Retransmitting #3 (no NAT) to 192.168.22.48:54730:
  202. INVITE sip:[email protected]:54730;rinstance=fb463844353dc9ec SIP/2.0
  203. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  204. Max-Forwards: 70
  205. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  206. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>
  207. Contact: <sip:[email protected]:5060>
  208. Call-ID: [email protected]:5060
  209. CSeq: 102 INVITE
  210. User-Agent: FPBX-2.11.0(10.12.0)
  211. Date: Mon, 11 Jul 2016 05:42:58 GMT
  212. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
  213. Supported: replaces, timer
  214. Call-Info: ;answer-after=0
  215. Content-Type: application/sdp
  216. Content-Length: 285
  218. v=0
  219. o=root 1306888896 1306888896 IN IP4 192.168.22.74
  220. s=Asterisk PBX 10.12.0
  221. c=IN IP4 192.168.22.74
  222. t=0 0
  223. m=audio 19940 RTP/AVP 0 8 3 101
  224. a=rtpmap:0 PCMU/8000
  225. a=rtpmap:8 PCMA/8000
  226. a=rtpmap:3 GSM/8000
  227. a=rtpmap:101 telephone-event/8000
  228. a=fmtp:101 0-16
  229. a=ptime:20
  230. a=sendrecv
  232. ---
  234. <--- SIP read from UDP:192.168.22.48:54730 --->
  235. SIP/2.0 400 /tmp/buildKudu/dev/ua/sdk2/CPCAPI2/shared/sipfoundry/main/rutil/ParseBuffer.cxx:74, Parse failed expected '<' in context: Call-Info
  236. ;answer-after=0
  237. ^
  239. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  240. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>;tag=17913b7e
  241. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  242. Call-ID: [email protected]:5060
  243. CSeq: 102 INVITE
  244. Accept-Language: en
  245. Content-Length: 0
  247. <------------->
  248. --- (3 headers 7 lines) ---
  249. Retransmitting #4 (no NAT) to 192.168.22.48:54730:
  250. INVITE sip:[email protected]:54730;rinstance=fb463844353dc9ec SIP/2.0
  251. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  252. Max-Forwards: 70
  253. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  254. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>
  255. Contact: <sip:[email protected]:5060>
  256. Call-ID: [email protected]:5060
  257. CSeq: 102 INVITE
  258. User-Agent: FPBX-2.11.0(10.12.0)
  259. Date: Mon, 11 Jul 2016 05:42:58 GMT
  260. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
  261. Supported: replaces, timer
  262. Call-Info: ;answer-after=0
  263. Content-Type: application/sdp
  264. Content-Length: 285
  266. v=0
  267. o=root 1306888896 1306888896 IN IP4 192.168.22.74
  268. s=Asterisk PBX 10.12.0
  269. c=IN IP4 192.168.22.74
  270. t=0 0
  271. m=audio 19940 RTP/AVP 0 8 3 101
  272. a=rtpmap:0 PCMU/8000
  273. a=rtpmap:8 PCMA/8000
  274. a=rtpmap:3 GSM/8000
  275. a=rtpmap:101 telephone-event/8000
  276. a=fmtp:101 0-16
  277. a=ptime:20
  278. a=sendrecv
  280. ---
  282. <--- SIP read from UDP:192.168.22.48:54730 --->
  283. SIP/2.0 400 /tmp/buildKudu/dev/ua/sdk2/CPCAPI2/shared/sipfoundry/main/rutil/ParseBuffer.cxx:74, Parse failed expected '<' in context: Call-Info
  284. ;answer-after=0
  285. ^
  287. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  288. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>;tag=17913b7e
  289. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  290. Call-ID: [email protected]:5060
  291. CSeq: 102 INVITE
  292. Accept-Language: en
  293. Content-Length: 0
  295. <------------->
  296. --- (3 headers 7 lines) ---
  298. <--- SIP read from UDP:192.168.22.48:54730 --->
  299. SIP/2.0 400 /tmp/buildKudu/dev/ua/sdk2/CPCAPI2/shared/sipfoundry/main/rutil/ParseBuffer.cxx:74, Parse failed expected '<' in context: Call-Info
  300. ;answer-after=0
  301. ^
  303. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  304. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>;tag=17913b7e
  305. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  306. Call-ID: [email protected]:5060
  307. CSeq: 102 INVITE
  308. Accept-Language: en
  309. Content-Length: 0
  311. <------------->
  312. --- (3 headers 7 lines) ---
  313. Retransmitting #5 (no NAT) to 192.168.22.48:54730:
  314. INVITE sip:[email protected]:54730;rinstance=fb463844353dc9ec SIP/2.0
  315. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  316. Max-Forwards: 70
  317. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  318. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>
  319. Contact: <sip:[email protected]:5060>
  320. Call-ID: [email protected]:5060
  321. CSeq: 102 INVITE
  322. User-Agent: FPBX-2.11.0(10.12.0)
  323. Date: Mon, 11 Jul 2016 05:42:58 GMT
  324. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
  325. Supported: replaces, timer
  326. Call-Info: ;answer-after=0
  327. Content-Type: application/sdp
  328. Content-Length: 285
  330. v=0
  331. o=root 1306888896 1306888896 IN IP4 192.168.22.74
  332. s=Asterisk PBX 10.12.0
  333. c=IN IP4 192.168.22.74
  334. t=0 0
  335. m=audio 19940 RTP/AVP 0 8 3 101
  336. a=rtpmap:0 PCMU/8000
  337. a=rtpmap:8 PCMA/8000
  338. a=rtpmap:3 GSM/8000
  339. a=rtpmap:101 telephone-event/8000
  340. a=fmtp:101 0-16
  341. a=ptime:20
  342. a=sendrecv
  344. ---
  346. <--- SIP read from UDP:192.168.22.48:54730 --->
  347. SIP/2.0 400 /tmp/buildKudu/dev/ua/sdk2/CPCAPI2/shared/sipfoundry/main/rutil/ParseBuffer.cxx:74, Parse failed expected '<' in context: Call-Info
  348. ;answer-after=0
  349. ^
  351. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  352. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>;tag=17913b7e
  353. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  354. Call-ID: [email protected]:5060
  355. CSeq: 102 INVITE
  356. Accept-Language: en
  357. Content-Length: 0
  359. <------------->
  360. --- (3 headers 7 lines) ---
  362. <--- SIP read from UDP:192.168.22.48:54730 --->
  363. SIP/2.0 400 /tmp/buildKudu/dev/ua/sdk2/CPCAPI2/shared/sipfoundry/main/rutil/ParseBuffer.cxx:74, Parse failed expected '<' in context: Call-Info
  364. ;answer-after=0
  365. ^
  367. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  368. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>;tag=17913b7e
  369. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  370. Call-ID: [email protected]:5060
  371. CSeq: 102 INVITE
  372. Accept-Language: en
  373. Content-Length: 0
  375. <------------->
  376. --- (3 headers 7 lines) ---
  377. Retransmitting #6 (no NAT) to 192.168.22.48:54730:
  378. INVITE sip:[email protected]:54730;rinstance=fb463844353dc9ec SIP/2.0
  379. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  380. Max-Forwards: 70
  381. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  382. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>
  383. Contact: <sip:[email protected]:5060>
  384. Call-ID: [email protected]:5060
  385. CSeq: 102 INVITE
  386. User-Agent: FPBX-2.11.0(10.12.0)
  387. Date: Mon, 11 Jul 2016 05:42:58 GMT
  388. Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
  389. Supported: replaces, timer
  390. Call-Info: ;answer-after=0
  391. Content-Type: application/sdp
  392. Content-Length: 285
  394. v=0
  395. o=root 1306888896 1306888896 IN IP4 192.168.22.74
  396. s=Asterisk PBX 10.12.0
  397. c=IN IP4 192.168.22.74
  398. t=0 0
  399. m=audio 19940 RTP/AVP 0 8 3 101
  400. a=rtpmap:0 PCMU/8000
  401. a=rtpmap:8 PCMA/8000
  402. a=rtpmap:3 GSM/8000
  403. a=rtpmap:101 telephone-event/8000
  404. a=fmtp:101 0-16
  405. a=ptime:20
  406. a=sendrecv
  408. ---
  410. <--- SIP read from UDP:192.168.22.48:54730 --->
  411. SIP/2.0 400 /tmp/buildKudu/dev/ua/sdk2/CPCAPI2/shared/sipfoundry/main/rutil/ParseBuffer.cxx:74, Parse failed expected '<' in context: Call-Info
  412. ;answer-after=0
  413. ^
  415. Via: SIP/2.0/UDP 192.168.22.74:5060;branch=z9hG4bK16d40687
  416. To: <sip:[email protected]:54730;rinstance=fb463844353dc9ec>;tag=17913b7e
  417. From: "NEPOMNJASCHIJ ALEKSEJ GENNADEVICH" <sip:[email protected]>;tag=as3c556f1d
  418. Call-ID: [email protected]:5060
  419. CSeq: 102 INVITE
  420. Accept-Language: en
  421. Content-Length: 0
  423. <------------->
  424. --- (3 headers 7 lines) ---
  425. [2016-07-11 13:43:04] WARNING[16300]: chan_sip.c:4020 retrans_pkt: Retransmission timeout reached on transmission [email protected]:5060 for seqno 102 (Critical Request) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
  426. Packet timed out after 6400ms with no response
  427. [2016-07-11 13:43:04] WARNING[16300]: chan_sip.c:4020 retrans_pkt: Retransmission timeout reached on transmission [email protected]:5060 for seqno 102 (Critical Request) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
  428. Packet timed out after 6400ms with no response
  429. [2016-07-11 13:43:04] WARNING[16300]: chan_sip.c:4049 retrans_pkt: Hanging up call [email protected]:5060 - no reply to our critical packet (see https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions).
  430. [2016-07-11 13:43:04] WARNING[16300]: chan_sip.c:4049 retrans_pkt: Hanging up call [email protected]:5060 - no reply to our critical packet (see https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions).
  431. -- Executing [failed@from-internal:1] Macro("OutgoingSpoolFailed", "user-callerid,LIMIT,EXTERNAL,") in new stack
  432. -- Executing [s@macro-user-callerid:1] Set("OutgoingSpoolFailed", "TOUCH_MONITOR=1468215784.165") in new stack
  433. -- Executing [s@macro-user-callerid:2] Set("OutgoingSpoolFailed", "CDR(did)=") in new stack
  434. -- Executing [s@macro-user-callerid:3] Set("OutgoingSpoolFailed", "AMPUSER=") in new stack
  435. -- Executing [s@macro-user-callerid:4] GotoIf("OutgoingSpoolFailed", "0?report") in new stack
  436. -- Executing [s@macro-user-callerid:5] ExecIf("OutgoingSpoolFailed", "1?Set(REALCALLERIDNUM=)") in new stack
  437. -- Executing [s@macro-user-callerid:6] Set("OutgoingSpoolFailed", "AMPUSER=") in new stack
  438. -- Executing [s@macro-user-callerid:7] GotoIf("OutgoingSpoolFailed", "0?limit") in new stack
  439. -- Executing [s@macro-user-callerid:8] Set("OutgoingSpoolFailed", "AMPUSERCIDNAME=") in new stack
  440. -- Executing [s@macro-user-callerid:9] GotoIf("OutgoingSpoolFailed", "1?report") in new stack
  441. -- Goto (macro-user-callerid,s,17)
  442. -- Executing [s@macro-user-callerid:17] GotoIf("OutgoingSpoolFailed", "1?continue") in new stack
  443. -- Goto (macro-user-callerid,s,30)
  444. -- Executing [s@macro-user-callerid:30] Set("OutgoingSpoolFailed", "CALLERID(number)=") in new stack
  445. -- Executing [s@macro-user-callerid:31] Set("OutgoingSpoolFailed", "CALLERID(name)=") in new stack
  446. -- Executing [s@macro-user-callerid:32] Set("OutgoingSpoolFailed", "CDR(cnum)=") in new stack
  447. -- Executing [s@macro-user-callerid:33] Set("OutgoingSpoolFailed", "CDR(cnam)=") in new stack
  448. -- Executing [s@macro-user-callerid:34] Set("OutgoingSpoolFailed", "CHANNEL(language)=en") in new stack
  449. -- Executing [failed@from-internal:2] Set("OutgoingSpoolFailed", "MOHCLASS=default") in new stack
  450. -- Executing [failed@from-internal:3] Set("OutgoingSpoolFailed", "_NODEST=") in new stack
  451. -- Executing [failed@from-internal:4] Gosub("OutgoingSpoolFailed", "sub-record-check,s,1(out,failed,)") in new stack
  452. -- Executing [s@sub-record-check:1] Set("OutgoingSpoolFailed", "REC_POLICY_MODE_SAVE=") in new stack
  453. -- Executing [s@sub-record-check:2] GotoIf("OutgoingSpoolFailed", "1?check") in new stack
  454. -- Goto (sub-record-check,s,7)
  455. -- Executing [s@sub-record-check:7] Set("OutgoingSpoolFailed", "__MON_FMT=wav") in new stack
  456. -- Executing [s@sub-record-check:8] GotoIf("OutgoingSpoolFailed", "1?next") in new stack
  457. -- Goto (sub-record-check,s,11)
  458. -- Executing [s@sub-record-check:11] ExecIf("OutgoingSpoolFailed", "0?Return()") in new stack
  459. -- Executing [s@sub-record-check:12] ExecIf("OutgoingSpoolFailed", "0?Set(__REC_POLICY_MODE=)") in new stack
  460. -- Executing [s@sub-record-check:13] GotoIf("OutgoingSpoolFailed", "0?out,1") in new stack
  461. -- Executing [s@sub-record-check:14] Set("OutgoingSpoolFailed", "__REC_STATUS=INITIALIZED") in new stack
  462. -- Executing [s@sub-record-check:15] Set("OutgoingSpoolFailed", "NOW=1468215784") in new stack
  463. -- Executing [s@sub-record-check:16] Set("OutgoingSpoolFailed", "__DAY=11") in new stack
  464. -- Executing [s@sub-record-check:17] Set("OutgoingSpoolFailed", "__MONTH=07") in new stack
  465. -- Executing [s@sub-record-check:18] Set("OutgoingSpoolFailed", "__YEAR=2016") in new stack
  466. -- Executing [s@sub-record-check:19] Set("OutgoingSpoolFailed", "__TIMESTR=20160711-134304") in new stack
  467. -- Executing [s@sub-record-check:20] Set("OutgoingSpoolFailed", "__FROMEXTEN=unknown") in new stack
  468. -- Executing [s@sub-record-check:21] Set("OutgoingSpoolFailed", "__CALLFILENAME=out-failed-unknown-20160711-134304-1468215784.165") in new stack
  469. -- Executing [s@sub-record-check:22] Goto("OutgoingSpoolFailed", "out,1") in new stack
  470. -- Goto (sub-record-check,out,1)
  471. -- Executing [out@sub-record-check:1] ExecIf("OutgoingSpoolFailed", "1?Set(__REC_POLICY_MODE=)") in new stack
  472. -- Executing [out@sub-record-check:2] GosubIf("OutgoingSpoolFailed", "0?record,1(exten,failed,unknown)") in new stack
  473. -- Executing [out@sub-record-check:3] Return("OutgoingSpoolFailed", "") in new stack
  474. -- Executing [failed@from-internal:5] Macro("OutgoingSpoolFailed", "dialout-trunk,2,failed,,off") in new stack
  475. -- Executing [s@macro-dialout-trunk:1] Set("OutgoingSpoolFailed", "DIAL_TRUNK=2") in new stack
  476. -- Executing [s@macro-dialout-trunk:2] GosubIf("OutgoingSpoolFailed", "0?sub-pincheck,s,1()") in new stack
  477. -- Executing [s@macro-dialout-trunk:3] GotoIf("OutgoingSpoolFailed", "1?disabletrunk,1") in new stack
  478. -- Goto (macro-dialout-trunk,disabletrunk,1)
  479. -- Executing [disabletrunk@macro-dialout-trunk:1] NoOp("OutgoingSpoolFailed", "TRUNK: SIP/virtualpbx DISABLED - falling through to next trunk") in new stack
  480. -- Executing [failed@from-internal:6] Macro("OutgoingSpoolFailed", "dialout-trunk,3,failed,,off") in new stack
  481. -- Executing [s@macro-dialout-trunk:1] Set("OutgoingSpoolFailed", "DIAL_TRUNK=3") in new stack
  482. -- Executing [s@macro-dialout-trunk:2] GosubIf("OutgoingSpoolFailed", "0?sub-pincheck,s,1()") in new stack
  483. -- Executing [s@macro-dialout-trunk:3] GotoIf("OutgoingSpoolFailed", "0?disabletrunk,1") in new stack
  484. -- Executing [s@macro-dialout-trunk:4] Set("OutgoingSpoolFailed", "DIAL_NUMBER=failed") in new stack
  485. -- Executing [s@macro-dialout-trunk:5] Set("OutgoingSpoolFailed", "DIAL_TRUNK_OPTIONS=tr") in new stack
  486. -- Executing [s@macro-dialout-trunk:6] Set("OutgoingSpoolFailed", "OUTBOUND_GROUP=OUT_3") in new stack
  487. -- Executing [s@macro-dialout-trunk:7] GotoIf("OutgoingSpoolFailed", "0?nomax") in new stack
  488. -- Executing [s@macro-dialout-trunk:8] GotoIf("OutgoingSpoolFailed", "0?chanfull") in new stack
  489. -- Executing [s@macro-dialout-trunk:9] GotoIf("OutgoingSpoolFailed", "0?skipoutcid") in new stack
  490. -- Executing [s@macro-dialout-trunk:10] Set("OutgoingSpoolFailed", "DIAL_TRUNK_OPTIONS=") in new stack
  491. -- Executing [s@macro-dialout-trunk:11] Macro("OutgoingSpoolFailed", "outbound-callerid,3") in new stack
  492. -- Executing [s@macro-outbound-callerid:1] ExecIf("OutgoingSpoolFailed", "0?Set(CALLERPRES()=)") in new stack
  493. -- Executing [s@macro-outbound-callerid:2] ExecIf("OutgoingSpoolFailed", "1?Set(REALCALLERIDNUM=)") in new stack
  494. -- Executing [s@macro-outbound-callerid:3] GotoIf("OutgoingSpoolFailed", "1?normcid") in new stack
  495. -- Goto (macro-outbound-callerid,s,6)
  496. -- Executing [s@macro-outbound-callerid:6] Set("OutgoingSpoolFailed", "USEROUTCID=") in new stack
  497. -- Executing [s@macro-outbound-callerid:7] Set("OutgoingSpoolFailed", "EMERGENCYCID=") in new stack
  498. -- Executing [s@macro-outbound-callerid:8] Set("OutgoingSpoolFailed", "TRUNKOUTCID=83912050398") in new stack
  499. -- Executing [s@macro-outbound-callerid:9] GotoIf("OutgoingSpoolFailed", "1?trunkcid") in new stack
  500. -- Goto (macro-outbound-callerid,s,14)
  501. -- Executing [s@macro-outbound-callerid:14] ExecIf("OutgoingSpoolFailed", "1?Set(CALLERID(all)=83912050398)") in new stack
  502. -- Executing [s@macro-outbound-callerid:15] ExecIf("OutgoingSpoolFailed", "0?Set(CALLERID(all)=)") in new stack
  503. -- Executing [s@macro-outbound-callerid:16] ExecIf("OutgoingSpoolFailed", "0?Set(CALLERID(all)=)") in new stack
  504. -- Executing [s@macro-outbound-callerid:17] ExecIf("OutgoingSpoolFailed", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
  505. -- Executing [s@macro-outbound-callerid:18] Set("OutgoingSpoolFailed", "CDR(outbound_cnum)=83912050398") in new stack
  506. -- Executing [s@macro-outbound-callerid:19] Set("OutgoingSpoolFailed", "CDR(outbound_cnam)=") in new stack
  507. -- Executing [s@macro-dialout-trunk:12] GosubIf("OutgoingSpoolFailed", "0?sub-flp-3,s,1()") in new stack
  508. -- Executing [s@macro-dialout-trunk:13] Set("OutgoingSpoolFailed", "OUTNUM=failed") in new stack
  509. -- Executing [s@macro-dialout-trunk:14] Set("OutgoingSpoolFailed", "custom=SIP/mobilon_out") in new stack
  510. -- Executing [s@macro-dialout-trunk:15] ExecIf("OutgoingSpoolFailed", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default))") in new stack
  511. -- Executing [s@macro-dialout-trunk:16] ExecIf("OutgoingSpoolFailed", "0?Set(DIAL_TRUNK_OPTIONS=M(confirm))") in new stack
  512. -- Executing [s@macro-dialout-trunk:17] Macro("OutgoingSpoolFailed", "dialout-trunk-predial-hook,") in new stack
  513. -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("OutgoingSpoolFailed", "") in new stack
  514. -- Executing [s@macro-dialout-trunk:18] GotoIf("OutgoingSpoolFailed", "0?bypass,1") in new stack
  515. -- Executing [s@macro-dialout-trunk:19] ExecIf("OutgoingSpoolFailed", "0?Set(CONNECTEDLINE(num,i)=failed)") in new stack
  516. -- Executing [s@macro-dialout-trunk:20] ExecIf("OutgoingSpoolFailed", "0?Set(CONNECTEDLINE(name,i)=CID:83912050398)") in new stack
  517. -- Executing [s@macro-dialout-trunk:21] GotoIf("OutgoingSpoolFailed", "0?customtrunk") in new stack
  518. -- Executing [s@macro-dialout-trunk:22] Dial("OutgoingSpoolFailed", "SIP/mobilon_out/failed,300,") in new stack
  519. [2016-07-11 13:43:04] NOTICE[18893]: chan_sip.c:28201 sip_request_call: Asked to get a channel of unsupported format (nothing) while capability is (gsm|ulaw|alaw)
  520. [2016-07-11 13:43:04] NOTICE[18893]: chan_sip.c:28201 sip_request_call: Asked to get a channel of unsupported format (nothing) while capability is (gsm|ulaw|alaw)
  521. [2016-07-11 13:43:04] WARNING[18893]: app_dial.c:2345 dial_exec_full: Unable to create channel of type 'SIP' (cause 58 - Bearer capability not available)
  522. [2016-07-11 13:43:04] WARNING[18893]: app_dial.c:2345 dial_exec_full: Unable to create channel of type 'SIP' (cause 58 - Bearer capability not available)
  523. == Everyone is busy/congested at this time (1:0/0/1)
  524. -- Executing [s@macro-dialout-trunk:23] NoOp("OutgoingSpoolFailed", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 58") in new stack
  525. -- Executing [s@macro-dialout-trunk:24] GotoIf("OutgoingSpoolFailed", "0?continue,1:s-CHANUNAVAIL,1") in new stack
  526. -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
  527. -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("OutgoingSpoolFailed", "RC=58") in new stack
  528. -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("OutgoingSpoolFailed", "58,1") in new stack
  529. -- Goto (macro-dialout-trunk,58,1)
  530. -- Executing [58@macro-dialout-trunk:1] Goto("OutgoingSpoolFailed", "continue,1") in new stack
  531. -- Goto (macro-dialout-trunk,continue,1)
  532. -- Executing [continue@macro-dialout-trunk:1] NoOp("OutgoingSpoolFailed", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 58 - failing through to other trunks") in new stack
  533. -- Executing [continue@macro-dialout-trunk:2] Set("OutgoingSpoolFailed", "CALLERID(number)=") in new stack
  534. -- Executing [failed@from-internal:7] Macro("OutgoingSpoolFailed", "outisbusy,") in new stack
  535. -- Executing [s@macro-outisbusy:1] Progress("OutgoingSpoolFailed", "") in new stack
  536. -- Executing [s@macro-outisbusy:2] GotoIf("OutgoingSpoolFailed", "0?emergency,1") in new stack
  537. -- Executing [s@macro-outisbusy:3] GotoIf("OutgoingSpoolFailed", "0?intracompany,1") in new stack
  538. -- Executing [s@macro-outisbusy:4] Playback("OutgoingSpoolFailed", "all-circuits-busy-now&pls-try-call-later, noanswer") in new stack
  539. [2016-07-11 13:43:04] WARNING[18893]: channel.c:5300 set_format: Unable to find a codec translation path from (nothing) to (gsm|ulaw|alaw)
  540. [2016-07-11 13:43:04] WARNING[18893]: channel.c:5300 set_format: Unable to find a codec translation path from (nothing) to (gsm|ulaw|alaw)
  541. [2016-07-11 13:43:04] WARNING[18893]: file.c:1017 ast_streamfile: Unable to open all-circuits-busy-now (format (nothing)): Function not implemented
  542. [2016-07-11 13:43:04] WARNING[18893]: file.c:1017 ast_streamfile: Unable to open all-circuits-busy-now (format (nothing)): Function not implemented
  543. [2016-07-11 13:43:04] WARNING[18893]: app_playback.c:480 playback_exec: ast_streamfile failed on OutgoingSpoolFailed for all-circuits-busy-now&pls-try-call-later, noanswer
  544. [2016-07-11 13:43:04] WARNING[18893]: app_playback.c:480 playback_exec: ast_streamfile failed on OutgoingSpoolFailed for all-circuits-busy-now&pls-try-call-later, noanswer
  545. [2016-07-11 13:43:04] WARNING[18893]: channel.c:5300 set_format: Unable to find a codec translation path from (nothing) to (gsm|ulaw|alaw)
  546. [2016-07-11 13:43:04] WARNING[18893]: channel.c:5300 set_format: Unable to find a codec translation path from (nothing) to (gsm|ulaw|alaw)
  547. [2016-07-11 13:43:04] WARNING[18893]: file.c:1017 ast_streamfile: Unable to open pls-try-call-later (format (nothing)): Function not implemented
  548. [2016-07-11 13:43:04] WARNING[18893]: file.c:1017 ast_streamfile: Unable to open pls-try-call-later (format (nothing)): Function not implemented
  549. [2016-07-11 13:43:04] WARNING[18893]: app_playback.c:480 playback_exec: ast_streamfile failed on OutgoingSpoolFailed for all-circuits-busy-now&pls-try-call-later, noanswer
  550. [2016-07-11 13:43:04] WARNING[18893]: app_playback.c:480 playback_exec: ast_streamfile failed on OutgoingSpoolFailed for all-circuits-busy-now&pls-try-call-later, noanswer
  551. -- Executing [s@macro-outisbusy:5] Congestion("OutgoingSpoolFailed", "20") in new stack
  552. [2016-07-11 13:43:04] WARNING[18893]: channel.c:5300 set_format: Unable to find a codec translation path from (nothing) to (slin)
  553. [2016-07-11 13:43:04] WARNING[18893]: channel.c:5300 set_format: Unable to find a codec translation path from (nothing) to (slin)
  554. [2016-07-11 13:43:04] WARNING[18893]: indications.c:157 playtones_alloc: Unable to set 'OutgoingSpoolFailed' to signed linear format (write)
  555. [2016-07-11 13:43:04] WARNING[18893]: indications.c:157 playtones_alloc: Unable to set 'OutgoingSpoolFailed' to signed linear format (write)
  556. [2016-07-11 13:43:04] WARNING[18893]: channel.c:4827 ast_prod: Prodding channel 'OutgoingSpoolFailed' failed
  557. [2016-07-11 13:43:04] WARNING[18893]: channel.c:4827 ast_prod: Prodding channel 'OutgoingSpoolFailed' failed
  558. [2016-07-11 13:43:04] WARNING[18893]: channel.c:4641 ast_indicate_data: Unable to handle indication 8 for 'OutgoingSpoolFailed'
  559. [2016-07-11 13:43:04] WARNING[18893]: channel.c:4641 ast_indicate_data: Unable to handle indication 8 for 'OutgoingSpoolFailed'
  560. [2016-07-11 13:43:04] WARNING[18893]: channel.c:5300 set_format: Unable to find a codec translation path from (nothing) to (slin)
  561. [2016-07-11 13:43:04] WARNING[18893]: channel.c:5300 set_format: Unable to find a codec translation path from (nothing) to (slin)
  562. [2016-07-11 13:43:04] ERROR[18893]: channel.c:8445 ast_channel_start_silence_generator: Could not set write format to SLINEAR
  563. [2016-07-11 13:43:04] ERROR[18893]: channel.c:8445 ast_channel_start_silence_generator: Could not set write format to SLINEAR
  564. localhost*CLI>
  565. Disconnected from Asterisk server
  566. Executing last minute cleanups
  567. [root@localhost agi-bin]#