[Gelöst] SNOM D865 - Keine ausgehenden Anrufe nach Firmware-Update mehr möglich

[Freggle]

Hallo liebe Community,

ich habe bei einem Kunden eine FreePBX Telefonanlage aufgestellt, die am Freitag noch hervorragend funktioniert hat.
Die "neuen" SNOM D865 hatten eine uralte Firmware aufgespielt, die für den Kunden selbstverständlich nicht akzeptiert wurde, da die Übersetzungen erst zu knapp 20% vorhanden war.
Gestern habe ich nun alle Geräte mit der aktuellen Firmware 10.1.169.1 bespielt, was echt gut funktionierte.
Komischerweise kann ich seitdem allerdings keine ausgehenden Anrufe mehr tätigen.
Der Fehler muss in den Telefoneinstellungen stecken, da ich mit einem SIP Softphone auf meinem Smartphone nach außen telefonieren kann.
Eingehende Anrufe funktionieren sowohl auf alle Klingelgruppen, als auch auf jede Nebenstelle.

Ich bin mit meinem Latein am Ende und hoffe auf erfahrende User, die mir hier Lösungsansätze bieten können.

Vielen Dank im Voraus und bleibt alle gesund!

 

[chrsto]

und hoffe auf erfahrende User

Solche User zweifeln die Existenz von Glaskugeln an und nutzen in der Regel Log Dateien und Mitschnitte des SIP Verkehrs.

 

[Freggle]

[Edit Novize: Überflüssiges Fullquote des Beitrags direkt darüber gelöscht - siehe Forumsregeln]
Es hätte ja sein können, dass jemand vor diesem Problem stand und es durch einen Parameter gelöst hat.
Das Log des Telefons bzw. FreePBX/Asterisk reiche ich gerne nach und editiere.

 

[Tippfehler]

Ich hatte mit einem Snom D385 ein vielleicht ähnliches Problem. Alle Eingaben waren richtig, aber es gab bei Anrufen falsche Anzeigen im Display. Die Daten hatte ich mehrmals neu eingegeben und auch ein ursprünglich funktionierendes Backup zurück gelesen. Es wurden testweise auch zwei Telefone vergeblich gegeneinander getauscht. Erst nachdem ich das Sip-Konto-Passwort einmal absichtlich falsch und dann nochmal richtig eingegeben hatte, wurde die Einstellung richtig übernommen und alles funktionierte wieder, wie es sollte. Ob es am Snom oder am Asterisk lag, der weiß ich nicht.

 

[Freggle]

@Tippfehler:
Habe deinen Vorschlag mal zu herzen genommen und mich versucht mit einem falschen Passwort zu registrieren, dann das korrekte Passwort zur Neuregistrierung angegeben. Auch das hat leider nichts genützt.

Danach habe ich die Identität komplett gelöscht und neu angelegt. Selbes Ergebnis.

Deswegen hier die gewünschten Logs:

SNOM 865:

Spoiler

Sent to Udp:192.168.0.5:5060 from Udp:192.168.0.11:51023 at Jan 29 19:12:29.948 (1507 bytes):

