[Problem] Problem Softphone: Retransmission timeout reached on transmission

Philipp971

Neuer User
Mitglied seit
3 Sep 2019
Beiträge
79
Punkte für Reaktionen
1
Punkte
8
Hallo Zusammen!

Ich stehe vor einer kuriosen Problematik auf welche ich nicht im Ansatz einen Lösungsweg weiß.
Es handelt sich um FreePBX14 mit Asterisk16 - angeschlossen an einen funktionierenden Sipgate-Trunk mit ein paar Nebenstellen (Softphones und ein Hardphone) in meiner Testumgebung.

Nun ist es so dass ALLE Anrufe, rausgehend, reingehend und allg. intern mit dem Hardphone (SNOMD345) problemlos durchgeführt werden können.
Mit einem Softphone, egal ob PhonerLite, Zoiper (Smartphone Zoiper) etc. hängt sich der Call nach 6 Sekunden auf. Die Verbindung wird zwar aufgebaut, zum Beispiel zum DNOM-Telefon, aber sobald man den Anruf annimmt, wird kein Ton übertragen und die Verbindung nur 6 Sekunden lang gehalten, danach wird aufgelegt. Es ist keine Firewall dazwischen, nichts besonderes konfiguriert, es funktionierte bis zu einem bestimmten Zeitpunkt vollkommen problemlos.

Interessant ist die Zeile:
Code:
Retransmission timeout reached on transmission [email protected] for seqno 13 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 6401ms with no response
[2019-10-25 12:39:54] WARNING[9276] chan_sip.c: Hanging up call [email protected] - no reply to our critical packet (see https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions)

Daraufin habe ich mir die verlinkte Website angesehen - wüsste auf Anhieb aber leider auch nicht wie ich weiter verfahren soll - ich bin alles andere als versiert an der Stelle.
Interessant ist, das es eben nur Calls VOM Softphone betrifft. Rufe ich das Softphone vom Hardphone aus an, wird der Call gehalten, ABER es werden nur Töne VOM Hardphone an das Softphone übertragen. Alles was vom Softphone kommt wird scheinbar verworfen. (?).

Hier der gesamte Code eines Anrufes VOM Softphone an das Hardphone:
Vielleicht könnt ihr mir hier ja einen Hinweis geben, wo ich meinen Lösungsansatz ansetzen kann. Nachvollziehen kann ich das leider nicht aus welchen Gründen es nur ein Softphone betrifft und alle anderen Call-Funktionalitäten (Hardphone etc.) problemlos agieren.

