-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2025-06-19 12:02:37 CEST. -- Jun 19 12:01:00 volumio volumio[989]: STREAMING PROXY: Starting server on port 3245 Jun 19 12:01:00 volumio volumio[989]: Node JS runtime: 14 Jun 19 12:01:00 volumio volumio[989]: info: MyVolumio token set successfully Jun 19 12:01:00 volumio volumio[989]: info: MYVOLUMIO: Adding device Jun 19 12:01:00 volumio volumio[989]: info: MYVOLUMIO: Evaluating Server Jun 19 12:01:00 volumio volumio[989]: info: MyVolumio status changed Jun 19 12:01:00 volumio volumio[989]: info: Streaming services startup Jun 19 12:01:00 volumio volumio[989]: info: Starting Streaming Daemon Jun 19 12:01:00 volumio volumio[989]: info: Removing browser output: myVolumio user plan is not superstar Jun 19 12:01:00 volumio volumio[989]: info: Removing audio output: Jun 19 12:01:00 volumio volumio[989]: info: Stoppping Tunnel 1 Jun 19 12:01:00 volumio sudo[1501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 19 12:01:00 volumio sudo[1501]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 12:01:00 volumio sudo[1501]: pam_unix(sudo:session): session closed for user root Jun 19 12:01:00 volumio sudo[1503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 19 12:01:00 volumio sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 12:01:00 volumio volumio[989]: error: Cannot start Volumio Streaming Daemon Jun 19 12:01:00 volumio volumio[989]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 19 12:01:00 volumio volumio[989]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 19 12:01:00 volumio sudo[1503]: pam_unix(sudo:session): session closed for user root Jun 19 12:01:00 volumio volumio[989]: info: Remote SSH Stopped Jun 19 12:01:00 volumio ntpd[803]: Soliciting pool server 54.36.61.42 Jun 19 12:01:00 volumio ntpd[803]: Soliciting pool server 194.57.169.1 Jun 19 12:01:00 volumio ntpd[803]: Soliciting pool server 82.64.230.205 Jun 19 12:01:01 volumio volumio[989]: info: Setting Geolocation for MyVolumio to eu4 Jun 19 12:01:01 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 12:01:01 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 12:01:01 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 12:01:01 volumio volumio[989]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 19 12:01:01 volumio ntpd[803]: Soliciting pool server 195.154.185.215 Jun 19 12:01:01 volumio ntpd[803]: Soliciting pool server 109.190.177.200 Jun 19 12:01:01 volumio ntpd[803]: Soliciting pool server 212.227.232.161 Jun 19 12:01:02 volumio volumio[989]: info: Updating MyVolumio device info Jun 19 12:01:02 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 12:01:02 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 12:01:02 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 12:01:02 volumio volumio[989]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 19 12:01:02 volumio ntpd[803]: Soliciting pool server 82.64.42.185 Jun 19 12:01:02 volumio ntpd[803]: Soliciting pool server 51.15.191.239 Jun 19 12:01:02 volumio ntpd[803]: Soliciting pool server 162.159.200.1 Jun 19 12:01:03 volumio ntpd[803]: Soliciting pool server 82.67.41.119 Jun 19 12:01:03 volumio ntpd[803]: Soliciting pool server 51.68.44.27 Jun 19 12:01:03 volumio ntpd[803]: Soliciting pool server 82.127.104.72 Jun 19 12:01:05 volumio ntpd[803]: receive: Unexpected origin timestamp 0xebfe5e60.da284096 does not match aorg 0000000000.00000000 from server@82.64.45.50 xmt 0xebfe5e61.e6d804dd Jun 19 12:01:05 volumio ntpd[803]: receive: Unexpected origin timestamp 0xebfe5e60.da294f40 does not match aorg 0000000000.00000000 from server@129.151.225.244 xmt 0xebfe5e61.e6d76a2c Jun 19 12:01:05 volumio ntpd[803]: receive: Unexpected origin timestamp 0xebfe5e60.da2251fb does not match aorg 0000000000.00000000 from server@212.227.232.161 xmt 0xebfe5e61.e7d4e521 Jun 19 12:01:06 volumio systemd[1]: Starting Daily apt download activities... Jun 19 12:01:06 volumio volumio[989]: info: MYVOLUMIO: Adding device Jun 19 12:01:06 volumio volumio[989]: info: MYVOLUMIO: Evaluating Server Jun 19 12:01:07 volumio volumio[989]: info: Setting Geolocation for MyVolumio to eu4 Jun 19 12:01:07 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 12:01:07 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 12:01:07 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 12:01:07 volumio volumio[989]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 19 12:01:08 volumio volumio[989]: info: Updating MyVolumio device info Jun 19 12:01:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 12:01:08 volumio volumio[989]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 19 12:01:09 volumio systemd[1]: apt-daily.service: Succeeded. Jun 19 12:01:09 volumio systemd[1]: Started Daily apt download activities. Jun 19 12:01:13 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 19 12:01:13 volumio volumio[989]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 19 12:01:13 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Jun 19 12:01:13 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:16 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 12:01:16 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 12:01:16 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 19 12:01:16 volumio volumio-remote-updater[624]: No test mode Jun 19 12:01:16 volumio volumio-remote-updater[624]: No alpha test mode Jun 19 12:01:16 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 19 12:01:16 volumio volumio[989]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 19 12:01:16 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 19 12:01:18 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 12:01:18 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 12:01:18 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 19 12:01:18 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 19 12:01:18 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 19 12:01:18 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 19 12:01:18 volumio volumio[989]: info: CoreCommandRouter::volumioGetBrowseSources Jun 19 12:01:18 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 12:01:19 volumio volumio[989]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 19 12:01:19 volumio volumio[989]: info: Completed starting MyVolumio Plugin Jun 19 12:01:19 volumio volumio[989]: [Metrics] CommandRouter: 70s 26.60ms Jun 19 12:01:19 volumio volumio[989]: info: CoreCommandRouter::volumiosetStartupVolume Jun 19 12:01:19 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 12:01:19 volumio volumio[989]: info: VolumeController:: Setting startup Volume 50 Jun 19 12:01:19 volumio volumio[989]: info: VolumeController::SetAlsaVolume50 Jun 19 12:01:19 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 12:01:19 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent Jun 19 12:01:19 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:01:19 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:19 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 12:01:19 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:01:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 12:01:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 19 12:01:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 12:01:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 19 12:01:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 19 12:01:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 19 12:01:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 12:01:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 19 12:01:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 19 12:01:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Jun 19 12:01:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 12:01:20 volumio volumio[989]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Invalid command! Jun 19 12:01:20 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 12:01:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 19 12:01:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 19 12:01:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 19 12:01:25 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 12:01:26 volumio volumio[989]: info: BOOT COMPLETED Jun 19 12:01:52 volumio sudo[1693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 19 12:01:52 volumio sudo[1695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 19 12:01:52 volumio sudo[1695]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 12:01:52 volumio sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 12:01:52 volumio sudo[1693]: pam_unix(sudo:session): session closed for user root Jun 19 12:01:52 volumio sudo[1695]: pam_unix(sudo:session): session closed for user root Jun 19 12:01:52 volumio volumio[989]: verbose: New Socket.io Connection to 192.168.1.93 from 192.168.1.134 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 4 Jun 19 12:01:52 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 19 12:01:52 volumio volumio[989]: info: CoreCommandRouter::volumioGetVisibleSources Jun 19 12:01:52 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 12:01:52 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Jun 19 12:01:52 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:52 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 19 12:01:52 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 19 12:01:52 volumio volumio[989]: info: Received Get System Info Jun 19 12:01:52 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 12:01:52 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 12:01:52 volumio volumio[989]: info: Discovery: Getting this device information Jun 19 12:01:52 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Jun 19 12:01:52 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:52 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 12:01:52 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Jun 19 12:01:52 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:52 volumio volumio[989]: info: Listing playlists Jun 19 12:01:52 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 19 12:01:52 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 19 12:01:52 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 19 12:01:53 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Jun 19 12:01:53 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:54 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 19 12:01:54 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 19 12:01:54 volumio volumio[989]: info: Received Get System Info Jun 19 12:01:54 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 12:01:54 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 12:01:54 volumio volumio[989]: info: Discovery: Getting this device information Jun 19 12:01:54 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Jun 19 12:01:54 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:54 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 19 12:01:55 volumio volumio[989]: info: Received Get System Info Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 12:01:55 volumio volumio[989]: info: Discovery: Getting this device information Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Jun 19 12:01:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::volumioPlay Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::play index undefined Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 12:01:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::startPlaybackTimer Jun 19 12:01:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::clearAddPlayTracks USB/T5_EVO/music/[Vocal Jazz] Diana Krall - Wallflower (Deluxe Edition) (2015) [FLAC HD]/01 - California Dreamin'.flac Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 12:01:55 volumio volumio[989]: info: sendMpdCommand stop took 3 milliseconds Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand clear Jun 19 12:01:55 volumio volumio[989]: info: Jun 19 12:01:55 volumio volumio[989]: ---------------------------- MPD announces system playlist update Jun 19 12:01:55 volumio volumio[989]: info: Ignoring MPD Status Update Jun 19 12:01:55 volumio volumio[989]: info: sendMpdCommand clear took 4 milliseconds Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand add "USB/T5_EVO/music/[Vocal Jazz] Diana Krall - Wallflower (Deluxe Edition) (2015) [FLAC HD]/01 - California Dreamin'.flac" Jun 19 12:01:55 volumio volumio[989]: info: Jun 19 12:01:55 volumio volumio[989]: ---------------------------- MPD announces system playlist update Jun 19 12:01:55 volumio volumio[989]: info: Ignoring MPD Status Update Jun 19 12:01:55 volumio volumio[989]: info: Jun 19 12:01:55 volumio volumio[989]: ---------------------------- MPD announces system playlist update Jun 19 12:01:55 volumio volumio[989]: info: Ignoring MPD Status Update Jun 19 12:01:55 volumio volumio[989]: error: updateQueue error: null Jun 19 12:01:55 volumio volumio[989]: info: Jun 19 12:01:55 volumio volumio[989]: ---------------------------- MPD announces system playlist update Jun 19 12:01:55 volumio volumio[989]: info: Ignoring MPD Status Update Jun 19 12:01:55 volumio volumio[989]: info: ------------------------------ 15ms Jun 19 12:01:55 volumio volumio[989]: info: sendMpdCommand add "USB/T5_EVO/music/[Vocal Jazz] Diana Krall - Wallflower (Deluxe Edition) (2015) [FLAC HD]/01 - California Dreamin'.flac" took 11 milliseconds Jun 19 12:01:55 volumio volumio[989]: info: ------------------------------ 10ms Jun 19 12:01:55 volumio volumio[989]: info: ------------------------------ 9ms Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand play Jun 19 12:01:55 volumio volumio[989]: info: Jun 19 12:01:55 volumio volumio[989]: ---------------------------- MPD announces system playlist update Jun 19 12:01:55 volumio volumio[989]: info: Ignoring MPD Status Update Jun 19 12:01:55 volumio volumio[989]: info: Jun 19 12:01:55 volumio volumio[989]: ---------------------------- MPD announces system playlist update Jun 19 12:01:55 volumio volumio[989]: info: Ignoring MPD Status Update Jun 19 12:01:55 volumio volumio[989]: info: Jun 19 12:01:55 volumio volumio[989]: ---------------------------- MPD announces state update: player Jun 19 12:01:55 volumio volumio[989]: info: ------------------------------ 24ms Jun 19 12:01:55 volumio volumio[989]: info: sendMpdCommand play took 19 milliseconds Jun 19 12:01:55 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:01:55 volumio volumio[989]: info: ------------------------------ 20ms Jun 19 12:01:55 volumio volumio[989]: info: ------------------------------ 8ms Jun 19 12:01:55 volumio volumio[989]: info: Jun 19 12:01:55 volumio volumio[989]: ---------------------------- MPD announces state update: player Jun 19 12:01:55 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:01:55 volumio volumio[989]: info: Jun 19 12:01:55 volumio volumio[989]: ---------------------------- MPD announces state update: player Jun 19 12:01:55 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 19 12:01:55 volumio volumio[989]: info: sendMpdCommand status took 20 milliseconds Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 19 12:01:55 volumio volumio[989]: info: sendMpdCommand status took 15 milliseconds Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 19 12:01:55 volumio volumio[989]: info: sendMpdCommand status took 14 milliseconds Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 12:01:55 volumio volumio[989]: info: sendMpdCommand clearerror took 19 milliseconds Jun 19 12:01:55 volumio volumio[989]: info: sendMpdCommand clearerror took 18 milliseconds Jun 19 12:01:55 volumio volumio[989]: info: sendMpdCommand clearerror took 16 milliseconds Jun 19 12:01:55 volumio volumio[989]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 19 12:01:55 volumio volumio[989]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 19 12:01:55 volumio volumio[989]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::parseTrackInfo Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::parseTrackInfo Jun 19 12:01:55 volumio volumio[989]: verbose: ControllerMpd::parseTrackInfo Jun 19 12:01:55 volumio volumio[989]: info: ControllerMpd::pushState Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::servicePushState Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:01:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:01:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:55 volumio volumio[989]: 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":"California Dreamin'","artist":"Diana Krall","album":"Wallflower","uri":"USB/T5_EVO/music/[Vocal Jazz] Diana Krall - Wallflower (Deluxe Edition) (2015) [FLAC HD]/01 - California Dreamin'.flac","trackType":"flac"} Jun 19 12:01:55 volumio volumio[989]: verbose: CURRENT POSITION 0 Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:01:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:01:55 volumio volumio[989]: info: No code Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:01:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:01:55 volumio volumio[989]: info: ControllerMpd::pushState Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::servicePushState Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:01:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:01:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:55 volumio volumio[989]: 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":"California Dreamin'","artist":"Diana Krall","album":"Wallflower","uri":"USB/T5_EVO/music/[Vocal Jazz] Diana Krall - Wallflower (Deluxe Edition) (2015) [FLAC HD]/01 - California Dreamin'.flac","trackType":"flac"} Jun 19 12:01:55 volumio volumio[989]: verbose: CURRENT POSITION 0 Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:01:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:01:55 volumio volumio[989]: info: No code Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:01:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:01:55 volumio volumio[989]: info: ControllerMpd::pushState Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::servicePushState Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:01:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:01:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:55 volumio volumio[989]: 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":"California Dreamin'","artist":"Diana Krall","album":"Wallflower","uri":"USB/T5_EVO/music/[Vocal Jazz] Diana Krall - Wallflower (Deluxe Edition) (2015) [FLAC HD]/01 - California Dreamin'.flac","trackType":"flac"} Jun 19 12:01:55 volumio volumio[989]: verbose: CURRENT POSITION 0 Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:01:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:01:55 volumio volumio[989]: info: No code Jun 19 12:01:55 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:01:55 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:01:55 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:01:55 volumio volumio[989]: info: ------------------------------ 150ms Jun 19 12:01:55 volumio volumio[989]: info: ------------------------------ 146ms Jun 19 12:01:55 volumio volumio[989]: info: ------------------------------ 143ms Jun 19 12:02:02 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 19 12:02:07 volumio volumio[989]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 12:02:07 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 19 12:02:07 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 19 12:02:07 volumio sudo[1734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 19 12:02:07 volumio sudo[1734]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 12:02:07 volumio sudo[1739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 19 12:02:07 volumio sudo[1739]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 12:02:07 volumio sudo[1745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 19 12:02:07 volumio sudo[1745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 12:02:07 volumio sudo[1751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 19 12:02:07 volumio sudo[1751]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 12:02:07 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 19 12:02:07 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 19 12:02:07 volumio sudo[1739]: pam_unix(sudo:session): session closed for user root Jun 19 12:02:07 volumio sudo[1734]: pam_unix(sudo:session): session closed for user root Jun 19 12:02:07 volumio sudo[1751]: pam_unix(sudo:session): session closed for user root Jun 19 12:02:07 volumio sudo[1757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 19 12:02:07 volumio sudo[1757]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 12:02:07 volumio sudo[1762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 19 12:02:07 volumio sudo[1757]: pam_unix(sudo:session): session closed for user root Jun 19 12:02:07 volumio sudo[1760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 19 12:02:07 volumio sudo[1762]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 12:02:07 volumio sudo[1760]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 12:02:07 volumio sudo[1745]: pam_unix(sudo:session): session closed for user root Jun 19 12:02:07 volumio sudo[1760]: pam_unix(sudo:session): session closed for user root Jun 19 12:02:08 volumio sudo[1762]: pam_unix(sudo:session): session closed for user root Jun 19 12:02:10 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 19 12:02:10 volumio volumio[989]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 19 12:02:10 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 19 12:02:10 volumio volumio[989]: info: Received Get System Version Jun 19 12:02:10 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 12:02:10 volumio volumio[989]: info: Received Get System Info Jun 19 12:02:10 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 12:02:10 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 12:02:10 volumio volumio[989]: info: Discovery: Getting this device information Jun 19 12:02:10 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Jun 19 12:02:10 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:10 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 12:02:13 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Jun 19 12:02:13 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 12:02:20 volumio volumio[989]: info: Retrieving Cloud Streaming UI Jun 19 12:02:20 volumio volumio[989]: info: Getting Tidal Cloud Configuration Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 12:02:20 volumio volumio[989]: info: Getting Qobuz Cloud Configuration Jun 19 12:02:20 volumio volumio[989]: info: Asking plugin for UI Config Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 12:02:20 volumio volumio[989]: info: Getting Spotify Cloud Configuration Jun 19 12:02:20 volumio volumio[989]: info: Asking plugin for UI Config Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 12:02:20 volumio volumio[989]: info: Saving Spotify Acccount Jun 19 12:02:20 volumio volumio[989]: info: Got Tidal Cloud Configuration Jun 19 12:02:20 volumio volumio[989]: info: Got it Jun 19 12:02:20 volumio volumio[989]: info: Got it Jun 19 12:02:20 volumio volumio[989]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 12:02:20 volumio volumio[989]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::volumioGetBrowseSources Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::volumioGetBrowseSources Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::volumioGetBrowseSources Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 12:02:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 19 12:02:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 12:02:27 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jun 19 12:02:27 volumio sudo[1807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/music Jun 19 12:02:27 volumio sudo[1807]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 12:02:27 volumio sudo[1807]: pam_unix(sudo:session): session closed for user root Jun 19 12:02:27 volumio systemd[1]: mnt-NAS-music.mount: Succeeded. Jun 19 12:02:27 volumio volumio[989]: info: Share music successfully unmounted Jun 19 12:02:28 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 12:02:28 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 19 12:02:30 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:30 volumio volumio[989]: info: Jun 19 12:02:30 volumio volumio[989]: ---------------------------- MPD announces state update: update Jun 19 12:02:30 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:02:30 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:30 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:30 volumio volumio[989]: info: Jun 19 12:02:30 volumio volumio[989]: ---------------------------- MPD announces state update: update Jun 19 12:02:30 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:02:30 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:30 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:30 volumio volumio[989]: info: Jun 19 12:02:30 volumio volumio[989]: ---------------------------- MPD announces state update: update Jun 19 12:02:30 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:02:30 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:30 volumio volumio[989]: info: sendMpdCommand status took 10 milliseconds Jun 19 12:02:30 volumio volumio[989]: info: sendMpdCommand status took 8 milliseconds Jun 19 12:02:30 volumio volumio[989]: info: sendMpdCommand status took 8 milliseconds Jun 19 12:02:30 volumio volumio[989]: info: sendMpdCommand status took 7 milliseconds Jun 19 12:02:30 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:30 volumio volumio[989]: info: Command Router : Notfying DB Updatetrue Jun 19 12:02:30 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:30 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 12:02:30 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:30 volumio volumio[989]: info: Command Router : Notfying DB Updatetrue Jun 19 12:02:30 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent Jun 19 12:02:30 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:30 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 12:02:30 volumio volumio[989]: info: [1750327350996] Database update started Jun 19 12:02:31 volumio volumio[989]: info: MPD Database updated - AlbumList cache refreshed Jun 19 12:02:31 volumio volumio[989]: info: Jun 19 12:02:31 volumio volumio[989]: ---------------------------- MPD announces state update: database Jun 19 12:02:31 volumio volumio[989]: info: Jun 19 12:02:31 volumio volumio[989]: ---------------------------- MPD announces system playlist update Jun 19 12:02:31 volumio volumio[989]: info: Ignoring MPD Status Update Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:31 volumio volumio[989]: info: Jun 19 12:02:31 volumio volumio[989]: ---------------------------- MPD announces state update: update Jun 19 12:02:31 volumio volumio[989]: info: sendMpdCommand status took 340 milliseconds Jun 19 12:02:31 volumio volumio[989]: info: sendMpdCommand status took 338 milliseconds Jun 19 12:02:31 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:31 volumio volumio[989]: info: sendMpdCommand playlistinfo took 46 milliseconds Jun 19 12:02:31 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:31 volumio volumio[989]: info: sendMpdCommand playlistinfo took 27 milliseconds Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:31 volumio volumio[989]: info: Command Router : Notfying DB Updatetrue Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::parseTrackInfo Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::parseTrackInfo Jun 19 12:02:31 volumio volumio[989]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 19 12:02:31 volumio volumio[989]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 19 12:02:31 volumio volumio[989]: info: ------------------------------ 386ms Jun 19 12:02:31 volumio volumio[989]: info: ------------------------------ 382ms Jun 19 12:02:31 volumio volumio[989]: info: MPD Database updated - AlbumList cache refreshed Jun 19 12:02:31 volumio volumio[989]: info: Jun 19 12:02:31 volumio volumio[989]: ---------------------------- MPD announces state update: database Jun 19 12:02:31 volumio volumio[989]: info: Jun 19 12:02:31 volumio volumio[989]: ---------------------------- MPD announces system playlist update Jun 19 12:02:31 volumio volumio[989]: info: Ignoring MPD Status Update Jun 19 12:02:31 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:31 volumio volumio[989]: info: MPD Database updated - AlbumList cache refreshed Jun 19 12:02:31 volumio volumio[989]: info: Jun 19 12:02:31 volumio volumio[989]: ---------------------------- MPD announces state update: database Jun 19 12:02:31 volumio volumio[989]: info: Jun 19 12:02:31 volumio volumio[989]: ---------------------------- MPD announces system playlist update Jun 19 12:02:31 volumio volumio[989]: info: Ignoring MPD Status Update Jun 19 12:02:31 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:31 volumio volumio[989]: error: updateQueue error: null Jun 19 12:02:31 volumio volumio[989]: error: updateQueue error: null Jun 19 12:02:31 volumio volumio[989]: error: updateQueue error: null Jun 19 12:02:31 volumio volumio[989]: info: sendMpdCommand status took 71 milliseconds Jun 19 12:02:31 volumio volumio[989]: info: sendMpdCommand status took 68 milliseconds Jun 19 12:02:31 volumio volumio[989]: info: ------------------------------ 73ms Jun 19 12:02:31 volumio volumio[989]: info: sendMpdCommand status took 67 milliseconds Jun 19 12:02:31 volumio volumio[989]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 19 12:02:31 volumio volumio[989]: info: sendMpdCommand status took 19 milliseconds Jun 19 12:02:31 volumio volumio[989]: info: ------------------------------ 31ms Jun 19 12:02:31 volumio volumio[989]: info: sendMpdCommand status took 16 milliseconds Jun 19 12:02:31 volumio volumio[989]: info: ------------------------------ 18ms Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:31 volumio volumio[989]: info: Command Router : Notfying DB Updatefalse Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::parseTrackInfo Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:31 volumio volumio[989]: info: ControllerMpd::pushState Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::servicePushState Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: 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} Jun 19 12:02:31 volumio volumio[989]: verbose: CURRENT POSITION 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: No code Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: ControllerMpd::pushState Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::servicePushState Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: 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} Jun 19 12:02:31 volumio volumio[989]: verbose: CURRENT POSITION 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: No code Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 19 12:02:31 volumio volumio[989]: info: ControllerMpd::pushState Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::servicePushState Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: 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} Jun 19 12:02:31 volumio volumio[989]: verbose: CURRENT POSITION 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: No code Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: ControllerMpd::pushState Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::servicePushState Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: 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} Jun 19 12:02:31 volumio volumio[989]: verbose: CURRENT POSITION 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: No code Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: ------------------------------ 212ms Jun 19 12:02:31 volumio volumio[989]: info: ------------------------------ 214ms Jun 19 12:02:31 volumio volumio[989]: info: ------------------------------ 553ms Jun 19 12:02:31 volumio volumio[989]: info: ------------------------------ 174ms Jun 19 12:02:31 volumio volumio[989]: info: ------------------------------ 162ms Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:31 volumio volumio[989]: info: Jun 19 12:02:31 volumio volumio[989]: ---------------------------- MPD announces state update: update Jun 19 12:02:31 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:31 volumio volumio[989]: info: Jun 19 12:02:31 volumio volumio[989]: ---------------------------- MPD announces state update: update Jun 19 12:02:31 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:31 volumio volumio[989]: info: sendMpdCommand status took 7 milliseconds Jun 19 12:02:31 volumio volumio[989]: info: sendMpdCommand status took 6 milliseconds Jun 19 12:02:31 volumio volumio[989]: info: sendMpdCommand status took 5 milliseconds Jun 19 12:02:31 volumio volumio[989]: info: sendMpdCommand status took 4 milliseconds Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:31 volumio volumio[989]: info: Command Router : Notfying DB Updatefalse Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:31 volumio volumio[989]: info: Command Router : Notfying DB Updatefalse Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent Jun 19 12:02:31 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:31 volumio volumio[989]: info: ControllerMpd::pushState Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::servicePushState Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: 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} Jun 19 12:02:31 volumio volumio[989]: verbose: CURRENT POSITION 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: No code Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: ControllerMpd::pushState Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::servicePushState Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: 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} Jun 19 12:02:31 volumio volumio[989]: verbose: CURRENT POSITION 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: No code Jun 19 12:02:31 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:31 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:31 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:31 volumio volumio[989]: info: ------------------------------ 115ms Jun 19 12:02:31 volumio volumio[989]: info: ------------------------------ 114ms Jun 19 12:02:32 volumio sudo[1061]: pam_unix(sudo:session): session closed for user root Jun 19 12:02:32 volumio volumio[989]: info: Cannot mount NAS music at system boot, trial number 1 ,retrying in 5 seconds Jun 19 12:02:32 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand update Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:34 volumio volumio[989]: info: Jun 19 12:02:34 volumio volumio[989]: ---------------------------- MPD announces state update: update Jun 19 12:02:34 volumio volumio[989]: info: sendMpdCommand update took 4 milliseconds Jun 19 12:02:34 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:34 volumio volumio[989]: info: Jun 19 12:02:34 volumio volumio[989]: ---------------------------- MPD announces state update: update Jun 19 12:02:34 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:34 volumio volumio[989]: info: sendMpdCommand status took 6 milliseconds Jun 19 12:02:34 volumio volumio[989]: info: sendMpdCommand status took 3 milliseconds Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:34 volumio volumio[989]: info: Command Router : Notfying DB Updatefalse Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:34 volumio volumio[989]: info: ControllerMpd::pushState Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::servicePushState Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:34 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:34 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:34 volumio volumio[989]: 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} Jun 19 12:02:34 volumio volumio[989]: verbose: CURRENT POSITION 0 Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:34 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:34 volumio volumio[989]: info: No code Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:34 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:34 volumio volumio[989]: info: ------------------------------ 85ms Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:34 volumio volumio[989]: info: Jun 19 12:02:34 volumio volumio[989]: ---------------------------- MPD announces state update: update Jun 19 12:02:34 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:34 volumio volumio[989]: info: Jun 19 12:02:34 volumio volumio[989]: ---------------------------- MPD announces state update: update Jun 19 12:02:34 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:34 volumio volumio[989]: info: sendMpdCommand status took 89 milliseconds Jun 19 12:02:34 volumio volumio[989]: info: sendMpdCommand status took 88 milliseconds Jun 19 12:02:34 volumio volumio[989]: info: sendMpdCommand status took 6 milliseconds Jun 19 12:02:34 volumio volumio[989]: info: sendMpdCommand status took 6 milliseconds Jun 19 12:02:34 volumio volumio[989]: info: sendMpdCommand status took 5 milliseconds Jun 19 12:02:34 volumio volumio[989]: info: sendMpdCommand status took 3 milliseconds Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:34 volumio volumio[989]: info: Command Router : Notfying DB Updatefalse Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:34 volumio volumio[989]: info: Command Router : Notfying DB Updatefalse Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:34 volumio volumio[989]: info: Command Router : Notfying DB Updatefalse Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:34 volumio volumio[989]: info: ControllerMpd::pushState Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::servicePushState Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:34 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:34 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:34 volumio volumio[989]: 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} Jun 19 12:02:34 volumio volumio[989]: verbose: CURRENT POSITION 0 Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:34 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:34 volumio volumio[989]: info: No code Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:34 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:34 volumio volumio[989]: info: ControllerMpd::pushState Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::servicePushState Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:34 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:34 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:34 volumio volumio[989]: 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} Jun 19 12:02:34 volumio volumio[989]: verbose: CURRENT POSITION 0 Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:34 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:34 volumio volumio[989]: info: No code Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:34 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:34 volumio volumio[989]: info: ControllerMpd::pushState Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::servicePushState Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:34 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:34 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:34 volumio volumio[989]: 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} Jun 19 12:02:34 volumio volumio[989]: verbose: CURRENT POSITION 0 Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:34 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:34 volumio volumio[989]: info: No code Jun 19 12:02:34 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:34 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:34 volumio volumio[989]: info: ------------------------------ 255ms Jun 19 12:02:34 volumio volumio[989]: info: ------------------------------ 174ms Jun 19 12:02:34 volumio volumio[989]: info: ------------------------------ 172ms Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:34 volumio volumio[989]: info: Jun 19 12:02:34 volumio volumio[989]: ---------------------------- MPD announces state update: update Jun 19 12:02:34 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:34 volumio volumio[989]: info: Jun 19 12:02:34 volumio volumio[989]: ---------------------------- MPD announces state update: update Jun 19 12:02:34 volumio volumio[989]: info: ControllerMpd::getState Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Jun 19 12:02:34 volumio volumio[989]: info: sendMpdCommand status took 22 milliseconds Jun 19 12:02:34 volumio volumio[989]: info: sendMpdCommand status took 22 milliseconds Jun 19 12:02:34 volumio volumio[989]: info: sendMpdCommand status took 21 milliseconds Jun 19 12:02:34 volumio volumio[989]: info: sendMpdCommand status took 21 milliseconds Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:34 volumio volumio[989]: info: Command Router : Notfying DB Updatefalse Jun 19 12:02:34 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:34 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:35 volumio volumio[989]: info: Command Router : Notfying DB Updatefalse Jun 19 12:02:35 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent Jun 19 12:02:35 volumio volumio[989]: verbose: ControllerMpd::parseState Jun 19 12:02:35 volumio volumio[989]: info: ControllerMpd::pushState Jun 19 12:02:35 volumio volumio[989]: info: CoreCommandRouter::servicePushState Jun 19 12:02:35 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:35 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:35 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 12:02:35 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:35 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:35 volumio volumio[989]: 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} Jun 19 12:02:35 volumio volumio[989]: verbose: CURRENT POSITION 0 Jun 19 12:02:35 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop Jun 19 12:02:35 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Jun 19 12:02:35 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:35 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:35 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:35 volumio volumio[989]: info: No code Jun 19 12:02:35 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:35 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:35 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:35 volumio volumio[989]: info: ControllerMpd::pushState Jun 19 12:02:35 volumio volumio[989]: info: CoreCommandRouter::servicePushState Jun 19 12:02:35 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:35 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:35 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:35 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:35 volumio volumio[989]: 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} Jun 19 12:02:35 volumio volumio[989]: verbose: CURRENT POSITION 0 Jun 19 12:02:35 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop Jun 19 12:02:35 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Jun 19 12:02:35 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:35 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:35 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:35 volumio volumio[989]: info: No code Jun 19 12:02:35 volumio volumio[989]: info: CoreStateMachine::pushState Jun 19 12:02:35 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Jun 19 12:02:35 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Jun 19 12:02:35 volumio volumio[989]: info: ------------------------------ 142ms Jun 19 12:02:35 volumio volumio[989]: info: ------------------------------ 141ms Jun 19 12:02:36 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 12:02:37 volumio volumio[989]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 12:02:37 volumio volumio[989]: TypeError: Cannot read property 'replace' of undefined Jun 19 12:02:37 volumio volumio[989]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Jun 19 12:02:37 volumio volumio[989]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Jun 19 12:02:37 volumio volumio[989]: at listOnTimeout (internal/timers.js:554:17) Jun 19 12:02:37 volumio volumio[989]: at processTimers (internal/timers.js:497:7) Jun 19 12:02:37 volumio volumio[989]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 12:02:37 volumio sudo[1868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-19 12:01 Jun 19 12:02:37 volumio sudo[1868]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"