FHEM Forum

FHEM => Anfängerfragen => Thema gestartet von: LaserBrenner am 09 Februar 2019, 21:49:18

Titel: : telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: LaserBrenner am 09 Februar 2019, 21:49:18
Hallo,
ich betreibe FHEM auf einem Raspberry PI. Installation und Konfiguration funktionieren auf einer "sauberen" aktuellen Debian-Installation einwandfrei. 
Nach dem Reboot des Systems ist jedoch das FHEM-Webfrontend nicht mehr erreichbar. 
/opt/fhem/log zeigt: "telnetPort: Can't open server port at 7072: Address already in use. Exiting."

Hab schon diverse Beiträge durchgelesen komme aber nicht weiter, es hieß mit
netstat -lpn |grep :7072 kommt man an mehr Informationen kann diese aber nicht deuten

2019.02.09 21:24:44 5: Loading ./FHEM/98_telnet.pm
2019.02.09 21:24:44 1: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
root@FHEMServer:/opt/fhem# netstat -lpn |grep :7072
tcp        0      0 0.0.0.0:7072            0.0.0.0:*               LISTEN      449/perl
root@FHEMServer:/opt/fhem#

Könnt ihr mir weiterhelfen?

Gruß
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: ChristianH am 10 Februar 2019, 08:54:30
Ich hatte das mal restart, habe zwischen stop und start eine Pause eingebaut, dann ging es.

Weiss natürlich nicht, ob das bei dir auch so ist

Gesendet von meinem ONEPLUS A5010 mit Tapatalk

Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: LaserBrenner am 10 Februar 2019, 09:08:58
Wenn ich fhem manuell stoppe und wieder starte bekomme ich immer noch web Zugriff. Über die fhem app bekomme ich aber Zugriff.
Titel: : telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: justme1968 am 10 Februar 2019, 09:41:07
vermutlich verhält sich ein modul bzw einer der aus fhem gestartetem prozesse nicht ganz sauber.

bitte schaut mal mit ps und im log ob sich erkennen lässt welcher fhem prozess das genau ist.

verwendet ihr das WEATHER modul?
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: DS_Starter am 10 Februar 2019, 10:03:14
Guten Morgen,

falls ihr DbLog im asynchronen Modus verwendet, soll das Attribut "shutdownWait" gesetzt sein damit der Shutdown (Restart) wartet bis der Hintergrundprozess seine Daten weggeschrieben hat.
Siehe "set <name> configCheck" im DbLog.

VG
Titel: : telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: justme1968 am 10 Februar 2019, 10:04:22
@DS_Starter: wäre das nicht etwas für das neue delayed shutdown?
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: DS_Starter am 10 Februar 2019, 10:10:46
Zitatwäre das nicht etwas für das neue delayed shutdown?
Ja, wahrscheinlich. Ich habe ein paar Dinge auf meiner ToDo für DbLog die ich abarbeiten will wenn ich mich wieder etwas
intensiver mit dem Modul beschäftige. Momentan arbeite ich am SSCam weiter.
Ich setz das Thema mal mit drauf.
Muss mal schauen wo etwas dazu stand, hatte ich nur am Rande bemerkt das es jetzt sowas gibt. Vllt. hat du einen Link parat ...
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: justme1968 am 10 Februar 2019, 10:19:54
das war der thread dazu: https://forum.fhem.de/index.php/topic,95819.0.html
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: DS_Starter am 10 Februar 2019, 10:20:47
Habe es auch gerade gefunden -> https://forum.fhem.de/index.php/topic,95819.0.html  :)

Danke für den Tipp mit delayed shutdown Andre.

schönen Sonntag & Grüße,
Heiko
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: LaserBrenner am 10 Februar 2019, 12:55:50
Zitat von: justme1968 am 10 Februar 2019, 09:41:07
vermutlich verhält sich ein modul bzw einer der aus fhem gestartetem prozesse nicht ganz sauber.

bitte schaut mal mit ps und im log ob sich erkennen lässt welcher fhem prozess das genau ist.

