-- Logs begin at Thu 2025-05-08 12:10:31 CEST, end at Thu 2025-05-08 19:11:01 CEST. -- May 08 19:10:01 volumio go-librespot[1258]: time="2025-05-08T19:10:01+02:00" level=trace msg="sent dealer ping" May 08 19:10:01 volumio go-librespot[1258]: time="2025-05-08T19:10:01+02:00" level=trace msg="received dealer pong" May 08 19:10:06 volumio go-librespot[1258]: time="2025-05-08T19:10:06+02:00" level=debug msg="fetched chunk 6/15, size: 524288" uri="spotify:track:7AimHQrOUoUTYbVX87D3Zl" May 08 19:10:08 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:08 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:18 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:18 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:18 volumio volumio[1085]: info: Listing playlists May 08 19:10:18 volumio volumio[1085]: info: Listing playlists May 08 19:10:19 volumio go-librespot[1258]: time="2025-05-08T19:10:19+02:00" level=debug msg="fetched chunk 7/15, size: 524288" uri="spotify:track:7AimHQrOUoUTYbVX87D3Zl" May 08 19:10:28 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:28 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:31 volumio go-librespot[1258]: time="2025-05-08T19:10:31+02:00" level=trace msg="sent dealer ping" May 08 19:10:31 volumio go-librespot[1258]: time="2025-05-08T19:10:31+02:00" level=debug msg="fetched chunk 8/15, size: 524288" uri="spotify:track:7AimHQrOUoUTYbVX87D3Zl" May 08 19:10:31 volumio go-librespot[1258]: time="2025-05-08T19:10:31+02:00" level=trace msg="received dealer pong" May 08 19:10:33 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 19:10:34 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 19:10:34 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 19:10:34 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 19:10:34 volumio volumio[1085]: info: Discovery: Getting this device information May 08 19:10:34 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:34 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:34 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 19:10:34 volumio volumio[1085]: verbose: New Socket.io Connection to 192.168.1.105:3000 from 192.168.1.40 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 May 08 19:10:34 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:34 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:34 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 08 19:10:34 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 08 19:10:35 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 19:10:35 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 19:10:35 volumio volumio[1085]: info: Discovery: Getting this device information May 08 19:10:35 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:35 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:35 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 19:10:35 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 19:10:35 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 19:10:35 volumio volumio[1085]: info: Discovery: Getting this device information May 08 19:10:35 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:35 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:35 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 19:10:39 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:39 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:41 volumio sudo[8029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 19:10:41 volumio sudo[8029]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 19:10:41 volumio sudo[8029]: pam_unix(sudo:session): session closed for user root May 08 19:10:41 volumio sudo[8032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 19:10:41 volumio sudo[8032]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 19:10:41 volumio sudo[8032]: pam_unix(sudo:session): session closed for user root May 08 19:10:41 volumio volumio[1085]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.40 UA: Mozilla/5.0 (Linux; Android 11; Raspberry Pi 4 Build/RQ1A.210205.004; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/88.0.4324.152 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 08 19:10:42 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 08 19:10:42 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 08 19:10:43 volumio sudo[8036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 19:10:43 volumio sudo[8036]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 19:10:43 volumio sudo[8036]: pam_unix(sudo:session): session closed for user root May 08 19:10:43 volumio sudo[8039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 19:10:43 volumio sudo[8039]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 19:10:43 volumio sudo[8039]: pam_unix(sudo:session): session closed for user root May 08 19:10:43 volumio volumio[1085]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.40 UA: Mozilla/5.0 (Linux; Android 11; Raspberry Pi 4 Build/RQ1A.210205.004; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/88.0.4324.152 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 08 19:10:44 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:44 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:44 volumio volumio[1085]: info: Listing playlists May 08 19:10:44 volumio volumio[1085]: info: Listing playlists May 08 19:10:44 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 08 19:10:44 volumio volumio[1085]: info: CoreCommandRouter::volumioGetVisibleSources May 08 19:10:44 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 19:10:44 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:44 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:44 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 08 19:10:44 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 08 19:10:44 volumio volumio[1085]: info: Received Get System Info May 08 19:10:44 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 19:10:44 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 19:10:44 volumio volumio[1085]: info: Discovery: Getting this device information May 08 19:10:44 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:44 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:44 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 19:10:44 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:44 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:44 volumio volumio[1085]: info: Listing playlists May 08 19:10:44 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 08 19:10:44 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 08 19:10:44 volumio go-librespot[1258]: time="2025-05-08T19:10:44+02:00" level=debug msg="fetched chunk 9/15, size: 524288" uri="spotify:track:7AimHQrOUoUTYbVX87D3Zl" May 08 19:10:44 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 08 19:10:45 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 19:10:45 volumio volumio[1085]: info: Received Get System Info May 08 19:10:45 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 19:10:45 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 19:10:45 volumio volumio[1085]: info: Discovery: Getting this device information May 08 19:10:45 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:45 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:45 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 19:10:45 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 08 19:10:45 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 08 19:10:46 volumio volumio[1085]: info: CoreCommandRouter::volumioPause May 08 19:10:46 volumio volumio[1085]: info: CoreStateMachine::pause May 08 19:10:46 volumio volumio[1085]: info: CoreStateMachine::stPlaybackTimer May 08 19:10:46 volumio volumio[1085]: info: CoreStateMachine::servicePause May 08 19:10:46 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:46 volumio volumio[1085]: info: CoreCommandRouter::servicePause May 08 19:10:46 volumio volumio[1085]: info: Spotify Received pause May 08 19:10:46 volumio volumio[1085]: SPOTIFY: SPOTIFY PAUSE May 08 19:10:46 volumio volumio[1085]: SPOTIFY: {"status":"play","position":16,"title":"96 Quite Bitter Beings","artist":"CKY","album":"Camp Kill Yourself, Vol.1","albumart":"https://i.scdn.co/image/ab67616d0000b2730f53a197220e069351f208ce","uri":"spotify:track:7AimHQrOUoUTYbVX87D3Zl","trackType":"spotify","codec":"ogg","seek":251,"duration":201,"samplerate":"320 kbps","bitdepth":"16 bit","random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":23,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} May 08 19:10:46 volumio volumio[1085]: info: Sending Spotify command to local API: /player/pause May 08 19:10:46 volumio go-librespot[1258]: time="2025-05-08T19:10:46+02:00" level=debug msg="pause track at 79238ms" May 08 19:10:46 volumio go-librespot[1258]: time="2025-05-08T19:10:46+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 08 19:10:46 volumio go-librespot[1258]: time="2025-05-08T19:10:46+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 08 19:10:46 volumio go-librespot[1258]: time="2025-05-08T19:10:46+02:00" level=trace msg="emitting websocket event: paused" May 08 19:10:46 volumio volumio[1085]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7AimHQrOUoUTYbVX87D3Zl","play_origin":"go-librespot"}} May 08 19:10:46 volumio volumio[1085]: SPOTIFY: PUSH STATE SPOTIFY May 08 19:10:46 volumio volumio[1085]: SPOTIFY: {"status":"pause","service":"spop","title":"96 Quite Bitter Beings","artist":"CKY","album":"Camp Kill Yourself, Vol.1","albumart":"https://i.scdn.co/image/ab67616d00001e020f53a197220e069351f208ce","uri":"spotify:track:7AimHQrOUoUTYbVX87D3Zl","trackType":"spotify","seek":80000,"duration":201,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 08 19:10:46 volumio volumio[1085]: info: CoreCommandRouter::servicePushState May 08 19:10:46 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:46 volumio volumio[1085]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"96 Quite Bitter Beings","artist":"CKY","album":"Camp Kill Yourself, Vol.1","albumart":"https://i.scdn.co/image/ab67616d00001e020f53a197220e069351f208ce","uri":"spotify:track:7AimHQrOUoUTYbVX87D3Zl","trackType":"spotify","seek":80000,"duration":201,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 08 19:10:46 volumio volumio[1085]: verbose: CURRENT POSITION 16 May 08 19:10:46 volumio volumio[1085]: info: CoreStateMachine::syncState stateService pause May 08 19:10:46 volumio volumio[1085]: info: CoreStateMachine::syncState currentStatus pause May 08 19:10:46 volumio volumio[1085]: info: CoreStateMachine::pushState May 08 19:10:46 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:46 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 19:10:46 volumio volumio[1085]: info: CoreCommandRouter::volumioPushState May 08 19:10:46 volumio volumio[1085]: info: CoreStateMachine::stPlaybackTimer May 08 19:10:46 volumio volumio[1085]: SPOTIFY: RECEIVED VOLUMIO VOLUME 23 May 08 19:10:49 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 19:10:49 volumio volumio[1085]: info: Received Get System Info May 08 19:10:49 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 19:10:49 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 19:10:49 volumio volumio[1085]: info: Discovery: Getting this device information May 08 19:10:49 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:49 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:49 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 19:10:51 volumio volumio[1085]: error: Upnp client error: Error: This socket has been ended by the other party May 08 19:10:53 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 08 19:10:54 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:54 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:54 volumio volumio[1085]: info: Clearing queue after UPNP request May 08 19:10:54 volumio volumio[1085]: info: CoreStateMachine::ClearQueue May 08 19:10:54 volumio volumio[1085]: info: CoreStateMachine::stop May 08 19:10:54 volumio volumio[1085]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 19:10:54 volumio volumio[1085]: info: CoreStateMachine::updateTrackBlock May 08 19:10:54 volumio volumio[1085]: info: CorePlayQueue::getTrackBlock May 08 19:10:54 volumio volumio[1085]: info: CoreStateMachine::stPlaybackTimer May 08 19:10:54 volumio volumio[1085]: info: CoreStateMachine::pushState May 08 19:10:54 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:54 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 19:10:54 volumio volumio[1085]: info: CoreCommandRouter::volumioPushState May 08 19:10:54 volumio volumio[1085]: info: CoreStateMachine::serviceStop May 08 19:10:54 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:54 volumio volumio[1085]: info: CoreCommandRouter::serviceStop May 08 19:10:54 volumio volumio[1085]: info: Spotify Stop May 08 19:10:54 volumio volumio[1085]: SPOTIFY: SPOTIFY STOP May 08 19:10:54 volumio volumio[1085]: SPOTIFY: {"status":"pause","position":16,"title":"96 Quite Bitter Beings","artist":"CKY","album":"Camp Kill Yourself, Vol.1","albumart":"https://i.scdn.co/image/ab67616d0000b2730f53a197220e069351f208ce","uri":"spotify:track:7AimHQrOUoUTYbVX87D3Zl","trackType":"spotify","codec":"ogg","seek":77849,"duration":201,"samplerate":"320 kbps","bitdepth":"16 bit","random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":23,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} May 08 19:10:54 volumio volumio[1085]: info: Sending Spotify command to local API: /player/pause May 08 19:10:54 volumio volumio[1085]: info: CorePlayQueue::clearPlayQueue May 08 19:10:54 volumio volumio[1085]: info: CorePlayQueue::saveQueue May 08 19:10:54 volumio volumio[1085]: info: CoreCommandRouter::volumioPushState May 08 19:10:54 volumio volumio[1085]: info: CoreCommandRouter::volumioPushQueue May 08 19:10:54 volumio volumio[1085]: SPOTIFY: RECEIVED VOLUMIO VOLUME 23 May 08 19:10:54 volumio volumio[1085]: SPOTIFY: RECEIVED VOLUMIO VOLUME 23 May 08 19:10:54 volumio go-librespot[1258]: time="2025-05-08T19:10:54+02:00" level=debug msg="pause track at 79635ms" May 08 19:10:54 volumio volumio[1085]: info: May 08 19:10:54 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:54 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:54 volumio volumio[1085]: info: May 08 19:10:54 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:54 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:54 volumio volumio[1085]: info: May 08 19:10:54 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:54 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:54 volumio volumio[1085]: info: Starting UPNP Playback May 08 19:10:54 volumio volumio[1085]: info: Preparing playback through UPNP May 08 19:10:54 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:54 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:54 volumio volumio[1085]: info: CoreStateMachine::setConsumeUpdateService mpd May 08 19:10:54 volumio volumio[1085]: error: updateQueue error: null May 08 19:10:54 volumio volumio[1085]: error: updateQueue error: null May 08 19:10:54 volumio volumio[1085]: error: updateQueue error: null May 08 19:10:54 volumio volumio[1085]: info: ------------------------------ 5ms May 08 19:10:54 volumio volumio[1085]: info: ------------------------------ 3ms May 08 19:10:54 volumio volumio[1085]: info: ------------------------------ 2ms May 08 19:10:54 volumio go-librespot[1258]: time="2025-05-08T19:10:54+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 08 19:10:54 volumio go-librespot[1258]: time="2025-05-08T19:10:54+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 08 19:10:54 volumio go-librespot[1258]: time="2025-05-08T19:10:54+02:00" level=trace msg="emitting websocket event: paused" May 08 19:10:54 volumio volumio[1085]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7AimHQrOUoUTYbVX87D3Zl","play_origin":"go-librespot"}} May 08 19:10:54 volumio volumio[1085]: SPOTIFY: PUSH STATE SPOTIFY May 08 19:10:54 volumio volumio[1085]: SPOTIFY: {"status":"pause","service":"spop","title":"96 Quite Bitter Beings","artist":"CKY","album":"Camp Kill Yourself, Vol.1","albumart":"https://i.scdn.co/image/ab67616d00001e020f53a197220e069351f208ce","uri":"spotify:track:7AimHQrOUoUTYbVX87D3Zl","trackType":"spotify","seek":80000,"duration":201,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 08 19:10:54 volumio volumio[1085]: info: CoreCommandRouter::servicePushState May 08 19:10:54 volumio volumio[1085]: verbose: In UPNP mode May 08 19:10:54 volumio volumio[1085]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received spop May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 3ms May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 2ms May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 2ms May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 2ms May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 1ms May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 2ms May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 2ms May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 1ms May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 2ms May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 2ms May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 1ms May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 3ms May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 2ms May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 1ms May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces system playlist update May 08 19:10:55 volumio volumio[1085]: info: Ignoring MPD Status Update May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 2ms May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 1ms May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces state update: player May 08 19:10:55 volumio volumio[1085]: info: ControllerMpd::getState May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces state update: player May 08 19:10:55 volumio volumio[1085]: info: ControllerMpd::getState May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces state update: player May 08 19:10:55 volumio volumio[1085]: info: ControllerMpd::getState May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces state update: player May 08 19:10:55 volumio volumio[1085]: info: sendMpdCommand status took 17 milliseconds May 08 19:10:55 volumio volumio[1085]: info: sendMpdCommand status took 16 milliseconds May 08 19:10:55 volumio volumio[1085]: info: sendMpdCommand status took 16 milliseconds May 08 19:10:55 volumio volumio[1085]: info: ControllerMpd::getState May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::parseState May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::parseState May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::parseState May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces state update: player May 08 19:10:55 volumio volumio[1085]: info: ControllerMpd::getState May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status May 08 19:10:55 volumio volumio[1085]: info: May 08 19:10:55 volumio volumio[1085]: ---------------------------- MPD announces state update: player May 08 19:10:55 volumio volumio[1085]: info: ControllerMpd::getState May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status May 08 19:10:55 volumio volumio[1085]: info: sendMpdCommand status took 4 milliseconds May 08 19:10:55 volumio volumio[1085]: info: sendMpdCommand playlistinfo took 4 milliseconds May 08 19:10:55 volumio volumio[1085]: info: sendMpdCommand playlistinfo took 4 milliseconds May 08 19:10:55 volumio volumio[1085]: info: sendMpdCommand playlistinfo took 3 milliseconds May 08 19:10:55 volumio volumio[1085]: info: sendMpdCommand status took 2 milliseconds May 08 19:10:55 volumio volumio[1085]: info: sendMpdCommand status took 2 milliseconds May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::parseState May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::parseTrackInfo May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::parseTrackInfo May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::parseTrackInfo May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::parseState May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::parseState May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 19:10:55 volumio volumio[1085]: info: ControllerMpd::pushState May 08 19:10:55 volumio volumio[1085]: info: CoreCommandRouter::servicePushState May 08 19:10:55 volumio volumio[1085]: verbose: In UPNP mode May 08 19:10:55 volumio volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"IqHg1R1XSIL6fhFwKPGv4Q.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.1.16:8123/api/tts_proxy/IqHg1R1XSIL6fhFwKPGv4Q.mp3","trackType":"mp3"} May 08 19:10:55 volumio volumio[1085]: verbose: CURRENT POSITION 16 May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::syncState stateService play May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::syncState currentStatus stop May 08 19:10:55 volumio volumio[1085]: info: ControllerMpd::pushState May 08 19:10:55 volumio volumio[1085]: info: CoreCommandRouter::servicePushState May 08 19:10:55 volumio volumio[1085]: verbose: In UPNP mode May 08 19:10:55 volumio volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":420,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"IqHg1R1XSIL6fhFwKPGv4Q.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.1.16:8123/api/tts_proxy/IqHg1R1XSIL6fhFwKPGv4Q.mp3","trackType":"mp3"} May 08 19:10:55 volumio volumio[1085]: verbose: CURRENT POSITION 16 May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::syncState stateService play May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::syncState currentStatus play May 08 19:10:55 volumio volumio[1085]: info: Received an update from plugin. extracting info from payload May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::pushState May 08 19:10:55 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:55 volumio volumio[1085]: info: ControllerMpd::pushState May 08 19:10:55 volumio volumio[1085]: info: CoreCommandRouter::servicePushState May 08 19:10:55 volumio volumio[1085]: verbose: In UPNP mode May 08 19:10:55 volumio volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":420,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"IqHg1R1XSIL6fhFwKPGv4Q.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.1.16:8123/api/tts_proxy/IqHg1R1XSIL6fhFwKPGv4Q.mp3","trackType":"mp3"} May 08 19:10:55 volumio volumio[1085]: verbose: CURRENT POSITION 16 May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::syncState stateService play May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::syncState currentStatus play May 08 19:10:55 volumio volumio[1085]: info: Received an update from plugin. extracting info from payload May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::pushState May 08 19:10:55 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 31ms May 08 19:10:55 volumio volumio[1085]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 08 19:10:55 volumio volumio[1085]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 29ms May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 28ms May 08 19:10:55 volumio volumio[1085]: info: sendMpdCommand playlistinfo took 7 milliseconds May 08 19:10:55 volumio volumio[1085]: info: sendMpdCommand playlistinfo took 5 milliseconds May 08 19:10:55 volumio volumio[1085]: info: sendMpdCommand playlistinfo took 6 milliseconds May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::parseTrackInfo May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::parseTrackInfo May 08 19:10:55 volumio volumio[1085]: verbose: ControllerMpd::parseTrackInfo May 08 19:10:55 volumio volumio[1085]: info: ControllerMpd::pushState May 08 19:10:55 volumio volumio[1085]: info: CoreCommandRouter::servicePushState May 08 19:10:55 volumio volumio[1085]: verbose: In UPNP mode May 08 19:10:55 volumio volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"IqHg1R1XSIL6fhFwKPGv4Q.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.1.16:8123/api/tts_proxy/IqHg1R1XSIL6fhFwKPGv4Q.mp3","trackType":"mp3"} May 08 19:10:55 volumio volumio[1085]: verbose: CURRENT POSITION 16 May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::syncState stateService play May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::syncState currentStatus play May 08 19:10:55 volumio volumio[1085]: info: Received an update from plugin. extracting info from payload May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::pushState May 08 19:10:55 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:55 volumio volumio[1085]: info: ControllerMpd::pushState May 08 19:10:55 volumio volumio[1085]: info: CoreCommandRouter::servicePushState May 08 19:10:55 volumio volumio[1085]: verbose: In UPNP mode May 08 19:10:55 volumio volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"IqHg1R1XSIL6fhFwKPGv4Q.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.1.16:8123/api/tts_proxy/IqHg1R1XSIL6fhFwKPGv4Q.mp3","trackType":"mp3"} May 08 19:10:55 volumio volumio[1085]: verbose: CURRENT POSITION 16 May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::syncState stateService play May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::syncState currentStatus play May 08 19:10:55 volumio volumio[1085]: info: Received an update from plugin. extracting info from payload May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::pushState May 08 19:10:55 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:55 volumio volumio[1085]: info: ControllerMpd::pushState May 08 19:10:55 volumio volumio[1085]: info: CoreCommandRouter::servicePushState May 08 19:10:55 volumio volumio[1085]: verbose: In UPNP mode May 08 19:10:55 volumio volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"IqHg1R1XSIL6fhFwKPGv4Q.mp3","artist":null,"album":" Home Assistant","uri":"http://192.168.1.16:8123/api/tts_proxy/IqHg1R1XSIL6fhFwKPGv4Q.mp3","trackType":"mp3"} May 08 19:10:55 volumio volumio[1085]: verbose: CURRENT POSITION 16 May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::syncState stateService play May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::syncState currentStatus play May 08 19:10:55 volumio volumio[1085]: info: Received an update from plugin. extracting info from payload May 08 19:10:55 volumio volumio[1085]: info: CoreStateMachine::pushState May 08 19:10:55 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:55 volumio volumio[1085]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 08 19:10:55 volumio volumio[1085]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 08 19:10:55 volumio volumio[1085]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 17ms May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 15ms May 08 19:10:55 volumio volumio[1085]: info: ------------------------------ 14ms May 08 19:10:57 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 19:10:57 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 19:10:57 volumio volumio[1085]: info: Discovery: Getting this device information May 08 19:10:57 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:10:57 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:57 volumio volumio[1085]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined May 08 19:10:57 volumio volumio[1085]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) May 08 19:10:57 volumio volumio[1085]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) May 08 19:10:57 volumio volumio[1085]: at ControllerVolumioDiscovery.getThisDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:464:38) May 08 19:10:57 volumio volumio[1085]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 08 19:10:57 volumio volumio[1085]: at ControllerSystem.getSystemInfo (/volumio/app/plugins/system_controller/system/index.js:617:45) May 08 19:10:57 volumio volumio[1085]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 08 19:10:57 volumio volumio[1085]: at RESTApiSystem.getSystemInfo (/volumio/app/plugins/user_interface/rest_api/system.js:38:41) May 08 19:10:57 volumio volumio[1085]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) May 08 19:10:57 volumio volumio[1085]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) May 08 19:10:57 volumio volumio[1085]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) May 08 19:10:57 volumio volumio[1085]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) May 08 19:10:57 volumio volumio[1085]: at /volumio/node_modules/express/lib/router/index.js:281:22 May 08 19:10:57 volumio volumio[1085]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) May 08 19:10:57 volumio volumio[1085]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) May 08 19:10:57 volumio volumio[1085]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) May 08 19:10:57 volumio volumio[1085]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) May 08 19:10:59 volumio volumio[1085]: info: May 08 19:10:59 volumio volumio[1085]: ---------------------------- MPD announces state update: player May 08 19:10:59 volumio volumio[1085]: info: ControllerMpd::getState May 08 19:10:59 volumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status May 08 19:10:59 volumio volumio[1085]: info: May 08 19:10:59 volumio volumio[1085]: ---------------------------- MPD announces state update: player May 08 19:10:59 volumio volumio[1085]: info: ControllerMpd::getState May 08 19:10:59 volumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status May 08 19:10:59 volumio volumio[1085]: info: May 08 19:10:59 volumio volumio[1085]: ---------------------------- MPD announces state update: player May 08 19:10:59 volumio volumio[1085]: info: ControllerMpd::getState May 08 19:10:59 volumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status May 08 19:10:59 volumio volumio[1085]: info: sendMpdCommand status took 2 milliseconds May 08 19:10:59 volumio volumio[1085]: info: sendMpdCommand status took 2 milliseconds May 08 19:10:59 volumio volumio[1085]: info: sendMpdCommand status took 1 milliseconds May 08 19:10:59 volumio volumio[1085]: verbose: ControllerMpd::parseState May 08 19:10:59 volumio volumio[1085]: verbose: ControllerMpd::parseState May 08 19:10:59 volumio volumio[1085]: verbose: ControllerMpd::parseState May 08 19:10:59 volumio volumio[1085]: info: ControllerMpd::pushState May 08 19:10:59 volumio volumio[1085]: info: CoreCommandRouter::servicePushState May 08 19:10:59 volumio volumio[1085]: info: CoreStateMachine::pushState May 08 19:10:59 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:59 volumio volumio[1085]: info: ControllerMpd::pushState May 08 19:10:59 volumio volumio[1085]: info: CoreCommandRouter::servicePushState May 08 19:10:59 volumio volumio[1085]: info: CoreStateMachine::pushState May 08 19:10:59 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:59 volumio volumio[1085]: info: ControllerMpd::pushState May 08 19:10:59 volumio volumio[1085]: info: CoreCommandRouter::servicePushState May 08 19:10:59 volumio volumio[1085]: info: CoreStateMachine::pushState May 08 19:10:59 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:10:59 volumio volumio[1085]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 08 19:10:59 volumio volumio[1085]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 08 19:10:59 volumio volumio[1085]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 08 19:10:59 volumio volumio[1085]: info: ------------------------------ 8ms May 08 19:10:59 volumio volumio[1085]: info: ------------------------------ 7ms May 08 19:10:59 volumio volumio[1085]: info: ------------------------------ 6ms May 08 19:11:01 volumio go-librespot[1258]: time="2025-05-08T19:11:01+02:00" level=trace msg="sent dealer ping" May 08 19:11:01 volumio go-librespot[1258]: time="2025-05-08T19:11:01+02:00" level=trace msg="received dealer pong" May 08 19:11:01 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 08 19:11:01 volumio volumio[1085]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 08 19:11:01 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 08 19:11:01 volumio volumio[1085]: info: Received Get System Version May 08 19:11:01 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 19:11:01 volumio volumio[1085]: info: Received Get System Info May 08 19:11:01 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 19:11:01 volumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 19:11:01 volumio volumio[1085]: info: Discovery: Getting this device information May 08 19:11:01 volumio volumio[1085]: info: CoreCommandRouter::volumioGetState May 08 19:11:01 volumio volumio[1085]: info: CorePlayQueue::getTrack 16 May 08 19:11:01 volumio volumio[1085]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 19:11:01 volumio volumio[1085]: TypeError: Cannot read property 'name' of undefined May 08 19:11:01 volumio volumio[1085]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) May 08 19:11:01 volumio volumio[1085]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) May 08 19:11:01 volumio volumio[1085]: at ControllerVolumioDiscovery.getThisDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:464:38) May 08 19:11:01 volumio volumio[1085]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 08 19:11:01 volumio volumio[1085]: at ControllerSystem.getSystemInfo (/volumio/app/plugins/system_controller/system/index.js:617:45) May 08 19:11:01 volumio volumio[1085]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 08 19:11:01 volumio volumio[1085]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1057:49) May 08 19:11:01 volumio volumio[1085]: at Socket.emit (events.js:315:20) May 08 19:11:01 volumio volumio[1085]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 08 19:11:01 volumio volumio[1085]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 08 19:11:01 volumio volumio[1085]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 19:11:01 volumio sudo[8206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-08 19:10 May 08 19:11:01 volumio sudo[8206]: 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="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"