-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-11-01 20:06:24 CET. -- Nov 01 20:05:10 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 13; SM-M225FV Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 01 20:05:10 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 13; SM-M225FV Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Nov 01 20:05:10 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 13; SM-M225FV Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Nov 01 20:05:10 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 13; SM-M225FV Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Nov 01 20:05:10 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 13; SM-M225FV Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Nov 01 20:05:10 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 13; SM-M225FV Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Nov 01 20:05:10 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 13; SM-M225FV Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Nov 01 20:05:10 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 13; SM-M225FV Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Nov 01 20:05:10 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 13; SM-M225FV Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Nov 01 20:05:10 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 13; SM-M225FV Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Nov 01 20:05:11 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 13; SM-M225FV Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Nov 01 20:05:11 volumio volumio[975]: info: Received Get System Info Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 20:05:11 volumio volumio[975]: info: Discovery: Getting this device information Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::volumioGetVisibleSources Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::volumioGetQueue Nov 01 20:05:11 volumio volumio[975]: info: CoreStateMachine::getQueue Nov 01 20:05:11 volumio volumio[975]: info: CorePlayQueue::getQueue Nov 01 20:05:11 volumio volumio[975]: info: Listing playlists Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 01 20:05:11 volumio volumio[975]: info: Received Get System Info Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 20:05:11 volumio volumio[975]: info: Discovery: Getting this device information Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:05:11 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 01 20:05:12 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 01 20:05:13 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 01 20:05:13 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 20:05:13 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 20:05:13 volumio volumio[975]: info: Discovery: Getting this device information Nov 01 20:05:13 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:05:13 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 20:05:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 01 20:05:15 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 01 20:05:17 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 13; SM-M225FV Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Nov 01 20:05:17 volumio volumio[975]: info: Received Get System Info Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 20:05:17 volumio volumio[975]: info: Discovery: Getting this device information Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::volumioGetVisibleSources Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::volumioGetQueue Nov 01 20:05:17 volumio volumio[975]: info: CoreStateMachine::getQueue Nov 01 20:05:17 volumio volumio[975]: info: CorePlayQueue::getQueue Nov 01 20:05:17 volumio volumio[975]: info: Listing playlists Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 01 20:05:17 volumio volumio[975]: info: Received Get System Info Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 20:05:17 volumio volumio[975]: info: Discovery: Getting this device information Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 01 20:05:17 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 01 20:05:19 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 01 20:05:20 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 01 20:05:20 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 20:05:20 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 20:05:20 volumio volumio[975]: info: Discovery: Getting this device information Nov 01 20:05:20 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:05:20 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 20:05:20 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.1.11:3000 from 192.168.1.7 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 16 Nov 01 20:05:20 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:05:20 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 01 20:05:20 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 01 20:05:21 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 01 20:05:22 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 01 20:05:30 volumio volumio[975]: info: Preload queue cleared Nov 01 20:05:30 volumio volumio[975]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 01 20:05:30 volumio volumio[975]: info: CoreStateMachine::ClearQueue Nov 01 20:05:30 volumio volumio[975]: info: CoreStateMachine::stop Nov 01 20:05:30 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 20:05:30 volumio volumio[975]: info: CorePlayQueue::clearPlayQueue Nov 01 20:05:30 volumio volumio[975]: info: CorePlayQueue::saveQueue Nov 01 20:05:30 volumio volumio[975]: info: CoreCommandRouter::volumioPushQueue Nov 01 20:05:30 volumio volumio[975]: info: CoreStateMachine::addQueueItems Nov 01 20:05:30 volumio volumio[975]: info: CorePlayQueue::addQueueItems Nov 01 20:05:30 volumio volumio[975]: info: Preload queue cleared Nov 01 20:05:30 volumio volumio[975]: info: Adding Item to queue: webrns/0 Nov 01 20:05:30 volumio volumio[975]: info: Using cached record of: webrns/0 Nov 01 20:05:30 volumio volumio[975]: info: CoreCommandRouter::volumioPushQueue Nov 01 20:05:30 volumio volumio[975]: info: CorePlayQueue::saveQueue Nov 01 20:05:30 volumio volumio[975]: info: CoreStateMachine::updateTrackBlock Nov 01 20:05:30 volumio volumio[975]: info: CorePlayQueue::getTrackBlock Nov 01 20:05:30 volumio volumio[975]: info: CoreCommandRouter::volumioPlay Nov 01 20:05:30 volumio volumio[975]: info: CoreStateMachine::play index 0 Nov 01 20:05:30 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 20:05:30 volumio volumio[975]: info: CoreStateMachine::stop Nov 01 20:05:30 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 20:05:30 volumio volumio[975]: info: CoreStateMachine::play index undefined Nov 01 20:05:30 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 20:05:30 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:30 volumio volumio[975]: info: CoreStateMachine::startPlaybackTimer Nov 01 20:05:30 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:30 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 20:05:30 volumio volumio[975]: info: Nov 01 20:05:30 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:30 volumio volumio[975]: info: sendMpdCommand stop took 4 milliseconds Nov 01 20:05:30 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:30 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:30 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Nov 01 20:05:30 volumio volumio[975]: info: Nov 01 20:05:30 volumio volumio[975]: ---------------------------- MPD announces system playlist update Nov 01 20:05:30 volumio volumio[975]: info: Ignoring MPD Status Update Nov 01 20:05:30 volumio volumio[975]: info: sendMpdCommand status took 2 milliseconds Nov 01 20:05:30 volumio volumio[975]: info: sendMpdCommand clear took 3 milliseconds Nov 01 20:05:30 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:30 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 20:05:30 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" Nov 01 20:05:30 volumio volumio[975]: info: Nov 01 20:05:30 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:30 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:30 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:30 volumio volumio[975]: info: Nov 01 20:05:30 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:30 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:30 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:30 volumio volumio[975]: error: updateQueue error: null Nov 01 20:05:30 volumio volumio[975]: info: Nov 01 20:05:30 volumio volumio[975]: ---------------------------- MPD announces system playlist update Nov 01 20:05:30 volumio volumio[975]: info: Ignoring MPD Status Update Nov 01 20:05:30 volumio volumio[975]: info: ------------------------------ 13ms Nov 01 20:05:30 volumio volumio[975]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 01 20:05:30 volumio volumio[975]: info: sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" took 8 milliseconds Nov 01 20:05:30 volumio volumio[975]: info: sendMpdCommand status took 5 milliseconds Nov 01 20:05:30 volumio volumio[975]: info: sendMpdCommand status took 3 milliseconds Nov 01 20:05:30 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Nov 01 20:05:30 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand play Nov 01 20:05:30 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:30 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:30 volumio volumio[975]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 01 20:05:30 volumio volumio[975]: info: ControllerMpd::pushState Nov 01 20:05:30 volumio volumio[975]: info: CoreCommandRouter::servicePushState Nov 01 20:05:30 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:05:30 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:30 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 20:05:30 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:05:31 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:31 volumio volumio[975]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rad357pl Received mpd Nov 01 20:05:31 volumio volumio[975]: info: ControllerMpd::pushState Nov 01 20:05:31 volumio volumio[975]: info: CoreCommandRouter::servicePushState Nov 01 20:05:31 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:05:31 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:31 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:05:31 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:31 volumio volumio[975]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rad357pl Received mpd Nov 01 20:05:31 volumio volumio[975]: info: ------------------------------ 41ms Nov 01 20:05:31 volumio volumio[975]: info: ------------------------------ 46ms Nov 01 20:05:31 volumio volumio[975]: info: ------------------------------ 46ms Nov 01 20:05:31 volumio volumio[975]: info: Nov 01 20:05:31 volumio volumio[975]: ---------------------------- MPD announces system playlist update Nov 01 20:05:31 volumio volumio[975]: info: Ignoring MPD Status Update Nov 01 20:05:31 volumio volumio[975]: info: Nov 01 20:05:31 volumio volumio[975]: ---------------------------- MPD announces system playlist update Nov 01 20:05:31 volumio volumio[975]: info: Ignoring MPD Status Update Nov 01 20:05:31 volumio volumio[975]: info: ------------------------------ 50ms Nov 01 20:05:31 volumio volumio[975]: info: sendMpdCommand play took 41 milliseconds Nov 01 20:05:31 volumio volumio[975]: info: ------------------------------ 7ms Nov 01 20:05:31 volumio volumio[975]: info: ------------------------------ 6ms Nov 01 20:05:31 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 01 20:05:32 volumio volumio[975]: info: Nov 01 20:05:32 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:32 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:32 volumio volumio[975]: info: Nov 01 20:05:32 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:32 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:32 volumio volumio[975]: info: Nov 01 20:05:32 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:32 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:32 volumio volumio[975]: info: Nov 01 20:05:32 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:32 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 01 20:05:32 volumio volumio[975]: info: sendMpdCommand status took 19 milliseconds Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 01 20:05:32 volumio volumio[975]: info: sendMpdCommand status took 18 milliseconds Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 01 20:05:32 volumio volumio[975]: info: sendMpdCommand status took 18 milliseconds Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 20:05:32 volumio volumio[975]: info: Nov 01 20:05:32 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:32 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:32 volumio volumio[975]: info: Nov 01 20:05:32 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:32 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 01 20:05:32 volumio volumio[975]: info: sendMpdCommand status took 54 milliseconds Nov 01 20:05:32 volumio volumio[975]: info: sendMpdCommand clearerror took 45 milliseconds Nov 01 20:05:32 volumio volumio[975]: info: sendMpdCommand clearerror took 43 milliseconds Nov 01 20:05:32 volumio volumio[975]: info: sendMpdCommand clearerror took 41 milliseconds Nov 01 20:05:32 volumio volumio[975]: info: sendMpdCommand playlistinfo took 24 milliseconds Nov 01 20:05:32 volumio volumio[975]: info: sendMpdCommand playlistinfo took 24 milliseconds Nov 01 20:05:32 volumio volumio[975]: info: sendMpdCommand playlistinfo took 22 milliseconds Nov 01 20:05:32 volumio volumio[975]: info: sendMpdCommand status took 19 milliseconds Nov 01 20:05:32 volumio volumio[975]: info: sendMpdCommand status took 17 milliseconds Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 20:05:32 volumio volumio[975]: info: ControllerMpd::pushState Nov 01 20:05:32 volumio volumio[975]: info: CoreCommandRouter::servicePushState Nov 01 20:05:32 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:32 volumio volumio[975]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Nov 01 20:05:32 volumio volumio[975]: verbose: CURRENT POSITION 0 Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::syncState stateService pause Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:05:32 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 20:05:32 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:05:32 volumio volumio[975]: info: ControllerMpd::pushState Nov 01 20:05:32 volumio volumio[975]: info: CoreCommandRouter::servicePushState Nov 01 20:05:32 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:32 volumio volumio[975]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Nov 01 20:05:32 volumio volumio[975]: verbose: CURRENT POSITION 0 Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::syncState stateService pause Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:05:32 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:05:32 volumio volumio[975]: info: ControllerMpd::pushState Nov 01 20:05:32 volumio volumio[975]: info: CoreCommandRouter::servicePushState Nov 01 20:05:32 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:32 volumio volumio[975]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Nov 01 20:05:32 volumio volumio[975]: verbose: CURRENT POSITION 0 Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::syncState stateService pause Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:05:32 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:05:32 volumio volumio[975]: info: ------------------------------ 146ms Nov 01 20:05:32 volumio volumio[975]: info: ------------------------------ 145ms Nov 01 20:05:32 volumio volumio[975]: info: ------------------------------ 143ms Nov 01 20:05:32 volumio volumio[975]: info: sendMpdCommand clearerror took 92 milliseconds Nov 01 20:05:32 volumio volumio[975]: info: sendMpdCommand playlistinfo took 86 milliseconds Nov 01 20:05:32 volumio volumio[975]: info: sendMpdCommand playlistinfo took 84 milliseconds Nov 01 20:05:32 volumio volumio[975]: info: sendMpdCommand playlistinfo took 84 milliseconds Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Nov 01 20:05:32 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Nov 01 20:05:32 volumio volumio[975]: info: ControllerMpd::pushState Nov 01 20:05:32 volumio volumio[975]: info: CoreCommandRouter::servicePushState Nov 01 20:05:32 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:32 volumio volumio[975]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Nov 01 20:05:32 volumio volumio[975]: verbose: CURRENT POSITION 0 Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::syncState stateService pause Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:05:32 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:05:32 volumio volumio[975]: info: ControllerMpd::pushState Nov 01 20:05:32 volumio volumio[975]: info: CoreCommandRouter::servicePushState Nov 01 20:05:32 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:32 volumio volumio[975]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Nov 01 20:05:32 volumio volumio[975]: verbose: CURRENT POSITION 0 Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::syncState stateService pause Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:05:32 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:05:32 volumio volumio[975]: info: ControllerMpd::pushState Nov 01 20:05:32 volumio volumio[975]: info: CoreCommandRouter::servicePushState Nov 01 20:05:32 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:32 volumio volumio[975]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Nov 01 20:05:32 volumio volumio[975]: verbose: CURRENT POSITION 0 Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::syncState stateService pause Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Nov 01 20:05:32 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:05:32 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:05:32 volumio volumio[975]: info: ------------------------------ 195ms Nov 01 20:05:32 volumio volumio[975]: info: ------------------------------ 157ms Nov 01 20:05:32 volumio volumio[975]: info: ------------------------------ 155ms Nov 01 20:05:44 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 20:05:44 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 20:05:44 volumio volumio[975]: info: Discovery: Getting this device information Nov 01 20:05:44 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:05:44 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 20:05:45 volumio volumio[975]: info: Preload queue cleared Nov 01 20:05:45 volumio volumio[975]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 01 20:05:45 volumio volumio[975]: info: CoreStateMachine::ClearQueue Nov 01 20:05:45 volumio volumio[975]: info: CoreStateMachine::stop Nov 01 20:05:45 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 20:05:45 volumio volumio[975]: info: CorePlayQueue::clearPlayQueue Nov 01 20:05:45 volumio volumio[975]: info: CorePlayQueue::saveQueue Nov 01 20:05:45 volumio volumio[975]: info: CoreCommandRouter::volumioPushQueue Nov 01 20:05:45 volumio volumio[975]: info: CoreStateMachine::addQueueItems Nov 01 20:05:45 volumio volumio[975]: info: CorePlayQueue::addQueueItems Nov 01 20:05:45 volumio volumio[975]: info: Preload queue cleared Nov 01 20:05:45 volumio volumio[975]: info: Adding Item to queue: webrns/0 Nov 01 20:05:45 volumio volumio[975]: info: Using cached record of: webrns/0 Nov 01 20:05:45 volumio volumio[975]: info: CoreCommandRouter::volumioPushQueue Nov 01 20:05:45 volumio volumio[975]: info: CorePlayQueue::saveQueue Nov 01 20:05:45 volumio volumio[975]: info: CoreStateMachine::updateTrackBlock Nov 01 20:05:45 volumio volumio[975]: info: CorePlayQueue::getTrackBlock Nov 01 20:05:45 volumio volumio[975]: info: CoreCommandRouter::volumioPlay Nov 01 20:05:45 volumio volumio[975]: info: CoreStateMachine::play index 0 Nov 01 20:05:45 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 20:05:45 volumio volumio[975]: info: CoreStateMachine::stop Nov 01 20:05:45 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 20:05:45 volumio volumio[975]: info: CoreStateMachine::play index undefined Nov 01 20:05:45 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 20:05:45 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:45 volumio volumio[975]: info: CoreStateMachine::startPlaybackTimer Nov 01 20:05:45 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:45 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 20:05:45 volumio volumio[975]: info: Nov 01 20:05:45 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:45 volumio volumio[975]: info: sendMpdCommand stop took 4 milliseconds Nov 01 20:05:45 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:45 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:45 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Nov 01 20:05:45 volumio volumio[975]: info: Nov 01 20:05:45 volumio volumio[975]: ---------------------------- MPD announces system playlist update Nov 01 20:05:45 volumio volumio[975]: info: Ignoring MPD Status Update Nov 01 20:05:45 volumio volumio[975]: info: sendMpdCommand status took 3 milliseconds Nov 01 20:05:45 volumio volumio[975]: info: sendMpdCommand clear took 3 milliseconds Nov 01 20:05:45 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:45 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 20:05:45 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" Nov 01 20:05:45 volumio volumio[975]: info: Nov 01 20:05:45 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:45 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:45 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:45 volumio volumio[975]: info: Nov 01 20:05:45 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:45 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:45 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:45 volumio volumio[975]: error: updateQueue error: null Nov 01 20:05:45 volumio volumio[975]: info: Nov 01 20:05:45 volumio volumio[975]: ---------------------------- MPD announces system playlist update Nov 01 20:05:45 volumio volumio[975]: info: Ignoring MPD Status Update Nov 01 20:05:45 volumio volumio[975]: info: ------------------------------ 9ms Nov 01 20:05:45 volumio volumio[975]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 01 20:05:45 volumio volumio[975]: info: sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" took 7 milliseconds Nov 01 20:05:45 volumio volumio[975]: info: sendMpdCommand status took 5 milliseconds Nov 01 20:05:45 volumio volumio[975]: info: sendMpdCommand status took 5 milliseconds Nov 01 20:05:45 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Nov 01 20:05:45 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand play Nov 01 20:05:45 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:45 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:45 volumio volumio[975]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 01 20:05:45 volumio volumio[975]: info: ControllerMpd::pushState Nov 01 20:05:45 volumio volumio[975]: info: CoreCommandRouter::servicePushState Nov 01 20:05:45 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:05:45 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:45 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 20:05:45 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:05:45 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:45 volumio volumio[975]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rad357pl Received mpd Nov 01 20:05:45 volumio volumio[975]: info: ControllerMpd::pushState Nov 01 20:05:45 volumio volumio[975]: info: CoreCommandRouter::servicePushState Nov 01 20:05:45 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:05:45 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:45 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:05:45 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:45 volumio volumio[975]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rad357pl Received mpd Nov 01 20:05:45 volumio volumio[975]: info: ------------------------------ 43ms Nov 01 20:05:45 volumio volumio[975]: info: ------------------------------ 49ms Nov 01 20:05:45 volumio volumio[975]: info: ------------------------------ 49ms Nov 01 20:05:45 volumio volumio[975]: info: Nov 01 20:05:45 volumio volumio[975]: ---------------------------- MPD announces system playlist update Nov 01 20:05:45 volumio volumio[975]: info: Ignoring MPD Status Update Nov 01 20:05:45 volumio volumio[975]: info: Nov 01 20:05:45 volumio volumio[975]: ---------------------------- MPD announces system playlist update Nov 01 20:05:45 volumio volumio[975]: info: Ignoring MPD Status Update Nov 01 20:05:45 volumio volumio[975]: info: ------------------------------ 52ms Nov 01 20:05:45 volumio volumio[975]: info: sendMpdCommand play took 42 milliseconds Nov 01 20:05:45 volumio volumio[975]: info: ------------------------------ 6ms Nov 01 20:05:45 volumio volumio[975]: info: ------------------------------ 5ms Nov 01 20:05:45 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 01 20:05:47 volumio volumio[975]: info: Nov 01 20:05:47 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:47 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:47 volumio volumio[975]: info: Nov 01 20:05:47 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:47 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:47 volumio volumio[975]: info: Nov 01 20:05:47 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:47 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:47 volumio volumio[975]: info: Nov 01 20:05:47 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:47 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 01 20:05:47 volumio volumio[975]: info: sendMpdCommand status took 18 milliseconds Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 01 20:05:47 volumio volumio[975]: info: sendMpdCommand status took 17 milliseconds Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 01 20:05:47 volumio volumio[975]: info: sendMpdCommand status took 16 milliseconds Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 20:05:47 volumio volumio[975]: info: Nov 01 20:05:47 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:47 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:47 volumio volumio[975]: info: Nov 01 20:05:47 volumio volumio[975]: ---------------------------- MPD announces state update: player Nov 01 20:05:47 volumio volumio[975]: info: ControllerMpd::getState Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 01 20:05:47 volumio volumio[975]: info: sendMpdCommand status took 54 milliseconds Nov 01 20:05:47 volumio volumio[975]: info: sendMpdCommand clearerror took 45 milliseconds Nov 01 20:05:47 volumio volumio[975]: info: sendMpdCommand clearerror took 44 milliseconds Nov 01 20:05:47 volumio volumio[975]: info: sendMpdCommand clearerror took 42 milliseconds Nov 01 20:05:47 volumio volumio[975]: info: sendMpdCommand playlistinfo took 23 milliseconds Nov 01 20:05:47 volumio volumio[975]: info: sendMpdCommand playlistinfo took 21 milliseconds Nov 01 20:05:47 volumio volumio[975]: info: sendMpdCommand playlistinfo took 21 milliseconds Nov 01 20:05:47 volumio volumio[975]: info: sendMpdCommand status took 17 milliseconds Nov 01 20:05:47 volumio volumio[975]: info: sendMpdCommand status took 16 milliseconds Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::parseState Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 20:05:47 volumio volumio[975]: info: ControllerMpd::pushState Nov 01 20:05:47 volumio volumio[975]: info: CoreCommandRouter::servicePushState Nov 01 20:05:47 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:47 volumio volumio[975]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Nov 01 20:05:47 volumio volumio[975]: verbose: CURRENT POSITION 0 Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::syncState stateService pause Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:05:47 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 20:05:47 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:05:47 volumio volumio[975]: info: ControllerMpd::pushState Nov 01 20:05:47 volumio volumio[975]: info: CoreCommandRouter::servicePushState Nov 01 20:05:47 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:47 volumio volumio[975]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Nov 01 20:05:47 volumio volumio[975]: verbose: CURRENT POSITION 0 Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::syncState stateService pause Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:05:47 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:05:47 volumio volumio[975]: info: ControllerMpd::pushState Nov 01 20:05:47 volumio volumio[975]: info: CoreCommandRouter::servicePushState Nov 01 20:05:47 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:47 volumio volumio[975]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Nov 01 20:05:47 volumio volumio[975]: verbose: CURRENT POSITION 0 Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::syncState stateService pause Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:05:47 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:05:47 volumio volumio[975]: info: ------------------------------ 140ms Nov 01 20:05:47 volumio volumio[975]: info: ------------------------------ 138ms Nov 01 20:05:47 volumio volumio[975]: info: ------------------------------ 137ms Nov 01 20:05:47 volumio volumio[975]: info: sendMpdCommand clearerror took 91 milliseconds Nov 01 20:05:47 volumio volumio[975]: info: sendMpdCommand playlistinfo took 86 milliseconds Nov 01 20:05:47 volumio volumio[975]: info: sendMpdCommand playlistinfo took 84 milliseconds Nov 01 20:05:47 volumio volumio[975]: info: sendMpdCommand playlistinfo took 83 milliseconds Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Nov 01 20:05:47 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Nov 01 20:05:47 volumio volumio[975]: info: ControllerMpd::pushState Nov 01 20:05:47 volumio volumio[975]: info: CoreCommandRouter::servicePushState Nov 01 20:05:47 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:47 volumio volumio[975]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Nov 01 20:05:47 volumio volumio[975]: verbose: CURRENT POSITION 0 Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::syncState stateService pause Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:05:47 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:05:47 volumio volumio[975]: info: ControllerMpd::pushState Nov 01 20:05:47 volumio volumio[975]: info: CoreCommandRouter::servicePushState Nov 01 20:05:47 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:47 volumio volumio[975]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Nov 01 20:05:47 volumio volumio[975]: verbose: CURRENT POSITION 0 Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::syncState stateService pause Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:05:47 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:05:47 volumio volumio[975]: info: ControllerMpd::pushState Nov 01 20:05:47 volumio volumio[975]: info: CoreCommandRouter::servicePushState Nov 01 20:05:47 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Nov 01 20:05:47 volumio volumio[975]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Nov 01 20:05:47 volumio volumio[975]: verbose: CURRENT POSITION 0 Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::syncState stateService pause Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Nov 01 20:05:47 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:05:47 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:05:47 volumio volumio[975]: info: ------------------------------ 195ms Nov 01 20:05:47 volumio volumio[975]: info: ------------------------------ 156ms Nov 01 20:05:47 volumio volumio[975]: info: ------------------------------ 154ms Nov 01 20:06:01 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:06:03 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 20:06:03 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:03 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:03 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:03 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:03 volumio volumio[975]: Bus error Nov 01 20:06:03 volumio volumio[975]: Cannot get aplay -l output: Error: Command failed: /usr/bin/aplay -l Nov 01 20:06:03 volumio volumio[975]: Bus error Nov 01 20:06:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 01 20:06:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 20:06:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 01 20:06:03 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:03 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:03 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:03 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:03 volumio volumio[975]: Bus error Nov 01 20:06:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 20:06:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 20:06:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 20:06:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 20:06:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 20:06:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 20:06:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 20:06:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 01 20:06:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 01 20:06:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 20:06:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 20:06:14 volumio volumio[975]: info: Discovery: Getting this device information Nov 01 20:06:14 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:06:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 20:06:22 volumio volumio[975]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Nov 01 20:06:22 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Nov 01 20:06:22 volumio volumio[975]: info: Preparing to save Alsa Options, stopping services first Nov 01 20:06:22 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Nov 01 20:06:22 volumio volumio[975]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"Brak dostępnych urządzeń audio"},"i2s":true,"i2sid":{"value":"generic-dac","label":"Generic I2S DAC"}} Nov 01 20:06:22 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 01 20:06:22 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Nov 01 20:06:22 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 20:06:22 volumio volumio[975]: info: Enabling I2S DAC: Generic I2S DAC Nov 01 20:06:22 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Nov 01 20:06:22 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 20:06:22 volumio sudo[1870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Nov 01 20:06:22 volumio sudo[1870]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 20:06:23 volumio sudo[1870]: pam_unix(sudo:session): session closed for user root Nov 01 20:06:23 volumio volumio[975]: info: No Overlays Loaded Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 20:06:23 volumio sudo[1873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dac Nov 01 20:06:23 volumio sudo[1873]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 20:06:23 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Nov 01 20:06:23 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /pcm5102a-codec/#sound-dai-cells Nov 01 20:06:23 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /pcm5102a-codec/compatible Nov 01 20:06:23 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /pcm5102a-codec/status Nov 01 20:06:23 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Nov 01 20:06:23 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Nov 01 20:06:23 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Nov 01 20:06:23 volumio volumio[975]: Bus error Nov 01 20:06:23 volumio volumio[975]: Cannot get aplay -l output: Error: Command failed: /usr/bin/aplay -l Nov 01 20:06:23 volumio volumio[975]: Bus error Nov 01 20:06:23 volumio volumio[975]: warn: Unable to locate the device Brak dostępnych urządzeń audio a reboot may be required. Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio sudo[1873]: pam_unix(sudo:session): session closed for user root Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio volumio[975]: Bus error Nov 01 20:06:23 volumio volumio[975]: Cannot get aplay -l output: Error: Command failed: /usr/bin/aplay -l Nov 01 20:06:23 volumio volumio[975]: Bus error Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio volumio[975]: Bus error Nov 01 20:06:23 volumio volumio[975]: Bus error Nov 01 20:06:23 volumio volumio[975]: Cannot get aplay -l output: Error: Command failed: /usr/bin/aplay -l Nov 01 20:06:23 volumio volumio[975]: Bus error Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 01 20:06:23 volumio volumio[975]: info: Updating Volume Controller Parameters: Device: 2 Name: Generic I2S DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 01 20:06:23 volumio volumio[975]: info: Disabling external Volume Control Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Nov 01 20:06:23 volumio volumio[975]: info: Preparing to generate the ALSA configuration file Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio volumio[975]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Nov 01 20:06:23 volumio volumio[975]: info: I2S Param [object Object] successfully enabled Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio volumio[975]: Bus error Nov 01 20:06:23 volumio volumio[975]: Cannot get aplay -l output: Error: Command failed: /usr/bin/aplay -l Nov 01 20:06:23 volumio volumio[975]: Bus error Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 01 20:06:23 volumio volumio[975]: info: Getting Alsa Cards List without I2S DAC Nov 01 20:06:23 volumio volumio[975]: Bus error Nov 01 20:06:23 volumio volumio[975]: Cannot get aplay -l output: Error: Command failed: /usr/bin/aplay -l Nov 01 20:06:23 volumio volumio[975]: Bus error Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio volumio[975]: Bus error Nov 01 20:06:23 volumio volumio[975]: Cannot get aplay -l output: Error: Command failed: /usr/bin/aplay -l Nov 01 20:06:23 volumio volumio[975]: Bus error Nov 01 20:06:23 volumio volumio[975]: error: Error retrieving UIConf: TypeError: Cannot read property 'id' of undefined Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio volumio[975]: info: VolumeController:: Volume=undefined Mute =false Nov 01 20:06:23 volumio volumio[975]: info: CoreStateMachine::pushState Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 20:06:23 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Nov 01 20:06:23 volumio volumio[975]: info: Asound.conf file written Nov 01 20:06:23 volumio sudo[1898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 01 20:06:23 volumio sudo[1898]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 20:06:23 volumio sudo[1898]: pam_unix(sudo:session): session closed for user root Nov 01 20:06:23 volumio volumio[975]: Bus error Nov 01 20:06:23 volumio volumio[975]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read data cache entry [10f57142] Nov 01 20:06:23 volumio kernel: SQUASHFS error: Unable to read page, block 10f57142, size 1188c Nov 01 20:06:23 volumio volumio[975]: Error: Command failed: /usr/sbin/alsactl -L -R nrestore Nov 01 20:06:23 volumio volumio[975]: Bus error Nov 01 20:06:23 volumio volumio[975]: at checkExecSyncError (child_process.js:616:11) Nov 01 20:06:23 volumio volumio[975]: at execSync (child_process.js:652:15) Nov 01 20:06:23 volumio volumio[975]: at /volumio/app/plugins/audio_interface/alsa_controller/index.js:2219:13 Nov 01 20:06:23 volumio volumio[975]: at /volumio/node_modules/graceful-fs/graceful-fs.js:144:16 Nov 01 20:06:23 volumio volumio[975]: at /volumio/node_modules/graceful-fs/graceful-fs.js:61:14 Nov 01 20:06:23 volumio volumio[975]: at FSReqCallback.oncomplete (fs.js:171:23) { Nov 01 20:06:23 volumio volumio[975]: status: 135, Nov 01 20:06:23 volumio volumio[975]: signal: null, Nov 01 20:06:23 volumio volumio[975]: output: [ null, '', 'Bus error\n' ], Nov 01 20:06:23 volumio volumio[975]: pid: 1900, Nov 01 20:06:23 volumio volumio[975]: stdout: '', Nov 01 20:06:23 volumio volumio[975]: stderr: 'Bus error\n' Nov 01 20:06:23 volumio volumio[975]: } Nov 01 20:06:23 volumio volumio[975]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 20:06:24 volumio sudo[1914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 20:05 Nov 01 20:06:24 volumio sudo[1914]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"