Anwesenheitserkennung Bluetooth PebbleBee

[PatrickR]

Hi!

Kurze Anmerkungen:
Die URL "http://svn.fhem.de/trac/browser/trunk/fhem/contrib/PRESENCE/deb/lepresenced-0.8-1.deb" ist nicht korrekt, da sie auf die SVN-Website(!) führt aber nicht auf den Datei-Download. Probiere mal https://svn.fhem.de/trac/export/13992/trunk/fhem/contrib/PRESENCE/deb/lepresenced-0.8-1.deb

Der FHEM-Update-Befehl aktualisiert das Contrib-Verzeichnis nicht.

Aktuelle Version ist übrigens 0.81, aber ein Paket habe ich bislang nur für 0.8 gebaut.

Danke fürs Aktualisieren der Doku und fürs Testen!

Patrick


Von unterwegs gesendet.

[Devender]

Hi!

Kurze Anmerkungen:
Die URL "http://svn.fhem.de/trac/browser/trunk/fhem/contrib/PRESENCE/deb/lepresenced-0.8-1.deb" ist nicht korrekt, da sie auf die SVN-Website(!) führt aber nicht auf den Datei-Download. Probiere mal https://svn.fhem.de/trac/export/13992/trunk/fhem/contrib/PRESENCE/deb/lepresenced-0.8-1.deb

japp, das geht mit

Code Auswählen Erweitern

wget https://svn.fhem.de/trac/export/13992/trunk/fhem/contrib/PRESENCE/deb/lepresenced-0.8-1.deb

Danke Patrick!

[SouzA]

Hallo,

ich habe mal wieder ein Problem mit der Batterieüberwachung.
Nachdem ich mal einen Ausflug mit dem Modul bletagbattery gemacht habe und danach das lepresenced gar nicht mehr funktioniert hat, bin ich nun wieder mit den Anleitungen von der Anwesenheitserkennung im Wiki unterwegs.
Habe alles nach Wiki eingerichtet, lepresenced läuft, die Gtags werden auch erkannt.
Wenn ich die Anleitung für die Batterieüberwachung durchgehe, mache ich folgendes:

Code Auswählen Erweitern


sudo apt-get install bc


mit nano ein skript in /home/pi namens Auto_GtagBatterie.sh mit folgendem Inhalt erstellen:

Code Auswählen Erweitern

#!/bin/bash
stringZ=$(sudo gatttool -b 7C:2F:80:AD:AD:4D --char-read --handle=0x001b)
stringZ=${stringZ:33:2}
stringZ=$(echo "$stringZ" | tr a-f A-F)
decimal=$(echo "ibase=16; $stringZ" | bc)
perl /opt/fhem/fhem.pl 7072 "setreading Auto_Gtag batteryLevel $decimal"

Ausführbar machen:

Code Auswählen Erweitern

sudo chmod +x Auto_GtagBatterie.sh


in fhem erweitere ich das userreading vom device entsprechend:

Code Auswählen Erweitern

attr Auto_Gtag userReadings batteryLevel

Wenn ich das skript nun ausführen will mit:

Code Auswählen Erweitern

./Auto_GtagBatterie.sh
oder
sudo ./Auto_GtagBatterie.sh
oder
bash ./Auto_GtagBatterie.sh
oder
sudo bash ./Auto_GtagBatterie.sh

kommt folgende Fehlermeldung:

Code Auswählen Erweitern

Use of uninitialized value in numeric gt (>) at /opt/fhem/fhem.pl line 452.

Wenn ich nun mit nano mal /opt/fhem/fhem.pl in Zeile 452 gucke, steht dieses hier dort:

