-- Logs begin at Thu 2024-10-03 23:27:17 CEST, end at Thu 2024-10-03 23:34:48 CEST. -- Oct 03 23:34:26 kehrlebox volumio[975]: info: Setting Geolocation for MyVolumio to eu4 Oct 03 23:34:26 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 23:34:26 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 23:34:26 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 23:34:26 kehrlebox volumio[975]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 03 23:34:26 kehrlebox volumio[1120]: ........................................................................++++ Oct 03 23:34:27 kehrlebox volumio[975]: info: Updating MyVolumio device info Oct 03 23:34:27 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 23:34:27 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 23:34:27 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 23:34:27 kehrlebox sshd[1362]: Connection reset by 192.168.178.54 port 61198 [preauth] Oct 03 23:34:28 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 23:34:28 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 23:34:28 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 03 23:34:28 kehrlebox volumio-remote-updater[630]: No test mode Oct 03 23:34:28 kehrlebox volumio-remote-updater[630]: No alpha test mode Oct 03 23:34:28 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 03 23:34:28 kehrlebox volumio[975]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 03 23:34:28 kehrlebox volumio[975]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 03 23:34:28 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 03 23:34:30 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 03 23:34:30 kehrlebox volumio[975]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Oct 03 23:34:30 kehrlebox volumio[975]: info: CoreCommandRouter::volumioGetState Oct 03 23:34:30 kehrlebox volumio[975]: info: CorePlayQueue::getTrack 0 Oct 03 23:34:31 kehrlebox volumio[1120]: .................++++ Oct 03 23:34:31 kehrlebox volumio[1120]: e is 65537 (0x010001) Oct 03 23:34:31 kehrlebox volumio[1120]: writing RSA key Oct 03 23:34:33 kehrlebox sshd[1369]: Accepted password for volumio from 192.168.178.54 port 61201 ssh2 Oct 03 23:34:33 kehrlebox sshd[1369]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Oct 03 23:34:33 kehrlebox systemd-logind[651]: New session 1 of user volumio. Oct 03 23:34:33 kehrlebox systemd[1]: Created slice User Slice of UID 1000. Oct 03 23:34:33 kehrlebox systemd[1]: Starting User Runtime Directory /run/user/1000... Oct 03 23:34:33 kehrlebox systemd[1]: Started User Runtime Directory /run/user/1000. Oct 03 23:34:33 kehrlebox systemd[1]: Starting User Manager for UID 1000... Oct 03 23:34:33 kehrlebox systemd[1387]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Oct 03 23:34:34 kehrlebox systemd[1387]: Reached target Timers. Oct 03 23:34:34 kehrlebox systemd[1387]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Oct 03 23:34:34 kehrlebox systemd[1387]: Reached target Paths. Oct 03 23:34:34 kehrlebox systemd[1387]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Oct 03 23:34:34 kehrlebox systemd[1387]: Listening on Sound System. Oct 03 23:34:34 kehrlebox systemd[1387]: Starting D-Bus User Message Bus Socket. Oct 03 23:34:34 kehrlebox systemd[1387]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Oct 03 23:34:34 kehrlebox systemd[1387]: Listening on GnuPG network certificate management daemon. Oct 03 23:34:34 kehrlebox systemd[1387]: Listening on GnuPG cryptographic agent and passphrase cache. Oct 03 23:34:34 kehrlebox systemd[1387]: Listening on D-Bus User Message Bus Socket. Oct 03 23:34:34 kehrlebox systemd[1387]: Reached target Sockets. Oct 03 23:34:34 kehrlebox systemd[1387]: Reached target Basic System. Oct 03 23:34:34 kehrlebox systemd[1]: Started User Manager for UID 1000. Oct 03 23:34:34 kehrlebox systemd[1387]: Starting Music Player Daemon... Oct 03 23:34:34 kehrlebox systemd[1]: Started Session 1 of user volumio. Oct 03 23:34:35 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 23:34:35 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 23:34:35 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 03 23:34:35 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 03 23:34:35 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 03 23:34:35 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 03 23:34:35 kehrlebox volumio[975]: info: CoreCommandRouter::volumioGetBrowseSources Oct 03 23:34:35 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 23:34:37 kehrlebox mpd[1398]: exception: failed to open log file "/var/log/mpd.log" (config line 7): Permission denied Oct 03 23:34:37 kehrlebox systemd[1387]: mpd.service: Main process exited, code=exited, status=1/FAILURE Oct 03 23:34:37 kehrlebox systemd[1387]: mpd.service: Failed with result 'exit-code'. Oct 03 23:34:37 kehrlebox systemd[1387]: Failed to start Music Player Daemon. Oct 03 23:34:37 kehrlebox systemd[1387]: Reached target Default. Oct 03 23:34:37 kehrlebox systemd[1387]: Startup finished in 3.389s. Oct 03 23:34:37 kehrlebox wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Oct 03 23:34:38 kehrlebox volumio[975]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 03 23:34:38 kehrlebox volumio[975]: info: Completed starting MyVolumio Plugin Oct 03 23:34:38 kehrlebox volumio[975]: info: BOOT COMPLETED Oct 03 23:34:38 kehrlebox volumio[975]: [Metrics] CommandRouter: 61s 841.86ms Oct 03 23:34:38 kehrlebox volumio[975]: info: CoreCommandRouter::volumiosetStartupVolume Oct 03 23:34:38 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 23:34:38 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 23:34:38 kehrlebox volumio[975]: info: CoreCommandRouter::Close All Modals sent Oct 03 23:34:38 kehrlebox volumio[975]: info: CoreCommandRouter::Close All Modals sent Oct 03 23:34:39 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 03 23:34:39 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 23:34:39 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 03 23:34:39 kehrlebox wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Oct 03 23:34:41 kehrlebox wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Oct 03 23:34:42 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 23:34:42 kehrlebox volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 03 23:34:43 kehrlebox wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Oct 03 23:34:46 kehrlebox volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 23:34:46 kehrlebox volumio[975]: info: GPIO-Buttons-Extention: Getting UI config Oct 03 23:34:46 kehrlebox volumio[975]: error: Cannot translate keys: TypeError: Cannot convert undefined or null to object Oct 03 23:34:46 kehrlebox volumio[975]: error: Cannot translate keys: TypeError: Cannot convert undefined or null to object Oct 03 23:34:46 kehrlebox volumio[975]: error: Cannot translate keys: TypeError: Cannot convert undefined or null to object Oct 03 23:34:46 kehrlebox volumio[975]: error: Cannot translate keys: TypeError: Cannot convert undefined or null to object Oct 03 23:34:46 kehrlebox volumio[975]: error: Cannot translate keys: TypeError: Cannot convert undefined or null to object Oct 03 23:34:46 kehrlebox volumio[975]: error: Cannot translate keys: TypeError: Cannot convert undefined or null to object Oct 03 23:34:46 kehrlebox volumio[975]: error: Cannot translate keys: TypeError: Cannot convert undefined or null to object Oct 03 23:34:46 kehrlebox volumio[975]: error: Cannot translate keys: TypeError: Cannot convert undefined or null to object Oct 03 23:34:46 kehrlebox volumio[975]: error: Cannot translate keys: TypeError: Cannot convert undefined or null to object Oct 03 23:34:46 kehrlebox volumio[975]: error: Cannot translate keys: TypeError: Cannot convert undefined or null to object Oct 03 23:34:46 kehrlebox volumio[975]: info: Config: {"syncSave":true,"autosave":true,"autosaveDelay":1000,"saved":true,"atomicSave":false,"data":{"button0":{"enabled":{"type":"boolean","value":true},"pin":{"type":"number","value":12},"socketCmd":{"type":"string","value":"playPlaylist"},"socketData":{"type":"string","value":"Kinder Lieder - Simone Sommerland"}},"button1":{"enabled":{"type":"boolean","value":false},"pin":{"type":"number","value":2},"socketCmd":{"type":"string","value":"playPlaylist"},"socketData":{"type":"string","value":"{\"name\": \"2\"}"}},"button2":{"enabled":{"type":"boolean","value":false},"pin":{"type":"number","value":27},"socketCmd":{"type":"string","value":"playPlaylist"},"socketData":{"type":"string","value":"{\"name\": \"3\"}"}},"button3":{"enabled":{"type":"boolean","value":false},"pin":{"type":"number","value":26},"socketCmd":{"type":"string","value":"playPlaylist"},"socketData":{"type":"string","value":"{\"name\": \"4\"}"}},"button4":{"enabled":{"type":"boolean","value":false},"pin":{"type":"number","value":25},"socketCmd":{"type":"string","value":"playPlaylist"},"socketData":{"type":"string","value":"{\"name\": \"5\"}"}}},"callbacks":{"_":{}},"filePath":"/data/configuration/system_hardware/gpio-buttons-extention/config.json"} Oct 03 23:34:46 kehrlebox volumio[975]: info: Listing playlists Oct 03 23:34:46 kehrlebox volumio[975]: info: Get button0Enabled: true Oct 03 23:34:46 kehrlebox volumio[975]: info: Get button0Pin: 12 Oct 03 23:34:46 kehrlebox volumio[975]: info: Get pinConf Label: 12 Oct 03 23:34:46 kehrlebox volumio[975]: info: Get button0SocketCmd: "playPlaylist" Oct 03 23:34:46 kehrlebox volumio[975]: info: Get button0SocketData: "Kinder Lieder - Simone Sommerland" Oct 03 23:34:46 kehrlebox volumio[975]: info: Get socketDataConf Label: Playlist undefined Oct 03 23:34:46 kehrlebox volumio[975]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 23:34:46 kehrlebox volumio[975]: SyntaxError: Unexpected token K in JSON at position 0 Oct 03 23:34:46 kehrlebox volumio[975]: at JSON.parse () Oct 03 23:34:46 kehrlebox volumio[975]: at /data/plugins/system_hardware/gpio-buttons-extention/index.js:125:54 Oct 03 23:34:46 kehrlebox volumio[975]: at Array.forEach () Oct 03 23:34:46 kehrlebox volumio[975]: at /data/plugins/system_hardware/gpio-buttons-extention/index.js:104:33 Oct 03 23:34:46 kehrlebox volumio[975]: at Array.forEach () Oct 03 23:34:46 kehrlebox volumio[975]: at Socket. (/data/plugins/system_hardware/gpio-buttons-extention/index.js:91:13) Oct 03 23:34:46 kehrlebox volumio[975]: at Socket.on (/data/plugins/system_hardware/gpio-buttons-extention/node_modules/component-emitter/index.js:65:8) Oct 03 23:34:46 kehrlebox volumio[975]: at Socket.Emitter.emit (/data/plugins/system_hardware/gpio-buttons-extention/node_modules/component-emitter/index.js:145:20) Oct 03 23:34:46 kehrlebox volumio[975]: at Socket.onevent (/data/plugins/system_hardware/gpio-buttons-extention/node_modules/socket.io-client/lib/socket.js:278:10) Oct 03 23:34:46 kehrlebox volumio[975]: at Socket.onpacket (/data/plugins/system_hardware/gpio-buttons-extention/node_modules/socket.io-client/lib/socket.js:236:12) Oct 03 23:34:46 kehrlebox volumio[975]: at Manager. (/data/plugins/system_hardware/gpio-buttons-extention/node_modules/component-bind/index.js:21:15) Oct 03 23:34:46 kehrlebox volumio[975]: at Manager.Emitter.emit (/data/plugins/system_hardware/gpio-buttons-extention/node_modules/component-emitter/index.js:145:20) Oct 03 23:34:46 kehrlebox volumio[975]: at Manager.ondecoded (/data/plugins/system_hardware/gpio-buttons-extention/node_modules/socket.io-client/lib/manager.js:349:8) Oct 03 23:34:46 kehrlebox volumio[975]: at Decoder. (/data/plugins/system_hardware/gpio-buttons-extention/node_modules/component-bind/index.js:21:15) Oct 03 23:34:46 kehrlebox volumio[975]: at Decoder.Emitter.emit (/data/plugins/system_hardware/gpio-buttons-extention/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Oct 03 23:34:46 kehrlebox volumio[975]: at Decoder.add (/data/plugins/system_hardware/gpio-buttons-extention/node_modules/socket.io-parser/index.js:254:12) Oct 03 23:34:46 kehrlebox volumio[975]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 23:34:48 kehrlebox sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-03 23:33 Oct 03 23:34:48 kehrlebox sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"