verwendet ihr das WEATHER modul?

Äh was ist ps?
Und ja ich verwende das WEATHER Modul...
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: LaserBrenner am 10 Februar 2019, 13:35:21

Linux FHEMServer 4.14.34-v7+ #1110 SMP Mon Apr 16 15:18:51 BST 2018 armv7l

The programs included with the Debian GNU/Linux system are free software;
the exact distribution terms for each program are described in the
individual files in /usr/share/doc/*/copyright.

Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent
permitted by applicable law.
Last login: Sat Feb  9 21:24:01 2019 from 192.168.2.213
root@FHEMServer:~# ps -e
  PID TTY          TIME CMD
    1 ?        00:00:03 systemd
    2 ?        00:00:00 kthreadd
    4 ?        00:00:00 kworker/0:0H
    6 ?        00:00:00 mm_percpu_wq
    7 ?        00:00:00 ksoftirqd/0
    8 ?        00:00:01 rcu_sched
    9 ?        00:00:00 rcu_bh
   10 ?        00:00:00 migration/0
   11 ?        00:00:00 cpuhp/0
   12 ?        00:00:00 cpuhp/1
   13 ?        00:00:00 migration/1
   14 ?        00:00:00 ksoftirqd/1
   16 ?        00:00:00 kworker/1:0H
   17 ?        00:00:00 cpuhp/2
   18 ?        00:00:00 migration/2
   19 ?        00:00:00 ksoftirqd/2
   21 ?        00:00:00 kworker/2:0H
   22 ?        00:00:00 cpuhp/3
   23 ?        00:00:00 migration/3
   24 ?        00:00:00 ksoftirqd/3
   25 ?        00:00:00 kworker/3:0
   26 ?        00:00:00 kworker/3:0H
   27 ?        00:00:00 kdevtmpfs
   28 ?        00:00:00 netns
   33 ?        00:00:00 khungtaskd
   34 ?        00:00:00 oom_reaper
   35 ?        00:00:00 writeback
   36 ?        00:00:00 kcompactd0
   37 ?        00:00:00 crypto
   38 ?        00:00:00 kblockd
   39 ?        00:00:00 watchdogd
   40 ?        00:00:00 rpciod
   41 ?        00:00:00 xprtiod
   44 ?        00:00:00 kswapd0
   45 ?        00:00:00 nfsiod
   55 ?        00:00:00 kthrotld
   56 ?        00:00:00 iscsi_eh
   57 ?        00:00:00 dwc_otg
   58 ?        00:00:00 DWC Notificatio
   59 ?        00:00:00 vchiq-slot/0
   60 ?        00:00:00 vchiq-recy/0
   61 ?        00:00:00 vchiq-sync/0
   62 ?        00:00:00 vchiq-keep/0
   63 ?        00:00:00 SMIO
   64 ?        00:00:00 irq/92-mmc1
   68 ?        00:00:01 mmcqd/0
   69 ?        00:00:00 jbd2/mmcblk0p2-
   70 ?        00:00:00 ext4-rsv-conver
   71 ?        00:00:00 ipv6_addrconf
   90 ?        00:00:00 kworker/1:1H
   94 ?        00:00:00 systemd-journal
  109 ?        00:00:03 kworker/3:2
  126 ?        00:00:00 systemd-udevd
  157 ?        00:00:01 kworker/u8:2
  172 ?        00:00:24 ft5406
  233 ?        00:00:00 cfg80211
  243 ?        00:00:00 brcmf_wq/mmc1:0
  244 ?        00:00:00 brcmf_wdog/mmc1
  284 ?        00:00:00 systemd-timesyn
  327 ?        00:00:00 dbus-daemon
  335 ?        00:00:00 thd
  343 ?        00:00:00 systemd-logind
  347 ?        00:00:04 avahi-daemon
  351 ?        00:00:00 rsyslogd
  359 ?        00:00:00 cron
  380 ?        00:00:00 avahi-daemon
  429 ?        00:00:00 wpa_supplicant
  450 ?        00:00:00 kworker/0:1H
  479 ?        00:00:00 kworker/u9:0
  481 ?        00:00:00 kworker/u9:2
  482 ?        00:00:00 hciattach
  487 ?        00:00:00 bluetoothd
  647 ?        00:00:01 dhcpcd
  659 tty1     00:00:00 agetty
  662 ?        00:00:00 sshd
  826 ?        00:00:00 sshd
  828 ?        00:00:00 sshd
  836 ?        00:00:00 systemd
  839 ?        00:00:00 (sd-pam)
  851 pts/0    00:00:00 bash
  852 pts/1    00:00:00 bash
  887 ?        00:00:00 kworker/3:1H
  888 ?        00:00:00 kworker/2:1H
  994 pts/1    00:01:21 perl
  998 ?        00:00:01 kworker/u8:1
4898 ?        00:00:02 kworker/2:0
5442 ?        00:00:00 kworker/1:0
5478 ?        00:00:00 kworker/0:0
5530 ?        00:00:00 kworker/0:1
5531 ?        00:00:00 kworker/2:1
5566 ?        00:00:00 kworker/1:2
5567 ?        00:00:00 kworker/0:2
5568 ?        00:00:00 kworker/1:1
5569 ?        00:00:00 sshd
5579 pts/2    00:00:00 bash
5592 pts/2    00:00:00 ps
root@FHEMServer:~#


So hier meine Prozesse leider kenne ich mich mit linux nicht so gut aus für mich sieht es alles okay aus...
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: justme1968 am 10 Februar 2019, 17:29:18
tritt das problem immer auf oder zufällig nur wenn weather gerade im hintergrund daten holt?
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: CoolTux am 10 Februar 2019, 18:10:01
Wie kommst Du genau auf Weather? Übersehe ich da was? Kann da in keinem Post hier ein Hinweis finden.

Grüße
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: justme1968 am 10 Februar 2019, 18:12:32
bin vor ein paar tagen darauf gestoßen das weather fork start fhemfork verwendet.

das kann genau solche symptome erzeugen. such mal den thread der damals zu fhemfork geführt hat.

boris weiß schon bescheid.
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: CoolTux am 10 Februar 2019, 18:21:01
Zitat von: justme1968 am 10 Februar 2019, 18:12:32
bin vor ein paar tagen darauf gestoßen das weather fork start fhemfork verwendet.

das kann genau solche symptome erzeugen. such mal den thread der damals zu fhemfork geführt hat.

boris weiß schon bescheid.
Meinst Du wirklich Weather oder Calendar? Ich kann mich da bei Weather so gar nicht dran erinnern. Ich schau mal.
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: justme1968 am 10 Februar 2019, 18:22:25
weather.

die updates werden im hintergrund per SubProcess geholt. da steckt das fork drin.
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: CoolTux am 10 Februar 2019, 18:30:25
Tut mir leid Andre, aber dem muss ich wieder sprechen. Ich selbst habe Weather umgeschrieben und da wird rein gar nicht mit SubProcess gearbeitet. Wo hingegen bei Calendar in der Tat mit SubProcess gearbeitet wird.
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: justme1968 am 10 Februar 2019, 18:36:43
arg... du hast recht.

jetzt frage ich mich aber wie ich darauf komme... war das vielleicht noch die alte version?

hmm... sehr komisch
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: CoolTux am 10 Februar 2019, 18:43:13
Zitat von: justme1968 am 10 Februar 2019, 18:36:43
arg... du hast recht.

jetzt frage ich mich aber wie ich darauf komme... war das vielleicht noch die alte version?

hmm... sehr komisch

Ich kann es dir nicht ganz genau sagen mit der alten Version, aber zu 90 Prozent glaube ich war da auch kein SubProcess mit im Spiel. Aktuell verwenden glaube nur 3 oder 4 Module SubProcess. Calendar, AptToDate, Julians npmj oder so und glaube HMCCU Server nutzt dann eventuell noch, wobei es da Probleme gab und glaube umgeschrieben werden sollte.

Grüße
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: LaserBrenner am 10 Februar 2019, 21:04:40
Das Problem ist seit einen Stromausfall (also Neustart des systems) immer. Egal welchen Explorer ich verwende. Vielleicht noch wichtig ich hatte bei mir https eingestellt.
Titel: Antw:: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
Beitrag von: LaserBrenner am 11 Februar 2019, 20:23:40
Der Internet-Explorer liefert übrigens beim versuch mit "https://192.168.2.88:8083/fhem" fhem aufzurufen
Fehler beim Öffnen von res://ieframe.dll/invalidcert.htm?SSLError=50331648

Und hier noch der mitschrieb aus meiner log Datei

2019.02.11 15:10:45 0: Server shutdown
2019.02.11 15:10:48 1: Including fhem.cfg
2019.02.11 15:10:48 3: telnetPort: port 7072 opened
2019.02.11 15:10:48 3: WEB: port 8083 opened
2019.02.11 15:10:48 3: WEBphone: port 8084 opened
2019.02.11 15:10:48 3: WEBtablet: port 8085 opened
2019.02.11 15:10:49 2: eventTypes: loaded 2098 events from ./log/eventTypes.txt
2019.02.11 15:10:49 3: TABLETUI: new ext defined infix:ftui/: dir:./www/tablet/:
2019.02.11 15:10:49 3: Registering HTTPSRV TABLETUI for URL /ftui   and assigned link ftui/ ...
2019.02.11 15:10:49 3: FahrzeitHausArbeit: Defined with URL https://maps.googleapis.com/maps/api/distancematrix/json?origins=Bargteheide,Hasselbusch+40&destinations=Hamburg,Bramfelder%20Chaussee+130&mode=driving&language=de-DE&departure_time=now&key=AIzaSyBwmnWociALY83ATuam6rWkLOGRYa6J4XE and interval 360
2019.02.11 15:10:49 3: FahrzeitArbeitHaus: Defined with URL https://maps.googleapis.com/maps/api/distancematrix/json?origins=Hamburg,Bramfelder%20Chaussee+130&destinations=Bargteheide,Hasselbusch+40&mode=driving&language=de-DE&departure_time=now&key=AIzaSyBwmnWociALY83ATuam6rWkLOGRYa6J4XE and interval 360
2019.02.11 15:10:49 3: Opening CUL_2 device /dev/ttyACM2
2019.02.11 15:10:50 3: Setting CUL_2 serial parameters to 9600,8,N,1
2019.02.11 15:10:50 3: CUL_2: Possible commands: ABbCeFGhiKkLlMmNRTtUuVWXxYZ
2019.02.11 15:10:50 3: CUL_2 device opened
2019.02.11 15:10:50 3: UVOZON: Defined with URL http://www.donnerwetter.de/biowetter/uvregion.hts?lid=DE14100&Ort=BARGTEHEIDE&Hauttyp=3&B1=anzeigen&UVind=true&UVZeit=12 and interval 3600
2019.02.11 15:10:50 3: UVOZON: the attribute reading03Expr should no longer be used. Please use reading03OExpr instead
2019.02.11 15:10:50 3: UVOZON: For most old attributes you can specify enableControlSet and then set device upgradeAttributes to automatically modify the configuration
2019.02.11 15:10:50 3: UVOZON: the attribute reading04Expr should no longer be used. Please use reading04OExpr instead
2019.02.11 15:10:50 3: UVOZON: For most old attributes you can specify enableControlSet and then set device upgradeAttributes to automatically modify the configuration
2019.02.11 15:10:50 3: Opening CUL_0 device /dev/ttyACM0
2019.02.11 15:10:50 3: Setting CUL_0 serial parameters to 9600,8,N,1
2019.02.11 15:10:50 3: CUL_0: Possible commands: BbCFikApZGMKUYRTVWXefmltuxEz
2019.02.11 15:10:50 3: CUL_0 device opened
2019.02.11 15:10:50 3: Opening CUL_1 device /dev/ttyACM1
2019.02.11 15:10:50 3: Setting CUL_1 serial parameters to 9600,8,N,1
2019.02.11 15:10:50 3: CUL_1: Possible commands: mBbCFiAZGMYRTVWXefltuxz
2019.02.11 15:10:50 3: CUL_1 device opened
2019.02.11 15:10:50 2: Switched CUL_1 rfmode to MAX
2019.02.11 15:10:50 3: Opening Rademacher device /dev/serial/by-id/usb-Rademacher_DuoFern_USB-Stick_WR00JWCZ-if00-port0
2019.02.11 15:10:50 3: Setting Rademacher serial parameters to 115200,8,N,1
2019.02.11 15:10:50 3: Rademacher device opened
2019.02.11 15:10:51 3: Alpha2: Defined with URL http://192.168.2.44:10070/data/dynamic.xml and interval 600
2019.02.11 15:10:51 3: CUL_MAX_Check: Detected firmware version 167 of the CUL-compatible IODev
2019.02.11 15:10:52 1: FULLY: [Fully_fur_Tab] Version 1.1 Opening device 192.168.2.80
2019.02.11 15:10:52 1: Including ./log/fhem.save
2019.02.11 15:10:53 1: usb create starting
2019.02.11 15:10:53 3: Probing ZWDongle device /dev/serial1
2019.02.11 15:10:53 3: Probing CUL device /dev/ttyAMA0
2019.02.11 15:10:54 3: Probing TCM_ESP3 device /dev/ttyAMA0
2019.02.11 15:10:54 3: Probing ZWDongle device /dev/ttyAMA0
2019.02.11 15:10:54 3: Probing FRM device /dev/ttyAMA0
2019.02.11 15:10:59 1: usb create end
2019.02.11 15:10:59 0: Featurelevel: 5.9
2019.02.11 15:10:59 0: Server started with 165 defined entities (fhem.pl:18423/2019-01-26 perl:5.024001 os:linux user:fhem pid:12992)
2019.02.11 15:10:59 3: DbLog logdb - Creating Push-Handle to database SQLite:dbname=fhem.db with user
2019.02.11 15:10:59 3: DbLog logdb - Push-Handle to db SQLite:dbname=fhem.db created
2019.02.11 15:10:59 3: telnetForBlockingFn_1549894259: port 34121 opened
2019.02.11 15:11:04 2: FULLY: [Fully_fur_Tab] Error during request. connect to http://192.168.2.80:2323 timed out
2019.02.11 15:11:04 3: UVOZON: Read callback: Error: read from http://www.donnerwetter.de:80 timed out
2019.02.11 15:11:04 3: FahrzeitArbeitHaus: Read callback: Error: read from https://maps.googleapis.com:443 timed out
2019.02.11 15:11:04 3: FahrzeitHausArbeit: Read callback: Error: read from https://maps.googleapis.com:443 timed out
2019.02.11 15:11:05 3: Alpha2: Read callback: Error: http://192.168.2.44:10070/data/dynamic.xml: empty answer received
2019.02.11 15:11:05 3: ABFALL myABFALL - CALENDAR:AbfallGoogleCalender triggered, updating ABFALL myABFALL ...
2019.02.11 15:11:44 2: CUL_MAX_SendQueueHandler: Missing ack from 0ad485 for 0f7304031234560ad48500130b0f0ba9
2019.02.11 15:12:31 1: PERL WARNING: Use of uninitialized value $args[0] in numeric eq (==) at ./FHEM/10_MAX.pm line 703.
2019.02.11 15:12:31 1: PERL WARNING: Use of uninitialized value $args[0] in numeric ge (>=) at ./FHEM/10_MAX.pm line 706.
2019.02.11 15:12:31 2: Invalid WallThermostatState packet
2019.02.11 15:12:31 1: PERL WARNING: Use of uninitialized value $desiredTemperatureRaw in bitwise and (&) at ./FHEM/10_MAX.pm line 741.
2019.02.11 15:19:46 2: Invalid WallThermostatState packet



für mich sieht das alles OK aus, ich hoffe das jemand von euch einen Hinweis entdeckt...

dank & gruß
Matthias