-- Logs begin at Wed 2026-10-14 22:11:12 IDT, end at Fri 2026-10-16 18:28:07 IDT. -- Oct 16 18:27:00 music volumio[973]: info: CoreCommandRouter::volumioGetState Oct 16 18:27:03 music go-librespot[1302]: time="2026-10-16T18:27:03+03:00" level=debug msg="fetched chunk 9/14, size: 524288" uri="spotify:track:4q604hQba5940BbOoaenmz" Oct 16 18:27:08 music volumio[973]: info: Oct 16 18:27:08 music volumio[973]: ---------------------------- Client requests Volumio pause Oct 16 18:27:08 music volumio[973]: info: CoreCommandRouter::volumioPause Oct 16 18:27:08 music volumio[973]: info: CoreStateMachine::pause Oct 16 18:27:08 music volumio[973]: info: CoreStateMachine::stPlaybackTimer Oct 16 18:27:08 music volumio[973]: info: CoreStateMachine::servicePause Oct 16 18:27:08 music volumio[973]: info: CoreCommandRouter::servicePause Oct 16 18:27:08 music volumio[973]: info: Spotify Received pause Oct 16 18:27:08 music volumio[973]: SPOTIFY: SPOTIFY PAUSE Oct 16 18:27:08 music volumio[973]: SPOTIFY: {"status":"play","title":"Blood In The Cut","artist":"K.Flay","album":"Every Where Is Some Where","albumart":"https://i.scdn.co/image/ab67616d00001e02c0920d3c7c26034b09f7be7a","uri":"spotify:track:4q604hQba5940BbOoaenmz","trackType":"spotify","codec":"ogg","seek":72734,"duration":188,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":42,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Oct 16 18:27:08 music volumio[973]: info: Sending Spotify command to local API: /player/pause Oct 16 18:27:08 music volumio[973]: info: CoreCommandRouter::volumioGetState Oct 16 18:27:08 music go-librespot[1302]: time="2026-10-16T18:27:08+03:00" level=debug msg="pause track at 85513ms" Oct 16 18:27:09 music go-librespot[1302]: time="2026-10-16T18:27:09+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 16 18:27:09 music go-librespot[1302]: time="2026-10-16T18:27:09+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 16 18:27:09 music go-librespot[1302]: time="2026-10-16T18:27:09+03:00" level=trace msg="emitting websocket event: paused" Oct 16 18:27:09 music volumio[973]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:user:spotify:playlist:37i9dQZF1F5p3rmiWPIYgZ","uri":"spotify:track:4q604hQba5940BbOoaenmz","play_origin":"com.spotify.gaia"}} Oct 16 18:27:09 music volumio[973]: SPOTIFY: PUSH STATE SPOTIFY Oct 16 18:27:09 music volumio[973]: SPOTIFY: {"status":"pause","service":"spop","title":"Blood In The Cut","artist":"K.Flay","album":"Every Where Is Some Where","albumart":"https://i.scdn.co/image/ab67616d00001e02c0920d3c7c26034b09f7be7a","uri":"spotify:track:4q604hQba5940BbOoaenmz","trackType":"spotify","seek":84734,"duration":188,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 16 18:27:09 music volumio[973]: info: CoreCommandRouter::servicePushState Oct 16 18:27:09 music volumio[973]: info: CoreStateMachine::pushState Oct 16 18:27:09 music volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:27:09 music volumio[973]: info: CoreCommandRouter::volumioPushState Oct 16 18:27:09 music volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 42 Oct 16 18:27:09 music kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Oct 16 18:27:09 music volumio[973]: info: camilladsp spawned new process with pid 23494, instance 1, run: true Oct 16 18:27:10 music volumio[973]: info: CoreCommandRouter::volumioGetState Oct 16 18:27:20 music volumio[973]: info: CoreCommandRouter::volumioGetState Oct 16 18:27:20 music volumio[973]: info: Listing playlists Oct 16 18:27:20 music volumio[973]: info: Listing playlists Oct 16 18:27:24 music go-librespot[1302]: time="2026-10-16T18:27:24+03:00" level=trace msg="sent dealer ping" Oct 16 18:27:24 music go-librespot[1302]: time="2026-10-16T18:27:24+03:00" level=trace msg="received dealer pong" Oct 16 18:27:30 music volumio[973]: info: CoreCommandRouter::volumioGetState Oct 16 18:27:40 music volumio[973]: info: CoreCommandRouter::volumioGetState Oct 16 18:27:40 music volumio[973]: info: Listing playlists Oct 16 18:27:40 music volumio[973]: info: Listing playlists Oct 16 18:27:50 music volumio[973]: info: CoreCommandRouter::volumioGetState Oct 16 18:27:54 music go-librespot[1302]: time="2026-10-16T18:27:54+03:00" level=trace msg="sent dealer ping" Oct 16 18:27:54 music go-librespot[1302]: time="2026-10-16T18:27:54+03:00" level=trace msg="received dealer pong" Oct 16 18:28:00 music volumio[973]: info: CoreCommandRouter::volumioGetState Oct 16 18:28:00 music volumio[973]: info: Listing playlists Oct 16 18:28:00 music volumio[973]: info: Listing playlists Oct 16 18:28:06 music volumio[973]: info: Oct 16 18:28:06 music volumio[973]: ---------------------------- Client requests Volumio play Oct 16 18:28:06 music volumio[973]: info: CoreCommandRouter::volumioPlay Oct 16 18:28:06 music volumio[973]: info: CoreStateMachine::play index undefined Oct 16 18:28:06 music volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:28:06 music volumio[973]: info: CorePlayQueue::getTrack 0 Oct 16 18:28:06 music volumio[973]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:28:06 music volumio[973]: info: CorePlayQueue::getTrack 0 Oct 16 18:28:06 music volumio[973]: verbose: ControllerMpd::sendMpdCommand play Oct 16 18:28:06 music volumio[973]: info: sendMpdCommand play took 13 milliseconds Oct 16 18:28:06 music volumio[973]: info: CoreCommandRouter::servicePushState Oct 16 18:28:06 music volumio[973]: info: CorePlayQueue::getTrack 0 Oct 16 18:28:06 music volumio[973]: verbose: STATE SERVICE {"status":"play"} Oct 16 18:28:06 music volumio[973]: verbose: CURRENT POSITION 0 Oct 16 18:28:06 music volumio[973]: info: CoreStateMachine::syncState stateService play Oct 16 18:28:06 music volumio[973]: info: CoreStateMachine::syncState currentStatus pause Oct 16 18:28:06 music volumio[973]: info: CoreStateMachine::pushState Oct 16 18:28:06 music volumio[973]: info: CorePlayQueue::getTrack 0 Oct 16 18:28:06 music volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:28:06 music volumio[973]: info: CoreCommandRouter::volumioPushState Oct 16 18:28:06 music volumio[973]: info: [1792164486677] [RadioParadise] getMetadata started with url undefined Oct 16 18:28:06 music volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 42 Oct 16 18:28:06 music volumio[973]: info: CoreCommandRouter::volumioGetState Oct 16 18:28:06 music volumio[973]: info: CorePlayQueue::getTrack 0 Oct 16 18:28:06 music volumio[973]: info: [1792164486706] [RadioParadise] Error: connect ECONNREFUSED 127.0.0.1:443 Oct 16 18:28:06 music volumio[973]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 18:28:06 music volumio[973]: TypeError: Cannot read property 'toUpperCase' of undefined Oct 16 18:28:06 music volumio[973]: at ControllerRadioParadise.errorToast (/data/plugins/music_service/radio_paradise/index.js:481:41) Oct 16 18:28:06 music volumio[973]: at ClientRequest. (/data/plugins/music_service/radio_paradise/index.js:452:14) Oct 16 18:28:06 music volumio[973]: at ClientRequest.emit (events.js:315:20) Oct 16 18:28:06 music volumio[973]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Oct 16 18:28:06 music volumio[973]: at TLSSocket.emit (events.js:315:20) Oct 16 18:28:06 music volumio[973]: at emitErrorNT (internal/streams/destroy.js:106:8) Oct 16 18:28:06 music volumio[973]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Oct 16 18:28:06 music volumio[973]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 16 18:28:06 music volumio[973]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 18:28:07 music sudo[23636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-16 18:27 Oct 16 18:28:07 music sudo[23636]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"