-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2025-07-03 15:30:34 CEST. -- Jul 03 15:29:00 volumio volumio[940]: STREAMING PROXY: Starting server on port 3245 Jul 03 15:29:00 volumio volumio[940]: Node JS runtime: 14 Jul 03 15:29:00 volumio volumio[940]: info: MyVolumio token set successfully Jul 03 15:29:00 volumio volumio[940]: info: MYVOLUMIO: Adding device Jul 03 15:29:00 volumio volumio[940]: info: MYVOLUMIO: Evaluating Server Jul 03 15:29:00 volumio volumio[940]: info: MyVolumio status changed Jul 03 15:29:00 volumio volumio[940]: info: Streaming services startup Jul 03 15:29:00 volumio volumio[940]: info: Starting Streaming Daemon Jul 03 15:29:00 volumio volumio[940]: info: Removing browser output: myVolumio user plan is not superstar Jul 03 15:29:00 volumio volumio[940]: info: Removing audio output: Jul 03 15:29:00 volumio volumio[940]: info: Stoppping Tunnel 1 Jul 03 15:29:00 volumio sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 03 15:29:00 volumio sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:29:00 volumio sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 03 15:29:00 volumio sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 15:29:01 volumio sudo[1337]: pam_unix(sudo:session): session closed for user root Jul 03 15:29:01 volumio volumio[940]: error: Cannot start Volumio Streaming Daemon Jul 03 15:29:01 volumio volumio[940]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 03 15:29:01 volumio volumio[940]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 03 15:29:01 volumio sudo[1340]: pam_unix(sudo:session): session closed for user root Jul 03 15:29:01 volumio volumio[940]: info: Remote SSH Stopped Jul 03 15:29:01 volumio volumio[940]: info: Setting Geolocation for MyVolumio to eu6 Jul 03 15:29:01 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:29:01 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:29:01 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:29:01 volumio volumio[940]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 03 15:29:02 volumio systemd[1]: systemd-timedated.service: Succeeded. Jul 03 15:29:02 volumio volumio[940]: info: Updating MyVolumio device info Jul 03 15:29:02 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:29:02 volumio volumio[940]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 03 15:29:05 volumio volumio[940]: info: MYVOLUMIO: Adding device Jul 03 15:29:05 volumio volumio[940]: info: MYVOLUMIO: Evaluating Server Jul 03 15:29:06 volumio volumio[940]: info: Setting Geolocation for MyVolumio to eu4 Jul 03 15:29:06 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:29:06 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:29:06 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:29:06 volumio volumio[1237]: .................................................................................................................++++ Jul 03 15:29:06 volumio volumio[940]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 03 15:29:07 volumio volumio[940]: info: Updating MyVolumio device info Jul 03 15:29:07 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:29:07 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:29:07 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:29:07 volumio volumio[940]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 03 15:29:12 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 15:29:12 volumio volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jul 03 15:29:12 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jul 03 15:29:12 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Jul 03 15:29:15 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 15:29:15 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:29:15 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 03 15:29:15 volumio volumio-remote-updater[597]: No test mode Jul 03 15:29:15 volumio volumio-remote-updater[597]: No alpha test mode Jul 03 15:29:15 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 03 15:29:15 volumio volumio[940]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 03 15:29:15 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 03 15:29:17 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:29:17 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:29:17 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 03 15:29:17 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 03 15:29:17 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 03 15:29:17 volumio volumio[940]: info: CoreCommandRouter::volumioGetBrowseSources Jul 03 15:29:17 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 15:29:18 volumio volumio[940]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 03 15:29:18 volumio volumio[940]: info: Completed starting MyVolumio Plugin Jul 03 15:29:18 volumio volumio[940]: [Metrics] CommandRouter: 41s 478.03ms Jul 03 15:29:18 volumio volumio[940]: info: CoreCommandRouter::volumiosetStartupVolume Jul 03 15:29:18 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 15:29:18 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 15:29:18 volumio volumio[940]: info: CoreCommandRouter::Close All Modals sent Jul 03 15:29:18 volumio volumio[940]: info: CoreCommandRouter::Close All Modals sent Jul 03 15:29:19 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 03 15:29:19 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 03 15:29:19 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 03 15:29:22 volumio volumio[1237]: .....................................................................................................................++++ Jul 03 15:29:22 volumio volumio[1237]: e is 65537 (0x010001) Jul 03 15:29:22 volumio volumio[1237]: writing RSA key Jul 03 15:29:25 volumio volumio[940]: info: BOOT COMPLETED Jul 03 15:29:33 volumio ntpd[779]: Soliciting pool server 89.25.168.148 Jul 03 15:29:34 volumio ntpd[779]: Soliciting pool server 156.17.20.254 Jul 03 15:29:34 volumio ntpd[779]: Soliciting pool server 46.175.224.7 Jul 03 15:29:35 volumio ntpd[779]: Soliciting pool server 178.215.228.24 Jul 03 15:29:35 volumio ntpd[779]: Soliciting pool server 51.68.141.5 Jul 03 15:29:36 volumio ntpd[779]: Soliciting pool server 194.146.251.102 Jul 03 15:29:36 volumio ntpd[779]: Soliciting pool server 85.115.212.251 Jul 03 15:29:37 volumio ntpd[779]: Soliciting pool server 193.70.94.182 Jul 03 15:29:37 volumio ntpd[779]: Soliciting pool server 193.59.134.156 Jul 03 15:29:37 volumio ntpd[779]: Soliciting pool server 162.159.200.123 Jul 03 15:29:38 volumio ntpd[779]: Soliciting pool server 193.25.222.136 Jul 03 15:29:38 volumio ntpd[779]: Soliciting pool server 2001:678:8::123 Jul 03 15:29:39 volumio ntpd[779]: Soliciting pool server 212.127.95.218 Jul 03 15:29:40 volumio ntpd[779]: Soliciting pool server 149.156.70.60 Jul 03 15:29:42 volumio ntpd[779]: receive: Unexpected origin timestamp 0xec110445.0dcc3ccc does not match aorg 0000000000.00000000 from server@156.17.20.254 xmt 0xec110446.2aabfedc Jul 03 15:30:13 volumio sshd[1739]: Accepted password for volumio from 10.202.6.20 port 64208 ssh2 Jul 03 15:30:13 volumio sshd[1739]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jul 03 15:30:13 volumio systemd[1]: Created slice User Slice of UID 1000. Jul 03 15:30:13 volumio systemd[1]: Starting User Runtime Directory /run/user/1000... Jul 03 15:30:13 volumio systemd-logind[601]: New session 1 of user volumio. Jul 03 15:30:13 volumio systemd[1]: Started User Runtime Directory /run/user/1000. Jul 03 15:30:13 volumio systemd[1]: Starting User Manager for UID 1000... Jul 03 15:30:13 volumio systemd[1742]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Jul 03 15:30:13 volumio systemd[1742]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Jul 03 15:30:13 volumio systemd[1742]: Reached target Paths. Jul 03 15:30:13 volumio systemd[1742]: Starting D-Bus User Message Bus Socket. Jul 03 15:30:13 volumio systemd[1742]: Listening on Sound System. Jul 03 15:30:13 volumio systemd[1742]: Reached target Timers. Jul 03 15:30:13 volumio systemd[1742]: Listening on GnuPG cryptographic agent and passphrase cache. Jul 03 15:30:13 volumio systemd[1742]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Jul 03 15:30:13 volumio systemd[1742]: Listening on GnuPG network certificate management daemon. Jul 03 15:30:13 volumio systemd[1742]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Jul 03 15:30:13 volumio systemd[1742]: Listening on D-Bus User Message Bus Socket. Jul 03 15:30:13 volumio systemd[1742]: Reached target Sockets. Jul 03 15:30:13 volumio systemd[1742]: Reached target Basic System. Jul 03 15:30:13 volumio systemd[1742]: Reached target Default. Jul 03 15:30:13 volumio systemd[1742]: Startup finished in 358ms. Jul 03 15:30:13 volumio systemd[1]: Started User Manager for UID 1000. Jul 03 15:30:13 volumio systemd[1]: Started Session 1 of user volumio. Jul 03 15:30:25 volumio volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 15:30:25 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 03 15:30:25 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 03 15:30:25 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 03 15:30:25 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jul 03 15:30:25 volumio volumio[940]: info: Received Get System Version Jul 03 15:30:25 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 15:30:33 volumio volumio[940]: info: Command Delete User Data Received Jul 03 15:30:33 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: inputs , setMCUFactoryReset Jul 03 15:30:33 volumio volumio[940]: info: Error : CoreCommandRouter::executeOnPlugin: No method [setMCUFactoryReset] in plugin inputs Jul 03 15:30:33 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , deleteUserData Jul 03 15:30:33 volumio volumio[940]: info: Created User Data delete file, rebooting Jul 03 15:30:33 volumio volumio[940]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Jul 03 15:30:33 volumio volumio[940]: info: PLUGIN onReboot : networkfs Jul 03 15:30:33 volumio volumio[940]: info: PLUGIN onReboot : audiophonicsonoff Jul 03 15:30:33 volumio volumio[940]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 15:30:33 volumio volumio[940]: TypeError: Cannot read property 'writeSync' of undefined Jul 03 15:30:33 volumio volumio[940]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Jul 03 15:30:33 volumio volumio[940]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Jul 03 15:30:33 volumio volumio[940]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Jul 03 15:30:33 volumio volumio[940]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 03 15:30:33 volumio volumio[940]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 03 15:30:33 volumio volumio[940]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Jul 03 15:30:33 volumio volumio[940]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Jul 03 15:30:33 volumio volumio[940]: at /volumio/app/plugins/system_controller/system/index.js:824:26 Jul 03 15:30:33 volumio volumio[940]: at /volumio/node_modules/graceful-fs/graceful-fs.js:144:16 Jul 03 15:30:33 volumio volumio[940]: at /volumio/node_modules/graceful-fs/graceful-fs.js:61:14 Jul 03 15:30:33 volumio volumio[940]: at FSReqCallback.oncomplete (fs.js:171:23) Jul 03 15:30:33 volumio volumio[940]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 15:30:34 volumio sudo[1924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-03 15:29 Jul 03 15:30:34 volumio sudo[1924]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"