-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2025-12-15 11:24:15 CET. -- Dec 15 11:23:00 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 15 11:23:03 volumio go-librespot[14681]: time="2025-12-15T11:23:03+01:00" level=debug msg="fetched chunk 14/14, size: 310320" uri="spotify:track:0Sz65xfu0L3HK8M1bUkTay" Dec 15 11:23:04 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 15 11:23:08 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 15 11:23:12 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 15 11:23:16 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 15 11:23:16 volumio volumio[909]: info: CALLMETHOD: miscellanea my_music updateMusicLibraryBrowseSourcesVisibility [object Object] Dec 15 11:23:16 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibraryBrowseSourcesVisibility Dec 15 11:23:16 volumio volumio[909]: info: CoreCommandRouter::volumioUpdateToBrowseSources Dec 15 11:23:16 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 11:23:16 volumio volumio[909]: Cannot find translation for source 80s80s Radio Dec 15 11:23:18 volumio go-librespot[14681]: time="2025-12-15T11:23:18+01:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:list:play-history:v1/resume-point-revision/75d506fe-1ffd-4c56-a0cd-03c96c79db2f" Dec 15 11:23:18 volumio go-librespot[14681]: time="2025-12-15T11:23:18+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 306" Dec 15 11:23:19 volumio volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 15 11:23:19 volumio volumio[909]: info: CoreStateMachine::getQueue Dec 15 11:23:19 volumio volumio[909]: info: CorePlayQueue::getQueue Dec 15 11:23:23 volumio volumio[909]: info: CoreCommandRouter::volumioClearQueue Dec 15 11:23:23 volumio volumio[909]: info: CoreStateMachine::ClearQueue Dec 15 11:23:23 volumio volumio[909]: info: CoreStateMachine::stop Dec 15 11:23:23 volumio volumio[909]: info: CoreStateMachine::serviceStop Dec 15 11:23:23 volumio volumio[909]: info: CoreCommandRouter::serviceStop Dec 15 11:23:23 volumio volumio[909]: info: Spotify Stop Dec 15 11:23:23 volumio volumio[909]: SPOTIFY: SPOTIFY STOP Dec 15 11:23:23 volumio volumio[909]: SPOTIFY: {"status":"play","title":"It Is Wednesday My Dudes","artist":"RetroMoon","album":"It Is Wednesday My Dudes","albumart":"https://i.scdn.co/image/ab67616d00001e02abe028a31c5a75e501387555","uri":"spotify:track:0Sz65xfu0L3HK8M1bUkTay","trackType":"spotify","codec":"ogg","seek":99862,"duration":185,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":53,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Dec 15 11:23:23 volumio volumio[909]: info: Sending Spotify command to local API: /player/pause Dec 15 11:23:23 volumio volumio[909]: info: CorePlayQueue::clearPlayQueue Dec 15 11:23:23 volumio volumio[909]: info: CorePlayQueue::saveQueue Dec 15 11:23:23 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:23:23 volumio volumio[909]: info: CoreCommandRouter::volumioPushQueue Dec 15 11:23:23 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:23:23 volumio go-librespot[14681]: time="2025-12-15T11:23:23+01:00" level=debug msg="pause track at 156565ms" Dec 15 11:23:23 volumio go-librespot[14681]: time="2025-12-15T11:23:23+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 15 11:23:23 volumio go-librespot[14681]: time="2025-12-15T11:23:23+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 15 11:23:23 volumio go-librespot[14681]: time="2025-12-15T11:23:23+01:00" level=trace msg="emitting websocket event: paused" Dec 15 11:23:23 volumio volumio[909]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:search:IT+IS+Wednesday","uri":"spotify:track:0Sz65xfu0L3HK8M1bUkTay","play_origin":"search"}} Dec 15 11:23:23 volumio volumio[909]: info: Spotify is playing in volatile mode Dec 15 11:23:23 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 11:23:23 volumio volumio[909]: SPOTIFY: UNSET VOLATILE Dec 15 11:23:23 volumio volumio[909]: SPOTIFY: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":53,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":null,"repeat":null,"repeatSingle":false,"updatedb":false,"consume":false} Dec 15 11:23:23 volumio volumio[909]: SPOTIFY: PUSH STATE SPOTIFY Dec 15 11:23:23 volumio volumio[909]: SPOTIFY: {"status":"pause","service":"spop","title":"It Is Wednesday My Dudes","artist":"RetroMoon","album":"It Is Wednesday My Dudes","albumart":"https://i.scdn.co/image/ab67616d00001e02abe028a31c5a75e501387555","uri":"spotify:track:0Sz65xfu0L3HK8M1bUkTay","trackType":"spotify","seek":156862,"duration":185,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 15 11:23:23 volumio volumio[909]: info: CoreCommandRouter::servicePushState Dec 15 11:23:23 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:23:23 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 11:23:23 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:23:23 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:23:23 volumio volumio[909]: info: CoreCommandRouter::volumioClearQueue Dec 15 11:23:23 volumio volumio[909]: info: CoreStateMachine::ClearQueue Dec 15 11:23:23 volumio volumio[909]: info: CoreStateMachine::stop Dec 15 11:23:23 volumio volumio[909]: info: CoreStateMachine::serviceStop Dec 15 11:23:23 volumio volumio[909]: info: CoreCommandRouter::serviceStop Dec 15 11:23:23 volumio volumio[909]: info: Spotify Stop Dec 15 11:23:23 volumio volumio[909]: SPOTIFY: SPOTIFY STOP Dec 15 11:23:23 volumio volumio[909]: SPOTIFY: {"status":"pause","title":"It Is Wednesday My Dudes","artist":"RetroMoon","album":"It Is Wednesday My Dudes","albumart":"https://i.scdn.co/image/ab67616d00001e02abe028a31c5a75e501387555","uri":"spotify:track:0Sz65xfu0L3HK8M1bUkTay","trackType":"spotify","codec":"ogg","seek":156862,"duration":185,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":53,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Dec 15 11:23:23 volumio volumio[909]: info: CorePlayQueue::clearPlayQueue Dec 15 11:23:23 volumio volumio[909]: info: CorePlayQueue::saveQueue Dec 15 11:23:23 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:23:23 volumio volumio[909]: info: CoreCommandRouter::volumioPushQueue Dec 15 11:23:23 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:23:24 volumio volumio[909]: info: CoreCommandRouter::volumioClearQueue Dec 15 11:23:24 volumio volumio[909]: info: CoreStateMachine::ClearQueue Dec 15 11:23:24 volumio volumio[909]: info: CoreStateMachine::stop Dec 15 11:23:24 volumio volumio[909]: info: CoreStateMachine::serviceStop Dec 15 11:23:24 volumio volumio[909]: info: CoreCommandRouter::serviceStop Dec 15 11:23:24 volumio volumio[909]: info: Spotify Stop Dec 15 11:23:24 volumio volumio[909]: SPOTIFY: SPOTIFY STOP Dec 15 11:23:24 volumio volumio[909]: SPOTIFY: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":53,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":null,"repeat":null,"repeatSingle":false,"updatedb":false,"consume":false} Dec 15 11:23:24 volumio volumio[909]: info: CorePlayQueue::clearPlayQueue Dec 15 11:23:24 volumio volumio[909]: info: CorePlayQueue::saveQueue Dec 15 11:23:24 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:23:24 volumio volumio[909]: info: CoreCommandRouter::volumioPushQueue Dec 15 11:23:24 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:23:27 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 15 11:23:27 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 15 11:23:27 volumio sudo[15229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 11:23:27 volumio sudo[15229]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 15 11:23:27 volumio sudo[15229]: pam_unix(sudo:session): session closed for user root Dec 15 11:23:27 volumio sudo[15232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 11:23:27 volumio sudo[15232]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 15 11:23:27 volumio sudo[15232]: pam_unix(sudo:session): session closed for user root Dec 15 11:23:27 volumio volumio[909]: verbose: New Socket.io Connection to 10.88.148.202 from 10.88.148.242 UA: Mozilla/5.0 (Linux; Android 16; ASUSAI2501C Build/BQ2A.250525.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 15 11:23:27 volumio sudo[15235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 11:23:27 volumio sudo[15235]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 15 11:23:27 volumio sudo[15235]: pam_unix(sudo:session): session closed for user root Dec 15 11:23:27 volumio sudo[15237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 11:23:27 volumio sudo[15237]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 15 11:23:27 volumio sudo[15237]: pam_unix(sudo:session): session closed for user root Dec 15 11:23:27 volumio volumio[909]: verbose: New Socket.io Connection to 10.88.148.202 from 10.88.148.242 UA: Mozilla/5.0 (Linux; Android 16; ASUSAI2501C Build/BQ2A.250525.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 15 11:23:27 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 15 11:23:27 volumio volumio[909]: info: CoreCommandRouter::volumioGetVisibleSources Dec 15 11:23:27 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 11:23:27 volumio volumio[909]: info: CoreCommandRouter::volumioGetState Dec 15 11:23:27 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 15 11:23:27 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 15 11:23:27 volumio volumio[909]: info: Received Get System Info Dec 15 11:23:27 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 11:23:27 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 11:23:27 volumio volumio[909]: info: Discovery: Getting this device information Dec 15 11:23:27 volumio volumio[909]: info: CoreCommandRouter::volumioGetState Dec 15 11:23:27 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 11:23:27 volumio volumio[909]: info: CoreCommandRouter::volumioGetState Dec 15 11:23:27 volumio volumio[909]: info: Listing playlists Dec 15 11:23:27 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 15 11:23:27 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 15 11:23:27 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 15 11:23:28 volumio go-librespot[14681]: time="2025-12-15T11:23:28+01:00" level=trace msg="sent dealer ping" Dec 15 11:23:28 volumio go-librespot[14681]: time="2025-12-15T11:23:28+01:00" level=trace msg="received dealer pong" Dec 15 11:23:29 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 15 11:23:29 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 15 11:23:29 volumio volumio[909]: info: Received Get System Info Dec 15 11:23:29 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 11:23:29 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 11:23:29 volumio volumio[909]: info: Discovery: Getting this device information Dec 15 11:23:29 volumio volumio[909]: info: CoreCommandRouter::volumioGetState Dec 15 11:23:29 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 11:23:30 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 15 11:23:30 volumio volumio[909]: info: Received Get System Info Dec 15 11:23:30 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 11:23:30 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 11:23:30 volumio volumio[909]: info: Discovery: Getting this device information Dec 15 11:23:30 volumio volumio[909]: info: CoreCommandRouter::volumioGetState Dec 15 11:23:30 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 11:23:37 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 15 11:23:39 volumio volumio[909]: info: CoreCommandRouter::volumioSeek Dec 15 11:23:39 volumio volumio[909]: info: CoreStateMachine::seek Dec 15 11:23:39 volumio volumio[909]: info: Spotify seek to: 65000 Dec 15 11:23:39 volumio volumio[909]: info: Sending Spotify command with payload to local API: /player/seek Dec 15 11:23:39 volumio volumio[909]: info: CoreStateMachine::startPlaybackTimer Dec 15 11:23:39 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Dec 15 11:23:39 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:23:39 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:23:39 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:23:39 volumio go-librespot[14681]: time="2025-12-15T11:23:39+01:00" level=debug msg="seek track to 65000ms" Dec 15 11:23:39 volumio go-librespot[14681]: time="2025-12-15T11:23:39+01:00" level=trace msg="seek to 65000ms (diff: 93ms, samples: 2866500, bytes: 2212642)" uri="spotify:track:0Sz65xfu0L3HK8M1bUkTay" Dec 15 11:23:40 volumio go-librespot[14681]: time="2025-12-15T11:23:40+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 15 11:23:40 volumio go-librespot[14681]: time="2025-12-15T11:23:40+01:00" level=trace msg="emitting websocket event: seek" Dec 15 11:23:40 volumio volumio[909]: SPOTIFY: received: {"type":"seek","data":{"context_uri":"spotify:search:IT+IS+Wednesday","uri":"spotify:track:0Sz65xfu0L3HK8M1bUkTay","position":65000,"duration":185250,"play_origin":"search"}} Dec 15 11:23:40 volumio volumio[909]: SPOTIFY: PUSH STATE SPOTIFY Dec 15 11:23:40 volumio volumio[909]: SPOTIFY: {"status":"pause","service":"spop","title":"It Is Wednesday My Dudes","artist":"RetroMoon","album":"It Is Wednesday My Dudes","albumart":"https://i.scdn.co/image/ab67616d00001e02abe028a31c5a75e501387555","uri":"spotify:track:0Sz65xfu0L3HK8M1bUkTay","trackType":"spotify","seek":65000,"duration":185,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 15 11:23:40 volumio volumio[909]: info: CoreCommandRouter::servicePushState Dec 15 11:23:40 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:23:40 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 11:23:40 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:23:40 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:23:42 volumio volumio[909]: info: CoreCommandRouter::volumioVolatilePlay Dec 15 11:23:42 volumio volumio[909]: info: CoreStateMachine::volatilePlay Dec 15 11:23:42 volumio volumio[909]: info: Spotify Play Dec 15 11:23:42 volumio volumio[909]: info: Sending Spotify command to local API: /player/resume Dec 15 11:23:42 volumio go-librespot[14681]: time="2025-12-15T11:23:42+01:00" level=trace msg="seek to 65000ms (diff: 93ms, samples: 2866500, bytes: 2212642)" uri="spotify:track:0Sz65xfu0L3HK8M1bUkTay" Dec 15 11:23:42 volumio go-librespot[14681]: time="2025-12-15T11:23:42+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Dec 15 11:23:42 volumio go-librespot[14681]: time="2025-12-15T11:23:42+01:00" level=debug msg="resume track at 64907ms" Dec 15 11:23:42 volumio go-librespot[14681]: time="2025-12-15T11:23:42+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 15 11:23:42 volumio go-librespot[14681]: time="2025-12-15T11:23:42+01:00" level=trace msg="scheduling prefetch in 90s" Dec 15 11:23:42 volumio go-librespot[14681]: time="2025-12-15T11:23:42+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 15 11:23:42 volumio go-librespot[14681]: time="2025-12-15T11:23:42+01:00" level=trace msg="emitting websocket event: playing" Dec 15 11:23:42 volumio volumio[909]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:search:IT+IS+Wednesday","uri":"spotify:track:0Sz65xfu0L3HK8M1bUkTay","resume":true,"play_origin":"search"}} Dec 15 11:23:42 volumio volumio[909]: SPOTIFY: PUSH STATE SPOTIFY Dec 15 11:23:42 volumio volumio[909]: SPOTIFY: {"status":"play","service":"spop","title":"It Is Wednesday My Dudes","artist":"RetroMoon","album":"It Is Wednesday My Dudes","albumart":"https://i.scdn.co/image/ab67616d00001e02abe028a31c5a75e501387555","uri":"spotify:track:0Sz65xfu0L3HK8M1bUkTay","trackType":"spotify","seek":65000,"duration":185,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 15 11:23:42 volumio volumio[909]: info: CoreCommandRouter::servicePushState Dec 15 11:23:42 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:23:42 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 11:23:42 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:23:42 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:23:42 volumio volumio[909]: SPOTIFY: PUSH STATE SPOTIFY Dec 15 11:23:42 volumio volumio[909]: SPOTIFY: {"status":"play","service":"spop","title":"It Is Wednesday My Dudes","artist":"RetroMoon","album":"It Is Wednesday My Dudes","albumart":"https://i.scdn.co/image/ab67616d00001e02abe028a31c5a75e501387555","uri":"spotify:track:0Sz65xfu0L3HK8M1bUkTay","trackType":"spotify","seek":65000,"duration":185,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 15 11:23:42 volumio volumio[909]: info: CoreCommandRouter::servicePushState Dec 15 11:23:42 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:23:42 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:23:42 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:23:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 15 11:23:55 volumio volumio[909]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 15 11:23:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 15 11:23:55 volumio volumio[909]: info: Received Get System Version Dec 15 11:23:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 11:23:55 volumio volumio[909]: info: Received Get System Info Dec 15 11:23:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 11:23:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 11:23:55 volumio volumio[909]: info: Discovery: Getting this device information Dec 15 11:23:55 volumio volumio[909]: info: CoreCommandRouter::volumioGetState Dec 15 11:23:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 11:23:58 volumio go-librespot[14681]: time="2025-12-15T11:23:58+01:00" level=trace msg="sent dealer ping" Dec 15 11:23:58 volumio go-librespot[14681]: time="2025-12-15T11:23:58+01:00" level=trace msg="received dealer pong" Dec 15 11:24:00 volumio volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 15 11:24:00 volumio volumio[909]: info: CoreStateMachine::getQueue Dec 15 11:24:00 volumio volumio[909]: info: CorePlayQueue::getQueue Dec 15 11:24:02 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Dec 15 11:24:02 volumio volumio[909]: info: [1765794242950] [80s80s] handleBrowseUri curUri: 80s80s Dec 15 11:24:03 volumio volumio[909]: info: Preload queue cleared Dec 15 11:24:04 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Dec 15 11:24:04 volumio volumio[909]: info: [1765794244142] [80s80s] handleBrowseUri curUri: 80s80s/eighties Dec 15 11:24:04 volumio volumio[909]: info: [1765794244143] [80s80s] getRadioContent url: eighties Dec 15 11:24:04 volumio volumio[909]: info: Preload queue cleared Dec 15 11:24:06 volumio volumio[909]: info: Preload queue cleared Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::ClearQueue Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::stop Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::serviceStop Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::serviceStop Dec 15 11:24:06 volumio volumio[909]: info: Spotify Stop Dec 15 11:24:06 volumio volumio[909]: SPOTIFY: SPOTIFY STOP Dec 15 11:24:06 volumio volumio[909]: SPOTIFY: {"status":"play","title":"It Is Wednesday My Dudes","artist":"RetroMoon","album":"It Is Wednesday My Dudes","albumart":"https://i.scdn.co/image/ab67616d00001e02abe028a31c5a75e501387555","uri":"spotify:track:0Sz65xfu0L3HK8M1bUkTay","trackType":"spotify","codec":"ogg","seek":65000,"duration":185,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":53,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Dec 15 11:24:06 volumio volumio[909]: info: Sending Spotify command to local API: /player/pause Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::clearPlayQueue Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::saveQueue Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::volumioPushQueue Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::addQueueItems Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::addQueueItems Dec 15 11:24:06 volumio volumio[909]: info: Preload queue cleared Dec 15 11:24:06 volumio volumio[909]: info: Adding Item to queue: webeighties/1 Dec 15 11:24:06 volumio volumio[909]: info: Exploding uri webeighties/1 in service 80s80s Dec 15 11:24:06 volumio volumio[909]: info: [1765794246702] [80s80s] explodeUri: webeighties/1 Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::volumioPushQueue Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::saveQueue Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::updateTrackBlock Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::getTrackBlock Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::volumioPlay Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::play index 0 Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::stop Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::stPlaybackTimer Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::updateTrackBlock Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::getTrackBlock Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::serviceStop Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::serviceStop Dec 15 11:24:06 volumio volumio[909]: info: ControllerMpd::stop Dec 15 11:24:06 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand stop Dec 15 11:24:06 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:24:06 volumio volumio[909]: info: sendMpdCommand stop took 9 milliseconds Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::servicePushState Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Dec 15 11:24:06 volumio volumio[909]: verbose: STATE SERVICE {"status":"stop"} Dec 15 11:24:06 volumio volumio[909]: verbose: CURRENT POSITION 0 Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::syncState stateService stop Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus stop Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:24:06 volumio volumio[909]: info: No code Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::play index undefined Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::startPlaybackTimer Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Dec 15 11:24:06 volumio volumio[909]: info: [1765794246750] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/ndw/mp3-192/volumio Dec 15 11:24:06 volumio volumio[909]: info: [1765794246751] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=137&count=2 Dec 15 11:24:06 volumio go-librespot[14681]: time="2025-12-15T11:24:06+01:00" level=debug msg="pause track at 89389ms" Dec 15 11:24:06 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:24:06 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:24:06 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:24:06 volumio go-librespot[14681]: time="2025-12-15T11:24:06+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 15 11:24:06 volumio volumio[909]: info: [1765794246951] [80s80s] received new event containing 2 songs. Dec 15 11:24:06 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand stop Dec 15 11:24:06 volumio volumio[909]: info: sendMpdCommand stop took 1 milliseconds Dec 15 11:24:06 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand clear Dec 15 11:24:06 volumio volumio[909]: info: Dec 15 11:24:06 volumio volumio[909]: ---------------------------- MPD announces system playlist update Dec 15 11:24:06 volumio volumio[909]: info: Ignoring MPD Status Update Dec 15 11:24:06 volumio volumio[909]: info: sendMpdCommand clear took 2 milliseconds Dec 15 11:24:06 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 15 11:24:06 volumio volumio[909]: info: Dec 15 11:24:06 volumio volumio[909]: ---------------------------- MPD announces system playlist update Dec 15 11:24:06 volumio volumio[909]: info: Ignoring MPD Status Update Dec 15 11:24:06 volumio volumio[909]: error: updateQueue error: null Dec 15 11:24:06 volumio volumio[909]: info: Dec 15 11:24:06 volumio volumio[909]: ---------------------------- MPD announces state update: options Dec 15 11:24:06 volumio volumio[909]: error: updateQueue error: null Dec 15 11:24:06 volumio volumio[909]: info: ------------------------------ 4ms Dec 15 11:24:06 volumio volumio[909]: info: sendMpdCommand consume 1 took 3 milliseconds Dec 15 11:24:06 volumio volumio[909]: info: ControllerMpd::getState Dec 15 11:24:06 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 15 11:24:06 volumio volumio[909]: info: ------------------------------ 3ms Dec 15 11:24:06 volumio volumio[909]: info: [1765794246961] [80s80s] adding url: http://streams.80s80s.de/ndw/mp3-192/volumio Dec 15 11:24:06 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/ndw/mp3-192/volumio" Dec 15 11:24:06 volumio volumio[909]: info: Dec 15 11:24:06 volumio volumio[909]: ---------------------------- MPD announces state update: options Dec 15 11:24:06 volumio volumio[909]: info: ControllerMpd::getState Dec 15 11:24:06 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 15 11:24:06 volumio volumio[909]: info: Dec 15 11:24:06 volumio volumio[909]: ---------------------------- MPD announces system playlist update Dec 15 11:24:06 volumio volumio[909]: info: Ignoring MPD Status Update Dec 15 11:24:06 volumio volumio[909]: info: sendMpdCommand status took 3 milliseconds Dec 15 11:24:06 volumio volumio[909]: info: sendMpdCommand add "http://streams.80s80s.de/ndw/mp3-192/volumio" took 3 milliseconds Dec 15 11:24:06 volumio volumio[909]: info: sendMpdCommand status took 2 milliseconds Dec 15 11:24:06 volumio volumio[909]: verbose: ControllerMpd::parseState Dec 15 11:24:06 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand play Dec 15 11:24:06 volumio volumio[909]: verbose: ControllerMpd::parseState Dec 15 11:24:06 volumio volumio[909]: info: ControllerMpd::pushState Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::servicePushState Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Dec 15 11:24:06 volumio volumio[909]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 15 11:24:06 volumio volumio[909]: info: ControllerMpd::pushState Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::servicePushState Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Dec 15 11:24:06 volumio volumio[909]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 15 11:24:06 volumio volumio[909]: info: ------------------------------ 20ms Dec 15 11:24:06 volumio volumio[909]: info: ------------------------------ 25ms Dec 15 11:24:06 volumio volumio[909]: info: Dec 15 11:24:06 volumio volumio[909]: ---------------------------- MPD announces system playlist update Dec 15 11:24:06 volumio volumio[909]: info: Ignoring MPD Status Update Dec 15 11:24:06 volumio volumio[909]: info: ------------------------------ 26ms Dec 15 11:24:06 volumio volumio[909]: info: sendMpdCommand play took 21 milliseconds Dec 15 11:24:06 volumio volumio[909]: info: ------------------------------ 3ms Dec 15 11:24:06 volumio volumio[909]: info: [1765794246991] [80s80s] Pushing the next song state: Steinwolke - Katharine, Katharine Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Dec 15 11:24:06 volumio volumio[909]: info: CoreCommandRouter::servicePushState Dec 15 11:24:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Dec 15 11:24:06 volumio volumio[909]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music114/v4/37/99/e5/3799e51f-042a-b4b8-fa28-57fa307773ec/source/600x600bb.jpg","name":"Steinwolke - Katharine, Katharine","title":"Katharine, Katharine","artist":"80s80s NDW","album":"","streaming":true,"disableUiControls":true,"duration":"191","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 15 11:24:06 volumio volumio[909]: verbose: CURRENT POSITION 0 Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Dec 15 11:24:06 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus stop Dec 15 11:24:06 volumio volumio[909]: info: [1765794246993] [80s80s] PlayNextTrack API delay: 30 Dec 15 11:24:07 volumio volumio[909]: info: [1765794247005] [80s80s] Setting timer to: 8007 milliseconds. Dec 15 11:24:07 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:24:07 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Dec 15 11:24:07 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Dec 15 11:24:07 volumio go-librespot[14681]: time="2025-12-15T11:24:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 15 11:24:07 volumio go-librespot[14681]: time="2025-12-15T11:24:07+01:00" level=trace msg="emitting websocket event: paused" Dec 15 11:24:07 volumio volumio[909]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:search:IT+IS+Wednesday","uri":"spotify:track:0Sz65xfu0L3HK8M1bUkTay","play_origin":"search"}} Dec 15 11:24:07 volumio volumio[909]: info: Spotify is playing in volatile mode Dec 15 11:24:07 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 11:24:07 volumio volumio[909]: SPOTIFY: UNSET VOLATILE Dec 15 11:24:07 volumio volumio[909]: SPOTIFY: {"status":"stop","position":0,"title":"80s80s NDW","albumart":"/albumart?sourceicon=music_service/80s80s/images/80s80s-ndw-cover.png","uri":"http://streams.80s80s.de/ndw/mp3-192/volumio","trackType":"80s80s Radio","seek":0,"duration":1000,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":53,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"80s80s Radio","volatile":false,"service":"80s80s"} Dec 15 11:24:07 volumio volumio[909]: SPOTIFY: PUSH STATE SPOTIFY Dec 15 11:24:07 volumio volumio[909]: SPOTIFY: {"status":"pause","service":"spop","title":"It Is Wednesday My Dudes","artist":"RetroMoon","album":"It Is Wednesday My Dudes","albumart":"https://i.scdn.co/image/ab67616d00001e02abe028a31c5a75e501387555","uri":"spotify:track:0Sz65xfu0L3HK8M1bUkTay","trackType":"spotify","seek":89000,"duration":185,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 15 11:24:07 volumio volumio[909]: info: CoreCommandRouter::servicePushState Dec 15 11:24:07 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:24:07 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 11:24:07 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:24:07 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:24:12 volumio volumio[909]: info: Dec 15 11:24:12 volumio volumio[909]: ---------------------------- MPD announces state update: player Dec 15 11:24:12 volumio volumio[909]: info: ControllerMpd::getState Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 15 11:24:12 volumio volumio[909]: info: Dec 15 11:24:12 volumio volumio[909]: ---------------------------- MPD announces state update: player Dec 15 11:24:12 volumio volumio[909]: info: ControllerMpd::getState Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 15 11:24:12 volumio volumio[909]: info: Dec 15 11:24:12 volumio volumio[909]: ---------------------------- MPD announces state update: player Dec 15 11:24:12 volumio volumio[909]: info: sendMpdCommand status took 3 milliseconds Dec 15 11:24:12 volumio volumio[909]: info: sendMpdCommand status took 2 milliseconds Dec 15 11:24:12 volumio volumio[909]: info: ControllerMpd::getState Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::parseState Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::parseState Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 11:24:12 volumio volumio[909]: info: sendMpdCommand status took 2 milliseconds Dec 15 11:24:12 volumio volumio[909]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 15 11:24:12 volumio volumio[909]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::parseState Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 15 11:24:12 volumio volumio[909]: info: ControllerMpd::pushState Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::servicePushState Dec 15 11:24:12 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:24:12 volumio volumio[909]: info: ControllerMpd::pushState Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::servicePushState Dec 15 11:24:12 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:24:12 volumio volumio[909]: info: ------------------------------ 25ms Dec 15 11:24:12 volumio volumio[909]: info: ------------------------------ 23ms Dec 15 11:24:12 volumio volumio[909]: info: Dec 15 11:24:12 volumio volumio[909]: ---------------------------- MPD announces state update: player Dec 15 11:24:12 volumio volumio[909]: info: ControllerMpd::getState Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 15 11:24:12 volumio volumio[909]: info: Dec 15 11:24:12 volumio volumio[909]: ---------------------------- MPD announces system playlist update Dec 15 11:24:12 volumio volumio[909]: info: Ignoring MPD Status Update Dec 15 11:24:12 volumio volumio[909]: info: Dec 15 11:24:12 volumio volumio[909]: ---------------------------- MPD announces state update: player Dec 15 11:24:12 volumio volumio[909]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 15 11:24:12 volumio volumio[909]: info: ControllerMpd::getState Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 15 11:24:12 volumio volumio[909]: info: sendMpdCommand status took 3 milliseconds Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::parseState Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 11:24:12 volumio volumio[909]: info: ControllerMpd::pushState Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::servicePushState Dec 15 11:24:12 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:24:12 volumio volumio[909]: info: ------------------------------ 35ms Dec 15 11:24:12 volumio volumio[909]: info: Dec 15 11:24:12 volumio volumio[909]: ---------------------------- MPD announces system playlist update Dec 15 11:24:12 volumio volumio[909]: info: Ignoring MPD Status Update Dec 15 11:24:12 volumio volumio[909]: info: Dec 15 11:24:12 volumio volumio[909]: ---------------------------- MPD announces state update: player Dec 15 11:24:12 volumio volumio[909]: info: ControllerMpd::getState Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 15 11:24:12 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:24:12 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:24:12 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:24:12 volumio volumio[909]: info: Dec 15 11:24:12 volumio volumio[909]: ---------------------------- MPD announces system playlist update Dec 15 11:24:12 volumio volumio[909]: info: Ignoring MPD Status Update Dec 15 11:24:12 volumio volumio[909]: info: Dec 15 11:24:12 volumio volumio[909]: ---------------------------- MPD announces state update: player Dec 15 11:24:12 volumio volumio[909]: info: ControllerMpd::getState Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 15 11:24:12 volumio volumio[909]: info: ------------------------------ 18ms Dec 15 11:24:12 volumio volumio[909]: info: sendMpdCommand status took 16 milliseconds Dec 15 11:24:12 volumio volumio[909]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 15 11:24:12 volumio volumio[909]: info: ------------------------------ 8ms Dec 15 11:24:12 volumio volumio[909]: info: sendMpdCommand status took 7 milliseconds Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::parseState Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::parseState Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 11:24:12 volumio volumio[909]: info: ControllerMpd::pushState Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::servicePushState Dec 15 11:24:12 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:24:12 volumio volumio[909]: info: ------------------------------ 32ms Dec 15 11:24:12 volumio volumio[909]: info: ------------------------------ 14ms Dec 15 11:24:12 volumio volumio[909]: info: sendMpdCommand status took 13 milliseconds Dec 15 11:24:12 volumio volumio[909]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 15 11:24:12 volumio volumio[909]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::parseState Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 15 11:24:12 volumio volumio[909]: info: ControllerMpd::pushState Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::servicePushState Dec 15 11:24:12 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:24:12 volumio volumio[909]: info: ControllerMpd::pushState Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::servicePushState Dec 15 11:24:12 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:24:12 volumio volumio[909]: info: ------------------------------ 49ms Dec 15 11:24:12 volumio volumio[909]: info: ------------------------------ 39ms Dec 15 11:24:12 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:24:12 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:24:12 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:24:12 volumio volumio[909]: info: sendMpdCommand playlistinfo took 24 milliseconds Dec 15 11:24:12 volumio volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 15 11:24:12 volumio volumio[909]: info: ControllerMpd::pushState Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::servicePushState Dec 15 11:24:12 volumio volumio[909]: info: CoreStateMachine::pushState Dec 15 11:24:12 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Dec 15 11:24:12 volumio volumio[909]: info: ------------------------------ 48ms Dec 15 11:24:12 volumio volumio[909]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 15 11:24:15 volumio volumio[909]: info: [1765794255013] [80s80s] Pushing the next song state Steinwolke - Katharine, Katharine and getting next track. Dec 15 11:24:15 volumio volumio[909]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 11:24:15 volumio volumio[909]: TypeError: Cannot set property 'name' of undefined Dec 15 11:24:15 volumio volumio[909]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Dec 15 11:24:15 volumio volumio[909]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Dec 15 11:24:15 volumio volumio[909]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Dec 15 11:24:15 volumio volumio[909]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Dec 15 11:24:15 volumio volumio[909]: at processImmediate (internal/timers.js:461:21) Dec 15 11:24:15 volumio volumio[909]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 11:24:15 volumio sudo[15373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-15 11:23 Dec 15 11:24:15 volumio sudo[15373]: 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"