Code:
[2019-10-25 12:49:20] VERBOSE[9276][C-00000008] netsock2.c: Using SIP RTP TOS bits 184
[2019-10-25 12:49:20] VERBOSE[9276][C-00000008] netsock2.c: Using SIP RTP CoS mark 5
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [2466@from-internal:1] GotoIf("SIP/29-0000000e", "1?ext-local,2466,1:followme-check,2466,1") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx_builtins.c: Goto (ext-local,2466,1)
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [2466@ext-local:1] Set("SIP/29-0000000e", "__RINGTIMER=15") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [2466@ext-local:2] Macro("SIP/29-0000000e", "exten-vm,novm,2466,0,0,0") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:1] Macro("SIP/29-0000000e", "user-callerid,") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/29-0000000e", "TOUCH_MONITOR=1572000560.14") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/29-0000000e", "AMPUSER=29") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/29-0000000e", "0?report") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("SIP/29-0000000e", "1?Set(REALCALLERIDNUM=29)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/29-0000000e", "AMPUSER=29") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("SIP/29-0000000e", "0?limit") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/29-0000000e", "AMPUSERCIDNAME=Robin Hofmann") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:8] ExecIf("SIP/29-0000000e", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:9] GotoIf("SIP/29-0000000e", "0?report") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/29-0000000e", "AMPUSERCID=29") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:11] Set("SIP/29-0000000e", "__DIAL_OPTIONS=HhTtr") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/29-0000000e", "CALLERID(all)="Robin Hofmann" <29>") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("SIP/29-0000000e", "0?limit") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("SIP/29-0000000e", "0?Set(GROUP(concurrency_limit)=29)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:15] ExecIf("SIP/29-0000000e", "0?Set(CHANNEL(language)=)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:16] NoOp("SIP/29-0000000e", "Macro Depth is 2") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("SIP/29-0000000e", "1?report2:macroerror") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("SIP/29-0000000e", "0?continue") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("SIP/29-0000000e", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:20] Set("SIP/29-0000000e", "__TTL=64") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:21] GotoIf("SIP/29-0000000e", "1?continue") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:37] Set("SIP/29-0000000e", "CALLERID(number)=29") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:38] Set("SIP/29-0000000e", "CALLERID(name)=Robin Hofmann") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:39] GotoIf("SIP/29-0000000e", "0?cnum") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:40] Set("SIP/29-0000000e", "CDR(cnam)=Robin Hofmann") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:41] Set("SIP/29-0000000e", "CDR(cnum)=29") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-user-callerid:42] Set("SIP/29-0000000e", "CHANNEL(language)=de_DE") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:2] Set("SIP/29-0000000e", "RingGroupMethod=none") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:3] Set("SIP/29-0000000e", "__EXTTOCALL=2466") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:4] Set("SIP/29-0000000e", "__PICKUPMARK=2466") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:5] Set("SIP/29-0000000e", "RT=") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:6] ExecIf("SIP/29-0000000e", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:7] ExecIf("SIP/29-0000000e", "0?MacroExit()") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:8] ExecIf("SIP/29-0000000e", "0?Gosub(ext-intercom,*802466,1())") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:9] ExecIf("SIP/29-0000000e", "0?MacroExit()") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:10] ExecIf("SIP/29-0000000e", "0?ChanSpy(SIP/2466,q)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:11] ExecIf("SIP/29-0000000e", "0?MacroExit()") in new stack
[2019-10-25 12:49:20] ERROR[12005][C-00000008] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:12] ExecIf("SIP/29-0000000e", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2019-10-25 12:49:20] ERROR[12005][C-00000008] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2019-10-25 12:49:20] ERROR[12005][C-00000008] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:13] ExecIf("SIP/29-0000000e", "0?MacroExit()") in new stack
[2019-10-25 12:49:20] ERROR[12005][C-00000008] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2019-10-25 12:49:20] ERROR[12005][C-00000008] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:14] ExecIf("SIP/29-0000000e", "0?Gosub(ext-intercom,*802466,1())") in new stack
[2019-10-25 12:49:20] ERROR[12005][C-00000008] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2019-10-25 12:49:20] ERROR[12005][C-00000008] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:15] ExecIf("SIP/29-0000000e", "0?MacroExit()") in new stack
[2019-10-25 12:49:20] ERROR[12005][C-00000008] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2019-10-25 12:49:20] ERROR[12005][C-00000008] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:16] ExecIf("SIP/29-0000000e", "0?ChanSpy(SIP/2466,q)") in new stack
[2019-10-25 12:49:20] ERROR[12005][C-00000008] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2019-10-25 12:49:20] ERROR[12005][C-00000008] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:17] ExecIf("SIP/29-0000000e", "0?MacroExit()") in new stack
[2019-10-25 12:49:20] ERROR[12005][C-00000008] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:18] Gosub("SIP/29-0000000e", "sub-record-check,s,1(exten,2466,dontcare)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/29-0000000e", "0?initialized") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@sub-record-check:2] Set("SIP/29-0000000e", "__REC_STATUS=INITIALIZED") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@sub-record-check:3] Set("SIP/29-0000000e", "NOW=1572000560") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@sub-record-check:4] Set("SIP/29-0000000e", "__DAY=25") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@sub-record-check:5] Set("SIP/29-0000000e", "__MONTH=10") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@sub-record-check:6] Set("SIP/29-0000000e", "__YEAR=2019") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@sub-record-check:7] Set("SIP/29-0000000e", "__TIMESTR=20191025-124920") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@sub-record-check:8] Set("SIP/29-0000000e", "__FROMEXTEN=29") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@sub-record-check:9] Set("SIP/29-0000000e", "__MON_FMT=wav") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/29-0000000e", "Recordings initialized") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/29-0000000e", "0?Set(ARG3=dontcare)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@sub-record-check:12] Set("SIP/29-0000000e", "REC_POLICY_MODE_SAVE=") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/29-0000000e", "0?Set(REC_STATUS=NO)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/29-0000000e", "5?checkaction") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx_builtins.c: Goto (sub-record-check,s,17)
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/29-0000000e", "1?sub-record-check,exten,1") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/29-0000000e", "Exten Recording Check between 29 and 2466") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/29-0000000e", "CALLTYPE=internal") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/29-0000000e", "0?Set(CALLTYPE=)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/29-0000000e", "CALLEE=dontcare") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/29-0000000e", "0?Set(CALLEE=dontcare)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/29-0000000e", "0?callee") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [exten@sub-record-check:7] GotoIf("SIP/29-0000000e", "1?caller") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx_builtins.c: Goto (sub-record-check,exten,13)
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [exten@sub-record-check:13] Set("SIP/29-0000000e", "RECMODE=dontcare") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [exten@sub-record-check:14] ExecIf("SIP/29-0000000e", "0?Set(RECMODE=dontcare)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [exten@sub-record-check:15] ExecIf("SIP/29-0000000e", "1?Set(RECMODE=dontcare)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [exten@sub-record-check:16] Gosub("SIP/29-0000000e", "recordcheck,1(dontcare,internal,2466)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/29-0000000e", "Starting recording check against dontcare") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/29-0000000e", "dontcare") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/29-0000000e", "") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [exten@sub-record-check:17] Return("SIP/29-0000000e", "") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:19] GotoIf("SIP/29-0000000e", "1?macrodial") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx_builtins.c: Goto (macro-exten-vm,s,25)
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:25] GosubIf("SIP/29-0000000e", "0?clrheader,1()") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-exten-vm:26] Macro("SIP/29-0000000e", "dial-one,,HhTtr,2466") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:1] Set("SIP/29-0000000e", "DEXTEN=2466") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:2] Set("SIP/29-0000000e", "__CRM_SOURCE=29") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:3] ExecIf("SIP/29-0000000e", "0?Set(__EXTTOCALL=2466)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:4] Set("SIP/29-0000000e", "DIALSTATUS_CW=") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:5] GosubIf("SIP/29-0000000e", "0?screen,1()") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:6] GosubIf("SIP/29-0000000e", "0?cf,1()") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:7] GotoIf("SIP/29-0000000e", "1?skip1") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx_builtins.c: Goto (macro-dial-one,s,10)
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:10] GotoIf("SIP/29-0000000e", "0?nodial") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:11] GotoIf("SIP/29-0000000e", "0?continue") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:12] Set("SIP/29-0000000e", "EXTHASCW=ENABLED") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:13] GotoIf("SIP/29-0000000e", "0?next1:cwinusebusy") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx_builtins.c: Goto (macro-dial-one,s,25)
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:25] GotoIf("SIP/29-0000000e", "0?next3:continue") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx_builtins.c: Goto (macro-dial-one,s,27)
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:27] GotoIf("SIP/29-0000000e", "0?nodial") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:28] GosubIf("SIP/29-0000000e", "1?dstring,1():dlocal,1()") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [dstring@macro-dial-one:1] Set("SIP/29-0000000e", "DSTRING=") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [dstring@macro-dial-one:2] Set("SIP/29-0000000e", "DEVICES=2466") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("SIP/29-0000000e", "0?Return()") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("SIP/29-0000000e", "0?Set(DEVICES=466)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [dstring@macro-dial-one:5] Set("SIP/29-0000000e", "LOOPCNT=1") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [dstring@macro-dial-one:6] Set("SIP/29-0000000e", "ITER=1") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [dstring@macro-dial-one:7] Set("SIP/29-0000000e", "THISDIAL=SIP/2466") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("SIP/29-0000000e", "1?zap2dahdi,1()") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/29-0000000e", "0?Return()") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("SIP/29-0000000e", "NEWDIAL=") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("SIP/29-0000000e", "LOOPCNT2=1") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("SIP/29-0000000e", "ITER2=1") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("SIP/29-0000000e", "THISPART2=SIP/2466") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/29-0000000e", "0?Set(THISPART2=DAHDI/2466)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("SIP/29-0000000e", "NEWDIAL=SIP/2466&") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("SIP/29-0000000e", "ITER2=2") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/29-0000000e", "0?begin2") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("SIP/29-0000000e", "THISDIAL=SIP/2466") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("SIP/29-0000000e", "") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("SIP/29-0000000e", "1?docheck") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx_builtins.c: Goto (macro-dial-one,dstring,15)
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf("SIP/29-0000000e", "0?skipset") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [dstring@macro-dial-one:16] Set("SIP/29-0000000e", "DSTRING=SIP/2466&") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [dstring@macro-dial-one:17] Set("SIP/29-0000000e", "ITER=2") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf("SIP/29-0000000e", "0?begin") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf("SIP/29-0000000e", "0?Return()") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [dstring@macro-dial-one:20] Set("SIP/29-0000000e", "DSTRING=SIP/2466") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [dstring@macro-dial-one:21] Return("SIP/29-0000000e", "") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:29] GotoIf("SIP/29-0000000e", "0?nodial") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:30] GotoIf("SIP/29-0000000e", "0?skiptrace") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:31] GosubIf("SIP/29-0000000e", "1?ctset,1():ctclear,1()") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [ctset@macro-dial-one:1] Set("SIP/29-0000000e", "DB(CALLTRACE/2466)=29") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [ctset@macro-dial-one:2] Return("SIP/29-0000000e", "") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:32] Set("SIP/29-0000000e", "D_OPTIONS=HhTtr") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:33] GosubIf("SIP/29-0000000e", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:34] NoOp("SIP/29-0000000e", "Blind Transfer: , Attended Transfer: , User: 29, Alert Info: ") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:35] ExecIf("SIP/29-0000000e", "1?Set(ALERT_INFO=)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:36] ExecIf("SIP/29-0000000e", "0?Set(ALERT_INFO=)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:37] ExecIf("SIP/29-0000000e", "0?Set(ALERT_INFO=)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:38] ExecIf("SIP/29-0000000e", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:39] ExecIf("SIP/29-0000000e", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:40] GosubIf("SIP/29-0000000e", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:41] ExecIf("SIP/29-0000000e", "0?Set(CHANNEL(musicclass)=)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:42] GosubIf("SIP/29-0000000e", "0?qwait,1()") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:43] Set("SIP/29-0000000e", "__CWIGNORE=") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:44] Set("SIP/29-0000000e", "__KEEPCID=TRUE") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:45] GotoIf("SIP/29-0000000e", "0?usegoto,1") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:46] GotoIf("SIP/29-0000000e", "0?godial") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:47] Gosub("SIP/29-0000000e", "sub-presencestate-display,s,1(2466)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@sub-presencestate-display:1] Goto("SIP/29-0000000e", "state-not_set,1") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx_builtins.c: Goto (sub-presencestate-display,state-not_set,1)
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [state-not_set@sub-presencestate-display:1] Set("SIP/29-0000000e", "PRESENCESTATE_DISPLAY=") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [state-not_set@sub-presencestate-display:2] Return("SIP/29-0000000e", "") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:48] Set("SIP/29-0000000e", "CONNECTEDLINE(name,i)=Philipp Müller") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:49] Set("SIP/29-0000000e", "CONNECTEDLINE(num)=2466") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:50] Set("SIP/29-0000000e", "D_OPTIONS=HhTtrI") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:51] Macro("SIP/29-0000000e", "dialout-one-predial-hook,") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/29-0000000e", "") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:52] ExecIf("SIP/29-0000000e", "0?Set(D_OPTIONS=HhtrII)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:53] NoOp("SIP/29-0000000e", "") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:54] ExecIf("SIP/29-0000000e", "0?Set(D_OPTIONS=HhTtrIg)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-dial-one:55] Dial("SIP/29-0000000e", "SIP/2466,,HhTtrIb(func-apply-sipheaders^s^1)") in new stack
[2019-10-25 12:49:20] WARNING[12005][C-00000008] taskprocessor.c: The 'stasis/m:cache_pattern:1/channel:all-0000005f' task processor queue reached 500 scheduled tasks again.
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] netsock2.c: Using SIP RTP TOS bits 184
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] netsock2.c: Using SIP RTP CoS mark 5
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] app_stack.c: SIP/2466-0000000f Internal Gosub(func-apply-sipheaders,s,1) start
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/2466-0000000f", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/2466-0000000f", "Applying SIP Headers to channel SIP/2466-0000000f") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/2466-0000000f", "TECH=SIP") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/2466-0000000f", "SIPHEADERKEYS=") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/2466-0000000f", "0") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] app_while.c: Jumping to priority 12
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] pbx.c: Executing [s@func-apply-sipheaders:13] Return("SIP/2466-0000000f", "") in new stack
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] app_stack.c: Spawn extension (from-internal, 2466, 1) exited non-zero on 'SIP/2466-0000000f'
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] app_stack.c: SIP/2466-0000000f Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] app_dial.c: Called SIP/2466
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] app_dial.c: Connected line update to SIP/29-0000000e prevented.
[2019-10-25 12:49:20] VERBOSE[12005][C-00000008] app_dial.c: SIP/2466-0000000f is ringing
[2019-10-25 12:49:21] VERBOSE[12005][C-00000008] app_dial.c: SIP/2466-0000000f is ringing
[2019-10-25 12:49:22] VERBOSE[12005][C-00000008] app_dial.c: Connected line update to SIP/29-0000000e prevented.
[2019-10-25 12:49:22] VERBOSE[12005][C-00000008] app_dial.c: SIP/2466-0000000f answered SIP/29-0000000e
[2019-10-25 12:49:22] VERBOSE[12006][C-00000008] bridge_channel.c: Channel SIP/2466-0000000f joined 'simple_bridge' basic-bridge <9ff59844-59b4-4e5b-b29a-e7a0991e33d7>
[2019-10-25 12:49:22] VERBOSE[12005][C-00000008] bridge_channel.c: Channel SIP/29-0000000e joined 'simple_bridge' basic-bridge <9ff59844-59b4-4e5b-b29a-e7a0991e33d7>
[2019-10-25 12:49:28] WARNING[9276] chan_sip.c: Retransmission timeout reached on transmission dUzadLaafxz3Nhqu8nX-MQ.. for seqno 2 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 6400ms with no response
[2019-10-25 12:49:28] WARNING[9276] chan_sip.c: Hanging up call dUzadLaafxz3Nhqu8nX-MQ.. - no reply to our critical packet (see https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions).
[2019-10-25 12:49:28] VERBOSE[12005][C-00000008] bridge_channel.c: Channel SIP/29-0000000e left 'simple_bridge' basic-bridge <9ff59844-59b4-4e5b-b29a-e7a0991e33d7>
[2019-10-25 12:49:28] VERBOSE[12005][C-00000008] app_macro.c: Spawn extension (macro-dial-one, s, 55) exited non-zero on 'SIP/29-0000000e' in macro 'dial-one'
[2019-10-25 12:49:28] VERBOSE[12005][C-00000008] app_macro.c: Spawn extension (macro-exten-vm, s, 26) exited non-zero on 'SIP/29-0000000e' in macro 'exten-vm'
[2019-10-25 12:49:28] VERBOSE[12005][C-00000008] pbx.c: Spawn extension (ext-local, 2466, 2) exited non-zero on 'SIP/29-0000000e'
[2019-10-25 12:49:28] VERBOSE[12005][C-00000008] pbx.c: Executing [h@ext-local:1] Macro("SIP/29-0000000e", "hangupcall,") in new stack
[2019-10-25 12:49:28] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/29-0000000e", "1?theend") in new stack
[2019-10-25 12:49:28] VERBOSE[12005][C-00000008] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-10-25 12:49:28] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/29-0000000e", "0?Set(CDR(recordingfile)=)") in new stack
[2019-10-25 12:49:28] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/29-0000000e", "SIP/2466-0000000f montior file= ") in new stack
[2019-10-25 12:49:28] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/29-0000000e", "1?skipagi") in new stack
[2019-10-25 12:49:28] VERBOSE[12005][C-00000008] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2019-10-25 12:49:28] VERBOSE[12005][C-00000008] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/29-0000000e", "") in new stack
[2019-10-25 12:49:28] VERBOSE[12005][C-00000008] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/29-0000000e' in macro 'hangupcall'
[2019-10-25 12:49:28] VERBOSE[12005][C-00000008] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/29-0000000e'
[2019-10-25 12:49:28] VERBOSE[12006][C-00000008] bridge_channel.c: Channel SIP/2466-0000000f left 'simple_bridge' basic-bridge <9ff59844-59b4-4e5b-b29a-e7a0991e33d7>
 
