D785 SiP: Timeout bei Nutzung FritzboxVPN

Ich nutze ein Snom D785 mit einer FreePBX. Bei der Installation waren beide im selben Subnetz. Die Konfiguration ist fehlerfrei. Das Telefon reagiert auf Incoming und outgoing Calls. Nun ist die FreePBX an einen anderen Standort gezogen. Seid dem, hat das Snom d785 Probleme bei outgoing Calls. Anrufen und telefonieren kann man das Telefon ohne Probleme.

Die VPN Strecke ist grundsätzlich ok. Ich kann mit der App Groundwire auf dem iPhone jederzeit die Telefonanlage erreichen und auch das Snom d785 erreichen. 

Ich habe daher das Syslog des Telefons mir angesehen und habe dort das Timeout Problem zur FreePBX (10.10.12.74) gefunden, wenn ich wähle.

Nur ein paar Minuten später war es kein Problem mit dem d785 wieder zu wählen.

Da keine Änderungen an der VPN vorgenommen wurden und ich immer dann mit der App Groundwire wählen kann, wenn das Snom d785 wieder einmal nicht wählen will, schließe ich auf ein Konfigurationsfehler des d785. 

Ich hatte auch parallel ein Snom M400 mit zwei Snom M70 im Einsatz. Diese hatten keine Probleme mit der VPN Leitung und konnten, wie auch die App Groundwire die FreePBX immer erreichen.

Hat hier irgend jemand eine Idee? Die Situation ist frustrierend.

Syslog des d785:

2024-07-06T14:52:15.850+02:00 10.10.10.126 0004139B9614 [FATAL ] SIP: sip_transport_state_cb context lost

2024-07-06T14:52:15.851+02:00 10.10.10.126 0004139B9614 [ERROR ] PHN: No reply for naptr lookup

2024-07-06T14:52:15.855+02:00 10.10.10.126 0004139B9614 [ERROR ] PHN: No reply for srv lookup

2024-07-06T14:52:15.857+02:00 10.10.10.126 0004139B9614 [ERROR ] PHN: No reply for srv lookup

2024-07-06T14:52:15.859+02:00 10.10.10.126 0004139B9614 [ERROR ] PHN: No reply for srv lookup

2024-07-06T14:52:15.901+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: process auth: Match challenge for user=32, realm=asterisk

2024-07-06T14:52:15.934+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: Registration Metrics failed

2024-07-06T14:52:18.847+02:00 10.10.10.126 0004139B9614 [NOTICE] PHN: TPL: Socket 54 idle/connect timeout

2024-07-06T14:52:24.700+02:00 10.10.10.126 0004139B9614 [WARN  ] SIP: sip::ProcessAuthDigest: 401 needs 128 bit nonce

2024-07-06T14:52:24.700+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: process auth: Match challenge for user=snom-d785, realm=fritz.box

2024-07-06T14:52:28.239+02:00 10.10.10.126 0004139B9614 [NOTICE] CSTA: csta_cid = 5#012phone_cid5#012

