-- Logs begin at Thu 2026-01-08 18:56:47 PST, end at Thu 2026-01-08 22:38:45 PST. -- Jan 08 22:37:00 volumio-x86 volumio[4718]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jan 08 22:37:00 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:37:00 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 08 22:37:00 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:37:00 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 08 22:37:06 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 08 22:37:06 volumio-x86 volumio[4718]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 08 22:37:06 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:37:06 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:11 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:37:11 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:37:11 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 08 22:37:11 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 08 22:37:11 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 08 22:37:11 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetBrowseSources Jan 08 22:37:11 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 08 22:37:12 volumio-x86 volumio[4718]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 08 22:37:12 volumio-x86 volumio[4718]: info: Completed starting MyVolumio Plugin Jan 08 22:37:12 volumio-x86 volumio[4718]: [Metrics] CommandRouter: 46s 545.49ms Jan 08 22:37:12 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumiosetStartupVolume Jan 08 22:37:12 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 22:37:12 volumio-x86 volumio[4718]: info: VolumeController:: Setting startup Volume 20 Jan 08 22:37:12 volumio-x86 volumio[4718]: info: VolumeController::SetAlsaVolume20 Jan 08 22:37:12 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 08 22:37:12 volumio-x86 volumio[4718]: info: CoreCommandRouter::Close All Modals sent Jan 08 22:37:12 volumio-x86 volumio[4718]: info: CoreCommandRouter::Close All Modals sent Jan 08 22:37:12 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:12 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:12 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:37:12 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:13 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 08 22:37:13 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 08 22:37:13 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 08 22:37:13 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:37:13 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:19 volumio-x86 volumio[4718]: info: BOOT COMPLETED Jan 08 22:37:22 volumio-x86 volumio[4718]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 08 22:37:22 volumio-x86 volumio[4718]: info: Retrying Login Due to Network Error Jan 08 22:37:23 volumio-x86 volumio-remote-updater[814]: No test mode Jan 08 22:37:23 volumio-x86 volumio-remote-updater[814]: No alpha test mode Jan 08 22:37:23 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 08 22:37:24 volumio-x86 volumio[4718]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES



 

 

 



NEW ADDITIONS



 

 

 

