Seite 2 von 3

Letzter Versuch: Senden liefert (fast) nur NC

Verfasst: Mi 20. Mär 2024, 13:42
von ckeuer
Ich habe die iTelex-Box neu gestrtet und dann die folgenden Nummern angewählt: 747474, 922692 und 93250. Bei allen NC bekommen.

Hier ist das, was dabei von der Ethernetkarte mit Protokolltiefe 4 gekommen ist:

iTelex...
UART Initialisiert
STDOUT Initialisiert
CLOCK Initialisiert
LED_core Initialisiert
Config Initialisiert
EXTINT Initialisiert
MMC/SD Error
SHELL Initialisiert
THREAD Initialisiert
itelex_init1:
...SendePuffer, EmpfPuffer ok
...Config ok
...Timer-Callback-Funktion ok
ENC28j60 (Rev.: 6) initialisiert ( HW-Add: 02:03:6f:55:1c:c8 ) Fullduplex: Link ready
-+-> ARP initialisiert
|-> UDP (Tornado-engine) initialisiert
|-> TCP (Hurrican-engine) initialisiert
| IP : 192.168.1.112
| Netmask: 255.255.255.0
| Gateway: 192.168.1.1
| DNS : 192.168.1.1
|-> NTP-Server Zeit aktualisieren:Oeffne neues Socket.
UDP-Socket aufgemacht zur 132.163.96.1.
UDP-Packet gesendet.
Warte auf Antwort.Antwort erhalten.
UDP-Socket geschlossen.
Zeit: 13:27:16.71
HTTP-Server Port 80.
Telnet-Server Port 23.
itelex_init2:
...Cgi ok
iTelex Port 134.
...Email ok
...TlnBuch ok
iTelex TlnServer Port 11811.
...Teilnehmer-Server ok
00:00:00,19: Neustart 870 Reset-Flags 01
13:27:16,87: iTelex: Teilnehmer-Verzeichnis aus EEPROM geladen.
13:27:16,87: iTelex( 0): ModusWechsel von 99 nach 0.
13:27:17,06: iTelex: Teilnehmer-Verzeichnis Pruefsumme aktualisiert auf 84E7C975.
13:27:17,15: iTelex: Teilnehmer-Verzeichnis Pruefsumme aktualisiert auf 134852E8.
13:27:46,65: iTelex(39180): Verbindung an Teilnehmer-Server tlnserv3.teleprinter.net Socket #0 hergestellt fuer Selbstaktualisierung.
13:27:46,70: iTelex(39181): Teilnehmer-Server Empfang: (6/6) 02 04 56 67 0F EA
13:27:46,70: iTelex(39181): Dynamische IP-Aktualisierung: neue IP 86.103.15.234
13:28:07,93: iTelex(46977): TWI Reservierung intern / gehend von 22
13:28:07,93: iTelex(46977): ModusWechsel von 0 nach 1.
13:28:07,95: iTelex(46978): TWI Einschaltkommando intern / gehend
13:28:07,95: iTelex(46978): ModusWechsel von 1 nach 2.
13:28:10,12: iTelex(55545): TWI Wahlziffer 7 intern / gehend
13:28:11,83: iTelex(62336): TWI Wahlziffer 4 intern / gehend
13:28:13,82: iTelex( 4673): TWI Wahlziffer 7 intern / gehend
13:28:15,39: iTelex(10878): TWI Wahlziffer 4 intern / gehend
13:28:17,35: iTelex(18684): TWI Wahlziffer 7 intern / gehend
13:28:18,90: iTelex(24774): TWI Wahlziffer 4 intern / gehend
13:28:18,90: iTelex(24774): Teilnehmer 747474 im eigenen Telefonbuch gefunden.
13:28:18,90: iTelex(24774): Abfrage bei Teilnehmer-Servern
13:28:18,93: iTelex(24774): Verbindung an Teilnehmer-Server tlnserv3.teleprinter.net Socket #0 hergestellt fuer Rufnummer-Abfrage.
13:28:19,05: iTelex(24775): Teilnehmer-Server Empfang: (102/102) 05 'd' D2 'g' 0B 00 'Wettervorhersage DWD :Baudot =a' 00 00 00 00 00 00 00 00 00 00 00 01 'telex.telephontechnik.de' 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 88 13 'f' 00 00 'X' EB '[' E6
13:28:19,08: iTelex(24775): Teilnehmer-Server meldet Eintrag gefunden: 0.0.0.0
13:28:19,14: iTelex(24775): Verbindungsaufbau zu Hostname telex.telephontechnik.de = 84.157.254.31 Port 5000
13:28:24,15: iTelex(24775): ! Client-Socket konnte nicht erstmalig geoeffnet werden
13:28:24,15: iTelex(24775): WahlAbbruchMeldung 'nc'
13:28:24,16: iTelex(24775): ModusWechsel von 2 nach 18.
13:28:24,16: iTelex(24775): InterneVerbindungBeenden ausgehend von Modus 18
13:28:24,69: iTelex(24776): Ascii-Verarbeitung: 0D 0D 0D 0A (+4)
13:28:24,69: iTelex(24776): gewandelt in: 0D 0D 0D 0A
13:28:24,70: iTelex(24776): Ascii-Verarbeitung: 4 Zeichen aus AsciiHilfPuffer verarbeitet,BaudotMode = 1iTelex(30099): Ascii-Verarbeitung: 6E 63 0D 0A (+0)
13:28:26,27: iTelex(30099): gewandelt in: 6E 63 0D 0A
13:28:26,28: iTelex(30099): Ascii-Verarbeitung: 4 Zeichen aus AsciiHilfPuffer verarbeitet,BaudotMode = 1iTelex(32919): Reste gedruckt --> Ausschaltung intern
13:28:27,03: iTelex(32919): ModusWechsel von 18 nach 19.
13:28:28,36: iTelex(38168): TWI Ausschaltung quittiert
13:28:28,36: iTelex(38168): ModusWechsel von 19 nach 32.
13:28:28,36: iTelex(38168): Grundstellung erreicht (Socket geschlossen, TWI geschlossen)
13:28:28,36: iTelex(38168): ModusWechsel von 32 nach 0.
13:28:33,28: iTelex(55018): TWI Reservierung intern / gehend von 22
13:28:33,28: iTelex(55018): ModusWechsel von 0 nach 1.
13:28:33,29: iTelex(55019): TWI Einschaltkommando intern / gehend
13:28:33,29: iTelex(55019): ModusWechsel von 1 nach 2.
13:28:35,68: iTelex(64444): TWI Wahlziffer 9 intern / gehend
13:28:36,93: iTelex( 3875): TWI Wahlziffer 2 intern / gehend
13:28:38,15: iTelex( 8695): TWI Wahlziffer 2 intern / gehend
13:28:40,05: iTelex(16238): TWI Wahlziffer 6 intern / gehend
13:28:42,28: iTelex(25098): TWI Wahlziffer 9 intern / gehend
13:28:43,54: iTelex(30058): TWI Wahlziffer 2 intern / gehend
13:28:45,56: iTelex(38001): Abfrage bei Teilnehmer-Servern
13:28:45,59: iTelex(38001): Verbindung an Teilnehmer-Server tlnserv3.teleprinter.net Socket #0 hergestellt fuer Rufnummer-Abfrage.
13:28:45,66: iTelex(38002): Teilnehmer-Server Empfang: (102/102) 05 64 44 14 0E 00 4D 69 63 68 61 65 6C 2C 20 4D 61 72 69 65 6E 6D 75 65 6E 73 74 65 72 20 3A 54 31 30 30 30 00 00 00 00 00 00 00 00 00 00 00 00 05 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 D8 1F 78 89 86 00 21 00 00 16 EE A4 E9
13:28:45,70: iTelex(38002): Teilnehmer-Server meldet Eintrag gefunden: 216.31.120.137
13:28:45,70: iTelex(38002): Verbindungsaufbau zu IP 216.31.120.137 Port 134
13:28:50,71: iTelex(38002): ! Client-Socket konnte nicht erstmalig geoeffnet werden
13:28:50,71: iTelex(38002): WahlAbbruchMeldung 'nc'
13:28:50,72: iTelex(38002): ModusWechsel von 2 nach 18.
13:28:50,72: iTelex(38002): InterneVerbindungBeenden ausgehend von Modus 18
13:28:51,33: iTelex(38003): Ascii-Verarbeitung: 0D 0D 0D 0A (+4)
13:28:51,33: iTelex(38003): gewandelt in: 0D 0D 0D 0A
13:28:51,34: iTelex(38003): Ascii-Verarbeitung: 4 Zeichen aus AsciiHilfPuffer verarbeitet,BaudotMode = 1iTelex: Teilnehmer-Verzeichnis Pruefsumme aktualisiert auf 237344A3.
13:28:52,83: iTelex(42815): Ascii-Verarbeitung: 6E 63 0D 0A (+0)
13:28:52,83: iTelex(42815): gewandelt in: 6E 63 0D 0A
13:28:52,84: iTelex(42815): Ascii-Verarbeitung: 4 Zeichen aus AsciiHilfPuffer verarbeitet,BaudotMode = 1iTelex(45601): Reste gedruckt --> Ausschaltung intern
13:28:53,59: iTelex(45601): ModusWechsel von 18 nach 19.
13:28:54,92: iTelex(50856): TWI Ausschaltung quittiert
13:28:54,92: iTelex(50856): ModusWechsel von 19 nach 32.
13:28:54,92: iTelex(50856): Grundstellung erreicht (Socket geschlossen, TWI geschlossen)
13:28:54,92: iTelex(50856): ModusWechsel von 32 nach 0.
13:29:00,57: iTelex( 4709): TWI Reservierung intern / gehend von 22
13:29:00,57: iTelex( 4709): ModusWechsel von 0 nach 1.
13:29:00,59: iTelex( 4710): TWI Einschaltkommando intern / gehend
13:29:00,59: iTelex( 4710): ModusWechsel von 1 nach 2.
13:29:02,89: iTelex(13808): TWI Wahlziffer 9 intern / gehend
13:29:04,40: iTelex(19785): TWI Wahlziffer 3 intern / gehend
13:29:05,70: iTelex(24917): TWI Wahlziffer 2 intern / gehend
13:29:07,43: iTelex(31780): TWI Wahlziffer 5 intern / gehend
13:29:09,68: iTelex(40698): TWI Wahlziffer 0 intern / gehend
13:29:11,69: iTelex(48642): Abfrage bei Teilnehmer-Servern
13:29:11,73: iTelex(48642): Verbindung an Teilnehmer-Server tlnserv3.teleprinter.net Socket #0 hergestellt fuer Rufnummer-Abfrage.
13:29:11,80: iTelex(48643): Teilnehmer-Server Empfang: (102/102) 05 'dBl' 01 00 'Reinhold, Paderborn :Lo3000' 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 'bxhwy4t5cehdjg0y.myfritz.net' 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 86 00 00 00 00 'yx>' E8
13:29:11,83: iTelex(48643): Teilnehmer-Server meldet Eintrag gefunden: 0.0.0.0
13:29:11,86: iTelex(48643): Verbindungsaufbau zu Hostname bxhwy4t5cehdjg0y.myfritz.net = 92.116.56.134 Port 134
13:29:16,86: iTelex(48643): ! Client-Socket konnte nicht erstmalig geoeffnet werden
13:29:16,86: iTelex(48643): WahlAbbruchMeldung 'nc'
13:29:16,87: iTelex(48643): ModusWechsel von 2 nach 18.
13:29:16,87: iTelex(48643): InterneVerbindungBeenden ausgehend von Modus 18
13:29:17,40: iTelex(48644): Ascii-Verarbeitung: 0D 0D 0D 0A (+4)
13:29:17,40: iTelex(48644): gewandelt in: 0D 0D 0D 0A
13:29:17,40: iTelex(48644): Ascii-Verarbeitung: 4 Zeichen aus AsciiHilfPuffer verarbeitet,BaudotMode = 1iTelex: Teilnehmer-Verzeichnis Pruefsumme aktualisiert auf 88EEE63E.
13:29:18,99: iTelex(53948): Ascii-Verarbeitung: 6E 63 0D 0A (+0)
13:29:18,99: iTelex(53948): gewandelt in: 6E 63 0D 0A
13:29:18,99: iTelex(53948): Ascii-Verarbeitung: 4 Zeichen aus AsciiHilfPuffer verarbeitet,BaudotMode = 1iTelex(56737): Reste gedruckt --> Ausschaltung intern
13:29:19,74: iTelex(56737): ModusWechsel von 18 nach 19.
13:29:21,07: iTelex(62006): TWI Ausschaltung quittiert
13:29:21,07: iTelex(62006): ModusWechsel von 19 nach 32.
13:29:21,07: iTelex(62006): Grundstellung erreicht (Socket geschlossen, TWI geschlossen)
13:29:21,07: iTelex(62006): ModusWechsel von 32 nach 0.
13:29:21,10: iTelex POP: Client-Socket #0 zum Server erfolgreich geoeffnet
13:29:21,10: iTelex(62006): ModusWechsel von 0 nach 51.
13:29:21,24: iTelex(62007): Socket Empfang: (31/31) '+OK AlfaHosting GmbH - ready.' 0D 0A --> BufUsed 31
13:29:21,38: iTelex(62008): Socket Sendung: (16) 'USER abcdef' 0D 0A --> Res 16 SumAnz 0/00
13:29:21,46: iTelex(62009): Socket Empfang: (5/5) '+OK' 0D 0A --> BufUsed 5
13:29:21,58: iTelex(62010): Socket Sendung: (13) 'PASS xxxxx' 0D 0A --> Res 13 SumAnz 0/00
13:29:21,63: iTelex(62011): Socket Empfang: (16/16) '+OK Logged in.' 0D 0A --> BufUsed 16
13:29:21,75: iTelex(62012): Socket Sendung: (6) 'STAT' 0D 0A --> Res 6 SumAnz 0/00
13:29:21,81: iTelex(62013): Socket Empfang: (9/9) '+OK 0 0' 0D 0A --> BufUsed 9
13:29:22,04: iTelex(62014): Socket Sendung: (6) 'QUIT' 0D 0A --> Res 6 SumAnz 0/00
13:29:22,05: iTelex(62015): Socket Empfang: (18/18) '+OK Logging out.' 0D 0A --> BufUsed 18
13:29:22,17: iTelex(62303): Socket wurde von Gegenstelle erwartet geschlossen
13:29:22,17: iTelex(62303): ModusWechsel von 51 nach 32.
13:29:22,17: iTelex(62303): Grundstellung erreicht (Socket geschlossen, TWI geschlossen)
13:29:22,17: iTelex(62303): ModusWechsel von 32 nach 0.