2024-07-06T14:52:28.295+02:00 10.10.10.126 0004139B9614 [WARN  ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined

2024-07-06T14:52:28.658+02:00 10.10.10.126 0004139B9614 [WARN  ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined

2024-07-06T14:52:30.796+02:00 10.10.10.126 0004139B9614 [WARN  ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined

2024-07-06T14:53:02.990+02:00 10.10.10.126 0004139B9614 [WARN  ] SIP: transaction_timeout udp: 1000049 (32000ms)->Udp:10.10.12.74:5060

2024-07-06T14:53:02.990+02:00 10.10.10.126 0004139B9614 [ERROR ] SIP: transport error: 1000049 -> Udp:10.10.12.74:5060

2024-07-06T14:53:02.990+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: Add dirty host: Udp:10.10.12.74:5060 (0 sec)

2024-07-06T14:53:02.991+02:00 10.10.10.126 0004139B9614 [ERROR ] SIP: request 1000049 destination invalid Udp:10.10.12.74:5060 2c5a8966123b-l4qdcz1bdt1x

2024-07-06T14:53:02.991+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: final transport error: 1000049 -> Udp:10.10.12.74:5060

2024-07-06T14:53:03.093+02:00 10.10.10.126 0004139B9614 [ERROR ] SIP: transport error 1000049: generating fake 599

2024-07-06T14:53:03.150+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: MediaIpc::rtpClose: RP4

2024-07-06T14:53:03.150+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: MediaIpc::rtpClose: RC4

2024-07-06T14:53:03.151+02:00 10.10.10.126 0004139B9614 [ERROR ] MEDIA: Stream setup: invalid ssrc 3759288973, state off

2024-07-06T14:53:03.191+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: onRtpClose: RP4

2024-07-06T14:53:03.230+02:00 10.10.10.126 0004139B9614 [ERROR ] MEDIA: StopCall: failed to stop 3759288973

2024-07-06T14:53:03.230+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: onRtpClose: RC4

2024-07-06T14:53:08.040+02:00 10.10.10.126 0004139B9614 [NOTICE] PHN: Fetching URL: phone://mb_exit

2024-07-06T14:54:00.730+02:00 10.10.10.126 0004139B9614 [NOTICE] PHN: TPL: Socket 57 idle/connect timeout

2024-07-06T14:54:03.967+02:00 10.10.10.126 0004139B9614 [NOTICE] PHN: TPL: Socket 59 idle/connect timeout

2024-07-06T14:54:18.589+02:00 10.10.10.126 0004139B9614 [NOTICE] PHN: TPL: Socket 61 idle/connect timeout

2024-07-06T14:54:54.775+02:00 10.10.10.126 0004139B9614 [WARN  ] SIP: sip::ProcessAuthDigest: 401 needs 128 bit nonce

2024-07-06T14:54:54.775+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: process auth: Match challenge for user=snom-d785, realm=fritz.box

2024-07-06T14:57:24.843+02:00 10.10.10.126 0004139B9614 [WARN  ] SIP: sip::ProcessAuthDigest: 401 needs 128 bit nonce

2024-07-06T14:57:24.843+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: process auth: Match challenge for user=snom-d785, realm=fritz.box

2024-07-06T14:59:52.060+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: RTP: set_destination  adr=10.10.12.74:18350

2024-07-06T14:59:52.078+02:00 10.10.10.126 0004139B9614 [NOTICE] CSTA: csta_cid = -1#012phone_cid-1#012

2024-07-06T14:59:54.883+02:00 10.10.10.126 0004139B9614 [WARN  ] SIP: sip::ProcessAuthDigest: 401 needs 128 bit nonce

2024-07-06T14:59:54.883+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: process auth: Match challenge for user=snom-d785, realm=fritz.box

2024-07-06T15:00:00.354+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: RTP: set_destination RP5 adr=

2024-07-06T15:00:00.486+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: MediaIpc::rtpClose: RP5

2024-07-06T15:00:00.488+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: MediaIpc::rtpClose: RC5

2024-07-06T15:00:00.528+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: onRtpClose: RP5

2024-07-06T15:00:00.528+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: onRtpClose: RC5

2024-07-06T15:00:02.420+02:00 10.10.10.126 0004139B9614 [NOTICE] PHN: Fetching URL: phone://mb_exit

2024-07-06T15:02:24.936+02:00 10.10.10.126 0004139B9614 [WARN  ] SIP: sip::ProcessAuthDigest: 401 needs 128 bit nonce

2024-07-06T15:02:24.936+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: process auth: Match challenge for user=snom-d785, realm=fritz.box

2024-07-06T15:04:54.996+02:00 10.10.10.126 0004139B9614 [WARN  ] SIP: sip::ProcessAuthDigest: 401 needs 128 bit nonce

2024-07-06T15:04:54.996+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: process auth: Match challenge for user=snom-d785, realm=fritz.box

2024-07-06T15:05:18.135+02:00 10.10.10.126 0004139B9614 [NOTICE] CSTA: csta_cid = 6#012phone_cid6#012

2024-07-06T15:05:18.194+02:00 10.10.10.126 0004139B9614 [WARN  ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined

2024-07-06T15:05:18.577+02:00 10.10.10.126 0004139B9614 [WARN  ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined

2024-07-06T15:05:19.511+02:00 10.10.10.126 0004139B9614 [WARN  ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined

2024-07-06T15:05:20.589+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: process auth: Match challenge for user=32, realm=asterisk

2024-07-06T15:05:20.654+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: RTP: set_destination RP6 adr=10.10.12.74:19988

2024-07-06T15:05:20.713+02:00 10.10.10.126 0004139B9614 [WARN  ] MEDIA: CallStats: 2768770991 not found

2024-07-06T15:05:20.743+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: PlayCall ssrc:2768770991 active: 0 use new channel

2024-07-06T15:05:20.959+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: RTP: set_destination RP6 adr=10.10.12.74:19988

2024-07-06T15:05:21.310+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: RTP: set_destination  adr=10.10.10.1:7082

2024-07-06T15:05:21.311+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: RTP: set_rtcp_destination adr=10.10.10.1:7083

2024-07-06T15:05:21.349+02:00 10.10.10.126 0004139B9614 [NOTICE] CSTA: csta_cid = 6#012phone_cid6#012csta_cid = -2#012phone_cid-2#012

2024-07-06T15:05:25.768+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: MediaIpc::rtpClose: RP6

2024-07-06T15:05:25.769+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: MediaIpc::rtpClose: RC6

2024-07-06T15:05:26.021+02:00 10.10.10.126 0004139B9614 [NOTICE] SIP: RTP: set_destination RP7 adr=

2024-07-06T15:05:26.117+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: MediaIpc::rtpClose: RP7

2024-07-06T15:05:26.117+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: MediaIpc::rtpClose: RC7

2024-07-06T15:05:26.158+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: PlayCall ssrc:654321 active: 0 use new channel

2024-07-06T15:05:26.229+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: onRtpClose: RP6

2024-07-06T15:05:26.230+02:00 10.10.10.126 0004139B9614 [ERROR ] MEDIA: StopCall: failed to stop 2768770991

2024-07-06T15:05:26.231+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: onRtpClose: RC6

2024-07-06T15:05:26.302+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: onRtpClose: RP7

2024-07-06T15:05:26.303+02:00 10.10.10.126 0004139B9614 [NOTICE] MEDIA: onRtpClose: RC7

2024-07-06T15:05:28.070+02:00 10.10.10.126 0004139B9614 [NOTICE] PHN: Fetching URL: phone://mb_exit