-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2024-08-21 13:44:58 CEST. -- Aug 21 13:43:06 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 21 13:43:10 volumio kernel: hwmon hwmon1: Voltage normalised Aug 21 13:43:12 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 21 13:43:14 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:14 volumio volumio[1103]: info: CoreCommandRouter::volumioPlay Aug 21 13:43:14 volumio volumio[1103]: info: CoreStateMachine::play index undefined Aug 21 13:43:14 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 21 13:43:14 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:14 volumio volumio[1103]: info: CoreStateMachine::startPlaybackTimer Aug 21 13:43:14 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:14 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 21 13:43:14 volumio volumio[1103]: info: ControllerMpd::resume Aug 21 13:43:14 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand play Aug 21 13:43:14 volumio volumio[1103]: info: sendMpdCommand play took 3 milliseconds Aug 21 13:43:16 volumio volumio[1103]: info: Executing endpoint metavolumio Aug 21 13:43:16 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 21 13:43:16 volumio volumio[1103]: info: Executing endpoint metavolumio Aug 21 13:43:16 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 21 13:43:16 volumio volumio[1103]: info: Executing endpoint metavolumio Aug 21 13:43:16 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 21 13:43:19 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Aug 21 13:43:19 volumio volumio[1103]: info: MRS: Starting browser stream Aug 21 13:43:19 volumio volumio[1103]: info: MRS: Setting this device as Streaming Server Aug 21 13:43:19 volumio volumio[1103]: info: Aug 21 13:43:19 volumio volumio[1103]: [1724240599142] ---------------------------- MRS: Setting Streaming Server Aug 21 13:43:19 volumio volumio[1103]: info: Enabled audio output: browserPlayback Aug 21 13:43:19 volumio volumio[1103]: info: MRS: enable multiroom server output Aug 21 13:43:19 volumio volumio[1103]: info: MRS: Set multiroom target PCM to volumioMultiRoom Aug 21 13:43:19 volumio volumio[1103]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Aug 21 13:43:19 volumio volumio[1103]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Aug 21 13:43:19 volumio volumio[1103]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Aug 21 13:43:19 volumio volumio[1103]: info: MRS: STARTING volumioStreaming Aug 21 13:43:19 volumio sudo[20215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 21 13:43:19 volumio sudo[20215]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:19 volumio sudo[20215]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:19 volumio sudo[20218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Aug 21 13:43:19 volumio sudo[20218]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:19 volumio systemd[1]: Started VolumioStreamingService. Aug 21 13:43:19 volumio sudo[20218]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:19 volumio volumio[1103]: info: MRS: volumioStreaming STARTED Aug 21 13:43:19 volumio sudo[20221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 21 13:43:19 volumio sudo[20221]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:19 volumio sudo[20221]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:21 volumio volumio[1103]: info: CoreCommandRouter::volumioPlay Aug 21 13:43:21 volumio volumio[1103]: info: CoreStateMachine::play index undefined Aug 21 13:43:21 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 21 13:43:21 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:21 volumio volumio[1103]: info: CoreStateMachine::startPlaybackTimer Aug 21 13:43:21 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:21 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 21 13:43:21 volumio volumio[1103]: info: ControllerMpd::resume Aug 21 13:43:21 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand play Aug 21 13:43:21 volumio volumio[1103]: info: sendMpdCommand play took 1 milliseconds Aug 21 13:43:23 volumio volumio[1103]: info: CoreCommandRouter::volumioSeek Aug 21 13:43:23 volumio volumio[1103]: info: CoreStateMachine::seek Aug 21 13:43:23 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:23 volumio volumio[1103]: info: TRACKBLOCK {"service":"soundcloud","uri":"soundcloud/track@trackId=1876735539@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D","albumart":"https://i1.sndcdn.com/artworks-u6zoFtLRdl3VwdR8-eqxAtg-t500x500.jpg","artist":"whyyjerryy","album":"SoundCloud Track","name":"do you remember the time? (prod. realoneacoustc)","title":"do you remember the time? (prod. realoneacoustc)","samplerate":"128 kbps"} Aug 21 13:43:23 volumio volumio[1103]: info: CoreStateMachine::startPlaybackTimer Aug 21 13:43:23 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:23 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 21 13:43:23 volumio volumio[1103]: info: ControllerMpd::seek Aug 21 13:43:23 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:43:23 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 21 13:43:23 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:43:23 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:43:23 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:43:23 volumio volumio[1103]: error: [2@0] {seek} Bad song index Aug 21 13:43:23 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status pause Aug 21 13:43:23 volumio sudo[20225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 21 13:43:23 volumio sudo[20225]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:23 volumio sudo[20225]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:23 volumio volumio[1103]: info: peppyspectrum Daemon Stop Aug 21 13:43:23 volumio kernel: hwmon hwmon1: Voltage normalised Aug 21 13:43:23 volumio nmbd[762]: [2024/08/21 13:43:23.437058, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 21 13:43:23 volumio nmbd[762]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.137 for name WORKGROUP<1d>. Aug 21 13:43:23 volumio nmbd[762]: This response was from IP 192.168.178.73, reporting an IP address of 192.168.178.73. Aug 21 13:43:23 volumio nmbd[762]: [2024/08/21 13:43:23.437353, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 21 13:43:23 volumio nmbd[762]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.165 for name WORKGROUP<1d>. Aug 21 13:43:23 volumio nmbd[762]: This response was from IP 192.168.178.73, reporting an IP address of 192.168.178.73. Aug 21 13:43:23 volumio nmbd[762]: [2024/08/21 13:43:23.437540, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 21 13:43:23 volumio nmbd[762]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.178.137 for name WORKGROUP<1d>. Aug 21 13:43:23 volumio nmbd[762]: This response was from IP 192.168.178.48, reporting an IP address of 192.168.178.73. Aug 21 13:43:23 volumio nmbd[762]: [2024/08/21 13:43:23.437719, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 21 13:43:23 volumio nmbd[762]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.178.165 for name WORKGROUP<1d>. Aug 21 13:43:23 volumio nmbd[762]: This response was from IP 192.168.178.48, reporting an IP address of 192.168.178.73. Aug 21 13:43:23 volumio nmbd[762]: [2024/08/21 13:43:23.440986, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 21 13:43:23 volumio nmbd[762]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.178.137 for name WORKGROUP<1d>. Aug 21 13:43:23 volumio nmbd[762]: This response was from IP 192.168.178.48, reporting an IP address of 192.168.178.73. Aug 21 13:43:23 volumio nmbd[762]: [2024/08/21 13:43:23.441237, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 21 13:43:23 volumio nmbd[762]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.178.165 for name WORKGROUP<1d>. Aug 21 13:43:23 volumio nmbd[762]: This response was from IP 192.168.178.48, reporting an IP address of 192.168.178.73. Aug 21 13:43:23 volumio nmbd[762]: [2024/08/21 13:43:23.441635, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 21 13:43:23 volumio nmbd[762]: query_name_response: Multiple (5) responses received for a query on subnet 192.168.178.137 for name WORKGROUP<1d>. Aug 21 13:43:23 volumio nmbd[762]: This response was from IP 192.168.178.73, reporting an IP address of 192.168.178.73. Aug 21 13:43:23 volumio nmbd[762]: [2024/08/21 13:43:23.441844, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 21 13:43:23 volumio nmbd[762]: query_name_response: Multiple (5) responses received for a query on subnet 192.168.178.165 for name WORKGROUP<1d>. Aug 21 13:43:23 volumio nmbd[762]: This response was from IP 192.168.178.73, reporting an IP address of 192.168.178.73. Aug 21 13:43:23 volumio nmbd[762]: [2024/08/21 13:43:23.442066, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 21 13:43:23 volumio nmbd[762]: query_name_response: Multiple (6) responses received for a query on subnet 192.168.178.137 for name WORKGROUP<1d>. Aug 21 13:43:23 volumio nmbd[762]: This response was from IP 192.168.178.48, reporting an IP address of 192.168.178.73. Aug 21 13:43:23 volumio nmbd[762]: [2024/08/21 13:43:23.442285, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 21 13:43:23 volumio nmbd[762]: query_name_response: Multiple (6) responses received for a query on subnet 192.168.178.165 for name WORKGROUP<1d>. Aug 21 13:43:23 volumio nmbd[762]: This response was from IP 192.168.178.48, reporting an IP address of 192.168.178.73. Aug 21 13:43:24 volumio volumio[1103]: info: CoreCommandRouter::volumioSeek Aug 21 13:43:24 volumio volumio[1103]: info: CoreStateMachine::seek Aug 21 13:43:24 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:24 volumio volumio[1103]: info: TRACKBLOCK {"service":"soundcloud","uri":"soundcloud/track@trackId=1876735539@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D","albumart":"https://i1.sndcdn.com/artworks-u6zoFtLRdl3VwdR8-eqxAtg-t500x500.jpg","artist":"whyyjerryy","album":"SoundCloud Track","name":"do you remember the time? (prod. realoneacoustc)","title":"do you remember the time? (prod. realoneacoustc)","samplerate":"128 kbps"} Aug 21 13:43:24 volumio volumio[1103]: info: CoreStateMachine::startPlaybackTimer Aug 21 13:43:24 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:24 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 21 13:43:24 volumio volumio[1103]: info: ControllerMpd::seek Aug 21 13:43:24 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:43:24 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 21 13:43:24 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:43:24 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:43:24 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:43:24 volumio volumio[1103]: error: [2@0] {seek} Bad song index Aug 21 13:43:24 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status pause Aug 21 13:43:24 volumio sudo[20229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 21 13:43:24 volumio sudo[20229]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:24 volumio sudo[20229]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:24 volumio volumio[1103]: info: peppyspectrum Daemon Stop Aug 21 13:43:25 volumio volumio[1103]: info: CoreCommandRouter::volumioRandom Aug 21 13:43:25 volumio volumio[1103]: info: CoreCommandRouter::writePlayerControls Aug 21 13:43:25 volumio volumio[1103]: info: CoreStateMachine::setRepeat true single false Aug 21 13:43:25 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:43:25 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 21 13:43:25 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:43:25 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:43:25 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:43:25 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status pause Aug 21 13:43:25 volumio sudo[20232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 21 13:43:25 volumio sudo[20232]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:25 volumio sudo[20232]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:25 volumio volumio[1103]: info: peppyspectrum Daemon Stop Aug 21 13:43:26 volumio volumio[1103]: info: MRS: STARTING volumioStreaming Aug 21 13:43:26 volumio sudo[20291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 21 13:43:26 volumio sudo[20291]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:26 volumio sudo[20291]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:26 volumio sudo[20294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Aug 21 13:43:26 volumio sudo[20294]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:26 volumio systemd[1]: Stopping VolumioStreamingService... Aug 21 13:43:26 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Aug 21 13:43:26 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 21 13:43:26 volumio systemd[1]: volumioStreaming.service: Succeeded. Aug 21 13:43:26 volumio systemd[1]: Stopped VolumioStreamingService. Aug 21 13:43:26 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 21 13:43:26 volumio systemd[1]: Started VolumioStreamingService. Aug 21 13:43:26 volumio sudo[20294]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:26 volumio sudo[20301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 21 13:43:26 volumio sudo[20301]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:26 volumio volumio[1103]: info: MRS: volumioStreaming STARTED Aug 21 13:43:26 volumio sudo[20301]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:27 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 21 13:43:27 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:27 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:27 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:30 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 21 13:43:30 volumio volumio[1103]: info: CURURI: artists:// Aug 21 13:43:30 volumio volumio[1103]: info: Preload queue cleared Aug 21 13:43:31 volumio kernel: hwmon hwmon1: Voltage normalised Aug 21 13:43:34 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: soundcloud , handleBrowseUri Aug 21 13:43:34 volumio volumio[1103]: info: [soundcloud] browseUri: soundcloud Aug 21 13:43:35 volumio volumio[1103]: info: Preload queue cleared Aug 21 13:43:35 volumio volumio[1103]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1895884134 Aug 21 13:43:35 volumio volumio[1103]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1885018041 Aug 21 13:43:35 volumio volumio[1103]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1882701765 Aug 21 13:43:35 volumio volumio[1103]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1884912384 Aug 21 13:43:35 volumio volumio[1103]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1883448855 Aug 21 13:43:35 volumio volumio[1103]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1861291464 Aug 21 13:43:35 volumio volumio[1103]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1893595887 Aug 21 13:43:35 volumio volumio[1103]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1898102283 Aug 21 13:43:35 volumio volumio[1103]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1860933699 Aug 21 13:43:35 volumio volumio[1103]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1898205075 Aug 21 13:43:35 volumio volumio[1103]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1889945055 Aug 21 13:43:35 volumio volumio[1103]: info: Preload queue cleared Aug 21 13:43:35 volumio volumio[1103]: info: Preload queue cleared Aug 21 13:43:35 volumio volumio[1103]: info: Preload queue cleared Aug 21 13:43:35 volumio volumio[1103]: info: Preload queue cleared Aug 21 13:43:35 volumio volumio[1103]: info: Preload queue cleared Aug 21 13:43:35 volumio volumio[1103]: info: Preload queue cleared Aug 21 13:43:35 volumio volumio[1103]: info: Preload queue cleared Aug 21 13:43:35 volumio volumio[1103]: info: Preload queue cleared Aug 21 13:43:35 volumio volumio[1103]: info: Preload queue cleared Aug 21 13:43:35 volumio volumio[1103]: info: Preload queue cleared Aug 21 13:43:35 volumio volumio[1103]: info: Preload queue cleared Aug 21 13:43:36 volumio volumio[1103]: info: Preload queue cleared Aug 21 13:43:36 volumio volumio[1103]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::ClearQueue Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::stop Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::updateTrackBlock Aug 21 13:43:36 volumio volumio[1103]: info: CorePlayQueue::getTrackBlock Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::stPlaybackTimer Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:43:36 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:36 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 21 13:43:36 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:43:36 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:43:36 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::serviceStop Aug 21 13:43:36 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:36 volumio volumio[1103]: info: CoreCommandRouter::serviceStop Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 21 13:43:36 volumio volumio[1103]: info: ControllerMpd::stop Aug 21 13:43:36 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand stop Aug 21 13:43:36 volumio volumio[1103]: info: CorePlayQueue::clearPlayQueue Aug 21 13:43:36 volumio volumio[1103]: info: CorePlayQueue::saveQueue Aug 21 13:43:36 volumio volumio[1103]: info: CoreCommandRouter::volumioPushQueue Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::addQueueItems Aug 21 13:43:36 volumio volumio[1103]: info: CorePlayQueue::addQueueItems Aug 21 13:43:36 volumio volumio[1103]: info: Preload queue cleared Aug 21 13:43:36 volumio volumio[1103]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1895884134 Aug 21 13:43:36 volumio volumio[1103]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1895884134 in service soundcloud Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1895884134 Aug 21 13:43:36 volumio volumio[1103]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1885018041 Aug 21 13:43:36 volumio volumio[1103]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1885018041 in service soundcloud Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1885018041 Aug 21 13:43:36 volumio volumio[1103]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1882701765 Aug 21 13:43:36 volumio volumio[1103]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1882701765 in service soundcloud Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1882701765 Aug 21 13:43:36 volumio volumio[1103]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1884912384 Aug 21 13:43:36 volumio volumio[1103]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1884912384 in service soundcloud Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1884912384 Aug 21 13:43:36 volumio volumio[1103]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1883448855 Aug 21 13:43:36 volumio volumio[1103]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1883448855 in service soundcloud Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1883448855 Aug 21 13:43:36 volumio volumio[1103]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1861291464 Aug 21 13:43:36 volumio volumio[1103]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1861291464 in service soundcloud Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1861291464 Aug 21 13:43:36 volumio volumio[1103]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1893595887 Aug 21 13:43:36 volumio volumio[1103]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1893595887 in service soundcloud Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1893595887 Aug 21 13:43:36 volumio volumio[1103]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1898102283 Aug 21 13:43:36 volumio volumio[1103]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1898102283 in service soundcloud Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1898102283 Aug 21 13:43:36 volumio volumio[1103]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1860933699 Aug 21 13:43:36 volumio volumio[1103]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1860933699 in service soundcloud Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1860933699 Aug 21 13:43:36 volumio volumio[1103]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1898205075 Aug 21 13:43:36 volumio volumio[1103]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1898205075 in service soundcloud Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1898205075 Aug 21 13:43:36 volumio volumio[1103]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1889945055 Aug 21 13:43:36 volumio volumio[1103]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1889945055 in service soundcloud Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1889945055 Aug 21 13:43:36 volumio volumio[1103]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@title=Top-Featured%20Tracks@pageRef:o=%7B%22pageToken%22%3A%22%22%2C%22pageOffset%22%3A11%7D@noExplode=1 Aug 21 13:43:36 volumio volumio[1103]: info: Exploding uri soundcloud/tracks@topFeatured=1@title=Top-Featured%20Tracks@pageRef:o=%7B%22pageToken%22%3A%22%22%2C%22pageOffset%22%3A11%7D@noExplode=1 in service soundcloud Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@title=Top-Featured%20Tracks@pageRef:o=%7B%22pageToken%22%3A%22%22%2C%22pageOffset%22%3A11%7D@noExplode=1 Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1895884134 Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1885018041 Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1882701765 Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1884912384 Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1883448855 Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1861291464 Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1893595887 Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1898102283 Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1860933699 Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1898205075 Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1889945055 Aug 21 13:43:36 volumio volumio[1103]: info: CoreCommandRouter::volumioPushQueue Aug 21 13:43:36 volumio volumio[1103]: info: CorePlayQueue::saveQueue Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::updateTrackBlock Aug 21 13:43:36 volumio volumio[1103]: info: CorePlayQueue::getTrackBlock Aug 21 13:43:36 volumio volumio[1103]: info: CoreCommandRouter::volumioPlay Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::play index 1 Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::stop Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::play index undefined Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 21 13:43:36 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::startPlaybackTimer Aug 21 13:43:36 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:36 volumio volumio[1103]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=1885018041 Aug 21 13:43:36 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status stop Aug 21 13:43:36 volumio volumio[1103]: info: sendMpdCommand stop took 80 milliseconds Aug 21 13:43:36 volumio sudo[20308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 21 13:43:36 volumio sudo[20308]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:36 volumio sudo[20308]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:36 volumio volumio[1103]: info: peppyspectrum Daemon Stop Aug 21 13:43:36 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand stop Aug 21 13:43:36 volumio volumio[1103]: info: sendMpdCommand stop took 2 milliseconds Aug 21 13:43:36 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand clear Aug 21 13:43:36 volumio volumio[1103]: info: Aug 21 13:43:36 volumio volumio[1103]: ---------------------------- MPD announces system playlist update Aug 21 13:43:36 volumio volumio[1103]: info: Ignoring MPD Status Update Aug 21 13:43:36 volumio volumio[1103]: info: sendMpdCommand clear took 2 milliseconds Aug 21 13:43:36 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-media.sndcdn.com/JhEPwTSBG5cK.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vSmhFUHdUU0JHNWNLLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI0MjQwODcyfX19XX0_&Signature=Mcef0JhkyT~nQOUpF8Lb7bELaJAe-qbMk~esz2AorlCox2l5gKir37N59cCTMJxk44-EC9dcMz8cKCGCa3r~-w7J0unwroPua7Kw-lPA-4d7pL7hDb-khbr2zJu5fPe645dVLTZv1OMMd3idOKRX1ZzTy65CyVTQ6ZOztZvVnUX960QBwHcGNCobo4ZOvrC3vtZKUS40W7kITrsZCNyF-uxAEKHl-3JjVIdsfDdnCDg5z8kNdTCL018h2bLWJHUIipp7seAfE~5HNz0geuPYevJkOFBlRIdZuWJisb~te7dkbhYLxygSXTxfFw2JuNH-M2TTMZoID8PWw1KqIr77oQ__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" Aug 21 13:43:36 volumio volumio[1103]: info: Aug 21 13:43:36 volumio volumio[1103]: ---------------------------- MPD announces system playlist update Aug 21 13:43:36 volumio volumio[1103]: info: Ignoring MPD Status Update Aug 21 13:43:36 volumio volumio[1103]: info: Aug 21 13:43:36 volumio volumio[1103]: ---------------------------- MPD announces system playlist update Aug 21 13:43:36 volumio volumio[1103]: info: Ignoring MPD Status Update Aug 21 13:43:36 volumio volumio[1103]: error: updateQueue error: null Aug 21 13:43:36 volumio volumio[1103]: info: Aug 21 13:43:36 volumio volumio[1103]: ---------------------------- MPD announces system playlist update Aug 21 13:43:36 volumio volumio[1103]: info: Ignoring MPD Status Update Aug 21 13:43:36 volumio volumio[1103]: info: ------------------------------ 8ms Aug 21 13:43:36 volumio volumio[1103]: info: sendMpdCommand addid "https://cf-media.sndcdn.com/JhEPwTSBG5cK.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vSmhFUHdUU0JHNWNLLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI0MjQwODcyfX19XX0_&Signature=Mcef0JhkyT~nQOUpF8Lb7bELaJAe-qbMk~esz2AorlCox2l5gKir37N59cCTMJxk44-EC9dcMz8cKCGCa3r~-w7J0unwroPua7Kw-lPA-4d7pL7hDb-khbr2zJu5fPe645dVLTZv1OMMd3idOKRX1ZzTy65CyVTQ6ZOztZvVnUX960QBwHcGNCobo4ZOvrC3vtZKUS40W7kITrsZCNyF-uxAEKHl-3JjVIdsfDdnCDg5z8kNdTCL018h2bLWJHUIipp7seAfE~5HNz0geuPYevJkOFBlRIdZuWJisb~te7dkbhYLxygSXTxfFw2JuNH-M2TTMZoID8PWw1KqIr77oQ__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" took 7 milliseconds Aug 21 13:43:36 volumio volumio[1103]: info: ------------------------------ 6ms Aug 21 13:43:36 volumio volumio[1103]: info: ------------------------------ 6ms Aug 21 13:43:36 volumio volumio[1103]: verbose: MPD COMMAND [object Object] Aug 21 13:43:36 volumio volumio[1103]: verbose: MPD COMMAND [object Object] Aug 21 13:43:36 volumio volumio[1103]: verbose: MPD COMMAND [object Object] Aug 21 13:43:36 volumio volumio[1103]: info: Aug 21 13:43:36 volumio volumio[1103]: ---------------------------- MPD announces system playlist update Aug 21 13:43:36 volumio volumio[1103]: info: Ignoring MPD Status Update Aug 21 13:43:36 volumio volumio[1103]: info: Aug 21 13:43:36 volumio volumio[1103]: ---------------------------- MPD announces system playlist update Aug 21 13:43:36 volumio volumio[1103]: info: Ignoring MPD Status Update Aug 21 13:43:36 volumio volumio[1103]: info: Aug 21 13:43:36 volumio volumio[1103]: ---------------------------- MPD announces system playlist update Aug 21 13:43:36 volumio volumio[1103]: info: Ignoring MPD Status Update Aug 21 13:43:36 volumio volumio[1103]: info: ------------------------------ 11ms Aug 21 13:43:36 volumio volumio[1103]: info: ------------------------------ 4ms Aug 21 13:43:36 volumio volumio[1103]: info: ------------------------------ 3ms Aug 21 13:43:36 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 21 13:43:36 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand play Aug 21 13:43:36 volumio volumio[1103]: info: Aug 21 13:43:36 volumio volumio[1103]: ---------------------------- MPD announces system playlist update Aug 21 13:43:36 volumio volumio[1103]: info: Ignoring MPD Status Update Aug 21 13:43:36 volumio volumio[1103]: info: Aug 21 13:43:36 volumio volumio[1103]: ---------------------------- MPD announces system playlist update Aug 21 13:43:36 volumio volumio[1103]: info: Ignoring MPD Status Update Aug 21 13:43:36 volumio volumio[1103]: info: ------------------------------ 7ms Aug 21 13:43:36 volumio volumio[1103]: info: sendMpdCommand play took 5 milliseconds Aug 21 13:43:36 volumio volumio[1103]: info: ------------------------------ 5ms Aug 21 13:43:36 volumio volumio[1103]: info: ------------------------------ 4ms Aug 21 13:43:37 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 21 13:43:37 volumio volumio[1103]: info: Aug 21 13:43:37 volumio volumio[1103]: ---------------------------- MPD announces state update: player Aug 21 13:43:37 volumio volumio[1103]: info: ControllerMpd::getState Aug 21 13:43:37 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand status Aug 21 13:43:37 volumio volumio[1103]: info: Aug 21 13:43:37 volumio volumio[1103]: ---------------------------- MPD announces state update: player Aug 21 13:43:37 volumio volumio[1103]: info: ControllerMpd::getState Aug 21 13:43:37 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand status Aug 21 13:43:37 volumio volumio[1103]: info: Aug 21 13:43:37 volumio volumio[1103]: ---------------------------- MPD announces state update: player Aug 21 13:43:37 volumio volumio[1103]: info: ControllerMpd::getState Aug 21 13:43:37 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand status Aug 21 13:43:37 volumio volumio[1103]: info: Aug 21 13:43:37 volumio volumio[1103]: ---------------------------- MPD announces state update: player Aug 21 13:43:37 volumio volumio[1103]: info: sendMpdCommand status took 39 milliseconds Aug 21 13:43:37 volumio volumio[1103]: info: sendMpdCommand status took 37 milliseconds Aug 21 13:43:37 volumio volumio[1103]: info: sendMpdCommand status took 34 milliseconds Aug 21 13:43:37 volumio volumio[1103]: info: ControllerMpd::getState Aug 21 13:43:37 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand status Aug 21 13:43:37 volumio volumio[1103]: verbose: ControllerMpd::parseState Aug 21 13:43:37 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 13:43:37 volumio volumio[1103]: verbose: ControllerMpd::parseState Aug 21 13:43:37 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 13:43:37 volumio volumio[1103]: verbose: ControllerMpd::parseState Aug 21 13:43:37 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 13:43:37 volumio volumio[1103]: info: sendMpdCommand status took 11 milliseconds Aug 21 13:43:38 volumio volumio[1103]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 21 13:43:38 volumio volumio[1103]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 21 13:43:38 volumio volumio[1103]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 21 13:43:38 volumio volumio[1103]: verbose: ControllerMpd::parseState Aug 21 13:43:38 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 13:43:38 volumio volumio[1103]: verbose: ControllerMpd::parseTrackInfo Aug 21 13:43:38 volumio volumio[1103]: verbose: ControllerMpd::parseTrackInfo Aug 21 13:43:38 volumio volumio[1103]: verbose: ControllerMpd::parseTrackInfo Aug 21 13:43:38 volumio volumio[1103]: info: ControllerMpd::pushState Aug 21 13:43:38 volumio volumio[1103]: info: CoreCommandRouter::servicePushState Aug 21 13:43:38 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:38 volumio volumio[1103]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"R2R MOE - 9 LIVES","artist":"R2R MOE","album":"SoundCloud Track","uri":"https://cf-media.sndcdn.com/JhEPwTSBG5cK.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vSmhFUHdUU0JHNWNLLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI0MjQwODcyfX19XX0_&Signature=Mcef0JhkyT~nQOUpF8Lb7bELaJAe-qbMk~esz2AorlCox2l5gKir37N59cCTMJxk44-EC9dcMz8cKCGCa3r~-w7J0unwroPua7Kw-lPA-4d7pL7hDb-khbr2zJu5fPe645dVLTZv1OMMd3idOKRX1ZzTy65CyVTQ6ZOztZvVnUX960QBwHcGNCobo4ZOvrC3vtZKUS40W7kITrsZCNyF-uxAEKHl-3JjVIdsfDdnCDg5z8kNdTCL018h2bLWJHUIipp7seAfE~5HNz0geuPYevJkOFBlRIdZuWJisb~te7dkbhYLxygSXTxfFw2JuNH-M2TTMZoID8PWw1KqIr77oQ__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Aug 21 13:43:38 volumio volumio[1103]: verbose: CURRENT POSITION 1 Aug 21 13:43:38 volumio volumio[1103]: info: CoreStateMachine::syncState stateService play Aug 21 13:43:38 volumio volumio[1103]: info: CoreStateMachine::syncState currentStatus stop Aug 21 13:43:38 volumio volumio[1103]: info: ControllerMpd::pushState Aug 21 13:43:38 volumio volumio[1103]: info: CoreCommandRouter::servicePushState Aug 21 13:43:38 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:38 volumio volumio[1103]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"R2R MOE - 9 LIVES","artist":"R2R MOE","album":"SoundCloud Track","uri":"https://cf-media.sndcdn.com/JhEPwTSBG5cK.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vSmhFUHdUU0JHNWNLLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI0MjQwODcyfX19XX0_&Signature=Mcef0JhkyT~nQOUpF8Lb7bELaJAe-qbMk~esz2AorlCox2l5gKir37N59cCTMJxk44-EC9dcMz8cKCGCa3r~-w7J0unwroPua7Kw-lPA-4d7pL7hDb-khbr2zJu5fPe645dVLTZv1OMMd3idOKRX1ZzTy65CyVTQ6ZOztZvVnUX960QBwHcGNCobo4ZOvrC3vtZKUS40W7kITrsZCNyF-uxAEKHl-3JjVIdsfDdnCDg5z8kNdTCL018h2bLWJHUIipp7seAfE~5HNz0geuPYevJkOFBlRIdZuWJisb~te7dkbhYLxygSXTxfFw2JuNH-M2TTMZoID8PWw1KqIr77oQ__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Aug 21 13:43:38 volumio volumio[1103]: verbose: CURRENT POSITION 1 Aug 21 13:43:38 volumio volumio[1103]: info: CoreStateMachine::syncState stateService play Aug 21 13:43:38 volumio volumio[1103]: info: CoreStateMachine::syncState currentStatus play Aug 21 13:43:38 volumio volumio[1103]: info: Received an update from plugin. extracting info from payload Aug 21 13:43:38 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:43:38 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 21 13:43:38 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:43:38 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:43:38 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:43:38 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:43:38 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:43:38 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:43:38 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:43:38 volumio volumio[1103]: info: ControllerMpd::pushState Aug 21 13:43:38 volumio volumio[1103]: info: CoreCommandRouter::servicePushState Aug 21 13:43:38 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:38 volumio volumio[1103]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"R2R MOE - 9 LIVES","artist":"R2R MOE","album":"SoundCloud Track","uri":"https://cf-media.sndcdn.com/JhEPwTSBG5cK.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vSmhFUHdUU0JHNWNLLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI0MjQwODcyfX19XX0_&Signature=Mcef0JhkyT~nQOUpF8Lb7bELaJAe-qbMk~esz2AorlCox2l5gKir37N59cCTMJxk44-EC9dcMz8cKCGCa3r~-w7J0unwroPua7Kw-lPA-4d7pL7hDb-khbr2zJu5fPe645dVLTZv1OMMd3idOKRX1ZzTy65CyVTQ6ZOztZvVnUX960QBwHcGNCobo4ZOvrC3vtZKUS40W7kITrsZCNyF-uxAEKHl-3JjVIdsfDdnCDg5z8kNdTCL018h2bLWJHUIipp7seAfE~5HNz0geuPYevJkOFBlRIdZuWJisb~te7dkbhYLxygSXTxfFw2JuNH-M2TTMZoID8PWw1KqIr77oQ__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Aug 21 13:43:38 volumio volumio[1103]: verbose: CURRENT POSITION 1 Aug 21 13:43:38 volumio volumio[1103]: info: CoreStateMachine::syncState stateService play Aug 21 13:43:38 volumio volumio[1103]: info: CoreStateMachine::syncState currentStatus play Aug 21 13:43:38 volumio volumio[1103]: info: Received an update from plugin. extracting info from payload Aug 21 13:43:38 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:43:38 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:43:38 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:43:38 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:43:38 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:43:38 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:43:38 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:43:38 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:43:38 volumio volumio[1103]: info: ------------------------------ 121ms Aug 21 13:43:38 volumio volumio[1103]: info: ------------------------------ 144ms Aug 21 13:43:38 volumio volumio[1103]: info: ------------------------------ 142ms Aug 21 13:43:38 volumio volumio[1103]: info: Aug 21 13:43:38 volumio volumio[1103]: ---------------------------- MPD announces state update: player Aug 21 13:43:38 volumio volumio[1103]: info: ControllerMpd::getState Aug 21 13:43:38 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand status Aug 21 13:43:38 volumio volumio[1103]: info: Aug 21 13:43:38 volumio volumio[1103]: ---------------------------- MPD announces state update: player Aug 21 13:43:38 volumio volumio[1103]: info: ControllerMpd::getState Aug 21 13:43:38 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand status Aug 21 13:43:38 volumio volumio[1103]: info: sendMpdCommand playlistinfo took 98 milliseconds Aug 21 13:43:38 volumio volumio[1103]: verbose: ControllerMpd::parseTrackInfo Aug 21 13:43:38 volumio volumio[1103]: info: ControllerMpd::pushState Aug 21 13:43:38 volumio volumio[1103]: info: CoreCommandRouter::servicePushState Aug 21 13:43:38 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:38 volumio volumio[1103]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"R2R MOE - 9 LIVES","artist":"R2R MOE","album":"SoundCloud Track","uri":"https://cf-media.sndcdn.com/JhEPwTSBG5cK.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vSmhFUHdUU0JHNWNLLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI0MjQwODcyfX19XX0_&Signature=Mcef0JhkyT~nQOUpF8Lb7bELaJAe-qbMk~esz2AorlCox2l5gKir37N59cCTMJxk44-EC9dcMz8cKCGCa3r~-w7J0unwroPua7Kw-lPA-4d7pL7hDb-khbr2zJu5fPe645dVLTZv1OMMd3idOKRX1ZzTy65CyVTQ6ZOztZvVnUX960QBwHcGNCobo4ZOvrC3vtZKUS40W7kITrsZCNyF-uxAEKHl-3JjVIdsfDdnCDg5z8kNdTCL018h2bLWJHUIipp7seAfE~5HNz0geuPYevJkOFBlRIdZuWJisb~te7dkbhYLxygSXTxfFw2JuNH-M2TTMZoID8PWw1KqIr77oQ__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Aug 21 13:43:38 volumio volumio[1103]: verbose: CURRENT POSITION 1 Aug 21 13:43:38 volumio volumio[1103]: info: CoreStateMachine::syncState stateService play Aug 21 13:43:38 volumio volumio[1103]: info: CoreStateMachine::syncState currentStatus play Aug 21 13:43:38 volumio volumio[1103]: info: Received an update from plugin. extracting info from payload Aug 21 13:43:38 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:43:38 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 21 13:43:38 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:43:38 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:43:38 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:43:38 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:43:38 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:43:38 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:43:38 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:43:38 volumio volumio[1103]: info: ------------------------------ 194ms Aug 21 13:43:38 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status play Aug 21 13:43:38 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status play Aug 21 13:43:38 volumio sudo[20317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 21 13:43:38 volumio sudo[20317]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:38 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status play Aug 21 13:43:38 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status play Aug 21 13:43:38 volumio sudo[20320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 21 13:43:38 volumio systemd[1]: Started peppyspectrum Daemon. Aug 21 13:43:38 volumio sudo[20320]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:38 volumio sudo[20323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 21 13:43:38 volumio sudo[20323]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:38 volumio sudo[20317]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:38 volumio sudo[20323]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:38 volumio sudo[20320]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:38 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status play Aug 21 13:43:38 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status play Aug 21 13:43:38 volumio sudo[20332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 21 13:43:38 volumio sudo[20332]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:38 volumio sudo[20334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 21 13:43:38 volumio sudo[20334]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:38 volumio sudo[20332]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:38 volumio sudo[20334]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:38 volumio sudo[20338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 21 13:43:38 volumio sudo[20338]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:38 volumio volumio[1103]: info: sendMpdCommand status took 892 milliseconds Aug 21 13:43:38 volumio volumio[1103]: info: sendMpdCommand status took 892 milliseconds Aug 21 13:43:38 volumio volumio[1103]: verbose: ControllerMpd::parseState Aug 21 13:43:38 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 13:43:38 volumio volumio[1103]: verbose: ControllerMpd::parseState Aug 21 13:43:38 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 13:43:39 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 21 13:43:39 volumio sudo[20338]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:39 volumio volumio[1103]: info: peppyspectrum Daemon Started Aug 21 13:43:39 volumio volumio[1103]: info: peppyspectrum Daemon Started Aug 21 13:43:39 volumio volumio[1103]: info: peppyspectrum Daemon Started Aug 21 13:43:39 volumio volumio[1103]: info: peppyspectrum Daemon Started Aug 21 13:43:39 volumio volumio[1103]: info: peppyspectrum Daemon Started Aug 21 13:43:39 volumio volumio[1103]: info: sendMpdCommand playlistinfo took 93 milliseconds Aug 21 13:43:39 volumio volumio[1103]: info: sendMpdCommand playlistinfo took 92 milliseconds Aug 21 13:43:39 volumio volumio[1103]: verbose: ControllerMpd::parseTrackInfo Aug 21 13:43:39 volumio volumio[1103]: verbose: ControllerMpd::parseTrackInfo Aug 21 13:43:39 volumio volumio[1103]: info: ControllerMpd::pushState Aug 21 13:43:39 volumio volumio[1103]: info: CoreCommandRouter::servicePushState Aug 21 13:43:39 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:39 volumio volumio[1103]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1359,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"R2R MOE - 9 LIVES","artist":"R2R MOE","album":"SoundCloud Track","uri":"https://cf-media.sndcdn.com/JhEPwTSBG5cK.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vSmhFUHdUU0JHNWNLLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI0MjQwODcyfX19XX0_&Signature=Mcef0JhkyT~nQOUpF8Lb7bELaJAe-qbMk~esz2AorlCox2l5gKir37N59cCTMJxk44-EC9dcMz8cKCGCa3r~-w7J0unwroPua7Kw-lPA-4d7pL7hDb-khbr2zJu5fPe645dVLTZv1OMMd3idOKRX1ZzTy65CyVTQ6ZOztZvVnUX960QBwHcGNCobo4ZOvrC3vtZKUS40W7kITrsZCNyF-uxAEKHl-3JjVIdsfDdnCDg5z8kNdTCL018h2bLWJHUIipp7seAfE~5HNz0geuPYevJkOFBlRIdZuWJisb~te7dkbhYLxygSXTxfFw2JuNH-M2TTMZoID8PWw1KqIr77oQ__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Aug 21 13:43:39 volumio volumio[1103]: verbose: CURRENT POSITION 1 Aug 21 13:43:39 volumio volumio[1103]: info: CoreStateMachine::syncState stateService play Aug 21 13:43:39 volumio volumio[1103]: info: CoreStateMachine::syncState currentStatus play Aug 21 13:43:39 volumio volumio[1103]: info: Received an update from plugin. extracting info from payload Aug 21 13:43:39 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:43:39 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 21 13:43:39 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:43:39 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:43:39 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:43:39 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:43:39 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:43:39 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:43:39 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:43:39 volumio volumio[1103]: info: ControllerMpd::pushState Aug 21 13:43:39 volumio volumio[1103]: info: CoreCommandRouter::servicePushState Aug 21 13:43:39 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:43:39 volumio volumio[1103]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1359,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"R2R MOE - 9 LIVES","artist":"R2R MOE","album":"SoundCloud Track","uri":"https://cf-media.sndcdn.com/JhEPwTSBG5cK.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vSmhFUHdUU0JHNWNLLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI0MjQwODcyfX19XX0_&Signature=Mcef0JhkyT~nQOUpF8Lb7bELaJAe-qbMk~esz2AorlCox2l5gKir37N59cCTMJxk44-EC9dcMz8cKCGCa3r~-w7J0unwroPua7Kw-lPA-4d7pL7hDb-khbr2zJu5fPe645dVLTZv1OMMd3idOKRX1ZzTy65CyVTQ6ZOztZvVnUX960QBwHcGNCobo4ZOvrC3vtZKUS40W7kITrsZCNyF-uxAEKHl-3JjVIdsfDdnCDg5z8kNdTCL018h2bLWJHUIipp7seAfE~5HNz0geuPYevJkOFBlRIdZuWJisb~te7dkbhYLxygSXTxfFw2JuNH-M2TTMZoID8PWw1KqIr77oQ__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Aug 21 13:43:39 volumio volumio[1103]: verbose: CURRENT POSITION 1 Aug 21 13:43:39 volumio volumio[1103]: info: CoreStateMachine::syncState stateService play Aug 21 13:43:39 volumio volumio[1103]: info: CoreStateMachine::syncState currentStatus play Aug 21 13:43:39 volumio volumio[1103]: info: Received an update from plugin. extracting info from payload Aug 21 13:43:39 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:43:39 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:43:39 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:43:39 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:43:39 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:43:39 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:43:39 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:43:39 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:43:39 volumio volumio[1103]: info: ------------------------------ 1097ms Aug 21 13:43:39 volumio volumio[1103]: info: ------------------------------ 1091ms Aug 21 13:43:39 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:39 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:39 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:39 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:39 volumio volumio[1103]: info: peppyspectrum Daemon Started Aug 21 13:43:39 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status play Aug 21 13:43:39 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status play Aug 21 13:43:39 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status play Aug 21 13:43:39 volumio sudo[20341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 21 13:43:39 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status play Aug 21 13:43:39 volumio sudo[20341]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:39 volumio sudo[20343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 21 13:43:39 volumio sudo[20343]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:39 volumio volumio[20322]: pygame 1.9.4.post1 Aug 21 13:43:39 volumio volumio[20322]: Hello from the pygame community. https://www.pygame.org/contribute.html Aug 21 13:43:39 volumio volumio[20322]: Traceback (most recent call last): Aug 21 13:43:39 volumio volumio[20322]: File "spectrum.py", line 715, in Aug 21 13:43:39 volumio volumio[20322]: pm = Spectrum(None, True) Aug 21 13:43:39 volumio volumio[20322]: File "spectrum.py", line 63, in __init__ Aug 21 13:43:39 volumio volumio[20322]: self.init_display() Aug 21 13:43:39 volumio volumio[20322]: File "spectrum.py", line 104, in init_display Aug 21 13:43:39 volumio volumio[20322]: pygame.display.init() Aug 21 13:43:39 volumio volumio[20322]: pygame.error: Unable to open /dev/fb1 Aug 21 13:43:39 volumio sudo[20346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 21 13:43:39 volumio sudo[20346]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:39 volumio sudo[20343]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:39 volumio sudo[20341]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:39 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 21 13:43:39 volumio volumio[1103]: info: peppyspectrum Daemon Started Aug 21 13:43:39 volumio volumio[1103]: info: peppyspectrum Daemon Started Aug 21 13:43:39 volumio sudo[20346]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:39 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Aug 21 13:43:39 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Aug 21 13:43:39 volumio sudo[20350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 21 13:43:39 volumio sudo[20350]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:43:39 volumio volumio[1103]: info: peppyspectrum Daemon Started Aug 21 13:43:39 volumio systemd[1]: Started peppyspectrum Daemon. Aug 21 13:43:40 volumio sudo[20350]: pam_unix(sudo:session): session closed for user root Aug 21 13:43:40 volumio volumio[1103]: info: peppyspectrum Daemon Started Aug 21 13:43:40 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:40 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:40 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:40 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:41 volumio volumio[20352]: pygame 1.9.4.post1 Aug 21 13:43:41 volumio volumio[20352]: Hello from the pygame community. https://www.pygame.org/contribute.html Aug 21 13:43:41 volumio volumio[20352]: Traceback (most recent call last): Aug 21 13:43:41 volumio volumio[20352]: File "spectrum.py", line 715, in Aug 21 13:43:41 volumio volumio[20352]: pm = Spectrum(None, True) Aug 21 13:43:41 volumio volumio[20352]: File "spectrum.py", line 63, in __init__ Aug 21 13:43:41 volumio volumio[20352]: self.init_display() Aug 21 13:43:41 volumio volumio[20352]: File "spectrum.py", line 104, in init_display Aug 21 13:43:41 volumio volumio[20352]: pygame.display.init() Aug 21 13:43:41 volumio volumio[20352]: pygame.error: Unable to open /dev/fb1 Aug 21 13:43:41 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:41 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:41 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:41 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:41 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Aug 21 13:43:41 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Aug 21 13:43:42 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:42 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:42 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:42 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:43 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:43 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:43 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:43 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:44 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:44 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:44 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:44 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:45 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:45 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:45 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:45 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:46 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:46 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:46 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:46 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:47 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:47 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:47 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:47 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:48 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:48 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:48 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:48 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:49 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:49 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:49 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:49 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:50 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:50 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:50 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:50 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:51 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:51 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:51 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:51 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:52 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:52 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:52 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:52 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:53 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:53 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:53 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:53 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:54 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:54 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:54 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:54 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:55 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:55 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:55 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:55 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:56 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:56 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:56 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:56 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:57 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:57 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:57 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:57 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:58 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:58 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:58 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:58 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:43:59 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:43:59 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:43:59 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:43:59 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:00 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:00 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:00 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:00 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:01 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:01 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:01 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:01 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:02 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:02 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:02 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:02 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:03 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:03 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:03 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:03 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:04 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:04 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:04 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:04 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:05 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:05 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:05 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:05 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:06 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:06 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:06 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:06 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:07 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:07 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:07 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:07 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:08 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:09 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:09 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:09 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:10 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:10 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:10 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:10 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 21 13:44:10 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 13:44:10 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 21 13:44:10 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:10 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:10 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:10 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:10 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:10 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:10 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:10 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:10 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 21 13:44:10 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 21 13:44:10 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:10 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:10 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:11 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:11 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:11 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:11 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:12 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:12 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:12 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:12 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:13 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:13 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:13 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:13 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:14 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:14 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:14 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:14 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:14 volumio volumio[1103]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 21 13:44:14 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 21 13:44:14 volumio volumio[1103]: info: Preparing to save Alsa Options, stopping services first Aug 21 13:44:14 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:14 volumio volumio[1103]: info: CoreCommandRouter::volumioPause Aug 21 13:44:14 volumio volumio[1103]: info: CoreStateMachine::pause Aug 21 13:44:14 volumio volumio[1103]: info: CoreStateMachine::stPlaybackTimer Aug 21 13:44:14 volumio volumio[1103]: info: CoreStateMachine::servicePause Aug 21 13:44:14 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:44:14 volumio volumio[1103]: info: CoreCommandRouter::servicePause Aug 21 13:44:14 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 21 13:44:14 volumio volumio[1103]: info: ControllerMpd::pause Aug 21 13:44:14 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand pause Aug 21 13:44:14 volumio volumio[1103]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphones"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Aug 21 13:44:14 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 21 13:44:15 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:15 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 21 13:44:15 volumio volumio[1103]: info: Found match in Cards Database: setting mixer PCM for card Headphones Aug 21 13:44:15 volumio vtcs[19779]: [2024-08-21 13:44:15.195] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Aug 21 13:44:15 volumio vtcs[19779]: [2024-08-21 13:44:15.196] [tisoc] [error] [SpkconServer.cpp:473] socket write error. fd=3 errorno=32 error=Broken pipe Aug 21 13:44:15 volumio vtcs[19779]: [2024-08-21 13:44:15.196] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Aug 21 13:44:15 volumio sudo[20365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 21 13:44:15 volumio sudo[20365]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:15 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Aug 21 13:44:15 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Aug 21 13:44:15 volumio systemd[1]: vtcs.service: Succeeded. Aug 21 13:44:15 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Aug 21 13:44:15 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:15 volumio sudo[20365]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 21 13:44:15 volumio volumio[1103]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 21 13:44:15 volumio volumio[1103]: info: Disabling external Volume Control Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:15 volumio volumio[1103]: info: Preparing to generate the ALSA configuration file Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:15 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:15 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:15 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 21 13:44:15 volumio volumio[1103]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 21 13:44:15 volumio volumio[1103]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 21 13:44:15 volumio volumio[1103]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 21 13:44:15 volumio volumio[1103]: info: Reading ALSA contributions from plugins. Aug 21 13:44:15 volumio volumio[1103]: info: Aug 21 13:44:15 volumio volumio[1103]: ---------------------------- MPD announces state update: player Aug 21 13:44:15 volumio volumio[1103]: info: sendMpdCommand pause took 526 milliseconds Aug 21 13:44:15 volumio volumio[1103]: info: ControllerMpd::getState Aug 21 13:44:15 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand status Aug 21 13:44:15 volumio volumio[1103]: info: Aug 21 13:44:15 volumio volumio[1103]: ---------------------------- MPD announces state update: player Aug 21 13:44:15 volumio volumio[1103]: info: ControllerMpd::getState Aug 21 13:44:15 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand status Aug 21 13:44:15 volumio volumio[1103]: info: Aug 21 13:44:15 volumio volumio[1103]: ---------------------------- MPD announces state update: player Aug 21 13:44:15 volumio volumio[1103]: info: ControllerMpd::getState Aug 21 13:44:15 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand status Aug 21 13:44:15 volumio sudo[20372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 21 13:44:15 volumio sudo[20372]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:15 volumio volumio[1103]: info: sendMpdCommand status took 72 milliseconds Aug 21 13:44:15 volumio volumio[1103]: info: sendMpdCommand status took 71 milliseconds Aug 21 13:44:15 volumio volumio[1103]: info: sendMpdCommand status took 70 milliseconds Aug 21 13:44:15 volumio volumio[1103]: verbose: ControllerMpd::parseState Aug 21 13:44:15 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 13:44:15 volumio volumio[1103]: verbose: ControllerMpd::parseState Aug 21 13:44:15 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 13:44:15 volumio volumio[1103]: verbose: ControllerMpd::parseState Aug 21 13:44:15 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 21 13:44:15 volumio volumio[1103]: info: sendMpdCommand playlistinfo took 25 milliseconds Aug 21 13:44:15 volumio volumio[1103]: info: sendMpdCommand playlistinfo took 26 milliseconds Aug 21 13:44:15 volumio volumio[1103]: info: sendMpdCommand playlistinfo took 26 milliseconds Aug 21 13:44:15 volumio volumio[1103]: verbose: ControllerMpd::parseTrackInfo Aug 21 13:44:15 volumio volumio[1103]: verbose: ControllerMpd::parseTrackInfo Aug 21 13:44:15 volumio volumio[1103]: verbose: ControllerMpd::parseTrackInfo Aug 21 13:44:15 volumio volumio[1103]: info: ControllerMpd::pushState Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::servicePushState Aug 21 13:44:15 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:44:15 volumio volumio[1103]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":38605,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"R2R MOE - 9 LIVES","artist":"R2R MOE","album":"SoundCloud Track","uri":"https://cf-media.sndcdn.com/JhEPwTSBG5cK.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vSmhFUHdUU0JHNWNLLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI0MjQwODcyfX19XX0_&Signature=Mcef0JhkyT~nQOUpF8Lb7bELaJAe-qbMk~esz2AorlCox2l5gKir37N59cCTMJxk44-EC9dcMz8cKCGCa3r~-w7J0unwroPua7Kw-lPA-4d7pL7hDb-khbr2zJu5fPe645dVLTZv1OMMd3idOKRX1ZzTy65CyVTQ6ZOztZvVnUX960QBwHcGNCobo4ZOvrC3vtZKUS40W7kITrsZCNyF-uxAEKHl-3JjVIdsfDdnCDg5z8kNdTCL018h2bLWJHUIipp7seAfE~5HNz0geuPYevJkOFBlRIdZuWJisb~te7dkbhYLxygSXTxfFw2JuNH-M2TTMZoID8PWw1KqIr77oQ__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Aug 21 13:44:15 volumio volumio[1103]: verbose: CURRENT POSITION 1 Aug 21 13:44:15 volumio volumio[1103]: info: CoreStateMachine::syncState stateService pause Aug 21 13:44:15 volumio volumio[1103]: info: CoreStateMachine::syncState currentStatus pause Aug 21 13:44:15 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:44:15 volumio sudo[20372]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:15 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:44:15 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:44:15 volumio volumio[1103]: info: CoreStateMachine::stPlaybackTimer Aug 21 13:44:15 volumio volumio[1103]: info: ControllerMpd::pushState Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::servicePushState Aug 21 13:44:15 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:44:15 volumio volumio[1103]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":38605,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"R2R MOE - 9 LIVES","artist":"R2R MOE","album":"SoundCloud Track","uri":"https://cf-media.sndcdn.com/JhEPwTSBG5cK.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vSmhFUHdUU0JHNWNLLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI0MjQwODcyfX19XX0_&Signature=Mcef0JhkyT~nQOUpF8Lb7bELaJAe-qbMk~esz2AorlCox2l5gKir37N59cCTMJxk44-EC9dcMz8cKCGCa3r~-w7J0unwroPua7Kw-lPA-4d7pL7hDb-khbr2zJu5fPe645dVLTZv1OMMd3idOKRX1ZzTy65CyVTQ6ZOztZvVnUX960QBwHcGNCobo4ZOvrC3vtZKUS40W7kITrsZCNyF-uxAEKHl-3JjVIdsfDdnCDg5z8kNdTCL018h2bLWJHUIipp7seAfE~5HNz0geuPYevJkOFBlRIdZuWJisb~te7dkbhYLxygSXTxfFw2JuNH-M2TTMZoID8PWw1KqIr77oQ__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Aug 21 13:44:15 volumio volumio[1103]: verbose: CURRENT POSITION 1 Aug 21 13:44:15 volumio volumio[1103]: info: CoreStateMachine::syncState stateService pause Aug 21 13:44:15 volumio volumio[1103]: info: CoreStateMachine::syncState currentStatus pause Aug 21 13:44:15 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:44:15 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:44:15 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:44:15 volumio volumio[1103]: info: CoreStateMachine::stPlaybackTimer Aug 21 13:44:15 volumio volumio[1103]: info: ControllerMpd::pushState Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::servicePushState Aug 21 13:44:15 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:44:15 volumio volumio[1103]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":38605,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"R2R MOE - 9 LIVES","artist":"R2R MOE","album":"SoundCloud Track","uri":"https://cf-media.sndcdn.com/JhEPwTSBG5cK.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vSmhFUHdUU0JHNWNLLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI0MjQwODcyfX19XX0_&Signature=Mcef0JhkyT~nQOUpF8Lb7bELaJAe-qbMk~esz2AorlCox2l5gKir37N59cCTMJxk44-EC9dcMz8cKCGCa3r~-w7J0unwroPua7Kw-lPA-4d7pL7hDb-khbr2zJu5fPe645dVLTZv1OMMd3idOKRX1ZzTy65CyVTQ6ZOztZvVnUX960QBwHcGNCobo4ZOvrC3vtZKUS40W7kITrsZCNyF-uxAEKHl-3JjVIdsfDdnCDg5z8kNdTCL018h2bLWJHUIipp7seAfE~5HNz0geuPYevJkOFBlRIdZuWJisb~te7dkbhYLxygSXTxfFw2JuNH-M2TTMZoID8PWw1KqIr77oQ__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Aug 21 13:44:15 volumio volumio[1103]: verbose: CURRENT POSITION 1 Aug 21 13:44:15 volumio volumio[1103]: info: CoreStateMachine::syncState stateService pause Aug 21 13:44:15 volumio volumio[1103]: info: CoreStateMachine::syncState currentStatus pause Aug 21 13:44:15 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:44:15 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:44:15 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:44:15 volumio volumio[1103]: info: CoreStateMachine::stPlaybackTimer Aug 21 13:44:15 volumio volumio[1103]: info: ------------------------------ 174ms Aug 21 13:44:15 volumio volumio[1103]: info: ------------------------------ 172ms Aug 21 13:44:15 volumio volumio[1103]: info: ------------------------------ 170ms Aug 21 13:44:15 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 21 13:44:15 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 21 13:44:15 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 21 13:44:15 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status pause Aug 21 13:44:15 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status pause Aug 21 13:44:16 volumio sudo[20381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 21 13:44:16 volumio sudo[20381]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:16 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status pause Aug 21 13:44:16 volumio sudo[20381]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:16 volumio sudo[20384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 21 13:44:16 volumio sudo[20384]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:16 volumio sudo[20384]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:16 volumio sudo[20387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 21 13:44:16 volumio sudo[20387]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:16 volumio volumio[1103]: info: VolumeController:: Volume=86 Mute =false Aug 21 13:44:16 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:44:16 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:44:16 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:44:16 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:44:16 volumio sudo[20387]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:16 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status pause Aug 21 13:44:16 volumio volumio[1103]: info: peppyspectrum Daemon Stop Aug 21 13:44:16 volumio volumio[1103]: info: peppyspectrum Daemon Stop Aug 21 13:44:16 volumio volumio[1103]: info: peppyspectrum Daemon Stop Aug 21 13:44:16 volumio sudo[20390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 21 13:44:16 volumio sudo[20390]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:16 volumio volumio[1103]: info: Asound.conf file unchanged, so no further update is needed Aug 21 13:44:16 volumio volumio[1103]: info: Output device has changed, restarting MPD Aug 21 13:44:16 volumio sudo[20390]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:16 volumio sudo[20393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 21 13:44:16 volumio sudo[20393]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:16 volumio volumio[1103]: info: Output device has changed, restarting Shairport Sync Aug 21 13:44:16 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:16 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 13:44:16 volumio sudo[20393]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:16 volumio sudo[20396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 21 13:44:16 volumio sudo[20396]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:16 volumio systemd[1]: musicservicesshield.service: Succeeded. Aug 21 13:44:16 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Aug 21 13:44:16 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Aug 21 13:44:16 volumio systemd[1]: Stopping Music Player Daemon... Aug 21 13:44:16 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:16 volumio volumio[1103]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 21 13:44:16 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:16 volumio volumio[1103]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 21 13:44:16 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:16 volumio systemd[1]: mpd.service: Succeeded. Aug 21 13:44:16 volumio systemd[1]: Stopped Music Player Daemon. Aug 21 13:44:16 volumio systemd[1]: Starting Music Player Daemon... Aug 21 13:44:16 volumio sudo[20401]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 21 13:44:16 volumio sudo[20401]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:16 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 21 13:44:16 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 21 13:44:16 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 21 13:44:16 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 21 13:44:16 volumio sudo[20401]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:16 volumio sudo[20409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 21 13:44:16 volumio sudo[20409]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:16 volumio volumio[1103]: info: MPD Permissions set Aug 21 13:44:16 volumio volumio[1103]: info: peppyspectrum Daemon Stop Aug 21 13:44:16 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:16 volumio sudo[20411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 21 13:44:16 volumio sudo[20411]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:17 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 13:44:17 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:17 volumio sudo[20411]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:17 volumio sudo[20409]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:17 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 21 13:44:17 volumio volumio[1103]: info: Starting Shairport Sync Aug 21 13:44:17 volumio sudo[20423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 21 13:44:17 volumio sudo[20423]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 21 13:44:17 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 21 13:44:17 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 21 13:44:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 21 13:44:17 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 21 13:44:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 21 13:44:17 volumio sudo[20423]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:17 volumio volumio[1103]: info: Shairport-Sync Started Aug 21 13:44:18 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:18 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:18 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:18 volumio volumio[1103]: info: TidalConnect service stoped! Aug 21 13:44:18 volumio mpd[20413]: Aug 21 13:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 21 13:44:18 volumio systemd[1]: Started Music Player Daemon. Aug 21 13:44:18 volumio sudo[20396]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:18 volumio volumio[1103]: info: MRS: STARTING volumioStreaming Aug 21 13:44:18 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Aug 21 13:44:18 volumio sudo[20434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream0.ts /tmp/hls/stream1.ts /tmp/hls/stream10.ts /tmp/hls/stream11.ts /tmp/hls/stream12.ts /tmp/hls/stream13.ts /tmp/hls/stream14.ts /tmp/hls/stream15.ts /tmp/hls/stream16.ts /tmp/hls/stream17.ts /tmp/hls/stream18.ts /tmp/hls/stream19.ts /tmp/hls/stream2.ts /tmp/hls/stream20.ts /tmp/hls/stream21.ts /tmp/hls/stream22.ts /tmp/hls/stream23.ts /tmp/hls/stream24.ts /tmp/hls/stream25.ts /tmp/hls/stream26.ts /tmp/hls/stream27.ts /tmp/hls/stream28.ts /tmp/hls/stream29.ts /tmp/hls/stream3.ts /tmp/hls/stream30.ts /tmp/hls/stream31.ts /tmp/hls/stream32.ts /tmp/hls/stream33.ts /tmp/hls/stream34.ts /tmp/hls/stream35.ts /tmp/hls/stream36.ts /tmp/hls/stream37.ts /tmp/hls/stream4.ts /tmp/hls/stream5.ts /tmp/hls/stream6.ts /tmp/hls/stream7.ts /tmp/hls/stream8.ts /tmp/hls/stream9.ts Aug 21 13:44:18 volumio sudo[20434]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:18 volumio sudo[20434]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:18 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 21 13:44:18 volumio volumio[1103]: info: TidalConnect service stoped! Aug 21 13:44:18 volumio sudo[20445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Aug 21 13:44:18 volumio sudo[20445]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:18 volumio volumio[1103]: error: updateQueue error: null Aug 21 13:44:18 volumio systemd[1]: Stopping VolumioStreamingService... Aug 21 13:44:18 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Aug 21 13:44:18 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 21 13:44:18 volumio volumio[1103]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 21 13:44:18 volumio volumio[1103]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 21 13:44:18 volumio sudo[20456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 21 13:44:18 volumio sudo[20456]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:19 volumio systemd[1]: Started Volumio Tidal Connect Service. Aug 21 13:44:19 volumio sudo[20456]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:19 volumio volumio[1103]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 21 13:44:19 volumio volumio[1103]: info: CoreStateMachine::getcurrentVolume Aug 21 13:44:19 volumio volumio[1103]: info: CoreCommandRouter::volumioRetrievevolume Aug 21 13:44:19 volumio volumio[20432]: cset: --> shielding system active with Aug 21 13:44:19 volumio volumio[20432]: cset: "system" cpuset of CPUSPEC(1-3) with 194 tasks running Aug 21 13:44:19 volumio volumio[20432]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Aug 21 13:44:19 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Aug 21 13:44:19 volumio volumio[1103]: info: Executing endpoint tc_getconfig Aug 21 13:44:19 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 21 13:44:19 volumio vtcs[20461]: STARTING TidalConnect services, version: 1.4.0.34 Aug 21 13:44:19 volumio vtcs[20461]: STARTED TidalConnect services. Aug 21 13:44:19 volumio volumio[1103]: info: Executing endpoint tc_connect Aug 21 13:44:19 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 21 13:44:19 volumio volumio[1103]: info: Connecting to TidalConnect Aug 21 13:44:19 volumio volumio[1103]: info: CoreCommandRouter::servicePushState Aug 21 13:44:19 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:44:19 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:44:19 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:44:19 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:44:19 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:44:19 volumio volumio[1103]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Aug 21 13:44:19 volumio volumio[1103]: info: CoreCommandRouter::servicePushState Aug 21 13:44:19 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:44:19 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:44:19 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:44:19 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:44:19 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:44:19 volumio volumio[1103]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Aug 21 13:44:19 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status pause Aug 21 13:44:19 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status pause Aug 21 13:44:19 volumio sudo[20486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 21 13:44:19 volumio sudo[20486]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:19 volumio volumio[1103]: info: VolumeController:: Volume=86 Mute =false Aug 21 13:44:19 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:44:19 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:44:19 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:44:19 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:44:19 volumio volumio[1103]: info: CoreStateMachine::updateTrackBlock Aug 21 13:44:19 volumio volumio[1103]: info: CorePlayQueue::getTrackBlock Aug 21 13:44:19 volumio volumio[1103]: info: CoreCommandRouter::volumioRetrievevolume Aug 21 13:44:19 volumio sudo[20486]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:19 volumio sudo[20489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 21 13:44:19 volumio sudo[20489]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:19 volumio sudo[20489]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:19 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status pause Aug 21 13:44:20 volumio volumio[1103]: info: peppyspectrum Daemon Stop Aug 21 13:44:20 volumio sudo[20493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 21 13:44:20 volumio sudo[20493]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:20 volumio sudo[20493]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:20 volumio volumio[1103]: info: peppyspectrum Daemon Stop Aug 21 13:44:20 volumio volumio[1103]: info: peppyspectrum Daemon Stop Aug 21 13:44:20 volumio volumio[1103]: info: TidalConnect service stoped! Aug 21 13:44:20 volumio volumio[1103]: info: TidalConnect service stoped! Aug 21 13:44:20 volumio volumio[1103]: info: VolumeController:: Volume=86 Mute =false Aug 21 13:44:20 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:44:20 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:44:20 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:44:20 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:44:20 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status pause Aug 21 13:44:20 volumio sudo[20501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 21 13:44:20 volumio sudo[20501]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:20 volumio sudo[20501]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:20 volumio volumio[1103]: info: peppyspectrum Daemon Stop Aug 21 13:44:20 volumio volumio[1103]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 21 13:44:20 volumio volumio[1103]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 21 13:44:20 volumio sudo[20508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 21 13:44:20 volumio sudo[20508]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:20 volumio sudo[20508]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:22 volumio volumio[1103]: info: TidalConnect service started! Aug 21 13:44:22 volumio volumio[1103]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Aug 21 13:44:22 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Aug 21 13:44:22 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Aug 21 13:44:22 volumio sudo[20512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 21 13:44:22 volumio sudo[20512]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:22 volumio sudo[20512]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:22 volumio volumio[1103]: info: MPD Permissions set Aug 21 13:44:22 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:22 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:22 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:22 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:22 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:22 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:22 volumio sudo[20515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 21 13:44:22 volumio sudo[20515]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:22 volumio systemd[1]: musicservicesshield.service: Succeeded. Aug 21 13:44:22 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Aug 21 13:44:22 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Aug 21 13:44:22 volumio systemd[1]: Stopping Music Player Daemon... Aug 21 13:44:22 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 13:44:22 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:22 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 21 13:44:22 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 21 13:44:22 volumio systemd[1]: mpd.service: Succeeded. Aug 21 13:44:22 volumio systemd[1]: Stopped Music Player Daemon. Aug 21 13:44:22 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 21 13:44:22 volumio systemd[1]: Starting Music Player Daemon... Aug 21 13:44:22 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 21 13:44:22 volumio sudo[20521]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 21 13:44:22 volumio sudo[20521]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:22 volumio sudo[20521]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:23 volumio volumio[1103]: info: TidalConnect service started! Aug 21 13:44:24 volumio mpd[20524]: Aug 21 13:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 21 13:44:24 volumio systemd[1]: Started Music Player Daemon. Aug 21 13:44:24 volumio sudo[20515]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:24 volumio volumio[1103]: error: updateQueue error: null Aug 21 13:44:24 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Aug 21 13:44:24 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 21 13:44:24 volumio volumio[20527]: cset: --> shielding system active with Aug 21 13:44:24 volumio volumio[20527]: cset: "system" cpuset of CPUSPEC(1-3) with 202 tasks running Aug 21 13:44:24 volumio volumio[20527]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Aug 21 13:44:24 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Aug 21 13:44:28 volumio volumio[1103]: info: MRS: Starting browser stream Aug 21 13:44:28 volumio volumio[1103]: info: MRS: Setting this device as single Aug 21 13:44:28 volumio volumio[1103]: info: Aug 21 13:44:28 volumio volumio[1103]: [1724240668758] ---------------------------- Setting Multiroom Single Aug 21 13:44:28 volumio volumio[1103]: info: MRS: disable multiroom output Aug 21 13:44:28 volumio volumio[1103]: info: MRS: Set multiroom target PCM to volumioDiscard Aug 21 13:44:28 volumio volumio[1103]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Aug 21 13:44:28 volumio volumio[1103]: verbose: New Socket.io Connection to 192.168.178.165 from 192.168.178.157 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/127.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:28 volumio volumio[1103]: info: Received Get System Info Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 21 13:44:28 volumio volumio[1103]: info: Discovery: Getting this device information Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:28 volumio volumio[1103]: info: Received Get System Info Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 21 13:44:28 volumio volumio[1103]: info: Discovery: Getting this device information Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::volumioGetVisibleSources Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 21 13:44:28 volumio volumio[1103]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 21 13:44:28 volumio volumio[1103]: info: Received Get System Info Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 21 13:44:28 volumio volumio[1103]: info: Discovery: Getting this device information Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 21 13:44:28 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:29 volumio volumio[1103]: info: Listing playlists Aug 21 13:44:29 volumio volumio[1103]: info: Received Get System Info Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 21 13:44:29 volumio volumio[1103]: info: Discovery: Getting this device information Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:29 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:29 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 21 13:44:29 volumio volumio[1103]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:29 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:29 volumio volumio[1103]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Aug 21 13:44:32 volumio wpa_supplicant[923]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 13:44:32 volumio volumio[1103]: info: MRS: STOPPING volumioStreaming Aug 21 13:44:32 volumio sudo[20601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 21 13:44:32 volumio sudo[20601]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:32 volumio sudo[20445]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:32 volumio sudo[20604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 21 13:44:32 volumio sudo[20604]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:32 volumio sudo[20604]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:33 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 21 13:44:33 volumio volumio[1103]: verbose: New Socket.io Connection to 192.168.178.165 from 192.168.178.157 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/127.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Aug 21 13:44:33 volumio volumio[1103]: info: MRS: Removed streaming files Aug 21 13:44:33 volumio volumio[1103]: error: MRS: Cannot start volumioStreaming Command failed: /usr/bin/sudo /bin/systemctl restart volumioStreaming Aug 21 13:44:33 volumio volumio[1103]: Job for volumioStreaming.service canceled. Aug 21 13:44:33 volumio volumio[1103]: {"killed":false,"code":1,"signal":null,"cmd":"/usr/bin/sudo /bin/systemctl restart volumioStreaming","stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl restart volumioStreaming\nJob for volumioStreaming.service canceled.\n\n at ChildProcess.exithandler (child_process.js:308:12)\n at ChildProcess.emit (events.js:315:20)\n at maybeClose (internal/child_process.js:1048:16)\n at Socket. (internal/child_process.js:439:11)\n at Socket.emit (events.js:315:20)\n at Pipe. (net.js:673:12)"} Aug 21 13:44:33 volumio volumio[1103]: verbose: New Socket.io Connection to 192.168.178.137 from 192.168.178.157 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/127.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:33 volumio volumio[1103]: info: Received Get System Info Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 21 13:44:33 volumio volumio[1103]: info: Discovery: Getting this device information Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:33 volumio volumio[1103]: info: Received Get System Info Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 21 13:44:33 volumio volumio[1103]: info: Discovery: Getting this device information Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::volumioGetVisibleSources Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 21 13:44:33 volumio volumio[1103]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 21 13:44:33 volumio volumio[1103]: info: Received Get System Info Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 21 13:44:33 volumio volumio[1103]: info: Discovery: Getting this device information Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:33 volumio volumio[1103]: info: Listing playlists Aug 21 13:44:33 volumio volumio[1103]: info: Received Get System Info Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 21 13:44:33 volumio volumio[1103]: info: Discovery: Getting this device information Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::volumioGetQueue Aug 21 13:44:33 volumio volumio[1103]: info: CoreStateMachine::getQueue Aug 21 13:44:33 volumio volumio[1103]: info: CorePlayQueue::getQueue Aug 21 13:44:33 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 21 13:44:34 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:34 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:34 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:34 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:34 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:34 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:34 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:34 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:34 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:34 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:34 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:34 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:34 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:34 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:34 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:34 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:34 volumio wpa_supplicant[923]: RRM: Ignoring radio measurement request: Not RRM network Aug 21 13:44:38 volumio volumio[1103]: info: CoreCommandRouter::volumioPlay Aug 21 13:44:38 volumio volumio[1103]: info: CoreStateMachine::play index undefined Aug 21 13:44:38 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 21 13:44:38 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:44:38 volumio volumio[1103]: info: CoreStateMachine::startPlaybackTimer Aug 21 13:44:38 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:44:38 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 21 13:44:38 volumio volumio[1103]: info: ControllerMpd::resume Aug 21 13:44:38 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand play Aug 21 13:44:38 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 21 13:44:38 volumio volumio[1103]: info: sendMpdCommand play took 7 milliseconds Aug 21 13:44:41 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:42 volumio kernel: hwmon hwmon1: Voltage normalised Aug 21 13:44:42 volumio volumio[1103]: info: CoreCommandRouter::volumioPlay Aug 21 13:44:42 volumio volumio[1103]: info: CoreStateMachine::play index undefined Aug 21 13:44:42 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 21 13:44:42 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:44:42 volumio volumio[1103]: info: CoreStateMachine::startPlaybackTimer Aug 21 13:44:42 volumio volumio[1103]: info: CorePlayQueue::getTrack 1 Aug 21 13:44:42 volumio volumio[1103]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 21 13:44:42 volumio volumio[1103]: info: ControllerMpd::resume Aug 21 13:44:42 volumio volumio[1103]: verbose: ControllerMpd::sendMpdCommand play Aug 21 13:44:42 volumio volumio[1103]: info: sendMpdCommand play took 0 milliseconds Aug 21 13:44:42 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 21 13:44:43 volumio volumio[1103]: info: Executing endpoint metavolumio Aug 21 13:44:43 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 21 13:44:43 volumio volumio[1103]: info: Executing endpoint metavolumio Aug 21 13:44:43 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 21 13:44:43 volumio volumio[1103]: info: Executing endpoint metavolumio Aug 21 13:44:43 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 21 13:44:43 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 21 13:44:46 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 21 13:44:50 volumio kernel: hwmon hwmon1: Voltage normalised Aug 21 13:44:51 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:51 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:51 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 21 13:44:51 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 13:44:51 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 21 13:44:51 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:51 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:51 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:51 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:51 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:51 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:51 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:51 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:51 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 21 13:44:51 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 21 13:44:54 volumio volumio[1103]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 21 13:44:54 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 21 13:44:54 volumio volumio[1103]: info: Preparing to save Alsa Options, stopping services first Aug 21 13:44:54 volumio volumio[1103]: info: CoreCommandRouter::volumioGetState Aug 21 13:44:54 volumio volumio[1103]: info: CoreCommandRouter::volumioPause Aug 21 13:44:54 volumio volumio[1103]: info: CoreStateMachine::pause Aug 21 13:44:54 volumio volumio[1103]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphones"},"i2s":true,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Aug 21 13:44:54 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 21 13:44:54 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 21 13:44:54 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 13:44:54 volumio volumio[1103]: info: Enabling I2S DAC: Adafruit MAX98357 Aug 21 13:44:54 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Aug 21 13:44:54 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 13:44:54 volumio sudo[20615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 21 13:44:54 volumio sudo[20615]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:54 volumio sudo[20615]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:54 volumio volumio[1103]: info: No Overlays Loaded Aug 21 13:44:54 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 13:44:54 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Aug 21 13:44:54 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 13:44:54 volumio sudo[20618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay max98357a Aug 21 13:44:54 volumio sudo[20618]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:54 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Aug 21 13:44:54 volumio sudo[20618]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:54 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:54 volumio volumio[1103]: warn: Unable to locate the device Headphones a reboot may be required. Aug 21 13:44:55 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 13:44:55 volumio volumio[1103]: Invalid card number. Aug 21 13:44:55 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 21 13:44:55 volumio volumio[1103]: info: Updating Volume Controller Parameters: Device: 2 Name: Adafruit MAX98357 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 21 13:44:55 volumio volumio[1103]: info: Disabling external Volume Control Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Aug 21 13:44:55 volumio volumio[1103]: info: Preparing to generate the ALSA configuration file Aug 21 13:44:55 volumio volumio[1103]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 21 13:44:55 volumio volumio[1103]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 21 13:44:55 volumio volumio[1103]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 21 13:44:55 volumio volumio[1103]: info: Reading ALSA contributions from plugins. Aug 21 13:44:55 volumio volumio[1103]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 21 13:44:55 volumio volumio[1103]: error: Cannot enable I2S Param: Error: Command failed: /usr/bin/sudo /usr/bin/dtoverlay max98357a Aug 21 13:44:55 volumio volumio[1103]: * Failed to apply overlay '0_max98357a' (kernel) Aug 21 13:44:55 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 21 13:44:55 volumio volumio[1103]: info: Getting Alsa Cards List without I2S DAC Aug 21 13:44:55 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 13:44:55 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:55 volumio volumio[1103]: Invalid card number. Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 21 13:44:55 volumio volumio[1103]: info: VolumeController:: Volume=undefined Mute =false Aug 21 13:44:55 volumio volumio[1103]: info: CoreStateMachine::pushState Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 21 13:44:55 volumio volumio[1103]: info: CoreCommandRouter::volumioPushState Aug 21 13:44:55 volumio volumio[1103]: info: MRS: Pushing multiroomSync output update for this device Aug 21 13:44:55 volumio volumio[1103]: info: MRS: Pushing multiroomSync output Aug 21 13:44:55 volumio volumio[1103]: info: PeppySpectrum ---peppyspectrum status pause Aug 21 13:44:55 volumio sudo[20645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 21 13:44:55 volumio sudo[20645]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:55 volumio volumio[1103]: info: Asound.conf file written Aug 21 13:44:55 volumio sudo[20645]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:56 volumio sudo[20648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 21 13:44:56 volumio sudo[20648]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:56 volumio sudo[20648]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:56 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:56 volumio volumio[1103]: info: Output device has changed, restarting MPD Aug 21 13:44:56 volumio sudo[20653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 21 13:44:56 volumio sudo[20653]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:56 volumio sudo[20653]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:56 volumio volumio[1103]: info: Output device has changed, restarting Shairport Sync Aug 21 13:44:56 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:56 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 13:44:56 volumio sudo[20656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 21 13:44:56 volumio sudo[20656]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:56 volumio systemd[1]: musicservicesshield.service: Succeeded. Aug 21 13:44:56 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Aug 21 13:44:56 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Aug 21 13:44:56 volumio systemd[1]: Stopping Music Player Daemon... Aug 21 13:44:56 volumio systemd[1]: mpd.service: Succeeded. Aug 21 13:44:56 volumio systemd[1]: Stopped Music Player Daemon. Aug 21 13:44:56 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:56 volumio volumio[1103]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 21 13:44:56 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:56 volumio volumio[1103]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 21 13:44:56 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:56 volumio systemd[1]: Starting Music Player Daemon... Aug 21 13:44:56 volumio vtcs[20461]: [2024-08-21 13:44:56.528] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Aug 21 13:44:56 volumio vtcs[20461]: [2024-08-21 13:44:56.531] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Aug 21 13:44:56 volumio volumio[1103]: info: Output device has changed, restarting MPD Aug 21 13:44:56 volumio sudo[20661]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 21 13:44:56 volumio sudo[20661]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:56 volumio sudo[20661]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:56 volumio sudo[20669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 21 13:44:56 volumio sudo[20669]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:56 volumio sudo[20674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 21 13:44:56 volumio sudo[20674]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:56 volumio sudo[20674]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:56 volumio volumio[1103]: info: Output device has changed, restarting Shairport Sync Aug 21 13:44:56 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:56 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 21 13:44:56 volumio sudo[20677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 21 13:44:56 volumio sudo[20677]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:56 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Aug 21 13:44:56 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Aug 21 13:44:56 volumio systemd[1]: vtcs.service: Succeeded. Aug 21 13:44:56 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Aug 21 13:44:56 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 21 13:44:56 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 21 13:44:56 volumio systemd[1]: mpd.service: Succeeded. Aug 21 13:44:56 volumio systemd[1]: Stopped Music Player Daemon. Aug 21 13:44:56 volumio sudo[20669]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:57 volumio systemd[1]: Starting Music Player Daemon... Aug 21 13:44:57 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:57 volumio volumio[1103]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 21 13:44:57 volumio volumio[1103]: xcb_connection_has_error() returned true Aug 21 13:44:57 volumio volumio[1103]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 21 13:44:57 volumio volumio[1103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 21 13:44:57 volumio sudo[20682]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 21 13:44:57 volumio sudo[20682]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:57 volumio sudo[20682]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:57 volumio sudo[20692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 21 13:44:57 volumio sudo[20692]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:57 volumio sudo[20694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 21 13:44:57 volumio sudo[20694]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:57 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 21 13:44:57 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 21 13:44:57 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 21 13:44:57 volumio sudo[20694]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:57 volumio sudo[20692]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:57 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 21 13:44:57 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 21 13:44:57 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 21 13:44:57 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 21 13:44:57 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 21 13:44:57 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 21 13:44:57 volumio volumio[1103]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 21 13:44:57 volumio volumio[1103]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Aug 21 13:44:57 volumio volumio[1103]: info: PLUGIN onReboot : networkfs Aug 21 13:44:57 volumio volumio[1103]: info: PLUGIN onReboot : audiophonicsonoff Aug 21 13:44:57 volumio volumio[1103]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 21 13:44:57 volumio sudo[20698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 21 13:44:57 volumio volumio[1103]: TypeError: Cannot read property 'writeSync' of undefined Aug 21 13:44:57 volumio volumio[1103]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Aug 21 13:44:57 volumio volumio[1103]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Aug 21 13:44:57 volumio volumio[1103]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Aug 21 13:44:57 volumio volumio[1103]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 21 13:44:57 volumio volumio[1103]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 21 13:44:57 volumio volumio[1103]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Aug 21 13:44:57 volumio volumio[1103]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Aug 21 13:44:57 volumio volumio[1103]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Aug 21 13:44:57 volumio volumio[1103]: at Socket.emit (events.js:315:20) Aug 21 13:44:57 volumio volumio[1103]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 21 13:44:57 volumio volumio[1103]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 21 13:44:57 volumio volumio[1103]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 21 13:44:57 volumio sudo[20698]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 21 13:44:57 volumio sudo[20698]: pam_unix(sudo:session): session closed for user root Aug 21 13:44:58 volumio sudo[20712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-21 13:43 Aug 21 13:44:58 volumio sudo[20712]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"