- 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:
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.
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>