2 Probleme die möglicherweise das Selbe sind

Hallo,

D-385N

da das Telefon anscheinend einen nicht funktionierenden Update Link hinterlegt hat (autom. Updates schlugen fehl) habe ich unter Advanced Settings > Update > Update Policy «Never update, do not load settings» eingestellt, und das Update manuell ausgeführt.

Trotzdem, meist nach einem Telefonat, startete sich das Telefon automatisch neu, und anschliessend erschien die Meldung «SW Update failed». Trotzdem aber funktionierte es soweit, für ungefähr 3 Wochen.

Nun, gestern Nacht lief noch alles und heute früh, ohne irgendein Zutun, erschien die meldung, das Telefon konnte sich nicht verbinden, folglich ist meine Nummer nicht erreichbar und auch ich konnte nicht raustelefonieren. Möglicherweise hat das Telefon irgendwelche Einstellungen vergessen (die mir bekannten sind alle noch da).

FRAGEN:

Warum hält sich das Telefon nicht an die Einstellungen?

Was kann ein Update triggern trotz o.g. Einstellungen?

Welche Faktoren gibt es die trotz erfolgreicher Provisionierung, und obwohl ich bereits telefonieren konnte, plötzlich und ohne Zutun, ein Verbindungsproblem auslösen können?

Danke für die Unterstützung,

Christian


P.S.:

Die letzten Logs sind:

