-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Mon 2025-12-08 17:21:05 UTC. -- Dec 08 17:20:03 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 08 17:20:03 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 17:20:03 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 08 17:20:03 muzik volumio-remote-updater[606]: No test mode Dec 08 17:20:03 muzik volumio-remote-updater[606]: No alpha test mode Dec 08 17:20:03 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 08 17:20:03 muzik volumio[855]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 08 17:20:03 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 08 17:20:04 muzik systemd[1]: systemd-timedated.service: Succeeded. Dec 08 17:20:04 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 17:20:04 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 17:20:04 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 08 17:20:04 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 08 17:20:04 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 08 17:20:04 muzik volumio[855]: info: CoreCommandRouter::volumioGetBrowseSources Dec 08 17:20:04 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 08 17:20:05 muzik go-librespot[1139]: time="2025-12-08T17:20:05Z" level=trace msg="sent dealer ping" Dec 08 17:20:05 muzik volumio[855]: info: BOOT COMPLETED Dec 08 17:20:05 muzik go-librespot[1139]: time="2025-12-08T17:20:05Z" level=trace msg="received dealer pong" Dec 08 17:20:05 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 08 17:20:05 muzik volumio[855]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 08 17:20:05 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 08 17:20:05 muzik volumio[855]: info: Received Get System Version Dec 08 17:20:05 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 08 17:20:05 muzik volumio[855]: info: Received Get System Info Dec 08 17:20:05 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 08 17:20:05 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 08 17:20:05 muzik volumio[855]: info: Discovery: Getting this device information Dec 08 17:20:05 muzik volumio[855]: info: CoreCommandRouter::volumioGetState Dec 08 17:20:05 muzik volumio[855]: info: CorePlayQueue::getTrack 0 Dec 08 17:20:05 muzik volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 17:20:25 muzik ntpd[730]: Soliciting pool server 193.57.144.50 Dec 08 17:20:26 muzik ntpd[730]: Soliciting pool server 139.162.219.252 Dec 08 17:20:26 muzik ntpd[730]: Soliciting pool server 194.213.3.203 Dec 08 17:20:27 muzik ntpd[730]: Soliciting pool server 217.154.60.177 Dec 08 17:20:27 muzik ntpd[730]: Soliciting pool server 162.159.200.123 Dec 08 17:20:27 muzik ntpd[730]: Soliciting pool server 213.5.132.231 Dec 08 17:20:27 muzik ntpd[730]: Soliciting pool server 213.210.39.123 Dec 08 17:20:28 muzik ntpd[730]: Soliciting pool server 176.58.127.131 Dec 08 17:20:28 muzik ntpd[730]: Soliciting pool server 212.71.233.44 Dec 08 17:20:28 muzik ntpd[730]: Soliciting pool server 217.154.37.71 Dec 08 17:20:28 muzik ntpd[730]: Soliciting pool server 178.79.138.215 Dec 08 17:20:29 muzik ntpd[730]: Soliciting pool server 81.2.125.2 Dec 08 17:20:29 muzik ntpd[730]: Soliciting pool server 143.20.69.40 Dec 08 17:20:29 muzik ntpd[730]: Soliciting pool server 176.58.115.34 Dec 08 17:20:29 muzik ntpd[730]: Soliciting pool server 162.159.200.1 Dec 08 17:20:30 muzik ntpd[730]: Soliciting pool server 83.217.166.45 Dec 08 17:20:30 muzik ntpd[730]: Soliciting pool server 34.89.88.197 Dec 08 17:20:30 muzik ntpd[730]: Soliciting pool server 109.74.206.120 Dec 08 17:20:30 muzik ntpd[730]: Soliciting pool server 131.111.8.60 Dec 08 17:20:33 muzik ntpd[730]: receive: Unexpected origin timestamp 0xece18760.94a27463 does not match aorg 0000000000.00000000 from server@81.2.125.2 xmt 0xece18761.9a933ff5 Dec 08 17:20:33 muzik ntpd[730]: receive: Unexpected origin timestamp 0xece18760.94a5404b does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xece18761.9babf064 Dec 08 17:20:33 muzik ntpd[730]: receive: Unexpected origin timestamp 0xece18760.94a451d5 does not match aorg 0000000000.00000000 from server@217.154.60.177 xmt 0xece18761.9b2520d8 Dec 08 17:20:33 muzik ntpd[730]: receive: Unexpected origin timestamp 0xece18760.949eab90 does not match aorg 0000000000.00000000 from server@34.89.88.197 xmt 0xece18761.a3cb2228 Dec 08 17:20:36 muzik go-librespot[1139]: time="2025-12-08T17:20:36Z" level=trace msg="sent dealer ping" Dec 08 17:20:36 muzik go-librespot[1139]: time="2025-12-08T17:20:36Z" level=trace msg="received dealer pong" Dec 08 17:20:45 muzik volumio[855]: info: Preload queue cleared Dec 08 17:20:45 muzik volumio[855]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 08 17:20:45 muzik volumio[855]: info: CoreStateMachine::ClearQueue Dec 08 17:20:45 muzik volumio[855]: info: CoreStateMachine::stop Dec 08 17:20:45 muzik volumio[855]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 08 17:20:45 muzik volumio[855]: info: CorePlayQueue::clearPlayQueue Dec 08 17:20:45 muzik volumio[855]: info: CorePlayQueue::saveQueue Dec 08 17:20:45 muzik volumio[855]: info: CoreCommandRouter::volumioPushQueue Dec 08 17:20:45 muzik volumio[855]: info: CoreStateMachine::addQueueItems Dec 08 17:20:45 muzik volumio[855]: info: CorePlayQueue::addQueueItems Dec 08 17:20:45 muzik volumio[855]: info: Preload queue cleared Dec 08 17:20:45 muzik volumio[855]: info: Adding Item to queue: spotify:artist:6ZLTlhejhndI4Rh53vYhrY Dec 08 17:20:45 muzik volumio[855]: info: Exploding uri spotify:artist:6ZLTlhejhndI4Rh53vYhrY in service spop Dec 08 17:20:45 muzik volumio[855]: SPOTIFY: EXPLODING URI:spotify:artist:6ZLTlhejhndI4Rh53vYhrY Dec 08 17:20:45 muzik volumio[855]: (node:855) UnhandledPromiseRejectionWarning: WebapiRegularError: An error occurred while communicating with Spotify's Web API. Dec 08 17:20:45 muzik volumio[855]: Details: No token provided. Dec 08 17:20:45 muzik volumio[855]: at _toError (/data/plugins/music_service/spop/node_modules/spotify-web-api-node/src/http-manager.js:39:12) Dec 08 17:20:45 muzik volumio[855]: at /data/plugins/music_service/spop/node_modules/spotify-web-api-node/src/http-manager.js:71:25 Dec 08 17:20:45 muzik volumio[855]: at Request.callback (/data/plugins/music_service/spop/node_modules/superagent/lib/node/index.js:905:3) Dec 08 17:20:45 muzik volumio[855]: at /data/plugins/music_service/spop/node_modules/superagent/lib/node/index.js:1127:20 Dec 08 17:20:45 muzik volumio[855]: at IncomingMessage. (/data/plugins/music_service/spop/node_modules/superagent/lib/node/parsers/json.js:22:7) Dec 08 17:20:45 muzik volumio[855]: at Stream.emit (events.js:315:20) Dec 08 17:20:45 muzik volumio[855]: at Unzip. (/data/plugins/music_service/spop/node_modules/superagent/lib/node/unzip.js:53:12) Dec 08 17:20:45 muzik volumio[855]: at Unzip.emit (events.js:315:20) Dec 08 17:20:45 muzik volumio[855]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 08 17:20:45 muzik volumio[855]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 08 17:20:45 muzik volumio[855]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 08 17:20:45 muzik volumio[855]: (node:855) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Dec 08 17:20:45 muzik volumio[855]: (node:855) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Dec 08 17:20:52 muzik volumio[855]: info: Preload queue cleared Dec 08 17:20:52 muzik volumio[855]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 08 17:20:52 muzik volumio[855]: info: CoreStateMachine::ClearQueue Dec 08 17:20:52 muzik volumio[855]: info: CoreStateMachine::stop Dec 08 17:20:52 muzik volumio[855]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 08 17:20:52 muzik volumio[855]: info: CorePlayQueue::clearPlayQueue Dec 08 17:20:52 muzik volumio[855]: info: CorePlayQueue::saveQueue Dec 08 17:20:52 muzik volumio[855]: info: CoreCommandRouter::volumioPushQueue Dec 08 17:20:52 muzik volumio[855]: info: CoreStateMachine::addQueueItems Dec 08 17:20:52 muzik volumio[855]: info: CorePlayQueue::addQueueItems Dec 08 17:20:52 muzik volumio[855]: info: Preload queue cleared Dec 08 17:20:52 muzik volumio[855]: info: Adding Item to queue: spotify:artist:6ZLTlhejhndI4Rh53vYhrY Dec 08 17:20:52 muzik volumio[855]: info: Exploding uri spotify:artist:6ZLTlhejhndI4Rh53vYhrY in service spop Dec 08 17:20:52 muzik volumio[855]: SPOTIFY: EXPLODING URI:spotify:artist:6ZLTlhejhndI4Rh53vYhrY Dec 08 17:20:52 muzik volumio[855]: (node:855) UnhandledPromiseRejectionWarning: WebapiRegularError: An error occurred while communicating with Spotify's Web API. Dec 08 17:20:52 muzik volumio[855]: Details: No token provided. Dec 08 17:20:52 muzik volumio[855]: at _toError (/data/plugins/music_service/spop/node_modules/spotify-web-api-node/src/http-manager.js:39:12) Dec 08 17:20:52 muzik volumio[855]: at /data/plugins/music_service/spop/node_modules/spotify-web-api-node/src/http-manager.js:71:25 Dec 08 17:20:52 muzik volumio[855]: at Request.callback (/data/plugins/music_service/spop/node_modules/superagent/lib/node/index.js:905:3) Dec 08 17:20:52 muzik volumio[855]: at /data/plugins/music_service/spop/node_modules/superagent/lib/node/index.js:1127:20 Dec 08 17:20:52 muzik volumio[855]: at IncomingMessage. (/data/plugins/music_service/spop/node_modules/superagent/lib/node/parsers/json.js:22:7) Dec 08 17:20:52 muzik volumio[855]: at Stream.emit (events.js:315:20) Dec 08 17:20:52 muzik volumio[855]: at Unzip. (/data/plugins/music_service/spop/node_modules/superagent/lib/node/unzip.js:53:12) Dec 08 17:20:52 muzik volumio[855]: at Unzip.emit (events.js:315:20) Dec 08 17:20:52 muzik volumio[855]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 08 17:20:52 muzik volumio[855]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 08 17:20:52 muzik volumio[855]: (node:855) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 3) Dec 08 17:21:05 muzik volumio[855]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Dec 08 17:21:05 muzik volumio[855]: info: PLUGIN onReboot : networkfs Dec 08 17:21:05 muzik volumio[855]: info: PLUGIN onReboot : audiophonicsonoff Dec 08 17:21:05 muzik volumio[855]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 17:21:05 muzik volumio[855]: TypeError: Cannot read property 'writeSync' of undefined Dec 08 17:21:05 muzik volumio[855]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Dec 08 17:21:05 muzik volumio[855]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Dec 08 17:21:05 muzik volumio[855]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Dec 08 17:21:05 muzik volumio[855]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 08 17:21:05 muzik volumio[855]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 08 17:21:05 muzik volumio[855]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Dec 08 17:21:05 muzik volumio[855]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Dec 08 17:21:05 muzik volumio[855]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Dec 08 17:21:05 muzik volumio[855]: at Socket.emit (events.js:315:20) Dec 08 17:21:05 muzik volumio[855]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 08 17:21:05 muzik volumio[855]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 08 17:21:05 muzik volumio[855]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 17:21:05 muzik sudo[2084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-08 17:20 Dec 08 17:21:05 muzik sudo[2084]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"