-- Logs begin at Tue 2024-09-17 23:19:36 CST, end at Sun 2025-03-16 20:42:56 CST. -- Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 16 20:41:00 volumio volumio[10810]: info: Adding plugin bluetooth to MyMusic Plugins Mar 16 20:41:00 volumio volumio[10810]: info: Adding plugin multiroom to MyMusic Plugins Mar 16 20:41:00 volumio volumio[10810]: info: Adding plugin metavolumio to MyMusic Plugins Mar 16 20:41:00 volumio volumio[10810]: info: Adding plugin cd_controller to MyMusic Plugins Mar 16 20:41:00 volumio volumio[10810]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 16 20:41:00 volumio volumio[10810]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 16 20:41:00 volumio volumio[10810]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 16 20:41:01 volumio volumio[10810]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 16 20:41:01 volumio volumio[10810]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 16 20:41:01 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 20:41:01 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 20:41:01 volumio volumio[10810]: info: Starting MyVolumio Remote Streaming Endpoints Mar 16 20:41:01 volumio volumio[10810]: info: MyVolumio not started Mar 16 20:41:01 volumio volumio[10810]: info: Initializing device activation check Mar 16 20:41:01 volumio volumio[10810]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 16 20:41:01 volumio volumio[10810]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 16 20:41:01 volumio volumio[10810]: info: Starting Streaming Service Transparent Proxy Mar 16 20:41:01 volumio volumio[10810]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 16 20:41:01 volumio volumio[10810]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 16 20:41:01 volumio volumio[10810]: info: Streaming services startup Mar 16 20:41:01 volumio volumio[10810]: info: Starting Streaming Daemon Mar 16 20:41:01 volumio sudo[11117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 16 20:41:01 volumio sudo[11117]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:41:01 volumio volumio[10810]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 16 20:41:01 volumio sudo[11117]: pam_unix(sudo:session): session closed for user root Mar 16 20:41:01 volumio volumio[10810]: error: Cannot start Volumio Streaming Daemon Mar 16 20:41:01 volumio volumio[10810]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 16 20:41:01 volumio volumio[10810]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 16 20:41:02 volumio volumio[10810]: STREAMING PROXY: Starting server on port 3245 Mar 16 20:41:02 volumio volumio[10810]: Node JS runtime: 14 Mar 16 20:41:04 volumio volumio[10810]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Mar 16 20:41:06 volumio volumio[10810]: info: Discovery: adding 06f144c4-3d50-496f-b8cd-711fc1b1777c Mar 16 20:41:06 volumio volumio[10810]: info: Discovery: Found device Volumio Mar 16 20:41:06 volumio volumio[10810]: info: CoreCommandRouter::volumioGetState Mar 16 20:41:06 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:16 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 16 20:41:16 volumio volumio[10810]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 16 20:41:16 volumio volumio[10810]: info: CoreCommandRouter::volumioGetState Mar 16 20:41:16 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:16 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 20:41:16 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 20:41:16 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 16 20:41:17 volumio volumio-remote-updater[611]: No test mode Mar 16 20:41:17 volumio volumio-remote-updater[611]: No alpha test mode Mar 16 20:41:17 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 16 20:41:17 volumio volumio[10810]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

 

NEW ADDITIONS

 

","title":"Update v3.795","updateavailable":true} Mar 16 20:41:17 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 16 20:41:21 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 20:41:21 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 20:41:21 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 16 20:41:21 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 16 20:41:21 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 16 20:41:21 volumio volumio[10810]: info: CoreCommandRouter::volumioGetBrowseSources Mar 16 20:41:21 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 20:41:21 volumio volumio[10810]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 16 20:41:21 volumio volumio[10810]: info: Completed starting MyVolumio Plugin Mar 16 20:41:21 volumio volumio[10810]: info: BOOT COMPLETED Mar 16 20:41:21 volumio volumio[10810]: [Metrics] CommandRouter: 37s 918.96ms Mar 16 20:41:21 volumio volumio[10810]: info: CoreCommandRouter::volumiosetStartupVolume Mar 16 20:41:21 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 20:41:21 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 20:41:21 volumio volumio[10810]: info: CoreCommandRouter::Close All Modals sent Mar 16 20:41:21 volumio volumio[10810]: info: CoreCommandRouter::Close All Modals sent Mar 16 20:41:22 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 16 20:41:22 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 16 20:41:22 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 16 20:41:41 volumio kernel: usb 1-1.3: USB disconnect, device number 6 Mar 16 20:41:41 volumio volumio[10810]: info: Partition removed: {"syspath":"/sys/devices/platform/ff540000.usb/usb1/1-1/1-1.3/1-1.3:1.0/host2/target2:0:0/2:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-TOSHIBA_TransMemory_C4420D66667EE260100005BD-0:0-part1 /dev/disk/by-partuuid/eed6b8c0-01 /dev/disk/by-label/TOSHIBA /dev/disk/by-uuid/2A6260F56260C763 /dev/disk/by-path/platform-ff540000.usb-usb-0:1.3:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/ff540000.usb/usb1/1-1/1-1.3/1-1.3:1.0/host2/target2:0:0/2:0:0:0/block/sda/sda1","DEVTYPE":"partition","ID_BUS":"usb","ID_FS_LABEL":"TOSHIBA","ID_FS_LABEL_ENC":"TOSHIBA","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"2A6260F56260C763","ID_FS_UUID_ENC":"2A6260F56260C763","ID_INSTANCE":"0:0","ID_MODEL":"TransMemory","ID_MODEL_ENC":"TransMemory\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"140a","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"8064","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"121987200","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"eed6b8c0-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"eed6b8c0","ID_PATH":"platform-ff540000.usb-usb-0:1.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-ff540000_usb-usb-0_1_3_1_0-scsi-0_0_0_0","ID_REVISION":"PMAP","ID_SERIAL":"TOSHIBA_TransMemory_C4420D66667EE260100005BD-0:0","ID_SERIAL_SHORT":"C4420D66667EE260100005BD","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"TOSHIBA","ID_VENDOR_ENC":"TOSHIBA\\x20","ID_VENDOR_ID":"0930","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"3118","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3977314782"} Mar 16 20:41:41 volumio sudo[11185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Mar 16 20:41:41 volumio sudo[11185]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:41:41 volumio ntfs-3g[10883]: Unmounting /dev/sda1 (TOSHIBA) Mar 16 20:41:41 volumio systemd[1]: media-TOSHIBA.mount: Succeeded. Mar 16 20:41:41 volumio sudo[11185]: pam_unix(sudo:session): session closed for user root Mar 16 20:41:46 volumio volumio[10810]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/TOSHIBA Mar 16 20:41:47 volumio volumio[10810]: info: Scanning removed location : "USB/TOSHIBA" Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:47 volumio volumio[10810]: info: Mar 16 20:41:47 volumio volumio[10810]: ---------------------------- MPD announces state update: update Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:47 volumio volumio[10810]: info: Mar 16 20:41:47 volumio volumio[10810]: ---------------------------- MPD announces state update: update Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:47 volumio volumio[10810]: info: Mar 16 20:41:47 volumio volumio[10810]: ---------------------------- MPD announces state update: update Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:47 volumio volumio[10810]: info: sendMpdCommand status took 27 milliseconds Mar 16 20:41:47 volumio volumio[10810]: info: sendMpdCommand status took 26 milliseconds Mar 16 20:41:47 volumio volumio[10810]: info: sendMpdCommand status took 26 milliseconds Mar 16 20:41:47 volumio volumio[10810]: info: sendMpdCommand status took 26 milliseconds Mar 16 20:41:47 volumio volumio[10810]: info: sendMpdCommand status took 25 milliseconds Mar 16 20:41:47 volumio volumio[10810]: info: sendMpdCommand status took 24 milliseconds Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:47 volumio volumio[10810]: info: Command Router : Notfying DB Updatetrue Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:47 volumio volumio[10810]: info: Command Router : Notfying DB Updatetrue Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::Close All Modals sent Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:47 volumio volumio[10810]: info: Command Router : Notfying DB Updatetrue Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::Close All Modals sent Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:47 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: No code Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:47 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: No code Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:47 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: No code Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio kernel: usb 1-1: reset high-speed USB device number 5 using dwc2 Mar 16 20:41:47 volumio volumio[10810]: info: ------------------------------ 248ms Mar 16 20:41:47 volumio volumio[10810]: info: ------------------------------ 246ms Mar 16 20:41:47 volumio volumio[10810]: info: ------------------------------ 246ms Mar 16 20:41:47 volumio volumio[10810]: info: MPD Database updated - AlbumList cache refreshed Mar 16 20:41:47 volumio volumio[10810]: info: Mar 16 20:41:47 volumio volumio[10810]: ---------------------------- MPD announces state update: database Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:47 volumio volumio[10810]: info: Mar 16 20:41:47 volumio volumio[10810]: ---------------------------- MPD announces state update: update Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:47 volumio volumio[10810]: info: MPD Database updated - AlbumList cache refreshed Mar 16 20:41:47 volumio volumio[10810]: info: Mar 16 20:41:47 volumio volumio[10810]: ---------------------------- MPD announces state update: database Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:47 volumio volumio[10810]: info: Mar 16 20:41:47 volumio volumio[10810]: ---------------------------- MPD announces state update: update Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:47 volumio volumio[10810]: info: MPD Database updated - AlbumList cache refreshed Mar 16 20:41:47 volumio volumio[10810]: info: Mar 16 20:41:47 volumio volumio[10810]: ---------------------------- MPD announces state update: database Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:47 volumio volumio[10810]: info: Mar 16 20:41:47 volumio volumio[10810]: ---------------------------- MPD announces state update: update Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:47 volumio volumio[10810]: info: sendMpdCommand status took 134 milliseconds Mar 16 20:41:47 volumio volumio[10810]: info: sendMpdCommand status took 132 milliseconds Mar 16 20:41:47 volumio volumio[10810]: info: sendMpdCommand status took 132 milliseconds Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:47 volumio volumio[10810]: info: Command Router : Notfying DB Updatefalse Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::Close All Modals sent Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:47 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: No code Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:47 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: No code Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: ------------------------------ 214ms Mar 16 20:41:47 volumio volumio[10810]: info: ------------------------------ 214ms Mar 16 20:41:47 volumio volumio[10810]: info: sendMpdCommand status took 279 milliseconds Mar 16 20:41:47 volumio volumio[10810]: info: sendMpdCommand status took 280 milliseconds Mar 16 20:41:47 volumio volumio[10810]: info: sendMpdCommand status took 280 milliseconds Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:47 volumio volumio[10810]: info: Command Router : Notfying DB Updatefalse Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::Close All Modals sent Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:47 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: No code Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:47 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: No code Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: ------------------------------ 353ms Mar 16 20:41:47 volumio volumio[10810]: info: ------------------------------ 353ms Mar 16 20:41:47 volumio kernel: usb 1-1: USB disconnect, device number 5 Mar 16 20:41:47 volumio volumio[10810]: info: sendMpdCommand status took 422 milliseconds Mar 16 20:41:47 volumio volumio[10810]: info: sendMpdCommand status took 421 milliseconds Mar 16 20:41:47 volumio volumio[10810]: info: sendMpdCommand status took 422 milliseconds Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:47 volumio volumio[10810]: info: Command Router : Notfying DB Updatefalse Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::Close All Modals sent Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:47 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:47 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: No code Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:47 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: No code Mar 16 20:41:47 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:47 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:47 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:47 volumio volumio[10810]: info: ------------------------------ 501ms Mar 16 20:41:47 volumio volumio[10810]: info: ------------------------------ 500ms Mar 16 20:41:48 volumio kernel: usb 1-1: new high-speed USB device number 7 using dwc2 Mar 16 20:41:48 volumio kernel: usb 1-1: New USB device found, idVendor=05e3, idProduct=0610 Mar 16 20:41:48 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Mar 16 20:41:48 volumio kernel: usb 1-1: Product: USB2.0 Hub Mar 16 20:41:48 volumio kernel: hub 1-1:1.0: USB hub found Mar 16 20:41:48 volumio kernel: hub 1-1:1.0: 4 ports detected Mar 16 20:41:48 volumio kernel: usb 1-1.2: new high-speed USB device number 8 using dwc2 Mar 16 20:41:48 volumio kernel: usb 1-1.2: New USB device found, idVendor=0930, idProduct=140a Mar 16 20:41:48 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 16 20:41:48 volumio kernel: usb 1-1.2: Product: TransMemory Mar 16 20:41:48 volumio kernel: usb 1-1.2: Manufacturer: TOSHIBA Mar 16 20:41:48 volumio kernel: usb 1-1.2: SerialNumber: C4420D66667EE260100005BD Mar 16 20:41:48 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Mar 16 20:41:48 volumio kernel: scsi host3: usb-storage 1-1.2:1.0 Mar 16 20:41:49 volumio kernel: scsi 3:0:0:0: Direct-Access TOSHIBA TransMemory PMAP PQ: 0 ANSI: 6 Mar 16 20:41:49 volumio kernel: sd 3:0:0:0: [sda] 121995264 512-byte logical blocks: (62.5 GB/58.2 GiB) Mar 16 20:41:49 volumio kernel: sd 3:0:0:0: [sda] Write Protect is off Mar 16 20:41:49 volumio kernel: sd 3:0:0:0: [sda] Mode Sense: 45 00 00 00 Mar 16 20:41:49 volumio kernel: sd 3:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Mar 16 20:41:49 volumio kernel: sda: sda1 Mar 16 20:41:49 volumio kernel: sd 3:0:0:0: [sda] Attached SCSI removable disk Mar 16 20:41:49 volumio volumio[10810]: info: Mounting Device TOSHIBA Mar 16 20:41:49 volumio sudo[11222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/TOSHIBA -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 16 20:41:49 volumio sudo[11222]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:41:50 volumio sudo[11222]: pam_unix(sudo:session): session closed for user root Mar 16 20:41:50 volumio ntfs-3g[11229]: Version 2017.3.23AR.3 integrated FUSE 28 Mar 16 20:41:50 volumio ntfs-3g[11229]: Mounted /dev/sda1 (Read-Write, label "TOSHIBA", NTFS 3.1) Mar 16 20:41:50 volumio ntfs-3g[11229]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 16 20:41:50 volumio ntfs-3g[11229]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Mar 16 20:41:50 volumio ntfs-3g[11229]: Global ownership and permissions enforced, configuration type 7 Mar 16 20:41:50 volumio volumio[10810]: info: Scanning new location : "USB/TOSHIBA" Mar 16 20:41:50 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:50 volumio volumio[10810]: info: Mar 16 20:41:50 volumio volumio[10810]: ---------------------------- MPD announces state update: update Mar 16 20:41:50 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:50 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:50 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:50 volumio volumio[10810]: info: Mar 16 20:41:50 volumio volumio[10810]: ---------------------------- MPD announces state update: update Mar 16 20:41:50 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:50 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:50 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:50 volumio volumio[10810]: info: Mar 16 20:41:50 volumio volumio[10810]: ---------------------------- MPD announces state update: update Mar 16 20:41:50 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:50 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:50 volumio volumio[10810]: info: sendMpdCommand status took 8 milliseconds Mar 16 20:41:50 volumio volumio[10810]: info: sendMpdCommand status took 6 milliseconds Mar 16 20:41:50 volumio volumio[10810]: info: sendMpdCommand status took 6 milliseconds Mar 16 20:41:50 volumio volumio[10810]: info: sendMpdCommand status took 5 milliseconds Mar 16 20:41:50 volumio volumio[10810]: info: sendMpdCommand status took 4 milliseconds Mar 16 20:41:50 volumio volumio[10810]: info: sendMpdCommand status took 4 milliseconds Mar 16 20:41:50 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:50 volumio volumio[10810]: info: Command Router : Notfying DB Updatetrue Mar 16 20:41:50 volumio volumio[10810]: info: CoreCommandRouter::Close All Modals sent Mar 16 20:41:50 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:50 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:50 volumio volumio[10810]: info: Command Router : Notfying DB Updatetrue Mar 16 20:41:50 volumio volumio[10810]: info: CoreCommandRouter::Close All Modals sent Mar 16 20:41:50 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:50 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:50 volumio volumio[10810]: info: Command Router : Notfying DB Updatetrue Mar 16 20:41:50 volumio volumio[10810]: info: CoreCommandRouter::Close All Modals sent Mar 16 20:41:50 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:50 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:50 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:50 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:50 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:50 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:50 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:50 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:50 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:50 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:50 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:50 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:50 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:50 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 20:41:50 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:50 volumio volumio[10810]: info: No code Mar 16 20:41:50 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:50 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:50 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:50 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:50 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:50 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:50 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:50 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:50 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:50 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:50 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:50 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:50 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:50 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:50 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:50 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:50 volumio volumio[10810]: info: No code Mar 16 20:41:50 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:50 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:50 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:50 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:50 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:50 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:50 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:50 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:50 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:50 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:50 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:50 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:50 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:50 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:50 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:50 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:50 volumio volumio[10810]: info: No code Mar 16 20:41:50 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:50 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:50 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:50 volumio volumio[10810]: info: ------------------------------ 164ms Mar 16 20:41:50 volumio volumio[10810]: info: ------------------------------ 162ms Mar 16 20:41:50 volumio volumio[10810]: info: ------------------------------ 162ms Mar 16 20:41:53 volumio volumio[10810]: info: MPD Database updated - AlbumList cache refreshed Mar 16 20:41:53 volumio volumio[10810]: info: Mar 16 20:41:53 volumio volumio[10810]: ---------------------------- MPD announces state update: database Mar 16 20:41:53 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:53 volumio volumio[10810]: info: Mar 16 20:41:53 volumio volumio[10810]: ---------------------------- MPD announces state update: update Mar 16 20:41:53 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:53 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:53 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:53 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:53 volumio volumio[10810]: info: MPD Database updated - AlbumList cache refreshed Mar 16 20:41:53 volumio volumio[10810]: info: Mar 16 20:41:53 volumio volumio[10810]: ---------------------------- MPD announces state update: database Mar 16 20:41:53 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:53 volumio volumio[10810]: info: Mar 16 20:41:53 volumio volumio[10810]: ---------------------------- MPD announces state update: update Mar 16 20:41:53 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:53 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:53 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:53 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:53 volumio volumio[10810]: info: MPD Database updated - AlbumList cache refreshed Mar 16 20:41:53 volumio volumio[10810]: info: Mar 16 20:41:53 volumio volumio[10810]: ---------------------------- MPD announces state update: database Mar 16 20:41:53 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:53 volumio volumio[10810]: info: Mar 16 20:41:53 volumio volumio[10810]: ---------------------------- MPD announces state update: update Mar 16 20:41:53 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:53 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:53 volumio volumio[10810]: info: ControllerMpd::getState Mar 16 20:41:53 volumio volumio[10810]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:41:54 volumio volumio[10810]: info: sendMpdCommand status took 205 milliseconds Mar 16 20:41:54 volumio volumio[10810]: info: sendMpdCommand status took 204 milliseconds Mar 16 20:41:54 volumio volumio[10810]: info: sendMpdCommand status took 204 milliseconds Mar 16 20:41:54 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:54 volumio volumio[10810]: info: Command Router : Notfying DB Updatefalse Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::Close All Modals sent Mar 16 20:41:54 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:54 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:54 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:54 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: No code Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:54 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: No code Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: ------------------------------ 311ms Mar 16 20:41:54 volumio volumio[10810]: info: ------------------------------ 311ms Mar 16 20:41:54 volumio volumio[10810]: info: sendMpdCommand status took 437 milliseconds Mar 16 20:41:54 volumio volumio[10810]: info: sendMpdCommand status took 436 milliseconds Mar 16 20:41:54 volumio volumio[10810]: info: sendMpdCommand status took 437 milliseconds Mar 16 20:41:54 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:54 volumio volumio[10810]: info: Command Router : Notfying DB Updatefalse Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::Close All Modals sent Mar 16 20:41:54 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:54 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:54 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:54 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: No code Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:54 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: No code Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: ------------------------------ 522ms Mar 16 20:41:54 volumio volumio[10810]: info: ------------------------------ 523ms Mar 16 20:41:54 volumio volumio[10810]: info: sendMpdCommand status took 679 milliseconds Mar 16 20:41:54 volumio volumio[10810]: info: sendMpdCommand status took 678 milliseconds Mar 16 20:41:54 volumio volumio[10810]: info: sendMpdCommand status took 678 milliseconds Mar 16 20:41:54 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:54 volumio volumio[10810]: info: Command Router : Notfying DB Updatefalse Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::Close All Modals sent Mar 16 20:41:54 volumio volumio[10810]: verbose: ControllerMpd::parseState Mar 16 20:41:54 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:54 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: No code Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: ControllerMpd::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::servicePushState Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 20:41:54 volumio volumio[10810]: verbose: CURRENT POSITION 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::syncState stateService stop Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: No code Mar 16 20:41:54 volumio volumio[10810]: info: CoreStateMachine::pushState Mar 16 20:41:54 volumio volumio[10810]: info: CorePlayQueue::getTrack 0 Mar 16 20:41:54 volumio volumio[10810]: info: CoreCommandRouter::volumioPushState Mar 16 20:41:54 volumio volumio[10810]: info: ------------------------------ 749ms Mar 16 20:41:54 volumio volumio[10810]: info: ------------------------------ 750ms Mar 16 20:42:56 volumio volumio[10810]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 20:42:56 volumio volumio[10810]: Error: connect ETIMEDOUT 150.107.3.176:80 Mar 16 20:42:56 volumio volumio[10810]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 16 20:42:56 volumio volumio[10810]: errno: -110, Mar 16 20:42:56 volumio volumio[10810]: code: 'ETIMEDOUT', Mar 16 20:42:56 volumio volumio[10810]: syscall: 'connect', Mar 16 20:42:56 volumio volumio[10810]: address: '150.107.3.176', Mar 16 20:42:56 volumio volumio[10810]: port: 80 Mar 16 20:42:56 volumio volumio[10810]: } Mar 16 20:42:56 volumio volumio[10810]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 20:42:56 volumio sudo[11301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-16 20:41 Mar 16 20:42:56 volumio sudo[11301]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:19:36 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="43b87fbef9c19ede18711bdf23c439c5"