-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Wed 2025-09-17 19:00:01 BST. -- Sep 17 18:59:00 volumiokitchen volumio[987]: info: MyVolumio status changed Sep 17 18:59:00 volumiokitchen volumio[987]: info: Streaming services startup Sep 17 18:59:00 volumiokitchen volumio[987]: info: Starting Streaming Daemon Sep 17 18:59:00 volumiokitchen volumio[987]: info: Removing browser output: myVolumio user plan is not superstar Sep 17 18:59:00 volumiokitchen volumio[987]: info: Removing audio output: Sep 17 18:59:00 volumiokitchen volumio[987]: info: Stoppping Tunnel 1 Sep 17 18:59:00 volumiokitchen sudo[5782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 17 18:59:00 volumiokitchen sudo[5782]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:59:00 volumiokitchen sudo[5785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 17 18:59:00 volumiokitchen sudo[5785]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:59:00 volumiokitchen sudo[5782]: pam_unix(sudo:session): session closed for user root Sep 17 18:59:00 volumiokitchen volumio[987]: error: Cannot start Volumio Streaming Daemon Sep 17 18:59:00 volumiokitchen volumio[987]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 17 18:59:00 volumiokitchen volumio[987]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 17 18:59:00 volumiokitchen sudo[5785]: pam_unix(sudo:session): session closed for user root Sep 17 18:59:00 volumiokitchen volumio[987]: info: Remote SSH Stopped Sep 17 18:59:00 volumiokitchen volumio[987]: info: Setting Geolocation for MyVolumio to eu3 Sep 17 18:59:00 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:59:00 volumiokitchen ntpd[803]: Soliciting pool server 139.143.5.32 Sep 17 18:59:00 volumiokitchen ntpd[803]: Soliciting pool server 2001:678:8::123 Sep 17 18:59:00 volumiokitchen ntpd[803]: Soliciting pool server 172.237.96.114 Sep 17 18:59:00 volumiokitchen ntpd[803]: Soliciting pool server 149.22.188.7 Sep 17 18:59:00 volumiokitchen volumio[987]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 17 18:59:01 volumiokitchen volumio[987]: info: Updating MyVolumio device info Sep 17 18:59:01 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:59:01 volumiokitchen ntpd[803]: Soliciting pool server 162.159.200.1 Sep 17 18:59:01 volumiokitchen ntpd[803]: Soliciting pool server 212.71.233.40 Sep 17 18:59:01 volumiokitchen ntpd[803]: Soliciting pool server 139.162.242.115 Sep 17 18:59:01 volumiokitchen volumio[987]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 17 18:59:04 volumiokitchen ntpd[803]: receive: Unexpected origin timestamp 0xec757567.c3eeb4d2 does not match aorg 0000000000.00000000 from server@217.114.59.3 xmt 0xec757568.602a83fc Sep 17 18:59:04 volumiokitchen ntpd[803]: receive: Unexpected origin timestamp 0xec757567.c3e61d8e does not match aorg 0000000000.00000000 from server@193.57.144.50 xmt 0xec757568.61ed8a50 Sep 17 18:59:05 volumiokitchen volumio[987]: info: MYVOLUMIO: Adding device Sep 17 18:59:05 volumiokitchen volumio[987]: info: MYVOLUMIO: Evaluating Server Sep 17 18:59:06 volumiokitchen volumio[987]: info: Setting Geolocation for MyVolumio to eu5 Sep 17 18:59:06 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:59:06 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:59:06 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:59:06 volumiokitchen volumio[987]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 17 18:59:07 volumiokitchen volumio[987]: info: Updating MyVolumio device info Sep 17 18:59:07 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:59:07 volumiokitchen volumio[987]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 17 18:59:11 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 17 18:59:11 volumiokitchen volumio[987]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 17 18:59:11 volumiokitchen volumio[987]: info: CoreCommandRouter::volumioGetState Sep 17 18:59:11 volumiokitchen volumio[987]: info: CorePlayQueue::getTrack 0 Sep 17 18:59:16 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:59:16 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:59:16 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 17 18:59:16 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 17 18:59:16 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 17 18:59:16 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 17 18:59:16 volumiokitchen volumio[987]: info: CoreCommandRouter::volumioGetBrowseSources Sep 17 18:59:16 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 18:59:18 volumiokitchen volumio[987]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 17 18:59:18 volumiokitchen volumio[987]: info: Completed starting MyVolumio Plugin Sep 17 18:59:18 volumiokitchen volumio[987]: [Metrics] CommandRouter: 73s 382.99ms Sep 17 18:59:18 volumiokitchen volumio[987]: info: CoreCommandRouter::volumiosetStartupVolume Sep 17 18:59:18 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:59:18 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:59:18 volumiokitchen volumio[987]: info: CoreCommandRouter::Close All Modals sent Sep 17 18:59:18 volumiokitchen volumio[987]: info: CoreCommandRouter::Close All Modals sent Sep 17 18:59:18 volumiokitchen volumio[987]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 17 18:59:18 volumiokitchen volumio[987]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 17 18:59:18 volumiokitchen volumio[987]: info: camilladsp stopping service pid 7942... Sep 17 18:59:18 volumiokitchen volumio[987]: info: camilladsp service terminated, instance 1 Sep 17 18:59:18 volumiokitchen volumio[987]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 17 18:59:18 volumiokitchen volumio[987]: info: camilladsp service started and running in background, instance 1 Sep 17 18:59:18 volumiokitchen go-librespot[1200]: time="2025-09-17T18:59:18+01:00" level=trace msg="sent dealer ping" Sep 17 18:59:18 volumiokitchen go-librespot[1200]: time="2025-09-17T18:59:18+01:00" level=trace msg="received dealer pong" Sep 17 18:59:19 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 17 18:59:19 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 17 18:59:19 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 17 18:59:25 volumiokitchen volumio[987]: info: BOOT COMPLETED Sep 17 18:59:48 volumiokitchen go-librespot[1200]: time="2025-09-17T18:59:48+01:00" level=trace msg="sent dealer ping" Sep 17 18:59:48 volumiokitchen go-librespot[1200]: time="2025-09-17T18:59:48+01:00" level=trace msg="received dealer pong" Sep 17 18:59:50 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 17 18:59:50 volumiokitchen volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 17 18:59:50 volumiokitchen volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 18:59:50 volumiokitchen volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 18:59:50 volumiokitchen volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 18:59:51 volumiokitchen volumio[987]: info: FusionDsp - no preset used Sep 17 18:59:51 volumiokitchen volumio[987]: info: FusionDsp - no preset used Sep 17 18:59:51 volumiokitchen volumio[987]: info: FusionDsp - no preset used Sep 17 18:59:51 volumiokitchen volumio[987]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Sep 17 18:59:51 volumiokitchen volumio[987]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Sep 17 18:59:51 volumiokitchen volumio[987]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Sep 17 19:00:00 volumiokitchen volumio[987]: info: Starting Uninstall of plugin system_hardware - iractivityled Sep 17 19:00:00 volumiokitchen volumio[987]: info: Uninstalling plugin iractivityled Sep 17 19:00:00 volumiokitchen volumio[987]: info: [IrActivityLed] releasing built-in LED Sep 17 19:00:00 volumiokitchen volumio[987]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 19:00:00 volumiokitchen volumio[987]: TypeError: Cannot read property 'toString' of undefined Sep 17 19:00:00 volumiokitchen volumio[987]: at IrActivityLedController.releaseLed (/data/plugins/system_hardware/iractivityled/index.js:232:61) Sep 17 19:00:00 volumiokitchen volumio[987]: at IrActivityLedController.onStop (/data/plugins/system_hardware/iractivityled/index.js:68:17) Sep 17 19:00:00 volumiokitchen volumio[987]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Sep 17 19:00:00 volumiokitchen volumio[987]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Sep 17 19:00:00 volumiokitchen volumio[987]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Sep 17 19:00:00 volumiokitchen volumio[987]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) Sep 17 19:00:00 volumiokitchen volumio[987]: at Socket.emit (events.js:315:20) Sep 17 19:00:00 volumiokitchen volumio[987]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 17 19:00:00 volumiokitchen volumio[987]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 17 19:00:00 volumiokitchen volumio[987]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 19:00:01 volumiokitchen sudo[13179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-17 18:59 Sep 17 19:00:01 volumiokitchen sudo[13179]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"