Test Adapter ioBroker.backitup v3.1.x

[simatec]

@skokarl
Kannst du bitte mal den Log posten?
Ein Screenshot ist da nicht so praktikabel

[skokarl]

@simatec
Wir stellen das nochmal zurück,
hab eben den Adapter auf debug gestellt und das Backup händisch angestossen,
alles sauber, keine Fehler.
Vielleicht hatte heute Nacht meine NAS ein kurzes Problem, das kann tatsächlich sein, weil ich
im Moment sowieso mit meinem Netzwerk oben im Haus sporadisch Ärger habe.

Ich beobachte das, ansonsten gibt's beim nächsten Mal nen Log.

[simatec]

@skokarl
Kannst du bitte trotzdem den Log mal posten.
Ich habe im Sentry die Fehlermeldungen auch ein paar mal gesehen.

[skokarl]

@simatec

viel Müll drin.....

guck einfach nur bei 01:00 Uhr .... edit: log wieder entfernt

und eben hab ich ja nochmal händisch getestet.

[crunchip]

update 1.5.4 auf 1.5.5 klappt nicht

2020-05-02 23:54:13.608 - info: host.IoBroker iobroker upgrade backitup
2020-05-02 23:54:16.107 - info: host.IoBroker iobroker Update backitup from @1.5.4 to @1.5.5
2020-05-02 23:54:16.147 - info: host.IoBroker iobroker host.IoBroker Adapter "system.adapter.backitup.0" is stopped.
2020-05-02 23:54:16.224 - info: host.IoBroker "system.adapter.backitup.0" disabled
2020-05-02 23:54:16.231 - info: backitup.0 (4649) Got terminate signal TERMINATE_YOURSELF
2020-05-02 23:54:16.224 - info: host.IoBroker stopInstance system.adapter.backitup.0 (force=false, process=true)
2020-05-02 23:54:16.226 - info: host.IoBroker stopInstance system.adapter.backitup.0 send kill signal
2020-05-02 23:54:16.233 - info: backitup.0 (4649) cleaned everything up...
2020-05-02 23:54:16.234 - info: backitup.0 (4649) terminating
2020-05-02 23:54:16.240 - info: backitup.0 (4649) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason
2020-05-02 23:54:16.790 - info: host.IoBroker instance system.adapter.backitup.0 terminated with code 11 (ADAPTER_REQUESTED_TERMINATION)
2020-05-02 23:54:16.941 - info: host.IoBroker iobroker NPM version: 6.14.4
2020-05-02 23:54:16.949 - info: host.IoBroker iobroker npm install iobroker.backitup@1.5.5 --loglevel error --prefix "/opt/iobroker" (System call)
2020-05-02 23:54:34.439 - info: host.IoBroker iobroker npm ERR! code ETARGETnpm ERR! notarget No matching version found for iobroker.backitup@1.5.5.npm ERR! notarget In most cases you or one of your dependencies are requestingnpm ERR! notarget a package version that doesn't exist.
2020-05-02 23:54:34.446 - info: host.IoBroker iobroker npm ERR! A complete log of this run can be found in:npm ERR! /home/iobroker/.npm/_logs/2020-05-02T21_54_34_430Z-debug.log
2020-05-02 23:54:34.518 - info: host.IoBroker iobroker upload [3] backitup.admin /opt/iobroker/node_modules/iobroker.backitup/admin/words.js words.js application/javascript
2020-05-02 23:54:34.762 - info: host.IoBroker iobroker upload [2] backitup.admin /opt/iobroker/node_modules/iobroker.backitup/admin/index_m.html index_m.html text/html
2020-05-02 23:54:34.841 - info: host.IoBroker iobroker upload [1] backitup.admin /opt/iobroker/node_modules/iobroker.backitup/admin/index.html index.html text/html
2020-05-02 23:54:34.914 - info: host.IoBroker iobroker upload [0] backitup.admin /opt/iobroker/node_modules/iobroker.backitup/admin/backitup.png backitup.png image/png
2020-05-02 23:54:34.973 - info: host.IoBroker iobroker Adapter "backitup" updated
2020-05-02 23:54:34.974 - info: host.IoBroker iobroker host.IoBroker Adapter "system.adapter.backitup.0" is started
2020-05-02 23:54:35.047 - info: host.IoBroker "system.adapter.backitup.0" enabled
2020-05-02 23:54:35.073 - info: host.IoBroker instance system.adapter.backitup.0 started with pid 6761
2020-05-02 23:54:36.091 - info: host.IoBroker iobroker exit 0
2020-05-02 23:54:41.857 - info: backitup.0 (6761) starting. Version 1.5.4 in /opt/iobroker/node_modules/iobroker.backitup, node: v10.20.1, js-controller: 3.0.20
2020-05-02 23:54:41.914 - info: backitup.0 (6761) [iobroker] backup was activated at 05:30 every 1 day(s)