Letzter Versuch: Senden liefert (fast) nur NC

Verfasst: Mi 20. Mär 2024, 13:54
von M1ECY
I would try dialling the weather server on 727272 - this is the most reliable connection I can find - it always works

Letzter Versuch: Senden liefert (fast) nur NC

Verfasst: Mi 20. Mär 2024, 14:03
von DF3OE
Ändere mal die Reihenfolge der Teilnehmerserver-Einträge.

Päckchen schon angekommen?

Letzter Versuch: Senden liefert (fast) nur NC

Verfasst: Mi 20. Mär 2024, 14:49
von tasto
ckeuer hat geschrieben: Mi 20. Mär 2024, 12:08 Mein Router ist eine Unifi Dream Machine an einem Glasfasermodem.
Dann probiere unbedingt mal Centralex aus und schaue, ob die Probleme damit dann nicht vielleicht sofort weg sind. ;)

Viel Glück!

Letzter Versuch: Senden liefert (fast) nur NC

Verfasst: Mi 20. Mär 2024, 14:50
von DF3OE
Für ausgehende Verbindungen hat Centralex KEINE Bedeutung.

Letzter Versuch: Senden liefert (fast) nur NC

Verfasst: Mi 20. Mär 2024, 14:58
von ckeuer
Ich kann gerne die Reihenfolge der Teilnehmerserver ändern. Ich denke aber, dass das nicht das Problem ist. Es wird ja versucht, die richtigen Ziel-IP-Adressen und Ports zu erreichen.