","title":"Update v3.886","updateavailable":true} Jan 08 22:37:24 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 08 22:37:27 volumio-x86 volumio[4718]: verbose: New Socket.io Connection to 192.168.50.123:3000 from 192.168.50.41 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jan 08 22:37:28 volumio-x86 volumio[4718]: verbose: New Socket.io Connection to 192.168.50.123:3000 from 192.168.50.41 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:37:28 volumio-x86 volumio[4718]: info: Discovery: Getting this device information Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:37:28 volumio-x86 volumio[4718]: info: Discovery: Getting this device information Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:37:28 volumio-x86 volumio[4718]: info: Discovery: Getting this device information Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:37:28 volumio-x86 volumio[4718]: info: Discovery: Getting this device information Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:37:28 volumio-x86 volumio[4718]: info: Discovery: Getting this device information Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 08 22:37:28 volumio-x86 volumio[4718]: verbose: New Socket.io Connection to 192.168.50.123:3000 from 192.168.50.41 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Jan 08 22:37:28 volumio-x86 volumio[4718]: verbose: New Socket.io Connection to 192.168.50.123:3000 from 192.168.50.41 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 08 22:37:28 volumio-x86 sudo[5128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 08 22:37:28 volumio-x86 sudo[5128]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 22:37:28 volumio-x86 sudo[5133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 08 22:37:28 volumio-x86 sudo[5133]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 22:37:28 volumio-x86 sudo[5139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 08 22:37:28 volumio-x86 sudo[5139]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 22:37:28 volumio-x86 sudo[5133]: pam_unix(sudo:session): session closed for user root Jan 08 22:37:28 volumio-x86 sudo[5147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 08 22:37:28 volumio-x86 sudo[5139]: pam_unix(sudo:session): session closed for user root Jan 08 22:37:28 volumio-x86 sudo[5147]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 22:37:28 volumio-x86 sudo[5147]: pam_unix(sudo:session): session closed for user root Jan 08 22:37:28 volumio-x86 sudo[5156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 08 22:37:28 volumio-x86 sudo[5156]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 22:37:28 volumio-x86 sudo[5162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 08 22:37:28 volumio-x86 sudo[5162]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 22:37:28 volumio-x86 sudo[5156]: pam_unix(sudo:session): session closed for user root Jan 08 22:37:28 volumio-x86 sudo[5162]: pam_unix(sudo:session): session closed for user root Jan 08 22:37:28 volumio-x86 sudo[5128]: pam_unix(sudo:session): session closed for user root Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:37:28 volumio-x86 volumio[4718]: info: Discovery: Getting this device information Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:37:28 volumio-x86 volumio[4718]: info: Discovery: Getting this device information Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:37:28 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:28 volumio-x86 volumio[4718]: verbose: New Socket.io Connection to 192.168.50.123:3000 from 192.168.50.41 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Jan 08 22:37:29 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 08 22:37:29 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 08 22:37:29 volumio-x86 volumio[4718]: verbose: New Socket.io Connection to 192.168.50.123:3000 from 192.168.50.41 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Jan 08 22:37:29 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:37:29 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:37:29 volumio-x86 volumio[4718]: info: Discovery: Getting this device information Jan 08 22:37:29 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:37:29 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:29 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:37:31 volumio-x86 volumio[4718]: verbose: New Socket.io Connection to 192.168.50.123:3000 from 192.168.50.41 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Jan 08 22:37:31 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 08 22:37:31 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 08 22:37:31 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:37:31 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:37:31 volumio-x86 volumio[4718]: info: Discovery: Getting this device information Jan 08 22:37:31 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:37:31 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:31 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:37:32 volumio-x86 volumio[4718]: info: MyVolumio login type: Token Jan 08 22:37:33 volumio-x86 sudo[5175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 08 22:37:33 volumio-x86 sudo[5175]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 22:37:33 volumio-x86 sudo[5175]: pam_unix(sudo:session): session closed for user root Jan 08 22:37:33 volumio-x86 sudo[5178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 08 22:37:33 volumio-x86 sudo[5178]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 22:37:33 volumio-x86 sudo[5178]: pam_unix(sudo:session): session closed for user root Jan 08 22:37:33 volumio-x86 volumio[4718]: verbose: New Socket.io Connection to 192.168.50.123 from 192.168.50.41 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Jan 08 22:37:34 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:37:34 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:34 volumio-x86 sudo[5190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 08 22:37:34 volumio-x86 sudo[5190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 22:37:34 volumio-x86 sudo[5190]: pam_unix(sudo:session): session closed for user root Jan 08 22:37:34 volumio-x86 sudo[5193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 08 22:37:34 volumio-x86 sudo[5193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 22:37:34 volumio-x86 sudo[5193]: pam_unix(sudo:session): session closed for user root Jan 08 22:37:34 volumio-x86 volumio[4718]: verbose: New Socket.io Connection to 192.168.50.123 from 192.168.50.41 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Jan 08 22:37:34 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:37:34 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:34 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 08 22:37:34 volumio-x86 volumio[4718]: info: Listing playlists Jan 08 22:37:34 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 08 22:37:34 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 08 22:37:34 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 08 22:37:34 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 08 22:37:36 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 08 22:37:36 volumio-x86 volumio[4718]: info: Received Get System Info Jan 08 22:37:36 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 22:37:36 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 22:37:36 volumio-x86 volumio[4718]: info: Discovery: Getting this device information Jan 08 22:37:36 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:37:36 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:36 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 22:37:40 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPlay Jan 08 22:37:40 volumio-x86 volumio[4718]: info: CoreStateMachine::play index undefined Jan 08 22:37:40 volumio-x86 volumio[4718]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 22:37:40 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:40 volumio-x86 volumio[4718]: info: CoreStateMachine::startPlaybackTimer Jan 08 22:37:40 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:40 volumio-x86 volumio[4718]: info: [1767940660333] ControllerWebradio::clearAddPlayTrack Jan 08 22:37:40 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand stop Jan 08 22:37:40 volumio-x86 volumio[4718]: info: sendMpdCommand stop took 9 milliseconds Jan 08 22:37:40 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand clear Jan 08 22:37:40 volumio-x86 volumio[4718]: info: Jan 08 22:37:40 volumio-x86 volumio[4718]: ---------------------------- MPD announces system playlist update Jan 08 22:37:40 volumio-x86 volumio[4718]: info: Ignoring MPD Status Update Jan 08 22:37:40 volumio-x86 volumio[4718]: info: sendMpdCommand clear took 6 milliseconds Jan 08 22:37:40 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand load "http://stream.live.vc.bbcmedia.co.uk/bbc_world_service" Jan 08 22:37:40 volumio-x86 volumio[4718]: info: Jan 08 22:37:40 volumio-x86 volumio[4718]: ---------------------------- MPD announces system playlist update Jan 08 22:37:40 volumio-x86 volumio[4718]: info: Ignoring MPD Status Update Jan 08 22:37:40 volumio-x86 volumio[4718]: info: Jan 08 22:37:40 volumio-x86 volumio[4718]: ---------------------------- MPD announces system playlist update Jan 08 22:37:40 volumio-x86 volumio[4718]: info: Ignoring MPD Status Update Jan 08 22:37:40 volumio-x86 volumio[4718]: error: updateQueue error: null Jan 08 22:37:40 volumio-x86 volumio[4718]: info: ------------------------------ 21ms Jan 08 22:37:42 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand add "http://stream.live.vc.bbcmedia.co.uk/bbc_world_service" Jan 08 22:37:42 volumio-x86 volumio[4718]: error: updateQueue error: null Jan 08 22:37:42 volumio-x86 volumio[4718]: error: updateQueue error: null Jan 08 22:37:42 volumio-x86 volumio[4718]: info: Jan 08 22:37:42 volumio-x86 volumio[4718]: ---------------------------- MPD announces system playlist update Jan 08 22:37:42 volumio-x86 volumio[4718]: info: Ignoring MPD Status Update Jan 08 22:37:42 volumio-x86 volumio[4718]: info: ------------------------------ 2335ms Jan 08 22:37:42 volumio-x86 volumio[4718]: info: ------------------------------ 2333ms Jan 08 22:37:42 volumio-x86 volumio[4718]: info: sendMpdCommand add "http://stream.live.vc.bbcmedia.co.uk/bbc_world_service" took 7 milliseconds Jan 08 22:37:42 volumio-x86 volumio[4718]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 08 22:37:42 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand play Jan 08 22:37:42 volumio-x86 volumio[4718]: info: Jan 08 22:37:42 volumio-x86 volumio[4718]: ---------------------------- MPD announces system playlist update Jan 08 22:37:42 volumio-x86 volumio[4718]: info: Ignoring MPD Status Update Jan 08 22:37:42 volumio-x86 volumio[4718]: info: Jan 08 22:37:42 volumio-x86 volumio[4718]: ---------------------------- MPD announces system playlist update Jan 08 22:37:42 volumio-x86 volumio[4718]: info: Ignoring MPD Status Update Jan 08 22:37:42 volumio-x86 volumio[4718]: info: ------------------------------ 19ms Jan 08 22:37:42 volumio-x86 volumio[4718]: info: sendMpdCommand play took 14 milliseconds Jan 08 22:37:42 volumio-x86 volumio[4718]: info: ------------------------------ 12ms Jan 08 22:37:42 volumio-x86 volumio[4718]: info: ------------------------------ 10ms Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Jan 08 22:37:45 volumio-x86 volumio[4718]: ---------------------------- MPD announces state update: player Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::getState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand status Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Jan 08 22:37:45 volumio-x86 volumio[4718]: ---------------------------- MPD announces state update: player Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::getState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand status Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Jan 08 22:37:45 volumio-x86 volumio[4718]: ---------------------------- MPD announces state update: player Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::getState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand status Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Jan 08 22:37:45 volumio-x86 volumio[4718]: ---------------------------- MPD announces state update: player Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand status took 24 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::getState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand status Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand status took 26 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand status took 24 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Jan 08 22:37:45 volumio-x86 volumio[4718]: ---------------------------- MPD announces state update: player Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::getState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand status Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Jan 08 22:37:45 volumio-x86 volumio[4718]: ---------------------------- MPD announces state update: player Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::getState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand status Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand status took 9 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand status took 5 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseTrackInfo Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseTrackInfo Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseTrackInfo Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::servicePushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_world_service","artist":null,"album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_world_service","trackType":""} Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: CURRENT POSITION 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState stateService play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState currentStatus stop Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::servicePushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_world_service","artist":null,"album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_world_service","trackType":""} Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: CURRENT POSITION 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState stateService play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState currentStatus play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Received an update from plugin. extracting info from payload Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::servicePushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_world_service","artist":null,"album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_world_service","trackType":""} Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: CURRENT POSITION 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState stateService play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState currentStatus play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Received an update from plugin. extracting info from payload Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ------------------------------ 71ms Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ------------------------------ 113ms Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ------------------------------ 112ms Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand status took 84 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand playlistinfo took 81 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand playlistinfo took 77 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseTrackInfo Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseTrackInfo Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::servicePushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_world_service","artist":null,"album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_world_service","trackType":""} Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: CURRENT POSITION 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState stateService play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState currentStatus play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Received an update from plugin. extracting info from payload Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::servicePushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_world_service","artist":null,"album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_world_service","trackType":""} Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: CURRENT POSITION 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState stateService play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState currentStatus play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Received an update from plugin. extracting info from payload Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ------------------------------ 165ms Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ------------------------------ 157ms Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand playlistinfo took 80 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseTrackInfo Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::servicePushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_world_service","artist":null,"album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_world_service","trackType":""} Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: CURRENT POSITION 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState stateService play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState currentStatus play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Received an update from plugin. extracting info from payload Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ------------------------------ 201ms Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Jan 08 22:37:45 volumio-x86 volumio[4718]: ---------------------------- MPD announces system playlist update Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Ignoring MPD Status Update Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Jan 08 22:37:45 volumio-x86 volumio[4718]: ---------------------------- MPD announces state update: player Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::getState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand status Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Jan 08 22:37:45 volumio-x86 volumio[4718]: ---------------------------- MPD announces system playlist update Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Ignoring MPD Status Update Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Jan 08 22:37:45 volumio-x86 volumio[4718]: ---------------------------- MPD announces state update: player Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::getState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand status Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Jan 08 22:37:45 volumio-x86 volumio[4718]: ---------------------------- MPD announces system playlist update Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Ignoring MPD Status Update Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Jan 08 22:37:45 volumio-x86 volumio[4718]: ---------------------------- MPD announces state update: player Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::getState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand status Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ------------------------------ 15ms Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand status took 8 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ------------------------------ 7ms Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand status took 6 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ------------------------------ 5ms Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand status took 5 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseState Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseTrackInfo Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseTrackInfo Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::servicePushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":836,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"56 Kbps","isStreaming":false,"title":"BBC Radio","artist":"BBC Radio","album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_world_service","trackType":""} Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: CURRENT POSITION 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState stateService play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState currentStatus play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Received an update from plugin. extracting info from payload Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::servicePushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"56 Kbps","isStreaming":false,"title":"BBC Radio","artist":"BBC Radio","album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_world_service","trackType":""} Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: CURRENT POSITION 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState stateService play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState currentStatus play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Received an update from plugin. extracting info from payload Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ------------------------------ 77ms Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ------------------------------ 70ms Jan 08 22:37:45 volumio-x86 volumio[4718]: info: sendMpdCommand playlistinfo took 60 milliseconds Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseTrackInfo Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ControllerMpd::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::servicePushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"56 Kbps","isStreaming":false,"title":"BBC Radio","artist":"BBC Radio","album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_world_service","trackType":""} Jan 08 22:37:45 volumio-x86 volumio[4718]: verbose: CURRENT POSITION 0 Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState stateService play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState currentStatus play Jan 08 22:37:45 volumio-x86 volumio[4718]: info: Received an update from plugin. extracting info from payload Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:45 volumio-x86 volumio[4718]: info: ------------------------------ 95ms Jan 08 22:37:46 volumio-x86 volumio[4718]: info: Jan 08 22:37:46 volumio-x86 volumio[4718]: ---------------------------- MPD announces system playlist update Jan 08 22:37:46 volumio-x86 volumio[4718]: info: Ignoring MPD Status Update Jan 08 22:37:46 volumio-x86 volumio[4718]: info: Jan 08 22:37:46 volumio-x86 volumio[4718]: ---------------------------- MPD announces state update: player Jan 08 22:37:46 volumio-x86 volumio[4718]: info: ControllerMpd::getState Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand status Jan 08 22:37:46 volumio-x86 volumio[4718]: info: Jan 08 22:37:46 volumio-x86 volumio[4718]: ---------------------------- MPD announces system playlist update Jan 08 22:37:46 volumio-x86 volumio[4718]: info: Ignoring MPD Status Update Jan 08 22:37:46 volumio-x86 volumio[4718]: info: Jan 08 22:37:46 volumio-x86 volumio[4718]: ---------------------------- MPD announces state update: player Jan 08 22:37:46 volumio-x86 volumio[4718]: info: ControllerMpd::getState Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand status Jan 08 22:37:46 volumio-x86 volumio[4718]: info: Jan 08 22:37:46 volumio-x86 volumio[4718]: ---------------------------- MPD announces system playlist update Jan 08 22:37:46 volumio-x86 volumio[4718]: info: Ignoring MPD Status Update Jan 08 22:37:46 volumio-x86 volumio[4718]: info: Jan 08 22:37:46 volumio-x86 volumio[4718]: ---------------------------- MPD announces state update: player Jan 08 22:37:46 volumio-x86 volumio[4718]: info: ControllerMpd::getState Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand status Jan 08 22:37:46 volumio-x86 volumio[4718]: info: ------------------------------ 7ms Jan 08 22:37:46 volumio-x86 volumio[4718]: info: sendMpdCommand status took 6 milliseconds Jan 08 22:37:46 volumio-x86 volumio[4718]: info: ------------------------------ 5ms Jan 08 22:37:46 volumio-x86 volumio[4718]: info: sendMpdCommand status took 4 milliseconds Jan 08 22:37:46 volumio-x86 volumio[4718]: info: ------------------------------ 4ms Jan 08 22:37:46 volumio-x86 volumio[4718]: info: sendMpdCommand status took 3 milliseconds Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseState Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseState Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseState Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 22:37:46 volumio-x86 volumio[4718]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 08 22:37:46 volumio-x86 volumio[4718]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 08 22:37:46 volumio-x86 volumio[4718]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseTrackInfo Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: ControllerMpd::parseTrackInfo Jan 08 22:37:46 volumio-x86 volumio[4718]: info: ControllerMpd::pushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreCommandRouter::servicePushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"56 Kbps","isStreaming":false,"title":"BBC World Service Online","artist":"BBC Radio","album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_world_service","trackType":""} Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: CURRENT POSITION 0 Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState stateService play Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState currentStatus play Jan 08 22:37:46 volumio-x86 volumio[4718]: info: Received an update from plugin. extracting info from payload Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: ControllerMpd::pushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreCommandRouter::servicePushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"56 Kbps","isStreaming":false,"title":"BBC World Service Online","artist":"BBC Radio","album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_world_service","trackType":""} Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: CURRENT POSITION 0 Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState stateService play Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState currentStatus play Jan 08 22:37:46 volumio-x86 volumio[4718]: info: Received an update from plugin. extracting info from payload Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: ControllerMpd::pushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreCommandRouter::servicePushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CorePlayQueue::getTrack 0 Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"56 Kbps","isStreaming":false,"title":"BBC World Service Online","artist":"BBC Radio","album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_world_service","trackType":""} Jan 08 22:37:46 volumio-x86 volumio[4718]: verbose: CURRENT POSITION 0 Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState stateService play Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreStateMachine::syncState currentStatus play Jan 08 22:37:46 volumio-x86 volumio[4718]: info: Received an update from plugin. extracting info from payload Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:46 volumio-x86 volumio[4718]: info: ------------------------------ 107ms Jan 08 22:37:46 volumio-x86 volumio[4718]: info: ------------------------------ 106ms Jan 08 22:37:46 volumio-x86 volumio[4718]: info: ------------------------------ 105ms Jan 08 22:37:50 volumio-x86 volumio[4718]: info: VolumeController::SetAlsaVolume+ Jan 08 22:37:50 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:50 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:37:50 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:51 volumio-x86 volumio[4718]: info: VolumeController::SetAlsaVolume+ Jan 08 22:37:51 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:51 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:37:51 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:51 volumio-x86 volumio[4718]: info: VolumeController::SetAlsaVolume+ Jan 08 22:37:51 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:51 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:37:51 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:52 volumio-x86 volumio[4718]: info: VolumeController::SetAlsaVolume+ Jan 08 22:37:52 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:52 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:37:52 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:52 volumio-x86 volumio[4718]: info: VolumeController::SetAlsaVolume+ Jan 08 22:37:52 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:52 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:37:52 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:53 volumio-x86 volumio[4718]: info: VolumeController::SetAlsaVolume+ Jan 08 22:37:53 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:53 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:37:53 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:54 volumio-x86 volumio[4718]: info: VolumeController::SetAlsaVolume+ Jan 08 22:37:54 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:54 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:37:54 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:54 volumio-x86 volumio[4718]: info: VolumeController::SetAlsaVolume+ Jan 08 22:37:54 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:54 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:37:54 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:55 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:37:55 volumio-x86 sudo[5247]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jan 08 22:37:55 volumio-x86 sudo[5247]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 22:37:55 volumio-x86 volumio[4718]: info: VolumeController::SetAlsaVolume+ Jan 08 22:37:55 volumio-x86 volumio[4718]: info: CoreStateMachine::pushState Jan 08 22:37:55 volumio-x86 volumio[4718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 22:37:55 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioPushState Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: Fatal Python error: (pygame parachute) Segmentation Fault Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: Current thread 0x00007f2d7e5dc700 (most recent call first): Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/peppymeter.py", line 145 in init_display Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "./screensaver/volumio_peppymeter.py", line 113 in meter_thread Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 865 in run Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: Thread 0x00007f2d7f61e700 (most recent call first): Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "", line 917 in get_data Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "", line 818 in get_code Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "", line 724 in exec_module Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "", line 677 in _load_unlocked Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "", line 967 in _find_and_load_unlocked Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "", line 983 in _find_and_load Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3/dist-packages/requests/utils.py", line 172 in get_netrc_auth Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 449 in prepare_request Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 519 in request Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 536 in _send_request Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 297 in _connect_polling Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 195 in connect Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 277 in connect Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_random.py", line 123 in run Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: Thread 0x00007f2d7ee1d700 (most recent call first): Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/datasource.py", line 175 in get_data Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 865 in run Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: Thread 0x00007f2d85f17740 (most recent call first): Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 296 in wait Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 552 in wait Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 852 in start Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: File "./screensaver/volumio_peppymeter.py", line 172 in Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: pygame 1.9.4.post1 Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: Hello from the pygame community. https://www.pygame.org/contribute.html Jan 08 22:37:57 volumio-x86 sudo[5247]: pam_unix(sudo:session): session closed for user root Jan 08 22:37:57 volumio-x86 screensaver.sh[832]: /home/volumio/peppy_screensaver/screensaver.sh: line 28: 5247 Aborted sudo python3 ./screensaver/volumio_peppymeter.py Jan 08 22:38:02 volumio-x86 volumio[4718]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 08 22:38:02 volumio-x86 volumio[4718]: info: Retrying Login Due to Network Error Jan 08 22:38:12 volumio-x86 volumio[4718]: info: MyVolumio login type: Token Jan 08 22:38:18 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:38:18 volumio-x86 sudo[5352]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jan 08 22:38:18 volumio-x86 sudo[5352]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: Fatal Python error: (pygame parachute) Segmentation Fault Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: Current thread 0x00007f412483e700 (most recent call first): Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/peppymeter.py", line 145 in init_display Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "./screensaver/volumio_peppymeter.py", line 113 in meter_thread Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 865 in run Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: Thread 0x00007f412503f700 (most recent call first): Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "", line 917 in get_data Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "", line 818 in get_code Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "", line 724 in exec_module Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "", line 677 in _load_unlocked Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "", line 967 in _find_and_load_unlocked Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "", line 983 in _find_and_load Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3/dist-packages/requests/utils.py", line 172 in get_netrc_auth Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 449 in prepare_request Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 519 in request Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 536 in _send_request Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 297 in _connect_polling Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 195 in connect Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 277 in connect Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_random.py", line 123 in run Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: Thread 0x00007f4125880700 (most recent call first): Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/datasource.py", line 175 in get_data Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 865 in run Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: Thread 0x00007f412c179740 (most recent call first): Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 296 in wait Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 552 in wait Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 852 in start Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: File "./screensaver/volumio_peppymeter.py", line 172 in Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: pygame 1.9.4.post1 Jan 08 22:38:20 volumio-x86 screensaver.sh[832]: Hello from the pygame community. https://www.pygame.org/contribute.html Jan 08 22:38:21 volumio-x86 sudo[5352]: pam_unix(sudo:session): session closed for user root Jan 08 22:38:21 volumio-x86 screensaver.sh[832]: /home/volumio/peppy_screensaver/screensaver.sh: line 28: 5352 Aborted sudo python3 ./screensaver/volumio_peppymeter.py Jan 08 22:38:42 volumio-x86 volumio[4718]: info: CoreCommandRouter::volumioGetState Jan 08 22:38:42 volumio-x86 sudo[5409]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jan 08 22:38:42 volumio-x86 sudo[5409]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 22:38:42 volumio-x86 volumio[4718]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 08 22:38:42 volumio-x86 volumio[4718]: info: Retrying Login Due to Network Error Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: Fatal Python error: (pygame parachute) Segmentation Fault Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: Current thread 0x00007fbc56c2a700 (most recent call first): Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/peppymeter.py", line 145 in init_display Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "./screensaver/volumio_peppymeter.py", line 113 in meter_thread Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 865 in run Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: Thread 0x00007fbc5742b700 (most recent call first): Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "", line 917 in get_data Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "", line 818 in get_code Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "", line 724 in exec_module Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "", line 677 in _load_unlocked Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "", line 967 in _find_and_load_unlocked Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "", line 983 in _find_and_load Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/netrc.py", line 5 in Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "", line 219 in _call_with_frames_removed Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "", line 728 in exec_module Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "", line 677 in _load_unlocked Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "", line 967 in _find_and_load_unlocked Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "", line 983 in _find_and_load Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3/dist-packages/requests/utils.py", line 172 in get_netrc_auth Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 449 in prepare_request Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 519 in request Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 536 in _send_request Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 297 in _connect_polling Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 195 in connect Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 277 in connect Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_random.py", line 123 in run Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: Thread 0x00007fbc57c6c700 (most recent call first): Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/datasource.py", line 175 in get_data Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 865 in run Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: Thread 0x00007fbc5e565740 (most recent call first): Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 296 in wait Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 552 in wait Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "/usr/lib/python3.7/threading.py", line 852 in start Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: File "./screensaver/volumio_peppymeter.py", line 172 in Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: pygame 1.9.4.post1 Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: Hello from the pygame community. https://www.pygame.org/contribute.html Jan 08 22:38:44 volumio-x86 sudo[5409]: pam_unix(sudo:session): session closed for user root Jan 08 22:38:44 volumio-x86 screensaver.sh[832]: /home/volumio/peppy_screensaver/screensaver.sh: line 28: 5409 Aborted sudo python3 ./screensaver/volumio_peppymeter.py Jan 08 22:38:45 volumio-x86 volumio[4718]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 08 22:38:45 volumio-x86 volumio[4718]: Error: connect ETIMEDOUT 162.125.18.133:443 Jan 08 22:38:45 volumio-x86 volumio[4718]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jan 08 22:38:45 volumio-x86 volumio[4718]: errno: -110, Jan 08 22:38:45 volumio-x86 volumio[4718]: code: 'ETIMEDOUT', Jan 08 22:38:45 volumio-x86 volumio[4718]: syscall: 'connect', Jan 08 22:38:45 volumio-x86 volumio[4718]: address: '162.125.18.133', Jan 08 22:38:45 volumio-x86 volumio[4718]: port: 443 Jan 08 22:38:45 volumio-x86 volumio[4718]: } Jan 08 22:38:45 volumio-x86 volumio[4718]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 08 22:38:45 volumio-x86 sudo[5439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-08 22:37 Jan 08 22:38:45 volumio-x86 sudo[5439]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"