-- Logs begin at Sat 2025-06-07 00:03:47 UTC, end at Sat 2025-06-07 07:40:10 UTC. -- Jun 07 07:39:07 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 07 07:39:09 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState Jun 07 07:39:09 volumio volumio[1076]: info: CorePlayQueue::getTrack 2 Jun 07 07:39:09 volumio volumio[1076]: info: Listing playlists Jun 07 07:39:09 volumio volumio[1076]: info: Listing playlists Jun 07 07:39:14 volumio volumio[1076]: info: CorePlayQueue::getTrack 2 Jun 07 07:39:14 volumio volumio[1076]: info: CorePlayQueue::getTrack 3 Jun 07 07:39:14 volumio volumio[1076]: info: Prefetching next song Jun 07 07:39:14 volumio volumio[1076]: info: [1749281954363] ControllerVolusonic::prefetch Jun 07 07:39:14 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.157:4533/rest/stream.view?id=j090NDgWQwkszM9kWHsewu&format=raw&u=josh&t=9f1e916cd7111c1f747af3a2c3ad5e5a&s=wTiDU&v=1.10.2&f=json&c=volusonic" Jun 07 07:39:14 volumio volumio[1076]: info: Jun 07 07:39:14 volumio volumio[1076]: ---------------------------- MPD announces system playlist update Jun 07 07:39:14 volumio volumio[1076]: info: Ignoring MPD Status Update Jun 07 07:39:14 volumio volumio[1076]: info: sendMpdCommand add "http://192.168.1.157:4533/rest/stream.view?id=j090NDgWQwkszM9kWHsewu&format=raw&u=josh&t=9f1e916cd7111c1f747af3a2c3ad5e5a&s=wTiDU&v=1.10.2&f=json&c=volusonic" took 3 milliseconds Jun 07 07:39:14 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 07 07:39:14 volumio volumio[1076]: info: ------------------------------ 4ms Jun 07 07:39:14 volumio volumio[1076]: info: sendMpdCommand consume 1 took 2 milliseconds Jun 07 07:39:18 volumio volumio[1076]: info: CoreStateMachine::startPlaybackTimer Jun 07 07:39:18 volumio volumio[1076]: info: CorePlayQueue::getTrack 3 Jun 07 07:39:19 volumio volumio[1076]: info: Jun 07 07:39:19 volumio volumio[1076]: ---------------------------- MPD announces system playlist update Jun 07 07:39:19 volumio volumio[1076]: info: Ignoring MPD Status Update Jun 07 07:39:19 volumio volumio[1076]: info: [1749281959162] ControllerVolusonic: MPD player state update Jun 07 07:39:19 volumio volumio[1076]: info: ControllerMpd::getState Jun 07 07:39:19 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Jun 07 07:39:19 volumio volumio[1076]: info: Jun 07 07:39:19 volumio volumio[1076]: ---------------------------- MPD announces state update: player Jun 07 07:39:19 volumio volumio[1076]: info: ControllerMpd::getState Jun 07 07:39:19 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Jun 07 07:39:19 volumio volumio[1076]: info: Jun 07 07:39:19 volumio volumio[1076]: ---------------------------- MPD announces system playlist update Jun 07 07:39:19 volumio volumio[1076]: info: Ignoring MPD Status Update Jun 07 07:39:19 volumio volumio[1076]: info: Jun 07 07:39:19 volumio volumio[1076]: ---------------------------- MPD announces state update: player Jun 07 07:39:19 volumio volumio[1076]: info: ControllerMpd::getState Jun 07 07:39:19 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Jun 07 07:39:19 volumio volumio[1076]: info: sendMpdCommand status took 5 milliseconds Jun 07 07:39:19 volumio volumio[1076]: info: ------------------------------ 6ms Jun 07 07:39:19 volumio volumio[1076]: info: sendMpdCommand status took 4 milliseconds Jun 07 07:39:19 volumio volumio[1076]: verbose: ControllerMpd::parseState Jun 07 07:39:19 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 07:39:19 volumio volumio[1076]: verbose: ControllerMpd::parseState Jun 07 07:39:19 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 07:39:19 volumio volumio[1076]: info: ------------------------------ 6ms Jun 07 07:39:19 volumio volumio[1076]: info: sendMpdCommand status took 5 milliseconds Jun 07 07:39:19 volumio volumio[1076]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 07 07:39:19 volumio volumio[1076]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 07 07:39:19 volumio volumio[1076]: verbose: ControllerMpd::parseState Jun 07 07:39:19 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 07:39:19 volumio volumio[1076]: verbose: ControllerMpd::parseTrackInfo Jun 07 07:39:19 volumio volumio[1076]: verbose: ControllerMpd::parseTrackInfo Jun 07 07:39:19 volumio volumio[1076]: info: CorePlayQueue::getTrack 3 Jun 07 07:39:19 volumio volumio[1076]: info: [1749281959176] ControllerVolusonic::pushState Jun 07 07:39:19 volumio volumio[1076]: info: CoreCommandRouter::servicePushState Jun 07 07:39:19 volumio volumio[1076]: info: CorePlayQueue::getTrack 3 Jun 07 07:39:19 volumio volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"681 Kbps","isStreaming":false,"title":"Lumière - Promenade dans Lumière","artist":"Lorien Testard","album":"Clair Obscur: Expedition 33 (Original Soundtrack)","uri":"http://192.168.1.157:4533/rest/stream.view?id=j090NDgWQwkszM9kWHsewu&format=raw&u=josh&t=9f1e916cd7111c1f747af3a2c3ad5e5a&s=wTiDU&v=1.10.2&f=json&c=volusonic","trackType":""} Jun 07 07:39:19 volumio volumio[1076]: verbose: CURRENT POSITION 3 Jun 07 07:39:19 volumio volumio[1076]: info: CoreStateMachine::syncState stateService play Jun 07 07:39:19 volumio volumio[1076]: info: CoreStateMachine::syncState currentStatus play Jun 07 07:39:19 volumio volumio[1076]: info: Received an update from plugin. extracting info from payload Jun 07 07:39:19 volumio volumio[1076]: info: CoreStateMachine::pushState Jun 07 07:39:19 volumio volumio[1076]: info: CorePlayQueue::getTrack 3 Jun 07 07:39:19 volumio volumio[1076]: info: CoreCommandRouter::volumioPushState Jun 07 07:39:19 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 07:39:19 volumio volumio[1076]: info: CoreStateMachine::pushState Jun 07 07:39:19 volumio volumio[1076]: info: CorePlayQueue::getTrack 3 Jun 07 07:39:19 volumio volumio[1076]: info: CoreCommandRouter::volumioPushState Jun 07 07:39:19 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 07:39:19 volumio volumio[1076]: info: ControllerMpd::pushState Jun 07 07:39:19 volumio volumio[1076]: info: CoreCommandRouter::servicePushState Jun 07 07:39:19 volumio volumio[1076]: info: CorePlayQueue::getTrack 3 Jun 07 07:39:19 volumio volumio[1076]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jun 07 07:39:19 volumio volumio[1076]: info: ------------------------------ 41ms Jun 07 07:39:19 volumio volumio[1076]: info: sendMpdCommand playlistinfo took 31 milliseconds Jun 07 07:39:19 volumio volumio[1076]: verbose: ControllerMpd::parseTrackInfo Jun 07 07:39:19 volumio volumio[1076]: info: ControllerMpd::pushState Jun 07 07:39:19 volumio volumio[1076]: info: CoreCommandRouter::servicePushState Jun 07 07:39:19 volumio volumio[1076]: info: CorePlayQueue::getTrack 3 Jun 07 07:39:19 volumio volumio[1076]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jun 07 07:39:19 volumio volumio[1076]: info: ------------------------------ 42ms Jun 07 07:39:19 volumio volumio[1076]: info: CoreCommandRouter::volumioGetQueue Jun 07 07:39:19 volumio volumio[1076]: info: CoreStateMachine::getQueue Jun 07 07:39:19 volumio volumio[1076]: info: CorePlayQueue::getQueue Jun 07 07:39:19 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState Jun 07 07:39:19 volumio volumio[1076]: info: CorePlayQueue::getTrack 3 Jun 07 07:39:29 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState Jun 07 07:39:29 volumio volumio[1076]: info: CorePlayQueue::getTrack 3 Jun 07 07:39:29 volumio volumio[1076]: info: Listing playlists Jun 07 07:39:29 volumio volumio[1076]: info: Listing playlists Jun 07 07:39:39 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState Jun 07 07:39:39 volumio volumio[1076]: info: CorePlayQueue::getTrack 3 Jun 07 07:39:49 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState Jun 07 07:39:49 volumio volumio[1076]: info: CorePlayQueue::getTrack 3 Jun 07 07:39:49 volumio volumio[1076]: info: Listing playlists Jun 07 07:39:49 volumio volumio[1076]: info: Listing playlists Jun 07 07:39:59 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState Jun 07 07:39:59 volumio volumio[1076]: info: CorePlayQueue::getTrack 3 Jun 07 07:40:09 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState Jun 07 07:40:09 volumio volumio[1076]: info: CorePlayQueue::getTrack 3 Jun 07 07:40:09 volumio volumio[1076]: info: Listing playlists Jun 07 07:40:09 volumio volumio[1076]: info: Listing playlists Jun 07 07:40:09 volumio volumio[1076]: info: Adding volusonic/track/pfwkihm6DsJTkpFuD0SzaE to favourites with specific volusonic method Jun 07 07:40:09 volumio volumio[1076]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 07:40:09 volumio volumio[1076]: TypeError: Cannot read property 'then' of undefined Jun 07 07:40:09 volumio volumio[1076]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:579:20) Jun 07 07:40:09 volumio volumio[1076]: at emitTwo (events.js:126:13) Jun 07 07:40:09 volumio volumio[1076]: at Socket.emit (events.js:214:7) Jun 07 07:40:09 volumio volumio[1076]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 07 07:40:09 volumio volumio[1076]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jun 07 07:40:09 volumio volumio[1076]: at process._tickCallback (internal/process/next_tick.js:180:9) Jun 07 07:40:09 volumio volumio[1076]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 07:40:10 volumio sudo[2711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-07 07:39 Jun 07 07:40:10 volumio sudo[2711]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="e81b2037a65d8f554cb0c857a1007d659ad7f838" VOLUMIO_FE_VERSION="e489037accd8e0b2dcbd6e0b054400d8cd36d4e2" VOLUMIO_BE_VERSION="25875b197c75cd1ac7eb5664d94cdd19afb06985" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Feb 19 17:39:10 CET 2021" VOLUMIO_VERSION="2.873" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="781d4c9fbf86d562939968306a6a26f4"