Code Auswählen Erweitern

  while(sysread($client, $buf, 256) > 0) {


Das gehört in Summe zu:

Code Auswählen Erweitern

# Client code
if(int(@ARGV) > 1 && $ARGV[$#ARGV] ne "-i") {
  my $buf;
  my $addr = shift @ARGV;
  $addr = "localhost:$addr" if($addr !~ m/:/);
  my $client = IO::Socket::INET->new(PeerAddr => $addr);
  die "Can't connect to $addr\n" if(!$client);
  for(my $i=0; $i < int(@ARGV); $i++) {
    syswrite($client, $ARGV[$i]."\n");
  }
  shutdown($client, 1);
  while(sysread($client, $buf, 256) > 0) {
    $buf =~ s/\xff\xfb\x01Password: //;
    $buf =~ s/\xff\xfc\x01\r\n//;
    $buf =~ s/\xff\xfd\x00//;
    print($buf);
  }
  exit(0);
}
# End of client code
###################################################


Was hat das mit dem batteryLevel zu tun? Wofür ist das überhaupt? Wo kommt das her?
Und warum kommt der Fehler überhaupt?

Kann mich da jemand unterstützen?

Fragen über Fragen...

Vielen Dank und bis denn
SouzA

[Devender]

Meine Vermutung, er uebersetzt dir in der Zeile das $dezimal nicht mit einer Zahl.
Sender mal zum Test

Code Auswählen Erweitern


perl /opt/fhem/fhem.pl 7072 "setreading Auto_Gtag batteryLevel $decimal"
In

Code Auswählen Erweitern


perl /opt/fhem/fhem.pl 7072 "setreading Auto_Gtag batteryLevel 100"

Die fhem pl Meldung dürfte mit dem Aufruf fuer das Setreading zu tun haben. Nicht mit dem eigentlich Abruf der Batteriewerte. Ggf kannst du noch ein echo in das Skript einbauen.

Bei mir funktionieren die Skripte grundsätzlich nicht mehr, da es wohl einen Konflikt mit der blueZ Version gibt.
Ein downgrade hatte damals noch mehr Schaden angerichtet.

[SouzA]

Bei mir funktionieren die Skripte grundsätzlich nicht mehr, da es wohl einen Konflikt mit der blueZ Version gibt.
Ein downgrade hatte damals noch mehr Schaden angerichtet.

Hast du noch eine Batterieüberwachung drin?
Wenn ja, wie machst du das?


BTW.:
Es kommt der gleiche Fehler, wenn ich 100 statt $decimal eingebe.

[Devender]

Was passiert wenn du den fhem Aufruf entfernt und mit echo das dezimal ausgeben lässt?
Wir müssten ja erst mal schauen, ob das Skript generell Werte liefert.

Du kannst auch noch das #!bin/bash entfernen. Bei einigen klappte es dann mit der Ausgabe.

Ich nutze auf einem neuen Pi3 (komplett neu aufgesetzt) das Modul BleTagBattery. Das funktioniert.

Jetzt erinnere ich mich auch. Das war kein Problem mit bluez sondern mit dem Kernel des Pi.  . Mit dem downgrade hatte ich dann richtig Probleme auf dem Hauptpi.

[Devender]

So gerade das Skript auf dem Pi3 getestet: --> funktioniert, ohne den #!/bin/bash Teil.

Auf dem Hauptpi geht es weiterhin nicht wegene Connection Refused 111 Problem.

Der Hauptpi hat den Kernel:

Code Auswählen Erweitern

Linux fhem 4.4.48-v7+ #962 SMP Fri Feb 10 16:25:08 GMT 2017 armv7l GNU/Linux

Der neue Pi (wo es klappt)

Code Auswählen Erweitern

Linux fhem2 4.4.50-v7+ #970 SMP Mon Feb 20 19:18:29 GMT 2017 armv7l GNU/Linux

Vielleicht hilft dir das noch weiter. Falls du ein Upgrade machen solltest, sicher vorher deine SD-Karte 

[SouzA]

Hi,

ich hab die Version

Code Auswählen Erweitern

Linux raspberrypi 4.9.22-v7+ #987 SMP Fri Apr 14 23:23:05 BST 2017 armv7l GNU/Linux


Ich hab es im übrigen auch hinbekommen.
Der Telnetport hatte ein Passwort.
Habe das durch ein Globalpasswort ersetzt und die interne Adresse auf allowed gesetzt.
--> Funzt



Bis denn
SouzA

[Devender]

Nabend zusammen,

Hat irgendjemand aktuell auch das Problem, das die Tags oft njcht mehr als Anwesend erkannt werden?
Habe zwei PIs + Collectored laufen und seit ein paar Tagen scheint es hier ein Problem zu geben.
Letztes FHEm Udpate dürfte Montag gewesen sein.
Heute bei einem Kumpel das gleich. Die Tags, obwohl fast direkt neben dem PI plaziert nicht mehr anwesend.

Wenn ich die DEF des Tags bearbeitet - Kleinigkeit aendere und die MAC wieder mal falsch setze und wieder korrigieren - ist der jeweilige Tag wieder einen Moment active und Anwesend.
Über lescan werden die Tags gefunden...

Bin gerade etwas verwundert, das es nicht nur bei mir nicht mehr geht.

Gruesse,
Dirk

[PatrickR]

@Devender: Was sagt denn das Debug-Log?


Von unterwegs gesendet.

[Devender]

gute Frage, wie bekomme ich das lepresend dazu im DEBUG zu starten?
Hat sich da was geändert?

Ich bekomme bei Eingabe von:
sudo /opt/fhem/script/lepresenced --loglevel LOG_DEBUG

Code Auswählen Erweitern


pi@fhem:/opt/fhem/script $ sudo /opt/fhem/script/lepresenced --loglevel LOG_DEBUG
Unknown option: loglevel
usage:
        lepresenced --bluetoothdevice <bluetooth device> --listenaddress <listen address> --listenport <listen port> --daemon
        lepresenced -b <bluetooth device> -a <listen address> -p <listen port> -d
example:
        lepresenced --bluetoothdevice hci0 --listenaddress 127.0.0.1 --listenport 5333 --daemon

im Normalmode steht ja nicht viel aussagekräftiges im syslog.

Ich weiß, ich hab es damals sogar ins Wiki geschrieben.. 

[Devender]

so, jetzt hab ich es.

Hier das syslog vom PI mit collectored (Hauptsystem)

Code Auswählen Erweitern


Apr 21 22:51:28 fhem kernel: [  464.408389] Bluetooth: hci0 advertising data length corrected
Apr 21 22:51:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 18, max age: 60, result: present.
Apr 21 22:51:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 18, max age: 60, result: present.
Apr 21 22:51:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 18, max age: 60, result: present.
Apr 21 22:51:29 fhem lepresenced[451]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:a1:2a:4d, interval: 60. Adding client 127.0.0.1:47970 to clients list.
Apr 21 22:51:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 18, max age: 60, result: present.
Apr 21 22:51:30 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 2, max age: 60, result: present.
Apr 21 22:51:30 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 2, max age: 60, result: present.
Apr 21 22:51:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 21, max age: 60, result: present.
Apr 21 22:51:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 21, max age: 60, result: present.
Apr 21 22:51:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 2, max age: 60, result: present.
Apr 21 22:51:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 2, max age: 60, result: present.
Apr 21 22:51:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 21, max age: 60, result: present.
Apr 21 22:51:32 fhem lepresenced[451]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:a1:2a:4d, interval: 60. Adding client 127.0.0.1:47986 to clients list.
Apr 21 22:51:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 21, max age: 60, result: present.
Apr 21 22:51:32 fhem kernel: [  468.434889] Bluetooth: hci0 advertising data length corrected
Apr 21 22:51:33 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, max age: 60, result: absence.
Apr 21 22:51:35 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, max age: 60, result: absence.
Apr 21 22:53:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 22:53:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 22:53:29 fhem kernel: [  585.013436] Bluetooth: hci0 advertising data length corrected
Apr 21 22:53:30 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, result: present.
Apr 21 22:53:30 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, result: present.
Apr 21 22:53:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 22:53:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 22:53:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, result: present.
Apr 21 22:53:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, result: present.
Apr 21 22:53:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 22:53:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 22:53:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
pr 21 22:58:32 fhem lepresenced[451]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:a1:2a:4d, interval: 60. Adding client 127.0.0.1:47986 to clients list.
Apr 21 22:58:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 19, max age: 60, result: present.
Apr 21 22:58:33 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, max age: 60, result: absence.
Apr 21 22:58:35 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, max age: 60, result: absence.
Apr 21 22:58:56 fhem lepresenced[451]: [tid:0] main::cleanup_task: Cleanup finished, deleted 0 devices in 0 seconds.
Apr 21 22:58:58 fhem lepresenced[451]: [tid:0] main::stats_task: Active clients: 23, known devices: 3 (min/max age: 1/902)
Apr 21 22:59:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, result: present.
Apr 21 22:59:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, result: present.
Apr 21 22:59:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, result: present.
Apr 21 22:59:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, result: present.
Apr 21 22:59:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, result: present.
Apr 21 22:59:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, result: present.
Apr 21 22:59:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, result: present.
Apr 21 22:59:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, result: present.
Apr 21 22:59:30 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, result: present.
Apr 21 22:59:30 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, result: present.
Apr 21 22:59:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 4, max age: 60, result: present.
Apr 21 22:59:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 4, max age: 60, result: present.
Apr 21 22:59:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, result: present.
Apr 21 22:59:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, result: present.
Apr 21 22:59:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 4, max age: 60, result: present.
Apr 21 22:59:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 4, max age: 60, result: present.
Apr 21 22:59:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 4, max age: 60, result: present.
Apr 21 22:59:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 4, max age: 60, result: present.
Apr 21 22:59:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 4, max age: 60, result: present.
Apr 21 22:59:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 4, max age: 60, result: present.
Apr 21 22:59:33 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, max age: 60, result: absence.
Apr 21 22:59:35 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, max age: 60, result: absence.
Apr 21 23:00:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:29 fhem lepresenced[451]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:a1:2a:4d, interval: 60. Adding client 127.0.0.1:47970 to clients list.
Apr 21 23:00:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:30 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, result: present.
Apr 21 23:00:30 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, result: present.
Apr 21 23:00:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, result: present.
Apr 21 23:00:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, result: present.
Apr 21 23:00:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:32 fhem lepresenced[451]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:a1:2a:4d, interval: 60. Adding client 127.0.0.1:47986 to clients list.
Apr 21 23:00:32 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:00:33 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 34, max age: 60, result: present.
Apr 21 23:00:33 fhem lepresenced[451]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:ad:bc:f7, interval: 60. Adding client 127.0.0.1:48000 to clients list.
Apr 21 23:00:33 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 34, max age: 60, result: present.
Apr 21 23:00:35 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 36, max age: 60, result: present.
Apr 21 23:00:35 fhem lepresenced[451]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:ad:bc:f7, interval: 60. Adding client 127.0.0.1:48006 to clients list.
Apr 21 23:00:35 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 36, max age: 60, result: present.
Apr 21 23:01:11 fhem lepresenced[451]: [tid:0] main: Client 127.0.0.1:47992 disconnected. Connected clients: 6.
Apr 21 23:01:11 fhem lepresenced[451]: [tid:0] main: Client 127.0.0.1:48006 disconnected. Connected clients: 5.
Apr 21 23:01:11 fhem lepresenced[451]: [tid:0] main: Client 127.0.0.1:47986 disconnected. Connected clients: 4.
Apr 21 23:01:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:01:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:01:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:01:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:01:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:01:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:01:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:01:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:01:29 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 21 23:01:30 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, result: present.
Apr 21 23:01:30 fhem lepresenced[451]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, result: present.


Hatte das System eben neu gestartet. Das Zweitsystem welches eigentlich sogar näher an den Tags dran ist bringt mir im Log eigentlich fast gar keine lepresend Einträge:

Code Auswählen Erweitern


Apr 21 22:55:49 salidadelsol kernel: [  689.435289] Bluetooth: hci0 advertising data length corrected
Apr 21 22:55:50 salidadelsol kernel: [  689.955501] Bluetooth: hci0 advertising data length corrected
Apr 21 22:55:50 salidadelsol kernel: [  690.152283] Bluetooth: hci0 advertising data length corrected
Apr 21 22:55:52 salidadelsol kernel: [  691.959700] bt_err_ratelimited: 4 callbacks suppressed
Apr 21 22:55:52 salidadelsol kernel: [  691.959722] Bluetooth: hci0 advertising data length corrected
Apr 21 22:55:52 salidadelsol kernel: [  692.154716] Bluetooth: hci0 advertising data length corrected
Apr 21 22:57:34 salidadelsol lepresenced[1633]: [tid:0] main::stats_task: Active clients: 1, known devices: 4 (min/max age: 1/1)


Kannst du damit was anfangen?

[PatrickR]

Guten Abend!

Kannst du damit was anfangen?

Ja.

• Du benutzt scheinbar auf dem Hauptsystem eine alte lepresenced-Version. Das macht die Fehlersuche nicht gerade einfacher.
• Ich sehe keine Probleme beim Scannen auf dem Bluetooth-Gerät, der Scan läuft sauber durch. Wildgewordene Batterieskripte scheiden als Ursache also aus.
• 7c:2f:80:a1:14:31 ist durchgängig present, das Alter der Beacons ist 1-2 Sekunden. So muss es sein.
• Für 7c:2f:80:a1:2a:4d sieht alles ganz normal aus, d. h. 22:51 Uhr present, 22:53 Uhr absent, 22:58Uhr present, 23:00Uhr absent. Hat sich der Tag tatsächlich bewegt oder ggf. hart an der Erreichbarkeitsgrenze befunden? Könnte vielleicht die Batterie schwächeln?
• 7c:2f:80:ad:bc:f7 sieht normal aus, d. h. 22:51Uhr - 22:59Uhr absent, ab 23:00 present. Hat sich der Tag tatsächlich bewegt oder ggf. hart an der Erreichbarkeitsgrenze befunden? Könnte vielleicht die Batterie schwächeln?
• Ich sehe keinen direkten Zusammenhang zwischen dem Wechsel absent->present und dem Neu-Connecten durch FHEM/collectord. Vereinzelt könnte es passen, meistens aber nicht.
• Auf salidadelsol wird in der Tat etwas wenig geloggt. Ich stelle aber fest, dass salidadelsol 4 MAC-Adressen "sieht", aber nur ein Client verbunden ist. Das heißt, dass sich für 3 der MACs weder FHEM noch collectord interessiert.

Code Auswählen Erweitern


Apr 21 22:58:58 fhem lepresenced[451]: [tid:0] main::stats_task: Active clients: 23, known devices: 3 (min/max age: 1/902)

23 Clients. Heiliger DoS, Batman! Das sollte zwar eigentlich lepresenced nicht in die Knie zwingen, könnte aber das Resultat von einem Problem an anderer Stelle sein, z. B. FHEM oder collectord bauen aus unerfindlichem Grund immer wieder neue Verbindungen zu lepresenced auf. Kannst Du Dir das erklären?

Schlage Folgendes vor: Du updatest auf allen Knoten lepresenced auf die aktuelle Version (>=0., setzt den Log Level auf LOG_DEBUG und postest sagen wir 5 Minuten Log beginnend ab dem Start der lepresenceds. Tags neben den Pis, nicht bewegen, keine Reconnects in FHEM etc.

/Edit: lepresenced Version 0.81 inkl DEB-Paket eingecheckt.

Patrick

[Devender]

Guten Morgen Patrick,

danke für deine Unterstützung
Du weißt doch, "Never touch a running system", aber ich hab jetzt auch mal das deb Paket installiert und den Daemon gestartet.

Die mir bekannten MAC Adressen (aktuell 3 Tags) sind:
7C:2F:80:A1:2A:4D Batterie ca 35% -- Liegt im Auto in der Einfahrt
7C:2F:80:AD:BC:F7 Batterie ca 95% -- Liegt im Auto in der Garage
7C:2F:80:A1:14:31 Batterie ca 95% --macht eigentlich fast keine Probleme, ausser in den letzten Tagen regelmäßiges absent obwohl immer im Haus

Weitere BT Geräte habe ich nicht am Laufen.

Für die Test habe ich jetzt alle drei Tags "zwischen" die beiden Pis gelegt.

Hier mal der Log_DEBUG des Hauptpi mit collectored:

Code Auswählen Erweitern


Apr 22 10:09:14 fhem lepresenced[25123]: [tid:0] main: Version 0.8 started (device: hci0, listen addr: 0.0.0.0, listen port: 5333, daemonize: 0, legacy mode: 0, rssi threshold: 10, log level: 7).
Apr 22 10:09:14 fhem lepresenced[25123]: [tid:1] main::bluetooth_scan_thread: Received 'Set scan parameters failed: Input/output error', resetting...
Apr 22 10:09:16 fhem lepresenced[25123]: [tid:0] main: Connection from 127.0.0.1:33622. Connected clients: 1.
Apr 22 10:09:16 fhem lepresenced[25123]: [tid:0] main: Connection from 127.0.0.1:33626. Connected clients: 2.
Apr 22 10:09:16 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:a1:2a:4d, interval: 60. Adding client 127.0.0.1:33622 to clients list.
Apr 22 10:09:16 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 22 10:09:16 fhem lepresenced[25123]: [tid:0] main: Connection from 127.0.0.1:33627. Connected clients: 3.
Apr 22 10:09:16 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:a1:2a:4d, interval: 60 by client 127.0.0.1:33622.
Apr 22 10:09:16 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:ad:bc:f7, interval: 60. Adding client 127.0.0.1:33626 to clients list.
Apr 22 10:09:16 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 22 10:09:16 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, max age: 60, result: absence.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main: Connection from 127.0.0.1:33624. Connected clients: 4.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:ad:bc:f7, interval: 60 by client 127.0.0.1:33626.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:ad:bc:f7, interval: 60. Adding client 127.0.0.1:33627 to clients list.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, max age: 60, result: absence.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, max age: 60, result: absence.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main: Connection from 127.0.0.1:33630. Connected clients: 5.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:ad:bc:f7, interval: 60 by client 127.0.0.1:33627.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:a1:14:31, interval: 60. Adding client 127.0.0.1:33624 to clients list.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, max age: 60, result: absence.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, max age: 60, result: absence.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main: Connection from 127.0.0.1:33632. Connected clients: 6.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:a1:14:31, interval: 60 by client 127.0.0.1:33624.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:a1:2a:4d, interval: 60. Adding client 127.0.0.1:33630 to clients list.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, max age: 60, result: absence.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:a1:2a:4d, interval: 60 by client 127.0.0.1:33630.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:a1:14:31, interval: 60. Adding client 127.0.0.1:33632 to clients list.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 22 10:09:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, max age: 60, result: absence.
Apr 22 10:09:18 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:a1:14:31, interval: 60 by client 127.0.0.1:33632.
Apr 22 10:09:18 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, max age: 60, result: absence.
Apr 22 10:09:25 fhem lepresenced[25123]: [tid:1] main::bluetooth_scan_thread: hcitool exited, retrying...
Apr 22 10:09:26 fhem lepresenced[25123]: [tid:1] main::bluetooth_scan_thread: Received 'Invalid device: Network is down', resetting...
Apr 22 10:09:27 fhem lepresenced[25123]: [tid:1] main::bluetooth_scan_thread: hcitool exited, retrying...
Apr 22 10:09:28 fhem lepresenced[25123]: [tid:1] main::bluetooth_scan_thread: Received 'LE Scan ...'.
Apr 22 10:10:02 fhem lepresenced[25123]: [tid:0] main: Connection from 127.0.0.1:33736. Connected clients: 7.
Apr 22 10:10:02 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query for mac address b0:b4:48:9d:04:22, interval: 48000. Adding client 127.0.0.1:33736 to clients list.
Apr 22 10:10:02 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address b0:b4:48:9d:04:22, max age: 48000, result: absence.
Apr 22 10:10:16 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -48, result: present.
Apr 22 10:10:16 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:a1:2a:4d, interval: 60 by client 127.0.0.1:33622.
Apr 22 10:10:16 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 0, max age: 60, rssi: -49, result: present.
Apr 22 10:10:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -52, result: present.
Apr 22 10:10:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -52, result: present.
Apr 22 10:10:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 2, max age: 60, rssi: -75, result: present.
Apr 22 10:10:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 0, max age: 60, rssi: -48, result: present.
Apr 22 10:10:17 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:ad:bc:f7, interval: 60 by client 127.0.0.1:33626.
Apr 22 10:10:17 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:ad:bc:f7, interval: 60 by client 127.0.0.1:33627.
Apr 22 10:10:17 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:a1:14:31, interval: 60 by client 127.0.0.1:33624.
Apr 22 10:10:17 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:a1:2a:4d, interval: 60 by client 127.0.0.1:33630.
Apr 22 10:10:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -52, result: present.
Apr 22 10:10:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -52, result: present.
Apr 22 10:10:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 2, max age: 60, rssi: -75, result: present.
Apr 22 10:10:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 0, max age: 60, rssi: -48, result: present.
Apr 22 10:10:18 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 0, max age: 60, rssi: -75, result: present.
Apr 22 10:10:18 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:a1:14:31, interval: 60 by client 127.0.0.1:33632.
Apr 22 10:10:18 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 0, max age: 60, rssi: -75, result: present.
Apr 22 10:11:16 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 2, max age: 60, rssi: -47, result: present.
Apr 22 10:11:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -54, result: present.
Apr 22 10:11:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -54, result: present.
Apr 22 10:11:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -76, result: present.
Apr 22 10:11:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -48, result: present.
Apr 22 10:11:18 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -76, result: present.
Apr 22 10:12:16 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -48, result: present.
Apr 22 10:12:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -54, result: present.
Apr 22 10:12:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -54, result: present.
Apr 22 10:12:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -72, result: present.
Apr 22 10:12:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -48, result: present.
Apr 22 10:12:18 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 2, max age: 60, rssi: -72, result: present.
Apr 22 10:13:16 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -49, result: present.
Apr 22 10:13:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -53, result: present.
Apr 22 10:13:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -53, result: present.
Apr 22 10:13:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -75, result: present.
Apr 22 10:13:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -49, result: present.
Apr 22 10:13:18 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -75, result: present.
Apr 22 10:14:16 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -49, result: present.
Apr 22 10:14:16 fhem lepresenced[25123]: [tid:0] main::stats_task: Active clients: 7, known devices: 3 (min/max age: 0/1)
Apr 22 10:14:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -54, result: present.
Apr 22 10:14:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -54, result: present.
Apr 22 10:14:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -76, result: present.
Apr 22 10:14:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -49, result: present.
Apr 22 10:14:18 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -76, result: present.
Apr 22 10:15:16 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -48, result: present.
Apr 22 10:15:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -52, result: present.
Apr 22 10:15:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -52, result: present.
Apr 22 10:15:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -75, result: present.
Apr 22 10:15:17 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -49, result: present.
Apr 22 10:15:18 fhem lepresenced[25123]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -75, result: present.


Und hier die Ausgabe vom zweiten Pi

Code Auswählen Erweitern


Apr 22 10:22:53 salidadelsol lepresenced[25739]: [tid:0] main: Version 0.8 started (device: hci0, listen addr: 0.0.0.0, listen port: 5333, daemonize: 0, legacy mode: 0, rssi threshold: 10, log level: 7).
Apr 22 10:22:53 salidadelsol lepresenced[25739]: [tid:1] main::bluetooth_scan_thread: Received 'Set scan parameters failed: Input/output error', resetting...
Apr 22 10:22:53 salidadelsol lepresenced[25739]: [tid:1] main::bluetooth_scan_thread: hcitool exited, retrying...
Apr 22 10:22:53 salidadelsol lepresenced[25739]: [tid:0] main: Connection from 192.168.178.28:45542. Connected clients: 1.
Apr 22 10:22:54 salidadelsol lepresenced[25739]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:a1:2a:4d, interval: 60. Adding client 192.168.178.28:45542 to clients list.
Apr 22 10:22:54 salidadelsol lepresenced[25739]: [tid:0] main: Connection from 192.168.178.28:45544. Connected clients: 2.
Apr 22 10:22:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, max age: 60, result: absence.
Apr 22 10:22:54 salidadelsol lepresenced[25739]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:ad:bc:f7, interval: 60. Adding client 192.168.178.28:45544 to clients list.
Apr 22 10:22:54 salidadelsol lepresenced[25739]: [tid:0] main: Connection from 192.168.178.28:45546. Connected clients: 3.
Apr 22 10:22:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, max age: 60, result: absence.
Apr 22 10:22:54 salidadelsol lepresenced[25739]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:ad:bc:f7, interval: 60. Adding client 192.168.178.28:45546 to clients list.
Apr 22 10:22:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, max age: 60, result: absence.
Apr 22 10:22:54 salidadelsol lepresenced[25739]: [tid:1] main::bluetooth_scan_thread: Received 'LE Scan ...'.
Apr 22 10:22:59 salidadelsol lepresenced[25739]: [tid:0] main: Connection from 192.168.178.28:45558. Connected clients: 4.
Apr 22 10:22:59 salidadelsol lepresenced[25739]: [tid:0] main: Connection from 192.168.178.28:45560. Connected clients: 5.
Apr 22 10:22:59 salidadelsol lepresenced[25739]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:a1:2a:4d, interval: 60. Adding client 192.168.178.28:45558 to clients list.
Apr 22 10:22:59 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 3, max age: 60, rssi: -61, result: present.
Apr 22 10:22:59 salidadelsol lepresenced[25739]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:a1:2a:4d, interval: 60 by client 192.168.178.28:45558.
Apr 22 10:22:59 salidadelsol lepresenced[25739]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:a1:14:31, interval: 60. Adding client 192.168.178.28:45560 to clients list.
Apr 22 10:22:59 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 0, max age: 60, rssi: -61, result: present.
Apr 22 10:22:59 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 0, max age: 60, rssi: -67, result: present.
Apr 22 10:23:00 salidadelsol lepresenced[25739]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:a1:14:31, interval: 60 by client 192.168.178.28:45560.
Apr 22 10:23:00 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -67, result: present.
Apr 22 10:23:01 salidadelsol lepresenced[25739]: [tid:0] main: Connection from 192.168.178.28:45562. Connected clients: 6.
Apr 22 10:23:01 salidadelsol lepresenced[25739]: [tid:0] main::handle_command: Received query for mac address 7c:2f:80:a1:14:31, interval: 60. Adding client 192.168.178.28:45562 to clients list.
Apr 22 10:23:01 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 0, max age: 60, rssi: -67, result: present.
Apr 22 10:23:02 salidadelsol lepresenced[25739]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:a1:14:31, interval: 60 by client 192.168.178.28:45562.
Apr 22 10:23:02 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -67, result: present.
Apr 22 10:23:34 salidadelsol lepresenced[25739]: [tid:0] main: Connection from 127.0.0.1:50120. Connected clients: 7.
Apr 22 10:23:35 salidadelsol lepresenced[25739]: [tid:0] main::handle_command: Received query for mac address b0:b4:48:9d:04:22, interval: 48000. Adding client 127.0.0.1:50120 to clients list.
Apr 22 10:23:35 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address b0:b4:48:9d:04:22, max age: 48000, result: absence.
Apr 22 10:23:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 0, max age: 60, rssi: -66, result: present.
Apr 22 10:23:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -65, result: present.
Apr 22 10:23:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -65, result: present.
Apr 22 10:23:54 salidadelsol lepresenced[25739]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:a1:2a:4d, interval: 60 by client 192.168.178.28:45542.
Apr 22 10:23:54 salidadelsol lepresenced[25739]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:ad:bc:f7, interval: 60 by client 192.168.178.28:45544.
Apr 22 10:23:54 salidadelsol lepresenced[25739]: [tid:0] main::handle_command: Received query update for mac address 7c:2f:80:ad:bc:f7, interval: 60 by client 192.168.178.28:45546.
Apr 22 10:23:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 0, max age: 60, rssi: -66, result: present.
Apr 22 10:23:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -65, result: present.
Apr 22 10:23:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -65, result: present.
Apr 22 10:23:59 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 0, max age: 60, rssi: -63, result: present.
Apr 22 10:24:00 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -69, result: present.
Apr 22 10:24:02 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -68, result: present.
Apr 22 10:24:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -63, result: present.
Apr 22 10:24:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 2, max age: 60, rssi: -65, result: present.
Apr 22 10:24:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 2, max age: 60, rssi: -65, result: present.
Apr 22 10:24:59 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -66, result: present.
Apr 22 10:25:00 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -69, result: present.
Apr 22 10:25:02 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -68, result: present.
Apr 22 10:25:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -65, result: present.
Apr 22 10:25:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -68, result: present.
Apr 22 10:25:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -68, result: present.
Apr 22 10:25:59 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -64, result: present.
Apr 22 10:26:00 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 2, max age: 60, rssi: -69, result: present.
Apr 22 10:26:02 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -69, result: present.
Apr 22 10:26:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 0, max age: 60, rssi: -65, result: present.
Apr 22 10:26:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -66, result: present.
Apr 22 10:26:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -66, result: present.
Apr 22 10:26:59 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -64, result: present.
Apr 22 10:27:00 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -67, result: present.
Apr 22 10:27:02 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -69, result: present.
Apr 22 10:27:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -67, result: present.
Apr 22 10:27:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -67, result: present.
Apr 22 10:27:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -67, result: present.
Apr 22 10:27:54 salidadelsol lepresenced[25739]: [tid:0] main::stats_task: Active clients: 7, known devices: 4 (min/max age: 0/8)
Apr 22 10:27:59 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -65, result: present.
Apr 22 10:28:00 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -69, result: present.
Apr 22 10:28:02 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 1, max age: 60, rssi: -70, result: present.
Apr 22 10:28:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -66, result: present.
Apr 22 10:28:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -66, result: present.
Apr 22 10:28:54 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:ad:bc:f7, age: 1, max age: 60, rssi: -66, result: present.
Apr 22 10:28:59 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:2a:4d, age: 1, max age: 60, rssi: -63, result: present.
Apr 22 10:29:00 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 0, max age: 60, rssi: -69, result: present.
Apr 22 10:29:02 salidadelsol lepresenced[25739]: [tid:0] main: Sending update for mac address 7c:2f:80:a1:14:31, age: 0, max age: 60, rssi: -69, result: present.


Aktuell sind alle Tags anwesend und es sieht gut aus. Auch das Reading "room" von collectored wird korrekt mit beiden Namen gefüllt.
Ich würde nachher noch mal einen Test machen, wo alle Tags wieder an ihrem normalen Platz liegen.
Beim 7C:2F:80:A1:2A:4D Batterie ca 35% würde ich ja noch vermuten, dass die Batterie ein Problem sein könnte. Allerdings ist die Verbindung zum zweit Pi geringer (Entfernung) als zum Hauptpi.
Vielleicht 3 Meter.

Kannst Du was aus dem Log sehen?

Warum im Log von gestern so viele Active clients bei der Zweitinstanz erschienen sind, kann ich mir nicht erklären. Vielleicht durch die alte Version von lepresenced!?

Dirk

[PatrickR]

Guten Morgen!

Du weißt doch, "Never touch a running system", aber ich hab jetzt auch mal das deb Paket installiert und den Daemon gestartet.

Dann haben wir ja jetzt ganz neue Freiheiten dadurch bekommen, dass es nicht mehr rennt In jedem Fall hilft uns die rssi, das Problem etwas besser zu verstehen. Da Du jetzt das Paket benutzt müsstest Du auch mal schauen, ob z. B. der automatische Start von lepresenced jetzt doppelt vorhanden ist. Das Paket kümmert sich nämlich darum.

Was man an dem Log des Haupt-PI sieht ist, dass er um 10:09:14Uhr startet, Verbindungen annimmt, aber erst um 10:09:29Uhr tatsächlich ein erfolgreicher Scan beginnt. Da er in der Zwischenzeit blind ist, erklärt das die absents. Da müsste lepresenced korrekterweise weder mit present noch mit absent antworten sondern mit "don't know" oder so. In jedem Fall sollte die Problematik aber nur beim Start auftreten - ausgenommen andere Dienste (Batterieskripte etc.) ziehen lepresenced das Bluetooth-Gerät wieder unter dem Hintern weg. Das kannst Du aber abmildern, wenn Du das Intervall genügend groß stellst. Ggf. reichen die 60 Sekunden schon. (Ich verwende 120s) => Beobachten.
Das Bild aus lepresenced-Sicht ist auf salidadelsol identisch (10:22:53 Uhr start, 10:22:54 Uhr Beginn des Scans).

Warum im Log von gestern so viele Active clients bei der Zweitinstanz erschienen sind, kann ich mir nicht erklären. Vielleicht durch die alte Version von lepresenced!?

Mit sowohl der Hauptinstanz als auch salidadelsol sind immer noch 7 Clients verbunden. Ich kann nicht ausschließen, dass lepresenced nicht sauber aufräumt wenn ein Client unerwartet verschwindet aber das kann auch nur ein Teil der Erklärung sein. Auf meiner Produktivinstanz (Version 0.81) sind durchweg 3 Clients verbunden (für 3 MAC-Adressen), setze aber auch keinen collectord ein. Bei Dir kommt noch folgender Eintrag hinzu:

Code Auswählen Erweitern


Apr 22 10:10:02 fhem lepresenced[25123]: [tid:0] main::handle_command: Received query for mac address b0:b4:48:9d:04:22, interval: 48000. Adding client 127.0.0.1:33736 to clients list.

Augenscheinlich kommt auf dem Haupt-Pi von lokal eine Anfrage nach einer von Dir nicht verwendeten MAC-Adresse und zwar mit einem Maximalalter von 48000 Sekunden, also knapp über 13 Stunden. Spannenderweise taucht ein entsprechender Zugriff auf salidadelsol (ebenfalls von lokal) auch auf:

Code Auswählen Erweitern

Apr 22 10:23:35 salidadelsol lepresenced[25739]: [tid:0] main::handle_command: Received query for mac address b0:b4:48:9d:04:22, interval: 48000. Adding client 127.0.0.1:50120 to clients list.

Zusammenfassend sieht es bei dem Testsetup gut aus. Nach den Startschwierigkeiten läuft aus lepresenced-Sicht alles perfekt, was sich mit Deinen Beobachtungen deckt. Sollte sich das ändern, wenn Du die Tags jetzt wieder in die Autos verfrachtest, würde ich auf Funkprobleme (Reichweite, Interferenzen, Reflexionen usw.) tippen und ggf. die Pis und Tags näher zusammen bringen. Wenn Abbrüche zwischendurch auftreten schau mal nach, ob da das Batterieskript läuft. Zu gegebener Zeit könntest Du Dir noch ansehen, wie es zu den vielen Connections kommt.

Wenn alles läuft nicht vergessen, den Log Level runterzusetzen, um die SD-Karte zu schonen.

Patrick