Oct 21 18:12:43.628 [DEBUG0] PHN: IPv4: Start Detection on eth0, IP 192.168.0.182
Oct 21 18:12:43.628 [DEBUG1] PHN: IPv4Monitor: Device eth0, IP 192.168.0.182, State detecting
Oct 21 18:12:43.628 [DEBUG2] PHN: LLDP: testNetworkPolicyIsSet, timeout=90, now=31, has_policy=0, timestamp=0, elapsed=31
Oct 21 18:12:43.628 [DEBUG1] PHN: IPv4Monitor: Device eth0, IP 192.168.0.182, State detect no conflict
Oct 21 18:12:43.629 [DEBUG0] PHN: IPv4: Start Defending on eth0, IP 192.168.0.182
Oct 21 18:12:43.629 [DEBUG1] PHN: IPv4Monitor: Device eth0, IP 192.168.0.182, State defend no conflict
Oct 21 18:12:43.629 [INFO  ] PHN: DHCP: t1=302400
Oct 21 18:12:43.629 [INFO  ] PHN: DHCP: t2=529200, reboot=604790
Oct 21 18:12:43.629 [NOTICE] PHN: Finished waiting for DHCP 4; d4.state = dhcp_finished
Oct 21 18:12:43.630 [FATAL ] PHN: No NTP server found. Using default: 192.53.103.104 pool.ntp.org
Oct 21 18:12:43.630 [NOTICE] PHN: ---------------------------------------------------------------------
Oct 21 18:12:43.630 [NOTICE] PHN: Clock changed from 2023-10-21 18:11:31.542 to 2023-10-21 18:12:24.949 after ntpdate
Oct 21 18:12:43.630 [NOTICE] PHN: ---------------------------------------------------------------------
Oct 21 18:12:43.630 [DEBUG0] PHN: MediaDaemon: start
Oct 21 18:12:43.630 [DEBUG0] PHN: MediaDaemon: child pid 603
Oct 21 18:12:43.631 [DEBUG2] PHN: LLDP: testNetworkPolicyIsSet, timeout=90, now=44, has_policy=0, timestamp=0, elapsed=44
Oct 21 18:12:43.631 [INFO  ] PHN: HandleWatchdog: status: 1 -> 1, timeout: 960, dog_fd: 5, setting set: 0 value: 
Oct 21 18:12:43.631 [INFO  ] PHN: Watchdog: new timing: 960
Oct 21 18:12:43.631 [DEBUG1] PHN: FbAppPrint during lcs2: snomD385-SIP 10.1.159.12
Oct 21 18:12:43.631 [DEBUG1] PHN: Connect host: 127.0.0.1
Oct 21 18:12:43.631 [DEBUG1] PHN: IPv4Monitor: Device eth0, IP 192.168.0.182, State device none
Oct 21 18:12:43.631 [DEBUG2] PHN: Get U-Boot version: Start search for U-Boot version
Oct 21 18:12:43.632 [DEBUG2] PHN: Get U-Boot version: Search for U-Boot version start 'U-Boot '
Oct 21 18:12:43.632 [DEBUG2] PHN: Get U-Boot version: Search for U-Boot version end ')'
Oct 21 18:12:43.632 [INFO  ] PHN: Phone::uboot_version:2010.12-00001-gd311851f1 (Feb 25 2019 - 14:17:02)
Oct 21 18:12:43.632 [DEBUG2] PHN: LLDP: testNetworkPolicyIsSet, timeout=90, now=54, has_policy=0, timestamp=0, elapsed=54
Oct 21 18:12:43.842 [DEBUG0] UXM: Request backlight for module -1 with value 15
Oct 21 18:12:43.871 [DEBUG1] PHN: Parsing color string >43 49 56 255<
Oct 21 18:12:43.871 [INFO  ] PHN: color parsed as: 43 49 56 255
Oct 21 18:12:43.872 [DEBUG1] PHN: Parsing color string >70 90 120 255<
Oct 21 18:12:43.872 [INFO  ] PHN: color parsed as: 70 90 120 255
Oct 21 18:12:43.908 [DEBUG1] PHN: Parsing color string >43 49 56 255<
Oct 21 18:12:43.908 [INFO  ] PHN: color parsed as: 43 49 56 255
Oct 21 18:12:43.985 [DEBUG1] PHN: Parsing color string >224 224 224 255<
Oct 21 18:12:43.985 [INFO  ] PHN: color parsed as: 224 224 224 255
Oct 21 18:12:43.991 [DEBUG1] PHN: Parsing color string >70 90 120 255<
Oct 21 18:12:43.991 [INFO  ] PHN: color parsed as: 70 90 120 255
Oct 21 18:12:43.991 [ERROR ] GUI: gui_display_imlib::flush: return because gui is disabled
Oct 21 18:12:44.622 [ERROR ] GUI: gui_display_imlib::flush: return because gui is disabled
Oct 21 18:12:44.966 [ERROR ] GUI: gui_display_imlib::flush: return because gui is disabled
Oct 21 18:12:45.078 [ERROR ] GUI: gui_display_imlib::flush: return because gui is disabled
Oct 21 18:12:45.089 [NOTICE] GUI: Display re-enabled.
Oct 21 18:12:45.489 [INFO  ] PHN: ETH LINK STATUS: changed first time, not yet to propagate. Link: -1
Oct 21 18:12:45.645 [NOTICE] PHN: Don't load settings from remote site.
Oct 21 18:12:45.645 [NOTICE] PHN: SetProvisioningDone
Oct 21 18:12:45.645 [DEBUG0] MEDIA: MediaIpc::setLogLevel:  4
Oct 21 18:14:26.286 [WARN  ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined
Oct 21 18:14:26.633 [WARN  ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined
Oct 21 18:14:31.248 [WARN  ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined
Oct 21 18:14:34.037 [WARN  ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined
Oct 21 18:14:35.278 [WARN  ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined
Oct 21 18:14:38.204 [WARN  ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined
Oct 21 18:14:39.977 [WARN  ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined
Oct 21 18:14:46.402 [WARN  ] MEDIA: CallStats: 3740628136 not found
Oct 21 18:14:46.404 [ERROR ] SIP: Response CallStats 192 not found
Oct 21 18:14:46.405 [ERROR ] MEDIA: Stream setup: invalid ssrc 3740628136, state off
Oct 21 18:14:46.445 [ERROR ] MEDIA: StopCall: failed to stop 3740628136
Oct 21 18:14:53.028 [WARN  ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined
Oct 21 18:15:08.606 [WARN  ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined
Oct 21 18:15:08.942 [ERROR ] GUI: if_unreach(4, Not Found, ) invalid id
Oct 21 18:31:47.707 [ERROR ] PHN: TPL: Socket Error: 30/-1/deleted, poll data_cb -> No such file or directory (2)
Oct 21 18:31:50.496 [ERROR ] PHN: TPL: Socket Error: 31/-1/deleted, poll data_cb -> No such file or directory (2)
Oct 21 18:50:50.168 [ERROR ] PHN: TPL: Socket Error: 33/-1/deleted, poll data_cb -> No such file or directory (2)

profilePicture

Christian Sedlmair

$userLabel.Name

Joined: 04.10.2023

2 Comments

  1. Christian Sedlmair

    DANKE! Die Firma Snom hat sehr zuvorkommend reagiert, mich kontaktiert und damit auch andere etwas davon haben die Inhalte in kurz:

    • Der Provider (Netzversorger) kann auch pushen, also die Einstellungen des Telefons übersteuern
    • Es kann sein dass der Provider die aktuellste Software Version nicht bei sich freigegeben und bei sich registriert hat.
    • Letzteres kann dazu führen dass die Provisionierung erneut ausgeführt wurde und fehlschlägt.

    Nächster Schritt: Abklärung mit der Technikabteilung des Providers.

    UND

    Wichtig bei Providerwechsel: Der alte Provider muss die MAC Adresse des Telefons AUSTRAGEN WEIL die MAC Adressen zentral gespeichert sind. Wenn ein bisheriger Provider das nicht tut, kann ein neuer Provider das Telefon nicht provisionieren

  2. Christian Sedlmair

    Seit ich beim neuen Provider bin funktioniert alles reibungslos und auch das Telefon liess ich ohne Zutun problemlos provisionieren und zeigt auch kein unerwünschtes Verhalten mehr.

    Damit ist klar: Das unerwünschte Updaten war vom Provider getriggert, nicht vom Telefon.