Jul 11 20:26:01 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 11 20:26:01 volumio dbus-daemon[820]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.101' (uid=0 pid=11049 comm="timedatectl show --property=NTPSynchronized --valu") Jul 11 20:26:01 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 11 20:26:01 volumio dbus-daemon[820]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 11 20:26:01 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 11 20:26:01 volumio setdatetime-helper.sh[11048]: Time is already synchronized. Jul 11 20:26:01 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 11 20:26:01 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 11 20:26:04 volumio volumio[10457]: info: Adding plugin bluetooth to MyMusic Plugins Jul 11 20:26:04 volumio volumio[10457]: info: Adding plugin multiroom to MyMusic Plugins Jul 11 20:26:04 volumio volumio[10457]: info: Adding plugin metavolumio to MyMusic Plugins Jul 11 20:26:04 volumio volumio[10457]: info: Adding plugin cd_controller to MyMusic Plugins Jul 11 20:26:04 volumio volumio[10457]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 11 20:26:04 volumio volumio[10457]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 11 20:26:04 volumio volumio[10457]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 11 20:26:04 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 20:26:04 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 20:26:04 volumio volumio[10457]: info: Starting MyVolumio Remote Streaming Endpoints Jul 11 20:26:04 volumio volumio[10457]: info: MyVolumio login type: Token Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 11 20:26:04 volumio volumio[10457]: info: Streaming services startup Jul 11 20:26:04 volumio volumio[10457]: info: Starting Streaming Daemon Jul 11 20:26:04 volumio sudo[11076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 11 20:26:04 volumio sudo[11076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 20:26:04 volumio volumio[10457]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 11 20:26:04 volumio sudo[11076]: pam_unix(sudo:session): session closed for user root Jul 11 20:26:04 volumio volumio[10457]: error: Cannot start Volumio Streaming Daemon Jul 11 20:26:04 volumio volumio[10457]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 11 20:26:04 volumio volumio[10457]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 11 20:26:06 volumio volumio[10457]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 11 20:26:06 volumio volumio[10457]: info: Retrying Login Due to Network Error Jul 11 20:26:16 volumio volumio[10457]: info: MyVolumio login type: Token Jul 11 20:26:16 volumio volumio[10457]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 11 20:26:16 volumio volumio[10457]: info: Retrying Login Due to Network Error Jul 11 20:26:19 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 11 20:26:19 volumio volumio[10457]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 11 20:26:19 volumio volumio[10457]: info: CoreCommandRouter::volumioGetState Jul 11 20:26:19 volumio volumio[10457]: info: CorePlayQueue::getTrack 16 Jul 11 20:26:22 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 20:26:22 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 20:26:22 volumio volumio[10457]: info: Discovery: Getting this device information Jul 11 20:26:22 volumio volumio[10457]: info: CoreCommandRouter::volumioGetState Jul 11 20:26:22 volumio volumio[10457]: info: CorePlayQueue::getTrack 16 Jul 11 20:26:22 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 20:26:22 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 20:26:22 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 20:26:22 volumio volumio[10457]: info: Discovery: Getting this device information Jul 11 20:26:22 volumio volumio[10457]: info: CoreCommandRouter::volumioGetState Jul 11 20:26:22 volumio volumio[10457]: info: CorePlayQueue::getTrack 16 Jul 11 20:26:22 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 20:26:23 volumio volumio[10457]: verbose: New Socket.io Connection to 192.168.5.242:3000 from 192.168.5.137 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jul 11 20:26:24 volumio volumio[10457]: verbose: New Socket.io Connection to 192.168.5.242 from 192.168.5.137 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 12_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/12.4 Mobile/15E148 Safari/000.0 Quip/iOS v5.5.7 (WebDialog) Engine version: 3 Transport: polling Total Clients: 9 Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::volumioGetVisibleSources Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::volumioGetBrowseSources Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::volumioGetState Jul 11 20:26:24 volumio volumio[10457]: info: CorePlayQueue::getTrack 16 Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 11 20:26:24 volumio volumio[10457]: info: Received Get System Info Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 20:26:24 volumio volumio[10457]: info: Discovery: Getting this device information Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::volumioGetState Jul 11 20:26:24 volumio volumio[10457]: info: CorePlayQueue::getTrack 16 Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::volumioGetState Jul 11 20:26:24 volumio volumio[10457]: info: CorePlayQueue::getTrack 16 Jul 11 20:26:24 volumio volumio[10457]: info: Listing playlists Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::volumioGetQueue Jul 11 20:26:24 volumio volumio[10457]: info: CoreStateMachine::getQueue Jul 11 20:26:24 volumio volumio[10457]: info: CorePlayQueue::getQueue Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 20:26:24 volumio volumio[10457]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 20:26:24 volumio volumio[10457]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 20:26:24 volumio volumio[10457]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 20:26:24 volumio volumio[10457]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 20:26:24 volumio volumio[10457]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 20:26:24 volumio volumio[10457]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 20:26:26 volumio volumio[10457]: info: MyVolumio login type: Token Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioNext Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::next Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::stop Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::stPlaybackTimer Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::updateTrackBlock Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrackBlock Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 16 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::serviceStop Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 16 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::serviceStop Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::stop Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand stop Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand stop took 9 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::play index undefined Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::startPlaybackTimer Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::clearAddPlayTracks USB/500g/mp3/处理完成/丸子呦-广寒宫.flac Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand stop Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::updateTrackBlock Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrackBlock Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand status took 6 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand stop took 3 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand status took 3 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand status took 2 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand status took 2 milliseconds Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand clear Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:26 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand clear took 2 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand add "USB/500g/mp3/处理完成/丸子呦-广寒宫.flac" Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 11 20:26:26 volumio volumio[10457]: verbose: CURRENT POSITION 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::syncState stateService stop Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus stop Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:26 volumio volumio[10457]: info: No code Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:26 volumio volumio[10457]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jul 11 20:26:26 volumio volumio[10457]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jul 11 20:26:26 volumio volumio[10457]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 25ms Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 20ms Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 20ms Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 19ms Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:26 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:26 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:26 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:26 volumio volumio[10457]: error: updateQueue error: null Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:26 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 18ms Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand add "USB/500g/mp3/处理完成/丸子呦-广寒宫.flac" took 17 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 2ms Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 3ms Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand play Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:26 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:26 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 9ms Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 8ms Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand play took 6 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 6ms Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 5ms Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:26 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 1ms Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand status took 3 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand status took 2 milliseconds Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:26 volumio volumio[10457]: info: Jul 11 20:26:26 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand status took 13 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand status took 12 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand status took 11 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand status took 4 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand status took 2 milliseconds Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"443 Kbps","isStreaming":false,"title":"广寒宫","artist":"丸子呦","album":"广寒宫","uri":"USB/500g/mp3/处理完成/丸子呦-广寒宫.flac","trackType":"flac"} Jul 11 20:26:26 volumio volumio[10457]: verbose: CURRENT POSITION 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus stop Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"443 Kbps","isStreaming":false,"title":"广寒宫","artist":"丸子呦","album":"广寒宫","uri":"USB/500g/mp3/处理完成/丸子呦-广寒宫.flac","trackType":"flac"} Jul 11 20:26:26 volumio volumio[10457]: verbose: CURRENT POSITION 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:26 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 32ms Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 37ms Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand status took 25 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 11 20:26:26 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:26 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"443 Kbps","isStreaming":false,"title":"广寒宫","artist":"丸子呦","album":"广寒宫","uri":"USB/500g/mp3/处理完成/丸子呦-广寒宫.flac","trackType":"flac"} Jul 11 20:26:26 volumio volumio[10457]: verbose: CURRENT POSITION 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:26 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"443 Kbps","isStreaming":false,"title":"广寒宫","artist":"丸子呦","album":"广寒宫","uri":"USB/500g/mp3/处理完成/丸子呦-广寒宫.flac","trackType":"flac"} Jul 11 20:26:26 volumio volumio[10457]: verbose: CURRENT POSITION 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:26 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"443 Kbps","isStreaming":false,"title":"广寒宫","artist":"丸子呦","album":"广寒宫","uri":"USB/500g/mp3/处理完成/丸子呦-广寒宫.flac","trackType":"flac"} Jul 11 20:26:26 volumio volumio[10457]: verbose: CURRENT POSITION 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:26 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"443 Kbps","isStreaming":false,"title":"广寒宫","artist":"丸子呦","album":"广寒宫","uri":"USB/500g/mp3/处理完成/丸子呦-广寒宫.flac","trackType":"flac"} Jul 11 20:26:26 volumio volumio[10457]: verbose: CURRENT POSITION 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:26 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:26 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"604 Kbps","isStreaming":false,"title":"广寒宫","artist":"丸子呦","album":"广寒宫","uri":"USB/500g/mp3/处理完成/丸子呦-广寒宫.flac","trackType":"flac"} Jul 11 20:26:26 volumio volumio[10457]: verbose: CURRENT POSITION 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:26 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:26 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:26 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:26 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 89ms Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 88ms Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 89ms Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 80ms Jul 11 20:26:26 volumio volumio[10457]: info: ------------------------------ 79ms Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:26 volumio volumio[10457]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:26 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:27 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 81 milliseconds Jul 11 20:26:27 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:27 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:27 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:27 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:27 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"604 Kbps","isStreaming":false,"title":"广寒宫","artist":"丸子呦","album":"广寒宫","uri":"USB/500g/mp3/处理完成/丸子呦-广寒宫.flac","trackType":"flac"} Jul 11 20:26:27 volumio volumio[10457]: verbose: CURRENT POSITION 674 Jul 11 20:26:27 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:27 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:27 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:27 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:27 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:27 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:27 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:27 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:27 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:27 volumio volumio[10457]: info: ------------------------------ 118ms Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:27 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioNext Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::next Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::stop Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::stPlaybackTimer Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::updateTrackBlock Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrackBlock Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::serviceStop Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 674 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::serviceStop Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::stop Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand stop Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand stop took 16 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::play index undefined Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::startPlaybackTimer Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::clearAddPlayTracks USB/500g/mp3/处理完成/Josh Vietti - A Thousand Miles.flac Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand stop Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::updateTrackBlock Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrackBlock Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand status took 3 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand stop took 3 milliseconds Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand clear Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:30 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand clear took 7 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand status took 7 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand status took 7 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand status took 7 milliseconds Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand add "USB/500g/mp3/处理完成/Josh Vietti - A Thousand Miles.flac" Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"广寒宫","artist":"丸子呦","album":"广寒宫","uri":"USB/500g/mp3/处理完成/丸子呦-广寒宫.flac","trackType":"flac"} Jul 11 20:26:30 volumio volumio[10457]: verbose: CURRENT POSITION 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState stateService stop Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus stop Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: No code Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 11 20:26:30 volumio volumio[10457]: verbose: CURRENT POSITION 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState stateService stop Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus stop Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: No code Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 11 20:26:30 volumio volumio[10457]: verbose: CURRENT POSITION 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState stateService stop Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus stop Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: No code Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 11 20:26:30 volumio volumio[10457]: verbose: CURRENT POSITION 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState stateService stop Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus stop Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: No code Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 66ms Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 59ms Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 59ms Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 59ms Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:30 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:30 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:30 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:30 volumio volumio[10457]: error: updateQueue error: null Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:30 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 60ms Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand add "USB/500g/mp3/处理完成/Josh Vietti - A Thousand Miles.flac" took 57 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 5ms Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 5ms Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 4ms Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand play Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:30 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:30 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:30 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 50ms Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand play took 49 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 48ms Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 48ms Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 47ms Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand status took 12 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand status took 12 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand status took 11 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand status took 10 milliseconds Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:30 volumio volumio[10457]: info: Jul 11 20:26:30 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand status took 6 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand status took 5 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand status took 4 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand status took 4 milliseconds Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1198 Kbps","isStreaming":false,"title":"A Thousand Miles","artist":"Josh Vietti ","album":null,"uri":"USB/500g/mp3/处理完成/Josh Vietti - A Thousand Miles.flac","trackType":"flac"} Jul 11 20:26:30 volumio volumio[10457]: verbose: CURRENT POSITION 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus stop Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1198 Kbps","isStreaming":false,"title":"A Thousand Miles","artist":"Josh Vietti ","album":null,"uri":"USB/500g/mp3/处理完成/Josh Vietti - A Thousand Miles.flac","trackType":"flac"} Jul 11 20:26:30 volumio volumio[10457]: verbose: CURRENT POSITION 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:30 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1198 Kbps","isStreaming":false,"title":"A Thousand Miles","artist":"Josh Vietti ","album":null,"uri":"USB/500g/mp3/处理完成/Josh Vietti - A Thousand Miles.flac","trackType":"flac"} Jul 11 20:26:30 volumio volumio[10457]: verbose: CURRENT POSITION 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:30 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1198 Kbps","isStreaming":false,"title":"A Thousand Miles","artist":"Josh Vietti ","album":null,"uri":"USB/500g/mp3/处理完成/Josh Vietti - A Thousand Miles.flac","trackType":"flac"} Jul 11 20:26:30 volumio volumio[10457]: verbose: CURRENT POSITION 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:30 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 32ms Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 48ms Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 48ms Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 47ms Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 60 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 59 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 59 milliseconds Jul 11 20:26:30 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 59 milliseconds Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:30 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1106,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1191 Kbps","isStreaming":false,"title":"A Thousand Miles","artist":"Josh Vietti ","album":null,"uri":"USB/500g/mp3/处理完成/Josh Vietti - A Thousand Miles.flac","trackType":"flac"} Jul 11 20:26:30 volumio volumio[10457]: verbose: CURRENT POSITION 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:30 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1106,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1191 Kbps","isStreaming":false,"title":"A Thousand Miles","artist":"Josh Vietti ","album":null,"uri":"USB/500g/mp3/处理完成/Josh Vietti - A Thousand Miles.flac","trackType":"flac"} Jul 11 20:26:30 volumio volumio[10457]: verbose: CURRENT POSITION 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:30 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1106,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1191 Kbps","isStreaming":false,"title":"A Thousand Miles","artist":"Josh Vietti ","album":null,"uri":"USB/500g/mp3/处理完成/Josh Vietti - A Thousand Miles.flac","trackType":"flac"} Jul 11 20:26:30 volumio volumio[10457]: verbose: CURRENT POSITION 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:30 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1106,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1191 Kbps","isStreaming":false,"title":"A Thousand Miles","artist":"Josh Vietti ","album":null,"uri":"USB/500g/mp3/处理完成/Josh Vietti - A Thousand Miles.flac","trackType":"flac"} Jul 11 20:26:30 volumio volumio[10457]: verbose: CURRENT POSITION 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:30 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:30 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:30 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 117ms Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 114ms Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 115ms Jul 11 20:26:30 volumio volumio[10457]: info: ------------------------------ 114ms Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:30 volumio volumio[10457]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:30 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:31 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:31 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:31 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:31 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:31 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:31 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:31 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:31 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:31 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:31 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:31 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:31 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:31 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:31 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:31 volumio volumio[10457]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 11 20:26:31 volumio volumio[10457]: info: Retrying Login Due to Network Error Jul 11 20:26:31 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioNext Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::next Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::stop Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::stPlaybackTimer Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::updateTrackBlock Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrackBlock Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::serviceStop Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 43 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::serviceStop Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::stop Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand stop Jul 11 20:26:32 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:32 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand stop took 7 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::play index undefined Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::startPlaybackTimer Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::clearAddPlayTracks USB/500g/mp3/处理完成/拾贰-失恋不怪她阵线联盟.flac Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand stop Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::updateTrackBlock Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrackBlock Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand status took 4 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand stop took 2 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand status took 2 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand status took 1 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand status took 1 milliseconds Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand clear Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:32 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand clear took 2 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand add "USB/500g/mp3/处理完成/拾贰-失恋不怪她阵线联盟.flac" Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Thousand Miles","artist":"Josh Vietti ","album":null,"uri":"USB/500g/mp3/处理完成/Josh Vietti - A Thousand Miles.flac","trackType":"flac"} Jul 11 20:26:32 volumio volumio[10457]: verbose: CURRENT POSITION 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::syncState stateService stop Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus stop Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:32 volumio volumio[10457]: info: No code Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:32 volumio volumio[10457]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jul 11 20:26:32 volumio volumio[10457]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jul 11 20:26:32 volumio volumio[10457]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jul 11 20:26:32 volumio volumio[10457]: info: ------------------------------ 21ms Jul 11 20:26:32 volumio volumio[10457]: info: ------------------------------ 19ms Jul 11 20:26:32 volumio volumio[10457]: info: ------------------------------ 19ms Jul 11 20:26:32 volumio volumio[10457]: info: ------------------------------ 18ms Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:32 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:32 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:32 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:32 volumio volumio[10457]: error: updateQueue error: null Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:32 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:32 volumio volumio[10457]: info: ------------------------------ 18ms Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand add "USB/500g/mp3/处理完成/拾贰-失恋不怪她阵线联盟.flac" took 16 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: ------------------------------ 1ms Jul 11 20:26:32 volumio volumio[10457]: info: ------------------------------ 1ms Jul 11 20:26:32 volumio volumio[10457]: info: ------------------------------ 1ms Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand play Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:32 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:32 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:26:32 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:26:32 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:32 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:32 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:32 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:32 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:32 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:32 volumio volumio[10457]: info: ------------------------------ 6ms Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand play took 6 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: ------------------------------ 6ms Jul 11 20:26:32 volumio volumio[10457]: info: ------------------------------ 5ms Jul 11 20:26:32 volumio volumio[10457]: info: ------------------------------ 5ms Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand status took 2 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:32 volumio volumio[10457]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 11 20:26:32 volumio volumio[10457]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 11 20:26:32 volumio volumio[10457]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:32 volumio volumio[10457]: info: Jul 11 20:26:32 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand status took 8 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand status took 8 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand status took 9 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand status took 9 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand status took 4 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand status took 3 milliseconds Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"720 Kbps","isStreaming":false,"title":"失恋不怪她阵线联盟","artist":"拾贰","album":"失恋不怪她阵线联盟 (完整版)","uri":"USB/500g/mp3/处理完成/拾贰-失恋不怪她阵线联盟.flac","trackType":"flac"} Jul 11 20:26:32 volumio volumio[10457]: verbose: CURRENT POSITION 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus stop Jul 11 20:26:32 volumio volumio[10457]: info: ------------------------------ 14ms Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand status took 5 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 20:26:32 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:32 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"720 Kbps","isStreaming":false,"title":"失恋不怪她阵线联盟","artist":"拾贰","album":"失恋不怪她阵线联盟 (完整版)","uri":"USB/500g/mp3/处理完成/拾贰-失恋不怪她阵线联盟.flac","trackType":"flac"} Jul 11 20:26:32 volumio volumio[10457]: verbose: CURRENT POSITION 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:32 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"720 Kbps","isStreaming":false,"title":"失恋不怪她阵线联盟","artist":"拾贰","album":"失恋不怪她阵线联盟 (完整版)","uri":"USB/500g/mp3/处理完成/拾贰-失恋不怪她阵线联盟.flac","trackType":"flac"} Jul 11 20:26:32 volumio volumio[10457]: verbose: CURRENT POSITION 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:32 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"720 Kbps","isStreaming":false,"title":"失恋不怪她阵线联盟","artist":"拾贰","album":"失恋不怪她阵线联盟 (完整版)","uri":"USB/500g/mp3/处理完成/拾贰-失恋不怪她阵线联盟.flac","trackType":"flac"} Jul 11 20:26:32 volumio volumio[10457]: verbose: CURRENT POSITION 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:32 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"720 Kbps","isStreaming":false,"title":"失恋不怪她阵线联盟","artist":"拾贰","album":"失恋不怪她阵线联盟 (完整版)","uri":"USB/500g/mp3/处理完成/拾贰-失恋不怪她阵线联盟.flac","trackType":"flac"} Jul 11 20:26:32 volumio volumio[10457]: verbose: CURRENT POSITION 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:32 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"720 Kbps","isStreaming":false,"title":"失恋不怪她阵线联盟","artist":"拾贰","album":"失恋不怪她阵线联盟 (完整版)","uri":"USB/500g/mp3/处理完成/拾贰-失恋不怪她阵线联盟.flac","trackType":"flac"} Jul 11 20:26:32 volumio volumio[10457]: verbose: CURRENT POSITION 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:32 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:32 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"720 Kbps","isStreaming":false,"title":"失恋不怪她阵线联盟","artist":"拾贰","album":"失恋不怪她阵线联盟 (完整版)","uri":"USB/500g/mp3/处理完成/拾贰-失恋不怪她阵线联盟.flac","trackType":"flac"} Jul 11 20:26:32 volumio volumio[10457]: verbose: CURRENT POSITION 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:32 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:32 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:32 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:32 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:33 volumio volumio[10457]: info: ------------------------------ 71ms Jul 11 20:26:33 volumio volumio[10457]: info: ------------------------------ 70ms Jul 11 20:26:33 volumio volumio[10457]: info: ------------------------------ 70ms Jul 11 20:26:33 volumio volumio[10457]: info: ------------------------------ 71ms Jul 11 20:26:33 volumio volumio[10457]: info: ------------------------------ 67ms Jul 11 20:26:33 volumio volumio[10457]: info: ------------------------------ 66ms Jul 11 20:26:33 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 59 milliseconds Jul 11 20:26:33 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:33 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:33 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:33 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:33 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"720 Kbps","isStreaming":false,"title":"失恋不怪她阵线联盟","artist":"拾贰","album":"失恋不怪她阵线联盟 (完整版)","uri":"USB/500g/mp3/处理完成/拾贰-失恋不怪她阵线联盟.flac","trackType":"flac"} Jul 11 20:26:33 volumio volumio[10457]: verbose: CURRENT POSITION 664 Jul 11 20:26:33 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:26:33 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:26:33 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:26:33 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:33 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:33 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:33 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:33 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:33 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:33 volumio volumio[10457]: info: ------------------------------ 75ms Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:33 volumio volumio[10457]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:33 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:26:41 volumio volumio[10457]: info: MyVolumio login type: Token Jul 11 20:26:42 volumio volumio[10457]: info: CoreCommandRouter::volumioPause Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::pause Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::stPlaybackTimer Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::servicePause Jul 11 20:26:42 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:42 volumio volumio[10457]: info: CoreCommandRouter::servicePause Jul 11 20:26:42 volumio volumio[10457]: info: ControllerMpd::pause Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand pause Jul 11 20:26:42 volumio volumio[10457]: info: Jul 11 20:26:42 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:42 volumio volumio[10457]: info: sendMpdCommand pause took 0 milliseconds Jul 11 20:26:42 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:42 volumio volumio[10457]: info: Jul 11 20:26:42 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:42 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:42 volumio volumio[10457]: info: sendMpdCommand status took 1 milliseconds Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:42 volumio volumio[10457]: info: Jul 11 20:26:42 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:42 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:42 volumio volumio[10457]: info: Jul 11 20:26:42 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:26:42 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:26:42 volumio volumio[10457]: info: sendMpdCommand status took 1 milliseconds Jul 11 20:26:42 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 11 20:26:42 volumio volumio[10457]: info: sendMpdCommand status took 1 milliseconds Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:42 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:42 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:42 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:42 volumio volumio[10457]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10082,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1034 Kbps","isStreaming":false,"title":"失恋不怪她阵线联盟","artist":"拾贰","album":"失恋不怪她阵线联盟 (完整版)","uri":"USB/500g/mp3/处理完成/拾贰-失恋不怪她阵线联盟.flac","trackType":"flac"} Jul 11 20:26:42 volumio volumio[10457]: verbose: CURRENT POSITION 664 Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::syncState stateService pause Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus pause Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:42 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:42 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:26:42 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::stPlaybackTimer Jul 11 20:26:42 volumio volumio[10457]: info: ------------------------------ 7ms Jul 11 20:26:42 volumio volumio[10457]: info: sendMpdCommand status took 6 milliseconds Jul 11 20:26:42 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 11 20:26:42 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:42 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:42 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:42 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:42 volumio volumio[10457]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10082,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1034 Kbps","isStreaming":false,"title":"失恋不怪她阵线联盟","artist":"拾贰","album":"失恋不怪她阵线联盟 (完整版)","uri":"USB/500g/mp3/处理完成/拾贰-失恋不怪她阵线联盟.flac","trackType":"flac"} Jul 11 20:26:42 volumio volumio[10457]: verbose: CURRENT POSITION 664 Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::syncState stateService pause Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus pause Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:42 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:42 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::stPlaybackTimer Jul 11 20:26:42 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:42 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:42 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:42 volumio volumio[10457]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10082,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1034 Kbps","isStreaming":false,"title":"失恋不怪她阵线联盟","artist":"拾贰","album":"失恋不怪她阵线联盟 (完整版)","uri":"USB/500g/mp3/处理完成/拾贰-失恋不怪她阵线联盟.flac","trackType":"flac"} Jul 11 20:26:42 volumio volumio[10457]: verbose: CURRENT POSITION 664 Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::syncState stateService pause Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus pause Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:42 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:42 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::stPlaybackTimer Jul 11 20:26:42 volumio volumio[10457]: info: ------------------------------ 18ms Jul 11 20:26:42 volumio volumio[10457]: info: ------------------------------ 19ms Jul 11 20:26:42 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:42 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:42 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:42 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:42 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:42 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:42 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 11 20:26:42 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:26:42 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:26:42 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:26:42 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:42 volumio volumio[10457]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10082,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1034 Kbps","isStreaming":false,"title":"失恋不怪她阵线联盟","artist":"拾贰","album":"失恋不怪她阵线联盟 (完整版)","uri":"USB/500g/mp3/处理完成/拾贰-失恋不怪她阵线联盟.flac","trackType":"flac"} Jul 11 20:26:42 volumio volumio[10457]: verbose: CURRENT POSITION 664 Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::syncState stateService pause Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus pause Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:42 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:42 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:42 volumio volumio[10457]: info: CoreStateMachine::stPlaybackTimer Jul 11 20:26:42 volumio volumio[10457]: info: ------------------------------ 31ms Jul 11 20:26:42 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:42 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:42 volumio volumio[10457]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 11 20:26:42 volumio volumio[10457]: info: Retrying Login Due to Network Error Jul 11 20:26:44 volumio volumio[10457]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 20:26:44 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 11 20:26:44 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 20:26:44 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 11 20:26:44 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 20:26:44 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 20:26:44 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 20:26:44 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 20:26:44 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 20:26:44 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 20:26:44 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 20:26:44 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 11 20:26:44 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 11 20:26:45 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 20:26:45 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 20:26:45 volumio volumio[10457]: info: Discovery: Getting this device information Jul 11 20:26:45 volumio volumio[10457]: info: CoreCommandRouter::volumioGetState Jul 11 20:26:45 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:45 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 20:26:49 volumio volumio[10457]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Jul 11 20:26:49 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Jul 11 20:26:49 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Jul 11 20:26:49 volumio sudo[11325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 20:26:49 volumio sudo[11325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 20:26:49 volumio sudo[11325]: pam_unix(sudo:session): session closed for user root Jul 11 20:26:49 volumio volumio[10457]: info: MPD Permissions set Jul 11 20:26:49 volumio sudo[11327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 20:26:49 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:49 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:49 volumio sudo[11327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 20:26:49 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:49 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:49 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:49 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:49 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:49 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:49 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jul 11 20:26:49 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 20:26:49 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 11 20:26:49 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:49 volumio systemd[1]: mpd.service: Deactivated successfully. Jul 11 20:26:49 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jul 11 20:26:49 volumio systemd[1]: mpd.service: Consumed 1.863s CPU time. Jul 11 20:26:49 volumio systemd[1]: mpd.socket: Deactivated successfully. Jul 11 20:26:49 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jul 11 20:26:49 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jul 11 20:26:49 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 11 20:26:49 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jul 11 20:26:49 volumio sudo[11339]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 11 20:26:49 volumio sudo[11339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 11 20:26:49 volumio sudo[11339]: pam_unix(sudo:session): session closed for user root Jul 11 20:26:50 volumio mpd[11341]: 2025-07-11T20:26:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 11 20:26:50 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jul 11 20:26:50 volumio sudo[11327]: pam_unix(sudo:session): session closed for user root Jul 11 20:26:50 volumio volumio[10457]: error: updateQueue error: null Jul 11 20:26:52 volumio volumio[10457]: info: MyVolumio login type: Token Jul 11 20:26:54 volumio volumio[10457]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 11 20:26:54 volumio volumio[10457]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::volumioGetState Jul 11 20:26:54 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Jul 11 20:26:54 volumio volumio[10457]: info: Restoring Previous Volume level: 80 false true Jul 11 20:26:54 volumio volumio[10457]: info: VolumeController::SetAlsaVolume100 Jul 11 20:26:54 volumio sudo[11372]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 20:26:54 volumio sudo[11372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 20:26:54 volumio sudo[11372]: pam_unix(sudo:session): session closed for user root Jul 11 20:26:54 volumio sudo[11374]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 20:26:54 volumio sudo[11374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 20:26:54 volumio volumio[10457]: info: Volume configurations have been set Jul 11 20:26:54 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 11 20:26:54 volumio volumio[10457]: info: Updating Volume Controller Parameters: Device: 0 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 2 Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 11 20:26:54 volumio volumio[10457]: info: Disabling external Volume Control Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 20:26:54 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:54 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:54 volumio systemd[1]: mpd.service: Deactivated successfully. Jul 11 20:26:54 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jul 11 20:26:54 volumio systemd[1]: mpd.socket: Deactivated successfully. Jul 11 20:26:54 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jul 11 20:26:54 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jul 11 20:26:54 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:54 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:54 volumio volumio[10457]: info: MPD Permissions set Jul 11 20:26:54 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:54 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:54 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:54 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:54 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 11 20:26:54 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 11 20:26:54 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 11 20:26:54 volumio sudo[11391]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 11 20:26:54 volumio sudo[11391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 11 20:26:54 volumio sudo[11391]: pam_unix(sudo:session): session closed for user root Jul 11 20:26:54 volumio volumio[10457]: error: Myvolumio warning! Token not refreshed Jul 11 20:26:54 volumio volumio[10457]: info: MyVolumio token set successfully Jul 11 20:26:54 volumio volumio[10457]: info: MYVOLUMIO: Adding device Jul 11 20:26:54 volumio volumio[10457]: info: MYVOLUMIO: Evaluating Server Jul 11 20:26:55 volumio mpd[11405]: 2025-07-11T20:26:55 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 11 20:26:55 volumio volumio[10457]: info: MyVolumio status changed Jul 11 20:26:55 volumio volumio[10457]: info: Streaming services startup Jul 11 20:26:55 volumio volumio[10457]: info: Starting Streaming Daemon Jul 11 20:26:55 volumio volumio[10457]: info: Removing browser output: myVolumio user plan is not superstar Jul 11 20:26:55 volumio volumio[10457]: info: Removing audio output: Jul 11 20:26:55 volumio volumio[10457]: info: Stoppping Tunnel 1 Jul 11 20:26:55 volumio sudo[11423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 11 20:26:55 volumio sudo[11423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 20:26:55 volumio sudo[11425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 11 20:26:55 volumio sudo[11425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 20:26:55 volumio sudo[11423]: pam_unix(sudo:session): session closed for user root Jul 11 20:26:55 volumio volumio[10457]: error: Cannot start Volumio Streaming Daemon Jul 11 20:26:55 volumio volumio[10457]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 11 20:26:55 volumio volumio[10457]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 11 20:26:55 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jul 11 20:26:55 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jul 11 20:26:55 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jul 11 20:26:55 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jul 11 20:26:55 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jul 11 20:26:55 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jul 11 20:26:55 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jul 11 20:26:55 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jul 11 20:26:55 volumio sudo[11425]: pam_unix(sudo:session): session closed for user root Jul 11 20:26:55 volumio volumio[10457]: info: Remote SSH Stopped Jul 11 20:26:55 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jul 11 20:26:55 volumio sudo[11374]: pam_unix(sudo:session): session closed for user root Jul 11 20:26:55 volumio volumio[10457]: error: updateQueue error: null Jul 11 20:26:59 volumio volumio[10457]: info: VolumeController::SetAlsaVolume80 Jul 11 20:26:59 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:26:59 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:26:59 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:26:59 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:26:59 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:26:59 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:27:01 volumio volumio[10457]: info: CoreCommandRouter::volumioGetState Jul 11 20:27:01 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:27:01 volumio volumio[10457]: info: CoreCommandRouter::volumioPlay Jul 11 20:27:01 volumio volumio[10457]: info: CoreStateMachine::play index undefined Jul 11 20:27:01 volumio volumio[10457]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 11 20:27:01 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:27:01 volumio volumio[10457]: info: CoreStateMachine::startPlaybackTimer Jul 11 20:27:01 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:27:01 volumio volumio[10457]: info: ControllerMpd::resume Jul 11 20:27:01 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand play Jul 11 20:27:01 volumio volumio[10457]: info: sendMpdCommand play took 1 milliseconds Jul 11 20:27:05 volumio volumio[10457]: info: Setting Geolocation for MyVolumio to as1 Jul 11 20:27:05 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 20:27:05 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 20:27:05 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 20:27:05 volumio volumio[10457]: info: CoreCommandRouter::volumioNext Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::next Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::stop Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::updateTrackBlock Jul 11 20:27:05 volumio volumio[10457]: info: CorePlayQueue::getTrackBlock Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::stPlaybackTimer Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:27:05 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:27:05 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:27:05 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::serviceStop Jul 11 20:27:05 volumio volumio[10457]: info: CorePlayQueue::getTrack 664 Jul 11 20:27:05 volumio volumio[10457]: info: CoreCommandRouter::serviceStop Jul 11 20:27:05 volumio volumio[10457]: info: ControllerMpd::stop Jul 11 20:27:05 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand stop Jul 11 20:27:05 volumio volumio[10457]: info: FusionDsp - Volumio is not playing Jul 11 20:27:05 volumio volumio[10457]: info: FusionDsp - Clipped samples monitor stopped Jul 11 20:27:05 volumio volumio[10457]: info: sendMpdCommand stop took 10 milliseconds Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::play index undefined Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 11 20:27:05 volumio volumio[10457]: info: CorePlayQueue::getTrack 456 Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::startPlaybackTimer Jul 11 20:27:05 volumio volumio[10457]: info: CorePlayQueue::getTrack 456 Jul 11 20:27:05 volumio volumio[10457]: verbose: ControllerMpd::clearAddPlayTracks USB/500g/mp3/处理完成/许嵩 - 有何不可.flac Jul 11 20:27:05 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand stop Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::updateTrackBlock Jul 11 20:27:05 volumio volumio[10457]: info: CorePlayQueue::getTrackBlock Jul 11 20:27:05 volumio volumio[10457]: info: sendMpdCommand stop took 1 milliseconds Jul 11 20:27:05 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand clear Jul 11 20:27:05 volumio volumio[10457]: info: Jul 11 20:27:05 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:27:05 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 11 20:27:05 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:27:05 volumio volumio[10457]: info: sendMpdCommand clear took 5 milliseconds Jul 11 20:27:05 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand add "USB/500g/mp3/处理完成/许嵩 - 有何不可.flac" Jul 11 20:27:05 volumio volumio[10457]: error: updateQueue error: null Jul 11 20:27:05 volumio volumio[10457]: info: Jul 11 20:27:05 volumio volumio[10457]: ---------------------------- MPD announces system playlist update Jul 11 20:27:05 volumio volumio[10457]: info: Ignoring MPD Status Update Jul 11 20:27:05 volumio volumio[10457]: info: ------------------------------ 9ms Jul 11 20:27:05 volumio volumio[10457]: info: sendMpdCommand add "USB/500g/mp3/处理完成/许嵩 - 有何不可.flac" took 6 milliseconds Jul 11 20:27:05 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand play Jul 11 20:27:05 volumio dbus-daemon[820]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.103' (uid=0 pid=11471 comm="timedatectl show --property=NTPSynchronized --valu") Jul 11 20:27:05 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 11 20:27:05 volumio volumio[10457]: info: Jul 11 20:27:05 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:27:05 volumio volumio[10457]: info: ------------------------------ 26ms Jul 11 20:27:05 volumio volumio[10457]: info: sendMpdCommand play took 25 milliseconds Jul 11 20:27:05 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:27:05 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:27:05 volumio volumio[10457]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jul 11 20:27:05 volumio volumio[10457]: info: FusionDsp - ---- read samplerate from file: 96000 Jul 11 20:27:05 volumio volumio[10457]: info: camilladsp stopping service pid 11296... Jul 11 20:27:05 volumio dbus-daemon[820]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 11 20:27:05 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 11 20:27:05 volumio setdatetime-helper.sh[11468]: Time is already synchronized. Jul 11 20:27:05 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 11 20:27:05 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 11 20:27:05 volumio volumio[10457]: grep: /proc/11296/cmdline: binary file matches Jul 11 20:27:05 volumio volumio[10457]: grep: /proc/11296/cmdline: binary file matches Jul 11 20:27:05 volumio volumio[10457]: info: camilladsp service terminated, instance 1 Jul 11 20:27:05 volumio volumio[10457]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 11 20:27:05 volumio volumio[10457]: info: camilladsp service started and running in background, instance 1 Jul 11 20:27:05 volumio volumio[10457]: info: Jul 11 20:27:05 volumio volumio[10457]: ---------------------------- MPD announces state update: player Jul 11 20:27:05 volumio volumio[10457]: info: sendMpdCommand status took 265 milliseconds Jul 11 20:27:05 volumio volumio[10457]: info: ControllerMpd::getState Jul 11 20:27:05 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand status Jul 11 20:27:05 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:27:05 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:27:05 volumio volumio[10457]: info: sendMpdCommand status took 2 milliseconds Jul 11 20:27:05 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 20:27:05 volumio volumio[10457]: verbose: ControllerMpd::parseState Jul 11 20:27:05 volumio volumio[10457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 20:27:05 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:27:05 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:27:05 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:27:05 volumio volumio[10457]: info: CorePlayQueue::getTrack 456 Jul 11 20:27:05 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":242,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"有何不可","artist":"许嵩 ","album":null,"uri":"USB/500g/mp3/处理完成/许嵩 - 有何不可.flac","trackType":"flac"} Jul 11 20:27:05 volumio volumio[10457]: verbose: CURRENT POSITION 456 Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus stop Jul 11 20:27:05 volumio volumio[10457]: info: ------------------------------ 269ms Jul 11 20:27:05 volumio volumio[10457]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 11 20:27:05 volumio volumio[10457]: verbose: ControllerMpd::parseTrackInfo Jul 11 20:27:05 volumio volumio[10457]: info: ControllerMpd::pushState Jul 11 20:27:05 volumio volumio[10457]: info: CoreCommandRouter::servicePushState Jul 11 20:27:05 volumio volumio[10457]: info: CorePlayQueue::getTrack 456 Jul 11 20:27:05 volumio volumio[10457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":242,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"518 Kbps","isStreaming":false,"title":"有何不可","artist":"许嵩 ","album":null,"uri":"USB/500g/mp3/处理完成/许嵩 - 有何不可.flac","trackType":"flac"} Jul 11 20:27:05 volumio volumio[10457]: verbose: CURRENT POSITION 456 Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::syncState stateService play Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::syncState currentStatus play Jul 11 20:27:05 volumio volumio[10457]: info: Received an update from plugin. extracting info from payload Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:27:05 volumio volumio[10457]: info: CorePlayQueue::getTrack 456 Jul 11 20:27:05 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 20:27:05 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:27:05 volumio volumio[10457]: info: CoreStateMachine::pushState Jul 11 20:27:05 volumio volumio[10457]: info: CorePlayQueue::getTrack 456 Jul 11 20:27:05 volumio volumio[10457]: info: CoreCommandRouter::volumioPushState Jul 11 20:27:05 volumio volumio[10457]: info: ------------------------------ 27ms Jul 11 20:27:05 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:27:05 volumio volumio[10457]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jul 11 20:27:05 volumio volumio[10457]: info: FusionDsp - Volumio is playing Jul 11 20:27:05 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:27:05 volumio volumio[10457]: info: FusionDsp - Clipping Monitor started Jul 11 20:27:05 volumio volumio[10457]: error: Failed to add MyVolumio device: undefined Jul 11 20:27:06 volumio volumio[10457]: info: Updating MyVolumio device info Jul 11 20:27:06 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 20:27:06 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 20:27:06 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 20:27:06 volumio volumio[10457]: error: Failed to update MyVolumio device: undefined Jul 11 20:27:11 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 20:27:11 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 20:27:11 volumio volumio[10457]: info: Discovery: Getting this device information Jul 11 20:27:11 volumio volumio[10457]: info: CoreCommandRouter::volumioGetState Jul 11 20:27:11 volumio volumio[10457]: info: CorePlayQueue::getTrack 456 Jul 11 20:27:11 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 20:27:12 volumio volumio[10457]: verbose: New Socket.io Connection to 192.168.5.242:3000 from 192.168.5.137 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jul 11 20:27:12 volumio volumio[10457]: verbose: New Socket.io Connection to 192.168.5.242 from 192.168.5.137 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 12_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/12.4 Mobile/15E148 Safari/000.0 Quip/iOS v5.5.7 (WebDialog) Engine version: 3 Transport: polling Total Clients: 8 Jul 11 20:27:12 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 11 20:27:12 volumio volumio[10457]: info: CoreCommandRouter::volumioGetVisibleSources Jul 11 20:27:12 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 20:27:12 volumio volumio[10457]: info: CoreCommandRouter::volumioGetState Jul 11 20:27:12 volumio volumio[10457]: info: CorePlayQueue::getTrack 456 Jul 11 20:27:12 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 11 20:27:12 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 11 20:27:12 volumio volumio[10457]: info: Received Get System Info Jul 11 20:27:12 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 20:27:12 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 20:27:12 volumio volumio[10457]: info: Discovery: Getting this device information Jul 11 20:27:12 volumio volumio[10457]: info: CoreCommandRouter::volumioGetState Jul 11 20:27:12 volumio volumio[10457]: info: CorePlayQueue::getTrack 456 Jul 11 20:27:12 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 20:27:12 volumio volumio[10457]: info: CoreCommandRouter::volumioGetState Jul 11 20:27:12 volumio volumio[10457]: info: CorePlayQueue::getTrack 456 Jul 11 20:27:12 volumio volumio[10457]: info: Listing playlists Jul 11 20:27:12 volumio volumio[10457]: info: CoreCommandRouter::volumioGetQueue Jul 11 20:27:12 volumio volumio[10457]: info: CoreStateMachine::getQueue Jul 11 20:27:12 volumio volumio[10457]: info: CorePlayQueue::getQueue Jul 11 20:27:13 volumio volumio[10457]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 20:27:13 volumio volumio[10457]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 20:27:13 volumio volumio[10457]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 20:27:13 volumio volumio[10457]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 20:27:13 volumio volumio[10457]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 20:27:13 volumio volumio[10457]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 20:27:13 volumio volumio[10457]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 20:27:13 volumio volumio[10457]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 20:27:13 volumio volumio[10457]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 20:27:13 volumio volumio[10457]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 20:27:13 volumio volumio[10457]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 20:27:13 volumio volumio[10457]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 20:27:13 volumio volumio[10457]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 11 20:27:18 volumio sshd[11556]: Accepted password for volumio from 192.168.5.137 port 61776 ssh2 Jul 11 20:27:18 volumio sshd[11556]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Jul 11 20:27:18 volumio systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Jul 11 20:27:18 volumio systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Jul 11 20:27:18 volumio systemd-logind[823]: New session 7 of user volumio. Jul 11 20:27:18 volumio systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Jul 11 20:27:18 volumio systemd[1]: Starting user@1000.service - User Manager for UID 1000... Jul 11 20:27:18 volumio (systemd)[11561]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Jul 11 20:27:18 volumio systemd[11561]: Queued start job for default target default.target. Jul 11 20:27:18 volumio systemd[11561]: Created slice app.slice - User Application Slice. Jul 11 20:27:18 volumio systemd[11561]: Reached target paths.target - Paths. Jul 11 20:27:18 volumio systemd[11561]: Reached target timers.target - Timers. Jul 11 20:27:18 volumio systemd[11561]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 11 20:27:18 volumio systemd[11561]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Jul 11 20:27:18 volumio systemd[11561]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Jul 11 20:27:18 volumio systemd[11561]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Jul 11 20:27:18 volumio systemd[11561]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Jul 11 20:27:18 volumio systemd[11561]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Jul 11 20:27:18 volumio systemd[11561]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 11 20:27:18 volumio systemd[11561]: Reached target sockets.target - Sockets. Jul 11 20:27:18 volumio systemd[11561]: Reached target basic.target - Basic System. Jul 11 20:27:18 volumio systemd[11561]: Reached target default.target - Main User Target. Jul 11 20:27:18 volumio systemd[11561]: Startup finished in 266ms. Jul 11 20:27:18 volumio systemd[1]: Started user@1000.service - User Manager for UID 1000. Jul 11 20:27:18 volumio systemd[1]: Started session-7.scope - Session 7 of User volumio. Jul 11 20:27:18 volumio sshd[11556]: pam_env(sshd:session): deprecated reading of user environment enabled Jul 11 20:27:35 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 11 20:27:46 volumio volumio[10457]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 20:27:46 volumio volumio[10457]: Error: Client network socket disconnected before secure TLS connection was established Jul 11 20:27:46 volumio volumio[10457]: at connResetException (node:internal/errors:720:14) Jul 11 20:27:46 volumio volumio[10457]: at TLSSocket.onConnectEnd (node:_tls_wrap:1682:19) Jul 11 20:27:46 volumio volumio[10457]: at TLSSocket.emit (node:events:526:35) Jul 11 20:27:46 volumio volumio[10457]: at endReadableNT (node:internal/streams/readable:1376:12) Jul 11 20:27:46 volumio volumio[10457]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jul 11 20:27:46 volumio volumio[10457]: code: 'ECONNRESET', Jul 11 20:27:46 volumio volumio[10457]: path: null, Jul 11 20:27:46 volumio volumio[10457]: host: 'lastfm.freetls.fastly.net', Jul 11 20:27:46 volumio volumio[10457]: port: 443, Jul 11 20:27:46 volumio volumio[10457]: localAddress: undefined Jul 11 20:27:46 volumio volumio[10457]: } Jul 11 20:27:46 volumio volumio[10457]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 20:27:46 volumio sudo[12030]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-11 20:26' Jul 11 20:27:46 volumio sudo[12030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="4f980d5cf693b4272997a2fa2da8bb8036ddf1ab" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jul 5 02:28:04 UTC 2025" VOLUMIO_VERSION="4.015" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="fd2f0c5aa414951a829ccaf1ecae9bb1"