INVITE sip:05215573****@192.168.0.5;user=phone SIP/2.0Via: SIP/2.0/UDP 192.168.0.11:51023;branch=z9hG4bK-18jcz3a0a9qs;rportFrom: "xyz" <sip:[email protected]>;tag=z2lqh6vpi4To: <sip:05215573****@192.168.0.5;user=phone>Call-ID: 8deab765adde-yb95d28wi3iqCSeq: 2 INVITEMax-Forwards: 70User-Agent: snomD865/10.1.169.13Contact: "xyz" <sip:[email protected]:51023;line=2acixov6>;reg-id=1X-Serialnumber: 000413C10D0BAccept: application/sdpAllow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATEAllow-Events: talk, hold, refer, call-infoSupported: timer, 100rel, replaces, from-changeSession-Expires: 3600Min-SE: 90Authorization: Digest username="13",realm="asterisk",nonce="1706551950/9962b596902d874938b0b4b7ec553a86",uri="sip:05215573****@192.168.0.5;user=phone",qop=auth,nc=00000001,cnonce="58d0aa73",response="4369898559c59a170840788e5b5bc2a8",opaque="62192be313284f29",algorithm=MD5Content-Type: application/sdpContent-Length: 485v=0o=root 276382914 276382914 IN IP4 192.168.0.11s=callc=IN IP4 192.168.0.11t=0 0m=audio 55702 RTP/AVP 0 8 3 99 111 18 9 101a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:wMDf22VlgOd1E7/ZH6BoLGINRmJOpGP8sDt7aw5pa=rtpmap:0 PCMU/8000a=rtpmap:8 PCMA/8000a=rtpmap:3 GSM/8000a=rtpmap:99 G726-32/8000a=rtpmap:111 AAL2-G726-32/8000a=rtpmap:18 G729/8000a=fmtp:18 annexb=noa=rtpmap:9 G722/8000a=rtpmap:101 telephone-event/8000a=fmtp:101 0-15a=ptime:20a=sendrecv

---

Received from Udp:192.168.0.5:5060 on Udp:192.168.0.11:51023 at Jan 29 19:12:29.951 (331 bytes):

SIP/2.0 100 TryingVia: SIP/2.0/UDP 192.168.0.11:51023;rport=51023;received=192.168.0.11;branch=z9hG4bK-18jcz3a0a9qsCall-ID: 8deab765adde-yb95d28wi3iqFrom: "xyz" <sip:[email protected]>;tag=z2lqh6vpi4To: <sip:05215573****@192.168.0.5;user=phone>CSeq: 2 INVITEServer: FPBX-16.0.40.7(18.16.0)Content-Length: 0

---

Received from Udp:192.168.0.5:5060 on Udp:192.168.0.11:51023 at Jan 29 19:12:30.337 (893 bytes):

SIP/2.0 183 Session ProgressVia: SIP/2.0/UDP 192.168.0.11:51023;rport=51023;received=192.168.0.11;branch=z9hG4bK-18jcz3a0a9qsCall-ID: 8deab765adde-yb95d28wi3iqFrom: "xyz" <sip:[email protected]>;tag=z2lqh6vpi4To: <sip:05215573****@192.168.0.5;user=phone>;tag=39748f30-5282-4425-b3b6-9a59e26a87b9CSeq: 2 INVITEServer: FPBX-16.0.40.7(18.16.0)Contact: <sip:192.168.0.5:5060>Allow: OPTIONS, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, MESSAGE, REFERContent-Type: application/sdpContent-Length: 333v=0o=- 276382914 276382916 IN IP4 192.168.0.5s=Asteriskc=IN IP4 192.168.0.5t=0 0m=audio 12758 RTP/AVP 8 0 3 9 99 101a=rtpmap:8 PCMA/8000a=rtpmap:0 PCMU/8000a=rtpmap:3 GSM/8000a=rtpmap:9 G722/8000a=rtpmap:99 G726-32/8000a=rtpmap:101 telephone-event/8000a=fmtp:101 0-16a=ptime:20a=maxptime:150a=sendrecv

---

Sent to Udp:192.168.0.5:5060 from Udp:192.168.0.11:51023 at Jan 29 19:12:37.449 (689 bytes):

CANCEL sip:05215573****@192.168.0.5;user=phone SIP/2.0Via: SIP/2.0/UDP 192.168.0.11:51023;branch=z9hG4bK-18jcz3a0a9qs;rportFrom: "xyz" <sip:[email protected]>;tag=z2lqh6vpi4To: <sip:05215573****@192.168.0.5;user=phone>Call-ID: 8deab765adde-yb95d28wi3iqCSeq: 2 CANCELMax-Forwards: 70User-Agent: snomD865/10.1.169.13Reason: SIP;cause=487;text="Request terminated by user"Authorization: Digest username="13",realm="asterisk",nonce="1706551950/9962b596902d874938b0b4b7ec553a86",uri="sip:05215573****@192.168.0.5;user=phone",qop=auth,nc=00000002,cnonce="58d0aa73",response="8bbe9ce3f37047af08a4b1fa9d5a0166",opaque="62192be313284f29",algorithm=MD5Content-Length: 0