[mickym]

@crunchip Ja genau diese Fehler hatte ich auch. Über GitHub aber nun 1.5.6 bereits installiert und das klappte ohne Fehler.

[Glasfaser]

@crunchip

Liegt an der repro . ... simatec war schneller mit einem Update auf 1.5.6 als die repro aktualisiert ist !

[crunchip]

@Glasfaser alles gut, passt jetzt, hatte nur gedacht ich bin verpeilt.
so zwischen Tür und Angel...updates gemacht...dann,
grübel...
hatt ich doch schon vorhin gemacht,
also nochmal angestoßen und dann erst gesehen, das es gar nicht klappt

[simatec]

@crunchip
In der 1.5.5 hatte sich ein Fehler eingeschlichen... habe diese eigentlich noch vorm aktualisieren der Repo wieder vom npm genommen. Aber anscheinend war ich wohl doch zu spät.
1.5.6 ist aber die offizielle Version...

[Holzlenkrad]

Ich habe das Problem, dass nur sehr unregelmäßig die automatischen Backups durchgeführt werden. Da ich den Adapter noch nicht lange nutze, läuft derzeit praktisch alles auf Standardeinstellungen, Backup ohne History etc. einfach alle 2 Tage in das Standardverzeichnis /backup

Am 27.04. wurde eins gemacht, am 29.04. fehlt es, dann wieder am 01.05. und 03.05. und seitdem keins mehr. Ich würde euch gern mit Infos versorgen, aber im Log gibt es absolut keine Information, nicht mal an den Tagen, an denen das Backup geklappt hat. Hab das Log-Level jetzt mal auf Debug gestellt, vielleicht erfahre ich ja so etwas...

[simatec]

@Holzlenkrad
Ohne Log ist es wirklich schwierig...
stelle die Instanz auf debug und poste den Log.

Was passiert, wenn du das Backup manuell startest?

[Lstt]

Today have problem:

[DEBUG] [iobroker] - host.iobroker-x64 15714 states saved

[ERROR] [iobroker] - (node:31112) UnhandledPromiseRejectionWarning: Error: EACCES: permission denied, mkdir '/opt/iobroker/node_modules/iobroker.js-controller/tmp/backup'
    at Object.mkdirSync (fs.js:841:3)
    at /opt/iobroker/node_modules/iobroker.js-controller/lib/setup/setupBackup.js:296:32
    at processTicksAndRejections (internal/process/task_queues.js:97:5)

[ERROR] [iobroker] - (node:31112) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1)
(node:31112) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code.

---

Mod-Edit: Code/Log in Code Tags gepackt. Bitte benutzt die Code Tags Funktion -> </>
Hier gehts zur Hilfe.

[Holzlenkrad]

@simatec said in Test Adapter Backitup v1.5.x:

@Holzlenkrad
Ohne Log ist es wirklich schwierig...
stelle die Instanz auf debug und poste den Log.

Was passiert, wenn du das Backup manuell startest?

Manuell läuft es problemlos durch.
Der Cronjob steht bei mir auf 8 Uhr morgens alle 2 Tage. Heute morgen hätte es laufen sollen. Im Logfile findet sich trotz Log-Level Debug kein einziger Eintrag.