Ich frage mich aber, was die Meldung „! Client-Socket konnte nicht erstmalig geöffnet werden“ bedeutet und was die Ursache dafür ist.

Letzter Versuch: Senden liefert (fast) nur NC

Verfasst: Mi 20. Mär 2024, 17:44
von detlef
ckeuer hat geschrieben: Mi 20. Mär 2024, 14:58 Ich kann gerne die Reihenfolge der Teilnehmerserver ändern. Ich denke aber, dass das nicht das Problem ist. Es wird ja versucht, die richtigen Ziel-IP-Adressen und Ports zu erreichen.
Das sehe ich auch so. Die Daten wurden ordnungsgemäß vom Teilnehmerserver abgerufen.
ckeuer hat geschrieben: Mi 20. Mär 2024, 14:58 Ich frage mich aber, was die Meldung „! Client-Socket konnte nicht erstmalig geöffnet werden“ bedeutet und was die Ursache dafür ist.
Naja, ich würde sagen, dass schon der erste Verbindungsversuch fehlgeschlagen ist. Also der SYN, so wie es im Wireshark-Log auch schon zu sehen war.

Die Ursache vermute ich außerhalb von i-Telex. In deiner Netzwerk- bzw. Routerkonfiguration. Diesen komischen Router kenne ich nicht. Wenn das eine Fritzbox wäre, dann wüsste man, wo man dran ist. ;)
Hattest du den Router zwischendurch mal neu gestartet. Manchmal schmeißen die den ARP-Cache durcheinander und dann kommt auch das Routing durcheinander.