---

Received from Udp:192.168.0.5:5060 on Udp:192.168.0.11:51023 at Jan 29 19:12:37.855 (368 bytes):

SIP/2.0 200 OKVia: SIP/2.0/UDP 192.168.0.11:51023;rport=51023;received=192.168.0.11;branch=z9hG4bK-18jcz3a0a9qsCall-ID: 8deab765adde-yb95d28wi3iqFrom: "xyz" <sip:[email protected]>;tag=z2lqh6vpi4To: <sip:05215573****@192.168.0.5;user=phone>;tag=39748f30-5282-4425-b3b6-9a59e26a87b9CSeq: 2 CANCELServer: FPBX-16.0.40.7(18.16.0)Content-Length: 0

---

Received from Udp:192.168.0.5:5060 on Udp:192.168.0.11:51023 at Jan 29 19:12:37.878 (495 bytes):

SIP/2.0 487 Request TerminatedVia: SIP/2.0/UDP 192.168.0.11:51023;rport=51023;received=192.168.0.11;branch=z9hG4bK-18jcz3a0a9qsCall-ID: 8deab765adde-yb95d28wi3iqFrom: "xyz" <sip:[email protected]>;tag=z2lqh6vpi4To: <sip:05215573****@192.168.0.5;user=phone>;tag=39748f30-5282-4425-b3b6-9a59e26a87b9CSeq: 2 INVITEServer: FPBX-16.0.40.7(18.16.0)Allow: OPTIONS, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, MESSAGE, REFERContent-Length: 0

---

Sent to Udp:192.168.0.5:5060 from Udp:192.168.0.11:51023 at Jan 29 19:12:37.883 (743 bytes):

FreePBX/Asterisk

Spoiler

