-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Wed 2025-10-29 15:16:16 GMT. -- Oct 29 15:15:12 hipi go-librespot[1322]: time="2025-10-29T15:15:12Z" level=trace msg="sent dealer ping" Oct 29 15:15:12 hipi go-librespot[1322]: time="2025-10-29T15:15:12Z" level=trace msg="received dealer pong" Oct 29 15:15:22 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 15:15:22 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 15:15:22 hipi volumio[998]: info: Discovery: Getting this device information Oct 29 15:15:22 hipi volumio[998]: info: CoreCommandRouter::volumioGetState Oct 29 15:15:22 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:15:22 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 15:15:22 hipi volumio[998]: verbose: New Socket.io Connection to 192.168.4.70:3000 from 192.168.4.76 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 29 15:15:22 hipi volumio[998]: info: CoreCommandRouter::volumioGetState Oct 29 15:15:22 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:15:22 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 29 15:15:22 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 29 15:15:24 hipi volumio[998]: info: CoreCommandRouter::volumioGetState Oct 29 15:15:24 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:15:26 hipi volumio[998]: verbose: New Socket.io Connection to 192.168.4.70:3000 from 192.168.4.76 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 29 15:15:26 hipi volumio[998]: info: CoreCommandRouter::volumioGetState Oct 29 15:15:26 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:15:26 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 29 15:15:26 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 29 15:15:26 hipi volumio[998]: info: CoreCommandRouter::volumioGetState Oct 29 15:15:26 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:15:28 hipi sudo[4113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 15:15:28 hipi sudo[4113]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 15:15:28 hipi sudo[4113]: pam_unix(sudo:session): session closed for user root Oct 29 15:15:28 hipi sudo[4116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 15:15:28 hipi sudo[4116]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 15:15:28 hipi sudo[4116]: pam_unix(sudo:session): session closed for user root Oct 29 15:15:28 hipi volumio[998]: verbose: New Socket.io Connection to 192.168.4.70 from 192.168.4.76 UA: Mozilla/5.0 (Linux; Android 9; ANE-LX1 Build/HUAWEIANE-L01; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.179 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 29 15:15:30 hipi sudo[4133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 15:15:30 hipi sudo[4133]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 15:15:30 hipi sudo[4133]: pam_unix(sudo:session): session closed for user root Oct 29 15:15:30 hipi sudo[4136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 15:15:30 hipi sudo[4136]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 15:15:30 hipi sudo[4136]: pam_unix(sudo:session): session closed for user root Oct 29 15:15:30 hipi volumio[998]: verbose: New Socket.io Connection to 192.168.4.70 from 192.168.4.76 UA: Mozilla/5.0 (Linux; Android 9; ANE-LX1 Build/HUAWEIANE-L01; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.179 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 29 15:15:31 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 29 15:15:31 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 29 15:15:31 hipi volumio[998]: info: CoreCommandRouter::volumioGetVisibleSources Oct 29 15:15:31 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 15:15:31 hipi volumio[998]: info: CoreCommandRouter::volumioGetState Oct 29 15:15:31 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:15:31 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 29 15:15:31 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 29 15:15:31 hipi volumio[998]: info: Received Get System Info Oct 29 15:15:31 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 15:15:31 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 15:15:31 hipi volumio[998]: info: Discovery: Getting this device information Oct 29 15:15:31 hipi volumio[998]: info: CoreCommandRouter::volumioGetState Oct 29 15:15:31 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:15:31 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 15:15:31 hipi volumio[998]: info: CoreCommandRouter::volumioGetState Oct 29 15:15:31 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:15:31 hipi volumio[998]: info: Listing playlists Oct 29 15:15:31 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 29 15:15:31 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 29 15:15:32 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 29 15:15:32 hipi volumio[998]: info: Received Get System Info Oct 29 15:15:32 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 15:15:32 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 15:15:32 hipi volumio[998]: info: Discovery: Getting this device information Oct 29 15:15:32 hipi volumio[998]: info: CoreCommandRouter::volumioGetState Oct 29 15:15:32 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:15:32 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 15:15:32 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 29 15:15:34 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 29 15:15:35 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 29 15:15:35 hipi volumio[998]: info: Received Get System Info Oct 29 15:15:35 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 15:15:35 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 15:15:35 hipi volumio[998]: info: Discovery: Getting this device information Oct 29 15:15:35 hipi volumio[998]: info: CoreCommandRouter::volumioGetState Oct 29 15:15:35 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:15:35 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 15:15:35 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 29 15:15:35 hipi volumio[998]: info: Received Get System Info Oct 29 15:15:35 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 15:15:35 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 15:15:35 hipi volumio[998]: info: Discovery: Getting this device information Oct 29 15:15:35 hipi volumio[998]: info: CoreCommandRouter::volumioGetState Oct 29 15:15:35 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:15:35 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 15:15:36 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 29 15:15:36 hipi volumio[998]: info: Received Get System Info Oct 29 15:15:36 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 15:15:36 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 15:15:36 hipi volumio[998]: info: Discovery: Getting this device information Oct 29 15:15:36 hipi volumio[998]: info: CoreCommandRouter::volumioGetState Oct 29 15:15:36 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:15:36 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 15:15:39 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 29 15:15:39 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 29 15:15:40 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 29 15:15:41 hipi go-librespot[1322]: time="2025-10-29T15:15:41Z" level=trace msg="received accesspoint ping" Oct 29 15:15:41 hipi go-librespot[1322]: time="2025-10-29T15:15:41Z" level=trace msg="received accesspoint pong ack" Oct 29 15:15:42 hipi go-librespot[1322]: time="2025-10-29T15:15:42Z" level=trace msg="sent dealer ping" Oct 29 15:15:42 hipi go-librespot[1322]: time="2025-10-29T15:15:42Z" level=trace msg="received dealer pong" Oct 29 15:15:48 hipi go-librespot[1322]: time="2025-10-29T15:15:48Z" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Oct 29 15:15:48 hipi volumio[998]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Oct 29 15:15:48 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 15:15:48 hipi volumio[998]: Cannot find translation for source YouTube2 Oct 29 15:15:48 hipi volumio[998]: Cannot find translation for source YouTube Music Oct 29 15:15:48 hipi volumio[998]: Cannot find translation for source Randomizer Oct 29 15:15:48 hipi sudo[4157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Oct 29 15:15:48 hipi sudo[4157]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 15:15:48 hipi volumio[998]: info: Disabling plugin spop Oct 29 15:15:48 hipi volumio[998]: info: Done. Oct 29 15:15:48 hipi volumio[998]: info: Connection to go-librespot Websocket closed Oct 29 15:15:48 hipi systemd[1]: Stopping go-librespot Daemon... Oct 29 15:15:48 hipi systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Oct 29 15:15:48 hipi systemd[1]: go-librespot-daemon.service: Succeeded. Oct 29 15:15:48 hipi systemd[1]: Stopped go-librespot Daemon. Oct 29 15:15:48 hipi sudo[4157]: pam_unix(sudo:session): session closed for user root Oct 29 15:15:48 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 29 15:15:48 hipi volumio[998]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 29 15:15:48 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 29 15:15:48 hipi volumio[998]: info: Received Get System Version Oct 29 15:15:48 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 29 15:15:48 hipi volumio[998]: info: Received Get System Info Oct 29 15:15:48 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 15:15:48 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 15:15:48 hipi volumio[998]: info: Discovery: Getting this device information Oct 29 15:15:48 hipi volumio[998]: info: CoreCommandRouter::volumioGetState Oct 29 15:15:48 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:15:48 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 15:15:52 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 15:15:52 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 15:15:52 hipi volumio[998]: info: Discovery: Getting this device information Oct 29 15:15:52 hipi volumio[998]: info: CoreCommandRouter::volumioGetState Oct 29 15:15:52 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:15:52 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 15:15:55 hipi volumio[998]: info: Enabling plugin lastfm Oct 29 15:15:55 hipi volumio[998]: info: Loading plugin "lastfm"... Oct 29 15:15:55 hipi volumio[998]: info: PLUGIN START: lastfm Oct 29 15:15:55 hipi volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 29 15:15:55 hipi volumio[998]: info: [1761750955645] CoreMusicLibrary::Adding element LastFM Oct 29 15:15:55 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 15:15:55 hipi volumio[998]: Cannot find translation for source YouTube2 Oct 29 15:15:55 hipi volumio[998]: Cannot find translation for source YouTube Music Oct 29 15:15:55 hipi volumio[998]: Cannot find translation for source Randomizer Oct 29 15:15:55 hipi volumio[998]: Cannot find translation for source LastFM Oct 29 15:15:55 hipi volumio[998]: info: [LastFM] scrobbler initiated! Oct 29 15:15:55 hipi volumio[998]: info: [LastFM] extended logging: false Oct 29 15:15:55 hipi volumio[998]: info: [LastFM] try scrobble stream/radio plays: true Oct 29 15:15:55 hipi volumio[998]: info: [LastFM] Left init routine Oct 29 15:15:55 hipi volumio[998]: info: [LastFM] Socket already connected: false Oct 29 15:15:55 hipi volumio[998]: info: Done. Oct 29 15:15:55 hipi volumio[998]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 29 15:16:02 hipi volumio[998]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 29 15:16:05 hipi volumio[998]: info: CALLMETHOD: user_interface randomizer randomAlbum undefined Oct 29 15:16:05 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: randomizer , randomAlbum Oct 29 15:16:05 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 29 15:16:05 hipi volumio[998]: info: CURURI: albums:// Oct 29 15:16:05 hipi volumio[998]: info: listAlbums - loading Albums from cache Oct 29 15:16:06 hipi volumio[998]: info: Preload queue cleared Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::volumioClearQueue Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::ClearQueue Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::stop Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::clearPlayQueue Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::saveQueue Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::volumioPushState Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::volumioPushQueue Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::volumioAddQueueItems Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::addQueueItems Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::addQueueItems Oct 29 15:16:06 hipi volumio[998]: info: Preload queue cleared Oct 29 15:16:06 hipi volumio[998]: info: Adding Item to queue: albums://The Damned/Eternally Damned%3A The Very Best of The Damned Oct 29 15:16:06 hipi volumio[998]: info: Exploding uri albums://The Damned/Eternally Damned%3A The Very Best of The Damned in service mpd Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::volumioPushQueue Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::saveQueue Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::updateTrackBlock Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::getTrackBlock Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::volumioPlay Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::play index 0 Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::stop Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::play index undefined Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::startPlaybackTimer Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::clearAddPlayTracks USB/B2AC5264AC522361/MUSIC_PI/The Damned/Eternally Damned The Very Best of The Damned/01 Neat Neat Neat.flac Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::sendMpdCommand stop Oct 29 15:16:06 hipi volumio[998]: info: sendMpdCommand stop took 23 milliseconds Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::sendMpdCommand clear Oct 29 15:16:06 hipi volumio[998]: info: Oct 29 15:16:06 hipi volumio[998]: ---------------------------- MPD announces system playlist update Oct 29 15:16:06 hipi volumio[998]: info: Ignoring MPD Status Update Oct 29 15:16:06 hipi volumio[998]: info: sendMpdCommand clear took 5 milliseconds Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::sendMpdCommand add "USB/B2AC5264AC522361/MUSIC_PI/The Damned/Eternally Damned The Very Best of The Damned/01 Neat Neat Neat.flac" Oct 29 15:16:06 hipi volumio[998]: info: Oct 29 15:16:06 hipi volumio[998]: ---------------------------- MPD announces system playlist update Oct 29 15:16:06 hipi volumio[998]: info: Ignoring MPD Status Update Oct 29 15:16:06 hipi volumio[998]: info: Oct 29 15:16:06 hipi volumio[998]: ---------------------------- MPD announces system playlist update Oct 29 15:16:06 hipi volumio[998]: info: Ignoring MPD Status Update Oct 29 15:16:06 hipi volumio[998]: error: updateQueue error: null Oct 29 15:16:06 hipi volumio[998]: info: Oct 29 15:16:06 hipi volumio[998]: ---------------------------- MPD announces system playlist update Oct 29 15:16:06 hipi volumio[998]: info: Ignoring MPD Status Update Oct 29 15:16:06 hipi volumio[998]: info: ------------------------------ 17ms Oct 29 15:16:06 hipi volumio[998]: info: sendMpdCommand add "USB/B2AC5264AC522361/MUSIC_PI/The Damned/Eternally Damned The Very Best of The Damned/01 Neat Neat Neat.flac" took 16 milliseconds Oct 29 15:16:06 hipi volumio[998]: info: ------------------------------ 11ms Oct 29 15:16:06 hipi volumio[998]: info: ------------------------------ 10ms Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::sendMpdCommand play Oct 29 15:16:06 hipi volumio[998]: info: Oct 29 15:16:06 hipi volumio[998]: ---------------------------- MPD announces system playlist update Oct 29 15:16:06 hipi volumio[998]: info: Ignoring MPD Status Update Oct 29 15:16:06 hipi volumio[998]: info: Oct 29 15:16:06 hipi volumio[998]: ---------------------------- MPD announces system playlist update Oct 29 15:16:06 hipi volumio[998]: info: Ignoring MPD Status Update Oct 29 15:16:06 hipi volumio[998]: info: ------------------------------ 14ms Oct 29 15:16:06 hipi volumio[998]: info: sendMpdCommand play took 10 milliseconds Oct 29 15:16:06 hipi volumio[998]: info: ------------------------------ 8ms Oct 29 15:16:06 hipi volumio[998]: info: ------------------------------ 8ms Oct 29 15:16:06 hipi volumio[998]: error: Upnp client error: Error: This socket has been ended by the other party Oct 29 15:16:06 hipi volumio[998]: info: Oct 29 15:16:06 hipi volumio[998]: ---------------------------- MPD announces state update: player Oct 29 15:16:06 hipi volumio[998]: info: ControllerMpd::getState Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 29 15:16:06 hipi volumio[998]: info: Oct 29 15:16:06 hipi volumio[998]: ---------------------------- MPD announces state update: player Oct 29 15:16:06 hipi volumio[998]: info: ControllerMpd::getState Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 29 15:16:06 hipi volumio[998]: info: Oct 29 15:16:06 hipi volumio[998]: ---------------------------- MPD announces state update: player Oct 29 15:16:06 hipi volumio[998]: info: ControllerMpd::getState Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 29 15:16:06 hipi volumio[998]: info: Oct 29 15:16:06 hipi volumio[998]: ---------------------------- MPD announces state update: player Oct 29 15:16:06 hipi volumio[998]: info: sendMpdCommand status took 9 milliseconds Oct 29 15:16:06 hipi volumio[998]: info: ControllerMpd::getState Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::parseState Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 15:16:06 hipi volumio[998]: info: sendMpdCommand status took 11 milliseconds Oct 29 15:16:06 hipi volumio[998]: info: sendMpdCommand status took 11 milliseconds Oct 29 15:16:06 hipi volumio[998]: info: sendMpdCommand status took 4 milliseconds Oct 29 15:16:06 hipi volumio[998]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::parseState Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::parseState Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::parseState Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 29 15:16:06 hipi volumio[998]: info: ControllerMpd::pushState Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::servicePushState Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:06 hipi volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":163,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Neat Neat Neat","artist":"The Damned","album":"Eternally Damned: The Very Best of The Damned","uri":"USB/B2AC5264AC522361/MUSIC_PI/The Damned/Eternally Damned The Very Best of The Damned/01 Neat Neat Neat.flac","trackType":"flac"} Oct 29 15:16:06 hipi volumio[998]: verbose: CURRENT POSITION 0 Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::syncState stateService play Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::syncState currentStatus stop Oct 29 15:16:06 hipi volumio[998]: info: ------------------------------ 21ms Oct 29 15:16:06 hipi volumio[998]: info: Oct 29 15:16:06 hipi volumio[998]: ---------------------------- MPD announces state update: player Oct 29 15:16:06 hipi volumio[998]: info: ControllerMpd::getState Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 29 15:16:06 hipi volumio[998]: info: Oct 29 15:16:06 hipi volumio[998]: ---------------------------- MPD announces state update: player Oct 29 15:16:06 hipi volumio[998]: info: ControllerMpd::getState Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 29 15:16:06 hipi volumio[998]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 29 15:16:06 hipi volumio[998]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 29 15:16:06 hipi volumio[998]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 29 15:16:06 hipi volumio[998]: info: sendMpdCommand status took 3 milliseconds Oct 29 15:16:06 hipi volumio[998]: info: sendMpdCommand status took 3 milliseconds Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::parseState Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::parseState Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 15:16:06 hipi volumio[998]: info: ControllerMpd::pushState Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::servicePushState Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:06 hipi volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":163,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Neat Neat Neat","artist":"The Damned","album":"Eternally Damned: The Very Best of The Damned","uri":"USB/B2AC5264AC522361/MUSIC_PI/The Damned/Eternally Damned The Very Best of The Damned/01 Neat Neat Neat.flac","trackType":"flac"} Oct 29 15:16:06 hipi volumio[998]: verbose: CURRENT POSITION 0 Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::syncState stateService play Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 29 15:16:06 hipi volumio[998]: info: Received an update from plugin. extracting info from payload Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::pushState Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::volumioPushState Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::pushState Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::volumioPushState Oct 29 15:16:06 hipi volumio[998]: info: ControllerMpd::pushState Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::servicePushState Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:06 hipi volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":163,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Neat Neat Neat","artist":"The Damned","album":"Eternally Damned: The Very Best of The Damned","uri":"USB/B2AC5264AC522361/MUSIC_PI/The Damned/Eternally Damned The Very Best of The Damned/01 Neat Neat Neat.flac","trackType":"flac"} Oct 29 15:16:06 hipi volumio[998]: verbose: CURRENT POSITION 0 Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::syncState stateService play Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 29 15:16:06 hipi volumio[998]: info: Received an update from plugin. extracting info from payload Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::pushState Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::volumioPushState Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::pushState Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::volumioPushState Oct 29 15:16:06 hipi volumio[998]: info: ControllerMpd::pushState Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::servicePushState Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:06 hipi volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":163,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Neat Neat Neat","artist":"The Damned","album":"Eternally Damned: The Very Best of The Damned","uri":"USB/B2AC5264AC522361/MUSIC_PI/The Damned/Eternally Damned The Very Best of The Damned/01 Neat Neat Neat.flac","trackType":"flac"} Oct 29 15:16:06 hipi volumio[998]: verbose: CURRENT POSITION 0 Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::syncState stateService play Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 29 15:16:06 hipi volumio[998]: info: Received an update from plugin. extracting info from payload Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::pushState Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::volumioPushState Oct 29 15:16:06 hipi volumio[998]: info: CoreStateMachine::pushState Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::volumioPushState Oct 29 15:16:06 hipi volumio[998]: info: ------------------------------ 80ms Oct 29 15:16:06 hipi volumio[998]: info: ------------------------------ 80ms Oct 29 15:16:06 hipi volumio[998]: info: ------------------------------ 76ms Oct 29 15:16:06 hipi volumio[998]: info: sendMpdCommand playlistinfo took 60 milliseconds Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 29 15:16:06 hipi volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 29 15:16:06 hipi volumio[998]: info: ControllerMpd::pushState Oct 29 15:16:06 hipi volumio[998]: info: CoreCommandRouter::servicePushState Oct 29 15:16:06 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:06 hipi volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":163,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"583 Kbps","isStreaming":false,"title":"Neat Neat Neat","artist":"The Damned","album":"Eternally Damned: The Very Best of The Damned","uri":"USB/B2AC5264AC522361/MUSIC_PI/The Damned/Eternally Damned The Very Best of The Damned/01 Neat Neat Neat.flac","trackType":"flac"} Oct 29 15:16:06 hipi volumio[998]: verbose: CURRENT POSITION 0 Oct 29 15:16:07 hipi volumio[998]: info: CoreStateMachine::syncState stateService play Oct 29 15:16:07 hipi volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 29 15:16:07 hipi volumio[998]: info: Received an update from plugin. extracting info from payload Oct 29 15:16:07 hipi volumio[998]: info: CoreStateMachine::pushState Oct 29 15:16:07 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:07 hipi volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 15:16:07 hipi volumio[998]: info: CoreCommandRouter::volumioPushState Oct 29 15:16:07 hipi volumio[998]: info: CoreStateMachine::pushState Oct 29 15:16:07 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:07 hipi volumio[998]: info: CoreCommandRouter::volumioPushState Oct 29 15:16:07 hipi volumio[998]: info: ControllerMpd::pushState Oct 29 15:16:07 hipi volumio[998]: info: CoreCommandRouter::servicePushState Oct 29 15:16:07 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:07 hipi volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":163,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"583 Kbps","isStreaming":false,"title":"Neat Neat Neat","artist":"The Damned","album":"Eternally Damned: The Very Best of The Damned","uri":"USB/B2AC5264AC522361/MUSIC_PI/The Damned/Eternally Damned The Very Best of The Damned/01 Neat Neat Neat.flac","trackType":"flac"} Oct 29 15:16:07 hipi volumio[998]: verbose: CURRENT POSITION 0 Oct 29 15:16:07 hipi volumio[998]: info: CoreStateMachine::syncState stateService play Oct 29 15:16:07 hipi volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 29 15:16:07 hipi volumio[998]: info: Received an update from plugin. extracting info from payload Oct 29 15:16:07 hipi volumio[998]: info: CoreStateMachine::pushState Oct 29 15:16:07 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:07 hipi volumio[998]: info: CoreCommandRouter::volumioPushState Oct 29 15:16:07 hipi volumio[998]: info: CoreStateMachine::pushState Oct 29 15:16:07 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:07 hipi volumio[998]: info: CoreCommandRouter::volumioPushState Oct 29 15:16:07 hipi volumio[998]: info: ------------------------------ 104ms Oct 29 15:16:07 hipi volumio[998]: info: ------------------------------ 104ms Oct 29 15:16:07 hipi volumio[998]: info: [LastFM] Current track has sufficient metadata: title (Neat Neat Neat) and artist (The Damned) passed on explicitly Oct 29 15:16:07 hipi volumio[998]: info: CoreCommandRouter::volumioGetState Oct 29 15:16:07 hipi volumio[998]: info: CorePlayQueue::getTrack 0 Oct 29 15:16:07 hipi volumio[998]: [LastFM] updated "now playing" failed: { Oct 29 15:16:07 hipi volumio[998]: success: false, Oct 29 15:16:07 hipi volumio[998]: error: 'Invalid parameters - Your request is missing a required parameter' Oct 29 15:16:07 hipi volumio[998]: } Oct 29 15:16:16 hipi volumio[998]: info: [LastFM] finished init: Authenticated successfully! Oct 29 15:16:16 hipi volumio[998]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 15:16:16 hipi volumio[998]: TypeError: Cannot read property '@' of undefined Oct 29 15:16:16 hipi volumio[998]: at Object.callback (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:342:14) Oct 29 15:16:16 hipi volumio[998]: at /data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:60:13 Oct 29 15:16:16 hipi volumio[998]: at Parser. (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:308:18) Oct 29 15:16:16 hipi volumio[998]: at Parser.emit (events.js:315:20) Oct 29 15:16:16 hipi volumio[998]: at SAXParser.onerror (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:123:26) Oct 29 15:16:16 hipi volumio[998]: at emit (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:624:35) Oct 29 15:16:16 hipi volumio[998]: at error (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:653:5) Oct 29 15:16:16 hipi volumio[998]: at strictFail (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:677:7) Oct 29 15:16:16 hipi volumio[998]: at closeTag (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:871:9) Oct 29 15:16:16 hipi volumio[998]: at SAXParser.write (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:1436:13) Oct 29 15:16:16 hipi volumio[998]: at Parser.exports.Parser.Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:323:31) Oct 29 15:16:16 hipi volumio[998]: at Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:5:59) Oct 29 15:16:16 hipi volumio[998]: at IncomingMessage. (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:58:14) Oct 29 15:16:16 hipi volumio[998]: at IncomingMessage.emit (events.js:327:22) Oct 29 15:16:16 hipi volumio[998]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 29 15:16:16 hipi volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 29 15:16:16 hipi volumio[998]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 15:16:16 hipi sudo[4279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-29 15:15 Oct 29 15:16:16 hipi sudo[4279]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"