Letzter Versuch: Senden liefert (fast) nur NC

Verfasst: Do 21. Mär 2024, 09:10
von RainerDD
detlef hat geschrieben: Mi 20. Mär 2024, 17:44
Naja, ich würde sagen, dass schon der erste Verbindungsversuch fehlgeschlagen ist. Also der SYN, so wie es im Wireshark-Log auch schon zu sehen war.

Die Ursache vermute ich außerhalb von i-Telex. In deiner Netzwerk- bzw. Routerkonfiguration. Diesen komischen Router kenne ich nicht. Wenn das eine Fritzbox wäre, dann wüsste man, wo man dran ist. ;)
Hattest du den Router zwischendurch mal neu gestartet. Manchmal schmeißen die den ARP-Cache durcheinander und dann kommt auch das Routing durcheinander.
Ich denke auch, dass der Router der Verdächtige ist. Lt. Beschreibung hat er eine "Next-generation security Application-aware firewall" mit umfangreichen Filterregeln. Und eventuell finden diese Filterregeln eine ausgehende Verbindung zum Port 134 verdächtig und das Paket wird verworfen.
Kann man denn irgendwo im Router sowas wie ein Ereignisprotokoll der Firewall sehen? Und eine Konfiguration der Regeln sollte ja auch irgendwo möglich sein ...

Letzter Versuch: Senden liefert (fast) nur NC

Verfasst: Do 21. Mär 2024, 09:29
von FredSonnenrein
Übrigens noch ein Tipp:
i-Telex versucht je Anwahl-Versuch nur eine TCP-Anfrage, um z.B. einen Hostnamen in eine IP-Adresse umzuwandeln.
Daher kann es sinnvoll sein, Verbindungsversuche zur selben Adresse / Nummer auch mal mehrfach zu versuchen.

Letzter Versuch: Senden liefert (fast) nur NC

Verfasst: Do 21. Mär 2024, 09:37
von FredSonnenrein
Ich habe gerade nochmal die Logs durchgesehen.
Auffällig ist, dass Verbindungsprobleme meist dann auftraten, wenn die Ethernetkarte eine "neue" IP-Adresse kontaktiert hat. Anders gesagt: "Bekannte" IP-Adressen funktionierten einwandfrei.
Das spricht dafür, dass tatsächlich irgendwelche Firewall-Einstellungen o.ä. ein Timeout (5 Sekunden) zur Folge haben.
Am besten mal testweise abschalten und das Systemverhalten dann prüfen.