Der Datenpunkt backitup.0.info.iobrokerNextTime steht weiterhin auf 11. Mai 2020 um 08:00 Uhr.
latestBackup ist leer...

Ich bin da echt ratlos...

[simatec]

@Holzlenkrad
Zeige mal bitte die Logausgaben vom manuellen Backup.
Und poste mal die json deiner Config.
Welche Version ist aktuell installiert?
Hast du ein Update von node oder dem js-controller durchgeführt gehabt oder lief das Backup noch nie?

[Holzlenkrad]

@simatec said in Test Adapter Backitup v1.5.x:

Config

Das dürfte die Config sein oder?

Spoiler

 "native": {
   "minimalTime": "08:00",
   "minimalEveryXDays": "2",
   "minimalDeleteAfter": "10",
   "minimalNameSuffix": "",
   "ccuTime": "01:30",
   "ccuEveryXDays": "3",
   "ccuDeleteAfter": "5",
   "ccuNameSuffix": "",
   "ccuHost": "",
   "ccuUser": "Admin",
   "ccuPassword": "",
   "dropboxAccessToken": "",
   "dropboxMinimalDir": "/backupDir/iobroker",
   "dropboxCcuDir": "/backupDir/ccu",
   "dropboxDir": "/backupDir",
   "googledriveAccessJson": "",
   "googledriveMinimalDir": "/backupDir/iobroker",
   "googledriveCcuDir": "/backupDir/ccu",
   "googledriveDir": "/backupDir",
   "mySqlHost": "localhost",
   "mySqlPort": "3306",
   "mySqlName": "iobroker",
   "mySqlUser": "",
   "mySqlPassword": "",
   "mySqlDumpExe": "",
   "cifsMount": "",
   "cifsUser": "",
   "cifsPassword": "",
   "cifsDomain": "",
   "cifsDir": "sharename/backupDir",
   "cifsCcuDir": "sharename/backupDir",
   "cifsMinimalDir": "sharename/backupDir",
   "connectType": "CIFS",
   "smbType": "vers=1.0",
   "macAd": "20:DE:20:DE:20:DE",
   "wolWait": "25",
   "ftpHost": "",
   "ftpPort": "21",
   "ftpDir": "/backupDir",
   "ftpUser": "",
   "ftpPassword": "",
   "ftpCcuDir": "/backupDir/ccu",
   "ftpMinimalDir": "/backupDir/iobroker",
   "redisPath": "/var/lib/redis",
   "historyPath": "media/usb/history",
   "notificationsType": "Telegram",
   "telegramInstance": "",
   "telegramNoticeType": "longTelegramNotice",
   "telegramWaitToSend": "0",
   "pushoverInstance": "",
   "pushoverNoticeType": "longPushoverNotice",
   "pushoverDeviceID": "",
   "pushoverWaitToSend": "0",
   "emailReceiver": "xxx@xxx.com",
   "emailSender": "xxx@xxx.com",
   "emailInstance": "",
   "emailNoticeType": "longEmailNotice",
   "emailWaitToSend": "0",
   "historyEntriesNumber": "25",
   "restoreSource": "local",
   "mySqlDeleteAfter": 5,
   "minimalEnabled": true,
   "redisMinimalEnabled": false,
   "mysqlMinimalEnabled": false,
   "mysqlQuick": false,
   "mysqlSingleTransaction": false,
   "ccuEnabled": false,
   "dropboxEnabled": false,
   "dropboxDeleteOldBackup": false,
   "dropboxOwnDir": false,
   "googledriveEnabled": false,
   "googledriveDeleteOldBackup": false,
   "googledriveOwnDir": false,
   "mySqlEnabled": false,
   "cifsEnabled": false,
   "cifsOwnDir": false,
   "cifsDeleteOldBackup": false,
   "sudoMount": false,
   "wakeOnLAN": false,
   "ftpEnabled": false,
   "ftpOwnDir": false,
   "ftpDeleteOldBackup": false,
   "redisEnabled": false,
   "historyEnabled": false,
   "zigbeeEnabled": true,
   "notificationEnabled": true,
   "telegramSilentNotice": false,
   "telegramOnlyError": false,
   "pushoverSilentNotice": false,
   "pushoverOnlyError": false,
   "emailOnlyError": false,
   "debugLevel": true,
   "startAllRestore": false,
   "telegramUser": null
 },

