-- Logs begin at Thu 2025-04-24 16:49:56 EDT, end at Fri 2025-04-25 13:57:59 EDT. -- Apr 25 13:56:06 volumio-fl volumio[922]: info: CoreCommandRouter::volumioGetState Apr 25 13:56:16 volumio-fl volumio[922]: info: CoreCommandRouter::volumioGetState Apr 25 13:56:16 volumio-fl volumio[922]: info: Listing playlists Apr 25 13:56:16 volumio-fl volumio[922]: info: Listing playlists Apr 25 13:56:26 volumio-fl volumio[922]: info: CoreCommandRouter::volumioGetState Apr 25 13:56:36 volumio-fl volumio[922]: info: CoreCommandRouter::volumioGetState Apr 25 13:56:36 volumio-fl volumio[922]: info: Listing playlists Apr 25 13:56:36 volumio-fl volumio[922]: info: Listing playlists Apr 25 13:56:46 volumio-fl volumio[922]: info: CoreCommandRouter::volumioGetState Apr 25 13:56:56 volumio-fl volumio[922]: info: CoreCommandRouter::volumioGetState Apr 25 13:56:56 volumio-fl volumio[922]: info: Listing playlists Apr 25 13:56:56 volumio-fl volumio[922]: info: Listing playlists Apr 25 13:57:06 volumio-fl volumio[922]: info: CoreCommandRouter::volumioGetState Apr 25 13:57:16 volumio-fl volumio[922]: info: CoreCommandRouter::volumioGetState Apr 25 13:57:16 volumio-fl volumio[922]: info: Listing playlists Apr 25 13:57:16 volumio-fl volumio[922]: info: Listing playlists Apr 25 13:57:26 volumio-fl volumio[922]: info: CoreCommandRouter::volumioGetState Apr 25 13:57:36 volumio-fl volumio[922]: info: CoreCommandRouter::volumioGetState Apr 25 13:57:36 volumio-fl volumio[922]: info: Listing playlists Apr 25 13:57:36 volumio-fl volumio[922]: info: Listing playlists Apr 25 13:57:46 volumio-fl volumio[922]: info: CoreCommandRouter::volumioGetState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::volumioNext Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::next Apr 25 13:57:50 volumio-fl volumio[922]: info: [1745603870734] ControllerPandora::next Apr 25 13:57:50 volumio-fl volumio[922]: info: CorePlayQueue::getTrack 25 Apr 25 13:57:50 volumio-fl volumio[922]: info: [1745603870734] ControllerPandora::PandoraHandler::thumbsDownTrack Apr 25 13:57:50 volumio-fl volumio[922]: info: [1745603870737] [Pandora] PandoraHandler::thumbsDownTrack: Thumbs down delivered. Station: Alan Parsons Radio Track: Hitch a Ride Apr 25 13:57:50 volumio-fl volumio[922]: info: CorePlayQueue::getQueue Apr 25 13:57:50 volumio-fl volumio[922]: info: [1745603870739] [Pandora] handleMediaButton: User chose "next" function Apr 25 13:57:50 volumio-fl volumio[922]: info: [1745603870739] ControllerPandora::stop Apr 25 13:57:50 volumio-fl volumio[922]: info: ControllerMpd::stop Apr 25 13:57:50 volumio-fl volumio[922]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 13:57:50 volumio-fl volumio[922]: info: Apr 25 13:57:50 volumio-fl volumio[922]: ---------------------------- MPD announces state update: player Apr 25 13:57:50 volumio-fl volumio[922]: info: sendMpdCommand stop took 4 milliseconds Apr 25 13:57:50 volumio-fl volumio[922]: info: ControllerMpd::getState Apr 25 13:57:50 volumio-fl volumio[922]: verbose: ControllerMpd::sendMpdCommand status Apr 25 13:57:50 volumio-fl volumio[922]: info: [1745603870745] ControllerPandora::pushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::servicePushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::pushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::volumioPushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CorePlayQueue::getTrack 25 Apr 25 13:57:50 volumio-fl volumio[922]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Hitch a Ride","name":"Hitch a Ride","artist":"Boston","album":"Boston","albumart":"http://mediaserver-cont-sv5-1-v4v6.pandora.com/images/cc/35/97/3f/7d5c44eaab7e31bfa3e91dfe/1080W_1080H.jpg","realUri":"http://audio-dc6-t1-2-v4v6.pandora.com/access/5666202810235940109.mp3?version=5&lid=1812991750&token=fOkHzQrkXT2Q3CcbgAdG3MGLlAF8g1ass1g6WQVXDUh5qN6%2B5bVpfa6cHvPxD5O29TvJ%2BNpzoam8EeYoCmBTEEEnA6a6tg5t%2BXPx3vYnQzU0Cv9DwvtIRicAMsPMEJn6LwN9rgjs0xcVulYnWIEUsIMqhOUvZX7H771xvkafX18X1DJcdStpNkAExHK69%2BPomKnkuqn0MFtAH%2BCT0iXM%2Fx2iSefVPsF4L05gznqM0Mfs8cLY7%2FsO5Bh9BLvrqL5WmWgbpghacYrmsbxsc1YrUijOg1m4ji5s6IzzebAzbe82Nme0kkK1YfHP5D9E3Jn4F0refdFyC24VLp0U64Nemg%3D%3D","isStreaming":true,"duration":252,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://audio-dc6-t1-2-v4v6.pandora.com/access/5666202810235940109.mp3?version=5&lid=1812991750&token=fOkHzQrkXT2Q3CcbgAdG3MGLlAF8g1ass1g6WQVXDUh5qN6%2B5bVpfa6cHvPxD5O29TvJ%2BNpzoam8EeYoCmBTEEEnA6a6tg5t%2BXPx3vYnQzU0Cv9DwvtIRicAMsPMEJn6LwN9rgjs0xcVulYnWIEUsIMqhOUvZX7H771xvkafX18X1DJcdStpNkAExHK69%2BPomKnkuqn0MFtAH%2BCT0iXM%2Fx2iSefVPsF4L05gznqM0Mfs8cLY7%2FsO5Bh9BLvrqL5WmWgbpghacYrmsbxsc1YrUijOg1m4ji5s6IzzebAzbe82Nme0kkK1YfHP5D9E3Jn4F0refdFyC24VLp0U64Nemg%3D%3D","seek":227184,"status":"stop"} Apr 25 13:57:50 volumio-fl volumio[922]: verbose: CURRENT POSITION 25 Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::syncState stateService stop Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::syncState currentStatus pause Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::pushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::volumioPushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::setConsumeUpdateService pandora Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::removeQueueItem Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::stop Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::updateTrackBlock Apr 25 13:57:50 volumio-fl volumio[922]: info: CorePlayQueue::getTrackBlock Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::stPlaybackTimer Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::pushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CorePlayQueue::getTrack 25 Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::volumioPushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::serviceStop Apr 25 13:57:50 volumio-fl volumio[922]: info: CorePlayQueue::getTrack 25 Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::serviceStop Apr 25 13:57:50 volumio-fl volumio[922]: info: [1745603870756] ControllerPandora::stop Apr 25 13:57:50 volumio-fl volumio[922]: info: ControllerMpd::stop Apr 25 13:57:50 volumio-fl volumio[922]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 13:57:50 volumio-fl volumio[922]: info: CorePlayQueue::removeQueueItem 25 Apr 25 13:57:50 volumio-fl volumio[922]: info: CorePlayQueue::saveQueue Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::volumioPushQueue Apr 25 13:57:50 volumio-fl volumio[922]: info: Apr 25 13:57:50 volumio-fl volumio[922]: ---------------------------- MPD announces state update: player Apr 25 13:57:50 volumio-fl volumio[922]: info: ControllerMpd::getState Apr 25 13:57:50 volumio-fl volumio[922]: verbose: ControllerMpd::sendMpdCommand status Apr 25 13:57:50 volumio-fl volumio[922]: info: Apr 25 13:57:50 volumio-fl volumio[922]: ---------------------------- MPD announces state update: player Apr 25 13:57:50 volumio-fl volumio[922]: info: ControllerMpd::getState Apr 25 13:57:50 volumio-fl volumio[922]: verbose: ControllerMpd::sendMpdCommand status Apr 25 13:57:50 volumio-fl volumio[922]: info: sendMpdCommand status took 25 milliseconds Apr 25 13:57:50 volumio-fl volumio[922]: info: sendMpdCommand stop took 14 milliseconds Apr 25 13:57:50 volumio-fl volumio[922]: info: sendMpdCommand status took 3 milliseconds Apr 25 13:57:50 volumio-fl volumio[922]: info: sendMpdCommand status took 2 milliseconds Apr 25 13:57:50 volumio-fl volumio[922]: verbose: ControllerMpd::parseState Apr 25 13:57:50 volumio-fl volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 13:57:50 volumio-fl volumio[922]: info: [1745603870771] ControllerPandora::pushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::servicePushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::pushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CorePlayQueue::getTrack 25 Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::volumioPushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CorePlayQueue::getTrack 25 Apr 25 13:57:50 volumio-fl volumio[922]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Hitch a Ride","name":"Hitch a Ride","artist":"Boston","album":"Boston","albumart":"http://mediaserver-cont-sv5-1-v4v6.pandora.com/images/cc/35/97/3f/7d5c44eaab7e31bfa3e91dfe/1080W_1080H.jpg","realUri":"http://audio-dc6-t1-2-v4v6.pandora.com/access/5666202810235940109.mp3?version=5&lid=1812991750&token=fOkHzQrkXT2Q3CcbgAdG3MGLlAF8g1ass1g6WQVXDUh5qN6%2B5bVpfa6cHvPxD5O29TvJ%2BNpzoam8EeYoCmBTEEEnA6a6tg5t%2BXPx3vYnQzU0Cv9DwvtIRicAMsPMEJn6LwN9rgjs0xcVulYnWIEUsIMqhOUvZX7H771xvkafX18X1DJcdStpNkAExHK69%2BPomKnkuqn0MFtAH%2BCT0iXM%2Fx2iSefVPsF4L05gznqM0Mfs8cLY7%2FsO5Bh9BLvrqL5WmWgbpghacYrmsbxsc1YrUijOg1m4ji5s6IzzebAzbe82Nme0kkK1YfHP5D9E3Jn4F0refdFyC24VLp0U64Nemg%3D%3D","isStreaming":true,"duration":252,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://audio-dc6-t1-2-v4v6.pandora.com/access/5666202810235940109.mp3?version=5&lid=1812991750&token=fOkHzQrkXT2Q3CcbgAdG3MGLlAF8g1ass1g6WQVXDUh5qN6%2B5bVpfa6cHvPxD5O29TvJ%2BNpzoam8EeYoCmBTEEEnA6a6tg5t%2BXPx3vYnQzU0Cv9DwvtIRicAMsPMEJn6LwN9rgjs0xcVulYnWIEUsIMqhOUvZX7H771xvkafX18X1DJcdStpNkAExHK69%2BPomKnkuqn0MFtAH%2BCT0iXM%2Fx2iSefVPsF4L05gznqM0Mfs8cLY7%2FsO5Bh9BLvrqL5WmWgbpghacYrmsbxsc1YrUijOg1m4ji5s6IzzebAzbe82Nme0kkK1YfHP5D9E3Jn4F0refdFyC24VLp0U64Nemg%3D%3D","seek":227184,"status":"stop"} Apr 25 13:57:50 volumio-fl volumio[922]: verbose: CURRENT POSITION 25 Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::syncState stateService stop Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::syncState currentStatus stop Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::pushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CorePlayQueue::getTrack 25 Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::volumioPushState Apr 25 13:57:50 volumio-fl volumio[922]: info: No code Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::pushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CorePlayQueue::getTrack 25 Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::volumioPushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::setConsumeUpdateService pandora Apr 25 13:57:50 volumio-fl volumio[922]: verbose: ControllerMpd::parseState Apr 25 13:57:50 volumio-fl volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 13:57:50 volumio-fl volumio[922]: verbose: ControllerMpd::parseState Apr 25 13:57:50 volumio-fl volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 13:57:50 volumio-fl volumio[922]: error: Upnp client error: Error: This socket has been ended by the other party Apr 25 13:57:50 volumio-fl volumio[922]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 25 13:57:50 volumio-fl volumio[922]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 25 13:57:50 volumio-fl volumio[922]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 25 13:57:50 volumio-fl volumio[922]: verbose: ControllerMpd::parseTrackInfo Apr 25 13:57:50 volumio-fl volumio[922]: verbose: ControllerMpd::parseTrackInfo Apr 25 13:57:50 volumio-fl volumio[922]: verbose: ControllerMpd::parseTrackInfo Apr 25 13:57:50 volumio-fl volumio[922]: info: ControllerMpd::pushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::servicePushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::pushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::volumioPushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CorePlayQueue::getTrack 25 Apr 25 13:57:50 volumio-fl volumio[922]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Apr 25 13:57:50 volumio-fl volumio[922]: info: ControllerMpd::pushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::servicePushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::pushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::volumioPushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CorePlayQueue::getTrack 25 Apr 25 13:57:50 volumio-fl volumio[922]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Apr 25 13:57:50 volumio-fl volumio[922]: info: ControllerMpd::pushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::servicePushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreStateMachine::pushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CoreCommandRouter::volumioPushState Apr 25 13:57:50 volumio-fl volumio[922]: info: CorePlayQueue::getTrack 25 Apr 25 13:57:50 volumio-fl volumio[922]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Apr 25 13:57:50 volumio-fl volumio[922]: info: ------------------------------ 57ms Apr 25 13:57:50 volumio-fl volumio[922]: info: ------------------------------ 35ms Apr 25 13:57:50 volumio-fl volumio[922]: info: ------------------------------ 34ms Apr 25 13:57:56 volumio-fl volumio[922]: info: CoreCommandRouter::volumioGetState Apr 25 13:57:56 volumio-fl volumio[922]: info: Listing playlists Apr 25 13:57:56 volumio-fl volumio[922]: info: Listing playlists Apr 25 13:57:58 volumio-fl volumio[922]: info: CoreCommandRouter::volumioNext Apr 25 13:57:58 volumio-fl volumio[922]: info: CoreStateMachine::next Apr 25 13:57:58 volumio-fl volumio[922]: info: [1745603878219] ControllerPandora::next Apr 25 13:57:58 volumio-fl volumio[922]: info: CorePlayQueue::getTrack 25 Apr 25 13:57:58 volumio-fl volumio[922]: info: [1745603878219] ControllerPandora::PandoraHandler::thumbsDownTrack Apr 25 13:57:58 volumio-fl volumio[922]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 13:57:58 volumio-fl volumio[922]: TypeError: Cannot read property 'service' of undefined Apr 25 13:57:58 volumio-fl volumio[922]: at PandoraHandler.thumbsDownTrack (/data/plugins/music_service/pandora/pandora_handler.js:415:15) Apr 25 13:57:58 volumio-fl volumio[922]: at ControllerPandora.next (/data/plugins/music_service/pandora/index.js:1010:29) Apr 25 13:57:58 volumio-fl volumio[922]: at CoreStateMachine.next (/volumio/app/statemachine.js:1169:20) Apr 25 13:57:58 volumio-fl volumio[922]: at CoreCommandRouter.volumioNext (/volumio/app/index.js:103:28) Apr 25 13:57:58 volumio-fl volumio[922]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:274:26) Apr 25 13:57:58 volumio-fl volumio[922]: at Socket.emit (events.js:315:20) Apr 25 13:57:58 volumio-fl volumio[922]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 25 13:57:58 volumio-fl volumio[922]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 25 13:57:58 volumio-fl volumio[922]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 13:57:59 volumio-fl sudo[12679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-25 13:56 Apr 25 13:57:59 volumio-fl sudo[12679]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"