-- Logs begin at Mon 2024-05-13 04:37:42 EDT, end at Mon 2024-05-13 17:23:53 EDT. -- May 13 17:22:00 bar-music sudo[28084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 13 17:22:00 bar-music sudo[28086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 13 17:22:00 bar-music sudo[28084]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 17:22:00 bar-music sudo[28086]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 17:22:00 bar-music sudo[28084]: pam_unix(sudo:session): session closed for user root May 13 17:22:00 bar-music sudo[28086]: pam_unix(sudo:session): session closed for user root May 13 17:22:00 bar-music volumio[826]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.205 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 9 May 13 17:22:01 bar-music volumio[826]: info: CoreCommandRouter::volumioGetState May 13 17:22:01 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 13 17:22:01 bar-music volumio[826]: info: Listing playlists May 13 17:22:01 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 13 17:22:01 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 13 17:22:01 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 13 17:22:01 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 13 17:22:01 bar-music volumio[826]: info: CoreCommandRouter::volumioGetVisibleSources May 13 17:22:01 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 17:22:01 bar-music volumio[826]: info: CoreCommandRouter::volumioGetQueue May 13 17:22:01 bar-music volumio[826]: info: CoreStateMachine::getQueue May 13 17:22:01 bar-music volumio[826]: info: CorePlayQueue::getQueue May 13 17:22:02 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 13 17:22:03 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 13 17:22:03 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 17:22:03 bar-music volumio[826]: info: CURURI: artists:// May 13 17:22:03 bar-music volumio[826]: info: Preload queue cleared May 13 17:22:03 bar-music volumio[826]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Joss%20Whedon/d18f4844-32ad-4c2f-bd85-173bd834894d.jpg' May 13 17:22:09 bar-music nmbd[718]: [2024/05/13 17:22:09.190296, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 13 17:22:09 bar-music nmbd[718]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.104 for name WORKGROUP<1d>. May 13 17:22:09 bar-music nmbd[718]: This response was from IP 192.168.0.225, reporting an IP address of 192.168.0.225. May 13 17:22:12 bar-music volumio[826]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ozzy%20Osbourne/6b0c6f72-347f-4a8d-a7dc-a199f0154533.jpg' May 13 17:22:18 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 13 17:22:18 bar-music volumio[826]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 13 17:22:18 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 13 17:22:18 bar-music volumio[826]: info: Received Get System Version May 13 17:22:18 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 13 17:22:18 bar-music volumio[826]: info: Received Get System Info May 13 17:22:18 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 17:22:18 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 17:22:18 bar-music volumio[826]: info: Discovery: Getting this device information May 13 17:22:18 bar-music volumio[826]: info: CoreCommandRouter::volumioGetState May 13 17:22:18 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 17:22:44 bar-music volumio[826]: error: error May 13 17:22:44 bar-music volumio[826]: error: error May 13 17:22:44 bar-music volumio[826]: error: error May 13 17:22:44 bar-music volumio[826]: error: error May 13 17:22:44 bar-music volumio[826]: error: error May 13 17:22:44 bar-music volumio[826]: error: error May 13 17:22:44 bar-music volumio[826]: error: error May 13 17:22:44 bar-music volumio[826]: error: error May 13 17:22:44 bar-music volumio[826]: error: error May 13 17:22:44 bar-music volumio[826]: error: error May 13 17:22:45 bar-music volumio[826]: error: error May 13 17:22:45 bar-music volumio[826]: error: error May 13 17:22:45 bar-music volumio[826]: error: error May 13 17:22:45 bar-music volumio[826]: error: error May 13 17:22:45 bar-music volumio[826]: error: error May 13 17:22:45 bar-music volumio[826]: error: error May 13 17:22:45 bar-music volumio[826]: error: error May 13 17:22:45 bar-music volumio[826]: error: error May 13 17:22:54 bar-music volumio[826]: Searching plugin music_service/mpd May 13 17:22:54 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 13 17:22:54 bar-music volumio[826]: info: All search sources collected, pushing search results May 13 17:23:03 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 17:23:03 bar-music volumio[826]: info: CURURI: artists://Dog%20Fashion%20Disco May 13 17:23:03 bar-music volumio[826]: info: Preload queue cleared May 13 17:23:03 bar-music volumio[826]: info: Preload queue cleared May 13 17:23:03 bar-music volumio[826]: info: Preloading song: music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/01 - Leper Friend.mp3 May 13 17:23:03 bar-music volumio[826]: info: Preloading song: music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/02 - 9 to 5 at the Morgue.mp3 May 13 17:23:03 bar-music volumio[826]: info: Preloading song: music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/03 - Mushroom Cult.mp3 May 13 17:23:03 bar-music volumio[826]: info: Preloading song: music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/04 - Antiquity's Small Rewards.mp3 May 13 17:23:03 bar-music volumio[826]: info: Preloading song: music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/05 - Headless.mp3 May 13 17:23:03 bar-music volumio[826]: info: Preloading song: music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/06 - Corpse Is a Corpse.mp3 May 13 17:23:03 bar-music volumio[826]: info: Preloading song: music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/07 - Valley Girl Ventriloquist.mp3 May 13 17:23:03 bar-music volumio[826]: info: Preloading song: music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/08 - Pour Some Urine on Me.mp3 May 13 17:23:03 bar-music volumio[826]: info: Preloading song: music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/09 - Vertigo Motel.mp3 May 13 17:23:03 bar-music volumio[826]: info: Preloading song: music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/10 - Cartoon Autopsy.mp3 May 13 17:23:03 bar-music volumio[826]: info: Preloading song: music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/11 - Pink Riots.mp3 May 13 17:23:03 bar-music volumio[826]: info: Exploding uri music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/01 - Leper Friend.mp3 in service mpd May 13 17:23:03 bar-music volumio[826]: info: ALBUMART /albumart?cacheid=557&web=Dog%20Fashion%20Disco/Anarchists%20of%20Good%20Taste/small&path=%2Fmnt%2FUSB%2FMUSIC%2FDog%20Fashion%20Disco%2FAnarchists%20of%20Good%20Taste%2F01%20-%20Leper%20Friend.mp3&metadata=false May 13 17:23:03 bar-music volumio[826]: info: URI /mnt/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/01 - Leper Friend.mp3 May 13 17:23:03 bar-music volumio[826]: info: Exploding uri music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/02 - 9 to 5 at the Morgue.mp3 in service mpd May 13 17:23:03 bar-music volumio[826]: info: ALBUMART /albumart?cacheid=557&web=Dog%20Fashion%20Disco/Anarchists%20of%20Good%20Taste/small&path=%2Fmnt%2FUSB%2FMUSIC%2FDog%20Fashion%20Disco%2FAnarchists%20of%20Good%20Taste%2F02%20-%209%20to%205%20at%20the%20Morgue.mp3&metadata=false May 13 17:23:03 bar-music volumio[826]: info: URI /mnt/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/02 - 9 to 5 at the Morgue.mp3 May 13 17:23:03 bar-music volumio[826]: info: Exploding uri music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/03 - Mushroom Cult.mp3 in service mpd May 13 17:23:03 bar-music volumio[826]: info: ALBUMART /albumart?cacheid=557&web=Dog%20Fashion%20Disco/Anarchists%20of%20Good%20Taste/small&path=%2Fmnt%2FUSB%2FMUSIC%2FDog%20Fashion%20Disco%2FAnarchists%20of%20Good%20Taste%2F03%20-%20Mushroom%20Cult.mp3&metadata=false May 13 17:23:03 bar-music volumio[826]: info: URI /mnt/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/03 - Mushroom Cult.mp3 May 13 17:23:03 bar-music volumio[826]: info: Exploding uri music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/04 - Antiquity's Small Rewards.mp3 in service mpd May 13 17:23:03 bar-music volumio[826]: info: ALBUMART /albumart?cacheid=557&web=Dog%20Fashion%20Disco/Anarchists%20of%20Good%20Taste/small&path=%2Fmnt%2FUSB%2FMUSIC%2FDog%20Fashion%20Disco%2FAnarchists%20of%20Good%20Taste%2F04%20-%20Antiquity's%20Small%20Rewards.mp3&metadata=false May 13 17:23:03 bar-music volumio[826]: info: URI /mnt/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/04 - Antiquity's Small Rewards.mp3 May 13 17:23:03 bar-music volumio[826]: info: Exploding uri music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/05 - Headless.mp3 in service mpd May 13 17:23:03 bar-music volumio[826]: info: ALBUMART /albumart?cacheid=557&web=Dog%20Fashion%20Disco/Anarchists%20of%20Good%20Taste/small&path=%2Fmnt%2FUSB%2FMUSIC%2FDog%20Fashion%20Disco%2FAnarchists%20of%20Good%20Taste%2F05%20-%20Headless.mp3&metadata=false May 13 17:23:03 bar-music volumio[826]: info: URI /mnt/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/05 - Headless.mp3 May 13 17:23:03 bar-music volumio[826]: info: Exploding uri music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/06 - Corpse Is a Corpse.mp3 in service mpd May 13 17:23:03 bar-music volumio[826]: info: ALBUMART /albumart?cacheid=557&web=Dog%20Fashion%20Disco/Anarchists%20of%20Good%20Taste/small&path=%2Fmnt%2FUSB%2FMUSIC%2FDog%20Fashion%20Disco%2FAnarchists%20of%20Good%20Taste%2F06%20-%20Corpse%20Is%20a%20Corpse.mp3&metadata=false May 13 17:23:03 bar-music volumio[826]: info: URI /mnt/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/06 - Corpse Is a Corpse.mp3 May 13 17:23:03 bar-music volumio[826]: info: Exploding uri music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/07 - Valley Girl Ventriloquist.mp3 in service mpd May 13 17:23:03 bar-music volumio[826]: info: ALBUMART /albumart?cacheid=557&web=Dog%20Fashion%20Disco/Anarchists%20of%20Good%20Taste/small&path=%2Fmnt%2FUSB%2FMUSIC%2FDog%20Fashion%20Disco%2FAnarchists%20of%20Good%20Taste%2F07%20-%20Valley%20Girl%20Ventriloquist.mp3&metadata=false May 13 17:23:03 bar-music volumio[826]: info: URI /mnt/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/07 - Valley Girl Ventriloquist.mp3 May 13 17:23:03 bar-music volumio[826]: info: Exploding uri music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/08 - Pour Some Urine on Me.mp3 in service mpd May 13 17:23:03 bar-music volumio[826]: info: ALBUMART /albumart?cacheid=557&web=Dog%20Fashion%20Disco/Anarchists%20of%20Good%20Taste/small&path=%2Fmnt%2FUSB%2FMUSIC%2FDog%20Fashion%20Disco%2FAnarchists%20of%20Good%20Taste%2F08%20-%20Pour%20Some%20Urine%20on%20Me.mp3&metadata=false May 13 17:23:03 bar-music volumio[826]: info: URI /mnt/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/08 - Pour Some Urine on Me.mp3 May 13 17:23:03 bar-music volumio[826]: info: Exploding uri music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/09 - Vertigo Motel.mp3 in service mpd May 13 17:23:03 bar-music volumio[826]: info: ALBUMART /albumart?cacheid=557&web=Dog%20Fashion%20Disco/Anarchists%20of%20Good%20Taste/small&path=%2Fmnt%2FUSB%2FMUSIC%2FDog%20Fashion%20Disco%2FAnarchists%20of%20Good%20Taste%2F09%20-%20Vertigo%20Motel.mp3&metadata=false May 13 17:23:03 bar-music volumio[826]: info: URI /mnt/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/09 - Vertigo Motel.mp3 May 13 17:23:03 bar-music volumio[826]: info: Exploding uri music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/10 - Cartoon Autopsy.mp3 in service mpd May 13 17:23:03 bar-music volumio[826]: info: ALBUMART /albumart?cacheid=557&web=Dog%20Fashion%20Disco/Anarchists%20of%20Good%20Taste/small&path=%2Fmnt%2FUSB%2FMUSIC%2FDog%20Fashion%20Disco%2FAnarchists%20of%20Good%20Taste%2F10%20-%20Cartoon%20Autopsy.mp3&metadata=false May 13 17:23:03 bar-music volumio[826]: info: URI /mnt/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/10 - Cartoon Autopsy.mp3 May 13 17:23:03 bar-music volumio[826]: info: Exploding uri music-library/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/11 - Pink Riots.mp3 in service mpd May 13 17:23:03 bar-music volumio[826]: info: ALBUMART /albumart?cacheid=557&web=Dog%20Fashion%20Disco/Anarchists%20of%20Good%20Taste/small&path=%2Fmnt%2FUSB%2FMUSIC%2FDog%20Fashion%20Disco%2FAnarchists%20of%20Good%20Taste%2F11%20-%20Pink%20Riots.mp3&metadata=false May 13 17:23:03 bar-music volumio[826]: info: URI /mnt/USB/MUSIC/Dog Fashion Disco/Anarchists of Good Taste/11 - Pink Riots.mp3 May 13 17:23:10 bar-music volumio[826]: info: CoreCommandRouter::volumioAddQueueItems May 13 17:23:10 bar-music volumio[826]: info: CoreStateMachine::addQueueItems May 13 17:23:10 bar-music volumio[826]: info: CorePlayQueue::addQueueItems May 13 17:23:10 bar-music volumio[826]: info: Preload queue cleared May 13 17:23:10 bar-music volumio[826]: info: Adding Item to queue: artists://Dog%20Fashion%20Disco/Anarchists%20of%20Good%20Taste May 13 17:23:10 bar-music volumio[826]: info: Exploding uri artists://Dog%20Fashion%20Disco/Anarchists%20of%20Good%20Taste in service mpd May 13 17:23:10 bar-music volumio[826]: info: CoreCommandRouter::volumioPushQueue May 13 17:23:10 bar-music volumio[826]: info: CorePlayQueue::saveQueue May 13 17:23:10 bar-music volumio[826]: info: CoreStateMachine::updateTrackBlock May 13 17:23:10 bar-music volumio[826]: info: CorePlayQueue::getTrackBlock May 13 17:23:16 bar-music volumio[826]: info: CoreCommandRouter::volumioRandom May 13 17:23:16 bar-music volumio[826]: info: CoreCommandRouter::writePlayerControls May 13 17:23:16 bar-music volumio[826]: info: CoreStateMachine::setRandom true May 13 17:23:16 bar-music volumio[826]: info: CoreStateMachine::pushState May 13 17:23:16 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 17:23:16 bar-music volumio[826]: info: CoreCommandRouter::volumioPushState May 13 17:23:16 bar-music volumio[826]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 May 13 17:23:18 bar-music volumio[826]: info: CoreCommandRouter::volumioPause May 13 17:23:18 bar-music volumio[826]: info: CoreStateMachine::pause May 13 17:23:21 bar-music volumio[826]: info: CoreCommandRouter::volumioPause May 13 17:23:21 bar-music volumio[826]: info: CoreStateMachine::pause May 13 17:23:33 bar-music volumio[826]: info: CoreCommandRouter::volumioToggle May 13 17:23:33 bar-music volumio[826]: info: CoreStateMachine::pause May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: info: CoreCommandRouter::volumioToggle May 13 17:23:34 bar-music volumio[826]: info: CoreStateMachine::pause May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:34 bar-music volumio[826]: error: error May 13 17:23:36 bar-music volumio[826]: info: CoreCommandRouter::volumioToggle May 13 17:23:36 bar-music volumio[826]: info: CoreStateMachine::pause May 13 17:23:37 bar-music volumio[826]: info: CoreCommandRouter::volumioToggle May 13 17:23:37 bar-music volumio[826]: info: CoreStateMachine::pause May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::volumioPlay May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::play index 0 May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::stop May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::play index undefined May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::startPlaybackTimer May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSIC/PLOY/Rewind (Deluxe Edition) [Explicit]/01 - 9x2_10 [Explicit].mp3 May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::sendMpdCommand stop May 13 17:23:46 bar-music volumio[826]: info: sendMpdCommand stop took 3 milliseconds May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::sendMpdCommand clear May 13 17:23:46 bar-music volumio[826]: info: May 13 17:23:46 bar-music volumio[826]: ---------------------------- MPD announces system playlist update May 13 17:23:46 bar-music volumio[826]: info: Ignoring MPD Status Update May 13 17:23:46 bar-music volumio[826]: info: sendMpdCommand clear took 4 milliseconds May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIC/PLOY/Rewind (Deluxe Edition) [Explicit]/01 - 9x2_10 [Explicit].mp3" May 13 17:23:46 bar-music volumio[826]: info: May 13 17:23:46 bar-music volumio[826]: ---------------------------- MPD announces system playlist update May 13 17:23:46 bar-music volumio[826]: info: Ignoring MPD Status Update May 13 17:23:46 bar-music volumio[826]: info: May 13 17:23:46 bar-music volumio[826]: ---------------------------- MPD announces system playlist update May 13 17:23:46 bar-music volumio[826]: info: Ignoring MPD Status Update May 13 17:23:46 bar-music volumio[826]: error: updateQueue error: null May 13 17:23:46 bar-music volumio[826]: info: ------------------------------ 12ms May 13 17:23:46 bar-music volumio[826]: error: Upnp client error: Error: This socket has been ended by the other party May 13 17:23:46 bar-music volumio[826]: info: sendMpdCommand add "USB/MUSIC/PLOY/Rewind (Deluxe Edition) [Explicit]/01 - 9x2_10 [Explicit].mp3" took 23 milliseconds May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::sendMpdCommand play May 13 17:23:46 bar-music volumio[826]: info: May 13 17:23:46 bar-music volumio[826]: ---------------------------- MPD announces system playlist update May 13 17:23:46 bar-music volumio[826]: info: Ignoring MPD Status Update May 13 17:23:46 bar-music volumio[826]: info: ------------------------------ 23ms May 13 17:23:46 bar-music volumio[826]: info: ------------------------------ 22ms May 13 17:23:46 bar-music volumio[826]: info: May 13 17:23:46 bar-music volumio[826]: ---------------------------- MPD announces system playlist update May 13 17:23:46 bar-music volumio[826]: info: Ignoring MPD Status Update May 13 17:23:46 bar-music volumio[826]: info: May 13 17:23:46 bar-music volumio[826]: ---------------------------- MPD announces system playlist update May 13 17:23:46 bar-music volumio[826]: info: Ignoring MPD Status Update May 13 17:23:46 bar-music volumio[826]: info: sendMpdCommand play took 31 milliseconds May 13 17:23:46 bar-music volumio[826]: info: ------------------------------ 32ms May 13 17:23:46 bar-music volumio[826]: info: ------------------------------ 29ms May 13 17:23:46 bar-music volumio[826]: info: ------------------------------ 28ms May 13 17:23:46 bar-music volumio[826]: info: May 13 17:23:46 bar-music volumio[826]: ---------------------------- MPD announces state update: player May 13 17:23:46 bar-music volumio[826]: info: ControllerMpd::getState May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::sendMpdCommand status May 13 17:23:46 bar-music volumio[826]: info: May 13 17:23:46 bar-music volumio[826]: ---------------------------- MPD announces state update: player May 13 17:23:46 bar-music volumio[826]: info: ControllerMpd::getState May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::sendMpdCommand status May 13 17:23:46 bar-music volumio[826]: info: May 13 17:23:46 bar-music volumio[826]: ---------------------------- MPD announces state update: player May 13 17:23:46 bar-music volumio[826]: info: ControllerMpd::getState May 13 17:23:46 bar-music kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::sendMpdCommand status May 13 17:23:46 bar-music volumio[826]: info: sendMpdCommand status took 26 milliseconds May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::parseState May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 17:23:46 bar-music volumio[826]: info: May 13 17:23:46 bar-music volumio[826]: ---------------------------- MPD announces state update: player May 13 17:23:46 bar-music volumio[826]: info: ControllerMpd::getState May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::sendMpdCommand status May 13 17:23:46 bar-music volumio[826]: info: sendMpdCommand status took 33 milliseconds May 13 17:23:46 bar-music volumio[826]: info: sendMpdCommand status took 23 milliseconds May 13 17:23:46 bar-music volumio[826]: info: sendMpdCommand playlistinfo took 8 milliseconds May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::parseState May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::parseState May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::parseTrackInfo May 13 17:23:46 bar-music volumio[826]: info: ControllerMpd::pushState May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::servicePushState May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"9x2>10 [Explicit]","artist":"Ploy","album":"Rewind (Deluxe Edition) [Explicit]","uri":"USB/MUSIC/PLOY/Rewind (Deluxe Edition) [Explicit]/01 - 9x2_10 [Explicit].mp3","trackType":"mp3"} May 13 17:23:46 bar-music volumio[826]: verbose: CURRENT POSITION 0 May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::syncState stateService play May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::syncState currentStatus stop May 13 17:23:46 bar-music volumio[826]: info: ------------------------------ 58ms May 13 17:23:46 bar-music volumio[826]: info: May 13 17:23:46 bar-music volumio[826]: ---------------------------- MPD announces state update: player May 13 17:23:46 bar-music volumio[826]: info: ControllerMpd::getState May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::sendMpdCommand status May 13 17:23:46 bar-music volumio[826]: info: May 13 17:23:46 bar-music volumio[826]: ---------------------------- MPD announces state update: player May 13 17:23:46 bar-music volumio[826]: info: ControllerMpd::getState May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::sendMpdCommand status May 13 17:23:46 bar-music volumio[826]: info: sendMpdCommand status took 29 milliseconds May 13 17:23:46 bar-music volumio[826]: info: sendMpdCommand playlistinfo took 24 milliseconds May 13 17:23:46 bar-music volumio[826]: info: sendMpdCommand playlistinfo took 22 milliseconds May 13 17:23:46 bar-music volumio[826]: info: sendMpdCommand status took 7 milliseconds May 13 17:23:46 bar-music volumio[826]: info: sendMpdCommand status took 5 milliseconds May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::parseState May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::parseTrackInfo May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::parseTrackInfo May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::parseState May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::parseState May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 17:23:46 bar-music volumio[826]: info: ControllerMpd::pushState May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::servicePushState May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":822,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"9x2>10 [Explicit]","artist":"Ploy","album":"Rewind (Deluxe Edition) [Explicit]","uri":"USB/MUSIC/PLOY/Rewind (Deluxe Edition) [Explicit]/01 - 9x2_10 [Explicit].mp3","trackType":"mp3"} May 13 17:23:46 bar-music volumio[826]: verbose: CURRENT POSITION 0 May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::syncState stateService play May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::syncState currentStatus play May 13 17:23:46 bar-music volumio[826]: info: Received an update from plugin. extracting info from payload May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::pushState May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::volumioPushState May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::pushState May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::volumioPushState May 13 17:23:46 bar-music volumio[826]: info: ControllerMpd::pushState May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::servicePushState May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"9x2>10 [Explicit]","artist":"Ploy","album":"Rewind (Deluxe Edition) [Explicit]","uri":"USB/MUSIC/PLOY/Rewind (Deluxe Edition) [Explicit]/01 - 9x2_10 [Explicit].mp3","trackType":"mp3"} May 13 17:23:46 bar-music volumio[826]: verbose: CURRENT POSITION 0 May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::syncState stateService play May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::syncState currentStatus play May 13 17:23:46 bar-music volumio[826]: info: Received an update from plugin. extracting info from payload May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::pushState May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::volumioPushState May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::pushState May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::volumioPushState May 13 17:23:46 bar-music volumio[826]: info: ------------------------------ 118ms May 13 17:23:46 bar-music volumio[826]: info: ------------------------------ 118ms May 13 17:23:46 bar-music volumio[826]: info: sendMpdCommand playlistinfo took 54 milliseconds May 13 17:23:46 bar-music volumio[826]: info: sendMpdCommand playlistinfo took 53 milliseconds May 13 17:23:46 bar-music volumio[826]: info: sendMpdCommand playlistinfo took 53 milliseconds May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::parseTrackInfo May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::parseTrackInfo May 13 17:23:46 bar-music volumio[826]: verbose: ControllerMpd::parseTrackInfo May 13 17:23:46 bar-music volumio[826]: info: ControllerMpd::pushState May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::servicePushState May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"9x2>10 [Explicit]","artist":"Ploy","album":"Rewind (Deluxe Edition) [Explicit]","uri":"USB/MUSIC/PLOY/Rewind (Deluxe Edition) [Explicit]/01 - 9x2_10 [Explicit].mp3","trackType":"mp3"} May 13 17:23:46 bar-music volumio[826]: verbose: CURRENT POSITION 0 May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::syncState stateService play May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::syncState currentStatus play May 13 17:23:46 bar-music volumio[826]: info: Received an update from plugin. extracting info from payload May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::pushState May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::volumioPushState May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::pushState May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::volumioPushState May 13 17:23:46 bar-music volumio[826]: info: ControllerMpd::pushState May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::servicePushState May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"9x2>10 [Explicit]","artist":"Ploy","album":"Rewind (Deluxe Edition) [Explicit]","uri":"USB/MUSIC/PLOY/Rewind (Deluxe Edition) [Explicit]/01 - 9x2_10 [Explicit].mp3","trackType":"mp3"} May 13 17:23:46 bar-music volumio[826]: verbose: CURRENT POSITION 0 May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::syncState stateService play May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::syncState currentStatus play May 13 17:23:46 bar-music volumio[826]: info: Received an update from plugin. extracting info from payload May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::pushState May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::volumioPushState May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::pushState May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::volumioPushState May 13 17:23:46 bar-music volumio[826]: info: ControllerMpd::pushState May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::servicePushState May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"9x2>10 [Explicit]","artist":"Ploy","album":"Rewind (Deluxe Edition) [Explicit]","uri":"USB/MUSIC/PLOY/Rewind (Deluxe Edition) [Explicit]/01 - 9x2_10 [Explicit].mp3","trackType":"mp3"} May 13 17:23:46 bar-music volumio[826]: verbose: CURRENT POSITION 0 May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::syncState stateService play May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::syncState currentStatus play May 13 17:23:46 bar-music volumio[826]: info: Received an update from plugin. extracting info from payload May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::pushState May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::volumioPushState May 13 17:23:46 bar-music volumio[826]: info: CoreStateMachine::pushState May 13 17:23:46 bar-music volumio[826]: info: CorePlayQueue::getTrack 0 May 13 17:23:46 bar-music volumio[826]: info: CoreCommandRouter::volumioPushState May 13 17:23:46 bar-music volumio[826]: info: ------------------------------ 203ms May 13 17:23:46 bar-music volumio[826]: info: ------------------------------ 181ms May 13 17:23:46 bar-music volumio[826]: info: ------------------------------ 180ms May 13 17:23:46 bar-music volumio[826]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 May 13 17:23:46 bar-music volumio[826]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 May 13 17:23:46 bar-music volumio[826]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 May 13 17:23:46 bar-music volumio[826]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 May 13 17:23:46 bar-music volumio[826]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 May 13 17:23:46 bar-music volumio[826]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 May 13 17:23:46 bar-music volumio[826]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 May 13 17:23:46 bar-music volumio[826]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 May 13 17:23:46 bar-music volumio[826]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 May 13 17:23:46 bar-music volumio[826]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 May 13 17:23:52 bar-music volumio[826]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 17:23:52 bar-music volumio[826]: Error: connect ETIMEDOUT 199.232.38.79:80 May 13 17:23:52 bar-music volumio[826]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 13 17:23:52 bar-music volumio[826]: errno: -110, May 13 17:23:52 bar-music volumio[826]: code: 'ETIMEDOUT', May 13 17:23:52 bar-music volumio[826]: syscall: 'connect', May 13 17:23:52 bar-music volumio[826]: address: '199.232.38.79', May 13 17:23:52 bar-music volumio[826]: port: 80 May 13 17:23:52 bar-music volumio[826]: } May 13 17:23:52 bar-music volumio[826]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 17:23:53 bar-music sudo[28163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-13 17:22 May 13 17:23:53 bar-music sudo[28163]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"