I had configured my FreePBX with some extensions (SIP trunk) and everything worked fine, but some day my public IP had changed, and all extension were unable to log in. I used to clone a new FreePBX installation from a previous backup, now I can make calls but there is no audio and call drops automatically after 6-7 seconds.
On Asterisk logfile i get this warning:
WARNING[3207] chan_sip.c: Retransmission timeout reached on transmission 103104ZjZlNmJhYmQ2MzdkYTE0ZTVjMmYwMWI0ZTM3Y2ZlYWU for seqno 2 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
NAT configuration is set to NO, but I have tried all options and none worked.
Here is full asterisk log when placing a call:
6261 [2020-10-19 09:06:49] VERBOSE[3207][C-00000023] netsock2.c: Using SIP RTP TOS bits 184
6262 [2020-10-19 09:06:49] VERBOSE[3207][C-00000023] netsock2.c: Using SIP RTP CoS mark 5
6263 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [0676027933@from-internal:1] Macro("SIP/1003-0000002b", "user-callerid,LIMIT,EXTERNAL,") in new stack
6264 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/1003-0000002b", "TOUCH_MONITOR=1603098409.43") in new stack
6265 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/1003-0000002b", "AMPUSER=1003") in new stack
6266 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/1003-0000002b", "HOTDESCKCHAN=1003-0000002b") in new stack
6267 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/1003-0000002b", "HOTDESKEXTEN=1003") in new stack
6268 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/1003-0000002b", "HOTDESKCALL=0") in new stack
6269 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/1003-0000002b", "0?Set(HOTDESKCALL=1)") in new stack
6270 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/1003-0000002b", "0?Set(CALLERID(name)=)") in new stack
6271 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/1003-0000002b", "0?report") in new stack
6272 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/1003-0000002b", "1?Set(REALCALLERIDNUM=1003)") in new stack
6273 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/1003-0000002b", "AMPUSER=1003") in new stack
6274 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/1003-0000002b", "0?limit") in new stack
6275 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/1003-0000002b", "AMPUSERCIDNAME=1003") in new stack
6276 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/1003-0000002b", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
6277 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/1003-0000002b", "0?report") in new stack
6278 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/1003-0000002b", "AMPUSERCID=1003") in new stack
6279 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:16] Set("SIP/1003-0000002b", "__DIAL_OPTIONS=Ttr") in new stack
6280 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/1003-0000002b", "CALLERID(all)="1003" <1003>") in new stack
6281 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("SIP/1003-0000002b", "0?Set(CUSDIAL=)") in new stack
6282 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("SIP/1003-0000002b", "0?Set(CALLERID(all)="1003" <1003>)") in new stack
6283 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:20] GotoIf("SIP/1003-0000002b", "0?limit") in new stack
6284 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:21] ExecIf("SIP/1003-0000002b", "1?Set(GROUP(concurrency_limit)=1003)") in new stack
6285 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:22] ExecIf("SIP/1003-0000002b", "0?Set(CHANNEL(language)=)") in new stack
6286 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:23] NoOp("SIP/1003-0000002b", "Macro Depth is 1") in new stack
6287 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:24] GotoIf("SIP/1003-0000002b", "1?report2:macroerror") in new stack
6288 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx_builtins.c: Goto (macro-user-callerid,s,25)
6289 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("SIP/1003-0000002b", "1?continue") in new stack
6290 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx_builtins.c: Goto (macro-user-callerid,s,44)
6291 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:44] Set("SIP/1003-0000002b", "CALLERID(number)=1003") in new stack
6292 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:45] Set("SIP/1003-0000002b", "CALLERID(name)=1003") in new stack
6293 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:46] GotoIf("SIP/1003-0000002b", "0?cnum") in new stack
6294 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:47] Set("SIP/1003-0000002b", "CDR(cnam)=1003") in new stack
6295 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:48] Set("SIP/1003-0000002b", "CDR(cnum)=1003") in new stack
6296 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/1003-0000002b", "CHANNEL(language)=en") in new stack
6297 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [0676027933@from-internal:2] Gosub("SIP/1003-0000002b", "sub-record-check,s,1(out,0676027933,force)") in new stack
6298 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/1003-0000002b", "0?initialized") in new stack
6299 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-record-check:2] Set("SIP/1003-0000002b", "__REC_STATUS=INITIALIZED") in new stack
6300 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-record-check:3] Set("SIP/1003-0000002b", "NOW=1603098409") in new stack
6301 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-record-check:4] Set("SIP/1003-0000002b", "__DAY=19") in new stack
6302 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-record-check:5] Set("SIP/1003-0000002b", "__MONTH=10") in new stack
6303 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-record-check:6] Set("SIP/1003-0000002b", "__YEAR=2020") in new stack
6304 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-record-check:7] Set("SIP/1003-0000002b", "__TIMESTR=20201019-090649") in new stack
6305 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-record-check:8] Set("SIP/1003-0000002b", "__FROMEXTEN=1003") in new stack
6306 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-record-check:9] Set("SIP/1003-0000002b", "__MON_FMT=wav") in new stack
6307 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/1003-0000002b", "Recordings initialized") in new stack
6308 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/1003-0000002b", "0?Set(ARG3=dontcare)") in new stack
6309 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-record-check:12] Set("SIP/1003-0000002b", "REC_POLICY_MODE_SAVE=") in new stack
6310 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/1003-0000002b", "0?Set(REC_STATUS=NO)") in new stack
6311 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/1003-0000002b", "3?checkaction") in new stack
6312 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx_builtins.c: Goto (sub-record-check,s,17)
6313 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/1003-0000002b", "1?sub-record-check,out,1") in new stack
6314 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx_builtins.c: Goto (sub-record-check,out,1)
6315 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [out@sub-record-check:1] NoOp("SIP/1003-0000002b", "Outbound Recording Check from 1003 to 0676027933") in new stack
6316 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [out@sub-record-check:2] Set("SIP/1003-0000002b", "RECMODE=dontcare") in new stack
6317 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [out@sub-record-check:3] ExecIf("SIP/1003-0000002b", "1?Goto(routewins)") in new stack
6318 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx_builtins.c: Goto (sub-record-check,out,7)
6319 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [out@sub-record-check:7] Gosub("SIP/1003-0000002b", "recordcheck,1(force,out,0676027933)") in new stack
6320 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/1003-0000002b", "Starting recording check against force") in new stack
6321 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/1003-0000002b", "force") in new stack
6322 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx_builtins.c: Goto (sub-record-check,recordcheck,5)
6323 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [recordcheck@sub-record-check:5] Set("SIP/1003-0000002b", "__REC_POLICY_MODE=FORCE") in new stack
6324 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [recordcheck@sub-record-check:6] GotoIf("SIP/1003-0000002b", "1?startrec") in new stack
6325 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx_builtins.c: Goto (sub-record-check,recordcheck,16)
6326 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp("SIP/1003-0000002b", "Starting recording: out, 0676027933") in new stack
6327 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [recordcheck@sub-record-check:17] Set("SIP/1003-0000002b", "__CALLFILENAME=out-0676027933-1003-20201019-090649-1603098409.43") in new stack
6328 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [recordcheck@sub-record-check:18] MixMonitor("SIP/1003-0000002b", "2020/10/19/out-0676027933-1003-20201019-090649-1603098409.43.wav,abi(LOCAL_MIXMON_ID),") in new stack
6329 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [recordcheck@sub-record-check:19] Set("SIP/1003-0000002b", "__MIXMON_ID=0x7f4d980093c0") in new stack
6330 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [recordcheck@sub-record-check:20] Set("SIP/1003-0000002b", "__RECORD_ID=SIP/1003-0000002b") in new stack
6331 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [recordcheck@sub-record-check:21] Set("SIP/1003-0000002b", "__REC_STATUS=RECORDING") in new stack
6332 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [recordcheck@sub-record-check:22] Set("SIP/1003-0000002b", "CDR(recordingfile)=out-0676027933-1003-20201019-090649-1603098409.43.wav") in new stack
6333 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [recordcheck@sub-record-check:23] Return("SIP/1003-0000002b", "") in new stack
6334 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [out@sub-record-check:8] Return("SIP/1003-0000002b", "") in new stack
6335 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [0676027933@from-internal:3] ExecIf("SIP/1003-0000002b", "0 ?Set(CDR(accountcode)=)") in new stack
6336 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [0676027933@from-internal:4] Set("SIP/1003-0000002b", "_ROUTEID=1") in new stack
6337 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [0676027933@from-internal:5] Set("SIP/1003-0000002b", "_ROUTENAME=4_callout") in new stack
6338 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [0676027933@from-internal:6] Set("SIP/1003-0000002b", "INTRACOMPANYROUTE=YES") in new stack
6339 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [0676027933@from-internal:7] Set("SIP/1003-0000002b", "MOHCLASS=default") in new stack
6340 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [0676027933@from-internal:8] Set("SIP/1003-0000002b", "_CALLERIDNAMEINTERNAL=1003") in new stack
6341 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [0676027933@from-internal:9] Set("SIP/1003-0000002b", "_CALLERIDNUMINTERNAL=1003") in new stack
6342 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [0676027933@from-internal:10] Set("SIP/1003-0000002b", "_EMAILNOTIFICATION=FALSE") in new stack
6343 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [0676027933@from-internal:11] Set("SIP/1003-0000002b", "_NODEST=") in new stack
6344 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [0676027933@from-internal:12] Macro("SIP/1003-0000002b", "dialout-trunk,1,0676027933,,off") in new stack
6345 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:1] Set("SIP/1003-0000002b", "DIAL_TRUNK=1") in new stack
6346 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("SIP/1003-0000002b", "0?Set(DIAL_OPTIONS=tr)") in new stack
6347 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("SIP/1003-0000002b", "0?sub-pincheck,s,1()") in new stack
6348 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("SIP/1003-0000002b", "0?Set(CALLERID(num)=1003)") in new stack
6349 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("SIP/1003-0000002b", "0?disabletrunk,1") in new stack
6350 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:6] Set("SIP/1003-0000002b", "DIAL_NUMBER=0676027933") in new stack
6351 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:7] Set("SIP/1003-0000002b", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
6352 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:8] Set("SIP/1003-0000002b", "OUTBOUND_GROUP=OUT_1") in new stack
6353 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:9] Set("SIP/1003-0000002b", "DIAL_TRUNK_OPTIONS=T") in new stack
6354 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("SIP/1003-0000002b", "0?nomax") in new stack
6355 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf("SIP/1003-0000002b", "0?chanfull") in new stack
6356 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("SIP/1003-0000002b", "1?skipoutcid") in new stack
6357 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx_builtins.c: Goto (macro-dialout-trunk,s,14)
6358 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("SIP/1003-0000002b", "0?sub-flp-1,s,1()") in new stack
6359 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:15] Set("SIP/1003-0000002b", "OUTNUM=0676027933") in new stack
6360 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:16] Set("SIP/1003-0000002b", "custom=SIP/goip-02") in new stack
6361 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("SIP/1003-0000002b", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
6362 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("SIP/1003-0000002b", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
6363 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("SIP/1003-0000002b", "dialout-trunk-predial-hook,") in new stack
6364 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/1003-0000002b", "") in new stack
6365 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("SIP/1003-0000002b", "0?skipcrm") in new stack
6366 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:21] Set("SIP/1003-0000002b", "__CRM_DIRECTION=OUTBOUND") in new stack
6367 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:22] Set("SIP/1003-0000002b", "__CRM_DESTINATION=0676027933") in new stack
6368 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:23] Set("SIP/1003-0000002b", "__CRM_SOURCE=1003") in new stack
6369 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:24] AGI("SIP/1003-0000002b", "sangomacrm.agi") in new stack
6370 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
6371 [2020-10-19 09:06:49] VERBOSE[17885][C-00000023] app_mixmonitor.c: Begin MixMonitor Recording SIP/1003-0000002b
6372 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] res_agi.c: <SIP/1003-0000002b>AGI Script sangomacrm.agi completed, returning 0
6373 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:25] Set("SIP/1003-0000002b", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack
6374 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp("SIP/1003-0000002b", "CRM Finished") in new stack
6375 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf("SIP/1003-0000002b", "0?bypass,1") in new stack
6376 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf("SIP/1003-0000002b", "1?Set(CONNECTEDLINE(num,i)=0676027933)") in new stack
6377 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("SIP/1003-0000002b", "1?Set(CONNECTEDLINE(name,i)=CID:1003)") in new stack
6378 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("SIP/1003-0000002b", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)1003)") in new stack
6379 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("SIP/1003-0000002b", "0?customtrunk") in new stack
6380 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf("SIP/1003-0000002b", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
6381 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:33] Set("SIP/1003-0000002b", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack
6382 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-dialout-trunk:34] Dial("SIP/1003-0000002b", "SIP/goip-02/0676027933,300,Tb(func-apply-sipheaders^s^1,(1))U(sub-send-obroute-email^0676027933^0676027933^1^1603098409^1003^1003)") in new stack
6383 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] netsock2.c: Using SIP RTP TOS bits 184
6384 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] netsock2.c: Using SIP RTP CoS mark 5
6385 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] app_stack.c: SIP/goip-02-0000002c Internal Gosub(func-apply-sipheaders,s,1(1)) start
6386 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/goip-02-0000002c", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
6387 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/goip-02-0000002c", "Applying SIP Headers to channel SIP/goip-02-0000002c") in new stack
6388 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/goip-02-0000002c", "TECH=SIP") in new stack
6389 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/goip-02-0000002c", "SIPHEADERKEYS=Alert-Info") in new stack
6390 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/goip-02-0000002c", "1") in new stack
6391 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/goip-02-0000002c", "sipheader=unset") in new stack
6392 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("SIP/goip-02-0000002c", "1?SIPRemoveHeader(Alert-Info:)") in new stack
6393 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("SIP/goip-02-0000002c", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
6394 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("SIP/goip-02-0000002c", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack
6395 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("SIP/goip-02-0000002c", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack
6396 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("SIP/goip-02-0000002c", "0?SIPAddHeader(Alert-Info:unset)") in new stack
6397 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("SIP/goip-02-0000002c", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack
6398 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile("SIP/goip-02-0000002c", "") in new stack
6399 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/goip-02-0000002c", "0") in new stack
6400 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/goip-02-0000002c", "") in new stack
6401 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] app_stack.c: Spawn extension (from-trunk, 0676027933, 1) exited non-zero on 'SIP/goip-02-0000002c'
6402 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] app_stack.c: SIP/goip-02-0000002c Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL=
6403 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] app_dial.c: Called SIP/goip-02/0676027933
6404 [2020-10-19 09:06:49] VERBOSE[17883][C-00000023] app_dial.c: SIP/goip-02-0000002c is making progress passing it to SIP/1003-0000002b
6405 [2020-10-19 09:06:56] VERBOSE[17883][C-00000023] app_dial.c: SIP/goip-02-0000002c is making progress passing it to SIP/1003-0000002b
6406 [2020-10-19 09:07:02] VERBOSE[17883][C-00000023] app_dial.c: SIP/goip-02-0000002c answered SIP/1003-0000002b
6407 [2020-10-19 09:07:02] VERBOSE[17883][C-00000023] app_stack.c: SIP/goip-02-0000002c Internal Gosub(sub-send-obroute-email,s,1(0676027933,0676027933,1,1603098409,1003,1003)) start
6408 [2020-10-19 09:07:02] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-send-obroute-email:1] GotoIf("SIP/goip-02-0000002c", "0?sendEmail") in new stack
6409 [2020-10-19 09:07:02] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-send-obroute-email:2] NoOp("SIP/goip-02-0000002c", "email notifications disabled..exiting.") in new stack
6410 [2020-10-19 09:07:02] VERBOSE[17883][C-00000023] pbx.c: Executing [s@sub-send-obroute-email:3] Return("SIP/goip-02-0000002c", "") in new stack
6411 [2020-10-19 09:07:02] VERBOSE[17883][C-00000023] app_stack.c: Spawn extension (from-trunk, , 1) exited non-zero on 'SIP/goip-02-0000002c'
6412 [2020-10-19 09:07:02] VERBOSE[17883][C-00000023] app_stack.c: SIP/goip-02-0000002c Internal Gosub(sub-send-obroute-email,s,1(0676027933,0676027933,1,1603098409,1003,1003)) complete GOSUB_RETVAL=
6413 [2020-10-19 09:07:02] VERBOSE[17927][C-00000023] bridge_channel.c: Channel SIP/goip-02-0000002c joined 'simple_bridge' basic-bridge <29078156-18d6-4573-a00f-c79f0da1b1b4>
6414 [2020-10-19 09:07:02] VERBOSE[17883][C-00000023] bridge_channel.c: Channel SIP/1003-0000002b joined 'simple_bridge' basic-bridge <29078156-18d6-4573-a00f-c79f0da1b1b4>
6415 [2020-10-19 09:07:09] WARNING[3207] chan_sip.c: Retransmission timeout reached on transmission 103104NGU5ZDBkOWJkYzAzOTMyZmI2Mzg2Nzc5YzAwNmUzMzI for seqno 2 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
6416 Packet timed out after 6400ms with no response
6417 [2020-10-19 09:07:09] WARNING[3207] chan_sip.c: Hanging up call 103104NGU5ZDBkOWJkYzAzOTMyZmI2Mzg2Nzc5YzAwNmUzMzI - no reply to our critical packet (see https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions).
6418 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] bridge_channel.c: Channel SIP/1003-0000002b left 'simple_bridge' basic-bridge <29078156-18d6-4573-a00f-c79f0da1b1b4>
6419 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] app_macro.c: Spawn extension (macro-dialout-trunk, s, 34) exited non-zero on 'SIP/1003-0000002b' in macro 'dialout-trunk'
6420 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Spawn extension (from-internal, 0676027933, 12) exited non-zero on 'SIP/1003-0000002b'
6421 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Executing [h@from-internal:1] Macro("SIP/1003-0000002b", "hangupcall") in new stack
6422 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/1003-0000002b", "1?theend") in new stack
6423 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx_builtins.c: Goto (macro-hangupcall,s,3)
6424 [2020-10-19 09:07:09] VERBOSE[17927][C-00000023] bridge_channel.c: Channel SIP/goip-02-0000002c left 'simple_bridge' basic-bridge <29078156-18d6-4573-a00f-c79f0da1b1b4>
6425 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/1003-0000002b", "0?Set(CDR(recordingfile)=)") in new stack
6426 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/1003-0000002b", "SIP/goip-02-0000002c montior file= /var/spool/asterisk/monitor/2020/10/19/out-0676027933-1003-20201019-090649-1603098409.43.wav") in new stack
6427 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/1003-0000002b", "0?skipagi") in new stack
6428 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-hangupcall:6] AGI("SIP/1003-0000002b", "attendedtransfer-rec-restart.php,SIP/goip-02-0000002c,/var/spool/asterisk/monitor/2020/10/19/out-0676027933-1003-20201019-090649-1603098409.43.wav") in new stack
6429 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
6430 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] res_agi.c: <SIP/1003-0000002b>AGI Script attendedtransfer-rec-restart.php completed, returning 0
6431 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/1003-0000002b", "") in new stack
6432 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/1003-0000002b' in macro 'hangupcall'
6433 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/1003-0000002b'
6434 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] app_stack.c: SIP/1003-0000002b Internal Gosub(crm-hangup,s,1) start
6435 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/1003-0000002b", "Sending Hangup to CRM") in new stack
6436 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/1003-0000002b", "HANGUP CAUSE: 18") in new stack
6437 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/1003-0000002b", "0?Set(__CRM_VOICEMAIL=)") in new stack
6438 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/1003-0000002b", "MASTER CHANNEL: 1603098409.43 = 1603098409.43") in new stack
6439 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/1003-0000002b", "0?return") in new stack
6440 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Executing [s@crm-hangup:6] Set("SIP/1003-0000002b", "__CRM_HANGUP=1") in new stack
6441 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/1003-0000002b", "sangomacrm.agi") in new stack
6442 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
6443 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] res_agi.c: <SIP/1003-0000002b>AGI Script sangomacrm.agi completed, returning 0
6444 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] pbx.c: Executing [s@crm-hangup:8] Return("SIP/1003-0000002b", "") in new stack
6445 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/1003-0000002b'
6446 [2020-10-19 09:07:09] VERBOSE[17883][C-00000023] app_stack.c: SIP/1003-0000002b Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
6447 [2020-10-19 09:07:09] VERBOSE[17885][C-00000023] app_mixmonitor.c: MixMonitor close filestream (mixed)
6448 [2020-10-19 09:07:09] VERBOSE[17885][C-00000023] app_mixmonitor.c: End MixMonitor Recording SIP/1003-0000002b
I could not find what is missing from my configurations.
Any help please?