-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-05-31 09:50:33 CEST. -- May 31 09:49:01 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:49:01 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:49:01 volumio-manu volumio[8048]: info: Discovery: Getting this device information May 31 09:49:01 volumio-manu volumio[8048]: info: CoreCommandRouter::volumioGetState May 31 09:49:01 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:49:04 volumio-manu volumio[8048]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:49:04 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 09:49:05 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 09:49:05 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 09:49:05 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 31 09:49:05 volumio-manu volumio[8048]: info: Received Get System Version May 31 09:49:05 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 09:49:06 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 31 09:49:13 volumio-manu volumio[8048]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:49:13 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 09:49:13 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 09:49:13 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 09:49:13 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 31 09:49:13 volumio-manu volumio[8048]: info: Received Get System Version May 31 09:49:13 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 09:49:22 volumio-manu volumio[8048]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:49:22 volumio-manu volumio[8048]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:49:22 volumio-manu volumio[8048]: info: Retrieving Cloud Streaming UI May 31 09:49:22 volumio-manu volumio[8048]: info: Getting Tidal Cloud Configuration May 31 09:49:22 volumio-manu volumio[8048]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:49:22 volumio-manu volumio[8048]: info: Getting Qobuz Cloud Configuration May 31 09:49:22 volumio-manu volumio[8048]: info: Asking plugin for UI Config May 31 09:49:22 volumio-manu volumio[8048]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:49:22 volumio-manu volumio[8048]: info: Getting Spotify Cloud Configuration May 31 09:49:22 volumio-manu volumio[8048]: info: Asking plugin for UI Config May 31 09:49:22 volumio-manu volumio[8048]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:49:22 volumio-manu volumio[8048]: info: Saving Spotify Acccount May 31 09:49:22 volumio-manu volumio[8048]: info: Got Tidal Cloud Configuration May 31 09:49:22 volumio-manu volumio[8048]: info: Got it May 31 09:49:22 volumio-manu volumio[8048]: info: Got it May 31 09:49:22 volumio-manu volumio[8048]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 31 09:49:22 volumio-manu volumio[8048]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:49:22 volumio-manu volumio[8048]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 31 09:49:22 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 31 09:49:22 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 31 09:49:22 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 09:49:22 volumio-manu volumio[8048]: info: CoreCommandRouter::volumioGetBrowseSources May 31 09:49:22 volumio-manu volumio[8048]: info: CoreCommandRouter::volumioGetBrowseSources May 31 09:49:22 volumio-manu volumio[8048]: info: CoreCommandRouter::volumioGetBrowseSources May 31 09:49:22 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 09:49:22 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 31 09:49:26 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 09:49:28 volumio-manu go-librespot[8249]: time="2025-05-31T09:49:28+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" May 31 09:49:28 volumio-manu go-librespot[8249]: time="2025-05-31T09:49:28+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1712" May 31 09:49:28 volumio-manu go-librespot[8249]: time="2025-05-31T09:49:28+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update May 31 09:49:28 volumio-manu go-librespot[8249]: time="2025-05-31T09:49:28+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 31 09:49:28 volumio-manu go-librespot[8249]: time="2025-05-31T09:49:28+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1303" May 31 09:49:28 volumio-manu go-librespot[8249]: time="2025-05-31T09:49:28+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 31 09:49:29 volumio-manu go-librespot[8249]: time="2025-05-31T09:49:29+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1317" May 31 09:49:29 volumio-manu go-librespot[8249]: time="2025-05-31T09:49:29+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 352" May 31 09:49:29 volumio-manu go-librespot[8249]: time="2025-05-31T09:49:29+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update May 31 09:49:29 volumio-manu go-librespot[8249]: time="2025-05-31T09:49:29+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 31 09:49:30 volumio-manu volumiologrotate[610]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 31 09:49:30 volumio-manu volumiologrotate[610]: ls: cannot access 'MANU': No such file or directory May 31 09:49:30 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 09:49:31 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:49:31 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:49:31 volumio-manu volumio[8048]: info: Discovery: Getting this device information May 31 09:49:31 volumio-manu volumio[8048]: info: CoreCommandRouter::volumioGetState May 31 09:49:31 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:49:34 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 09:49:42 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 09:49:42 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 09:49:48 volumio-manu volumio[8048]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:49:48 volumio-manu volumio[8048]: info: Loaded the previous config. May 31 09:49:48 volumio-manu volumio[8048]: info: ## populating UI... May 31 09:49:48 volumio-manu volumio[8048]: info: 1/1 settings loaded May 31 09:49:48 volumio-manu volumio[8048]: info: Populated config screen. May 31 09:49:53 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 09:49:53 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 09:50:01 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:50:01 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:50:01 volumio-manu volumio[8048]: info: Discovery: Getting this device information May 31 09:50:01 volumio-manu volumio[8048]: info: CoreCommandRouter::volumioGetState May 31 09:50:01 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:50:12 volumio-manu go-librespot[8249]: time="2025-05-31T09:50:12+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 328" May 31 09:50:12 volumio-manu go-librespot[8249]: time="2025-05-31T09:50:12+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 254" May 31 09:50:12 volumio-manu go-librespot[8249]: time="2025-05-31T09:50:12+02:00" level=debug msg="skipping dealer message" uri="hm://collection/collection/31gszsn5p2sazmn7axnxuuzuswmy" May 31 09:50:12 volumio-manu go-librespot[8249]: time="2025-05-31T09:50:12+02:00" level=debug msg="skipping dealer message" uri="hm://collection/collection/31gszsn5p2sazmn7axnxuuzuswmy/json" May 31 09:50:16 volumio-manu go-librespot[8249]: time="2025-05-31T09:50:16+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 324" May 31 09:50:16 volumio-manu go-librespot[8249]: time="2025-05-31T09:50:16+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 255" May 31 09:50:16 volumio-manu go-librespot[8249]: time="2025-05-31T09:50:16+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 329" May 31 09:50:16 volumio-manu go-librespot[8249]: time="2025-05-31T09:50:16+02:00" level=debug msg="skipping dealer message" uri="hm://offline/v1/devices/38303935454545342d333435382d354641412d413138442d443542454442383744323037/cache/80f664fe4526e3c3e14d8492260a15b6/resources:write" May 31 09:50:16 volumio-manu go-librespot[8249]: time="2025-05-31T09:50:16+02:00" level=debug msg="skipping dealer message" uri="hm://collection/collection/31gszsn5p2sazmn7axnxuuzuswmy" May 31 09:50:16 volumio-manu go-librespot[8249]: time="2025-05-31T09:50:16+02:00" level=debug msg="skipping dealer message" uri="hm://collection/collection/31gszsn5p2sazmn7axnxuuzuswmy/json" May 31 09:50:19 volumio-manu volumio[8048]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/audiophonicsonoff/volumio/buster/armhf May 31 09:50:20 volumio-manu volumio[8048]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/audiophonicsonoff/volumio/buster/armhf May 31 09:50:20 volumio-manu volumio[8048]: info: performing onStop action May 31 09:50:21 volumio-manu volumio[8048]: info: Check plugin dependencies May 31 09:50:21 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 09:50:21 volumio-manu volumio[8048]: info: Rename folder May 31 09:50:21 volumio-manu volumio[8048]: info: Folder /tmp/downloaded_plugin.zip removed May 31 09:50:21 volumio-manu volumio[8048]: info: Move to category May 31 09:50:21 volumio-manu volumio[8048]: info: Checking if install.sh is present May 31 09:50:21 volumio-manu volumio[8048]: info: Executing install.sh May 31 09:50:21 volumio-manu sudo[10362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/audiophonicsonoff/install.sh May 31 09:50:21 volumio-manu sudo[10362]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 09:50:21 volumio-manu volumio[8048]: info: Installing Audiophonics on/off and its dependencies... May 31 09:50:21 volumio-manu volumio[8048]: info: No packages needed, only node_modules are required May 31 09:50:21 volumio-manu sudo[10362]: pam_unix(sudo:session): session closed for user root May 31 09:50:21 volumio-manu volumio[8048]: info: Install script completed May 31 09:50:21 volumio-manu volumio[8048]: info: Done installing plugin. May 31 09:50:21 volumio-manu volumio[8048]: info: Enabling plugin audiophonicsonoff May 31 09:50:21 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 09:50:21 volumio-manu volumio[8048]: info: [{"prettyName":"Podcast","name":"podcast","category":"music_service","version":"2.2.0","icon":"fa-podcast","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Audiophonics ON/OFF","name":"audiophonicsonoff","category":"system_hardware","version":"1.0.5","icon":"fa fa-cogs","isManuallyInstalled":false,"enabled":true,"active":false}] May 31 09:50:21 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 09:50:21 volumio-manu volumio[8048]: info: Folder /tmp/plugins removed May 31 09:50:21 volumio-manu volumio[8048]: info: Folder /tmp/downloaded_plugin.zip removed May 31 09:50:21 volumio-manu volumio[8048]: Plugin install end detected on script May 31 09:50:21 volumio-manu volumio[8048]: info: Folder /data/temp removed May 31 09:50:21 volumio-manu go-librespot[8249]: time="2025-05-31T09:50:21+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 249" May 31 09:50:21 volumio-manu go-librespot[8249]: time="2025-05-31T09:50:21+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 324" May 31 09:50:21 volumio-manu go-librespot[8249]: time="2025-05-31T09:50:21+02:00" level=debug msg="skipping dealer message" uri="hm://collection/artist/31gszsn5p2sazmn7axnxuuzuswmy/json" May 31 09:50:21 volumio-manu go-librespot[8249]: time="2025-05-31T09:50:21+02:00" level=debug msg="skipping dealer message" uri="hm://collection/artist/31gszsn5p2sazmn7axnxuuzuswmy" May 31 09:50:30 volumio-manu volumiologrotate[610]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 31 09:50:30 volumio-manu volumiologrotate[610]: ls: cannot access 'MANU': No such file or directory May 31 09:50:31 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:50:31 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:50:31 volumio-manu volumio[8048]: info: Discovery: Getting this device information May 31 09:50:31 volumio-manu volumio[8048]: info: CoreCommandRouter::volumioGetState May 31 09:50:31 volumio-manu volumio[8048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:50:32 volumio-manu volumio[8048]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ May 31 09:50:32 volumio-manu volumio[8048]: info: PLUGIN onReboot : networkfs May 31 09:50:32 volumio-manu sudo[10424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/AUDIO May 31 09:50:32 volumio-manu sudo[10424]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 09:50:32 volumio-manu sudo[10424]: pam_unix(sudo:session): session closed for user root May 31 09:50:32 volumio-manu systemd[1]: mnt-NAS-AUDIO.mount: Succeeded. May 31 09:50:32 volumio-manu sudo[10428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/AJEANCE_USB_2 May 31 09:50:32 volumio-manu sudo[10428]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 09:50:32 volumio-manu sudo[10428]: pam_unix(sudo:session): session closed for user root May 31 09:50:32 volumio-manu volumio[8048]: umount: /mnt/NAS/AJEANCE_USB_2: not mounted. May 31 09:50:32 volumio-manu volumio[8048]: error: Cannot umount share AJEANCE_USB_2 : Error: Command failed: /usr/bin/sudo /bin/umount -f /mnt/NAS/AJEANCE_USB_2 May 31 09:50:32 volumio-manu volumio[8048]: umount: /mnt/NAS/AJEANCE_USB_2: not mounted. May 31 09:50:32 volumio-manu volumio[8048]: info: PLUGIN onReboot : audiophonicsonoff May 31 09:50:32 volumio-manu volumio[8048]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 09:50:32 volumio-manu volumio[8048]: Error: ESPIPE: invalid seek, write May 31 09:50:32 volumio-manu volumio[8048]: at Object.writeSync (fs.js:711:3) May 31 09:50:32 volumio-manu volumio[8048]: at Gpio.writeSync (/data/plugins/system_hardware/audiophonicsonoff/node_modules/onoff/onoff.js:243:8) May 31 09:50:32 volumio-manu volumio[8048]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) May 31 09:50:32 volumio-manu volumio[8048]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) May 31 09:50:32 volumio-manu volumio[8048]: at HashMap. (/volumio/app/pluginmanager.js:668:31) May 31 09:50:32 volumio-manu volumio[8048]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 31 09:50:32 volumio-manu volumio[8048]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 31 09:50:32 volumio-manu volumio[8048]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) May 31 09:50:32 volumio-manu volumio[8048]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) May 31 09:50:32 volumio-manu volumio[8048]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) May 31 09:50:32 volumio-manu volumio[8048]: at Socket.emit (events.js:315:20) May 31 09:50:32 volumio-manu volumio[8048]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 31 09:50:32 volumio-manu volumio[8048]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { May 31 09:50:32 volumio-manu volumio[8048]: errno: -29, May 31 09:50:32 volumio-manu volumio[8048]: syscall: 'write', May 31 09:50:32 volumio-manu volumio[8048]: code: 'ESPIPE' May 31 09:50:32 volumio-manu volumio[8048]: } May 31 09:50:32 volumio-manu volumio[8048]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 09:50:33 volumio-manu sudo[10439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-31 09:49 May 31 09:50:33 volumio-manu sudo[10439]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"