-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-07-10 18:27:42 CEST. -- Jul 10 18:26:00 ballpit volumio[951]: info: MRS: Updating multiroomSync output Jul 10 18:26:00 ballpit volumio[951]: info: MRS: Updating multiroomSync output Jul 10 18:26:01 ballpit volumio[951]: info: MRS: Updating multiroomSync output Jul 10 18:26:03 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:26:03 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:26:13 ballpit volumio[951]: info: MRS: Updating multiroomSync output Jul 10 18:26:13 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:26:13 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:26:13 ballpit volumio[951]: info: Listing playlists Jul 10 18:26:13 ballpit volumio[951]: info: Listing playlists Jul 10 18:26:13 ballpit volumio[951]: info: MRS: Updating multiroomSync output Jul 10 18:26:23 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:26:23 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:26:24 ballpit volumio[951]: info: Enabling plugin minidlna Jul 10 18:26:24 ballpit volumio[951]: info: Loading plugin "minidlna"... Jul 10 18:26:24 ballpit volumio[951]: info: PLUGIN START: minidlna Jul 10 18:26:24 ballpit volumio[951]: info: Loading i18n strings for locale en Jul 10 18:26:25 ballpit volumio[951]: info: minidlna: Found miniDLNA version 1.2.1 Jul 10 18:26:25 ballpit volumio[951]: info: minidlna: /data/minidlna.conf written Jul 10 18:26:25 ballpit volumio[951]: info: minidlna: Starting minidlna.service Jul 10 18:26:25 ballpit sudo[6732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Jul 10 18:26:25 ballpit sudo[6732]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 18:26:25 ballpit systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Jul 10 18:26:25 ballpit sudo[6732]: pam_unix(sudo:session): session closed for user root Jul 10 18:26:25 ballpit volumio[951]: info: minidlna: systemctl start minidlna.service succeeded. Jul 10 18:26:25 ballpit volumio[951]: info: Done. Jul 10 18:26:33 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:26:33 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:26:33 ballpit volumio[951]: info: Listing playlists Jul 10 18:26:33 ballpit volumio[951]: info: Listing playlists Jul 10 18:26:43 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:26:43 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:26:47 ballpit volumio[951]: info: Enabling plugin minidlna Jul 10 18:26:47 ballpit volumio[951]: info: Loading plugin "minidlna"... Jul 10 18:26:47 ballpit volumio[951]: info: Applying required configuration parameters for plugin minidlna Jul 10 18:26:47 ballpit volumio[951]: info: PLUGIN START: minidlna Jul 10 18:26:47 ballpit volumio[951]: info: Loading i18n strings for locale en Jul 10 18:26:47 ballpit volumio[951]: info: minidlna: Found miniDLNA version 1.2.1 Jul 10 18:26:47 ballpit volumio[951]: info: minidlna: Starting minidlna.service Jul 10 18:26:47 ballpit sudo[6883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Jul 10 18:26:47 ballpit sudo[6883]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 18:26:47 ballpit sudo[6883]: pam_unix(sudo:session): session closed for user root Jul 10 18:26:47 ballpit volumio[951]: info: minidlna: systemctl start minidlna.service succeeded. Jul 10 18:26:47 ballpit volumio[951]: info: Done. Jul 10 18:26:49 ballpit sudo[6893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 10 18:26:49 ballpit sudo[6893]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 18:26:50 ballpit sudo[6893]: pam_unix(sudo:session): session closed for user root Jul 10 18:26:50 ballpit sudo[6896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 18:26:50 ballpit sudo[6896]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 18:26:50 ballpit sudo[6896]: pam_unix(sudo:session): session closed for user root Jul 10 18:26:50 ballpit volumio[951]: verbose: New Socket.io Connection to 192.168.1.215 from 10.10.100.110 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Jul 10 18:26:50 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 10 18:26:50 ballpit volumio[951]: info: CoreCommandRouter::volumioGetVisibleSources Jul 10 18:26:50 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 18:26:50 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 10 18:26:50 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:26:50 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:26:50 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 10 18:26:50 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 10 18:26:50 ballpit volumio[951]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 10 18:26:50 ballpit volumio[951]: info: Received Get System Info Jul 10 18:26:50 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 18:26:50 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 18:26:50 ballpit volumio[951]: info: Discovery: Getting this device information Jul 10 18:26:50 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:26:50 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:26:50 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 18:26:50 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:26:50 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:26:50 ballpit volumio[951]: info: Listing playlists Jul 10 18:26:50 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 10 18:26:50 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 10 18:26:50 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 10 18:26:50 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 10 18:26:51 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 10 18:26:51 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 10 18:26:51 ballpit volumio[951]: info: Received Get System Info Jul 10 18:26:51 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 18:26:51 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 18:26:51 ballpit volumio[951]: info: Discovery: Getting this device information Jul 10 18:26:51 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:26:51 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:26:51 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 18:26:52 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 10 18:26:52 ballpit volumio[951]: info: Received Get System Info Jul 10 18:26:52 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 18:26:52 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 18:26:52 ballpit volumio[951]: info: Discovery: Getting this device information Jul 10 18:26:52 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:26:52 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:26:52 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 18:26:53 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:26:53 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:26:53 ballpit volumio[951]: info: Listing playlists Jul 10 18:26:53 ballpit volumio[951]: info: Listing playlists Jul 10 18:26:53 ballpit volumio[951]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 10 18:26:57 ballpit volumio[951]: info: MRS: Updating multiroomSync output Jul 10 18:26:58 ballpit volumio[951]: info: MRS: Updating multiroomSync output Jul 10 18:27:00 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 18:27:03 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:03 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:08 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 10 18:27:08 ballpit volumio[951]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 10 18:27:08 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 10 18:27:08 ballpit volumio[951]: info: Received Get System Version Jul 10 18:27:08 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 18:27:08 ballpit volumio[951]: info: Received Get System Info Jul 10 18:27:08 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 18:27:08 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 18:27:08 ballpit volumio[951]: info: Discovery: Getting this device information Jul 10 18:27:08 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:08 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:08 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 18:27:13 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:13 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:13 ballpit volumio[951]: info: Listing playlists Jul 10 18:27:13 ballpit volumio[951]: info: Listing playlists Jul 10 18:27:23 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:23 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:23 ballpit volumio[951]: info: MRS: Updating multiroomSync output Jul 10 18:27:24 ballpit volumio[951]: info: MRS: Updating multiroomSync output Jul 10 18:27:27 ballpit volumio[951]: info: Clearing queue after UPNP request Jul 10 18:27:28 ballpit volumio[951]: info: CoreStateMachine::ClearQueue Jul 10 18:27:28 ballpit volumio[951]: info: CoreStateMachine::stop Jul 10 18:27:28 ballpit volumio[951]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 18:27:28 ballpit volumio[951]: info: CorePlayQueue::clearPlayQueue Jul 10 18:27:28 ballpit volumio[951]: info: CorePlayQueue::saveQueue Jul 10 18:27:28 ballpit volumio[951]: info: CoreCommandRouter::volumioPushState Jul 10 18:27:28 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:28 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:28 ballpit volumio[951]: info: CoreCommandRouter::volumioPushQueue Jul 10 18:27:28 ballpit volumio[951]: info: Jul 10 18:27:28 ballpit volumio[951]: ---------------------------- MPD announces system playlist update Jul 10 18:27:28 ballpit volumio[951]: info: Ignoring MPD Status Update Jul 10 18:27:28 ballpit volumio[951]: error: updateQueue error: null Jul 10 18:27:28 ballpit volumio[951]: info: ------------------------------ 4ms Jul 10 18:27:28 ballpit volumio[951]: info: Jul 10 18:27:28 ballpit volumio[951]: ---------------------------- MPD announces state update: options Jul 10 18:27:28 ballpit volumio[951]: info: ControllerMpd::getState Jul 10 18:27:28 ballpit volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 10 18:27:28 ballpit volumio[951]: info: sendMpdCommand status took 1 milliseconds Jul 10 18:27:28 ballpit volumio[951]: verbose: ControllerMpd::parseState Jul 10 18:27:28 ballpit volumio[951]: info: ControllerMpd::pushState Jul 10 18:27:28 ballpit volumio[951]: info: CoreCommandRouter::servicePushState Jul 10 18:27:28 ballpit volumio[951]: info: CoreStateMachine::pushState Jul 10 18:27:28 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:28 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 18:27:28 ballpit volumio[951]: info: CoreCommandRouter::volumioPushState Jul 10 18:27:28 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:28 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:28 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:28 ballpit volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 10 18:27:28 ballpit volumio[951]: verbose: CURRENT POSITION 9 Jul 10 18:27:28 ballpit volumio[951]: info: CoreStateMachine::syncState stateService stop Jul 10 18:27:28 ballpit volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 10 18:27:28 ballpit volumio[951]: info: CoreStateMachine::pushState Jul 10 18:27:28 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:28 ballpit volumio[951]: info: CoreCommandRouter::volumioPushState Jul 10 18:27:28 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:28 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:28 ballpit volumio[951]: info: No code Jul 10 18:27:28 ballpit volumio[951]: info: CoreStateMachine::pushState Jul 10 18:27:28 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:28 ballpit volumio[951]: info: CoreCommandRouter::volumioPushState Jul 10 18:27:28 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:28 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:28 ballpit volumio[951]: info: ------------------------------ 33ms Jul 10 18:27:28 ballpit volumio[951]: info: Starting UPNP Playback Jul 10 18:27:28 ballpit volumio[951]: info: Preparing playback through UPNP Jul 10 18:27:28 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:28 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:28 ballpit volumio[951]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 10 18:27:28 ballpit volumio[951]: info: Jul 10 18:27:28 ballpit volumio[951]: ---------------------------- MPD announces system playlist update Jul 10 18:27:28 ballpit volumio[951]: info: Ignoring MPD Status Update Jul 10 18:27:28 ballpit volumio[951]: info: ------------------------------ 2ms Jul 10 18:27:28 ballpit volumio[951]: info: Jul 10 18:27:28 ballpit volumio[951]: ---------------------------- MPD announces system playlist update Jul 10 18:27:28 ballpit volumio[951]: info: Ignoring MPD Status Update Jul 10 18:27:28 ballpit volumio[951]: info: ------------------------------ 1ms Jul 10 18:27:28 ballpit volumio[951]: info: Jul 10 18:27:28 ballpit volumio[951]: ---------------------------- MPD announces system playlist update Jul 10 18:27:28 ballpit volumio[951]: info: Ignoring MPD Status Update Jul 10 18:27:28 ballpit volumio[951]: info: ------------------------------ 1ms Jul 10 18:27:28 ballpit volumio[951]: info: Jul 10 18:27:28 ballpit volumio[951]: ---------------------------- MPD announces system playlist update Jul 10 18:27:28 ballpit volumio[951]: info: Ignoring MPD Status Update Jul 10 18:27:28 ballpit volumio[951]: info: Jul 10 18:27:28 ballpit volumio[951]: ---------------------------- MPD announces system playlist update Jul 10 18:27:28 ballpit volumio[951]: info: Ignoring MPD Status Update Jul 10 18:27:28 ballpit volumio[951]: info: ------------------------------ 1ms Jul 10 18:27:28 ballpit volumio[951]: info: ------------------------------ 1ms Jul 10 18:27:28 ballpit volumio[951]: info: Jul 10 18:27:28 ballpit volumio[951]: ---------------------------- MPD announces system playlist update Jul 10 18:27:28 ballpit volumio[951]: info: Ignoring MPD Status Update Jul 10 18:27:28 ballpit volumio[951]: info: ------------------------------ 1ms Jul 10 18:27:29 ballpit volumio[951]: info: Jul 10 18:27:29 ballpit volumio[951]: ---------------------------- MPD announces state update: player Jul 10 18:27:29 ballpit volumio[951]: info: ControllerMpd::getState Jul 10 18:27:29 ballpit volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 10 18:27:29 ballpit volumio[951]: info: sendMpdCommand status took 1 milliseconds Jul 10 18:27:29 ballpit volumio[951]: verbose: ControllerMpd::parseState Jul 10 18:27:29 ballpit volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 18:27:29 ballpit volumio[951]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 18:27:29 ballpit volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 10 18:27:29 ballpit volumio[951]: info: ControllerMpd::pushState Jul 10 18:27:29 ballpit volumio[951]: info: CoreCommandRouter::servicePushState Jul 10 18:27:29 ballpit volumio[951]: info: CoreStateMachine::pushState Jul 10 18:27:29 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 18:27:29 ballpit volumio[951]: info: CoreCommandRouter::volumioPushState Jul 10 18:27:29 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:29 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:29 ballpit volumio[951]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.138:8097/flow/49HotTQf/uuid:8e00003b-c5a5-f8c9-774e-dca6324cf1d5/5178136c5282460085fbc0ebb2646a6b.flac","trackType":"flac"} Jul 10 18:27:29 ballpit volumio[951]: verbose: CURRENT POSITION 9 Jul 10 18:27:29 ballpit volumio[951]: info: CoreStateMachine::syncState stateService stop Jul 10 18:27:29 ballpit volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 10 18:27:29 ballpit volumio[951]: info: CoreStateMachine::pushState Jul 10 18:27:29 ballpit volumio[951]: info: CoreCommandRouter::volumioPushState Jul 10 18:27:29 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:29 ballpit volumio[951]: info: No code Jul 10 18:27:29 ballpit volumio[951]: info: CoreStateMachine::pushState Jul 10 18:27:29 ballpit volumio[951]: info: CoreCommandRouter::volumioPushState Jul 10 18:27:29 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:29 ballpit volumio[951]: info: ------------------------------ 28ms Jul 10 18:27:29 ballpit volumio[951]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.SkW3wyw32gXkJRhPzl60PQWuRQ63.376ab783dacba4f4168406b602780a79.state.track' Jul 10 18:27:29 ballpit volumio[951]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.SkW3wyw32gXkJRhPzl60PQWuRQ63.376ab783dacba4f4168406b602780a79.state.track' Jul 10 18:27:29 ballpit volumio[951]: info: Clearing queue after UPNP request Jul 10 18:27:30 ballpit volumio[951]: info: CoreStateMachine::ClearQueue Jul 10 18:27:30 ballpit volumio[951]: info: CoreStateMachine::stop Jul 10 18:27:30 ballpit volumio[951]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 18:27:30 ballpit volumio[951]: info: CorePlayQueue::clearPlayQueue Jul 10 18:27:30 ballpit volumio[951]: info: CorePlayQueue::saveQueue Jul 10 18:27:30 ballpit volumio[951]: info: CoreCommandRouter::volumioPushState Jul 10 18:27:30 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:30 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:30 ballpit volumio[951]: info: CoreCommandRouter::volumioPushQueue Jul 10 18:27:30 ballpit volumio[951]: info: Jul 10 18:27:30 ballpit volumio[951]: ---------------------------- MPD announces system playlist update Jul 10 18:27:30 ballpit volumio[951]: info: Ignoring MPD Status Update Jul 10 18:27:30 ballpit volumio[951]: error: updateQueue error: null Jul 10 18:27:30 ballpit volumio[951]: info: ------------------------------ 1ms Jul 10 18:27:30 ballpit volumio[951]: info: Starting UPNP Playback Jul 10 18:27:30 ballpit volumio[951]: info: Preparing playback through UPNP Jul 10 18:27:30 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:30 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:30 ballpit volumio[951]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 10 18:27:30 ballpit volumio[951]: info: Jul 10 18:27:30 ballpit volumio[951]: ---------------------------- MPD announces system playlist update Jul 10 18:27:30 ballpit volumio[951]: info: Ignoring MPD Status Update Jul 10 18:27:30 ballpit volumio[951]: info: Jul 10 18:27:30 ballpit volumio[951]: ---------------------------- MPD announces system playlist update Jul 10 18:27:30 ballpit volumio[951]: info: Ignoring MPD Status Update Jul 10 18:27:30 ballpit volumio[951]: info: ------------------------------ 2ms Jul 10 18:27:30 ballpit volumio[951]: info: ------------------------------ 1ms Jul 10 18:27:30 ballpit volumio[951]: info: Jul 10 18:27:30 ballpit volumio[951]: ---------------------------- MPD announces system playlist update Jul 10 18:27:30 ballpit volumio[951]: info: Ignoring MPD Status Update Jul 10 18:27:30 ballpit volumio[951]: info: Jul 10 18:27:30 ballpit volumio[951]: ---------------------------- MPD announces system playlist update Jul 10 18:27:30 ballpit volumio[951]: info: Ignoring MPD Status Update Jul 10 18:27:30 ballpit volumio[951]: info: ------------------------------ 1ms Jul 10 18:27:30 ballpit volumio[951]: info: ------------------------------ 1ms Jul 10 18:27:30 ballpit volumio[951]: info: Jul 10 18:27:30 ballpit volumio[951]: ---------------------------- MPD announces system playlist update Jul 10 18:27:30 ballpit volumio[951]: info: Ignoring MPD Status Update Jul 10 18:27:30 ballpit volumio[951]: info: Jul 10 18:27:30 ballpit volumio[951]: ---------------------------- MPD announces system playlist update Jul 10 18:27:30 ballpit volumio[951]: info: Ignoring MPD Status Update Jul 10 18:27:30 ballpit volumio[951]: info: ------------------------------ 2ms Jul 10 18:27:30 ballpit volumio[951]: info: ------------------------------ 3ms Jul 10 18:27:31 ballpit volumio[951]: info: Jul 10 18:27:31 ballpit volumio[951]: ---------------------------- MPD announces state update: player Jul 10 18:27:31 ballpit volumio[951]: info: ControllerMpd::getState Jul 10 18:27:31 ballpit volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 10 18:27:31 ballpit volumio[951]: info: sendMpdCommand status took 1 milliseconds Jul 10 18:27:31 ballpit volumio[951]: verbose: ControllerMpd::parseState Jul 10 18:27:31 ballpit volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 18:27:31 ballpit volumio[951]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 10 18:27:31 ballpit volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 10 18:27:31 ballpit volumio[951]: info: ControllerMpd::pushState Jul 10 18:27:31 ballpit volumio[951]: info: CoreCommandRouter::servicePushState Jul 10 18:27:31 ballpit volumio[951]: verbose: In UPNP mode Jul 10 18:27:31 ballpit volumio[951]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.138:8097/flow/poHwFGug/uuid:8e00003b-c5a5-f8c9-774e-dca6324cf1d5/5178136c5282460085fbc0ebb2646a6b.flac","trackType":"flac"} Jul 10 18:27:31 ballpit volumio[951]: verbose: CURRENT POSITION 9 Jul 10 18:27:31 ballpit volumio[951]: info: CoreStateMachine::syncState stateService pause Jul 10 18:27:31 ballpit volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 10 18:27:31 ballpit volumio[951]: info: CoreStateMachine::pushState Jul 10 18:27:31 ballpit volumio[951]: info: CoreCommandRouter::volumioPushState Jul 10 18:27:31 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:31 ballpit volumio[951]: info: ------------------------------ 13ms Jul 10 18:27:31 ballpit volumio[951]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.SkW3wyw32gXkJRhPzl60PQWuRQ63.376ab783dacba4f4168406b602780a79.state.track' Jul 10 18:27:31 ballpit volumio[951]: info: Jul 10 18:27:31 ballpit volumio[951]: ---------------------------- MPD announces state update: player Jul 10 18:27:31 ballpit volumio[951]: info: ControllerMpd::getState Jul 10 18:27:31 ballpit volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 10 18:27:31 ballpit volumio[951]: info: sendMpdCommand status took 1 milliseconds Jul 10 18:27:31 ballpit volumio[951]: verbose: ControllerMpd::parseState Jul 10 18:27:31 ballpit volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 18:27:31 ballpit volumio[951]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 18:27:31 ballpit volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 10 18:27:31 ballpit volumio[951]: info: ControllerMpd::pushState Jul 10 18:27:31 ballpit volumio[951]: info: CoreCommandRouter::servicePushState Jul 10 18:27:31 ballpit volumio[951]: verbose: In UPNP mode Jul 10 18:27:31 ballpit volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.138:8097/flow/poHwFGug/uuid:8e00003b-c5a5-f8c9-774e-dca6324cf1d5/5178136c5282460085fbc0ebb2646a6b.flac","trackType":"flac"} Jul 10 18:27:31 ballpit volumio[951]: verbose: CURRENT POSITION 9 Jul 10 18:27:31 ballpit volumio[951]: info: CoreStateMachine::syncState stateService play Jul 10 18:27:31 ballpit volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 10 18:27:31 ballpit volumio[951]: info: ------------------------------ 3ms Jul 10 18:27:32 ballpit volumio[951]: info: Jul 10 18:27:32 ballpit volumio[951]: ---------------------------- MPD announces state update: player Jul 10 18:27:32 ballpit volumio[951]: info: ControllerMpd::getState Jul 10 18:27:32 ballpit volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 10 18:27:32 ballpit volumio[951]: info: Jul 10 18:27:32 ballpit volumio[951]: ---------------------------- MPD announces state update: player Jul 10 18:27:32 ballpit volumio[951]: info: sendMpdCommand status took 2 milliseconds Jul 10 18:27:32 ballpit volumio[951]: info: ControllerMpd::getState Jul 10 18:27:32 ballpit volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 10 18:27:32 ballpit volumio[951]: verbose: ControllerMpd::parseState Jul 10 18:27:32 ballpit volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 18:27:32 ballpit volumio[951]: info: sendMpdCommand status took 1 milliseconds Jul 10 18:27:32 ballpit volumio[951]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 18:27:32 ballpit volumio[951]: verbose: ControllerMpd::parseState Jul 10 18:27:32 ballpit volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 18:27:32 ballpit volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 10 18:27:32 ballpit volumio[951]: info: ControllerMpd::pushState Jul 10 18:27:32 ballpit volumio[951]: info: CoreCommandRouter::servicePushState Jul 10 18:27:32 ballpit volumio[951]: verbose: In UPNP mode Jul 10 18:27:32 ballpit volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.138:8097/flow/poHwFGug/uuid:8e00003b-c5a5-f8c9-774e-dca6324cf1d5/5178136c5282460085fbc0ebb2646a6b.flac","trackType":"flac"} Jul 10 18:27:32 ballpit volumio[951]: verbose: CURRENT POSITION 9 Jul 10 18:27:32 ballpit volumio[951]: info: CoreStateMachine::syncState stateService play Jul 10 18:27:32 ballpit volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 10 18:27:32 ballpit volumio[951]: info: Received an update from plugin. extracting info from payload Jul 10 18:27:32 ballpit volumio[951]: info: CoreStateMachine::pushState Jul 10 18:27:32 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 18:27:32 ballpit volumio[951]: info: CoreCommandRouter::volumioPushState Jul 10 18:27:32 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:32 ballpit volumio[951]: info: CoreStateMachine::pushState Jul 10 18:27:32 ballpit volumio[951]: info: CoreCommandRouter::volumioPushState Jul 10 18:27:32 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:33 ballpit volumio[951]: info: ------------------------------ 26ms Jul 10 18:27:33 ballpit volumio[951]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 10 18:27:33 ballpit volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 10 18:27:33 ballpit volumio[951]: info: ControllerMpd::pushState Jul 10 18:27:33 ballpit volumio[951]: info: CoreCommandRouter::servicePushState Jul 10 18:27:33 ballpit volumio[951]: verbose: In UPNP mode Jul 10 18:27:33 ballpit volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.138:8097/flow/poHwFGug/uuid:8e00003b-c5a5-f8c9-774e-dca6324cf1d5/5178136c5282460085fbc0ebb2646a6b.flac","trackType":"flac"} Jul 10 18:27:33 ballpit volumio[951]: verbose: CURRENT POSITION 9 Jul 10 18:27:33 ballpit volumio[951]: info: CoreStateMachine::syncState stateService play Jul 10 18:27:33 ballpit volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 10 18:27:33 ballpit volumio[951]: info: Received an update from plugin. extracting info from payload Jul 10 18:27:33 ballpit volumio[951]: info: CoreStateMachine::pushState Jul 10 18:27:33 ballpit volumio[951]: info: CoreCommandRouter::volumioPushState Jul 10 18:27:33 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:33 ballpit volumio[951]: info: CoreStateMachine::pushState Jul 10 18:27:33 ballpit volumio[951]: info: CoreCommandRouter::volumioPushState Jul 10 18:27:33 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:33 ballpit volumio[951]: info: ------------------------------ 40ms Jul 10 18:27:33 ballpit volumio[951]: info: Jul 10 18:27:33 ballpit volumio[951]: ---------------------------- MPD announces system playlist update Jul 10 18:27:33 ballpit volumio[951]: info: Ignoring MPD Status Update Jul 10 18:27:33 ballpit volumio[951]: info: Jul 10 18:27:33 ballpit volumio[951]: ---------------------------- MPD announces state update: player Jul 10 18:27:33 ballpit volumio[951]: info: ControllerMpd::getState Jul 10 18:27:33 ballpit volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 10 18:27:33 ballpit volumio[951]: info: ------------------------------ 1ms Jul 10 18:27:33 ballpit volumio[951]: info: sendMpdCommand status took 2 milliseconds Jul 10 18:27:33 ballpit volumio[951]: verbose: ControllerMpd::parseState Jul 10 18:27:33 ballpit volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 18:27:33 ballpit volumio[951]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 10 18:27:33 ballpit volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 10 18:27:33 ballpit volumio[951]: info: ControllerMpd::pushState Jul 10 18:27:33 ballpit volumio[951]: info: CoreCommandRouter::servicePushState Jul 10 18:27:33 ballpit volumio[951]: verbose: In UPNP mode Jul 10 18:27:33 ballpit volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"597 Kbps","isStreaming":false,"title":"5178136c5282460085fbc0ebb2646a6b.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.138:8097/flow/poHwFGug/uuid:8e00003b-c5a5-f8c9-774e-dca6324cf1d5/5178136c5282460085fbc0ebb2646a6b.flac","trackType":"flac"} Jul 10 18:27:33 ballpit volumio[951]: verbose: CURRENT POSITION 9 Jul 10 18:27:33 ballpit volumio[951]: info: CoreStateMachine::syncState stateService play Jul 10 18:27:33 ballpit volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 10 18:27:33 ballpit volumio[951]: info: Received an update from plugin. extracting info from payload Jul 10 18:27:33 ballpit volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 10 18:27:33 ballpit volumio[951]: info: CoreStateMachine::pushState Jul 10 18:27:33 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:33 ballpit volumio[951]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jul 10 18:27:33 ballpit volumio[951]: info: ------------------------------ 5ms Jul 10 18:27:33 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:33 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:33 ballpit volumio[951]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jul 10 18:27:33 ballpit volumio[951]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jul 10 18:27:33 ballpit volumio[951]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jul 10 18:27:33 ballpit volumio[951]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jul 10 18:27:33 ballpit volumio[951]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jul 10 18:27:33 ballpit volumio[951]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jul 10 18:27:33 ballpit volumio[951]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jul 10 18:27:33 ballpit volumio[951]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jul 10 18:27:33 ballpit volumio[951]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jul 10 18:27:33 ballpit volumio[951]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jul 10 18:27:33 ballpit volumio[951]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jul 10 18:27:33 ballpit volumio[951]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jul 10 18:27:33 ballpit volumio[951]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jul 10 18:27:33 ballpit volumio[951]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jul 10 18:27:33 ballpit volumio[951]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jul 10 18:27:33 ballpit volumio[951]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jul 10 18:27:33 ballpit volumio[951]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jul 10 18:27:33 ballpit volumio[951]: info: Listing playlists Jul 10 18:27:33 ballpit volumio[951]: info: Listing playlists Jul 10 18:27:41 ballpit volumio[951]: info: CoreCommandRouter::volumioGetState Jul 10 18:27:41 ballpit volumio[951]: info: CorePlayQueue::getTrack 9 Jul 10 18:27:41 ballpit volumio[951]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 18:27:41 ballpit volumio[951]: TypeError: Cannot read property 'name' of undefined Jul 10 18:27:41 ballpit volumio[951]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jul 10 18:27:41 ballpit volumio[951]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jul 10 18:27:41 ballpit volumio[951]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Jul 10 18:27:41 ballpit volumio[951]: at Socket.emit (events.js:315:20) Jul 10 18:27:41 ballpit volumio[951]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 10 18:27:41 ballpit volumio[951]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 10 18:27:41 ballpit volumio[951]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 18:27:42 ballpit sudo[7155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-10 18:26 Jul 10 18:27:42 ballpit sudo[7155]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"