Kannst Du auf dem dem Computer (mit dem Soft-Client) oder auf dem Asterisk mal Wireshark mitlaufen lassen?
 
  • Like
Reaktionen: Philipp971
Das kann ich nachher mal ausprobieren ja.
Habe das Tool bisher nicht wirklich genutzt, aber ich schaue nachher gleich mal.

Interessant ist, dass mit einer älteren Distro die Calls vom Softclient zum Hardphone problemlos funktionieren - hier gibt es keine Probleme.
Auf beiden Distros ist NAT aktiviert.
 
Wenn es mit einer älteren Version geklappt hat, ist um so mehr Paket-Analyse angesagt. Deine Vermutung ist, dass es ein neuer Software-Bug ist. Aber es kann auch sein, dass Du eine Funktionalität implizit angenommen hast, die in Wirklichkeit ein Software-Bug war. Wobei ich an Deiner Stelle überlegen würde, ob Du nicht die Zeit für einen Umstieg auf chan_pjsip oder FreeSWITCH freischaufeln kannst. chan_sip ist am Ende. Ich persönlich nutzte es nicht mit Asterisk 16 LTS sondern nur (noch) mit Asterisk 13 LTS.
Habe [Wirshark] bisher nicht wirklich genutzt […]
Wenn Fragen, einfach fragen. Ist am Anfang etwas viel, ich weiß. Aber Dein Fehler könnte auch auf jeder Ebene sein (UDP, SIP oder SDP).
 
  • Like