Backitup ist Version 1.5.6, js-controller 3.1.4.
Das Backup lief schon mal 3-5 mal automatisch, hab den Adapter aber auch erst seit letztem Monat installiert. Ich weiß nicht genau, ob ich seitdem schon mal den js-controller aktualisiert habe, denke aber schon, gab ja eine Menge Updates in letzter Zeit. Bin übrigens im Repo latest.

Und so sieht ein manuelles Backup aus:

Gestartet...
[DEBUG] [iobroker] start with {"type":"creator","time":"08:00","debugging":true,"everyXDays":"2","deleteBackupAfter":0,"dir":"/opt/iobroker","redis":{"enabled":false,"type":"creator","ftp":{"enabled":false,"type":"storage","source":"local","host":"","debugging":true,"deleteOldBackup":false,"ownDir":false,"dir":"/backupDir","dirMinimal":"/backupDir/iobroker","user":"","pass":"****","port":"21"},"cifs":{"enabled":false,"mountType":"CIFS","type":"storage","source":"local","mount":"","debugging":true,"fileDir":"/opt/iobroker/node_modules/iobroker.backitup","wakeOnLAN":false,"macAd":"20:DE:20:DE:20:DE","wolTime":"25","smb":"vers=1.0","sudo":false,"cifsDomain":"","deleteOldBackup":false,"ownDir":false,"dir":"sharename/backupDir","dirMinimal":"sharename/backupDir","user":"","pass":"****"},"dropbox":{"enabled":false,"type":"storage","source":"local","debugging":true,"deleteOldBackup":false,"accessToken":"****","ownDir":false,"dir":"/backupDir","dirMinimal":"/backupDir/iobroker"},"googledrive":{"enabled":false,"type":"storage","source":"local","debugging":true,"deleteOldBackup":false,"accessJson":"****","ownDir":false,"dir":"/backupDir","dirMinimal":"/backupDir/iobroker"},"path":"/var/lib/redis"},"historyDB":{"enabled":false,"type":"creator","ftp":{"enabled":false,"type":"storage","source":"local","host":"","debugging":true,"deleteOldBackup":false,"ownDir":false,"dir":"/backupDir","dirMinimal":"/backupDir/iobroker","user":"","pass":"****","port":"21"},"cifs":{"enabled":false,"mountType":"CIFS","type":"storage","source":"local","mount":"","debugging":true,"fileDir":"/opt/iobroker/node_modules/iobroker.backitup","wakeOnLAN":false,"macAd":"20:DE:20:DE:20:DE","wolTime":"25","smb":"vers=1.0","sudo":false,"cifsDomain":"","deleteOldBackup":false,"ownDir":false,"dir":"sharename/backupDir","dirMinimal":"sharename/backupDir","user":"","pass":"****"},"dropbox":{"enabled":false,"type":"storage","source":"local","debugging":true,"deleteOldBackup":false,"accessToken":"****","ownDir":false,"dir":"/backupDir","dirMinimal":"/backupDir/iobroker"},"googledrive":{"enabled":false,"type":"storage","source":"local","debugging":true,"deleteOldBackup":false,"accessJson":"****","ownDir":false,"dir":"/backupDir","dirMinimal":"/backupDir/iobroker"},"path":"media/usb/history"},"zigbee":{"enabled":true,"type":"creator","ftp":{"enabled":false,"type":"storage","source":"local","host":"","debugging":true,"deleteOldBackup":false,"ownDir":false,"dir":"/backupDir","dirMinimal":"/backupDir/iobroker","user":"","pass":"****","port":"21"},"cifs":{"enabled":false,"mountType":"CIFS","type":"storage","source":"local","mount":"","debugging":true,"fileDir":"/opt/iobroker/node_modules/iobroker.backitup","wakeOnLAN":false,"macAd":"20:DE:20:DE:20:DE","wolTime":"25","smb":"vers=1.0","sudo":false,"cifsDomain":"","deleteOldBackup":false,"ownDir":false,"dir":"sharename/backupDir","dirMinimal":"sharename/backupDir","user":"","pass":"****"},"dropbox":{"enabled":false,"type":"storage","source":"local","debugging":true,"deleteOldBackup":false,"accessToken":"****","ownDir":false,"dir":"/backupDir","dirMinimal":"/backupDir/iobroker"},"googledrive":{"enabled":false,"type":"storage","source":"local","debugging":true,"deleteOldBackup":false,"accessJson":"****","ownDir":false,"dir":"/backupDir","dirMinimal":"/backupDir/iobroker"},"path":"/opt/iobroker/iobroker-data"},"history":{"enabled":true,"type":"message","entriesNumber":"25","systemLang":"de"},"telegram":{"enabled":true,"notificationsType":"Telegram","type":"message","instance":"","SilentNotice":false,"NoticeType":"longTelegramNotice","User":null,"onlyError":false,"telegramWaiting":0,"systemLang":"de"},"email":{"enabled":true,"notificationsType":"Telegram","type":"message","instance":"","NoticeType":"longEmailNotice","emailReceiver":"xxx@xxx.com","emailSender":"xxx@xxx.com","onlyError":false,"emailWaiting":0,"systemLang":"de"},"pushover":{"enabled":true,"notificationsType":"Telegram","type":"message","instance":"","SilentNotice":false,"NoticeType":"longPushoverNotice","deviceID":"","onlyError":false,"pushoverWaiting":0,"systemLang":"de"},"backupDir":"/opt/iobroker/backups"}
[DEBUG] [iobroker] - host.iobroker-docker 757 states saved

