[gelöst] Alexa Adapter startet nach Update auf 3.10.4 nicht

[SKB]

@Thomas-Braun
Hier die Ausgabe von apt update:

pi@ioBroker:~ $ sudo apt update
OK:1 http://archive.raspberrypi.org/debian buster InRelease
OK:2 https://deb.nodesource.com/node_14.x buster InRelease
OK:3 https://packages.grafana.com/enterprise/deb stable InRelease
Holen:4 http://raspbian.raspberrypi.org/raspbian buster InRelease [15,0 kB]
OK:5 https://www.pivccu.de/piVCCU stable InRelease
Es wurden 15,0 kB in 2 s geholt (8.075 B/s).
Paketlisten werden gelesen... Fertig
Abhängigkeitsbaum wird aufgebaut.
Statusinformationen werden eingelesen.... Fertig
Aktualisierung für 1 Paket verfügbar. Führen Sie »apt list --upgradable« aus, um es anzuzeigen.

Das Update ist von Grafana. Mache ich später

Und hier die Ausgabe von which's:

pi@ioBroker:~ $ which node && which nodejs && which npm && node -v && nodejs -v && npm -v
/usr/bin/node
/usr/bin/nodejs
/usr/bin/npm
v14.18.0
v14.18.0
6.14.15

[apollon77]

@skb Hier gibts auch das Thema beim Loxone Adapter ... https://github.com/UncleSamSwiss/ioBroker.loxone/issues/191#issuecomment-939695553

Sinus1986 created this issue in UncleSamSwiss/ioBroker.loxone

closed Loxone Adapter (IOB) not connect with Miniserver #191

[SKB]

@apollon77 Die schreiben ja von "alten" Versionen, wenn ich das richtig sehe.
Ich habe:

Linux ioBroker 5.10.63-v7l+ #1457 SMP Tue Sep 28 11:26:14 BST 2021 armv7l

auf meinem Rapsberry Pi 4 - 4GB.

[Thomas Braun]

@skb
Nee, an alten Versionen oder schiefen Installationen liegt das nicht. Das ist alles i.O. bei dir.

[SKB]

@thomas-braun Schonmal beruhigend.
Wie kann ich weiter vorgehen? Dau erwähntest in dem anderen Thread etwas von den MQTT Brokern.
Hängt dies ggf. mit dem Shelly Adapter zusammen, der auch als MQTT fungiert?

[apollon77]

Ok, dann können wir nur mal manuell adapter starten um ggf mehr infos von nodejs zu bekommen.

Ala https://github.com/nodejs/help/issues/3168#issuecomment-756720041 müsste man dem nodejs prozess ein --report-signal=SIGBUS --report-on-signal übergeben ...

Versuchen gerade rauszufinden wie der Call aussehen muss

Wolvan created this issue in nodejs/help

closed How to debug SIGBUS error #3168

[Thomas Braun]

@skb
Nein, da hatte ich mich von der Meldung zu verleiten lassen. Da war ja von mqtt und wss: die Rede.

[apollon77]

@skb Schau mal ob passend was in dmesg bzw /var/log/syslog irgendwas steht

[SKB]

@thomas-braun Ok

[SKB]

@apollon77 syslog meldet, wenn ich den Adapter starte dieses:

Oct 11 15:24:26 ioBroker kernel: [ 9174.536882] 8<--- cut here ---
Oct 11 15:24:26 ioBroker kernel: [ 9174.536902] Unhandled fault: alignment exception (0xa21) at 0x04cb6e7e

und bei dmesg habe ich:

[Mo Okt 11 15:24:26 2021] Alignment trap: not handling instruction f4430add at [<b42e9b40>]
[Mo Okt 11 15:24:26 2021] 8<--- cut here ---
[Mo Okt 11 15:24:26 2021] Unhandled fault: alignment exception (0xa21) at 0x04cb6e7e
[Mo Okt 11 15:24:26 2021] pgd = 982f978d
[Mo Okt 11 15:24:26 2021] [04cb6e7e] *pgd=0d4cb003, *pmd=f8ce4003

[apollon77]

@skb kannst lpg bissl grosszügiger posten? oder wirklich nur das?

[SKB]

@apollon77 Das kommt, wenn ich versuche, den Adapter zu starten. Du kannst natürlich auch mehr von dem Inhalt haben

dmesg:

[Mo Okt 11 12:52:49 2021] eq3loop: eq3loop_open_slave() mmd_hmip
[Mo Okt 11 12:52:49 2021] eq3loop: eq3loop_close_slave() mmd_hmip
[Mo Okt 11 12:52:49 2021] eq3loop: eq3loop_open_slave() mmd_hmip
[Mo Okt 11 12:52:49 2021] eq3loop: eq3loop_close_slave() mmd_hmip
[Mo Okt 11 12:52:49 2021] eq3loop: eq3loop_open_slave() mmd_hmip
[Mo Okt 11 12:52:49 2021] eq3loop: eq3loop_close_slave() mmd_hmip
[Mo Okt 11 12:52:49 2021] eq3loop: eq3loop_open_slave() mmd_hmip
[Mo Okt 11 12:53:18 2021] tun: Universal TUN/TAP device driver, 1.6
[Mo Okt 11 12:53:19 2021] usb 1-1.4: USB disconnect, device number 3
[Mo Okt 11 12:53:20 2021] usb 1-1.4: new full-speed USB device number 4 using xhci_hcd
[Mo Okt 11 12:53:20 2021] usb 1-1.4: New USB device found, idVendor=1cf1, idProduct=0030, bcdDevice= 1.00
[Mo Okt 11 12:53:20 2021] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[Mo Okt 11 12:53:20 2021] usb 1-1.4: Product: ConBee II
[Mo Okt 11 12:53:20 2021] usb 1-1.4: Manufacturer: dresden elektronik ingenieurtechnik GmbH
[Mo Okt 11 12:53:20 2021] usb 1-1.4: SerialNumber: DE2427836
[Mo Okt 11 12:53:20 2021] cdc_acm 1-1.4:1.0: ttyACM0: USB ACM device
[Mo Okt 11 12:53:23 2021] usb 1-1.4: USB disconnect, device number 4
[Mo Okt 11 12:53:24 2021] usb 1-1.4: new full-speed USB device number 5 using xhci_hcd
[Mo Okt 11 12:53:24 2021] usb 1-1.4: New USB device found, idVendor=1cf1, idProduct=0030, bcdDevice= 1.00
[Mo Okt 11 12:53:24 2021] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[Mo Okt 11 12:53:24 2021] usb 1-1.4: Product: ConBee II
[Mo Okt 11 12:53:24 2021] usb 1-1.4: Manufacturer: dresden elektronik ingenieurtechnik GmbH
[Mo Okt 11 12:53:24 2021] usb 1-1.4: SerialNumber: DE2427836
[Mo Okt 11 12:53:24 2021] cdc_acm 1-1.4:1.0: ttyACM0: USB ACM device
[Mo Okt 11 13:03:25 2021] Alignment trap: not handling instruction f4430add at [<b42e9b40>]
[Mo Okt 11 13:03:25 2021] 8<--- cut here ---
[Mo Okt 11 13:03:25 2021] Unhandled fault: alignment exception (0xa21) at 0x042c7c96
[Mo Okt 11 13:03:25 2021] pgd = c2a2fdb4
[Mo Okt 11 13:03:25 2021] [042c7c96] *pgd=0b7e9003, *pmd=fbe6e003
[Mo Okt 11 13:04:01 2021] Alignment trap: not handling instruction f4430add at [<b636db40>]
[Mo Okt 11 13:04:01 2021] 8<--- cut here ---
[Mo Okt 11 13:04:01 2021] Unhandled fault: alignment exception (0xa21) at 0x04503cd6
[Mo Okt 11 13:04:01 2021] pgd = 61fb6e79
[Mo Okt 11 13:04:01 2021] [04503cd6] *pgd=0d46b003, *pmd=f86b3003
[Mo Okt 11 13:04:36 2021] Alignment trap: not handling instruction f4430add at [<b640ab40>]
[Mo Okt 11 13:04:36 2021] 8<--- cut here ---
[Mo Okt 11 13:04:36 2021] Unhandled fault: alignment exception (0xa21) at 0x05aa4466
[Mo Okt 11 13:04:36 2021] pgd = 6ae4652a
[Mo Okt 11 13:04:36 2021] [05aa4466] *pgd=0d67f003, *pmd=f8575003
[Mo Okt 11 13:07:02 2021] Alignment trap: not handling instruction f4430add at [<b42e9b40>]
[Mo Okt 11 13:07:02 2021] 8<--- cut here ---
[Mo Okt 11 13:07:02 2021] Unhandled fault: alignment exception (0xa21) at 0x04396546
[Mo Okt 11 13:07:02 2021] pgd = 6348d82f
[Mo Okt 11 13:07:02 2021] [04396546] *pgd=0b5dd003, *pmd=8e081003
[Mo Okt 11 13:07:37 2021] Alignment trap: not handling instruction f4430add at [<b639db40>]
[Mo Okt 11 13:07:37 2021] 8<--- cut here ---
[Mo Okt 11 13:07:37 2021] Unhandled fault: alignment exception (0xa21) at 0x0540489e
[Mo Okt 11 13:07:37 2021] pgd = e4f86eac
[Mo Okt 11 13:07:37 2021] [0540489e] *pgd=0190f003, *pmd=8e046003
[Mo Okt 11 15:19:00 2021] Alignment trap: not handling instruction f4430add at [<b63e0b40>]
[Mo Okt 11 15:19:00 2021] 8<--- cut here ---
[Mo Okt 11 15:19:00 2021] Unhandled fault: alignment exception (0xa21) at 0x049f33de
[Mo Okt 11 15:19:00 2021] pgd = 8db46220
[Mo Okt 11 15:19:00 2021] [049f33de] *pgd=0e48a003, *pmd=9ea6f003
[Mo Okt 11 15:19:16 2021] Alignment trap: not handling instruction f4430add at [<b639eb40>]
[Mo Okt 11 15:19:16 2021] 8<--- cut here ---
[Mo Okt 11 15:19:16 2021] Unhandled fault: alignment exception (0xa21) at 0x03d1ca6e
[Mo Okt 11 15:19:16 2021] pgd = 74c456c8
[Mo Okt 11 15:19:16 2021] [03d1ca6e] *pgd=0751a003, *pmd=9e9d3003
[Mo Okt 11 15:19:58 2021] Alignment trap: not handling instruction f4430add at [<b63dbb40>]
[Mo Okt 11 15:19:58 2021] 8<--- cut here ---
[Mo Okt 11 15:19:58 2021] Unhandled fault: alignment exception (0xa21) at 0x05490f56
[Mo Okt 11 15:19:58 2021] pgd = 0e07b0dc
[Mo Okt 11 15:19:58 2021] [05490f56] *pgd=07422003, *pmd=f869d003
[Mo Okt 11 15:24:26 2021] Alignment trap: not handling instruction f4430add at [<b42e9b40>]
[Mo Okt 11 15:24:26 2021] 8<--- cut here ---
[Mo Okt 11 15:24:26 2021] Unhandled fault: alignment exception (0xa21) at 0x04cb6e7e
[Mo Okt 11 15:24:26 2021] pgd = 982f978d
[Mo Okt 11 15:24:26 2021] [04cb6e7e] *pgd=0d4cb003, *pmd=f8ce4003
[Mo Okt 11 15:27:04 2021] Alignment trap: not handling instruction f4430add at [<b635bb40>]
[Mo Okt 11 15:27:04 2021] 8<--- cut here ---
[Mo Okt 11 15:27:04 2021] Unhandled fault: alignment exception (0xa21) at 0x040e81de
[Mo Okt 11 15:27:04 2021] pgd = 9f8645f1
[Mo Okt 11 15:27:04 2021] [040e81de] *pgd=0b7e2003, *pmd=9ebf6003