Reaktionen: Philipp971
Guten Morgen,

danke nochmal für deine weitere Nachfrage. :)
Ich habe am späten Nachmittag erfahren dass ein Kollege unseren Proxy geändert hat. Mit ihm habe ich es dann geklärt und der Fehler konnte somit auf die Umstellung 'geschoben' werden.

Ich habe auch schon überlegt auf chan_pjsip zu gehen, hatte aber zunächst die Anbindung des Trunks unter Asterisk16 bzw. FreePBX14 hinbekommen. Unter chan_sip habe ich die regulären peer Details als Kontext eingetragen. Unter PJSIP hab' ich zwar auch alles soweit in die Felder eingetragen, jedoch erfolgte keine Authentifizierung. Das probiere ich aber noch eimal aus dass ich den Trunk unter PJSIP einbinde - das muss ja funktionieren.

@sonyKatze - welchen VoiP-Provider nutzt Du?
 
Zuletzt bearbeitet:
Holen Sie sich 3CX - völlig kostenlos!
Verbinden Sie Ihr Team und Ihre Kunden Telefonie Livechat Videokonferenzen

Gehostet oder selbst-verwaltet. Für bis zu 10 Nutzer dauerhaft kostenlos. Keine Kreditkartendetails erforderlich. Ohne Risiko testen.

3CX
Für diese E-Mail-Adresse besteht bereits ein 3CX-Konto. Sie werden zum Kundenportal weitergeleitet, wo Sie sich anmelden oder Ihr Passwort zurücksetzen können, falls Sie dieses vergessen haben.