[DEBUG] [iobroker] - host.iobroker-docker 938 objects saved

[DEBUG] [iobroker] - readFile will not read this file (css/vis-common-user.css) in future versions: vis is not an object of type "meta"

[DEBUG] [iobroker] - Backup created: /opt/iobroker/backups/iobroker_2020_05_11-22_11_44_backupiobroker.tar.gz

[DEBUG] [iobroker] - done

[simatec]

@Holzlenkrad
Dein Backup wird so wie ich das sehe nicht ordentlich beendet.
Was mir aufgefallen ist, dass du zigbee aktiviert hast.
Hast du eine zigbee Instanz laufen?
Z.B. Zigbee.0 oder zigbee.1

[Holzlenkrad]

@simatec
Tatsächlich derzeit nicht, weil ich meine Zigbee Geräte noch neu anlernen muss.

Könnten die Probleme denn daher kommen, dass Zigbee schon im Backup-Adapter angeklickt wurde? Mit der Einstellung wurden ja schon ein paar Backups angelegt...

Und woran kann ich sehen, dass das Bacup nicht ordentlich durchgelaufen ist? Da steht ja
[DEBUG] [iobroker] - Backup created: X und
[DEBUG] [iobroker] - done

[simatec]

@Holzlenkrad
Ja da muss ich mir den Backupprozess fü zigbee mal anschauen.
Hatte gestern gerade eine Meldung dazu bekommen ...
Deaktiviere zigbee in backitup und es sollte durchlaufen.

Das das Backup nicht beendet wurde sieht man daran, dass am Ende kein "exit 0" kam.

[simatec]

@Holzlenkrad

Könntest du mal testen, ob es jetzt mit gesetzten Häckchen bei zigbee trotzdem läuft und auch beendet wird?
Installation über Github:

https://github.com/simatec/ioBroker.backitup/tarball/dev

Den Link direkt in der Katze unter Adaptern einfügen.

Am besten auch mal die debug Ausgaben hier posten

[JB_Sullivan]

Hallo zusammen - ist die Meldung etwas worüber man sich Gedanken machen muss?
Es steht zwar nicht kritisch, aber trotzdem ist es ein Fehler.