syslog:

Oct 11 15:14:16 ioBroker influxd[515]: ts=2021-10-11T13:14:16.778831Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:14:16 ioBroker influxd[515]: ts=2021-10-11T13:14:16.783824Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:14:16 ioBroker influxd[515]: ts=2021-10-11T13:14:16.786532Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:14:16 ioBroker influxd[515]: ts=2021-10-11T13:14:16.790893Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:14:16 ioBroker influxd[515]: ts=2021-10-11T13:14:16.793395Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:14:16 ioBroker influxd[515]: ts=2021-10-11T13:14:16.795358Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:14:16 ioBroker influxd[515]: [httpd] 192.168.2.187, 192.168.2.187,::1 - iobroker [11/Oct/2021:15:14:16 +0200] "GET /query?db=iobroker&epoch=ms&q=SELECT+$
Oct 11 15:15:07 ioBroker influxd[515]: [httpd] 127.0.0.1 - iobroker [11/Oct/2021:15:15:07 +0200] "POST /write?database=iobroker&db=iobroker&p=%5BREDACTED%5D&preci$
Oct 11 15:17:00 ioBroker bash[561]: Send diag info: {"uuid":"356e5785-ee5f-4d02-8f49-94099f3a0562","language":"de","country":"Germany","hosts":[{"version":"3.3.18$
Oct 11 15:17:01 ioBroker CRON[10599]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Oct 11 15:19:00 ioBroker kernel: [ 8848.995119] 8<--- cut here ---
Oct 11 15:19:00 ioBroker kernel: [ 8848.995153] pgd = 8db46220
Oct 11 15:19:00 ioBroker kernel: [ 8848.995165] [049f33de] *pgd=0e48a003, *pmd=9ea6f003
Oct 11 15:19:11 ioBroker grafana-server[508]: t=2021-10-11T15:19:11+0200 lvl=info msg="Request Completed" logger=context userId=0 orgId=1 uname= method=GET path=/$
Oct 11 15:19:12 ioBroker influxd[515]: ts=2021-10-11T13:19:12.864833Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:19:12 ioBroker influxd[515]: ts=2021-10-11T13:19:12.870197Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:19:12 ioBroker influxd[515]: ts=2021-10-11T13:19:12.872743Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:19:12 ioBroker influxd[515]: ts=2021-10-11T13:19:12.877217Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:19:12 ioBroker influxd[515]: ts=2021-10-11T13:19:12.880242Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:19:12 ioBroker influxd[515]: ts=2021-10-11T13:19:12.882348Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:19:12 ioBroker influxd[515]: [httpd] 192.168.2.187, 192.168.2.187,::1 - iobroker [11/Oct/2021:15:19:12 +0200] "GET /query?db=iobroker&epoch=ms&q=SELECT+$
Oct 11 15:19:16 ioBroker kernel: [ 8864.229207] 8<--- cut here ---
Oct 11 15:19:16 ioBroker kernel: [ 8864.229226] Unhandled fault: alignment exception (0xa21) at 0x03d1ca6e
Oct 11 15:19:36 ioBroker bash[561]: Send diag info: {"uuid":"356e5785-ee5f-4d02-8f49-94099f3a0562","language":"de","country":"Germany","hosts":[{"version":"3.3.18$
Oct 11 15:19:58 ioBroker kernel: [ 8906.194634] Alignment trap: not handling instruction f4430add at [<b63dbb40>]
Oct 11 15:19:58 ioBroker kernel: [ 8906.194643] 8<--- cut here ---
Oct 11 15:19:58 ioBroker kernel: [ 8906.194662] Unhandled fault: alignment exception (0xa21) at 0x05490f56
Oct 11 15:19:58 ioBroker kernel: [ 8906.194678] pgd = 0e07b0dc
Oct 11 15:19:58 ioBroker kernel: [ 8906.194689] [05490f56] *pgd=07422003, *pmd=f869d003
Oct 11 15:21:54 ioBroker influxd[515]: ts=2021-10-11T13:21:54.643788Z lvl=info msg="Retention policy deletion check (start)" log_id=0X7kVdWl000 service=retention $
Oct 11 15:21:54 ioBroker influxd[515]: ts=2021-10-11T13:21:54.644070Z lvl=info msg="Retention policy deletion check (end)" log_id=0X7kVdWl000 service=retention tr$
Oct 11 15:22:04 ioBroker influxd[515]: [httpd] 127.0.0.1 - iobroker [11/Oct/2021:15:22:04 +0200] "POST /write?database=iobroker&db=iobroker&p=%5BREDACTED%5D&preci$
Oct 11 15:24:09 ioBroker grafana-server[508]: t=2021-10-11T15:24:09+0200 lvl=info msg="Request Completed" logger=context userId=0 orgId=1 uname= method=GET path=/$
Oct 11 15:24:11 ioBroker influxd[515]: ts=2021-10-11T13:24:11.336695Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:24:11 ioBroker influxd[515]: ts=2021-10-11T13:24:11.341528Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:24:11 ioBroker influxd[515]: ts=2021-10-11T13:24:11.343849Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:24:11 ioBroker influxd[515]: ts=2021-10-11T13:24:11.347963Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:24:11 ioBroker influxd[515]: ts=2021-10-11T13:24:11.350283Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:24:11 ioBroker influxd[515]: ts=2021-10-11T13:24:11.351916Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:24:11 ioBroker influxd[515]: [httpd] 192.168.2.187, 192.168.2.187,::1 - iobroker [11/Oct/2021:15:24:11 +0200] "GET /query?db=iobroker&epoch=ms&q=SELECT+$
Oct 11 15:24:26 ioBroker kernel: [ 9174.536882] 8<--- cut here ---
Oct 11 15:24:26 ioBroker kernel: [ 9174.536902] Unhandled fault: alignment exception (0xa21) at 0x04cb6e7e
Oct 11 15:27:04 ioBroker kernel: [ 9332.379957] 8<--- cut here ---
Oct 11 15:27:04 ioBroker kernel: [ 9332.379977] Unhandled fault: alignment exception (0xa21) at 0x040e81de
Oct 11 15:29:12 ioBroker grafana-server[508]: t=2021-10-11T15:29:12+0200 lvl=info msg="Request Completed" logger=context userId=0 orgId=1 uname= method=GET path=/$
Oct 11 15:29:15 ioBroker influxd[515]: ts=2021-10-11T13:29:15.923043Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:29:15 ioBroker influxd[515]: ts=2021-10-11T13:29:15.928966Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:29:15 ioBroker influxd[515]: ts=2021-10-11T13:29:15.931680Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:29:15 ioBroker influxd[515]: ts=2021-10-11T13:29:15.936175Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:29:15 ioBroker influxd[515]: ts=2021-10-11T13:29:15.938728Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$
Oct 11 15:29:15 ioBroker influxd[515]: ts=2021-10-11T13:29:15.940713Z lvl=info msg="Executing query" log_id=0X7kVdWl000 service=query query="SELECT max(value) FRO$

Hoffe, das hilft?

[apollon77]

@skb Nur bedingt ... aber mal frech ... wenn du die influxdb aus machst ... kommt dann auch der Fehler?

[SKB]

@apollon77 Kann ich ausprobieren. Melde mich gleich.

Achso, welchen Fehler meinst Du denn?

Den?

[Mo Okt 11 15:27:04 2021] 8<--- cut here ---
[Mo Okt 11 15:27:04 2021] Unhandled fault: alignment exception (0xa21) at 0x040e81de
[Mo Okt 11 15:27:04 2021] pgd = 9f8645f1
[Mo Okt 11 15:27:04 2021] [040e81de] *pgd=0b7e2003, *pmd=9ebf6003

Der kommt genau, wenn ich auf Play beim Alexa Adapter klicke. Mit Influx hat das meiner Mienung nach nichts zu tun.
Die loggt fröhlich vor sich hin.

[apollon77]

@skb Ahhh der Fehler dort kommt gar nicht wenn der SIGBUS kommt? Dachte der hängt zusammen ... naja meine Idee war eher das InfluxDB seehr speicherlastig ist ud auch nur auf 64bit systemen sinn macht ...(was der 4er Raspi meines wisssens nach ist?) ... aber dennoch wars ne idee

[Thomas Braun]

@skb
Übertaktet ist der Pi aber nicht, oder?
Netzteil stabil?

Gib mal die komplette Ausgabe von

free -ht
dmesg -T

[apollon77]

@skb Wenn das raspi OS wirklich nur 32 bit hat dann sei dir bewusst das deine influxdb bei etwas unter 2GB DB-Größe ein mega hartes Limit hat!! so 1,8GB oder so ist ende und hat komische Memory effekte aufs rest system wenn du in diese nähe kommst. Hatte ich schon.

[Homoran]

@apollon77 sagte in Alexa Adapter startet nach Update auf 3.10.4 nicht mehr:

Wenn das raspi OS wirklich nur 32 bit hat

ich bin mir nicht sicher, aber ich glaube das Standard RaspberryPi OS ist nur 32Bit, das 64er ist noch in Erprobung

[SKB]

@apollon77 Also, soweit ich das zeitlich eingrenzen kann, ist es so, das der Fehler kommt, wenn SIGBUS kommt.

ioBroker Log:

host.ioBroker	2021-10-11 15:48:30.685	warn	instance system.adapter.alexa2.0 terminated due to SIGBUS

dmesg:

[Mo Okt 11 15:48:30 2021] Alignment trap: not handling instruction f4430add at [<b6374b40>]
[Mo Okt 11 15:48:30 2021] 8<--- cut here ---
[Mo Okt 11 15:48:30 2021] Unhandled fault: alignment exception (0xa21) at 0x054f564e
[Mo Okt 11 15:48:30 2021] pgd = 2d9e975c
[Mo Okt 11 15:48:30 2021] [054f564e] *pgd=0d470003, *pmd=b6e17003

[apollon77]

Und wenn influxdb aus ist passiert das auch? Und: Wie groß ist deine influxdb denn so?