27423[2024-01-29 19:12:30] VERBOSE[6772] netsock2.c: Using SIP RTP Audio TOS bits 184
27424[2024-01-29 19:12:30] VERBOSE[6772] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
27425[2024-01-29 19:12:30] VERBOSE[6772] netsock2.c: Using SIP RTP Audio CoS mark 5
27426[2024-01-29 19:12:30] VERBOSE[3130][C-00000071] pbx.c: Executing [05215573****@from-internal:1] ResetCDR("PJSIP/13-0000010d", "") in new stack
27427[2024-01-29 19:12:30] VERBOSE[3130][C-00000071] pbx.c: Executing [05215573****@from-internal:2] NoCDR("PJSIP/13-0000010d", "") in new stack
27428[2024-01-29 19:12:30] VERBOSE[3130][C-00000071] pbx.c: Executing [05215573****@from-internal:3] Progress("PJSIP/13-0000010d", "") in new stack
27429[2024-01-29 19:12:30] VERBOSE[3130][C-00000071] pbx.c: Executing [05215573****@from-internal:4] Wait("PJSIP/13-0000010d", "1") in new stack
27430[2024-01-29 19:12:30] VERBOSE[31909] res_rtp_asterisk.c: 0x7face82b0600 -- Strict RTP learning after remote address set to: 192.168.0.11:55702
27431[2024-01-29 19:12:30] VERBOSE[3130][C-00000071] res_rtp_asterisk.c: 0x7face82b0600 -- Strict RTP switching to RTP target address 192.168.0.11:55702 as source
27432[2024-01-29 19:12:31] VERBOSE[3130][C-00000071] pbx.c: Executing [05215573****@from-internal:5] Playback("PJSIP/13-0000010d", "silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer") in new stack
27433[2024-01-29 19:12:31] VERBOSE[3130][C-00000071] file.c: <PJSIP/13-0000010d> Playing 'silence/1.ulaw' (language 'de_DE')
27434[2024-01-29 19:12:32] VERBOSE[3130][C-00000071] file.c: <PJSIP/13-0000010d> Playing 'cannot-complete-as-dialed.ulaw' (language 'de_DE')
27435[2024-01-29 19:12:35] VERBOSE[3130][C-00000071] res_rtp_asterisk.c: 0x7face82b0600 -- Strict RTP learning complete - Locking on source address 192.168.0.11:55702
27436[2024-01-29 19:12:35] VERBOSE[3130][C-00000071] file.c: <PJSIP/13-0000010d> Playing 'check-number-dial-again.ulaw' (language 'de_DE')
27437[2024-01-29 19:12:37] VERBOSE[3130][C-00000071] pbx.c: Executing [h@from-internal:1] Macro("PJSIP/13-0000010d", "hangupcall") in new stack
27438[2024-01-29 19:12:37] VERBOSE[3130][C-00000071] pbx.c: Executing [s@macro-hangupcall:1] Gosub("PJSIP/13-0000010d", "app-missedcall-hangup,s,1()") in new stack
27439[2024-01-29 19:12:37] VERBOSE[3130][C-00000071] pbx.c: Executing [s@app-missedcall-hangup:1] NoOp("PJSIP/13-0000010d", "Callee: s") in new stack
27440[2024-01-29 19:12:37] VERBOSE[3130][C-00000071] pbx.c: Executing [s@app-missedcall-hangup:2] NoOp("PJSIP/13-0000010d", "Caller: ") in new stack
27441[2024-01-29 19:12:37] VERBOSE[3130][C-00000071] pbx.c: Executing [s@app-missedcall-hangup:3] AGI("PJSIP/13-0000010d", "agi://127.0.0.1/missedcallnotify.php,,,s,0,,PJSIP/13-0000010d,,") in new stack
27442[2024-01-29 19:12:37] VERBOSE[3130][C-00000071] res_agi.c: agi://127.0.0.1/missedcallnotify.php,,,s,0,,PJSIP/13-0000010d,,: As a minimum this script requires extension or 's' as argument
27443[2024-01-29 19:12:37] VERBOSE[3130][C-00000071] res_agi.c: <PJSIP/13-0000010d>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
27444[2024-01-29 19:12:37] VERBOSE[3130][C-00000071] pbx.c: Executing [s@app-missedcall-hangup:4] Return("PJSIP/13-0000010d", "") in new stack
27445[2024-01-29 19:12:37] VERBOSE[3130][C-00000071] pbx.c: Executing [s@macro-hangupcall:2] GotoIf("PJSIP/13-0000010d", "1?theend") in new stack
27446[2024-01-29 19:12:37] VERBOSE[3130][C-00000071] pbx_builtins.c: Goto (macro-hangupcall,s,4)
27447[2024-01-29 19:12:37] VERBOSE[3130][C-00000071] pbx.c: Executing [s@macro-hangupcall:4] ExecIf("PJSIP/13-0000010d", "0?Set(CDR(recordingfile)=)") in new stack
27448[2024-01-29 19:12:37] VERBOSE[3130][C-00000071] pbx.c: Executing [s@macro-hangupcall:5] Hangup("PJSIP/13-0000010d", "") in new stack
27449[2024-01-29 19:12:37] VERBOSE[3130][C-00000071] app_macro.c: Spawn extension (macro-hangupcall, s, 5) exited non-zero on 'PJSIP/13-0000010d' in macro 'hangupcall'
27450[2024-01-29 19:12:37] VERBOSE[3130][C-00000071] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/13-0000010d'

Ich hoffe, damit konnte ich etwas Licht in die Glaskugel bringen und hoffe weiterhin auf Hilfe.
Bin echt am Ende mit meinem Latein...

Viele Grüße und jetzt schon 1000 Dank!!

 

[Tippfehler]

Funktionieren interne Anrufe?

 

[Freggle]

ja, die funktionieren...
Habe den Fehler gefunden.
Manchmal sitzt der Fehler davor. Es war tatsächlich nicht das Telefon, sondern die FreePBX-Einstellungen.
Es gingen nur +49 Telefonnummern, nicht jedoch TelNummern mit Vorwahl/Rufnummer.

Irgendwie habe ich den Punkt in den Outbound-Routen gelöscht...
Danke dennoch, werde bestimmt mit neuen Herausforderungen auf Euch zukommen