Ich habe seit einigen Tagen eine nicht erklärliche Fehlermeldung:
2020.01.03 21:12:24 1: ./www/images/openautomation/phone_missed_out.svg is not useable
2020.01.03 21:12:55 1: ./www/images/openautomation/phone_call_end_out.svg is not useable
2020.01.03 21:12:55 1: ./www/images/openautomation/phone_call_end_out.svg is not useable
2020.01.03 21:12:55 1: ./www/images/openautomation/phone_missed_out.svg is not useable
2020.01.03 21:12:55 1: ./www/images/openautomation/phone_call_end_out.svg is not useable
2020.01.03 21:12:55 1: ./www/images/openautomation/phone_missed_out.svg is not useable
2020.01.03 21:12:55 1: ./www/images/openautomation/phone_missed_out.svg is not useable
2020.01.03 21:12:55 1: ./www/images/openautomation/phone_missed_out.svg is not useable
2020.01.03 21:12:55 1: ./www/images/openautomation/phone_missed_out.svg is not useable
2020.01.03 21:12:55 1: ./www/images/openautomation/phone_call_end_out.svg is not useable
2020.01.03 21:12:55 1: ./www/images/openautomation/phone_call_end_out.svg is not useable
2020.01.03 21:12:55 1: ./www/images/openautomation/phone_missed_out.svg is not useable
Diese Dateien gehören zu FBCallmonitor und in der Tat werden die Icons nicht angezeigt. Parallel (gleichzeitig!) meckert FHEM, dass er eine zu Telegram gehörige Datei nicht öffnen könne, die uniqueID-Datei. Nach Neustart geht alles wieder für einen Tag.
Hat jemand eine Idee, wo ich suchen kann?
könnte das die SD Karte sein?
Hi,
das klingt leider so wie SD Card.
Gruß Otto
ich hatte es befürchtet. also backup und tauschen...
Gesendet von iPad mit Tapatalk Pro
wäre dann zumindest der Gegenbeweis ;)
So, bevor ich das sichere (das ist leider umfangreich, weil ich so viele Dinge an diesem RPI herumgespielt hatte - ich habe den damals eingerichtet und war mir nicht sicher, ob ich jemals FHEM nutzen werde), habe ich folgenden Test gemacht. Ich habe FHEM heruntergefahren, eine Kopie der Dateien/Verzeichnisse angelegt, die angeblich "not useable" sind und dann die Kopie und das Original jeweils umbenannt:
ZitatOriginal => neuer Name mit "_old", Kopie => Anhängsel "Kopie" entfernt
Berechtigungen gesetzt, FHEM neu gestartet und die Sache läuft bis auf ein Detail, das mit TelegramBot zusammenhängt und vermutlich durch meine Bot-Neustarts erzeugt wurde.
Also ist definitiv die Karte hinüber und muss ersetzt werden.
ich wusste schon, warum ich mich seit Wochen vor einer kompletten Neuinstallation gedrückt habe, obwohl es ja klar war: https://forum.fhem.de/index.php/topic,107372.0.html (https://forum.fhem.de/index.php/topic,107372.0.html)
So was läuft nie nach Plan.
Gesendet von iPad mit Tapatalk Pro
Hmm, das ist nun sonderbar. Ich habe einen neuen RPi, eine neue SD-Karte und das System komplett neu aufgesetzt. Und auf einmal erscheint im Log
2020.01.19 10:55:33 1: TelegramBot_readToken: Error: No API token in file
2020.01.19 10:57:11 1: TelegramBot_readToken: Error: No API token in file
2020.01.19 10:58:50 1: TelegramBot_readToken: Error: No API token in file
2020.01.19 11:00:12 1: ./www/images/openautomation/phone_answering.svg is not useable
2020.01.19 11:00:12 1: ./www/images/openautomation/phone_answering.svg is not useable
Das waren genau die Fehlermeldungen, weshalb ich die ganze Aktion gestartet hatte. Also ist anscheinend die SD-Karte nicht defekt. Das System ist vom scratch, allein restore habe ich gemacht. Das kann eigentlich nicht sein, oder? Aber was ist es denn dann?
<edit> mit Verbose:
zuerst "API token successfully saved"
2020.01.19 11:14:34 5: TelegramBot_UpdatePoll TelegramBot: called
2020.01.19 11:14:34 5: TelegramBot_UpdatePoll TelegramBot: - Initiate non blocking polling - With callback set
2020.01.19 11:14:34 5: TelegramBot_readToken: Read Telegram API token from file
2020.01.19 11:14:34 1: TelegramBot_readToken: Error: No API token in file
2020.01.19 11:14:34 4: TelegramBot_UpdatePoll TelegramBot: initiate polling with nonblockingGet with 60s
2020.01.19 11:14:34 5: TelegramBot_UpdatePoll TelegramBot: - Ende > next polling started
2020.01.19 11:14:34 5: TelegramBot_Callback TelegramBot: called from Polling
2020.01.19 11:14:34 5: TelegramBot_Callback TelegramBot: data returned :{"ok":false,"error_code":404,"description":"Not Found"}:
2020.01.19 11:14:34 5: TelegramBot_Deepencode TelegramBot: encoded a String from :0: to :0:
2020.01.19 11:14:34 5: TelegramBot_Deepencode TelegramBot: encoded a String from :404: to :404:
2020.01.19 11:14:34 5: TelegramBot_Deepencode TelegramBot: encoded a String from :Not Found: to :Not Found:
2020.01.19 11:14:34 5: TelegramBot_Deepencode TelegramBot: found a HASH
2020.01.19 11:14:34 5: TelegramBot_Callback TelegramBot: after encoding
2020.01.19 11:14:34 5: TelegramBot_Callback TelegramBot: polling returned result? <undef>
2020.01.19 11:14:34 5: TelegramBot_Set TelegramBot: called
2020.01.19 11:14:34 5: TelegramBot_Set TelegramBot: called
2020.01.19 11:14:34 5: TelegramBot_UpdatePoll TelegramBot: called
2020.01.19 11:14:34 5: TelegramBot_UpdatePoll TelegramBot: got fails :108: wait 110 seconds
2020.01.19 11:14:34 4: TelegramBot_Callback TelegramBot: resulted in Callback returned error :Not Found: from Polling
2020.01.19 11:14:34 5: TelegramBot_Callback TelegramBot: - Ende > Control back to FHEM
und dann steht da
2020.01.19 11:15:44 5: TelegramBot_Set TelegramBot: called
2020.01.19 11:15:44 4: TelegramBot_Set TelegramBot: Processing TelegramBot_Set( token )
2020.01.19 11:15:44 4: TelegramBot_Setup TelegramBot: called
2020.01.19 11:15:44 5: TelegramBot_readToken: Read Telegram API token from file
2020.01.19 11:15:44 1: TelegramBot_readToken: Error: No API token in file
2020.01.19 11:15:44 1: TelegramBot_Setup TelegramBot: no valid API token found, please call "set TelegramBot token <token>" (once)
2020.01.19 11:15:44 5: TelegramBot_Set TelegramBot: token failed with :API token successfully saved:
Was sagen die Rechte von den angemerkten Dateien? Wie sind die Rechte der darüber liegenden Verzeichnisse?
Gerade nochmal hochgesetzt, ohne Änderung:
-rw-rw-rw- 1 fhem dialout 1838 Jan 5 11:48 phone_answering.svg
-rw-rw-rw- 1 fhem dialout 2298 Jan 5 11:48 phone_call_end_in.svg
-rw-rw-rw- 1 fhem dialout 2209 Jan 5 11:48 phone_call_end_out.svg
Ich habe gerade einen anderen Verdacht, weil es ja zwei anscheinend unabhängige Probleme gibt. Der API Telegram token wird angeblich auch nicht gespeichert. Ich habe mir den Quelltext angeschaut und dort ein paar Logs eingebaut (weil ich den Quelltext für die Einbindung der Bilder nicht gefunden habe). Also das Speichern des Telegram Token sieht bei mir jetzt so aus:
sub TelegramBot_storeToken($$;$)
{
my ($hash, $token, $name) = @_;
if ( $token !~ /^([[:alnum:]]|[-:_])+[[:alnum:]]+([[:alnum:]]|[-:_])+$/ ) {
return "specify valid API token containing only alphanumeric characters and -: characters";
}
$name = $hash->{NAME} if ( ! defined($name) );
my $index = "TelegramBot_".$name."_token";
my $key = getUniqueId().$index;
Log3 $hash, 5, "TelegramBot_storeToken: key =".$key;
my $enc_pwd = "";
if(eval "use Digest::MD5;1")
{
$key = Digest::MD5::md5_hex(unpack "H*", $key);
$key .= Digest::MD5::md5_hex($key);
}
for my $char (split //, $token)
{
my $encode=chop($key);
$enc_pwd.=sprintf("%.2x",ord($char)^ord($encode));
$key=$encode.$key;
}
my $err = setKeyValue($index, $enc_pwd);
Log3 $hash, 5, "TelegramBot_storeToken: enc_pwd =".$enc_pwd;
return "error while saving the API token - $err" if(defined($err));
return "API token successfully saved";
} # end storeToken
und nach der Eingabe sehe ich im Log
2020.01.19 11:45:41 5: TelegramBot_Get TelegramBot: called
2020.01.19 11:45:51 5: TelegramBot_Set TelegramBot: called
2020.01.19 11:45:51 4: TelegramBot_Set TelegramBot: Processing TelegramBot_Set( token )
2020.01.19 11:45:51 5: TelegramBot_storeToken: key =cbb76ab9cd50543b8af153e63c1acc6dTelegramBot_TelegramBot_token
2020.01.19 11:45:51 5: TelegramBot_storeToken: enc_pwd =daswuredeichjetzthiermalnichtwiedergebenweilessogeheimist
Ich weiß aber nicht, wo der Token gespeichert wird (und Dbconf habe ich eigentlich nicht, sondern fhem.cfg - oder legt sich das automatisch an?). Analog das auslesen, da habe ich umgeschrieben in
sub TelegramBot_readToken($;$)
{
my ($hash, $name) = @_;
$name = $hash->{NAME} if ( ! defined($name) );
my $index = "TelegramBot_" . $name . "_token";
my $key = getUniqueId().$index;
my ($token, $err);
Log3 $hash, 5, "TelegramBot_readToken: Read Telegram API token from file";
Log3 $hash, 5, "TelegramBot_readToken: index =".$index;
($err, $token) = getKeyValue($index);
Log3 $hash, 5, "TelegramBot_readToken: filename err =".$err;
Log3 $hash, 5, "TelegramBot_readToken: token =".$token;
if ( defined($err) ) {
Log3 $hash, 1, "TelegramBot_readToken: Error: unable to read API token from file: $err";
return "";
}
Log3 $hash, 5, "TelegramBot_readToken: token ".$token;
if ( defined($token) ) {
if ( eval "use Digest::MD5;1" ) {
$key = Digest::MD5::md5_hex(unpack "H*", $key);
$key .= Digest::MD5::md5_hex($key);
}
my $dec_pwd = '';
for my $char (map { pack('C', hex($_)) } ($token =~ /(..)/g)) {
my $decode=chop($key);
$dec_pwd.=chr(ord($char)^ord($decode));
$key=$decode.$key;
}
return $dec_pwd;
}
else {
Log3 $hash, 1, "TelegramBot_readToken: Error: No API token in file";
return "";
}
} # end readToken
und hier lese ich:
2020.01.19 11:45:51 5: TelegramBot_readToken: Read Telegram API token from file
2020.01.19 11:45:51 5: TelegramBot_readToken: index =TelegramBot_TelegramBot_token
2020.01.19 11:45:52 1: PERL WARNING: Use of uninitialized value $err in concatenation (.) or string at ./FHEM/50_TelegramBot.pm line 3303.
2020.01.19 11:45:52 5: TelegramBot_readToken: filename err =
2020.01.19 11:45:52 1: PERL WARNING: Use of uninitialized value $token in concatenation (.) or string at ./FHEM/50_TelegramBot.pm line 3304.
2020.01.19 11:45:52 5: TelegramBot_readToken: token =
2020.01.19 11:45:52 1: PERL WARNING: Use of uninitialized value $token in concatenation (.) or string at ./FHEM/50_TelegramBot.pm line 3311.
2020.01.19 11:45:52 5: TelegramBot_readToken: token
2020.01.19 11:45:52 1: TelegramBot_readToken: Error: No API token in file
2020.01.19 11:45:52 1: TelegramBot_Setup TelegramBot: no valid API token found, please call "set TelegramBot token <token>"
./FHEM/FhemUtils/uniqueID
Dort wird das Passwort oder der Token gespeichert
Ich würde ein
chmod -R 755 /opt/fhem/
machen.
Hat nichts gebracht:
2020.01.19 12:35:46 1: ./www/images/fhemSVG/car.svg is not useable
2020.01.19 12:36:29 1: ./www/images/openautomation/phone_call_end_out.svg is not useable
2020.01.19 12:36:29 1: ./www/images/openautomation/phone_answering.svg is not useable
2020.01.19 12:36:29 1: ./www/images/openautomation/phone_call_end_out.svg is not useable
2020.01.19 12:36:29 1: ./www/images/openautomation/phone_answering.svg is not useable
2020.01.19 12:36:55 5: TelegramBot_UpdatePoll TelegramBot: called
2020.01.19 12:36:55 5: TelegramBot_UpdatePoll TelegramBot: - Initiate non blocking polling - With callback set
2020.01.19 12:36:55 5: TelegramBot_readToken: Read Telegram API token from file
2020.01.19 12:36:55 5: TelegramBot_readToken: index =TelegramBot_TelegramBot_token
2020.01.19 12:36:55 5: TelegramBot_readToken: filename err =
2020.01.19 12:36:55 5: TelegramBot_readToken: token =
2020.01.19 12:36:55 5: TelegramBot_readToken: token
2020.01.19 12:36:55 1: TelegramBot_readToken: Error: No API token in file
Dabei enthält die Datei uniqueID den Token:
TelegramBot_TelegramBot_token:wiegesagtdaswirdsehrgeheimsein
Das ist doch nicht normal, dass das auf zwei verschiedenen RPis, SD-Karten und zwei Installationen passiert?!
Könnte die Reihenfolge, in der die Geräte angelegt werden, etwas ausmachen? Dann würde ich FHEM entfernen und händisch alle Geräte noch einmal hinzufügen. Ich habe gestern schon den ganzen Tag damit zugebracht, die eine Stunde kann ich dann auch noch investieren.
Ich würde einfach FHEM mal im Debug laufen lassen. Siehe Wiki FHEM startet nicht, letzter Abschnitt.
Es war die Reihenfolge. Ich habe die Geräte in der fhem.cfg anders angeordnet (insbesondere die IODev nach vorn) und auf einmal konnte man die svg-Datei wieder sehen, die angeblich verschwunden war. Telegram geht auch wieder.
Das war vielleicht eine Tortur. Danke!
Das ergibt im Nachhinein alles Sinn. Die Probleme traten auf, als ich den neuen Signalduino in FHEM eingerichtet hatte. Der stand in der cfg ganz unten.
Gesendet von iPhone mit Tapatalk Pro
Interessant. Vielleicht kannst Du Deine Erkenntnis dem Modulauthor einmal zukommen lassen.
Zitat von: andies am 19 Januar 2020, 13:55:28
Das ergibt im Nachhinein alles Sinn. Die Probleme traten auf, als ich den neuen Signalduino in FHEM eingerichtet hatte. Der stand in der cfg ganz unten.
Kannst Du mir dazu weitere Details liefern?
Insbesondere die Definition des SIGNALduino und ggf. die Definitionen der fehlerhaften Geräte.
Zitat von: Sidey am 19 Januar 2020, 16:39:09
Kannst Du mir dazu weitere Details liefern?
Ja, kann ich. Aber ich bin mir sicher, dass nicht das Modul, sondern etwas intern in FHEM verantwortlich ist. Denn das Problem trat bei zwei devices auf, die nichts mit dem Signalduino zu tun haben: FHEMWEB sowie TelegramBot. Und der Fehler dort war unerklärlich. Ich denke eher, wir sollten so etwas nur im Hinterkopf behalten. Anscheinend ist es wichtig, wann das Signalduino-Device geladen wird, das sollte möglichst frühzeitig sein und wenn jemand viel später dieses Gerät einbaut und sich dann wundert, das irgendwas nicht läuft, sollte er erstmal die Reihenfolge umstellen und schauen, ob dadurch das Problem gelöst wird.
Und wenn ich Dich schon mal "an der Strippe habe": https://forum.fhem.de/index.php/topic,38831.msg1014767.html#msg1014767 (https://forum.fhem.de/index.php/topic,38831.msg1014767.html#msg1014767)
So, und nun das versprochene device:
Internals:
Clients :IT:CUL_TCM97001:SD_RSL:OREGON:CUL_TX:SD_AS:Hideki:SD_WS07:SD_WS09: :SD_WS:RFXX10REC:Dooya:SOMFY:SD_BELL:SD_UT:SD_WS_Maverick:FLAMINGO:CUL_WS:Revolt: :FS10:CUL_FHTTK:Siro:FHT:FS20:CUL_EM:Fernotron:SD_Keeloq:SIGNALduino_un:
DEF 192.168.2.54:23
DMSG W44#F122E0910EDD1F6E7E
DevState initialized
DeviceName 192.168.2.54:23
FD 11
FUUID 5e244bdb-f33f-1115-16ab-f3c40f04ad5f2293
FVERSION 00_SIGNALduino.pm:v3.4.1-s20746/2019-12-14
LASTDMSG W44#F122E0910EDD1F6E7E
LASTDMSGID 44
MSGCNT 45
NAME sduino
NR 45
NR_CMD_LAST_H 2
PARTIAL
RAWMSG MU;P0=14600;P1=-1903;P2=1998;P3=3952;P4=-3841;P5=-10464;D=012134242424242121212421212421212124212424242121212121242121242121212421212121242424212424212424242124212121242424242421242421242424212124242424242425;CP=2;R=4;
RSSI -72
STATE opened
TIME 1579450546.38489
TYPE SIGNALduino
hasCC1101 1
sendworking 0
version V 3.3.1 SIGNALduino cc1101 (433 Mhz ) - compiled at Dec 3 2019 19:42:03
versionProtocols 1.10
versionmodul v3.4.1
DoubleMsgIDs:
MatchList:
10:SD_WS07 ^P7#[A-Fa-f0-9]{6}[AFaf][A-Fa-f0-9]{2,3}
11:SD_WS09 ^P9#F[A-Fa-f0-9]+
12:SD_WS ^W\d+x{0,1}#.*
13:RFXX10REC ^(20|29)[A-Fa-f0-9]+
14:Dooya ^P16#[A-Fa-f0-9]+
15:SOMFY ^Ys[0-9A-F]+
16:SD_WS_Maverick ^P47#[A-Fa-f0-9]+
17:SD_UT ^P(?:14|29|30|34|46|68|69|76|81|83|86|90|91|91.1|92|93|95)#.*
18:FLAMINGO ^P13\.?1?#[A-Fa-f0-9]+
19:CUL_WS ^K[A-Fa-f0-9]{5,}
1:IT ^i......
20:Revolt ^r[A-Fa-f0-9]{22}
21:FS10 ^P61#[A-F0-9]+
22:Siro ^P72#[A-Fa-f0-9]+
23:FHT ^81..(04|09|0d)..(0909a001|83098301|c409c401)..
24:FS20 ^81..(04|0c)..0101a001
25:CUL_EM ^E0.................
26:Fernotron ^P82#.*
27:SD_BELL ^P(?:15|32|41|42|57|79|96)#.*
28:SD_Keeloq ^P(?:87|88)#.*
2:CUL_TCM97001 ^s[A-Fa-f0-9]+
3:SD_RSL ^P1#[A-Fa-f0-9]{8}
4:OREGON ^(3[8-9A-F]|[4-6][0-9A-F]|7[0-8]).*
5:CUL_TX ^TX..........
6:SD_AS ^P2#[A-Fa-f0-9]{7,8}
7:Hideki ^P12#75[A-F0-9]+
9:CUL_FHTTK ^T[A-F0-9]{8}
X:SIGNALduino_un ^[u]\d+#.*
QUEUE:
READINGS:
2020-01-19 12:36:06 ccconf freq:433.920MHz bWidth:325KHz rAmpl:42dB sens:8dB (DataRate:5603.79Baud)
2020-01-18 23:10:43 ccpatable C3E = 00 C0 00 00 00 00 00 00 => 10_dBm
2020-01-19 16:57:38 ping OK
2020-01-19 13:30:51 state opened
2020-01-19 13:30:51 version V 3.3.1 SIGNALduino cc1101 (433 Mhz ) - compiled at Dec 3 2019 19:42:03
XMIT_TIME:
1579447795.12112
1579449564.14334
getcmd:
keepalive:
ok 1
retry 0
mcIdList:
10
11
12
18
43
58
msIdList:
0
0.1
0.2
0.3
0.4
1
4
6
7
17
23
25
33
33.1
33.2
35
51
53
55
65
74.1
muIdList:
8
9
17.1
24
26
27
28
29
30
31
34
37
39
44
44.1
45
48
59
60
61
62
64
66
67
70
72
74
80
84
85
89
94
Attributes:
development 0
flashCommand avrdude -c arduino -b 57600 -P [PORT] -p atmega328p -v -v -U flash:w:[HEXFILE] 2>[LOGFILE]
group intern
hardware miniculCC1101
updateChannelFW stable
whitelist_IDs 0,0.1,0.2,0.3,0.4,1,4,6,7,8,9,10,11,12,17,17.1,18,23,24,25,26,27,28,29,30,31,33,33.1,33.2,34,35,37,39,43,44,44.1,45,48,51,53,55,58,59,60,61,62,64,65,66,67,70,72,74,74.1,80,84,85,89,9
Zitat von: andies am 19 Januar 2020, 17:20:10
Ja, kann ich.
Ich meinte mit Definition die Ausgabe unter "Raw Definition". :)
Danke und Grüße
Sidey
Ach so:
defmod sduino SIGNALduino 192.168.2.54:23
attr sduino development 0
attr sduino flashCommand avrdude -c arduino -b 57600 -P [PORT] -p atmega328p -v -v -U flash:w:[HEXFILE] 2>[LOGFILE]
attr sduino group intern
attr sduino hardware miniculCC1101
attr sduino updateChannelFW stable
attr sduino whitelist_IDs 0,0.1,0.2,0.3,0.4,1,4,6,7,8,9,10,11,12,17,17.1,18,23,24,25,26,27,28,29,30,31,33,33.1,33.2,34,35,37,39,43,44,44.1,45,48,51,53,55,58,59,60,61,62,64,65,66,67,70,72,74,74.1,80,84,85,89,94
setstate sduino opened
setstate sduino 2020-01-19 19:21:18 ccconf freq:433.920MHz bWidth:325KHz rAmpl:42dB sens:8dB (DataRate:5603.79Baud)
setstate sduino 2020-01-19 19:21:26 ccpatable C3E = 00 C0 00 00 00 00 00 00 => 10_dBm
setstate sduino 2020-01-19 19:21:34 config MS=1;;MU=1;;MC=1;;Mred=1
setstate sduino 2020-01-19 19:55:07 ping OK
setstate sduino 2020-01-19 13:30:51 state opened
setstate sduino 2020-01-19 13:30:51 version V 3.3.1 SIGNALduino cc1101 (433 Mhz ) - compiled at Dec 3 2019 19:42:03
Nachdem es hierzu inzwischen mehrere Threads gibt (einmal zu den svg-Dateien hier und noch zu dem TelegramBOt API key) - poste ich auch noch ein paar Gedanken hier:
Zuerstmal der Hinweis aus dem Telegrambot-Thread: Das TelegramBot-Problem scheint mit einer "korrupten" uniqueID zusammenzuhängen. Denn das token steht wohl mehfrach in der Datei, wird aber nicht gefunden (get/setKeyValue). Auch die uniqueID selbst taucht mehrfach auf. Diese Datei ist bei Andies auch recht gross -> über 1MB
1) Ich kann mir schwer vorstellen, dass es einen Zusammenhang zu signalduino gibt
2) Ob es einen Zusammenhang mit FBCalllist gibt ist auch nicht klar, FBCalllist legt die Daten der Calllist in der uniqueID ab und auch dies kommt mehrfach vor
Meine Empfehlung wäre auf jeden Fall das Problem mit der uniqueID (am besten durch Neuanlage) zu lösen, denn das kann potentiell viele Module betreffen, die darin Daten ablegen und auch die uniqueID selbst ist ja wichtig