Problem zwischen lepresenced und hcitool (Bluetootherkennung)

Kharim · 02 Oktober 2017, 16:36:40

Hallo Zusammen,

ich bin gerade dabei, mein Bluetooth umzurüsten.

Ausgangssituation:
- RPI 2 mit FHEM, BlueTooth-Stick, lepresence und hcitool(und co)
- Scan auf Konsole über "sudo hcitool lescan" ok -> Beide Mi Band Typ 1 gefunden
- Im FHEM mittels BT-LAN über 127.0.0.1:5333 angebunden
Soweit alles gut. An/Abwesend wurde fehlerfrei (bis auf Reichweitenproblem) erkannt.

Nun bin ich gerade dabei, einen weiteren PI 2 aufzusetzen um dort die BT Erkennung laufen zu lassen. (Aufrund des Reichweitenproblems)
Was habe ich also getan?

-> BT-Stick am ersten PI abgezogen.
-> Raspbian lite
-> Durchinstallieren/Aktualisieren
- sudo apt-get install bluetooth bluez-hcidump bluez blueman bluez-tools
- sudo hcitool lescan -> ok, beide Mi Band gefunden
- wget https://svn.fhem.de/trac/export/13992/t ... -0.8-1.deb
- sudo dpkg -i lepresenced-0.8-1.deb
- sudo apt-get -f install
Nun im FHEM im Presence-Modul die IP des neuen PIs angeben -> BT-Geräte sollten wieder erkannt werden.

Dem ist auch so....aaaber: nach ca 2 Minuten fallen die BT-Geräte wieder auf abwesend und bleiben dort.
Gehe ich in die Konsole und starte "sudo hcitool lescan" werden die BT sofort erkannt und schwups meldet auch das FHEM wieder anwesend.
Nach 2 Minuten wieder abwesend -> Login -> Befehl -> Geräte da
usw usw usw

Es scheint also, als ob lepresence nicht alleine scannt?

(Ps.: Im Forum gibt so oder so ähnliche Beiträge mit diesem Problem - bin aber noch nirgend so wichtig fündig geworden.)

Vielen Dank,
Kharim

PatrickR · 02 Oktober 2017, 17:47:02

Deinstallere mal bitte blueman und starte den Pi durch. Falls das nicht hilft bitte wie immer ein Log mit LOG_DEBUG posten.

Von unterwegs gesendet.

Kharim · 02 Oktober 2017, 19:48:10

Die Deinstallation hat leider nichts gebracht.

(Behelfe mir aktuell mit einem Startscript, dass ein dauer-Scan erzeugt.)

Blöde Frage: Wie erzeuge ich das Debug-Log?

Danke,
Kharim

Devender · 02 Oktober 2017, 20:03:12

Zitat von: Kharim am 02 Oktober 2017, 19:48:10
Blöde Frage: Wie erzeuge ich das Debug-Log?

Danke,
Kharim

Schau mal bitte hier: https://wiki.fhem.de/wiki/PRESENCE#Probleml.C3.B6sungen

Dort wird Debug erklärt.
Und im speziellen noch den Bereich aus dem Wiki mit "Das BT-Device ist ständig "absent""

Und wie sehen deine defines der Tags bei den beiden PIs aus?

Kharim · 02 Oktober 2017, 21:08:37

Sorry wenn ich mich jetzt doof anstelle...

- bluez / bluez-hcidump sind installiert
- es handelt sich um einen Pi2 (also kein 3er)
- Wo genau muss man das "lepresenced --loglevel LOG_DEBUG" setzen? Sieht für mich nach einem Aufruf aus, also wird /usr/sbin/lepresenced wahrscheinlich der falsche Ort sein?!
- "Und wie sehen deine defines der Tags bei den beiden PIs aus?" Wie meinst du das? Wieso bei beiden Pi's?
Es läuft (logischer Weise) nur auf einem Pi ein FHEM, der das lepresenced des zweiten Pi's abfragt....

Code Auswählen

lan-bluetooth 88:0F:10:XX:XX:XX 192.168.100.33:5333 30 60

Grüße,
Kharim

Devender · 02 Oktober 2017, 22:02:05

Du kannst das lepresenced manuell mit dem passenden LOG Level starten oder du aenderst den Log Level im Script /usr/sbin ... manuell und startest dann den Systemservice neu.

Beides setzt voraus, dass du lepresenced vorher beendest.

Aber,was du ja eigentlich möchtest:

2 PIs, die Tags erkennen und somit Anwesend oder Abwesenheit signalisieren. Dafür eignet sich am besten lepresenced in Verbindung mit collectord.
Das heißt, auf beiden PIs wird eine lepresenced Installation durchgeführt ( und bei Bedarf - wenn du eine zweite fhem Installation machst die Tags einzeln auf beiden Systemen angelegt), sonst nur auf einer. Der Collecotred (siehe ebenfalls im Wiki) wird nur auf einer Instanz installiert und verbindet mit seiner Konfiguration deine beiden Systeme.

Details dazu auch wieder im Wikiartikel s.o.

Hilft dir das weiter?

PatrickR · 02 Oktober 2017, 23:51:28

Bitte bitte den Log Level in /etc/default/lepresenced ändern, nicht im Skript.

Von unterwegs gesendet.

Kharim · 03 Oktober 2017, 08:06:31

Zitat von: PatrickR am 02 Oktober 2017, 23:51:28
Bitte bitte den Log Level in /etc/default/lepresenced ändern, nicht im Skript.

Von unterwegs gesendet.

Danke, genau die Info hat gefehlt....Ebenso: Wo liegt das Log danach?
Ich habe unter /var/logs ein Debug-Log gefunden, das scheint aber ein Log der BT-Schnittstelle/hcitool selbst zu sein? - Oder etwas Vermischtes?
(Datei wird auch nach Deaktivierung und Neustart weiter geschrieben, aber nur der nicht lepresenced-Teil)

Code Auswählen

