-- Logs begin at Sun 2025-06-08 00:54:01 UTC, end at Mon 2025-06-09 23:35:45 UTC. -- Jun 09 23:34:03 volumio go-librespot[895]: time="2025-06-09T23:34:03Z" level=debug msg="fetched chunk 14/23, size: 524288" uri="spotify:track:32Du7kQYAlqVavv4enmRxK" Jun 09 23:34:06 volumio volumio[707]: info: CoreCommandRouter::volumioGetState Jun 09 23:34:06 volumio volumio[707]: info: Listing playlists Jun 09 23:34:06 volumio volumio[707]: info: Listing playlists Jun 09 23:34:15 volumio go-librespot[895]: time="2025-06-09T23:34:15Z" level=debug msg="fetched chunk 15/23, size: 524288" uri="spotify:track:32Du7kQYAlqVavv4enmRxK" Jun 09 23:34:16 volumio volumio[707]: info: CoreCommandRouter::volumioGetState Jun 09 23:34:26 volumio volumio[707]: info: CoreCommandRouter::volumioGetState Jun 09 23:34:26 volumio volumio[707]: info: Listing playlists Jun 09 23:34:26 volumio volumio[707]: info: Listing playlists Jun 09 23:34:27 volumio go-librespot[895]: time="2025-06-09T23:34:27Z" level=debug msg="fetched chunk 16/23, size: 524288" uri="spotify:track:32Du7kQYAlqVavv4enmRxK" Jun 09 23:34:36 volumio volumio[707]: info: CoreCommandRouter::volumioGetState Jun 09 23:34:39 volumio go-librespot[895]: time="2025-06-09T23:34:39Z" level=debug msg="fetched chunk 17/23, size: 524288" uri="spotify:track:32Du7kQYAlqVavv4enmRxK" Jun 09 23:34:46 volumio volumio[707]: info: CoreCommandRouter::volumioGetState Jun 09 23:34:46 volumio volumio[707]: info: Listing playlists Jun 09 23:34:46 volumio volumio[707]: info: Listing playlists Jun 09 23:34:51 volumio go-librespot[895]: time="2025-06-09T23:34:51Z" level=debug msg="fetched chunk 18/23, size: 524288" uri="spotify:track:32Du7kQYAlqVavv4enmRxK" Jun 09 23:34:56 volumio volumio[707]: info: CoreCommandRouter::volumioGetState Jun 09 23:35:04 volumio go-librespot[895]: time="2025-06-09T23:35:04Z" level=debug msg="fetched chunk 19/23, size: 524288" uri="spotify:track:32Du7kQYAlqVavv4enmRxK" Jun 09 23:35:06 volumio volumio[707]: info: CoreCommandRouter::volumioGetState Jun 09 23:35:06 volumio volumio[707]: info: Listing playlists Jun 09 23:35:06 volumio volumio[707]: info: Listing playlists Jun 09 23:35:16 volumio volumio[707]: info: CoreCommandRouter::volumioGetState Jun 09 23:35:16 volumio go-librespot[895]: time="2025-06-09T23:35:16Z" level=debug msg="fetched chunk 20/23, size: 524288" uri="spotify:track:32Du7kQYAlqVavv4enmRxK" Jun 09 23:35:26 volumio volumio[707]: info: CoreCommandRouter::volumioGetState Jun 09 23:35:26 volumio volumio[707]: info: Listing playlists Jun 09 23:35:26 volumio volumio[707]: info: Listing playlists Jun 09 23:35:28 volumio go-librespot[895]: time="2025-06-09T23:35:28Z" level=debug msg="fetched chunk 21/23, size: 524288" uri="spotify:track:32Du7kQYAlqVavv4enmRxK" Jun 09 23:35:32 volumio volumio[707]: verbose: New Socket.io Connection to 192.168.135.14 from 192.168.135.41 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Total Clients: 4 Jun 09 23:35:32 volumio volumio[707]: verbose: New Socket.io Connection to 192.168.135.14 from 192.168.135.41 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Total Clients: 5 Jun 09 23:35:32 volumio volumio[707]: verbose: New Socket.io Connection to 192.168.135.14 from 192.168.135.41 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Total Clients: 6 Jun 09 23:35:34 volumio volumio[707]: verbose: New Socket.io Connection to 192.168.135.14 from 192.168.135.41 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Total Clients: 7 Jun 09 23:35:34 volumio volumio[707]: info: CoreCommandRouter::volumioGetState Jun 09 23:35:34 volumio volumio[707]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 09 23:35:34 volumio volumio[707]: info: Listing playlists Jun 09 23:35:35 volumio sudo[15056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 09 23:35:35 volumio sudo[15056]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 23:35:35 volumio sudo[15056]: pam_unix(sudo:session): session closed for user root Jun 09 23:35:35 volumio sudo[15058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 09 23:35:35 volumio sudo[15058]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 23:35:35 volumio sudo[15058]: pam_unix(sudo:session): session closed for user root Jun 09 23:35:35 volumio volumio[707]: verbose: New Socket.io Connection to 192.168.135.14 from 192.168.135.41 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Total Clients: 7 Jun 09 23:35:35 volumio volumio[707]: info: CoreCommandRouter::volumioGetState Jun 09 23:35:35 volumio volumio[707]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 09 23:35:35 volumio volumio[707]: info: Listing playlists Jun 09 23:35:35 volumio volumio[707]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 09 23:35:35 volumio volumio[707]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 09 23:35:35 volumio volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 09 23:35:35 volumio volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 09 23:35:35 volumio volumio[707]: info: CoreCommandRouter::volumioPlay Jun 09 23:35:35 volumio volumio[707]: info: CoreStateMachine::play index undefined Jun 09 23:35:36 volumio volumio[707]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 23:35:36 volumio volumio[707]: info: CorePlayQueue::getTrack 0 Jun 09 23:35:36 volumio volumio[707]: info: CoreCommandRouter::volumioGetState Jun 09 23:35:36 volumio volumio[707]: info: CorePlayQueue::getTrack 0 Jun 09 23:35:36 volumio volumio[707]: info: CoreCommandRouter::volumioPause Jun 09 23:35:36 volumio volumio[707]: info: CoreStateMachine::pause Jun 09 23:35:36 volumio volumio[707]: info: CoreStateMachine::stPlaybackTimer Jun 09 23:35:36 volumio volumio[707]: info: CoreStateMachine::servicePause Jun 09 23:35:36 volumio volumio[707]: info: CorePlayQueue::getTrack 0 Jun 09 23:35:36 volumio volumio[707]: info: CoreCommandRouter::servicePause Jun 09 23:35:36 volumio volumio[707]: verbose: ControllerMpd::sendMpdCommand pause Jun 09 23:35:36 volumio volumio[707]: info: sendMpdCommand pause took 8 milliseconds Jun 09 23:35:36 volumio volumio[707]: info: CorePlayQueue::getTrack 0 Jun 09 23:35:36 volumio volumio[707]: info: CoreCommandRouter::servicePushState Jun 09 23:35:36 volumio volumio[707]: info: CorePlayQueue::getTrack 0 Jun 09 23:35:36 volumio volumio[707]: verbose: STATE SERVICE {"status":"pause","seek":0} Jun 09 23:35:36 volumio volumio[707]: verbose: CURRENT POSITION 0 Jun 09 23:35:36 volumio volumio[707]: info: CoreStateMachine::syncState stateService pause Jun 09 23:35:36 volumio volumio[707]: info: CoreStateMachine::syncState currentStatus pause Jun 09 23:35:36 volumio volumio[707]: info: CoreStateMachine::pushState Jun 09 23:35:36 volumio volumio[707]: info: CorePlayQueue::getTrack 0 Jun 09 23:35:36 volumio volumio[707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 23:35:36 volumio volumio[707]: info: CoreCommandRouter::volumioPushState Jun 09 23:35:36 volumio volumio[707]: info: CoreStateMachine::stPlaybackTimer Jun 09 23:35:36 volumio volumio[707]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jun 09 23:35:37 volumio volumio[707]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 09 23:35:38 volumio volumio[707]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 09 23:35:40 volumio go-librespot[895]: time="2025-06-09T23:35:40Z" level=debug msg="fetched chunk 22/23, size: 524288" uri="spotify:track:32Du7kQYAlqVavv4enmRxK" Jun 09 23:35:44 volumio go-librespot[895]: time="2025-06-09T23:35:44Z" level=debug msg="handling pause player command from 105139777eec075c74b3e11114a6a28a777f762a" Jun 09 23:35:44 volumio go-librespot[895]: time="2025-06-09T23:35:44Z" level=debug msg="pause track at 235386ms" Jun 09 23:35:44 volumio go-librespot[895]: time="2025-06-09T23:35:44Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 09 23:35:44 volumio go-librespot[895]: time="2025-06-09T23:35:44Z" level=debug msg="sending successful reply for dealer request" Jun 09 23:35:44 volumio volumio[707]: info: CoreCommandRouter::volumioPlay Jun 09 23:35:44 volumio volumio[707]: info: CoreStateMachine::play index undefined Jun 09 23:35:44 volumio volumio[707]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 23:35:44 volumio volumio[707]: info: CorePlayQueue::getTrack 0 Jun 09 23:35:44 volumio volumio[707]: info: CoreStateMachine::startPlaybackTimer Jun 09 23:35:44 volumio volumio[707]: info: CorePlayQueue::getTrack 0 Jun 09 23:35:44 volumio volumio[707]: verbose: ControllerMpd::sendMpdCommand play Jun 09 23:35:44 volumio volumio[707]: info: sendMpdCommand play took 2 milliseconds Jun 09 23:35:44 volumio volumio[707]: info: CoreCommandRouter::servicePushState Jun 09 23:35:44 volumio volumio[707]: info: CorePlayQueue::getTrack 0 Jun 09 23:35:44 volumio volumio[707]: verbose: STATE SERVICE {"status":"play","seek":0} Jun 09 23:35:44 volumio volumio[707]: verbose: CURRENT POSITION 0 Jun 09 23:35:44 volumio volumio[707]: info: CoreStateMachine::syncState stateService play Jun 09 23:35:44 volumio volumio[707]: info: CoreStateMachine::syncState currentStatus pause Jun 09 23:35:44 volumio volumio[707]: info: CoreStateMachine::pushState Jun 09 23:35:44 volumio volumio[707]: info: CorePlayQueue::getTrack 0 Jun 09 23:35:44 volumio volumio[707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 23:35:44 volumio go-librespot[895]: time="2025-06-09T23:35:44Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 09 23:35:44 volumio go-librespot[895]: time="2025-06-09T23:35:44Z" level=trace msg="emitting websocket event: paused" Jun 09 23:35:44 volumio volumio[707]: info: CoreCommandRouter::volumioPushState Jun 09 23:35:44 volumio volumio[707]: info: CoreStateMachine::pushState Jun 09 23:35:44 volumio volumio[707]: info: CorePlayQueue::getTrack 0 Jun 09 23:35:44 volumio volumio[707]: info: CoreCommandRouter::volumioPushState Jun 09 23:35:44 volumio volumio[707]: info: [1749512144697] [RadioParadise] getMetadata started with url undefined Jun 09 23:35:44 volumio volumio[707]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:32Du7kQYAlqVavv4enmRxK","play_origin":"playlist"}} Jun 09 23:35:44 volumio volumio[707]: info: Spotify is playing in volatile mode Jun 09 23:35:44 volumio volumio[707]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 23:35:44 volumio volumio[707]: SPOTIFY: UNSET VOLATILE Jun 09 23:35:44 volumio volumio[707]: SPOTIFY: {"status":"pause","position":0,"title":"Radio Paradise Main Mix (FLAC)","artist":"","albumart":"/albumart?sourceicon=music_service/radio_paradise/rp-cover-black.png","uri":"https://stream.radioparadise.com/flac","trackType":"flac","seek":0,"duration":1000,"random":null,"repeat":true,"repeatSingle":false,"consume":false,"volume":55,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"flac","volatile":false,"service":"radio_paradise"} Jun 09 23:35:44 volumio volumio[707]: info: Setting Spotify stop after unset volatile call Jun 09 23:35:44 volumio volumio[707]: SPOTIFY: PUSH STATE SPOTIFY Jun 09 23:35:44 volumio volumio[707]: SPOTIFY: {"status":"pause","service":"spop","title":"Long Black Braid (Live)","artist":"The Mallett Brothers Band","album":"Live in Portland, Maine","albumart":"https://i.scdn.co/image/ab67616d00001e02a5ca9515027ec107412d7ce7","uri":"spotify:track:32Du7kQYAlqVavv4enmRxK","trackType":"spotify","seek":235000,"duration":286,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 09 23:35:44 volumio volumio[707]: info: CoreCommandRouter::servicePushState Jun 09 23:35:44 volumio volumio[707]: info: CoreStateMachine::pushState Jun 09 23:35:44 volumio volumio[707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 23:35:44 volumio volumio[707]: info: CoreCommandRouter::volumioPushState Jun 09 23:35:44 volumio volumio[707]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jun 09 23:35:44 volumio volumio[707]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jun 09 23:35:44 volumio volumio[707]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jun 09 23:35:44 volumio volumio[707]: info: [1749512144764] [RadioParadise] Error: connect ECONNREFUSED 127.0.0.1:443 Jun 09 23:35:44 volumio volumio[707]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 23:35:44 volumio volumio[707]: TypeError: Cannot read property 'toUpperCase' of undefined Jun 09 23:35:44 volumio volumio[707]: at ControllerRadioParadise.errorToast (/data/plugins/music_service/radio_paradise/index.js:481:41) Jun 09 23:35:44 volumio volumio[707]: at ClientRequest. (/data/plugins/music_service/radio_paradise/index.js:452:14) Jun 09 23:35:44 volumio volumio[707]: at ClientRequest.emit (events.js:315:20) Jun 09 23:35:44 volumio volumio[707]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Jun 09 23:35:44 volumio volumio[707]: at TLSSocket.emit (events.js:315:20) Jun 09 23:35:44 volumio volumio[707]: at emitErrorNT (internal/streams/destroy.js:106:8) Jun 09 23:35:44 volumio volumio[707]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Jun 09 23:35:44 volumio volumio[707]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 09 23:35:44 volumio volumio[707]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 23:35:45 volumio sudo[15091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-09 23:34 Jun 09 23:35:45 volumio sudo[15091]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="64c5f9b9f2d5771ec24b53a1040f7efcbf52f54c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 23 Oct 2022 07:49:36 PM CEST" VOLUMIO_VERSION="3.381" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6ea168664f080ecaaf13b9803250560d"