Oct 13 23:43:00 volumio volumio[1143]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 13 23:43:00 volumio volumio[1143]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 13 23:43:00 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 23:43:00 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 23:43:00 volumio volumio[1143]: info: Starting MyVolumio Remote Streaming Endpoints Oct 13 23:43:00 volumio volumio[1143]: info: MyVolumio login type: Token Oct 13 23:43:01 volumio volumio[1143]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 13 23:43:01 volumio volumio[1143]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 13 23:43:08 volumio volumio[1143]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 13 23:43:08 volumio volumio[1143]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 13 23:43:08 volumio volumio[1143]: info: Streaming services startup Oct 13 23:43:08 volumio volumio[1143]: info: Starting Streaming Daemon Oct 13 23:43:08 volumio volumio[1143]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 13 23:43:08 volumio sudo[1700]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 13 23:43:08 volumio sudo[1700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 23:43:08 volumio sudo[1700]: pam_unix(sudo:session): session closed for user root Oct 13 23:43:09 volumio volumio[1143]: info: Getting Spotify volume Oct 13 23:43:09 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 23:43:09 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 23:43:09 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 23:43:09 volumio volumio[1143]: error: Cannot start Volumio Streaming Daemon Oct 13 23:43:09 volumio volumio[1143]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 13 23:43:09 volumio volumio[1143]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 13 23:43:10 volumio volumio[1143]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 13 23:43:10 volumio volumio[1143]: error: MyVolumio Custom Token format not valid, refreshing it Oct 13 23:43:11 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 13 23:43:12 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 13 23:43:12 volumio volumio-remote-updater[685]: Test mode disabled Oct 13 23:43:12 volumio volumio-remote-updater[685]: Alpha mode disabled Oct 13 23:43:12 volumio volumio-remote-updater[685]: Alpha legacy test mode disabled Oct 13 23:43:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 13 23:43:12 volumio volumio[1143]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Oct 13 23:43:12 volumio volumio[1143]: SPOTIFY: SPOTIFY VOLUME undefined Oct 13 23:43:12 volumio volumio[1143]: SPOTIFY: VOLUMIO VOLUME 46 Oct 13 23:43:12 volumio volumio[1143]: info: Aligning Spotify Volume to Volumio Volume Oct 13 23:43:12 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 13 23:43:12 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 13 23:43:12 volumio volumio[1143]: info: Setting Spotify Volume from Volumio: 46 Oct 13 23:43:12 volumio volumio[1143]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 13 23:43:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 13 23:43:13 volumio upmpdcli[1724]: writing RSA key Oct 13 23:43:13 volumio volumio[1143]: info: MyVolumio login type: Token Oct 13 23:43:14 volumio volumio[1143]: SPOTIFY: SETTING SPOTIFY VOLUME 46 Oct 13 23:43:14 volumio volumio[1143]: info: Sending Spotify command with payload to local API: /player/volume Oct 13 23:43:14 volumio volumio[1143]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 13 23:43:15 volumio volumio[1143]: info: MyVolumio token set successfully Oct 13 23:43:15 volumio volumio[1143]: info: MYVOLUMIO: Adding device Oct 13 23:43:15 volumio volumio[1143]: info: MYVOLUMIO: Evaluating Server Oct 13 23:43:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 23:43:16 volumio volumio[1143]: info: MyVolumio status changed Oct 13 23:43:16 volumio volumio[1143]: info: Streaming services startup Oct 13 23:43:16 volumio volumio[1143]: info: Starting Streaming Daemon Oct 13 23:43:16 volumio volumio[1143]: info: Removing browser output: myVolumio user plan is not superstar Oct 13 23:43:16 volumio volumio[1143]: info: Removing audio output: Oct 13 23:43:16 volumio volumio[1143]: info: Stoppping Tunnel 1 Oct 13 23:43:16 volumio sudo[1750]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 13 23:43:16 volumio sudo[1750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 23:43:16 volumio sudo[1750]: pam_unix(sudo:session): session closed for user root Oct 13 23:43:16 volumio sudo[1753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 13 23:43:16 volumio sudo[1753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 23:43:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 23:43:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 23:43:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 23:43:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 23:43:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 23:43:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 23:43:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 23:43:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 13 23:43:16 volumio sudo[1753]: pam_unix(sudo:session): session closed for user root Oct 13 23:43:16 volumio volumio[1143]: info: Setting Geolocation for MyVolumio to eu7 Oct 13 23:43:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 23:43:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 23:43:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 23:43:17 volumio volumio[1143]: info: Remote SSH Stopped Oct 13 23:43:17 volumio volumio[1143]: error: Cannot start Volumio Streaming Daemon Oct 13 23:43:17 volumio volumio[1143]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 13 23:43:17 volumio volumio[1143]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 13 23:43:17 volumio volumio[1143]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 13 23:43:17 volumio volumio[1143]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 13 23:43:17 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Oct 13 23:43:17 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 13 23:43:17 volumio volumio[1143]: info: Updating MyVolumio device info Oct 13 23:43:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 23:43:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 23:43:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 23:43:18 volumio volumio[1143]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 13 23:43:20 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 23:43:20 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 23:43:20 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 13 23:43:20 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 13 23:43:20 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 13 23:43:20 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 13 23:43:20 volumio volumio[1143]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 23:43:20 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 23:43:21 volumio volumio[1143]: info: MYVOLUMIO: Adding device Oct 13 23:43:21 volumio volumio[1143]: info: MYVOLUMIO: Evaluating Server Oct 13 23:43:22 volumio volumio[1143]: info: Setting Geolocation for MyVolumio to eu7 Oct 13 23:43:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 23:43:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 23:43:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 23:43:23 volumio volumio[1143]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 13 23:43:23 volumio volumio[1143]: info: Updating MyVolumio device info Oct 13 23:43:23 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 23:43:23 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 23:43:23 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 23:43:24 volumio volumio[1143]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 13 23:43:28 volumio volumio[1143]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 13 23:43:28 volumio volumio[1143]: info: Completed starting MyVolumio Plugin Oct 13 23:43:28 volumio volumio[1143]: [Metrics] CommandRouter: 73s 176.49ms Oct 13 23:43:28 volumio volumio[1143]: info: CoreCommandRouter::volumiosetStartupVolume Oct 13 23:43:28 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 23:43:28 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 23:43:28 volumio volumio[1143]: info: CoreCommandRouter::Close All Modals sent Oct 13 23:43:28 volumio volumio[1143]: info: CoreCommandRouter::Close All Modals sent Oct 13 23:43:29 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 13 23:43:29 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 23:43:29 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 13 23:43:35 volumio volumio[1143]: info: BOOT COMPLETED Oct 13 23:44:10 volumio volumio[1143]: info: Airplay started streaming, receiving metadatas Oct 13 23:44:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 13 23:44:10 volumio volumio[1143]: info: CoreCommandRouter::volumioStop Oct 13 23:44:10 volumio volumio[1143]: info: CoreStateMachine::stop Oct 13 23:44:10 volumio volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 23:44:10 volumio volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 23:44:10 volumio volumio[1143]: info: Airplay started streaming, receiving metadatas Oct 13 23:44:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 13 23:44:10 volumio volumio[1143]: info: CoreCommandRouter::volumioStop Oct 13 23:44:10 volumio volumio[1143]: info: CoreStateMachine::stop Oct 13 23:44:10 volumio volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 23:44:10 volumio volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 23:44:10 volumio volumio[1143]: info: Airplay started streaming, receiving metadatas Oct 13 23:44:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 13 23:44:10 volumio volumio[1143]: info: CoreCommandRouter::volumioStop Oct 13 23:44:10 volumio volumio[1143]: info: CoreStateMachine::stop Oct 13 23:44:10 volumio volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 23:44:10 volumio volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 23:44:10 volumio shairport-sync[1851]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 23:44:10 volumio shairport-sync[1851]: Dload Upload Total Spent Left Speed Oct 13 23:44:10 volumio shairport-sync[1851]: [79B blob data] Oct 13 23:44:10 volumio shairport-sync[1853]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 23:44:10 volumio shairport-sync[1853]: Dload Upload Total Spent Left Speed Oct 13 23:44:10 volumio volumio[1143]: info: Updating volume from AirPlay: -7.02; 77% Oct 13 23:44:10 volumio volumio[1143]: info: VolumeController::SetAlsaVolume77 Oct 13 23:44:10 volumio volumio[1143]: info: CoreStateMachine::pushState Oct 13 23:44:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Oct 13 23:44:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 23:44:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Oct 13 23:44:10 volumio volumio[1143]: info: Oct 13 23:44:10 volumio volumio[1143]: ---------------------------- Client requests AirplayActive Oct 13 23:44:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Oct 13 23:44:10 volumio shairport-sync[1853]: [79B blob data] Oct 13 23:44:10 volumio shairport-sync[1851]: [79B blob data] Oct 13 23:44:10 volumio volumio[1143]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Oct 13 23:44:10 volumio volumio[1143]: SPOTIFY: SPOTIFY VOLUME 46 Oct 13 23:44:10 volumio volumio[1143]: SPOTIFY: VOLUMIO VOLUME 77 Oct 13 23:44:10 volumio volumio[1143]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 13 23:44:10 volumio volumio[1143]: info: Setting Spotify Volume from Volumio: 77 Oct 13 23:44:10 volumio volumio[1143]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 23:44:10 volumio volumio[1143]: RangeError [ERR_OUT_OF_RANGE]: The value of "byteLength" is out of range. It must be >= 1 and <= 6. Received 8 Oct 13 23:44:10 volumio volumio[1143]: at new NodeError (node:internal/errors:405:5) Oct 13 23:44:10 volumio volumio[1143]: at boundsError (node:internal/buffer:88:9) Oct 13 23:44:10 volumio volumio[1143]: at Buffer.readUIntBE (node:internal/buffer:275:3) Oct 13 23:44:10 volumio volumio[1143]: at Object.number (/volumio/node_modules/daap-parser/daap-parser.js:33:15) Oct 13 23:44:10 volumio volumio[1143]: at Object.tag [as parseTag] (/volumio/node_modules/daap-parser/daap-parser.js:85:37) Oct 13 23:44:10 volumio volumio[1143]: at ShairportSyncReaderUDP.useData (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-base.js:89:38) Oct 13 23:44:10 volumio volumio[1143]: at Socket. (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-udp.js:21:12) Oct 13 23:44:10 volumio volumio[1143]: at Socket.emit (node:events:514:28) Oct 13 23:44:10 volumio volumio[1143]: at UDP.onMessage [as onmessage] (node:dgram:941:8) { Oct 13 23:44:10 volumio volumio[1143]: code: 'ERR_OUT_OF_RANGE' Oct 13 23:44:10 volumio volumio[1143]: } Oct 13 23:44:10 volumio volumio[1143]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 23:44:11 volumio shairport-sync[1851]: {"time":1791927850384,"response":"airplayActive Success"} Oct 13 23:44:16 volumio sudo[1887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 23:43' Oct 13 23:44:16 volumio sudo[1887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"