Sep  7 15:54:29 raspberrypi kernel: [    0.000000] On node 0 totalpages: 242688
Sep  7 15:54:29 raspberrypi kernel: [    0.000000] free_area_init_node: node 0, pgdat 80c6eec0, node_mem_map ba3a1000
Sep  7 15:54:29 raspberrypi kernel: [    0.000000]   Normal zone: 2133 pages used for memmap
Sep  7 15:54:29 raspberrypi kernel: [    0.000000]   Normal zone: 0 pages reserved
Sep  7 15:54:29 raspberrypi kernel: [    0.000000]   Normal zone: 242688 pages, LIFO batch:31
Sep  7 15:54:29 raspberrypi kernel: [    0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096
Sep  7 15:54:29 raspberrypi kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Sep  7 15:54:29 raspberrypi kernel: [    0.853291] dwc_otg: Microframe scheduler enabled
Sep  7 15:54:29 raspberrypi kernel: [    0.967339] dwc_otg: FIQ enabled
Sep  7 15:54:29 raspberrypi kernel: [    0.967347] dwc_otg: NAK holdoff enabled
Sep  7 15:54:29 raspberrypi kernel: [    0.967352] dwc_otg: FIQ split-transaction FSM enabled
Sep  7 15:54:29 raspberrypi kernel: [    0.967371] Module dwc_common_port init
Sep  7 15:54:29 raspberrypi thd[339]: Found socket passed from systemd
Oct  2 12:59:05 raspberrypi kernel: [    0.000000] On node 0 totalpages: 242688
Oct  2 12:59:05 raspberrypi kernel: [    0.000000] free_area_init_node: node 0, pgdat 80c6eec0, node_mem_map ba3a1000
Oct  2 12:59:05 raspberrypi kernel: [    0.000000]   Normal zone: 2133 pages used for memmap
Oct  2 12:59:05 raspberrypi kernel: [    0.000000]   Normal zone: 0 pages reserved
Oct  2 12:59:05 raspberrypi kernel: [    0.000000]   Normal zone: 242688 pages, LIFO batch:31
Oct  2 12:59:05 raspberrypi kernel: [    0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096
Oct  2 12:59:05 raspberrypi kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Oct  2 12:59:05 raspberrypi kernel: [    0.853328] dwc_otg: Microframe scheduler enabled
Oct  2 12:59:05 raspberrypi kernel: [    0.967477] dwc_otg: FIQ enabled
Oct  2 12:59:05 raspberrypi kernel: [    0.967485] dwc_otg: NAK holdoff enabled
Oct  2 12:59:05 raspberrypi kernel: [    0.967490] dwc_otg: FIQ split-transaction FSM enabled
Oct  2 12:59:05 raspberrypi kernel: [    0.967508] Module dwc_common_port init
Oct  2 12:59:05 raspberrypi thd[306]: Found socket passed from systemd
Oct  2 13:06:49 BTPI kernel: [    0.000000] On node 0 totalpages: 255488
Oct  2 13:06:49 BTPI kernel: [    0.000000] free_area_init_node: node 0, pgdat 80c6eec0, node_mem_map bd336000
Oct  2 13:06:49 BTPI kernel: [    0.000000]   Normal zone: 2246 pages used for memmap
Oct  2 13:06:49 BTPI kernel: [    0.000000]   Normal zone: 0 pages reserved
Oct  2 13:06:49 BTPI kernel: [    0.000000]   Normal zone: 255488 pages, LIFO batch:31
Oct  2 13:06:49 BTPI kernel: [    0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096
Oct  2 13:06:49 BTPI kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Oct  2 13:06:49 BTPI kernel: [    0.812402] dwc_otg: Microframe scheduler enabled
Oct  2 13:06:49 BTPI kernel: [    0.884603] dwc_otg: FIQ enabled
Oct  2 13:06:49 BTPI kernel: [    0.884612] dwc_otg: NAK holdoff enabled
Oct  2 13:06:49 BTPI kernel: [    0.884617] dwc_otg: FIQ split-transaction FSM enabled
Oct  2 13:06:49 BTPI kernel: [    0.884636] Module dwc_common_port init
Oct  2 13:06:49 BTPI thd[321]: Found socket passed from systemd
Oct  2 13:10:04 BTPI kernel: [    0.000000] On node 0 totalpages: 255488
Oct  2 13:10:04 BTPI kernel: [    0.000000] free_area_init_node: node 0, pgdat 80c6eb80, node_mem_map bd336000
Oct  2 13:10:04 BTPI kernel: [    0.000000]   Normal zone: 2246 pages used for memmap
Oct  2 13:10:04 BTPI kernel: [    0.000000]   Normal zone: 0 pages reserved
Oct  2 13:10:04 BTPI kernel: [    0.000000]   Normal zone: 255488 pages, LIFO batch:31
Oct  2 13:10:04 BTPI kernel: [    0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096
Oct  2 13:10:04 BTPI kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Oct  2 13:10:04 BTPI kernel: [    0.817332] dwc_otg: Microframe scheduler enabled
Oct  2 13:10:04 BTPI kernel: [    0.889394] dwc_otg: FIQ enabled
Oct  2 13:10:04 BTPI kernel: [    0.889402] dwc_otg: NAK holdoff enabled
Oct  2 13:10:04 BTPI kernel: [    0.889407] dwc_otg: FIQ split-transaction FSM enabled
Oct  2 13:10:04 BTPI kernel: [    0.889425] Module dwc_common_port init
Oct  2 13:10:04 BTPI thd[313]: Found socket passed from systemd
Oct  2 13:41:31 BTPI thd[307]: Found socket passed from systemd
Oct  2 13:41:31 BTPI kernel: [    0.000000] On node 0 totalpages: 255488
Oct  2 13:41:31 BTPI kernel: [    0.000000] free_area_init_node: node 0, pgdat 80c6eb80, node_mem_map bd336000
Oct  2 13:41:31 BTPI kernel: [    0.000000]   Normal zone: 2246 pages used for memmap
Oct  2 13:41:31 BTPI kernel: [    0.000000]   Normal zone: 0 pages reserved
Oct  2 13:41:31 BTPI kernel: [    0.000000]   Normal zone: 255488 pages, LIFO batch:31
Oct  2 13:41:31 BTPI kernel: [    0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096
Oct  2 13:41:31 BTPI kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Oct  2 13:41:31 BTPI kernel: [    0.817297] dwc_otg: Microframe scheduler enabled
Oct  2 13:41:31 BTPI kernel: [    0.889346] dwc_otg: FIQ enabled
Oct  2 13:41:31 BTPI kernel: [    0.889354] dwc_otg: NAK holdoff enabled
Oct  2 13:41:31 BTPI kernel: [    0.889359] dwc_otg: FIQ split-transaction FSM enabled
Oct  2 13:41:31 BTPI kernel: [    0.889377] Module dwc_common_port init
                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                              Oct  2 15:08:50 BTPI thd[305]: Found socket passed from systemd
Oct  2 15:08:50 BTPI kernel: [    0.000000] On node 0 totalpages: 255488
Oct  2 15:08:50 BTPI kernel: [    0.000000] free_area_init_node: node 0, pgdat 80c6eb80, node_mem_map bd336000
Oct  2 15:08:50 BTPI kernel: [    0.000000]   Normal zone: 2246 pages used for memmap
Oct  2 15:08:50 BTPI kernel: [    0.000000]   Normal zone: 0 pages reserved
Oct  2 15:08:50 BTPI kernel: [    0.000000]   Normal zone: 255488 pages, LIFO batch:31
Oct  2 15:08:50 BTPI kernel: [    0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096
Oct  2 15:08:50 BTPI kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Oct  2 15:08:50 BTPI kernel: [    0.814479] dwc_otg: Microframe scheduler enabled
Oct  2 15:08:50 BTPI kernel: [    0.840138] dwc_otg: FIQ enabled
Oct  2 15:08:50 BTPI kernel: [    0.840146] dwc_otg: NAK holdoff enabled
Oct  2 15:08:50 BTPI kernel: [    0.840151] dwc_otg: FIQ split-transaction FSM enabled
Oct  2 15:08:50 BTPI kernel: [    0.840168] Module dwc_common_port init
Oct  2 16:21:30 BTPI kernel: [    0.000000] On node 0 totalpages: 255488
Oct  2 16:21:30 BTPI kernel: [    0.000000] free_area_init_node: node 0, pgdat 80c6eb80, node_mem_map bd336000
Oct  2 16:21:30 BTPI kernel: [    0.000000]   Normal zone: 2246 pages used for memmap
Oct  2 16:21:30 BTPI kernel: [    0.000000]   Normal zone: 0 pages reserved
Oct  2 16:21:30 BTPI kernel: [    0.000000]   Normal zone: 255488 pages, LIFO batch:31
Oct  2 16:21:30 BTPI kernel: [    0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096
Oct  2 16:21:30 BTPI kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Oct  2 16:21:30 BTPI kernel: [    0.814568] dwc_otg: Microframe scheduler enabled
Oct  2 16:21:30 BTPI kernel: [    0.840261] dwc_otg: FIQ enabled
Oct  2 16:21:30 BTPI kernel: [    0.840268] dwc_otg: NAK holdoff enabled
Oct  2 16:21:30 BTPI kernel: [    0.840273] dwc_otg: FIQ split-transaction FSM enabled
Oct  2 16:21:30 BTPI kernel: [    0.840291] Module dwc_common_port init
Oct  2 16:21:30 BTPI thd[325]: Found socket passed from systemd
Oct  2 16:59:01 BTPI thd[310]: Found socket passed from systemd
Oct  2 16:59:01 BTPI kernel: [    0.000000] On node 0 totalpages: 255488
Oct  2 16:59:01 BTPI kernel: [    0.000000] free_area_init_node: node 0, pgdat 80c6eb80, node_mem_map bd336000
Oct  2 16:59:01 BTPI kernel: [    0.000000]   Normal zone: 2246 pages used for memmap
Oct  2 16:59:01 BTPI kernel: [    0.000000]   Normal zone: 0 pages reserved
Oct  2 16:59:01 BTPI kernel: [    0.000000]   Normal zone: 255488 pages, LIFO batch:31
Oct  2 16:59:01 BTPI kernel: [    0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096
Oct  2 16:59:01 BTPI kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Oct  2 16:59:01 BTPI kernel: [    0.814398] dwc_otg: Microframe scheduler enabled
Oct  2 16:59:01 BTPI kernel: [    0.840204] dwc_otg: FIQ enabled
Oct  2 16:59:01 BTPI kernel: [    0.840212] dwc_otg: NAK holdoff enabled
Oct  2 16:59:01 BTPI kernel: [    0.840217] dwc_otg: FIQ split-transaction FSM enabled
Oct  2 16:59:01 BTPI kernel: [    0.840235] Module dwc_common_port init
Oct  2 17:06:30 BTPI kernel: [    0.000000] On node 0 totalpages: 255488
Oct  2 17:06:30 BTPI kernel: [    0.000000] free_area_init_node: node 0, pgdat 80c6eb80, node_mem_map bd336000
Oct  2 17:06:30 BTPI kernel: [    0.000000]   Normal zone: 2246 pages used for memmap
Oct  2 17:06:30 BTPI kernel: [    0.000000]   Normal zone: 0 pages reserved
Oct  2 17:06:30 BTPI kernel: [    0.000000]   Normal zone: 255488 pages, LIFO batch:31
Oct  2 17:06:30 BTPI kernel: [    0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096
Oct  2 17:06:30 BTPI kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Oct  2 17:06:30 BTPI kernel: [    0.814528] dwc_otg: Microframe scheduler enabled
Oct  2 17:06:30 BTPI kernel: [    0.840336] dwc_otg: FIQ enabled
Oct  2 17:06:30 BTPI kernel: [    0.840344] dwc_otg: NAK holdoff enabled
Oct  2 17:06:30 BTPI kernel: [    0.840349] dwc_otg: FIQ split-transaction FSM enabled
Oct  2 17:06:30 BTPI kernel: [    0.840366] Module dwc_common_port init
Oct  2 17:06:30 BTPI thd[307]: Found socket passed from systemd
Oct  2 17:11:19 BTPI thd[312]: Found socket passed from systemd
Oct  2 17:11:19 BTPI kernel: [    0.000000] On node 0 totalpages: 255488
Oct  2 17:11:19 BTPI kernel: [    0.000000] free_area_init_node: node 0, pgdat 80c6eb80, node_mem_map bd336000
Oct  2 17:11:19 BTPI kernel: [    0.000000]   Normal zone: 2246 pages used for memmap
Oct  2 17:11:19 BTPI kernel: [    0.000000]   Normal zone: 0 pages reserved
Oct  2 17:11:19 BTPI kernel: [    0.000000]   Normal zone: 255488 pages, LIFO batch:31
Oct  2 17:11:19 BTPI kernel: [    0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096
Oct  2 17:11:19 BTPI kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Oct  2 17:11:19 BTPI kernel: [    0.814312] dwc_otg: Microframe scheduler enabled
Oct  2 17:11:19 BTPI kernel: [    0.840010] dwc_otg: FIQ enabled
Oct  2 17:11:19 BTPI kernel: [    0.840018] dwc_otg: NAK holdoff enabled
Oct  2 17:11:19 BTPI kernel: [    0.840023] dwc_otg: FIQ split-transaction FSM enabled
Oct  2 17:11:19 BTPI kernel: [    0.840041] Module dwc_common_port init
Oct  2 19:39:28 BTPI thd[306]: Found socket passed from systemd
Oct  2 19:39:28 BTPI kernel: [    0.000000] On node 0 totalpages: 255488
Oct  2 19:39:28 BTPI kernel: [    0.000000] free_area_init_node: node 0, pgdat 80c6eb80, node_mem_map bd336000
Oct  2 19:39:28 BTPI kernel: [    0.000000]   Normal zone: 2246 pages used for memmap
Oct  2 19:39:28 BTPI kernel: [    0.000000]   Normal zone: 0 pages reserved
Oct  2 19:39:28 BTPI kernel: [    0.000000]   Normal zone: 255488 pages, LIFO batch:31
Oct  2 19:39:28 BTPI kernel: [    0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096
Oct  2 19:39:28 BTPI kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Oct  2 19:39:28 BTPI kernel: [    0.814497] dwc_otg: Microframe scheduler enabled
Oct  2 19:39:28 BTPI kernel: [    0.840158] dwc_otg: FIQ enabled
Oct  2 19:39:28 BTPI kernel: [    0.840166] dwc_otg: NAK holdoff enabled
Oct  2 19:39:28 BTPI kernel: [    0.840171] dwc_otg: FIQ split-transaction FSM enabled
Oct  2 19:39:28 BTPI kernel: [    0.840189] Module dwc_common_port init
Oct  2 19:49:05 BTPI kernel: [    0.000000] On node 0 totalpages: 255488
Oct  2 19:49:05 BTPI kernel: [    0.000000] free_area_init_node: node 0, pgdat 80c6eb80, node_mem_map bd336000
Oct  2 19:49:05 BTPI kernel: [    0.000000]   Normal zone: 2246 pages used for memmap
Oct  2 19:49:05 BTPI kernel: [    0.000000]   Normal zone: 0 pages reserved
Oct  2 19:49:05 BTPI kernel: [    0.000000]   Normal zone: 255488 pages, LIFO batch:31
Oct  2 19:49:05 BTPI kernel: [    0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096
Oct  2 19:49:05 BTPI kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Oct  2 19:49:05 BTPI kernel: [    0.814330] dwc_otg: Microframe scheduler enabled
Oct  2 19:49:05 BTPI kernel: [    0.840025] dwc_otg: FIQ enabled
Oct  2 19:49:05 BTPI kernel: [    0.840033] dwc_otg: NAK holdoff enabled
Oct  2 19:49:05 BTPI kernel: [    0.840038] dwc_otg: FIQ split-transaction FSM enabled
Oct  2 19:49:05 BTPI kernel: [    0.840056] Module dwc_common_port init
Oct  2 19:49:05 BTPI thd[330]: Found socket passed from systemd
Oct  3 07:48:07 BTPI thd[309]: Found socket passed from systemd
Oct  3 07:48:07 BTPI kernel: [    0.000000] On node 0 totalpages: 255488
Oct  3 07:48:07 BTPI kernel: [    0.000000] free_area_init_node: node 0, pgdat 80c6eb80, node_mem_map bd336000
Oct  3 07:48:07 BTPI kernel: [    0.000000]   Normal zone: 2246 pages used for memmap
Oct  3 07:48:07 BTPI kernel: [    0.000000]   Normal zone: 0 pages reserved
Oct  3 07:48:07 BTPI kernel: [    0.000000]   Normal zone: 255488 pages, LIFO batch:31
Oct  3 07:48:07 BTPI kernel: [    0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096
Oct  3 07:48:07 BTPI kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Oct  3 07:48:07 BTPI kernel: [    0.814508] dwc_otg: Microframe scheduler enabled
Oct  3 07:48:07 BTPI kernel: [    0.840138] dwc_otg: FIQ enabled
Oct  3 07:48:07 BTPI kernel: [    0.840144] dwc_otg: NAK holdoff enabled
Oct  3 07:48:07 BTPI kernel: [    0.840150] dwc_otg: FIQ split-transaction FSM enabled
Oct  3 07:48:07 BTPI kernel: [    0.840166] Module dwc_common_port init
Oct  3 07:49:06 BTPI lepresenced[370]: [tid:0] main: Sending update for mac address 88:0f:XX:XX:XX:XX, age: 1, max age: 30, rssi: -60, result: present.
Oct  3 07:49:06 BTPI lepresenced[370]: [tid:0] main: Sending update for mac address 88:0f:XX:XX:XX:XX, age: 1, max age: 60, rssi: -60, result: present.
Oct  3 07:49:06 BTPI lepresenced[370]: [tid:0] main: Sending update for mac address c8:0f:1XX:XX:XX:XX, age: 1, max age: 30, rssi: -69, result: present.
Oct  3 07:49:06 BTPI lepresenced[370]: [tid:0] main: Sending update for mac address c8:0f:XX:XX:XX:XX, age: 0, max age: 60, rssi: -70, result: present.
Oct  3 07:50:06 BTPI lepresenced[370]: [tid:0] main: Sending update for mac address 88:0f:XX:XX:XX:XX, age: 24, max age: 60, rssi: -61, result: present.
Oct  3 07:50:06 BTPI lepresenced[370]: [tid:0] main: Sending update for mac address c8:0f:XX:XX:XX:XX, age: 24, max age: 60, rssi: -68, result: present.
Oct  3 07:51:06 BTPI lepresenced[370]: [tid:0] main: Sending update for mac address 88:0f:XX:XX:XX:XX, max age: 60, result: absence.
Oct  3 07:51:06 BTPI lepresenced[370]: [tid:0] main: Sending update for mac address c8:0f:XX:XX:XX:XX, max age: 60, result: absence.
Oct  3 07:51:06 BTPI lepresenced[370]: [tid:0] main: Sending update for mac address 88:0f:XX:XX:XX:XX, max age: 30, result: absence.
Oct  3 07:51:06 BTPI lepresenced[370]: [tid:0] main: Sending update for mac address c8:0f:XX:XX:XX:XX, max age: 30, result: absence.
Oct  3 07:51:36 BTPI lepresenced[370]: [tid:0] main: Sending update for mac address 88:0f:XX:XX:XX:XX, max age: 30, result: absence.
Oct  3 07:51:36 BTPI lepresenced[370]: [tid:0] main: Sending update for mac address c8:0f:XX:XX:XX:XX, max age: 30, result: absence.
Oct  3 07:52:06 BTPI lepresenced[370]: [tid:0] main: Sending update for mac address 88:0f:XX:XX:XX:XX, max age: 30, result: absence.
Oct  3 07:52:06 BTPI lepresenced[370]: [tid:0] main: Sending update for mac address c8:0f:XX:XX:XX:XX, max age: 30, result: absence.

------------------------------------------------

Zitat von: Devender am 02 Oktober 2017, 22:02:05
Aber,was du ja eigentlich möchtest:

2 PIs, die Tags erkennen und somit Anwesend oder Abwesenheit signalisieren. Dafür eignet sich am besten lepresenced in Verbindung mit collectord.
Das heißt, auf beiden PIs wird eine lepresenced Installation durchgeführt ( und bei Bedarf - wenn du eine zweite fhem Installation machst die Tags einzeln auf beiden Systemen angelegt), sonst nur auf einer. Der Collecotred (siehe ebenfalls im Wiki) wird nur auf einer Instanz installiert und verbindet mit seiner Konfiguration deine beiden Systeme.

Details dazu auch wieder im Wikiartikel s.o.

Hilft dir das weiter?

Nein - Verständnisfrage, oder Missverständnis ?!
Ich habe ZWEI Pi mit nur EINEM BT-Dongle.
Pi A beherbergt das FHEM und hat keinen BT Dongle (mehr)
Pi B steht im Korridor mit BT Dongle (und damit in Schlüsselbrett-Nähe)

Pi B hat lepresenced bekommen und scannt nach BT Geräten.
Pi A soll denn lepresenced von Pi B abfragen
Da ich an dieser Stelle nur einen BT-Dongle bzw einen "Scanner" habe, benötige ich kein collectord?

Zumal ich vermute, ein Problem zwischen leprecened und (oder vielmehr an) der BT-Schnittstelle/hcitool zu haben?! Da würde sicher auch der collectord nichts daran ändern.

Grüße,
Kharim

PatrickR · 03 Oktober 2017, 12:27:50

Mahlzeit!

Zitat von: Kharim am 03 Oktober 2017, 08:06:31
Danke, genau die Info hat gefehlt....Ebenso: Wo liegt das Log danach?

lepresenced loggt über Syslog, d. h. wenn Du es nicht explizit geändert hast in /var/log/syslog. Leider ist das Log, das Du gepostet hast, nicht besonders aussagekräftig. Ab 07:50:06 werden scheinbar keine Beacons mehr registriert ("age" geht noch und ist kurz darauf oberhalb der eingestellten Schwelle von 60 Sekunden). Vor allem fehlt der Anfang.

Um uns dem Problem zu nähern, tue bitte Folgendes:

Installiere das Paket der aktuellen Version (Direktlink https://svn.fhem.de/trac/export/15180/trunk/fhem/contrib/PRESENCE/deb/lepresenced-0.83-3.deb). Das wird zwar aller Voraussicht nach das Problem nicht lösen aber das Log wird aufschlussreicher.
Starte den Pi durch und poste die Ausgabe von egrep -i '(blue|hci|lepresenced)' /var/log/syslog | grep -v 'advertising data length corrected'
Bitte setze nach dem Neustart keine hci-Befehle manuell ab.

Devender hat zwar grundsätzlich Recht mit dem collectord, aber das würde ich ggf. vertagen bis der Pi richtig empfängt.

Grüße
Patrick

Kharim · 03 Oktober 2017, 12:43:27

Mal das syslog von heute Morgen / aus dem Zeitbereich.
Siehe Anhang.

Das Paket neu installieren mache ich gleich...

Kharim · 03 Oktober 2017, 12:59:42

Soo, das andere lepresenced installiert....

Aufgefallen ist mir sofort, dass der FHEM-Pi recht lange im Precense-Modul disconnected angezeigt hat. (War meiner Meinung nach vorher nicht so.)
Nach ca 2 Minuten war dann aber Verbindung da, und present der BTs.

Code Auswählen

pi@BTPI:~ $ egrep -i '(blue|hci|lepresenced)' /var/log/syslog | grep -v 'advertising data length corrected'
Sep  7 15:54:29 raspberrypi kernel: [    1.000037] sdhci: Secure Digital Host Controller Interface driver
Sep  7 15:54:29 raspberrypi kernel: [    1.007432] sdhci: Copyright(c) Pierre Ossman
Sep  7 15:54:29 raspberrypi kernel: [    1.022973] sdhci-pltfm: SDHCI platform and OF driver helper
Oct  2 12:59:05 raspberrypi kernel: [    1.000137] sdhci: Secure Digital Host Controller Interface driver
Oct  2 12:59:05 raspberrypi kernel: [    1.007540] sdhci: Copyright(c) Pierre Ossman
Oct  2 12:59:05 raspberrypi kernel: [    1.023074] sdhci-pltfm: SDHCI platform and OF driver helper
Oct  2 13:06:49 BTPI kernel: [    0.906074] sdhci: Secure Digital Host Controller Interface driver
Oct  2 13:06:49 BTPI kernel: [    0.910695] sdhci: Copyright(c) Pierre Ossman
Oct  2 13:06:49 BTPI kernel: [    0.920579] sdhci-pltfm: SDHCI platform and OF driver helper
Oct  2 13:10:04 BTPI kernel: [    0.910794] sdhci: Secure Digital Host Controller Interface driver
Oct  2 13:10:04 BTPI kernel: [    0.915373] sdhci: Copyright(c) Pierre Ossman
Oct  2 13:10:04 BTPI kernel: [    0.925248] sdhci-pltfm: SDHCI platform and OF driver helper
Oct  2 13:23:37 BTPI kernel: [  809.947969] Bluetooth: Core ver 2.22
Oct  2 13:23:37 BTPI kernel: [  809.948067] Bluetooth: HCI device and connection manager initialized
Oct  2 13:23:37 BTPI kernel: [  809.948089] Bluetooth: HCI socket layer initialized
Oct  2 13:23:37 BTPI kernel: [  809.948101] Bluetooth: L2CAP socket layer initialized
Oct  2 13:23:37 BTPI kernel: [  809.948134] Bluetooth: SCO socket layer initialized
Oct  2 13:23:37 BTPI systemd[1]: Starting Bluetooth service...
Oct  2 13:23:37 BTPI bluetoothd[1517]: Bluetooth daemon 5.43
Oct  2 13:23:38 BTPI systemd[1]: Started Bluetooth service.
Oct  2 13:23:38 BTPI systemd[1]: Reached target Bluetooth.
Oct  2 13:23:38 BTPI bluetoothd[1517]: Starting SDP server
Oct  2 13:23:38 BTPI kernel: [  810.182105] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Oct  2 13:23:38 BTPI kernel: [  810.182115] Bluetooth: BNEP filters: protocol multicast
Oct  2 13:23:38 BTPI kernel: [  810.182133] Bluetooth: BNEP socket layer initialized
Oct  2 13:23:38 BTPI bluetoothd[1517]: Bluetooth management interface 1.14 initialized
Oct  2 13:23:38 BTPI bluetoothd[1517]: Failed to obtain handles for "Service Changed" characteristic
Oct  2 13:23:38 BTPI bluetoothd[1517]: Sap driver initialization failed.
Oct  2 13:23:38 BTPI bluetoothd[1517]: sap-server: Operation not permitted (1)
Oct  2 13:41:31 BTPI kernel: [    0.910701] sdhci: Secure Digital Host Controller Interface driver
Oct  2 13:41:31 BTPI kernel: [    0.915287] sdhci: Copyright(c) Pierre Ossman
Oct  2 13:41:31 BTPI kernel: [    0.925190] sdhci-pltfm: SDHCI platform and OF driver helper
Übereinstimmungen in Binärdatei /var/log/syslog
pi@BTPI:~ $

Frag mich bitte nicht, warum das keine Einträge von heute sind......

Nun scheint der Status aber auf present zu bleiben......irgend etwas hat sich also doch geändert

PatrickR · 03 Oktober 2017, 14:09:52

Zitat von: Kharim am 03 Oktober 2017, 12:59:42
Frag mich bitte nicht, warum das keine Einträge von heute sind......

Hast Du den Pi nach der Paketinstallation neu gestartet?

Mach mal bitte:

Code Auswählen


ps aux|grep -i lepresenced
cat /etc/default/lepresenced

Die Nutzinformationen für die Fehlerbehebung werden immer weniger

Patrick

Kharim · 03 Oktober 2017, 15:20:21

Jap, ja klar habe ich den PI danach neu gestartet - "deswegen" funktioniert es jetzt auch aktuell...durch das neuere lepresenced.

Im Log oben steht zumindest etwas von einem Treiberproblem?

Code Auswählen


Oct  2 13:23:38 BTPI bluetoothd[1517]: Bluetooth management interface 1.14 initialized
Oct  2 13:23:38 BTPI bluetoothd[1517]: Failed to obtain handles for "Service Changed" characteristic
Oct  2 13:23:38 BTPI bluetoothd[1517]: Sap driver initialization failed.
Oct  2 13:23:38 BTPI bluetoothd[1517]: sap-server: Operation not permitted (1)

------------

Code Auswählen


pi@BTPI:~ $ ps aux|grep -i lepresenced
root       482  1.4  1.1  33808 11816 ?        Ssl  12:50   2:08 /usr/bin/perl /usr/sbin/lepresenced --device hci0 --listenaddress 0.0.0.0 --listenport 5333 --loglevel LOG_WARNING
pi         763  0.0  0.0   4776   544 pts/0    S+   15:18   0:00 grep --color=auto -i lepresenced

Code Auswählen


pi@BTPI:~ $ cat /etc/default/lepresenced
#
# Default settings for /etc/init.d/lepresenced
#

#Bluetooth device to use for scanning.
#BLUETOOTH_DEVICE="hci0"

#Address to listen for incoming connections. For local connections use 127.0.0.1.
#LISTEN_ADDRESS="0.0.0.0"

#Port to listen for incoming connections.
#LISTEN_PORT="5333"

#Syslog level. LOG_EMERG disables logging.
#SYSLOG_LEVEL="LOG_WARNING"

#Additional command line options
#MISC_OPTIONS=""

Ja das Debug-Log ist wieder deaktiviert - hatte ich vor "Neuinstallation" des neueren lepresenced wieder zurück gesetzt.